[41.184310] Initializing watchdogs
[41.184518]   /dev/watchdog0
[41.217253] [001/139] (960s left) kms_ccs (pipe-a-crc-primary-basic-y-tiled-gen12-rc-ccs-cc)
[41.669212] Starting subtest: pipe-A-crc-primary-basic-y-tiled-gen12-rc-ccs-cc
[41.707307] Subtest pipe-A-crc-primary-basic-y-tiled-gen12-rc-ccs-cc: SKIP (0.001s)
[42.152424] [002/139] (959s left) kms_ccs (pipe-b-bad-rotation-90-y-tiled-gen12-rc-ccs)
[42.582087] Starting subtest: pipe-B-bad-rotation-90-y-tiled-gen12-rc-ccs
[42.619619] Subtest pipe-B-bad-rotation-90-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[43.016129] [003/139] (958s left) perf (oa-exponents)
[43.396898] Starting subtest: oa-exponents
[43.418527] Starting dynamic subtest: 0-rcs0
[47.581681] Dynamic subtest 0-rcs0: SUCCESS (4.183s)
[47.602822] Subtest oa-exponents: SUCCESS (4.184s)
[47.701310] [004/139] (954s left) kms_invalid_mode (zero-vdisplay)
[48.016630] Starting subtest: zero-vdisplay
[48.038767] Starting dynamic subtest: pipe-A-DP-1
[48.107438] Dynamic subtest pipe-A-DP-1: SUCCESS (0.102s)
[48.143343] Starting dynamic subtest: pipe-B-DP-1
[48.163288] Dynamic subtest pipe-B-DP-1: SUCCESS (0.002s)
[48.195838] Starting dynamic subtest: pipe-C-DP-1
[48.217180] Dynamic subtest pipe-C-DP-1: SUCCESS (0.002s)
[48.239112] Subtest zero-vdisplay: SUCCESS (0.112s)
[48.463473] [005/139] (953s left) kms_chamelium_hpd (hdmi-hpd-fast)
[48.886553] Subtest hdmi-hpd-fast: SKIP (0.000s)
[49.173225] [006/139] (952s left) gem_exec_fence (syncobj-unused-fence)
[49.931077] Starting subtest: syncobj-unused-fence
[49.949920] Subtest syncobj-unused-fence: SUCCESS (0.008s)
[50.311228] [007/139] (951s left) gem_softpin (evict-snoop)
[50.526901] Starting subtest: evict-snoop
[50.567132] Subtest evict-snoop: SKIP (0.000s)
[50.646067] [008/139] (951s left) gem_tiled_fence_blits (normal)
[52.053121] Starting subtest: normal
[54.227641] Subtest normal: SUCCESS (2.176s)
[54.334278] [009/139] (948s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[54.888826] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip
[54.966258] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.022s)
[55.054598] [010/139] (947s left) syncobj_wait (invalid-wait-zero-handles)
[55.302148] Starting subtest: invalid-wait-zero-handles
[55.322993] Subtest invalid-wait-zero-handles: SUCCESS (0.000s)
[55.411247] [011/139] (947s left) kms_vrr (flip-suspend)
[55.704631] Starting subtest: flip-suspend
[55.744482] Subtest flip-suspend: SKIP (0.000s)
[55.838522] [012/139] (946s left) syncobj_timeline (wait-all-delayed-signal)
[56.069379] Starting subtest: wait-all-delayed-signal
[56.142419] Subtest wait-all-delayed-signal: SUCCESS (0.103s)
[56.248234] [013/139] (946s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled)
[56.455883] Starting subtest: multi-wait-all-for-submit-submitted-signaled
[56.527168] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.104s)
[56.637649] [014/139] (946s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[57.008987] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen
[57.051686] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[57.140215] [015/139] (945s left) kms_ccs (pipe-b-missing-ccs-buffer-y-tiled-gen12-mc-ccs)
[57.606967] Starting subtest: pipe-B-missing-ccs-buffer-y-tiled-gen12-mc-ccs
[57.644878] Subtest pipe-B-missing-ccs-buffer-y-tiled-gen12-mc-ccs: SKIP (0.000s)
[58.016488] [016/139] (944s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[58.388138] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[58.428298] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[58.508932] [017/139] (944s left) vc4/vc4_purgeable_bo (access-purged-bo-mem)
[58.792183] Subtest access-purged-bo-mem: SKIP (0.000s)
[58.879238] [018/139] (944s left) kms_cursor_edge_walk (64x64-right-edge)
[59.182250] Starting subtest: 64x64-right-edge
[59.201380] Starting dynamic subtest: pipe-A-DP-1
[63.117816] Dynamic subtest pipe-A-DP-1: SUCCESS (3.931s)
[63.152881] Starting dynamic subtest: pipe-C-DP-1
[66.590951] Dynamic subtest pipe-C-DP-1: SUCCESS (3.475s)
[66.613449] Subtest 64x64-right-edge: SUCCESS (7.413s)
[66.911685] [019/139] (936s left) i915_pipe_stress (stress-xrgb8888-ytiled)
[67.235476] Starting subtest: stress-xrgb8888-ytiled
[76.738147] Subtest stress-xrgb8888-ytiled: FAIL (8.203s)
[76.828059] [020/139] (926s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-blt)
[77.185609] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-blt
[77.223129] Subtest psr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[77.314139] [021/139] (926s left) kms_cursor_crc (cursor-offscreen-max-size)
[77.636716] Starting subtest: cursor-offscreen-max-size
[77.693577] Subtest cursor-offscreen-max-size: SKIP (0.000s)
[77.782466] [022/139] (925s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-mmap-cpu)
[78.151549] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-mmap-cpu
[79.911388] Subtest fbc-1p-offscren-pri-shrfb-draw-mmap-cpu: SUCCESS (1.839s)
[80.087695] [023/139] (923s left) v3d/v3d_create_bo (create-bo-zeroed)
[80.372803] Subtest create-bo-zeroed: SKIP (0.000s)
[80.454130] [024/139] (923s left) perf_pmu (busy-start)
[80.688956] Starting subtest: busy-start
[80.707643] Starting dynamic subtest: rcs0
[83.385116] Dynamic subtest rcs0: SUCCESS (2.526s)
[83.421172] Starting dynamic subtest: bcs0
[85.898492] Dynamic subtest bcs0: SUCCESS (2.526s)
[85.935098] Starting dynamic subtest: vcs0
[88.433319] Dynamic subtest vcs0: SUCCESS (2.519s)
[88.467210] Starting dynamic subtest: vecs0
[90.946771] Dynamic subtest vecs0: SUCCESS (2.525s)
[90.967391] Subtest busy-start: SUCCESS (10.097s)
[91.069660] [025/139] (912s left) perf (buffer-fill)
[91.425875] Starting subtest: buffer-fill
[91.444582] Starting dynamic subtest: 0-rcs0
[94.273745] Dynamic subtest 0-rcs0: SUCCESS (2.849s)
[94.292498] Subtest buffer-fill: SUCCESS (2.850s)
[94.402634] [026/139] (909s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-cpu)
[94.748973] Starting subtest: fbcpsr-rgb101010-draw-mmap-cpu
[94.791745] Subtest fbcpsr-rgb101010-draw-mmap-cpu: SKIP (0.000s)
[94.878465] [027/139] (909s left) kms_ccs (pipe-a-bad-rotation-90-4-tiled-dg2-rc-ccs-cc)
[95.274459] Starting subtest: pipe-A-bad-rotation-90-4-tiled-dg2-rc-ccs-cc
[95.313146] Subtest pipe-A-bad-rotation-90-4-tiled-dg2-rc-ccs-cc: SKIP (0.000s)
[95.734259] [028/139] (908s left) perf (per-context-mode-unprivileged)
[96.058613] Starting subtest: per-context-mode-unprivileged
[96.114492] Subtest per-context-mode-unprivileged: SKIP (0.000s)
[96.239839] [029/139] (907s left) prime_busy (hang-wait)
[96.493790] Starting subtest: hang-wait
[96.521135] Starting dynamic subtest: rcs0
[102.912371] Dynamic subtest rcs0: SUCCESS (6.432s)
[102.955929] Starting dynamic subtest: bcs0
[110.114651] Dynamic subtest bcs0: SUCCESS (7.035s)
[110.151143] Starting dynamic subtest: vcs0
[116.919622] Dynamic subtest vcs0: SUCCESS (6.974s)
[116.956219] Starting dynamic subtest: vecs0
[123.955503] Dynamic subtest vecs0: SUCCESS (7.039s)
[123.974196] Subtest hang-wait: SUCCESS (27.485s)
[124.070249] [030/139] (880s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[124.416594] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[124.456498] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[124.546645] [031/139] (879s left) syncobj_timeline (invalid-signal-illegal-handle)
[124.772734] Starting subtest: invalid-signal-illegal-handle
[124.793948] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[124.877948] [032/139] (879s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[125.098031] Starting subtest: writes-after-reads-uncached
[145.143924] Subtest writes-after-reads-uncached: SUCCESS (20.048s)
[145.278898] [033/139] (859s left) syncobj_timeline (device-submit-unordered)
[145.533960] Starting subtest: device-submit-unordered
[145.557889] Subtest device-submit-unordered: SUCCESS (0.001s)
[145.666289] [034/139] (858s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-onoff)
[146.086544] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-onoff
[146.130927] Subtest fbcpsr-1p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[146.245335] [035/139] (858s left) vc4/vc4_tiling (get-bad-modifier)
[146.534527] Subtest get-bad-modifier: SKIP (0.000s)
[146.633490] [036/139] (858s left) gem_exec_schedule (preempt-queue-contexts-chain)
[147.085029] Starting subtest: preempt-queue-contexts-chain
[147.109824] Starting dynamic subtest: rcs0
[148.001443] Dynamic subtest rcs0: SUCCESS (1.126s)
[148.041863] Starting dynamic subtest: bcs0
[149.114762] Dynamic subtest bcs0: SUCCESS (1.112s)
[149.160984] Starting dynamic subtest: vcs0
[150.201882] Dynamic subtest vcs0: SUCCESS (1.086s)
[150.244414] Starting dynamic subtest: vecs0
[151.336609] Dynamic subtest vecs0: SUCCESS (1.138s)
[151.357137] Subtest preempt-queue-contexts-chain: SUCCESS (4.466s)
[151.735730] [037/139] (853s left) kms_big_fb (4-tiled-16bpp-rotate-180)
[152.065725] Starting subtest: 4-tiled-16bpp-rotate-180
[152.108810] Subtest 4-tiled-16bpp-rotate-180: SKIP (0.000s)
[152.197295] [038/139] (852s left) gem_reg_read (timestamp-moving)
[152.428937] Starting subtest: timestamp-moving
[153.393662] Subtest timestamp-moving: SUCCESS (1.001s)
[153.505488] [039/139] (851s left) kms_ccs (pipe-d-missing-ccs-buffer-y-tiled-gen12-rc-ccs)
[154.053423] Subtest pipe-D-missing-ccs-buffer-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[154.373422] [040/139] (850s left) gem_exec_reloc (basic-range-active)
[154.606116] Starting subtest: basic-range-active
[154.671129] Subtest basic-range-active: SUCCESS (0.087s)
[154.893370] [041/139] (850s left) gem_mmap_gtt (coherency)
[155.110292] Starting subtest: coherency
[155.147266] Subtest coherency: SKIP (0.000s)
[155.245057] [042/139] (850s left) kms_ccs (pipe-c-missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[155.749234] Starting subtest: pipe-C-missing-ccs-buffer-4-tiled-mtl-mc-ccs
[155.793749] Subtest pipe-C-missing-ccs-buffer-4-tiled-mtl-mc-ccs: SKIP (0.000s)
[156.105621] [043/139] (849s left) kms_content_protection (srm)
[156.446690] Starting subtest: srm
[156.509250] Starting dynamic subtest: pipe-A-DP-1
[277.400993] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[284.827317] Closing watchdogs
[284.837541] Initializing watchdogs
[284.837718]   /dev/watchdog0
[284.875498] [044/139] (720s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[285.181089] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[285.202122] Starting dynamic subtest: pipe-A-valid-mode
[285.906402] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.719s)
[285.928303] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SUCCESS (0.722s)
[286.263381] [045/139] (719s left) gem_exec_whisper (basic-fds-forked)
[286.544232] Starting subtest: basic-fds-forked
[304.844946] Subtest basic-fds-forked: SUCCESS (18.339s)
[305.048783] [046/139] (700s left) api_intel_bb (simple-bb)
[305.281531] Starting subtest: simple-bb
[305.303286] Subtest simple-bb: SUCCESS (0.007s)
[305.396371] [047/139] (700s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary)
[305.742340] Starting subtest: psr-shrfb-scaledprimary
[305.784933] Subtest psr-shrfb-scaledprimary: SKIP (0.000s)
[305.876217] [048/139] (699s left) kms_ccs (pipe-a-bad-aux-stride-y-tiled-ccs)
[306.279764] Starting subtest: pipe-A-bad-aux-stride-y-tiled-ccs
[306.300898] Subtest pipe-A-bad-aux-stride-y-tiled-ccs: SUCCESS (0.002s)
[306.732117] [049/139] (699s left) kms_vblank (query-busy-hang)
[307.032469] Starting subtest: query-busy-hang
[307.051244] Starting dynamic subtest: pipe-A-DP-1
[314.001117] Dynamic subtest pipe-A-DP-1: SUCCESS (6.955s)
[314.047557] Starting dynamic subtest: pipe-C-DP-1
[321.130354] Dynamic subtest pipe-C-DP-1: SUCCESS (7.023s)
[321.149076] Subtest query-busy-hang: SUCCESS (13.989s)
[321.384362] [050/139] (684s left) kms_ccs (pipe-d-bad-pixel-format-4-tiled-mtl-rc-ccs-cc)
[321.923854] Subtest pipe-D-bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SKIP (0.000s)
[322.248451] [051/139] (683s left) gem_exec_balancer (indices)
[322.484117] Starting subtest: indices
[326.184043] Subtest indices: SUCCESS (3.550s)
[330.001435] [052/139] (676s left) kms_chamelium_edid (vga-edid-read)
[330.411883] Subtest vga-edid-read: SKIP (0.000s)
[330.714825] [053/139] (675s left) kms_color (ctm-0-50)
[331.011778] Starting subtest: ctm-0-50
[331.033196] Starting dynamic subtest: pipe-A
[332.836477] Dynamic subtest pipe-A: SUCCESS (1.810s)
[332.877511] Starting dynamic subtest: pipe-B
[334.866295] Dynamic subtest pipe-B: SUCCESS (2.019s)
[334.911103] Starting dynamic subtest: pipe-C
[336.884673] Dynamic subtest pipe-C: SUCCESS (2.017s)
[336.911083] Subtest ctm-0-50: SUCCESS (5.850s)
[337.211923] [054/139] (669s left) syncobj_wait (invalid-wait-bad-flags)
[337.434696] Starting subtest: invalid-wait-bad-flags
[337.456184] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[337.549233] [055/139] (668s left) kms_ccs (pipe-c-crc-primary-rotation-180-yf-tiled-ccs)
[338.044325] Starting subtest: pipe-C-crc-primary-rotation-180-yf-tiled-ccs
[338.085370] Subtest pipe-C-crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.001s)
[338.415568] [056/139] (668s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[338.715389] Starting subtest: nonblocking-crc-frame-sequence
[338.734191] Starting dynamic subtest: pipe-A-DP-1
[339.313827] Dynamic subtest pipe-A-DP-1: SUCCESS (0.586s)
[339.349980] Starting dynamic subtest: pipe-B-DP-1
[340.090245] Dynamic subtest pipe-B-DP-1: SUCCESS (0.782s)
[340.130861] Starting dynamic subtest: pipe-C-DP-1
[340.880450] Dynamic subtest pipe-C-DP-1: SUCCESS (0.772s)
[340.900066] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.153s)
[341.202840] [057/139] (665s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-render)
[341.581325] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-render
[341.618877] Subtest fbc-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[341.712480] [058/139] (664s left) gem_mmap_gtt (bad-object)
[341.933832] Starting subtest: bad-object
[341.955915] Subtest bad-object: SUCCESS (0.000s)
[342.049572] [059/139] (664s left) gem_ctx_persistence (process)
[342.263423] Starting subtest: process
[342.367023] Subtest process: SUCCESS (0.117s)
[342.473419] [060/139] (664s left) kms_draw_crc (draw-method-blt)
[342.777374] Starting subtest: draw-method-blt
[342.798637] Starting dynamic subtest: xrgb8888-untiled
[343.081363] Dynamic subtest xrgb8888-untiled: SUCCESS (0.300s)
[343.120493] Starting dynamic subtest: rgb565-untiled
[343.382703] Dynamic subtest rgb565-untiled: SUCCESS (0.298s)
[343.420710] Starting dynamic subtest: xrgb2101010-untiled
[343.682216] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.298s)
[343.721409] Starting dynamic subtest: xrgb8888-xtiled
[343.842961] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.165s)
[343.879850] Starting dynamic subtest: rgb565-xtiled
[344.015799] Dynamic subtest rgb565-xtiled: SUCCESS (0.165s)
[344.054219] Starting dynamic subtest: xrgb2101010-xtiled
[344.183327] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.167s)
[344.220437] Starting dynamic subtest: xrgb8888-ytiled
[344.363278] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.179s)
[344.399942] Starting dynamic subtest: rgb565-ytiled
[344.530240] Dynamic subtest rgb565-ytiled: SUCCESS (0.165s)
[344.568789] Starting dynamic subtest: xrgb2101010-ytiled
[344.709100] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.181s)
[344.727926] Subtest draw-method-blt: SUCCESS (1.935s)
[344.841752] [061/139] (662s left) kms_ccs (pipe-a-random-ccs-data-y-tiled-gen12-rc-ccs)
[345.255326] Starting subtest: pipe-A-random-ccs-data-y-tiled-gen12-rc-ccs
[345.318696] Subtest pipe-A-random-ccs-data-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[345.729457] [062/139] (661s left) dumb_buffer (map-invalid-size)
[345.959295] Starting subtest: map-invalid-size
[345.980632] Subtest map-invalid-size: SUCCESS (0.000s)
[346.068980] [063/139] (661s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[346.400184] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[349.311080] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SUCCESS (2.970s)
[349.478531] [064/139] (657s left) api_intel_allocator (standalone)
[349.721143] Starting subtest: standalone
[349.762773] Subtest standalone: SUCCESS (0.059s)
[349.862437] [065/139] (657s left) gem_flink_basic (double-flink)
[350.088945] Starting subtest: double-flink
[350.109743] Subtest double-flink: SUCCESS (0.000s)
[350.198501] [066/139] (657s left) gem_userptr_blits (readonly-pwrite-unsync)
[350.508660] Subtest readonly-pwrite-unsync: SKIP (0.000s)
[350.608527] [067/139] (656s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-mc-ccs)
[350.839449] Starting subtest: y-tiled-ccs-to-yf-tiled-mc-ccs
[350.881647] Subtest y-tiled-ccs-to-yf-tiled-mc-ccs: SKIP (0.000s)
[351.057444] [068/139] (656s left) template (b)
[351.285751] Starting subtest: B
[351.304612] Subtest B: SUCCESS (0.000s)
[351.396730] [069/139] (656s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0)
[351.724616] Starting subtest: linear-max-hw-stride-64bpp-rotate-0
[353.178884] Subtest linear-max-hw-stride-64bpp-rotate-0: SUCCESS (1.477s)
[353.359809] [070/139] (654s left) kms_flip (blocking-absolute-wf_vblank)
[353.663746] Starting subtest: blocking-absolute-wf_vblank
[353.688628] Starting dynamic subtest: A-DP1
[364.375115] Dynamic subtest A-DP1: SUCCESS (10.528s)
[364.423703] Starting dynamic subtest: B-DP1
[374.801223] Dynamic subtest B-DP1: SUCCESS (10.621s)
[374.839236] Starting dynamic subtest: C-DP1
[385.254116] Dynamic subtest C-DP1: SUCCESS (10.454s)
[385.276063] Subtest blocking-absolute-wf_vblank: SUCCESS (31.615s)
[385.690653] [071/139] (622s left) kms_plane_scaling (planes-downscale-factor-0-25-upscale-20x20)
[385.990668] Starting subtest: planes-downscale-factor-0-25-upscale-20x20
[386.011279] Starting dynamic subtest: pipe-A-DP-1
[386.048683] Dynamic subtest pipe-A-DP-1: SKIP (0.030s)
[386.085487] Starting dynamic subtest: pipe-B-DP-1
[386.151678] Dynamic subtest pipe-B-DP-1: SKIP (0.081s)
[386.193308] Subtest planes-downscale-factor-0-25-upscale-20x20: SKIP (0.146s)
[386.298665] [072/139] (621s left) kms_legacy_colorkey (invalid-plane)
[386.589398] Starting subtest: invalid-plane
[386.610585] Starting dynamic subtest: zero-id
[386.631169] Dynamic subtest zero-id: SUCCESS (0.000s)
[386.668713] Starting dynamic subtest: outrange-id
[386.687519] Dynamic subtest outrange-id: SUCCESS (0.000s)
[386.706217] Subtest invalid-plane: SUCCESS (0.004s)
[386.796782] [073/139] (621s left) kms_big_fb (y-tiled-16bpp-rotate-270)
[387.127835] Starting subtest: y-tiled-16bpp-rotate-270
[387.203423] Subtest y-tiled-16bpp-rotate-270: SKIP (0.077s)
[387.336529] [074/139] (620s left) gem_exec_schedule (pi-distinct-iova)
[387.718421] Starting subtest: pi-distinct-iova
[387.739643] Starting dynamic subtest: rcs0
[387.783972] Dynamic subtest rcs0: SUCCESS (0.074s)
[387.821148] Starting dynamic subtest: bcs0
[387.842171] Dynamic subtest bcs0: SUCCESS (0.070s)
[387.878780] Starting dynamic subtest: vcs0
[387.900196] Dynamic subtest vcs0: SUCCESS (0.053s)
[387.941105] Starting dynamic subtest: vecs0
[387.961743] Dynamic subtest vecs0: SUCCESS (0.053s)
[387.982188] Subtest pi-distinct-iova: SUCCESS (0.261s)
[388.203928] [075/139] (619s left) kms_plane_scaling (plane-scaler-unity-scaling-with-pixel-format)
[388.492192] Starting subtest: plane-scaler-unity-scaling-with-pixel-format
[388.512808] Starting dynamic subtest: pipe-A-DP-1
[390.893041] Dynamic subtest pipe-A-DP-1: SUCCESS (2.371s)
[390.931777] Starting dynamic subtest: pipe-B-DP-1
[393.502461] Dynamic subtest pipe-B-DP-1: SUCCESS (2.600s)
[393.541179] Starting dynamic subtest: pipe-C-DP-1
[393.889126] Dynamic subtest pipe-C-DP-1: SUCCESS (0.375s)
[393.910405] Subtest plane-scaler-unity-scaling-with-pixel-format: SUCCESS (5.380s)
[394.300463] [076/139] (613s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[394.605443] Starting subtest: flip-vs-cursor-busy-crc-atomic
[395.408694] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.795s)
[395.772540] [077/139] (612s left) kms_addfb_basic (invalid-set-prop-any)
[396.001874] Starting subtest: invalid-set-prop-any
[396.022829] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[396.185270] [078/139] (612s left) kms_big_fb (linear-16bpp-rotate-90)
[396.517092] Starting subtest: linear-16bpp-rotate-90
[396.617787] Subtest linear-16bpp-rotate-90: SKIP (0.086s)
[396.741981] [079/139] (611s left) gem_render_copy_redux (normal)
[396.974978] Starting subtest: normal
[399.447227] Subtest normal: SUCCESS (2.312s)
[399.534564] [080/139] (609s left) v3d/v3d_submit_cl (bad-perfmon)
[399.806664] Subtest bad-perfmon: SKIP (0.000s)
[399.901185] [081/139] (608s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[400.251743] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-gtt
[400.291776] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[400.387772] [082/139] (608s left) kms_ccs (pipe-c-bad-rotation-90-y-tiled-ccs)
[400.870345] Starting subtest: pipe-C-bad-rotation-90-y-tiled-ccs
[400.911972] Subtest pipe-C-bad-rotation-90-y-tiled-ccs: SKIP (0.000s)
[401.260781] [083/139] (607s left) gem_ctx_isolation (preservation-s3)
[401.494689] Starting subtest: preservation-S3
[401.516367] Starting dynamic subtest: rcs0
[419.639018] Dynamic subtest rcs0: SUCCESS (2.989s)
[419.683764] Starting dynamic subtest: bcs0
[438.615452] Dynamic subtest bcs0: SUCCESS (2.857s)
[438.658461] Starting dynamic subtest: vcs0
[457.521672] Dynamic subtest vcs0: SUCCESS (2.504s)
[457.580035] Starting dynamic subtest: vecs0
[476.498840] Dynamic subtest vecs0: SUCCESS (2.531s)
[476.518925] Subtest preservation-S3: SUCCESS (10.892s)
[481.949638] [084/139] (596s left) kms_big_fb (yf-tiled-8bpp-rotate-270)
[482.289725] Starting subtest: yf-tiled-8bpp-rotate-270
[482.331151] Subtest yf-tiled-8bpp-rotate-270: SKIP (0.000s)
[482.424213] [085/139] (595s left) gem_tiled_partial_pwrite_pread (reads)
[482.660106] Starting subtest: reads
[483.979346] Subtest reads: SUCCESS (1.328s)
[484.115099] [086/139] (594s left) gem_ctx_sseu (invalid-args)
[484.372129] Subtest invalid-args: SKIP (0.000s)
[484.463613] [087/139] (594s left) kms_rotation_crc (bad-tiling)
[484.762074] Starting subtest: bad-tiling
[487.236638] Subtest bad-tiling: SUCCESS (2.478s)
[487.696299] [088/139] (590s left) gen7_exec_parse (cmd-crossing-page)
[487.949056] Subtest cmd-crossing-page: SKIP (0.000s)
[488.045597] [089/139] (590s left) kms_psr (suspend)
[488.286033] Subtest suspend: SKIP (0.000s)
[488.380265] [090/139] (590s left) kms_ccs (pipe-d-crc-primary-rotation-180-y-tiled-gen12-mc-ccs)
[488.897227] Subtest pipe-D-crc-primary-rotation-180-y-tiled-gen12-mc-ccs: SKIP (0.000s)
[489.212489] [091/139] (589s left) gem_ctx_persistence (heartbeat-hostile)
[489.438418] Starting subtest: heartbeat-hostile
[490.631000] Subtest heartbeat-hostile: SUCCESS (1.195s)
[490.742714] [092/139] (588s left) kms_flip (2x-flip-vs-modeset-vs-hang)
[491.061162] Starting subtest: 2x-flip-vs-modeset-vs-hang
[491.102730] Subtest 2x-flip-vs-modeset-vs-hang: SKIP (0.003s)
[491.194456] [093/139] (587s left) gem_eio (wait-immediate)
[491.473229] Starting subtest: wait-immediate
[491.516558] Subtest wait-immediate: SUCCESS (0.070s)
[491.726045] [094/139] (587s left) kms_plane_scaling (plane-upscale-20x20-with-modifiers)
[492.016128] Starting subtest: plane-upscale-20x20-with-modifiers
[492.037435] Starting dynamic subtest: pipe-A-DP-1
[492.477924] Dynamic subtest pipe-A-DP-1: SUCCESS (0.432s)
[492.523104] Starting dynamic subtest: pipe-B-DP-1
[493.233258] Dynamic subtest pipe-B-DP-1: SUCCESS (0.759s)
[493.275135] Starting dynamic subtest: pipe-C-DP-1
[493.831682] Dynamic subtest pipe-C-DP-1: SUCCESS (0.582s)
[493.850500] Subtest plane-upscale-20x20-with-modifiers: SUCCESS (1.808s)
[494.275495] [095/139] (585s left) kms_prime (d3hot)
[496.572782] Subtest D3hot: SKIP (0.000s)
[496.684985] [096/139] (582s left) gem_partial_pwrite_pread (write-display)
[496.912052] Starting subtest: write-display
[510.782021] Subtest write-display: SUCCESS (13.871s)
[510.916355] [097/139] (568s left) kms_ccs (pipe-b-crc-primary-basic-yf-tiled-ccs)
[511.342378] Starting subtest: pipe-B-crc-primary-basic-yf-tiled-ccs
[512.009703] Subtest pipe-B-crc-primary-basic-yf-tiled-ccs: SUCCESS (0.648s)
[512.588178] [098/139] (566s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[512.977586] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt
[513.014606] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[513.114044] [099/139] (566s left) gen7_exec_parse (chained-batch)
[513.360981] Subtest chained-batch: SKIP (0.000s)
[513.456442] [100/139] (566s left) gem_pread (self)
[513.676319] Starting subtest: self
[513.719619] Subtest self: SUCCESS (0.065s)
[513.834370] [101/139] (566s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[514.208571] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[514.246043] Subtest psr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[514.343886] [102/139] (565s left) kms_ccs (pipe-c-bad-pixel-format-4-tiled-dg2-rc-ccs-cc)
[514.807977] Starting subtest: pipe-C-bad-pixel-format-4-tiled-dg2-rc-ccs-cc
[514.850754] Subtest pipe-C-bad-pixel-format-4-tiled-dg2-rc-ccs-cc: SKIP (0.000s)
[515.210596] [103/139] (564s left) i915_suspend (fence-restore-tiled2untiled)
[515.447828] Starting subtest: fence-restore-tiled2untiled
[535.757521] Subtest fence-restore-tiled2untiled: SUCCESS (4.223s)
[539.097978] [104/139] (560s left) kms_ccs (pipe-d-bad-rotation-90-yf-tiled-ccs)
[539.635848] Subtest pipe-D-bad-rotation-90-yf-tiled-ccs: SKIP (0.000s)
[539.971264] [105/139] (559s left) syncobj_wait (reset-signaled)
[540.191130] Starting subtest: reset-signaled
[540.212621] Subtest reset-signaled: SUCCESS (0.000s)
[540.309232] [106/139] (559s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-render)
[540.662926] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-render
[540.704897] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[540.800773] [107/139] (558s left) kms_pm_rpm (modeset-lpsp)
[542.571336] Starting subtest: modeset-lpsp
[542.894645] Subtest modeset-lpsp: SKIP (0.307s)
[543.399344] [108/139] (556s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-gtt)
[543.772808] Starting subtest: fbcpsr-rgb101010-draw-mmap-gtt
[543.809914] Subtest fbcpsr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[543.909051] [109/139] (555s left) gem_exec_whisper (basic-queues)
[544.177186] Starting subtest: basic-queues
[551.670742] Subtest basic-queues: SUCCESS (7.530s)
[551.884115] [110/139] (548s left) kms_big_fb (x-tiled-8bpp-rotate-90)
[552.217274] Starting subtest: x-tiled-8bpp-rotate-90
[552.323080] Subtest x-tiled-8bpp-rotate-90: SKIP (0.092s)
[552.427225] [111/139] (547s left) gem_pxp (verify-pxp-stale-buf-execution)
[555.381979] Subtest verify-pxp-stale-buf-execution: SKIP (0.000s)
[555.496387] [112/139] (544s left) gem_exec_fence (submit3)
[555.834684] Starting subtest: submit3
[555.855014] Starting dynamic subtest: rcs0
[555.874141] Dynamic subtest rcs0: SUCCESS (0.012s)
[555.918430] Starting dynamic subtest: bcs0
[555.939969] Dynamic subtest bcs0: SUCCESS (0.011s)
[555.983191] Starting dynamic subtest: vcs0
[556.003900] Dynamic subtest vcs0: SUCCESS (0.009s)
[556.047884] Starting dynamic subtest: vecs0
[556.066543] Dynamic subtest vecs0: SUCCESS (0.010s)
[556.085077] Subtest submit3: SUCCESS (0.054s)
[556.577825] [113/139] (543s left) drm_fdinfo (all-busy-idle-check-all)
[556.812673] Starting subtest: all-busy-idle-check-all
[557.424479] Subtest all-busy-idle-check-all: SUCCESS (0.630s)
[557.531558] [114/139] (542s left) gem_close_race (multigpu-basic-threads)
[557.763098] Starting subtest: multigpu-basic-threads
[557.804083] Subtest multigpu-basic-threads: SKIP (0.000s)
[557.981778] [115/139] (542s left) kms_async_flips (test-time-stamp)
[558.281903] Starting subtest: test-time-stamp
[558.474520] Starting dynamic subtest: pipe-A-DP-1
[558.868557] Dynamic subtest pipe-A-DP-1: SUCCESS (0.378s)
[559.093986] Starting dynamic subtest: pipe-B-DP-1
[559.356660] Dynamic subtest pipe-B-DP-1: SUCCESS (0.294s)
[559.562318] Starting dynamic subtest: pipe-C-DP-1
[559.839940] Dynamic subtest pipe-C-DP-1: SUCCESS (0.293s)
[559.866457] Subtest test-time-stamp: SUCCESS (1.524s)
[560.338860] [116/139] (540s left) gem_spin_batch (engines)
[560.577920] Starting subtest: engines
[560.600375] Starting dynamic subtest: rcs0
[564.100292] Dynamic subtest rcs0: SUCCESS (3.325s)
[564.141218] Starting dynamic subtest: bcs0
[567.431140] Dynamic subtest bcs0: SUCCESS (3.323s)
[567.473446] Starting dynamic subtest: vcs0
[570.742172] Dynamic subtest vcs0: SUCCESS (3.321s)
[570.785151] Starting dynamic subtest: vecs0
[574.068462] Dynamic subtest vecs0: SUCCESS (3.321s)
[574.087329] Subtest engines: SUCCESS (13.303s)
[574.179379] [117/139] (526s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[574.404831] Starting subtest: cpuset-medium-copy-XY
[587.596514] Subtest cpuset-medium-copy-XY: SUCCESS (13.225s)
[587.727019] [118/139] (513s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-render)
[588.272635] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-render
[588.314391] Subtest psr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[588.410717] [119/139] (512s left) syncobj_wait (invalid-single-wait-unsubmitted)
[588.673664] Starting subtest: invalid-single-wait-unsubmitted
[588.692774] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[588.789980] [120/139] (512s left) gem_exec_fair (basic-none-vip)
[589.205282] Starting subtest: basic-none-vip
[589.227058] Starting dynamic subtest: rcs0
[594.843033] Dynamic subtest rcs0: SUCCESS (5.817s)
[594.864594] Subtest basic-none-vip: SUCCESS (5.820s)
[595.086237] [121/139] (505s left) kms_cursor_legacy (2x-cursor-vs-flip-legacy)
[595.385464] Starting subtest: 2x-cursor-vs-flip-legacy
[595.426537] Subtest 2x-cursor-vs-flip-legacy: SKIP (0.004s)
[595.527728] [122/139] (505s left) i915_pm_rpm (gem-execbuf-stress)
[597.520931] Starting subtest: gem-execbuf-stress
[597.541911] Starting dynamic subtest: smem0
[601.132645] Dynamic subtest smem0: SUCCESS (3.609s)
[601.180816] Starting dynamic subtest: extra-wait-smem0
[655.220086] Dynamic subtest extra-wait-smem0: SUCCESS (53.911s)
[655.238988] Subtest gem-execbuf-stress: SUCCESS (57.526s)
[655.586638] [123/139] (445s left) syncobj_timeline (invalid-multi-wait-all-available-unsubmitted-submitted-sign
[655.824462] Starting subtest: invalid-multi-wait-all-available-unsubmitted-submitted-signaled
[656.213894] Subtest invalid-multi-wait-all-available-unsubmitted-submitted-signaled: FAIL (0.176s)
[656.321523] [124/139] (445s left) kms_psr (psr2_cursor_plane_onoff)
[656.577701] Subtest psr2_cursor_plane_onoff: SKIP (0.000s)
[656.681666] [125/139] (444s left) sysfs_heartbeat_interval (invalid)
[656.914710] Starting subtest: invalid
[656.936113] Starting dynamic subtest: vecs0
[656.956382] Dynamic subtest vecs0: SUCCESS (0.001s)
[656.997434] Starting dynamic subtest: vcs0
[657.016145] Dynamic subtest vcs0: SUCCESS (0.001s)
[657.057300] Starting dynamic subtest: bcs0
[657.077383] Dynamic subtest bcs0: SUCCESS (0.001s)
[657.121466] Starting dynamic subtest: rcs0
[657.142435] Dynamic subtest rcs0: SUCCESS (0.001s)
[657.163007] Subtest invalid: SUCCESS (0.015s)
[657.275322] [126/139] (444s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-blt)
[657.652783] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-blt
[659.893157] Subtest fbc-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (2.320s)
[660.079608] [127/139] (441s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions)
[660.399932] Starting subtest: cursorB-vs-flipA-atomic-transitions
[660.447815] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.004s)
[660.570694] [128/139] (441s left) gem_exec_reloc (basic-gtt-wc)
[660.806468] Starting subtest: basic-gtt-wc
[660.828630] Subtest basic-gtt-wc: SUCCESS (0.020s)
[661.029531] [129/139] (440s left) kms_addfb_basic (basic)
[661.264946] Starting subtest: basic
[661.284627] Subtest basic: SUCCESS (0.000s)
[661.478285] [130/139] (440s left) v3d/v3d_wait_bo (map-bo-0ns)
[661.753609] Subtest map-bo-0ns: SKIP (0.000s)
[661.847237] [131/139] (440s left) syncobj_timeline (multi-wait-all-available-submitted)
[662.057143] Starting subtest: multi-wait-all-available-submitted
[662.075804] Subtest multi-wait-all-available-submitted: SUCCESS (0.002s)
[662.173515] [132/139] (439s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-fullscreen)
[662.528052] Starting subtest: psr-2p-primscrn-spr-indfb-fullscreen
[662.565892] Subtest psr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[662.666586] [133/139] (439s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions-varying-size)
[662.964889] Starting subtest: cursorB-vs-flipA-atomic-transitions-varying-size
[663.003847] Subtest cursorB-vs-flipA-atomic-transitions-varying-size: SKIP (0.004s)
[663.105801] [134/139] (439s left) kms_getfb (getfb2-handle-closed)
[663.338104] Starting subtest: getfb2-handle-closed
[663.356967] Subtest getfb2-handle-closed: SUCCESS (0.000s)
[663.456781] [135/139] (438s left) gem_exec_reloc (basic-wc-gtt-noreloc)
[663.681252] Starting subtest: basic-wc-gtt-noreloc
[663.702799] Subtest basic-wc-gtt-noreloc: SUCCESS (0.009s)
[663.877202] [136/139] (438s left) syncobj_wait (invalid-multi-wait-all-unsubmitted)
[664.087662] Starting subtest: invalid-multi-wait-all-unsubmitted
[664.106549] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[664.201272] [137/139] (438s left) kms_sequence (get-forked-busy)
[664.501104] Starting subtest: get-forked-busy
[664.522503] Starting dynamic subtest: pipe-A-DP-1
[666.996948] Dynamic subtest pipe-A-DP-1: SUCCESS (2.489s)
[667.041111] Starting dynamic subtest: pipe-B-DP-1
[669.684842] Dynamic subtest pipe-B-DP-1: SUCCESS (2.668s)
[669.734074] Starting dynamic subtest: pipe-C-DP-1
[672.320810] Dynamic subtest pipe-C-DP-1: SUCCESS (2.643s)
[672.342600] Subtest get-forked-busy: SUCCESS (7.813s)
[672.665195] [138/139] (430s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-blt)
[673.033482] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-blt
[675.144494] Subtest fbc-1p-primscrn-spr-indfb-draw-blt: SUCCESS (2.178s)
[675.312476] [139/139] (427s left) kms_cursor_crc (cursor-rapid-movement-32x32)
[675.636692] Starting subtest: cursor-rapid-movement-32x32
[675.784208] Subtest cursor-rapid-movement-32x32: SKIP (0.118s)
[675.864122] Closing watchdogs
Done.