[42.991749] Initializing watchdogs [42.991814] /dev/watchdog0 [42.998652] [001/142] (960s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-pwrite) [44.965488] Starting subtest: fbc-1p-offscren-pri-indfb-draw-pwrite [46.985190] Subtest fbc-1p-offscren-pri-indfb-draw-pwrite: SUCCESS (2.022s) [47.125928] [002/142] (956s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-pgflip-blt) [49.116032] Starting subtest: fbcpsr-2p-primscrn-shrfb-pgflip-blt [49.117573] Subtest fbcpsr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s) [49.197277] [003/142] (954s left) kms_big_fb (y-tiled-16bpp-rotate-270) [51.216342] Starting subtest: y-tiled-16bpp-rotate-270 [51.217933] Subtest y-tiled-16bpp-rotate-270: SKIP (0.000s) [51.297187] [004/142] (952s left) v3d/v3d_submit_cl (bad-multisync-out-sync) [51.418603] Subtest bad-multisync-out-sync: SKIP (0.000s) [51.463303] [005/142] (952s left) gem_lmem_swapping (verify-ccs) [54.817456] Starting subtest: verify-ccs [54.818198] Starting dynamic subtest: lmem0 [92.484616] Dynamic subtest lmem0: SUCCESS (37.633s) [92.485565] Subtest verify-ccs: SUCCESS (37.633s) [95.514704] [006/142] (908s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-blt) [100.777352] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-blt [100.778917] Subtest fbc-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s) [100.851951] [007/142] (902s left) gem_mmap_wc (invalid-flags) [100.941137] Subtest invalid-flags: SKIP (0.000s) [100.964324] [008/142] (902s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled) [101.035833] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled [101.036710] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.001s) [101.097235] [009/142] (902s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-fullscreen) [103.073366] Starting subtest: fbc-2p-scndscrn-spr-indfb-fullscreen [103.075157] Subtest fbc-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s) [103.153652] [010/142] (900s left) kms_flip (2x-wf_vblank-ts-check) [104.196974] Starting subtest: 2x-wf_vblank-ts-check [104.211140] Subtest 2x-wf_vblank-ts-check: SKIP (0.014s) [104.269271] [011/142] (899s left) gem_mmap_wc (write-cpu-read-wc) [104.351783] Subtest write-cpu-read-wc: SKIP (0.000s) [104.383804] [012/142] (899s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite) [106.370496] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite [106.372189] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [106.472726] [013/142] (897s left) kms_ccs (pipe-a-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc) [108.557420] Starting subtest: pipe-A-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc [109.844383] Subtest pipe-A-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc: SUCCESS (1.288s) [110.113912] [014/142] (893s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions) [112.068184] Starting subtest: cursorA-vs-flipA-atomic-transitions [123.041266] Subtest cursorA-vs-flipA-atomic-transitions: SUCCESS (10.973s) [123.154103] [015/142] (880s left) gem_exec_schedule (semaphore-noskip) [123.236213] Starting subtest: semaphore-noskip [125.056137] Subtest semaphore-noskip: SUCCESS (1.811s) [125.408241] [016/142] (878s left) kms_ccs (pipe-d-random-ccs-data-y_tiled_gen12_rc_ccs) [127.506997] Starting subtest: pipe-D-random-ccs-data-y_tiled_gen12_rc_ccs [127.509555] Subtest pipe-D-random-ccs-data-y_tiled_gen12_rc_ccs: SKIP (0.000s) [127.608554] [017/142] (876s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt) [129.564474] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt [129.566067] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [129.642647] [018/142] (874s left) gem_eio (wait-wedge-immediate) [129.949968] Starting subtest: wait-wedge-immediate [131.374987] Subtest wait-wedge-immediate: SUCCESS (1.425s) [133.325763] [019/142] (870s left) gem_pxp (reject-modify-context-protection-off-1) [135.960059] Subtest reject-modify-context-protection-off-1: SKIP (0.000s) [136.008752] [020/142] (867s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt) [137.964956] Starting subtest: fbc-rgb565-draw-blt [142.898677] Subtest fbc-rgb565-draw-blt: FAIL (4.556s) [142.944783] [021/142] (860s left) kms_color (invalid-ctm-matrix-sizes) [144.902211] Starting subtest: invalid-ctm-matrix-sizes [144.903018] Starting dynamic subtest: pipe-A [144.905466] Dynamic subtest pipe-A: SUCCESS (0.003s) [144.910884] Starting dynamic subtest: pipe-B [144.911628] Dynamic subtest pipe-B: SUCCESS (0.005s) [144.916898] Starting dynamic subtest: pipe-C [144.917710] Dynamic subtest pipe-C: SUCCESS (0.006s) [144.923137] Starting dynamic subtest: pipe-D [144.923927] Dynamic subtest pipe-D: SUCCESS (0.006s) [144.924770] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.022s) [144.976360] [022/142] (858s left) i915_pm_freq_mult (media-freq) [145.061192] Starting subtest: media-freq [145.062276] Starting dynamic subtest: gt0 [145.564594] Dynamic subtest gt0: SUCCESS (0.503s) [145.565784] Subtest media-freq: SUCCESS (0.504s) [145.671566] [023/142] (858s left) gem_exec_parallel (fds) [145.749143] Starting subtest: fds [145.749965] Starting dynamic subtest: rcs0 [150.189332] Dynamic subtest rcs0: SUCCESS (4.432s) [150.194883] Starting dynamic subtest: bcs0 [154.317070] Dynamic subtest bcs0: SUCCESS (4.127s) [154.326067] Starting dynamic subtest: vcs0 [157.859536] Dynamic subtest vcs0: SUCCESS (3.542s) [157.865073] Starting dynamic subtest: vcs1 [161.923556] Dynamic subtest vcs1: SUCCESS (4.064s) [161.929465] Starting dynamic subtest: vecs0 [165.998248] Dynamic subtest vecs0: SUCCESS (4.075s) [166.003947] Starting dynamic subtest: vecs1 [170.127644] Dynamic subtest vecs1: SUCCESS (4.129s) [170.137908] Starting dynamic subtest: ccs0 [174.331533] Dynamic subtest ccs0: SUCCESS (4.204s) [174.337250] Starting dynamic subtest: ccs1 [177.947981] Dynamic subtest ccs1: SUCCESS (3.617s) [177.954349] Starting dynamic subtest: ccs2 [182.061813] Dynamic subtest ccs2: SUCCESS (4.113s) [182.067607] Starting dynamic subtest: ccs3 [186.317895] Dynamic subtest ccs3: SUCCESS (4.255s) [186.319168] Subtest fds: SUCCESS (40.560s) [186.543276] [024/142] (817s left) kms_hdr (static-swap) [188.528391] Starting subtest: static-swap [188.530227] Subtest static-swap: SKIP (0.000s) [188.577433] [025/142] (815s left) gem_userptr_blits (set-cache-level) [188.646347] Starting subtest: set-cache-level [188.647185] Subtest set-cache-level: SUCCESS (0.000s) [188.760785] [026/142] (815s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-pwrite) [190.753390] Starting subtest: psr-1p-primscrn-pri-indfb-draw-pwrite [190.754945] Subtest psr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [190.827764] [027/142] (813s left) kms_vblank (pipe-b-wait-idle) [192.797225] Starting subtest: pipe-B-wait-idle [195.150820] Subtest pipe-B-wait-idle: SUCCESS (2.353s) [195.255295] [028/142] (808s left) kms_psr (primary_render) [195.316549] Subtest primary_render: SKIP (0.000s) [195.331803] [029/142] (808s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff) [197.290211] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff [197.291997] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s) [197.372463] [030/142] (806s left) gem_exec_capture (userptr) [197.497040] Starting subtest: userptr [198.435242] Subtest userptr: SUCCESS (0.933s) [198.543662] [031/142] (805s left) kms_vblank (pipe-b-query-forked-hang) [200.500455] Starting subtest: pipe-B-query-forked-hang [213.999416] Subtest pipe-B-query-forked-hang: SUCCESS (13.499s) [214.117635] [032/142] (789s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling) [216.078383] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling [216.079291] Starting dynamic subtest: pipe-A-valid-mode [216.815958] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.736s) [216.817140] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SUCCESS (0.738s) [216.944796] [033/142] (787s left) kms_chamelium_hpd (vga-hpd-fast) [218.940008] Subtest vga-hpd-fast: SKIP (0.000s) [218.993266] [034/142] (785s left) kms_psr2_sf (cursor-plane-update-sf) [219.051965] Subtest cursor-plane-update-sf: SKIP (0.000s) [219.073280] [035/142] (784s left) gem_create (create-ext-placement-each) [219.247196] Starting subtest: create-ext-placement-each [219.248191] Subtest create-ext-placement-each: SUCCESS (0.000s) [219.343466] [036/142] (784s left) kms_ccs (pipe-b-crc-primary-basic-4_tiled_mtl_mc_ccs) [221.456139] Starting subtest: pipe-B-crc-primary-basic-4_tiled_mtl_mc_ccs [221.457745] Subtest pipe-B-crc-primary-basic-4_tiled_mtl_mc_ccs: SKIP (0.000s) [221.633262] [037/142] (782s left) perf_pmu (interrupts-sync) [221.700091] Starting subtest: interrupts-sync [222.837072] Subtest interrupts-sync: SUCCESS (1.128s) [222.935870] [038/142] (781s left) kms_ccs (pipe-a-bad-pixel-format-4_tiled_mtl_rc_ccs_cc) [224.890008] Starting subtest: pipe-A-bad-pixel-format-4_tiled_mtl_rc_ccs_cc [224.891781] Subtest pipe-A-bad-pixel-format-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s) [225.139328] [039/142] (778s left) kms_ccs (pipe-b-crc-primary-basic-y_tiled_gen12_rc_ccs_cc) [227.170416] Starting subtest: pipe-B-crc-primary-basic-y_tiled_gen12_rc_ccs_cc [227.171997] Subtest pipe-B-crc-primary-basic-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s) [227.348633] [040/142] (776s left) gem_exec_schedule (implicit-read-write) [227.416601] Starting subtest: implicit-read-write [227.417501] Starting dynamic subtest: rcs0 [227.684312] Dynamic subtest rcs0: SUCCESS (0.266s) [227.691056] Starting dynamic subtest: bcs0 [227.880708] Dynamic subtest bcs0: SUCCESS (0.188s) [227.887467] Starting dynamic subtest: vcs0 [228.081219] Dynamic subtest vcs0: SUCCESS (0.200s) [228.088042] Starting dynamic subtest: vcs1 [228.278766] Dynamic subtest vcs1: SUCCESS (0.205s) [228.286208] Starting dynamic subtest: vecs0 [228.493848] Dynamic subtest vecs0: SUCCESS (0.206s) [228.500510] Starting dynamic subtest: vecs1 [228.688916] Dynamic subtest vecs1: SUCCESS (0.202s) [228.695784] Starting dynamic subtest: ccs0 [228.894828] Dynamic subtest ccs0: SUCCESS (0.198s) [228.901518] Starting dynamic subtest: ccs1 [229.083818] Dynamic subtest ccs1: SUCCESS (0.195s) [229.090429] Starting dynamic subtest: ccs2 [229.289246] Dynamic subtest ccs2: SUCCESS (0.198s) [229.295868] Starting dynamic subtest: ccs3 [229.560593] Dynamic subtest ccs3: SUCCESS (0.271s) [229.561535] Subtest implicit-read-write: SUCCESS (2.136s) [229.940098] [041/142] (774s left) kms_cursor_legacy (torture-bo) [231.906433] Starting subtest: torture-bo [231.907402] Starting dynamic subtest: pipe-A [237.595972] Dynamic subtest pipe-A: SUCCESS (5.655s) [237.603744] Starting dynamic subtest: pipe-B [243.041275] Dynamic subtest pipe-B: SUCCESS (5.445s) [243.051051] Starting dynamic subtest: pipe-C [248.492396] Dynamic subtest pipe-C: SUCCESS (5.451s) [248.909272] Starting dynamic subtest: pipe-D [253.970201] Dynamic subtest pipe-D: SUCCESS (5.476s) [253.989219] Starting dynamic subtest: all-pipes [259.403798] Dynamic subtest all-pipes: SUCCESS (5.467s) [259.404650] Subtest torture-bo: SUCCESS (27.497s) [259.471072] [042/142] (744s left) syncobj_timeline (invalid-multi-wait-all-available-unsubmitted) [259.591461] Starting subtest: invalid-multi-wait-all-available-unsubmitted [259.592574] Subtest invalid-multi-wait-all-available-unsubmitted: SUCCESS (0.000s) [259.663175] [043/142] (744s left) gem_basic (multigpu-create-close) [259.751117] Starting subtest: multigpu-create-close [259.753336] Subtest multigpu-create-close: SKIP (0.000s) [259.830720] [044/142] (744s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled) [259.928528] Starting subtest: yf-tiled-ccs-to-yf-tiled [259.930584] Subtest yf-tiled-ccs-to-yf-tiled: SKIP (0.000s) [260.120564] [045/142] (744s left) i915_pm_rpm (legacy-planes) [264.532207] Starting subtest: legacy-planes [264.533947] Starting dynamic subtest: plane-40 [275.972354] Dynamic subtest plane-40: SUCCESS (11.439s) [275.998685] Starting dynamic subtest: plane-49 [287.675174] Dynamic subtest plane-49: SUCCESS (11.700s) [287.699500] Starting dynamic subtest: plane-58 [301.683180] Dynamic subtest plane-58: SUCCESS (14.006s) [301.707371] Starting dynamic subtest: plane-67 [315.655434] Dynamic subtest plane-67: SUCCESS (13.970s) [315.657068] Subtest legacy-planes: SUCCESS (51.125s) [316.737629] [046/142] (687s left) syncobj_timeline (wait-all-for-submit-complex) [316.798481] Starting subtest: wait-all-for-submit-complex [317.026781] Subtest wait-all-for-submit-complex: SUCCESS (0.215s) [317.148956] [047/142] (687s left) gem_wait (busy) [317.231403] Starting subtest: busy [317.232255] Starting dynamic subtest: all-engines [317.806184] Dynamic subtest all-engines: SUCCESS (0.574s) [317.813297] Starting dynamic subtest: rcs0 [318.344280] Dynamic subtest rcs0: SUCCESS (0.537s) [318.350991] Starting dynamic subtest: bcs0 [318.883267] Dynamic subtest bcs0: SUCCESS (0.538s) [318.890053] Starting dynamic subtest: vcs0 [319.421261] Dynamic subtest vcs0: SUCCESS (0.537s) [319.428250] Starting dynamic subtest: vcs1 [319.960486] Dynamic subtest vcs1: SUCCESS (0.538s) [319.967433] Starting dynamic subtest: vecs0 [320.499400] Dynamic subtest vecs0: SUCCESS (0.538s) [320.506287] Starting dynamic subtest: vecs1 [321.037484] Dynamic subtest vecs1: SUCCESS (0.537s) [321.044477] Starting dynamic subtest: ccs0 [321.576406] Dynamic subtest ccs0: SUCCESS (0.538s) [321.583450] Starting dynamic subtest: ccs1 [322.114469] Dynamic subtest ccs1: SUCCESS (0.537s) [322.121332] Starting dynamic subtest: ccs2 [322.653395] Dynamic subtest ccs2: SUCCESS (0.538s) [322.660392] Starting dynamic subtest: ccs3 [323.191464] Dynamic subtest ccs3: SUCCESS (0.537s) [323.192410] Subtest busy: SUCCESS (5.960s) [323.427763] [048/142] (680s left) gem_exec_flush (basic-batch-kernel-default-wb) [323.492453] Subtest basic-batch-kernel-default-wb: SKIP (0.000s) [323.530716] [049/142] (680s left) gem_create (create-valid-nonaligned) [323.594020] Starting subtest: create-valid-nonaligned [323.594819] Subtest create-valid-nonaligned: SUCCESS (0.000s) [323.814426] [050/142] (680s left) i915_query (query-regions-unallocated) [323.884826] Starting subtest: query-regions-unallocated [324.647488] Subtest query-regions-unallocated: SUCCESS (0.763s) [324.703785] [051/142] (679s left) kms_cursor_crc (cursor-rapid-movement-64x64) [326.745741] Starting subtest: cursor-rapid-movement-64x64 [326.750054] Starting dynamic subtest: pipe-A-HDMI-A-3 [327.145639] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (0.395s) [327.170061] Starting dynamic subtest: pipe-D-HDMI-A-3 [327.589008] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (0.425s) [327.589917] Subtest cursor-rapid-movement-64x64: SUCCESS (0.842s) [327.883631] [052/142] (676s left) gem_exec_reloc (basic-cpu-gtt) [327.948238] Subtest basic-cpu-gtt: SKIP (0.000s) [327.993150] [053/142] (676s left) gem_exec_reloc (basic-wc-cpu) [328.056056] Subtest basic-wc-cpu: SKIP (0.000s) [328.092477] [054/142] (676s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted) [328.156542] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted [328.157464] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s) [328.218287] [055/142] (676s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff) [330.186993] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff [330.188550] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s) [330.266195] [056/142] (674s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc) [332.262677] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc [332.264361] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s) [332.350827] [057/142] (672s left) kms_big_fb (linear-8bpp-rotate-0) [334.362536] Starting subtest: linear-8bpp-rotate-0 [335.842872] Subtest linear-8bpp-rotate-0: SUCCESS (1.472s) [335.985220] [058/142] (668s left) kms_rotation_crc (sprite-rotation-270) [337.971773] Starting subtest: sprite-rotation-270 [337.973356] Subtest sprite-rotation-270: SKIP (0.001s) [338.031791] [059/142] (666s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [340.003314] Starting subtest: basic-busy-flip-before-cursor-atomic [340.005125] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [340.065695] [060/142] (664s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-yf-tiled) [340.148747] Starting subtest: y-tiled-mc-ccs-to-vebox-yf-tiled [340.150609] Subtest y-tiled-mc-ccs-to-vebox-yf-tiled: SKIP (0.000s) [340.345883] [061/142] (664s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted) [340.439734] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted [340.556668] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.108s) [340.631347] [062/142] (664s left) gem_exec_big (single) [340.704443] Starting subtest: single [345.409844] Subtest single: SUCCESS (4.671s) [346.113697] [063/142] (658s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu) [348.109837] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu [348.111373] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [348.249635] [064/142] (656s left) perf (non-zero-reason) [348.559882] Starting subtest: non-zero-reason [348.560785] Starting dynamic subtest: 0-rcs0 [356.741837] Dynamic subtest 0-rcs0: SUCCESS (8.149s) [356.743287] Subtest non-zero-reason: SUCCESS (8.150s) [356.759778] [065/142] (647s left) kms_rotation_crc (sprite-rotation-180) [358.721262] Starting subtest: sprite-rotation-180 [365.746875] Subtest sprite-rotation-180: SUCCESS (7.026s) [365.948676] [066/142] (638s left) kms_cursor_legacy (cursorb-vs-flipa-toggle) [367.903913] Starting subtest: cursorB-vs-flipA-toggle [367.905517] Subtest cursorB-vs-flipA-toggle: SKIP (0.002s) [367.962964] [067/142] (636s left) api_intel_allocator (standalone) [368.038402] Starting subtest: standalone [368.077984] Subtest standalone: SUCCESS (0.041s) [368.199069] [068/142] (636s left) kms_psr (psr2_sprite_mmap_cpu) [368.290475] Subtest psr2_sprite_mmap_cpu: SKIP (0.000s) [368.307163] [069/142] (636s left) vgem_basic (bad-fence) [368.365054] Starting subtest: bad-fence [368.366394] Subtest bad-fence: SUCCESS (0.000s) [368.418225] [070/142] (636s left) gem_huc_copy (huc-copy) [368.511537] Starting subtest: huc-copy [368.517132] Subtest huc-copy: SUCCESS (0.006s) [368.650734] [071/142] (636s left) kms_addfb_basic (invalid-smem-bo-on-discrete) [368.719040] Starting subtest: invalid-smem-bo-on-discrete [368.719891] Subtest invalid-smem-bo-on-discrete: SUCCESS (0.000s) [370.679729] [072/142] (634s left) syncobj_timeline (invalid-query-bad-pad) [370.768716] Starting subtest: invalid-query-bad-pad [370.769886] Subtest invalid-query-bad-pad: SUCCESS (0.000s) [370.844843] [073/142] (634s left) kms_chamelium_color (gamma) [372.828658] Subtest gamma: SKIP (0.000s) [372.857741] [074/142] (632s left) kms_plane (plane-position-hole) [374.816187] Starting subtest: plane-position-hole [374.817131] Starting dynamic subtest: pipe-A-planes [376.542376] Dynamic subtest pipe-A-planes: SUCCESS (1.727s) [376.551431] Starting dynamic subtest: pipe-B-planes [378.027709] Dynamic subtest pipe-B-planes: SUCCESS (1.484s) [378.028642] Subtest plane-position-hole: SUCCESS (3.214s) [378.175980] [075/142] (626s left) kms_ccs (pipe-b-random-ccs-data-4_tiled_mtl_rc_ccs) [380.211357] Starting subtest: pipe-B-random-ccs-data-4_tiled_mtl_rc_ccs [380.214016] Subtest pipe-B-random-ccs-data-4_tiled_mtl_rc_ccs: SKIP (0.000s) [380.380242] [076/142] (624s left) i915_hwmon (hwmon-write) [380.440258] Starting subtest: hwmon-write [380.449854] Subtest hwmon-write: SUCCESS (0.010s) [380.513046] [077/142] (624s left) kms_chamelium_frames (hdmi-crc-fast) [382.505454] Subtest hdmi-crc-fast: SKIP (0.000s) [382.559360] [078/142] (622s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-downscaling) [384.512473] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-downscaling [384.513463] Starting dynamic subtest: pipe-A-valid-mode [387.534589] Dynamic subtest pipe-A-valid-mode: SUCCESS (3.021s) [387.535579] Subtest flip-32bpp-xtile-to-64bpp-xtile-downscaling: SUCCESS (3.023s) [387.650623] [079/142] (617s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip) [389.611940] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip [390.928053] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (1.307s) [391.042916] [080/142] (614s left) syncobj_wait (signal) [391.103728] Starting subtest: signal [391.104582] Subtest signal: SUCCESS (0.000s) [391.169404] [081/142] (613s left) kms_addfb_basic (unused-offsets) [391.241692] Starting subtest: unused-offsets [391.242533] Subtest unused-offsets: SUCCESS (0.000s) [393.217888] [082/142] (611s left) syncobj_wait (wait-all-for-submit-complex) [393.313038] Starting subtest: wait-all-for-submit-complex [393.538225] Subtest wait-all-for-submit-complex: SUCCESS (0.217s) [393.635261] [083/142] (611s left) kms_big_fb (y-tiled-addfb-size-overflow) [395.602820] Starting subtest: y-tiled-addfb-size-overflow [395.604561] Subtest y-tiled-addfb-size-overflow: SKIP (0.000s) [395.667755] [084/142] (609s left) drm_fdinfo (virtual-busy-idle-all) [395.753847] Starting subtest: virtual-busy-idle-all [395.755851] Subtest virtual-busy-idle-all: SKIP (0.000s) [395.834700] [085/142] (609s left) kms_ccs (pipe-b-bad-pixel-format-y_tiled_ccs) [397.889447] Starting subtest: pipe-B-bad-pixel-format-y_tiled_ccs [397.891193] Subtest pipe-B-bad-pixel-format-y_tiled_ccs: SKIP (0.000s) [398.078865] [086/142] (607s left) kms_vblank (pipe-d-wait-idle-hang) [400.031493] Starting subtest: pipe-D-wait-idle-hang [414.019063] Subtest pipe-D-wait-idle-hang: SUCCESS (13.988s) [414.124919] [087/142] (591s left) kms_ccs (pipe-c-random-ccs-data-y_tiled_ccs) [416.194467] Starting subtest: pipe-C-random-ccs-data-y_tiled_ccs [416.197047] Subtest pipe-C-random-ccs-data-y_tiled_ccs: SKIP (0.000s) [416.332154] [088/142] (588s left) gem_ctx_persistence (idempotent) [416.395996] Starting subtest: idempotent [416.396944] Subtest idempotent: SUCCESS (0.000s) [416.464617] [089/142] (588s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu) [418.422845] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu [418.424489] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [418.521503] [090/142] (586s left) v3d/v3d_submit_csd (multiple-job-submission) [418.667392] Subtest multiple-job-submission: SKIP (0.000s) [418.716807] [091/142] (586s left) kms_lease (atomic_implicit_crtc) [420.682469] Starting subtest: atomic_implicit_crtc [420.683436] Starting dynamic subtest: pipe-A-HDMI-A-3 [420.684438] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (0.001s) [420.695422] Starting dynamic subtest: pipe-B-HDMI-A-3 [420.696250] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (0.006s) [420.703688] Starting dynamic subtest: pipe-C-HDMI-A-3 [420.704595] Dynamic subtest pipe-C-HDMI-A-3: SUCCESS (0.006s) [420.712133] Starting dynamic subtest: pipe-D-HDMI-A-3 [420.712926] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (0.006s) [420.713759] Subtest atomic_implicit_crtc: SUCCESS (0.024s) [420.782729] [092/142] (584s left) kms_universal_plane (cursor-fb-leak-pipe-c) [422.742410] Starting subtest: cursor-fb-leak-pipe-C [423.348175] Subtest cursor-fb-leak-pipe-C: SUCCESS (0.605s) [423.497664] [093/142] (581s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-75) [425.454285] Starting subtest: planes-upscale-20x20-downscale-factor-0-75 [425.455243] Starting dynamic subtest: pipe-A-HDMI-A-3 [425.532139] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (0.078s) [425.540742] Starting dynamic subtest: pipe-B-HDMI-A-3 [425.638601] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (0.105s) [425.647377] Starting dynamic subtest: pipe-C-HDMI-A-3 [425.745817] Dynamic subtest pipe-C-HDMI-A-3: SUCCESS (0.106s) [425.754464] Starting dynamic subtest: pipe-D-HDMI-A-3 [425.852875] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (0.106s) [425.853700] Subtest planes-upscale-20x20-downscale-factor-0-75: SUCCESS (0.400s) [425.991451] [094/142] (579s left) kms_vblank (pipe-c-query-forked-busy-hang) [427.947274] Starting subtest: pipe-C-query-forked-busy-hang [442.046636] Subtest pipe-C-query-forked-busy-hang: SUCCESS (14.101s) [442.171212] [095/142] (563s left) gem_mmap_wc (write-wc-read-gtt) [442.234430] Subtest write-wc-read-gtt: SKIP (0.000s) [442.264419] [096/142] (563s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-plflip-blt) [444.229676] Starting subtest: psr-2p-primscrn-indfb-plflip-blt [444.231257] Subtest psr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s) [444.304094] [097/142] (561s left) kms_ccs (pipe-d-bad-rotation-90-4_tiled_mtl_rc_ccs_cc) [446.412627] Starting subtest: pipe-D-bad-rotation-90-4_tiled_mtl_rc_ccs_cc [446.414471] Subtest pipe-D-bad-rotation-90-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s) [446.527231] [098/142] (558s left) gem_ppgtt (blt-vs-render-ctx0) [446.591116] Starting subtest: blt-vs-render-ctx0 [479.038313] Subtest blt-vs-render-ctx0: SUCCESS (32.446s) [479.141647] [099/142] (526s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-pgflip-blt) [481.202221] Starting subtest: fbc-1p-primscrn-indfb-pgflip-blt [483.864077] Subtest fbc-1p-primscrn-indfb-pgflip-blt: SUCCESS (2.663s) [484.004214] [100/142] (521s left) kms_dsc (dsc-with-formats) [485.964154] Starting subtest: dsc-with-formats [485.965972] Subtest dsc-with-formats: SKIP (0.001s) [486.027812] [101/142] (519s left) gem_exec_schedule (preempt-queue-contexts-chain) [486.131698] Subtest preempt-queue-contexts-chain: SKIP (0.000s) [486.463284] [102/142] (519s left) prime_self_import (basic-with_one_bo_two_files) [486.526200] Starting subtest: basic-with_one_bo_two_files [486.537916] Subtest basic-with_one_bo_two_files: SUCCESS (0.011s) [486.646009] [103/142] (518s left) gem_exec_fair (basic-pace-solo) [486.712481] Subtest basic-pace-solo: SKIP (0.000s) [486.756851] [104/142] (518s left) kms_busy (extended-pageflip-hang-oldfb) [488.729166] Starting subtest: extended-pageflip-hang-oldfb [488.730178] Starting dynamic subtest: pipe-A [489.041388] Dynamic subtest pipe-A: SUCCESS (0.311s) [489.055836] Starting dynamic subtest: pipe-D [489.178872] Dynamic subtest pipe-D: SUCCESS (0.133s) [489.179848] Subtest extended-pageflip-hang-oldfb: SUCCESS (0.452s) [489.321540] [105/142] (516s left) kms_vblank (pipe-d-query-forked-hang) [491.278303] Starting subtest: pipe-D-query-forked-hang [505.021152] Subtest pipe-D-query-forked-hang: SUCCESS (13.744s) [505.145649] [106/142] (500s left) kms_pipe_crc_basic (hang-read-crc) [507.099661] Starting subtest: hang-read-crc [507.100548] Starting dynamic subtest: pipe-A-HDMI-A-3 [508.334116] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (1.233s) [508.344755] Starting dynamic subtest: pipe-B-HDMI-A-3 [509.590163] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (1.255s) [509.599591] Starting dynamic subtest: pipe-C-HDMI-A-3 [510.855494] Dynamic subtest pipe-C-HDMI-A-3: SUCCESS (1.263s) [510.863363] Starting dynamic subtest: pipe-D-HDMI-A-3 [512.227632] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (1.370s) [512.228653] Subtest hang-read-crc: SUCCESS (5.127s) [512.352254] [107/142] (493s left) gem_exec_reloc (basic-wc-gtt-noreloc) [512.418895] Subtest basic-wc-gtt-noreloc: SKIP (0.000s) [512.461639] [108/142] (493s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-render) [514.419200] Starting subtest: psr-2p-primscrn-spr-indfb-draw-render [514.420768] Subtest psr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s) [514.485170] [109/142] (491s left) gem_ctx_engines (independent) [514.566057] Starting subtest: independent [514.567054] Starting dynamic subtest: rcs0 [514.725165] Dynamic subtest rcs0: SUCCESS (0.150s) [514.732855] Starting dynamic subtest: bcs0 [514.851958] Dynamic subtest bcs0: SUCCESS (0.126s) [514.859826] Starting dynamic subtest: vcs0 [514.984376] Dynamic subtest vcs0: SUCCESS (0.132s) [514.992058] Starting dynamic subtest: vcs1 [515.116817] Dynamic subtest vcs1: SUCCESS (0.140s) [515.124563] Starting dynamic subtest: vecs0 [515.262455] Dynamic subtest vecs0: SUCCESS (0.136s) [515.270149] Starting dynamic subtest: vecs1 [515.397079] Dynamic subtest vecs1: SUCCESS (0.134s) [515.404750] Starting dynamic subtest: ccs0 [515.535161] Dynamic subtest ccs0: SUCCESS (0.137s) [515.542948] Starting dynamic subtest: ccs1 [515.671141] Dynamic subtest ccs1: SUCCESS (0.135s) [515.679206] Starting dynamic subtest: ccs2 [515.800063] Dynamic subtest ccs2: SUCCESS (0.136s) [515.807780] Starting dynamic subtest: ccs3 [515.945604] Dynamic subtest ccs3: SUCCESS (0.136s) [515.953396] Starting dynamic subtest: all-engines [516.364795] Dynamic subtest all-engines: SUCCESS (0.419s) [516.365663] Subtest independent: SUCCESS (1.791s) [517.520899] [110/142] (488s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size) [519.483524] Starting subtest: cursorB-vs-flipB-varying-size [519.485384] Subtest cursorB-vs-flipB-varying-size: SKIP (0.001s) [519.534894] [111/142] (486s left) kms_scaling_modes (scaling-mode-full) [521.503456] Starting subtest: scaling-mode-full [521.505517] Subtest scaling-mode-full: SKIP (0.001s) [521.568812] [112/142] (484s left) i915_getparams_basic (basic-eu-total) [521.638915] Starting subtest: basic-eu-total [521.640654] Subtest basic-eu-total: SUCCESS (0.000s) [521.723076] [113/142] (483s left) gem_caching (read-writes) [521.814985] Starting subtest: read-writes [531.674932] Subtest read-writes: SUCCESS (9.860s) [531.802512] [114/142] (473s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-pwrite) [533.759346] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-pwrite [533.761067] Subtest fbc-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s) [533.836592] [115/142] (471s left) kms_big_fb (4-tiled-32bpp-rotate-270) [535.816928] Starting subtest: 4-tiled-32bpp-rotate-270 [535.871648] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.054s) [535.993668] [116/142] (469s left) vc4/vc4_purgeable_bo (mark-purgeable-twice) [536.120488] Subtest mark-purgeable-twice: SKIP (0.000s) [536.169836] [117/142] (469s left) kms_ccs (pipe-a-random-ccs-data-4_tiled_dg2_rc_ccs) [538.187456] Starting subtest: pipe-A-random-ccs-data-4_tiled_dg2_rc_ccs [538.299514] Subtest pipe-A-random-ccs-data-4_tiled_dg2_rc_ccs: SUCCESS (0.112s) [538.537767] [118/142] (467s left) gem_mmap_wc (read-write) [538.598120] Subtest read-write: SKIP (0.000s) [538.631776] [119/142] (467s left) gem_exec_balancer (bonded-false-hang) [538.918261] Starting subtest: bonded-false-hang [538.920083] Subtest bonded-false-hang: SKIP (0.000s) [538.972570] [120/142] (466s left) v3d/v3d_create_bo (create-bo-4096) [539.055913] Subtest create-bo-4096: SKIP (0.000s) [539.105409] [121/142] (466s left) gem_partial_pwrite_pread (reads-snoop) [539.172301] Subtest reads-snoop: SKIP (0.000s) [539.215693] [122/142] (466s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy) [541.187375] Starting subtest: fbcpsr-modesetfrombusy [541.189156] Subtest fbcpsr-modesetfrombusy: SKIP (0.000s) [541.272512] [123/142] (464s left) gem_exec_whisper (basic-contexts-all) [541.381217] Starting subtest: basic-contexts-all [563.559564] Subtest basic-contexts-all: SUCCESS (22.168s) [563.738800] [124/142] (442s left) kms_ccs (pipe-d-crc-primary-rotation-180-4_tiled_mtl_mc_ccs) [565.836952] Starting subtest: pipe-D-crc-primary-rotation-180-4_tiled_mtl_mc_ccs [565.838623] Subtest pipe-D-crc-primary-rotation-180-4_tiled_mtl_mc_ccs: SKIP (0.000s) [565.948251] [125/142] (439s left) kms_ccs (pipe-c-crc-primary-basic-yf_tiled_ccs) [568.010804] Starting subtest: pipe-C-crc-primary-basic-yf_tiled_ccs [568.012655] Subtest pipe-C-crc-primary-basic-yf_tiled_ccs: SKIP (0.000s) [568.156858] [126/142] (437s left) kms_universal_plane (cursor-fb-leak-pipe-a) [570.110369] Starting subtest: cursor-fb-leak-pipe-A [570.567346] Subtest cursor-fb-leak-pipe-A: SUCCESS (0.457s) [570.715115] [127/142] (435s left) perf_pmu (all-busy-idle-check-all) [570.779633] Starting subtest: all-busy-idle-check-all [571.458555] Subtest all-busy-idle-check-all: SUCCESS (0.678s) [571.523503] [128/142] (434s left) core_setmaster (master-drop-set-root) [572.278345] Starting subtest: master-drop-set-root [572.282191] Subtest master-drop-set-root: SUCCESS (0.004s) [572.296459] [129/142] (433s left) kms_cursor_crc (cursor-random-256x85) [574.396277] Starting subtest: cursor-random-256x85 [574.400967] Starting dynamic subtest: pipe-A-HDMI-A-3 [578.687548] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (4.287s) [578.712868] Starting dynamic subtest: pipe-D-HDMI-A-3 [583.190379] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (4.485s) [583.191336] Subtest cursor-random-256x85: SUCCESS (8.793s) [583.393460] [130/142] (422s left) perf_pmu (module-unload) [583.459935] Starting subtest: module-unload [585.435588] Subtest module-unload: FAIL (1.958s) [585.447182] [131/142] (420s left) gem_ctx_persistence (userptr) [588.112625] Starting subtest: userptr [588.266829] Subtest userptr: SUCCESS (0.154s) [589.076036] [132/142] (416s left) gem_exec_fence (syncobj-timeline-unused-fence) [590.036167] Starting subtest: syncobj-timeline-unused-fence [590.047475] Subtest syncobj-timeline-unused-fence: SUCCESS (0.020s) [590.380850] [133/142] (415s left) kms_rotation_crc (primary-y-tiled-reflect-x-90) [592.350034] Starting subtest: primary-y-tiled-reflect-x-90 [592.351674] Subtest primary-y-tiled-reflect-x-90: SKIP (0.001s) [592.408870] [134/142] (413s left) kms_plane (pixel-format) [594.374478] Starting subtest: pixel-format [594.377179] Starting dynamic subtest: pipe-A-planes [606.468147] Dynamic subtest pipe-A-planes: SUCCESS (12.092s) [606.474830] Starting dynamic subtest: pipe-B-planes [618.565201] Dynamic subtest pipe-B-planes: SUCCESS (12.096s) [618.566062] Subtest pixel-format: SUCCESS (24.191s) [618.693034] [135/142] (387s left) kms_ccs (pipe-c-bad-rotation-90-y_tiled_ccs) [620.769383] Starting subtest: pipe-C-bad-rotation-90-y_tiled_ccs [620.771106] Subtest pipe-C-bad-rotation-90-y_tiled_ccs: SKIP (0.000s) [620.922358] [136/142] (385s left) syncobj_wait (multi-wait-for-submit-submitted) [620.981553] Starting subtest: multi-wait-for-submit-submitted [621.084709] Subtest multi-wait-for-submit-submitted: SUCCESS (0.102s) [621.171741] [137/142] (384s left) kms_frontbuffer_tracking (psr-rgb565-draw-render) [623.138577] Starting subtest: psr-rgb565-draw-render [623.140187] Subtest psr-rgb565-draw-render: SKIP (0.000s) [623.206008] [138/142] (382s left) v3d/v3d_submit_csd (valid-submission) [623.307998] Subtest valid-submission: SKIP (0.000s) [623.357873] [139/142] (382s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-async-flip) [625.329553] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip [626.190980] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.861s) [626.307713] [140/142] (379s left) kms_frontbuffer_tracking (fbc-1p-rte) [628.269975] Starting subtest: fbc-1p-rte [631.891619] Subtest fbc-1p-rte: SUCCESS (3.623s) [632.043514] [141/142] (374s left) kms_big_fb (x-tiled-8bpp-rotate-90) [634.007889] Starting subtest: x-tiled-8bpp-rotate-90 [634.058436] Subtest x-tiled-8bpp-rotate-90: SKIP (0.050s) [634.174125] [142/142] (372s left) kms_ccs (pipe-c-random-ccs-data-4_tiled_dg2_rc_ccs_cc) [636.308377] Starting subtest: pipe-C-random-ccs-data-4_tiled_dg2_rc_ccs_cc [636.420177] Subtest pipe-C-random-ccs-data-4_tiled_dg2_rc_ccs_cc: SUCCESS (0.112s) [636.589010] Closing watchdogs Done.