[29.356185] Initializing watchdogs [29.356249] /dev/watchdog0 [29.362150] [001/142] (960s left) kms_sysfs_edid_timing [43.806456] [002/142] (946s left) syncobj_wait (wait-delayed-signal) [43.879809] Starting subtest: wait-delayed-signal [43.981536] Subtest wait-delayed-signal: SUCCESS (0.102s) [44.066710] [003/142] (945s left) gem_exec_fence (syncobj-invalid-wait) [44.911801] Starting subtest: syncobj-invalid-wait [44.912655] Subtest syncobj-invalid-wait: SUCCESS (0.001s) [45.277429] [004/142] (944s left) kms_ccs (pipe-a-bad-pixel-format-y_tiled_gen12_mc_ccs) [47.258809] Starting subtest: pipe-A-bad-pixel-format-y_tiled_gen12_mc_ccs [47.260582] Subtest pipe-A-bad-pixel-format-y_tiled_gen12_mc_ccs: SKIP (0.000s) [47.513886] [005/142] (942s left) kms_psr (psr2_primary_page_flip) [47.574997] Subtest psr2_primary_page_flip: SKIP (0.000s) [47.591471] [006/142] (942s left) device_reset (unbind-reset-rebind) [47.662510] Starting subtest: unbind-reset-rebind [49.479903] Subtest unbind-reset-rebind: SUCCESS (1.817s) [51.502740] [007/142] (938s left) gem_exec_gttfill (engines) [52.483949] Starting subtest: engines [52.484872] Starting dynamic subtest: rcs0 [77.628808] Dynamic subtest rcs0: SUCCESS (25.136s) [77.634169] Starting dynamic subtest: bcs0 [102.900923] Dynamic subtest bcs0: SUCCESS (25.272s) [102.906354] Starting dynamic subtest: vcs0 [128.131750] Dynamic subtest vcs0: SUCCESS (25.231s) [128.137221] Starting dynamic subtest: vcs1 [153.372172] Dynamic subtest vcs1: SUCCESS (25.240s) [153.377661] Starting dynamic subtest: vecs0 [178.632754] Dynamic subtest vecs0: SUCCESS (25.260s) [178.638221] Starting dynamic subtest: vecs1 [203.915486] Dynamic subtest vecs1: SUCCESS (25.282s) [203.921015] Starting dynamic subtest: ccs0 [229.161860] Dynamic subtest ccs0: SUCCESS (25.246s) [229.167446] Starting dynamic subtest: ccs1 [254.464964] Dynamic subtest ccs1: SUCCESS (25.303s) [254.470556] Starting dynamic subtest: ccs2 [279.617377] Dynamic subtest ccs2: SUCCESS (25.161s) [279.622977] Starting dynamic subtest: ccs3 [304.849066] Dynamic subtest ccs3: SUCCESS (25.223s) [304.849900] Subtest engines: SUCCESS (252.357s) [305.059892] [008/142] (684s left) gem_lmem_swapping (heavy-verify-multi) [309.774727] Starting subtest: heavy-verify-multi [309.775561] Starting dynamic subtest: lmem0 [322.646702] Dynamic subtest lmem0: SUCCESS (12.862s) [322.647598] Subtest heavy-verify-multi: SUCCESS (12.862s) [325.008112] [009/142] (664s left) kms_plane_multiple (tiling-x) [329.900269] Starting subtest: tiling-x [329.901114] Starting dynamic subtest: pipe-A-HDMI-A-1 [330.612803] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.713s) [330.618055] Starting dynamic subtest: pipe-B-HDMI-A-1 [331.138976] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.525s) [331.144407] Starting dynamic subtest: pipe-C-HDMI-A-1 [331.666753] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.527s) [331.672386] Starting dynamic subtest: pipe-D-HDMI-A-1 [332.210232] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.542s) [332.211146] Subtest tiling-x: SUCCESS (2.311s) [333.345568] [010/142] (656s left) gem_render_copy (yf-tiled-ccs-to-y-tiled-ccs) [333.412506] Starting subtest: yf-tiled-ccs-to-y-tiled-ccs [333.414283] Subtest yf-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s) [333.581300] [011/142] (656s left) syncobj_basic (bad-create-flags) [333.641644] Starting subtest: bad-create-flags [333.642534] Subtest bad-create-flags: SUCCESS (0.000s) [333.697363] [012/142] (656s left) gem_eio (wait-wedge-immediate) [333.929982] Starting subtest: wait-wedge-immediate [335.407119] Subtest wait-wedge-immediate: SUCCESS (1.478s) [337.364968] [013/142] (652s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-pwrite) [339.335364] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-pwrite [339.337061] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [339.415998] [014/142] (650s left) gem_exec_balancer (invalid-balancer) [339.494761] Starting subtest: invalid-balancer [340.383278] Subtest invalid-balancer: SUCCESS (0.880s) [340.650186] [015/142] (649s left) gem_exec_reloc (basic-write-wc-active) [340.715295] Subtest basic-write-wc-active: SKIP (0.000s) [340.759991] [016/142] (649s left) kms_ccs (pipe-b-bad-pixel-format-4_tiled_dg2_mc_ccs) [342.787955] Starting subtest: pipe-B-bad-pixel-format-4_tiled_dg2_mc_ccs [342.788838] Subtest pipe-B-bad-pixel-format-4_tiled_dg2_mc_ccs: SUCCESS (0.002s) [342.971152] [017/142] (647s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-msflip-blt) [344.930498] Starting subtest: fbcpsr-1p-primscrn-shrfb-msflip-blt [344.932197] Subtest fbcpsr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s) [345.020308] [018/142] (645s left) kms_draw_crc (draw-method-render) [346.993647] Starting subtest: draw-method-render [346.994458] Starting dynamic subtest: xrgb8888-untiled [347.376610] Dynamic subtest xrgb8888-untiled: SUCCESS (0.382s) [347.389515] Starting dynamic subtest: rgb565-untiled [347.693325] Dynamic subtest rgb565-untiled: SUCCESS (0.316s) [347.700677] Starting dynamic subtest: xrgb2101010-untiled [347.977276] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.283s) [347.996724] Starting dynamic subtest: xrgb8888-xtiled [348.159122] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.182s) [348.170911] Starting dynamic subtest: rgb565-xtiled [348.310332] Dynamic subtest rgb565-xtiled: SUCCESS (0.149s) [348.322570] Starting dynamic subtest: xrgb2101010-xtiled [348.460210] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.149s) [348.476264] Starting dynamic subtest: xrgb8888-4tiled [348.625901] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.166s) [348.636583] Starting dynamic subtest: rgb565-4tiled [348.776633] Dynamic subtest rgb565-4tiled: SUCCESS (0.150s) [348.789028] Starting dynamic subtest: xrgb2101010-4tiled [348.926511] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.149s) [348.927957] Subtest draw-method-render: SUCCESS (1.932s) [349.004244] [019/142] (641s left) gem_vm_create (isolation) [349.073987] Starting subtest: isolation [349.083271] Subtest isolation: SUCCESS (0.009s) [349.181404] [020/142] (640s left) drm_fdinfo (busy-check-all) [349.243532] Starting subtest: busy-check-all [349.244462] Starting dynamic subtest: rcs0 [349.261021] Dynamic subtest rcs0: SKIP (0.016s) [349.267129] Starting dynamic subtest: bcs0 [349.268667] Dynamic subtest bcs0: SKIP (0.000s) [349.274520] Starting dynamic subtest: vcs0 [349.276111] Dynamic subtest vcs0: SKIP (0.000s) [349.281703] Starting dynamic subtest: vcs1 [349.283356] Dynamic subtest vcs1: SKIP (0.000s) [349.288944] Starting dynamic subtest: vecs0 [349.290494] Dynamic subtest vecs0: SKIP (0.000s) [349.296175] Starting dynamic subtest: vecs1 [349.297718] Dynamic subtest vecs1: SKIP (0.000s) [349.303309] Starting dynamic subtest: ccs0 [349.309920] Dynamic subtest ccs0: SKIP (0.000s) [349.315710] Starting dynamic subtest: ccs1 [349.317251] Dynamic subtest ccs1: SKIP (0.000s) [349.323443] Starting dynamic subtest: ccs2 [349.324992] Dynamic subtest ccs2: SKIP (0.000s) [349.330587] Starting dynamic subtest: ccs3 [349.332093] Dynamic subtest ccs3: SKIP (0.000s) [349.333569] Subtest busy-check-all: SKIP (0.021s) [349.345013] [021/142] (640s left) gem_softpin (allocator-evict) [349.448088] Starting subtest: allocator-evict [349.448921] Starting dynamic subtest: rcs0 [375.011779] Dynamic subtest rcs0: SUCCESS (25.555s) [375.017905] Starting dynamic subtest: bcs0 [401.750751] Dynamic subtest bcs0: SUCCESS (26.739s) [401.756834] Starting dynamic subtest: vcs0 [427.720839] Dynamic subtest vcs0: SUCCESS (25.970s) [427.726852] Starting dynamic subtest: vcs1 [453.719795] Dynamic subtest vcs1: SUCCESS (25.999s) [453.725788] Starting dynamic subtest: vecs0 [479.426775] Dynamic subtest vecs0: SUCCESS (25.707s) [479.432890] Starting dynamic subtest: vecs1 [505.122040] Dynamic subtest vecs1: SUCCESS (25.695s) [505.128133] Starting dynamic subtest: ccs0 [530.915873] Dynamic subtest ccs0: SUCCESS (25.794s) [530.921915] Starting dynamic subtest: ccs1 [557.632735] Dynamic subtest ccs1: SUCCESS (26.717s) [557.638981] Starting dynamic subtest: ccs2 [583.371348] Dynamic subtest ccs2: SUCCESS (25.738s) [583.377571] Starting dynamic subtest: ccs3 [609.125869] Dynamic subtest ccs3: SUCCESS (25.754s) [609.126847] Subtest allocator-evict: SUCCESS (259.669s) [609.261744] [022/142] (380s left) gem_userptr_blits (stress-mm-invalidate-close-overlap) [609.371388] Starting subtest: stress-mm-invalidate-close-overlap [611.528260] Subtest stress-mm-invalidate-close-overlap: SUCCESS (2.148s) [611.629252] [023/142] (378s left) gem_exec_schedule (submit-early-slice) [611.741890] Starting subtest: submit-early-slice [611.742647] Starting dynamic subtest: rcs0 [611.850159] Dynamic subtest rcs0: SUCCESS (0.108s) [611.858227] Starting dynamic subtest: bcs0 [611.920068] Dynamic subtest bcs0: SUCCESS (0.070s) [611.926379] Starting dynamic subtest: vcs0 [611.984235] Dynamic subtest vcs0: SUCCESS (0.064s) [611.990719] Starting dynamic subtest: vcs1 [612.048261] Dynamic subtest vcs1: SUCCESS (0.064s) [612.054388] Starting dynamic subtest: vecs0 [612.114703] Dynamic subtest vecs0: SUCCESS (0.066s) [612.120880] Starting dynamic subtest: vecs1 [612.178604] Dynamic subtest vecs1: SUCCESS (0.064s) [612.184867] Starting dynamic subtest: ccs0 [612.251235] Dynamic subtest ccs0: SUCCESS (0.072s) [612.258251] Starting dynamic subtest: ccs1 [612.314065] Dynamic subtest ccs1: SUCCESS (0.062s) [612.328980] Starting dynamic subtest: ccs2 [612.390444] Dynamic subtest ccs2: SUCCESS (0.076s) [612.396705] Starting dynamic subtest: ccs3 [612.453993] Dynamic subtest ccs3: SUCCESS (0.063s) [612.454850] Subtest submit-early-slice: SUCCESS (0.712s) [612.853208] [024/142] (377s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled) [612.915537] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled [612.916493] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.000s) [612.982055] [025/142] (377s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-render) [614.970855] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-render [617.986336] Subtest fbc-1p-primscrn-pri-indfb-draw-render: SUCCESS (3.015s) [618.095385] [026/142] (372s left) kms_ccs (pipe-d-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc) [620.208852] Starting subtest: pipe-D-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc [620.511669] Subtest pipe-D-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc: SUCCESS (0.303s) [620.691990] [027/142] (369s left) kms_pipe_b_c_ivb (disable-pipe-b-enable-pipe-c) [620.757052] Subtest disable-pipe-B-enable-pipe-C: SKIP (0.000s) [620.786918] [028/142] (369s left) vc4/vc4_purgeable_bo (access-purged-bo-mem) [620.878589] Subtest access-purged-bo-mem: SKIP (0.000s) [620.927080] [029/142] (369s left) perf_pmu (busy-accuracy-50) [620.999927] Starting subtest: busy-accuracy-50 [621.000729] Starting dynamic subtest: rcs0 [622.542524] Dynamic subtest rcs0: SUCCESS (1.541s) [622.549502] Starting dynamic subtest: bcs0 [624.090807] Dynamic subtest bcs0: SUCCESS (1.548s) [624.098175] Starting dynamic subtest: vcs0 [625.635728] Dynamic subtest vcs0: SUCCESS (1.545s) [625.642990] Starting dynamic subtest: vcs1 [627.184787] Dynamic subtest vcs1: SUCCESS (1.549s) [627.192027] Starting dynamic subtest: vecs0 [628.731740] Dynamic subtest vecs0: SUCCESS (1.547s) [628.738941] Starting dynamic subtest: vecs1 [630.278959] Dynamic subtest vecs1: SUCCESS (1.547s) [630.286409] Starting dynamic subtest: ccs0 [631.827515] Dynamic subtest ccs0: SUCCESS (1.549s) [631.834854] Starting dynamic subtest: ccs1 [633.372532] Dynamic subtest ccs1: SUCCESS (1.545s) [633.379872] Starting dynamic subtest: ccs2 [634.922618] Dynamic subtest ccs2: SUCCESS (1.549s) [634.929929] Starting dynamic subtest: ccs3 [636.464915] Dynamic subtest ccs3: SUCCESS (1.542s) [636.466131] Subtest busy-accuracy-50: SUCCESS (15.463s) [636.562029] [030/142] (353s left) kms_ccs (pipe-c-missing-ccs-buffer-4_tiled_mtl_mc_ccs) [638.631330] Starting subtest: pipe-C-missing-ccs-buffer-4_tiled_mtl_mc_ccs [638.633055] Subtest pipe-C-missing-ccs-buffer-4_tiled_mtl_mc_ccs: SKIP (0.000s) [638.764491] [031/142] (351s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling) [640.724033] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling [640.724956] Starting dynamic subtest: pipe-A-valid-mode [640.773547] Dynamic subtest pipe-A-valid-mode: SKIP (0.048s) [640.775284] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.049s) [640.857960] [032/142] (349s left) i915_query (test-query-geometry-subslices) [640.917526] Starting subtest: test-query-geometry-subslices [640.918459] Subtest test-query-geometry-subslices: SUCCESS (0.000s) [640.975703] [033/142] (349s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc) [642.937183] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc [642.938804] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [643.007752] [034/142] (347s left) kms_lease (atomic_implicit_crtc) [644.975950] Starting subtest: atomic_implicit_crtc [644.977009] Starting dynamic subtest: pipe-A-HDMI-A-1 [644.978034] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.001s) [644.985217] Starting dynamic subtest: pipe-B-HDMI-A-1 [644.986049] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.006s) [644.993085] Starting dynamic subtest: pipe-C-HDMI-A-1 [644.994466] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.006s) [645.001861] Starting dynamic subtest: pipe-D-HDMI-A-1 [645.002610] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.006s) [645.003384] Subtest atomic_implicit_crtc: SUCCESS (0.023s) [645.093448] [035/142] (345s left) prime_vgem (basic-write) [645.188514] Starting subtest: basic-write [645.196176] Subtest basic-write: SKIP (0.007s) [645.282053] [036/142] (345s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite) [647.262040] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite [647.263754] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [647.341832] [037/142] (343s left) gem_exec_reloc (basic-softpin) [647.431359] Subtest basic-softpin: SKIP (0.000s) [647.490790] [038/142] (342s left) kms_ccs (pipe-b-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs) [649.559292] Starting subtest: pipe-B-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs [649.562135] Subtest pipe-B-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs: SKIP (0.002s) [649.716520] [039/142] (340s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [651.675815] Starting subtest: basic-flip-after-cursor-legacy [652.740761] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.065s) [652.866102] [040/142] (337s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip) [654.836264] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip [654.838029] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s) [654.899167] [041/142] (335s left) kms_flip (absolute-wf_vblank-interruptible) [655.924727] Starting subtest: absolute-wf_vblank-interruptible [655.929417] Starting dynamic subtest: A-HDMI-A1 [663.648210] Dynamic subtest A-HDMI-A1: SUCCESS (7.717s) [663.655999] Starting dynamic subtest: B-HDMI-A1 [671.293253] Dynamic subtest B-HDMI-A1: SUCCESS (7.646s) [671.300578] Starting dynamic subtest: C-HDMI-A1 [678.937127] Dynamic subtest C-HDMI-A1: SUCCESS (7.643s) [678.944360] Starting dynamic subtest: D-HDMI-A1 [686.582337] Dynamic subtest D-HDMI-A1: SUCCESS (7.645s) [686.584470] Subtest absolute-wf_vblank-interruptible: SUCCESS (30.660s) [686.746208] [042/142] (303s left) gem_ctx_param (invalid-get-engines) [686.804448] Starting subtest: invalid-get-engines [686.805231] Subtest invalid-get-engines: SUCCESS (0.000s) [686.871690] [043/142] (303s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-blt) [688.838847] Starting subtest: fbcpsr-rgb101010-draw-blt [688.840444] Subtest fbcpsr-rgb101010-draw-blt: SKIP (0.000s) [688.903507] [044/142] (301s left) kms_plane (plane-position-hole) [690.857939] Starting subtest: plane-position-hole [690.858864] Starting dynamic subtest: pipe-A-planes [692.647516] Dynamic subtest pipe-A-planes: SUCCESS (1.789s) [692.656292] Starting dynamic subtest: pipe-B-planes [694.139261] Dynamic subtest pipe-B-planes: SUCCESS (1.490s) [694.140185] Subtest plane-position-hole: SUCCESS (3.282s) [694.272476] [045/142] (296s left) i915_selftest (perf) [696.326224] Starting subtest: perf [696.328236] Starting dynamic subtest: engine_cs [697.995381] Dynamic subtest engine_cs: SUCCESS (1.663s) [698.001385] Starting dynamic subtest: request [702.559350] Dynamic subtest request: SUCCESS (4.563s) [702.565438] Starting dynamic subtest: migrate [703.982360] Dynamic subtest migrate: SUCCESS (1.422s) [703.987794] Starting dynamic subtest: region [731.654280] Dynamic subtest region: SUCCESS (27.671s) [731.655255] Subtest perf: SUCCESS (35.323s) [731.666937] [046/142] (258s left) gem_exec_whisper (basic-forked-all) [733.147782] Starting subtest: basic-forked-all [752.334486] Subtest basic-forked-all: SUCCESS (19.176s) [753.430761] [047/142] (237s left) kms_frontbuffer_tracking (fbc-stridechange) [755.389109] Starting subtest: fbc-stridechange [757.806900] Subtest fbc-stridechange: SUCCESS (2.416s) [757.935868] [048/142] (232s left) kms_cursor_crc (cursor-offscreen-256x85) [760.061940] Starting subtest: cursor-offscreen-256x85 [760.066416] Starting dynamic subtest: pipe-A-HDMI-A-1 [762.589921] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.523s) [762.612111] Starting dynamic subtest: pipe-D-HDMI-A-1 [765.051032] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (2.444s) [765.051937] Subtest cursor-offscreen-256x85: SUCCESS (4.989s) [765.241778] [049/142] (225s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions) [767.210635] Starting subtest: cursorB-vs-flipA-atomic-transitions [767.218300] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.009s) [767.298782] [050/142] (223s left) gem_ctx_param (non-root-set) [767.361663] Starting subtest: non-root-set [767.369417] Subtest non-root-set: SUCCESS (0.009s) [767.423322] [051/142] (223s left) kms_vblank (pipe-d-ts-continuation-idle-hang) [769.385346] Starting subtest: pipe-D-ts-continuation-idle-hang [783.983038] Subtest pipe-D-ts-continuation-idle-hang: SUCCESS (14.599s) [784.111254] [052/142] (206s left) kms_vblank (pipe-b-query-forked) [786.071281] Starting subtest: pipe-B-query-forked [788.412525] Subtest pipe-B-query-forked: SUCCESS (2.341s) [788.514532] [053/142] (202s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size) [790.471656] Starting subtest: cursorB-vs-flipA-varying-size [790.473471] Subtest cursorB-vs-flipA-varying-size: SKIP (0.002s) [790.530295] [054/142] (200s left) gem_busy (busy) [790.603692] Starting subtest: busy [790.604573] Starting dynamic subtest: all-engines [790.705158] Dynamic subtest all-engines: SUCCESS (0.101s) [790.713227] Starting dynamic subtest: rcs0 [790.785273] Dynamic subtest rcs0: SUCCESS (0.079s) [790.792332] Starting dynamic subtest: bcs0 [790.825350] Dynamic subtest bcs0: SUCCESS (0.039s) [790.835207] Starting dynamic subtest: vcs0 [790.866564] Dynamic subtest vcs0: SUCCESS (0.039s) [790.891465] Starting dynamic subtest: vcs1 [790.908879] Dynamic subtest vcs1: SUCCESS (0.040s) [790.930061] Starting dynamic subtest: vecs0 [790.948871] Dynamic subtest vecs0: SUCCESS (0.038s) [790.961043] Starting dynamic subtest: vecs1 [790.989147] Dynamic subtest vecs1: SUCCESS (0.038s) [791.004989] Starting dynamic subtest: ccs0 [791.028562] Dynamic subtest ccs0: SUCCESS (0.039s) [791.046910] Starting dynamic subtest: ccs1 [791.070944] Dynamic subtest ccs1: SUCCESS (0.040s) [791.096165] Starting dynamic subtest: ccs2 [791.112609] Dynamic subtest ccs2: SUCCESS (0.040s) [791.130076] Starting dynamic subtest: ccs3 [791.152942] Dynamic subtest ccs3: SUCCESS (0.038s) [791.153948] Subtest busy: SUCCESS (0.549s) [791.534202] [055/142] (199s left) kms_flip (2x-blocking-absolute-wf_vblank) [792.586147] Starting subtest: 2x-blocking-absolute-wf_vblank [792.602397] Subtest 2x-blocking-absolute-wf_vblank: SKIP (0.017s) [792.664490] [056/142] (198s left) kms_vblank (pipe-c-wait-forked) [794.636836] Starting subtest: pipe-C-wait-forked [797.104973] Subtest pipe-C-wait-forked: SUCCESS (2.469s) [797.216488] [057/142] (193s left) kms_color (ctm-green-to-red) [799.174281] Starting subtest: ctm-green-to-red [799.175199] Starting dynamic subtest: pipe-A [799.822536] Dynamic subtest pipe-A: SUCCESS (0.649s) [799.829535] Starting dynamic subtest: pipe-B [800.268508] Dynamic subtest pipe-B: SUCCESS (0.444s) [800.277416] Starting dynamic subtest: pipe-C [800.792764] Dynamic subtest pipe-C: SUCCESS (0.523s) [800.800769] Starting dynamic subtest: pipe-D [801.352992] Dynamic subtest pipe-D: SUCCESS (0.561s) [801.353835] Subtest ctm-green-to-red: SUCCESS (2.181s) [801.470115] [058/142] (189s left) kms_plane_scaling (planes-downscale-factor-0-75) [803.422240] Starting subtest: planes-downscale-factor-0-75 [803.423062] Starting dynamic subtest: pipe-A-HDMI-A-1 [803.505990] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.082s) [803.512727] Starting dynamic subtest: pipe-B-HDMI-A-1 [803.611320] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.104s) [803.621258] Starting dynamic subtest: pipe-C-HDMI-A-1 [803.716865] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.101s) [803.724888] Starting dynamic subtest: pipe-D-HDMI-A-1 [803.823528] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.105s) [803.824394] Subtest planes-downscale-factor-0-75: SUCCESS (0.403s) [803.927120] [059/142] (186s left) i915_module_load (load) [803.986706] Starting subtest: load [803.988511] Subtest load: SKIP (0.001s) [804.027187] [060/142] (186s left) kms_selftest (drm_plane) [804.074724] Starting subtest: drm_plane [804.077840] Subtest drm_plane: SKIP (0.001s) [804.111268] [061/142] (186s left) gem_userptr_blits (coherency-unsync) [804.175849] Subtest coherency-unsync: SKIP (0.000s) [804.276939] [062/142] (186s left) kms_big_fb (y-tiled-32bpp-rotate-90) [806.228550] Starting subtest: y-tiled-32bpp-rotate-90 [806.230447] Subtest y-tiled-32bpp-rotate-90: SKIP (0.000s) [806.310553] [063/142] (184s left) gem_mmap_offset (open-flood) [806.389590] Starting subtest: open-flood [827.874600] Subtest open-flood: SUCCESS (21.486s) [827.969494] [064/142] (162s left) vc4/vc4_wait_bo (bad-bo) [828.082525] Subtest bad-bo: SKIP (0.000s) [828.131690] [065/142] (162s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt) [830.104734] Starting subtest: fbc-farfromfence-mmap-gtt [830.106443] Subtest fbc-farfromfence-mmap-gtt: SKIP (0.000s) [830.177858] [066/142] (160s left) gem_userptr_blits (sd-probe) [830.256464] Starting subtest: sd-probe [830.258463] Subtest sd-probe: SKIP (0.000s) [830.391909] [067/142] (160s left) kms_chamelium_frames (hdmi-frame-dump) [832.422255] Subtest hdmi-frame-dump: SKIP (0.000s) [832.482764] [068/142] (158s left) gem_exec_flush (basic-uc-pro-default) [832.551483] Subtest basic-uc-pro-default: SKIP (0.000s) [832.607735] [069/142] (158s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-render) [834.567370] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-render [834.568924] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s) [834.646501] [070/142] (156s left) kms_vblank (pipe-a-wait-forked-hang) [836.615872] Starting subtest: pipe-A-wait-forked-hang [851.003814] Subtest pipe-A-wait-forked-hang: SUCCESS (14.389s) [851.101579] [071/142] (139s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-wc) [853.062978] Starting subtest: fbc-rgb101010-draw-mmap-wc [853.064515] Subtest fbc-rgb101010-draw-mmap-wc: SKIP (0.000s) [853.135311] [072/142] (137s left) kms_chamelium_color (ctm-negative) [855.104204] Subtest ctm-negative: SKIP (0.000s) [855.130440] [073/142] (135s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-mmap-cpu) [857.092006] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-mmap-cpu [859.523804] Subtest fbc-1p-offscren-pri-shrfb-draw-mmap-cpu: SUCCESS (2.433s) [859.652681] [074/142] (131s left) vgem_basic (debugfs) [859.721852] Starting subtest: debugfs [859.722632] Subtest debugfs: SUCCESS (0.000s) [859.776804] [075/142] (131s left) gem_pwrite (basic-exhaustion) [859.867664] Subtest basic-exhaustion: SKIP (0.000s) [859.913290] [076/142] (131s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip) [861.880771] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip [862.820024] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.939s) [862.937911] [077/142] (128s left) kms_legacy_colorkey (invalid-plane) [864.894816] Starting subtest: invalid-plane [864.895640] Starting dynamic subtest: zero-id [864.896493] Dynamic subtest zero-id: SUCCESS (0.000s) [864.903158] Starting dynamic subtest: outrange-id [864.904075] Dynamic subtest outrange-id: SUCCESS (0.000s) [864.904989] Subtest invalid-plane: SUCCESS (0.001s) [864.951562] [078/142] (126s left) gem_userptr_blits (readonly-unsync) [865.026611] Subtest readonly-unsync: SKIP (0.000s) [865.146211] [079/142] (125s left) api_intel_bb (bb-with-allocator) [865.233776] Starting subtest: bb-with-allocator [865.258296] Subtest bb-with-allocator: SUCCESS (0.025s) [865.345662] [080/142] (125s left) syncobj_timeline (wait-all-for-submit-complex) [865.409518] Starting subtest: wait-all-for-submit-complex [865.635102] Subtest wait-all-for-submit-complex: SUCCESS (0.217s) [865.700383] [081/142] (125s left) kms_content_protection (legacy) [867.732346] Starting subtest: legacy [869.121748] Subtest legacy: SKIP (1.387s) [869.273571] [082/142] (121s left) i915_hangman (engine-error-state-capture) [869.344956] Starting subtest: engine-error-state-capture [869.345799] Starting dynamic subtest: rcs0 [872.063254] Dynamic subtest rcs0: SUCCESS (2.718s) [872.070051] Starting dynamic subtest: bcs0 [876.015343] Dynamic subtest bcs0: SUCCESS (3.951s) [876.023392] Starting dynamic subtest: vcs0 [880.053269] Dynamic subtest vcs0: SUCCESS (4.037s) [880.061361] Starting dynamic subtest: vcs1 [884.013273] Dynamic subtest vcs1: SUCCESS (3.959s) [884.020289] Starting dynamic subtest: vecs0 [888.052423] Dynamic subtest vecs0: SUCCESS (4.038s) [888.059329] Starting dynamic subtest: vecs1 [892.007306] Dynamic subtest vecs1: SUCCESS (3.954s) [892.014326] Starting dynamic subtest: ccs0 [896.064328] Dynamic subtest ccs0: SUCCESS (4.056s) [896.071299] Starting dynamic subtest: ccs1 [900.042595] Dynamic subtest ccs1: SUCCESS (3.978s) [900.050024] Starting dynamic subtest: ccs2 [904.067234] Dynamic subtest ccs2: SUCCESS (4.024s) [904.074154] Starting dynamic subtest: ccs3 [908.009237] Dynamic subtest ccs3: SUCCESS (3.941s) [908.010150] Subtest engine-error-state-capture: SUCCESS (38.665s) [908.113604] [083/142] ( 83s left) prime_vgem (coherency-blt) [908.191948] Starting subtest: coherency-blt [910.092968] Subtest coherency-blt: SUCCESS (1.892s) [910.231953] [084/142] ( 80s left) kms_flip (wf_vblank-ts-check-interruptible) [911.259149] Starting subtest: wf_vblank-ts-check-interruptible [911.264206] Starting dynamic subtest: A-HDMI-A1 [919.257330] Dynamic subtest A-HDMI-A1: SUCCESS (7.992s) [919.264840] Starting dynamic subtest: B-HDMI-A1 [927.185263] Dynamic subtest B-HDMI-A1: SUCCESS (7.928s) [927.192638] Starting dynamic subtest: C-HDMI-A1 [935.136217] Dynamic subtest C-HDMI-A1: SUCCESS (7.928s) [935.143342] Starting dynamic subtest: D-HDMI-A1 [943.040336] Dynamic subtest D-HDMI-A1: SUCCESS (7.925s) [943.042641] Subtest wf_vblank-ts-check-interruptible: SUCCESS (31.784s) [943.179613] [085/142] ( 48s left) i915_pm_dc (dc9-dpms) [946.144369] Starting subtest: dc9-dpms [947.363412] Subtest dc9-dpms: SUCCESS (1.218s) [947.529375] [086/142] ( 43s left) kms_cursor_crc (cursor-rapid-movement-64x21) [949.629199] Starting subtest: cursor-rapid-movement-64x21 [949.634417] Starting dynamic subtest: pipe-A-HDMI-A-1 [950.157802] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.524s) [950.181183] Starting dynamic subtest: pipe-D-HDMI-A-1 [950.601509] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.427s) [950.602303] Subtest cursor-rapid-movement-64x21: SUCCESS (0.973s) [950.877553] [087/142] ( 40s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling) [952.827488] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling [952.828419] Starting dynamic subtest: pipe-A-valid-mode [955.827058] Dynamic subtest pipe-A-valid-mode: SUCCESS (3.000s) [955.828121] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SUCCESS (3.001s) [955.943358] [088/142] ( 35s left) gem_ctx_persistence (heartbeat-hostile) [956.007835] Starting subtest: heartbeat-hostile [956.009655] Subtest heartbeat-hostile: SKIP (0.000s) [956.076813] [089/142] ( 35s left) gem_mmap_gtt (cpuset-big-copy-odd) [956.138114] Subtest cpuset-big-copy-odd: SKIP (0.000s) [956.171920] [090/142] ( 35s left) kms_ccs (pipe-a-bad-aux-stride-yf_tiled_ccs) [958.189713] Starting subtest: pipe-A-bad-aux-stride-yf_tiled_ccs [958.191423] Subtest pipe-A-bad-aux-stride-yf_tiled_ccs: SKIP (0.000s) [958.386100] [091/142] ( 32s left) kms_vblank (pipe-b-ts-continuation-modeset) [960.341461] Starting subtest: pipe-B-ts-continuation-modeset [960.727756] Subtest pipe-B-ts-continuation-modeset: SUCCESS (0.388s) [960.825295] [092/142] ( 30s left) perf_pmu (semaphore-wait) [960.893259] Starting subtest: semaphore-wait [960.894101] Starting dynamic subtest: rcs0 [961.428405] Dynamic subtest rcs0: SUCCESS (0.526s) [961.435363] Starting dynamic subtest: bcs0 [961.943109] Dynamic subtest bcs0: SUCCESS (0.511s) [961.950055] Starting dynamic subtest: vcs0 [962.456206] Dynamic subtest vcs0: SUCCESS (0.510s) [962.463156] Starting dynamic subtest: vcs1 [962.970344] Dynamic subtest vcs1: SUCCESS (0.511s) [962.977438] Starting dynamic subtest: vecs0 [963.484221] Dynamic subtest vecs0: SUCCESS (0.511s) [963.491253] Starting dynamic subtest: vecs1 [963.996713] Dynamic subtest vecs1: SUCCESS (0.509s) [964.003947] Starting dynamic subtest: ccs0 [964.508976] Dynamic subtest ccs0: SUCCESS (0.509s) [964.516011] Starting dynamic subtest: ccs1 [965.021144] Dynamic subtest ccs1: SUCCESS (0.509s) [965.028269] Starting dynamic subtest: ccs2 [965.533319] Dynamic subtest ccs2: SUCCESS (0.509s) [965.540582] Starting dynamic subtest: ccs3 [966.046119] Dynamic subtest ccs3: SUCCESS (0.509s) [966.046926] Subtest semaphore-wait: SUCCESS (5.146s) [966.156341] [093/142] ( 25s left) v3d/v3d_create_bo (create-bo-0) [966.244515] Subtest create-bo-0: SKIP (0.000s) [966.294650] [094/142] ( 25s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-move) [968.268840] Starting subtest: psr-1p-primscrn-spr-indfb-move [968.270319] Subtest psr-1p-primscrn-spr-indfb-move: SKIP (0.000s) [968.342459] [095/142] ( 23s left) kms_big_fb (4-tiled-32bpp-rotate-270) [970.317382] Starting subtest: 4-tiled-32bpp-rotate-270 [970.362018] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.043s) [970.463953] [096/142] ( 20s left) kms_ccs (pipe-b-random-ccs-data-y_tiled_ccs) [972.545233] Starting subtest: pipe-B-random-ccs-data-y_tiled_ccs [972.547629] Subtest pipe-B-random-ccs-data-y_tiled_ccs: SKIP (0.000s) [972.715988] [097/142] ( 18s left) gem_exec_fence (basic-wait) [972.902517] Starting subtest: basic-wait [972.903443] Starting dynamic subtest: rcs0 [972.988046] Dynamic subtest rcs0: SUCCESS (0.093s) [973.020561] Starting dynamic subtest: bcs0 [973.051876] Dynamic subtest bcs0: SUCCESS (0.061s) [973.084733] Starting dynamic subtest: vcs0 [973.110212] Dynamic subtest vcs0: SUCCESS (0.056s) [973.128234] Starting dynamic subtest: vcs1 [973.164343] Dynamic subtest vcs1: SUCCESS (0.052s) [973.197892] Starting dynamic subtest: vecs0 [973.218380] Dynamic subtest vecs0: SUCCESS (0.052s) [973.242065] Starting dynamic subtest: vecs1 [973.276497] Dynamic subtest vecs1: SUCCESS (0.056s) [973.304806] Starting dynamic subtest: ccs0 [973.343305] Dynamic subtest ccs0: SUCCESS (0.064s) [973.377695] Starting dynamic subtest: ccs1 [973.409960] Dynamic subtest ccs1: SUCCESS (0.064s) [973.440889] Starting dynamic subtest: ccs2 [973.473549] Dynamic subtest ccs2: SUCCESS (0.061s) [973.499847] Starting dynamic subtest: ccs3 [973.538206] Dynamic subtest ccs3: SUCCESS (0.062s) [973.540181] Subtest basic-wait: SUCCESS (0.645s) [974.535987] [098/142] ( 16s left) syncobj_timeline (invalid-single-wait-unsubmitted) [974.596362] Starting subtest: invalid-single-wait-unsubmitted [974.597343] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s) [974.679777] [099/142] ( 16s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-wc) [976.674144] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-wc [976.675820] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s) [976.749360] [100/142] ( 14s left) kms_big_fb (4-tiled-8bpp-rotate-270) [978.733262] Starting subtest: 4-tiled-8bpp-rotate-270 [978.786230] Subtest 4-tiled-8bpp-rotate-270: SKIP (0.051s) [978.894070] [101/142] ( 12s left) kms_frontbuffer_tracking (fbc-rgb565-draw-render) [980.905917] Starting subtest: fbc-rgb565-draw-render [983.905626] Subtest fbc-rgb565-draw-render: SUCCESS (3.000s) [983.999227] [102/142] ( 7s left) prime_self_import (basic-llseek-size) [984.049227] Starting subtest: basic-llseek-size [984.061633] Subtest basic-llseek-size: SUCCESS (0.012s) [984.147202] [103/142] ( 7s left) gem_exec_reloc (basic-write-wc) [984.216792] Subtest basic-write-wc: SKIP (0.000s) [984.259800] [104/142] ( 7s left) gem_ctx_shared (q-smoketest) [984.331946] Starting subtest: Q-smoketest [984.332841] Starting dynamic subtest: rcs0 [989.808751] Dynamic subtest rcs0: SUCCESS (5.438s) [989.815826] Starting dynamic subtest: bcs0 [995.222020] Dynamic subtest bcs0: SUCCESS (5.413s) [995.229977] Starting dynamic subtest: vcs0 [1000.629939] Dynamic subtest vcs0: SUCCESS (5.407s) [1000.637221] Starting dynamic subtest: vcs1 [1006.040237] Dynamic subtest vcs1: SUCCESS (5.409s) [1006.048122] Starting dynamic subtest: vecs0 [1011.449043] Dynamic subtest vecs0: SUCCESS (5.408s) [1011.456155] Starting dynamic subtest: vecs1 [1016.839812] Dynamic subtest vecs1: SUCCESS (5.415s) [1016.846953] Starting dynamic subtest: ccs0 [1022.280847] Dynamic subtest ccs0: SUCCESS (5.415s) [1022.287868] Starting dynamic subtest: ccs1 [1027.689875] Dynamic subtest ccs1: SUCCESS (5.408s) [1027.697145] Starting dynamic subtest: ccs2 [1033.099970] Dynamic subtest ccs2: SUCCESS (5.409s) [1033.106980] Starting dynamic subtest: ccs3 [1038.507755] Dynamic subtest ccs3: SUCCESS (5.405s) [1038.509220] Subtest Q-smoketest: SUCCESS (54.136s) [1038.708771] Overall timeout time exceeded, stopping. [1038.709262] Closing watchdogs Done.