[42.190166] Initializing watchdogs
[42.190210]   /dev/watchdog0
[42.196688] [001/141] (960s left) kms_color (ctm-blue-to-red)
[42.272409] Starting subtest: ctm-blue-to-red
[42.274751] Starting dynamic subtest: pipe-A
[43.055364] Dynamic subtest pipe-A: SUCCESS (0.783s)
[43.064768] Starting dynamic subtest: pipe-B
[44.565689] Dynamic subtest pipe-B: SUCCESS (1.511s)
[44.575798] Starting dynamic subtest: pipe-C
[46.036092] Dynamic subtest pipe-C: SUCCESS (1.469s)
[46.050893] Starting dynamic subtest: pipe-D
[47.509790] Dynamic subtest pipe-D: SUCCESS (1.473s)
[47.511865] Subtest ctm-blue-to-red: SUCCESS (5.238s)
[48.446594] [002/141] (954s left) kms_vblank (pipe-c-query-forked)
[48.535970] Starting subtest: pipe-C-query-forked
[51.479066] Subtest pipe-C-query-forked: SUCCESS (2.943s)
[52.400659] [003/141] (950s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[52.498746] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[52.502517] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[52.577647] [004/141] (950s left) kms_plane (pixel-format)
[52.676216] Starting subtest: pixel-format
[52.678595] Starting dynamic subtest: pipe-A-planes
[61.423064] Dynamic subtest pipe-A-planes: SUCCESS (8.742s)
[61.434092] Starting dynamic subtest: pipe-B-planes
[71.025839] Dynamic subtest pipe-B-planes: SUCCESS (9.601s)
[71.028648] Subtest pixel-format: SUCCESS (18.348s)
[71.933464] [005/141] (930s left) kms_vrr (flip-suspend)
[72.014035] Starting subtest: flip-suspend
[72.018076] Subtest flip-suspend: SKIP (0.000s)
[72.075776] [006/141] (930s left) gem_lmem_swapping (parallel-random-verify)
[72.145155] Subtest parallel-random-verify: SKIP (0.000s)
[72.161226] [007/141] (930s left) kms_pm_lpsp (screens-disabled)
[73.263746] Starting subtest: screens-disabled
[73.268194] Subtest screens-disabled: SKIP (0.000s)
[73.325909] [008/141] (929s left) kms_ccs (pipe-d-crc-primary-basic-y_tiled_gen12_rc_ccs_cc)
[73.725779] Starting subtest: pipe-D-crc-primary-basic-y_tiled_gen12_rc_ccs_cc
[73.731556] Subtest pipe-D-crc-primary-basic-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[74.622109] [009/141] (928s left) gem_userptr_blits (sync-unmap-cycles)
[74.703683] Starting subtest: sync-unmap-cycles
[80.074786] Subtest sync-unmap-cycles: SUCCESS (5.373s)
[80.176287] [010/141] (922s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-msflip-blt)
[80.255761] Starting subtest: fbc-1p-primscrn-shrfb-msflip-blt
[83.658690] Subtest fbc-1p-primscrn-shrfb-msflip-blt: SUCCESS (3.409s)
[83.773750] [011/141] (919s left) kms_ccs (pipe-d-bad-rotation-90-4_tiled_dg2_rc_ccs_cc)
[84.230553] Starting subtest: pipe-D-bad-rotation-90-4_tiled_dg2_rc_ccs_cc
[84.236311] Subtest pipe-D-bad-rotation-90-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[85.046257] [012/141] (917s left) gem_exec_parallel (userptr)
[85.116260] Starting subtest: userptr
[85.119427] Starting dynamic subtest: rcs0
[86.583896] Dynamic subtest rcs0: SUCCESS (1.468s)
[86.595432] Starting dynamic subtest: bcs0
[87.981715] Dynamic subtest bcs0: SUCCESS (1.397s)
[87.995720] Starting dynamic subtest: vcs0
[89.367964] Dynamic subtest vcs0: SUCCESS (1.386s)
[89.379873] Starting dynamic subtest: vcs1
[91.426387] Dynamic subtest vcs1: SUCCESS (2.057s)
[91.438540] Starting dynamic subtest: vecs0
[93.401466] Dynamic subtest vecs0: SUCCESS (1.974s)
[93.413427] Starting dynamic subtest: ccs0
[95.250057] Dynamic subtest ccs0: SUCCESS (1.849s)
[95.253131] Subtest userptr: SUCCESS (10.134s)
[95.435612] [013/141] (907s left) feature_discovery (psr2)
[95.489416] Starting subtest: psr2
[95.491833] Subtest psr2: SUCCESS (0.000s)
[95.546942] [014/141] (907s left) kms_plane_alpha_blend (constant-alpha-max)
[95.622561] Starting subtest: constant-alpha-max
[95.848803] Starting dynamic subtest: pipe-A-eDP-1
[96.985527] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.134s)
[96.997093] Starting dynamic subtest: pipe-D-eDP-1
[98.844822] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.854s)
[98.847908] Subtest constant-alpha-max: SUCCESS (3.221s)
[99.760750] [015/141] (903s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-render)
[99.838064] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-render
[99.842019] Subtest psr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[99.922334] [016/141] (903s left) gem_ctx_shared (q-out-order)
[99.972801] Starting subtest: Q-out-order
[99.975457] Starting dynamic subtest: rcs0
[100.076240] Dynamic subtest rcs0: SUCCESS (0.105s)
[100.099309] Starting dynamic subtest: bcs0
[100.155083] Dynamic subtest bcs0: SUCCESS (0.078s)
[100.173955] Starting dynamic subtest: vcs0
[100.212047] Dynamic subtest vcs0: SUCCESS (0.057s)
[100.233303] Starting dynamic subtest: vcs1
[100.269635] Dynamic subtest vcs1: SUCCESS (0.057s)
[100.290561] Starting dynamic subtest: vecs0
[100.326298] Dynamic subtest vecs0: SUCCESS (0.056s)
[100.348447] Starting dynamic subtest: ccs0
[100.399905] Dynamic subtest ccs0: SUCCESS (0.073s)
[100.403105] Subtest Q-out-order: SUCCESS (0.430s)
[101.508396] [017/141] (901s left) gem_softpin (allocator-basic)
[101.573293] Starting subtest: allocator-basic
[101.612429] Subtest allocator-basic: SUCCESS (0.041s)
[101.714176] [018/141] (901s left) gem_ppgtt (blt-vs-render-ctx0)
[101.772445] Starting subtest: blt-vs-render-ctx0
[134.542787] Subtest blt-vs-render-ctx0: SUCCESS (32.769s)
[134.656321] [019/141] (868s left) syncobj_wait (reset-signaled)
[134.801595] Starting subtest: reset-signaled
[134.804045] Subtest reset-signaled: SUCCESS (0.000s)
[134.866279] [020/141] (868s left) api_intel_bb (render-ccs)
[134.924808] Starting subtest: render-ccs
[135.304843] Subtest render-ccs: SUCCESS (0.381s)
[135.384789] [021/141] (867s left) kms_ccs (pipe-a-bad-rotation-90-4_tiled_mtl_rc_ccs_cc)
[135.761452] Starting subtest: pipe-A-bad-rotation-90-4_tiled_mtl_rc_ccs_cc
[136.646322] Subtest pipe-A-bad-rotation-90-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.885s)
[137.828137] [022/141] (865s left) kms_ccs (pipe-c-bad-rotation-90-4_tiled_dg2_rc_ccs)
[138.219709] Starting subtest: pipe-C-bad-rotation-90-4_tiled_dg2_rc_ccs
[138.225504] Subtest pipe-C-bad-rotation-90-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[139.063758] [023/141] (864s left) kms_flip (2x-plain-flip)
[139.128791] Starting subtest: 2x-plain-flip
[139.143037] Subtest 2x-plain-flip: SKIP (0.014s)
[139.203465] [024/141] (864s left) kms_ccs (pipe-b-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc)
[139.612946] Starting subtest: pipe-B-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc
[139.615712] Subtest pipe-B-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.000s)
[140.478771] [025/141] (862s left) kms_big_fb (4-tiled-32bpp-rotate-0)
[140.541659] Starting subtest: 4-tiled-32bpp-rotate-0
[142.053360] Subtest 4-tiled-32bpp-rotate-0: SUCCESS (1.512s)
[142.197945] [026/141] (861s left) syncobj_timeline (multi-wait-available-signaled)
[142.244198] Starting subtest: multi-wait-available-signaled
[142.246300] Subtest multi-wait-available-signaled: SUCCESS (0.001s)
[142.310304] [027/141] (861s left) kms_cursor_legacy (2x-long-flip-vs-cursor-legacy)
[142.422703] Starting subtest: 2x-long-flip-vs-cursor-legacy
[142.427072] Subtest 2x-long-flip-vs-cursor-legacy: SKIP (0.003s)
[142.566725] [028/141] (860s left) gen7_exec_parse (bitmasks)
[142.626618] Subtest bitmasks: SKIP (0.000s)
[142.645346] [029/141] (860s left) kms_big_fb (linear-8bpp-rotate-0)
[142.735372] Starting subtest: linear-8bpp-rotate-0
[143.991203] Subtest linear-8bpp-rotate-0: SUCCESS (1.255s)
[144.092477] [030/141] (859s left) kms_cursor_edge_walk (128x128-top-edge)
[144.180874] Starting subtest: 128x128-top-edge
[144.182906] Starting dynamic subtest: pipe-A-eDP-1
[147.766908] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.584s)
[147.778249] Starting dynamic subtest: pipe-D-eDP-1
[151.008867] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.240s)
[151.011386] Subtest 128x128-top-edge: SUCCESS (6.828s)
[151.923581] [031/141] (851s left) gem_exec_store (cachelines)
[151.998047] Starting subtest: cachelines
[152.000342] Starting dynamic subtest: rcs0
[152.047418] Dynamic subtest rcs0: SUCCESS (0.056s)
[152.060488] Starting dynamic subtest: bcs0
[152.098643] Dynamic subtest bcs0: SUCCESS (0.050s)
[152.111867] Starting dynamic subtest: vcs0
[153.434492] Dynamic subtest vcs0: SUCCESS (1.332s)
[153.448905] Starting dynamic subtest: vcs1
[155.417503] Dynamic subtest vcs1: SUCCESS (1.982s)
[155.431106] Starting dynamic subtest: vecs0
[157.401573] Dynamic subtest vecs0: SUCCESS (1.983s)
[157.415304] Starting dynamic subtest: ccs0
[157.452767] Dynamic subtest ccs0: SUCCESS (0.050s)
[157.455518] Subtest cachelines: SUCCESS (5.460s)
[157.632599] [032/141] (845s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[157.739228] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[162.978561] Subtest psr-1p-primscrn-spr-indfb-onoff: SUCCESS (5.244s)
[163.067447] [033/141] (840s left) kms_chamelium_frames (dp-crc-fast)
[163.446424] Subtest dp-crc-fast: SKIP (0.000s)
[164.313649] [034/141] (839s left) prime_busy (before)
[164.379783] Starting subtest: before
[164.387823] Starting dynamic subtest: rcs0
[164.411712] Dynamic subtest rcs0: SUCCESS (0.024s)
[164.425051] Starting dynamic subtest: bcs0
[164.451451] Dynamic subtest bcs0: SUCCESS (0.038s)
[164.464342] Starting dynamic subtest: vcs0
[164.490114] Dynamic subtest vcs0: SUCCESS (0.037s)
[164.503077] Starting dynamic subtest: vcs1
[164.505604] Dynamic subtest vcs1: SUCCESS (0.013s)
[164.518554] Starting dynamic subtest: vecs0
[164.520954] Dynamic subtest vecs0: SUCCESS (0.014s)
[164.530660] Starting dynamic subtest: ccs0
[164.538737] Dynamic subtest ccs0: SUCCESS (0.018s)
[164.648773] Subtest before: SUCCESS (0.268s)
[164.715492] [035/141] (838s left) kms_atomic (crtc-invalid-params)
[164.818091] Starting subtest: crtc-invalid-params
[164.843337] Subtest crtc-invalid-params: SUCCESS (0.026s)
[166.061066] [036/141] (837s left) kms_psr (psr2_sprite_render)
[166.142330] Starting subtest: psr2_sprite_render
[167.986325] Subtest psr2_sprite_render: SUCCESS (1.844s)
[168.883596] [037/141] (834s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[168.965917] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[168.970451] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[169.052476] [038/141] (834s left) gem_exec_schedule (pi-ringfull)
[169.238270] Starting subtest: pi-ringfull
[169.250284] Starting dynamic subtest: rcs0
[169.316796] Dynamic subtest rcs0: SUCCESS (0.081s)
[169.342303] Starting dynamic subtest: bcs0
[169.399042] Dynamic subtest bcs0: SUCCESS (0.081s)
[169.424153] Starting dynamic subtest: vcs0
[169.480866] Dynamic subtest vcs0: SUCCESS (0.080s)
[169.504420] Starting dynamic subtest: vcs1
[169.562948] Dynamic subtest vcs1: SUCCESS (0.080s)
[169.586929] Starting dynamic subtest: vecs0
[169.645397] Dynamic subtest vecs0: SUCCESS (0.081s)
[169.668867] Starting dynamic subtest: ccs0
[169.727532] Dynamic subtest ccs0: SUCCESS (0.080s)
[169.730488] Subtest pi-ringfull: SUCCESS (0.493s)
[169.943138] [039/141] (833s left) gem_softpin (evict-active)
[170.004185] Starting subtest: evict-active
[170.010570] Subtest evict-active: SUCCESS (0.007s)
[170.104132] [040/141] (833s left) perf_pmu (render-node-busy-idle)
[170.161993] Starting subtest: render-node-busy-idle
[170.164262] Starting dynamic subtest: rcs0
[170.864041] Dynamic subtest rcs0: FAIL (0.666s)
[170.878487] Starting dynamic subtest: bcs0
[171.537474] Dynamic subtest bcs0: FAIL (0.655s)
[171.550787] Starting dynamic subtest: vcs0
[173.467776] Dynamic subtest vcs0: SUCCESS (1.980s)
[173.482224] Starting dynamic subtest: vcs1
[175.449395] Dynamic subtest vcs1: SUCCESS (1.982s)
[175.464345] Starting dynamic subtest: vecs0
[177.433319] Dynamic subtest vecs0: SUCCESS (1.982s)
[177.447954] Starting dynamic subtest: ccs0
[178.119777] Dynamic subtest ccs0: FAIL (0.637s)
[178.122294] Subtest render-node-busy-idle: FAIL (7.911s)
[178.147116] [041/141] (825s left) kms_ccs (pipe-c-bad-pixel-format-yf_tiled_ccs)
[178.543918] Starting subtest: pipe-C-bad-pixel-format-yf_tiled_ccs
[178.549442] Subtest pipe-C-bad-pixel-format-yf_tiled_ccs: SKIP (0.000s)
[179.394543] [042/141] (824s left) gem_ctx_persistence (engines-queued)
[179.457362] Starting subtest: engines-queued
[179.460298] Starting dynamic subtest: rcs0
[179.489802] Dynamic subtest rcs0: SUCCESS (0.034s)
[179.506669] Starting dynamic subtest: bcs0
[179.513935] Dynamic subtest bcs0: SUCCESS (0.023s)
[179.527106] Starting dynamic subtest: vcs0
[181.404166] Dynamic subtest vcs0: SUCCESS (1.887s)
[181.419471] Starting dynamic subtest: vcs1
[183.450280] Dynamic subtest vcs1: SUCCESS (2.044s)
[183.465405] Starting dynamic subtest: vecs0
[185.434958] Dynamic subtest vecs0: SUCCESS (1.982s)
[185.450123] Starting dynamic subtest: ccs0
[185.654597] Dynamic subtest ccs0: SUCCESS (0.220s)
[185.657223] Subtest engines-queued: SUCCESS (6.199s)
[185.750994] [043/141] (817s left) kms_chamelium_audio (dp-audio)
[186.105737] Subtest dp-audio: SKIP (0.000s)
[186.972688] [044/141] (816s left) i915_query (engine-info)
[187.036293] Starting subtest: engine-info
[187.041874] Subtest engine-info: SUCCESS (0.005s)
[187.097145] [045/141] (816s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset-interruptible)
[187.159659] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset-interruptible
[187.172345] Subtest 2x-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.013s)
[187.235254] [046/141] (816s left) kms_frontbuffer_tracking (fbcpsr-2p-indfb-fliptrack-mmap-gtt)
[187.350965] Starting subtest: fbcpsr-2p-indfb-fliptrack-mmap-gtt
[187.355314] Subtest fbcpsr-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[187.436110] [047/141] (816s left) v3d/v3d_perfmon (create-single-perfmon)
[187.496126] Subtest create-single-perfmon: SKIP (0.000s)
[187.545998] [048/141] (816s left) gem_mmap_offset (basic-uaf)
[187.599289] Starting subtest: basic-uaf
[187.601250] Subtest basic-uaf: SUCCESS (0.002s)
[187.685585] [049/141] (816s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[187.782020] Starting subtest: cursorA-vs-flipA-legacy
[198.938919] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.157s)
[199.875059] [050/141] (804s left) perf_pmu (frequency)
[199.938322] Starting subtest: frequency
[199.940585] Starting dynamic subtest: gt0
[202.008476] Dynamic subtest gt0: SUCCESS (2.064s)
[202.022465] Starting dynamic subtest: idle-gt0
[202.530558] Dynamic subtest idle-gt0: SUCCESS (0.520s)
[202.545916] Starting dynamic subtest: gt1
[207.480332] Dynamic subtest gt1: SUCCESS (4.946s)
[207.493874] Starting dynamic subtest: idle-gt1
[207.986392] Dynamic subtest idle-gt1: SUCCESS (0.505s)
[207.988991] Subtest frequency: SUCCESS (8.042s)
[208.059547] [051/141] (795s left) kms_cursor_legacy (2x-flip-vs-cursor-legacy)
[208.136988] Starting subtest: 2x-flip-vs-cursor-legacy
[208.141798] Subtest 2x-flip-vs-cursor-legacy: SKIP (0.001s)
[208.228597] [052/141] (795s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[208.314791] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[213.261080] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (4.948s)
[213.362123] [053/141] (790s left) syncobj_timeline (invalid-multi-wait-all-available-unsubmitted-submitted-sign
[213.432259] Starting subtest: invalid-multi-wait-all-available-unsubmitted-submitted-signaled
[213.434622] Subtest invalid-multi-wait-all-available-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[213.500428] [054/141] (790s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-fullscreen)
[213.576743] Starting subtest: fbc-2p-scndscrn-spr-indfb-fullscreen
[213.581070] Subtest fbc-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[213.645186] [055/141] (790s left) i915_pm_rpm (dpms-mode-unset-lpsp)
[215.663048] Starting subtest: dpms-mode-unset-lpsp
[227.902398] Subtest dpms-mode-unset-lpsp: SUCCESS (12.243s)
[228.705127] [056/141] (775s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled)
[228.775074] Starting subtest: yf-tiled-ccs-to-yf-tiled
[228.779638] Subtest yf-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[228.951655] [057/141] (775s left) kms_ccs (pipe-d-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs)
[229.368385] Starting subtest: pipe-D-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs
[229.374346] Subtest pipe-D-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs: SKIP (0.001s)
[230.181690] [058/141] (773s left) gem_exec_params (sol-reset-not-gen7)
[230.230555] Starting subtest: sol-reset-not-gen7
[230.232533] Subtest sol-reset-not-gen7: SUCCESS (0.001s)
[230.330337] [059/141] (773s left) kms_cursor_crc (cursor-rapid-movement-32x10)
[230.440786] Starting subtest: cursor-rapid-movement-32x10
[230.470188] Subtest cursor-rapid-movement-32x10: SKIP (0.026s)
[230.666188] [060/141] (773s left) kms_content_protection (legacy)
[230.801489] Starting subtest: legacy
[234.914037] Subtest legacy: SKIP (4.109s)
[236.159760] [061/141] (768s left) api_intel_allocator (execbuf-with-allocator)
[236.227469] Starting subtest: execbuf-with-allocator
[236.232780] Subtest execbuf-with-allocator: SUCCESS (0.006s)
[236.321408] [062/141] (767s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions)
[236.411081] Starting subtest: flip-vs-cursor-atomic-transitions
[242.640770] Subtest flip-vs-cursor-atomic-transitions: SUCCESS (6.228s)
[243.585582] [063/141] (760s left) kms_big_fb (y-tiled-8bpp-rotate-0)
[243.680041] Starting subtest: y-tiled-8bpp-rotate-0
[243.684741] Subtest y-tiled-8bpp-rotate-0: SKIP (0.000s)
[243.764812] [064/141] (760s left) i915_pm_rps (min-max-config-idle)
[243.825534] Starting subtest: min-max-config-idle
[243.830316] Subtest min-max-config-idle: SKIP (0.001s)
[243.872481] [065/141] (760s left) vc4/vc4_create_bo (create-bo-0)
[243.925319] Subtest create-bo-0: SKIP (0.000s)
[243.968487] [066/141] (760s left) kms_vblank (pipe-b-wait-forked)
[244.062873] Starting subtest: pipe-B-wait-forked
[247.088555] Subtest pipe-B-wait-forked: SUCCESS (3.024s)
[248.001958] [067/141] (756s left) gem_cs_tlb (engines)
[248.067953] Starting subtest: engines
[248.070394] Starting dynamic subtest: rcs0
[250.225250] Dynamic subtest rcs0: SUCCESS (2.153s)
[250.242412] Starting dynamic subtest: bcs0
[252.376063] Dynamic subtest bcs0: SUCCESS (2.150s)
[252.390375] Starting dynamic subtest: vcs0
[254.526876] Dynamic subtest vcs0: SUCCESS (2.150s)
[254.540963] Starting dynamic subtest: vcs1
[256.676428] Dynamic subtest vcs1: SUCCESS (2.150s)
[256.690867] Starting dynamic subtest: vecs0
[258.827710] Dynamic subtest vecs0: SUCCESS (2.150s)
[258.839826] Starting dynamic subtest: ccs0
[260.978541] Dynamic subtest ccs0: SUCCESS (2.151s)
[260.980564] Subtest engines: SUCCESS (12.907s)
[261.077312] [068/141] (743s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-blt)
[261.162903] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-blt
[265.939351] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-blt: SUCCESS (4.781s)
[266.083626] [069/141] (738s left) api_intel_bb (misplaced-blitter)
[266.142643] Starting subtest: misplaced-blitter
[266.145145] Subtest misplaced-blitter: SUCCESS (0.003s)
[266.241747] [070/141] (738s left) syncobj_wait (single-wait-for-submit-submitted)
[266.313034] Starting subtest: single-wait-for-submit-submitted
[266.417517] Subtest single-wait-for-submit-submitted: SUCCESS (0.103s)
[266.483667] [071/141] (737s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180)
[266.588887] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180
[267.659068] Subtest x-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (1.069s)
[267.765027] [072/141] (736s left) kms_concurrent (pipe-c)
[267.822706] Starting subtest: pipe-C
[267.824519] Starting dynamic subtest: eDP-1
[269.432698] Dynamic subtest eDP-1: SUCCESS (1.608s)
[269.435228] Subtest pipe-C: SUCCESS (1.610s)
[270.325804] [073/141] (734s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-cpu)
[270.442034] Starting subtest: fbc-rgb101010-draw-mmap-cpu
[274.091659] Subtest fbc-rgb101010-draw-mmap-cpu: SUCCESS (3.654s)
[274.213462] [074/141] (730s left) kms_ccs (pipe-c-random-ccs-data-y_tiled_gen12_mc_ccs)
[274.616264] Starting subtest: pipe-C-random-ccs-data-y_tiled_gen12_mc_ccs
[274.623744] Subtest pipe-C-random-ccs-data-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[275.520497] [075/141] (729s left) perf (blocking-parameterized)
[275.682942] Starting subtest: blocking-parameterized
[295.699860] Subtest blocking-parameterized: SUCCESS (20.017s)
[295.735793] [076/141] (708s left) kms_psr (sprite_render)
[295.796428] Starting subtest: sprite_render
[297.591859] Subtest sprite_render: SUCCESS (1.796s)
[298.501428] [077/141] (706s left) syncobj_wait (reset-during-wait-for-submit)
[298.559297] Starting subtest: reset-during-wait-for-submit
[298.659041] Subtest reset-during-wait-for-submit: SUCCESS (0.103s)
[298.729336] [078/141] (705s left) kms_flip (2x-wf_vblank-ts-check)
[298.802950] Starting subtest: 2x-wf_vblank-ts-check
[298.821303] Subtest 2x-wf_vblank-ts-check: SKIP (0.019s)
[298.890594] [079/141] (705s left) kms_ccs (pipe-a-bad-rotation-90-4_tiled_mtl_mc_ccs)
[299.249635] Starting subtest: pipe-A-bad-rotation-90-4_tiled_mtl_mc_ccs
[300.278896] Subtest pipe-A-bad-rotation-90-4_tiled_mtl_mc_ccs: SUCCESS (1.032s)
[301.509476] [080/141] (703s left) kms_frontbuffer_tracking (psr-1p-pri-indfb-multidraw)
[301.588652] Starting subtest: psr-1p-pri-indfb-multidraw
[320.702413] Subtest psr-1p-pri-indfb-multidraw: SUCCESS (19.116s)
[320.811897] [081/141] (683s left) gem_pxp (create-regular-context-1)
[323.408197] Subtest create-regular-context-1: SKIP (0.000s)
[323.473475] [082/141] (681s left) kms_plane_scaling (planes-downscale-factor-0-5)
[323.550868] Starting subtest: planes-downscale-factor-0-5
[323.553593] Starting dynamic subtest: pipe-A-eDP-1
[323.558795] Dynamic subtest pipe-A-eDP-1: SKIP (0.004s)
[323.577045] Starting dynamic subtest: pipe-B-eDP-1
[323.584284] Dynamic subtest pipe-B-eDP-1: SKIP (0.012s)
[323.600206] Starting dynamic subtest: pipe-C-eDP-1
[323.607176] Dynamic subtest pipe-C-eDP-1: SKIP (0.012s)
[323.617165] Starting dynamic subtest: pipe-D-eDP-1
[323.622750] Dynamic subtest pipe-D-eDP-1: SKIP (0.012s)
[323.628571] Subtest planes-downscale-factor-0-5: SKIP (0.044s)
[323.680657] [083/141] (681s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[323.751990] Starting subtest: fbc-2p-pri-indfb-multidraw
[323.756744] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[323.840717] [084/141] (681s left) kms_frontbuffer_tracking (psr-modesetfrombusy)
[323.925232] Starting subtest: psr-modesetfrombusy
[328.197802] Subtest psr-modesetfrombusy: SUCCESS (4.279s)
[328.318917] [085/141] (676s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-pgflip-blt)
[328.404997] Starting subtest: fbc-2p-scndscrn-shrfb-pgflip-blt
[328.408276] Subtest fbc-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[328.485814] [086/141] (676s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-25)
[328.550920] Starting subtest: planes-unity-scaling-downscale-factor-0-25
[328.553102] Starting dynamic subtest: pipe-A-eDP-1
[328.564080] Dynamic subtest pipe-A-eDP-1: SKIP (0.008s)
[328.577971] Starting dynamic subtest: pipe-B-eDP-1
[328.582096] Dynamic subtest pipe-B-eDP-1: SKIP (0.015s)
[328.594642] Starting dynamic subtest: pipe-C-eDP-1
[328.598671] Dynamic subtest pipe-C-eDP-1: SKIP (0.015s)
[328.609328] Starting dynamic subtest: pipe-D-eDP-1
[328.612595] Dynamic subtest pipe-D-eDP-1: SKIP (0.015s)
[328.615893] Subtest planes-unity-scaling-downscale-factor-0-25: SKIP (0.059s)
[328.691469] [087/141] (676s left) gem_exec_schedule (semaphore-resolve)
[328.771079] Starting subtest: semaphore-resolve
[328.783683] Subtest semaphore-resolve: SUCCESS (0.020s)
[329.117334] [088/141] (675s left) i915_hwmon (hwmon-write)
[329.178978] Subtest hwmon-write: SKIP (0.000s)
[329.217528] [089/141] (675s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[329.313587] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[329.316084] Starting dynamic subtest: pipe-A-default-mode
[329.602545] Dynamic subtest pipe-A-default-mode: SKIP (0.285s)
[329.607539] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SKIP (0.287s)
[330.485107] [090/141] (674s left) gem_exec_fence (basic-wait)
[330.690312] Starting subtest: basic-wait
[330.692873] Starting dynamic subtest: rcs0
[330.744433] Dynamic subtest rcs0: SUCCESS (0.055s)
[330.758700] Starting dynamic subtest: bcs0
[330.793842] Dynamic subtest bcs0: SUCCESS (0.048s)
[330.807980] Starting dynamic subtest: vcs0
[330.821768] Dynamic subtest vcs0: SUCCESS (0.026s)
[330.835532] Starting dynamic subtest: vcs1
[330.860055] Dynamic subtest vcs1: SUCCESS (0.037s)
[330.874736] Starting dynamic subtest: vecs0
[330.897086] Dynamic subtest vecs0: SUCCESS (0.036s)
[330.910867] Starting dynamic subtest: ccs0
[330.952870] Dynamic subtest ccs0: SUCCESS (0.054s)
[330.955639] Subtest basic-wait: SUCCESS (0.264s)
[332.527331] [091/141] (672s left) kms_psr2_sf (overlay-primary-update-sf-dmg-area)
[337.711742] Starting subtest: overlay-primary-update-sf-dmg-area
[337.714443] Starting dynamic subtest: pipe-A-eDP-1
[344.493669] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.780s)
[344.508607] Starting dynamic subtest: pipe-B-eDP-1
[351.279559] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.782s)
[351.282456] Subtest overlay-primary-update-sf-dmg-area: SUCCESS (13.568s)
[352.181509] [092/141] (652s left) gem_exec_balancer (sequential)
[352.240384] Starting subtest: sequential
[355.024172] Subtest sequential: SUCCESS (2.783s)
[355.302550] [093/141] (649s left) gem_ctx_param (non-root-set)
[355.342460] Starting subtest: non-root-set
[355.351320] Subtest non-root-set: SUCCESS (0.016s)
[355.416634] [094/141] (649s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[355.495532] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[360.386210] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SUCCESS (4.898s)
[360.505105] [095/141] (644s left) perf_pmu (all-busy-idle-check-all)
[360.548936] Starting subtest: all-busy-idle-check-all
[361.241642] Subtest all-busy-idle-check-all: FAIL (0.629s)
[362.460617] [096/141] (642s left) syncobj_timeline (invalid-wait-bad-flags)
[362.529983] Starting subtest: invalid-wait-bad-flags
[362.532982] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[362.597196] [097/141] (642s left) kms_ccs (pipe-a-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc)
[362.996336] Starting subtest: pipe-A-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc
[363.002059] Subtest pipe-A-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc: SKIP (0.002s)
[363.858478] [098/141] (641s left) gem_userptr_blits (input-checking)
[363.933389] Starting subtest: input-checking
[363.935966] Subtest input-checking: SUCCESS (0.000s)
[364.056962] [099/141] (641s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-msflip-blt)
[364.140086] Starting subtest: fbcpsr-1p-primscrn-indfb-msflip-blt
[369.346345] Subtest fbcpsr-1p-primscrn-indfb-msflip-blt: SUCCESS (5.209s)
[369.481818] [100/141] (635s left) gem_eio (in-flight-contexts-10ms)
[369.690643] Starting subtest: in-flight-contexts-10ms
[383.860165] Subtest in-flight-contexts-10ms: SUCCESS (14.170s)
[385.570249] [101/141] (619s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[385.662899] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[386.420370] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.762s)
[386.557740] [102/141] (618s left) kms_vblank (pipe-a-ts-continuation-suspend)
[386.617163] Starting subtest: pipe-A-ts-continuation-suspend
[403.179067] Subtest pipe-A-ts-continuation-suspend: SUCCESS (1.568s)
[404.120920] [103/141] (616s left) v3d/v3d_submit_csd (bad-multisync-out-sync)
[404.208189] Subtest bad-multisync-out-sync: SKIP (0.000s)
[404.262212] [104/141] (616s left) gem_exec_reloc (basic-gtt-cpu)
[404.339882] Subtest basic-gtt-cpu: SKIP (0.000s)
[404.401406] [105/141] (616s left) vgem_basic (dmabuf-mmap)
[404.460885] Starting subtest: dmabuf-mmap
[404.468383] Subtest dmabuf-mmap: SUCCESS (0.011s)
[404.519458] [106/141] (615s left) kms_vblank (pipe-c-query-idle-hang)
[404.594809] Starting subtest: pipe-C-query-idle-hang
[417.450646] Subtest pipe-C-query-idle-hang: SUCCESS (12.855s)
[418.394103] [107/141] (602s left) kms_vblank (pipe-a-wait-idle-hang)
[418.456182] Starting subtest: pipe-A-wait-idle-hang
[429.474093] Subtest pipe-A-wait-idle-hang: SUCCESS (11.019s)
[430.420737] [108/141] (590s left) kms_chamelium_hpd (vga-hpd-fast)
[430.803212] Subtest vga-hpd-fast: SKIP (0.000s)
[431.666450] [109/141] (588s left) kms_addfb_basic (unused-handle)
[431.709674] Starting subtest: unused-handle
[431.712423] Subtest unused-handle: SUCCESS (0.000s)
[431.828321] [110/141] (588s left) gem_ctx_param (set-priority-range)
[431.893941] Starting subtest: set-priority-range
[431.911046] Subtest set-priority-range: SUCCESS (0.020s)
[431.979594] [111/141] (588s left) kms_rotation_crc (sprite-rotation-180)
[432.035428] Starting subtest: sprite-rotation-180
[438.151571] Subtest sprite-rotation-180: SUCCESS (6.118s)
[439.443672] [112/141] (581s left) kms_flip (flip-vs-suspend-interruptible)
[439.523577] Starting subtest: flip-vs-suspend-interruptible
[439.529213] Starting dynamic subtest: A-eDP1
[488.965692] Dynamic subtest A-eDP1: SUCCESS (4.711s)
[488.981768] Starting dynamic subtest: B-eDP1
[537.973953] Dynamic subtest B-eDP1: SUCCESS (4.732s)
[537.986945] Starting dynamic subtest: C-eDP1
[586.973819] Dynamic subtest C-eDP1: SUCCESS (4.711s)
[586.991452] Starting dynamic subtest: D-eDP1
[635.976963] Dynamic subtest D-eDP1: SUCCESS (4.760s)
[635.986107] Subtest flip-vs-suspend-interruptible: SUCCESS (18.936s)
[638.466617] [113/141] (559s left) gem_mmap_wc (write-gtt-read-wc)
[638.511451] Subtest write-gtt-read-wc: SKIP (0.000s)
[638.540916] [114/141] (559s left) kms_big_joiner (invalid-modeset)
[638.624193] Subtest invalid-modeset: SKIP (0.000s)
[638.642650] [115/141] (559s left) vc4/vc4_purgeable_bo (mark-willneed)
[638.701030] Subtest mark-willneed: SKIP (0.000s)
[638.750469] [116/141] (559s left) gem_wait (invalid-flags)
[638.812407] Starting subtest: invalid-flags
[638.814662] Subtest invalid-flags: SUCCESS (0.000s)
[639.037671] [117/141] (559s left) syncobj_timeline (reset-multiple-signaled)
[639.109513] Starting subtest: reset-multiple-signaled
[639.111586] Subtest reset-multiple-signaled: SUCCESS (0.001s)
[639.176394] [118/141] (559s left) kms_cursor_legacy (modeset-atomic-cursor-hotspot)
[639.283023] Starting subtest: modeset-atomic-cursor-hotspot
[639.287690] Subtest modeset-atomic-cursor-hotspot: SKIP (0.000s)
[639.432193] [119/141] (558s left) gem_madvise (dontneed-before-mmap)
[639.499534] Starting subtest: dontneed-before-mmap
[639.504714] Subtest dontneed-before-mmap: SUCCESS (0.008s)
[639.592154] [120/141] (558s left) gem_exec_async (forked-writes)
[639.667963] Starting subtest: forked-writes
[639.670441] Starting dynamic subtest: rcs0
[639.699715] Dynamic subtest rcs0: SUCCESS (0.031s)
[639.715789] Starting dynamic subtest: bcs0
[639.724749] Dynamic subtest bcs0: SUCCESS (0.024s)
[639.733563] Starting dynamic subtest: vcs0
[639.758901] Dynamic subtest vcs0: SUCCESS (0.033s)
[639.770339] Starting dynamic subtest: vcs1
[639.801124] Dynamic subtest vcs1: SUCCESS (0.041s)
[639.812170] Starting dynamic subtest: vecs0
[639.825655] Dynamic subtest vecs0: SUCCESS (0.024s)
[639.832510] Starting dynamic subtest: ccs0
[639.852836] Dynamic subtest ccs0: SUCCESS (0.026s)
[639.855185] Subtest forked-writes: SUCCESS (0.185s)
[640.057556] [121/141] (558s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-cpu)
[640.140309] Starting subtest: fbc-rgb565-draw-mmap-cpu
[642.998369] Subtest fbc-rgb565-draw-mmap-cpu: SUCCESS (2.861s)
[643.131580] [122/141] (555s left) kms_lease (lease_unleased_connector)
[643.184309] Starting subtest: lease_unleased_connector
[643.186457] Starting dynamic subtest: pipe-A-eDP-1
[643.197125] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.009s)
[643.217483] Starting dynamic subtest: pipe-B-eDP-1
[643.219791] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.008s)
[643.228668] Starting dynamic subtest: pipe-C-eDP-1
[643.230342] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.008s)
[643.236412] Starting dynamic subtest: pipe-D-eDP-1
[643.238080] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.008s)
[643.239732] Subtest lease_unleased_connector: SUCCESS (0.036s)
[643.327438] [123/141] (555s left) syncobj_wait (invalid-signal-one-illegal-handle)
[643.383137] Starting subtest: invalid-signal-one-illegal-handle
[643.385099] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[643.441889] [124/141] (555s left) kms_hdr (bpc-switch-suspend)
[643.517024] Starting subtest: bpc-switch-suspend
[644.689811] Starting dynamic subtest: pipe-A-eDP-1
[664.120658] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.734s)
[664.123161] Subtest bpc-switch-suspend: SUCCESS (5.906s)
[664.238337] [125/141] (548s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-msflip-blt)
[664.329852] Starting subtest: fbcpsr-2p-scndscrn-indfb-msflip-blt
[664.334302] Subtest fbcpsr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[664.414604] [126/141] (548s left) kms_vblank (pipe-d-query-forked-busy-hang)
[664.484419] Starting subtest: pipe-D-query-forked-busy-hang
[676.668552] Subtest pipe-D-query-forked-busy-hang: SUCCESS (12.185s)
[677.556470] [127/141] (535s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[677.597795] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[677.633779] [128/141] (535s left) kms_ccs (pipe-b-missing-ccs-buffer-4_tiled_mtl_mc_ccs)
[678.011095] Starting subtest: pipe-B-missing-ccs-buffer-4_tiled_mtl_mc_ccs
[678.036707] Subtest pipe-B-missing-ccs-buffer-4_tiled_mtl_mc_ccs: SUCCESS (0.025s)
[678.872974] [129/141] (534s left) gem_userptr_blits (nohangcheck)
[678.950302] Starting subtest: nohangcheck
[679.151901] Subtest nohangcheck: SUCCESS (0.201s)
[679.258588] [130/141] (534s left) gem_ccs (block-multicopy-inplace)
[679.318413] Starting subtest: block-multicopy-inplace
[679.322807] Subtest block-multicopy-inplace: SKIP (0.000s)
[679.385619] [131/141] (534s left) kms_ccs (pipe-c-crc-sprite-planes-basic-yf_tiled_ccs)
[679.782756] Starting subtest: pipe-C-crc-sprite-planes-basic-yf_tiled_ccs
[679.787822] Subtest pipe-C-crc-sprite-planes-basic-yf_tiled_ccs: SKIP (0.001s)
[680.634136] [132/141] (532s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[680.738599] Starting subtest: basic-flip-after-cursor-varying-size
[681.687997] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.950s)
[682.615731] [133/141] (530s left) kms_ccs (pipe-a-random-ccs-data-4_tiled_dg2_rc_ccs_cc)
[682.980392] Starting subtest: pipe-A-random-ccs-data-4_tiled_dg2_rc_ccs_cc
[682.987576] Subtest pipe-A-random-ccs-data-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[683.897857] [134/141] (529s left) gen9_exec_parse (bb-secure)
[683.960010] Subtest bb-secure: SKIP (0.000s)
[683.978406] [135/141] (529s left) syncobj_timeline (wait-any-complex)
[684.027339] Starting subtest: wait-any-complex
[684.088579] Subtest wait-any-complex: SUCCESS (0.063s)
[684.160482] [136/141] (529s left) kms_ccs (pipe-b-crc-primary-basic-4_tiled_mtl_rc_ccs)
[684.550643] Starting subtest: pipe-B-crc-primary-basic-4_tiled_mtl_rc_ccs
[685.650934] Subtest pipe-B-crc-primary-basic-4_tiled_mtl_rc_ccs: SUCCESS (1.101s)
[686.893010] [137/141] (526s left) gem_exec_fence (syncobj-timeline-signal)
[687.442250] Starting subtest: syncobj-timeline-signal
[687.448645] Subtest syncobj-timeline-signal: SUCCESS (0.007s)
[688.754930] [138/141] (524s left) drm_fdinfo (virtual-idle)
[688.811796] Starting subtest: virtual-idle
[691.865444] Subtest virtual-idle: SUCCESS (3.054s)
[691.937421] [139/141] (521s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt)
[692.027166] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt
[692.031742] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[692.116258] [140/141] (521s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[692.206997] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[692.211333] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[692.296920] [141/141] (521s left) gem_mmap_gtt (hang-user)
[692.345823] Subtest hang-user: SKIP (0.000s)
[692.362193] Closing watchdogs
Done.