[51.408603] Initializing watchdogs
[51.408856]   /dev/watchdog0
[51.425340] [001/137] (960s left) kms_content_protection (atomic)
[51.779720] Starting subtest: atomic
[51.840536] Starting dynamic subtest: pipe-A-DP-1
[172.100050] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[172.780982] Closing watchdogs
[172.790857] Initializing watchdogs
[172.791116]   /dev/watchdog0
[172.809484] [002/137] (839s left) perf_pmu (busy-idle)
[173.012990] Starting subtest: busy-idle
[173.014710] Starting dynamic subtest: rcs0
[173.654751] Dynamic subtest rcs0: SUCCESS (0.642s)
[173.673713] Starting dynamic subtest: bcs0
[174.289730] Dynamic subtest bcs0: SUCCESS (0.635s)
[174.304236] Starting dynamic subtest: vcs0
[174.915908] Dynamic subtest vcs0: SUCCESS (0.626s)
[174.930376] Starting dynamic subtest: vecs0
[175.542689] Dynamic subtest vecs0: SUCCESS (0.627s)
[175.544250] Subtest busy-idle: SUCCESS (2.530s)
[175.622079] [003/137] (836s left) gem_exec_await (wide-contexts)
[176.083946] Starting subtest: wide-contexts
[198.878438] Subtest wide-contexts: SUCCESS (22.795s)
[199.101852] [004/137] (812s left) kms_plane_alpha_blend (alpha-basic)
[199.506508] Starting subtest: alpha-basic
[199.840565] Starting dynamic subtest: pipe-A-DP-1
[200.514391] Dynamic subtest pipe-A-DP-1: FAIL (0.302s)
[200.530379] Starting dynamic subtest: pipe-C-DP-1
[201.229680] Dynamic subtest pipe-C-DP-1: FAIL (0.674s)
[201.230950] Subtest alpha-basic: FAIL (1.391s)
[201.310236] [005/137] (810s left) kms_cursor_crc (cursor-onscreen-128x42)
[201.594804] Starting subtest: cursor-onscreen-128x42
[201.606089] Starting dynamic subtest: pipe-A-DP-1
[204.137918] Dynamic subtest pipe-A-DP-1: SUCCESS (2.533s)
[204.214981] Starting dynamic subtest: pipe-B-DP-1
[206.621778] Dynamic subtest pipe-B-DP-1: SUCCESS (2.421s)
[206.692081] Starting dynamic subtest: pipe-C-DP-1
[209.101031] Dynamic subtest pipe-C-DP-1: SUCCESS (2.421s)
[209.102315] Subtest cursor-onscreen-128x42: SUCCESS (7.502s)
[209.445226] [006/137] (802s left) syncobj_wait (invalid-signal-illegal-handle)
[209.632654] Starting subtest: invalid-signal-illegal-handle
[209.634116] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[209.707528] [007/137] (802s left) gem_mmap_wc (write-cpu-read-wc-unflushed)
[209.878143] Starting subtest: write-cpu-read-wc-unflushed
[210.134979] Subtest write-cpu-read-wc-unflushed: SUCCESS (0.255s)
[210.209096] [008/137] (801s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-pwrite)
[210.489372] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-pwrite
[210.491900] Subtest psr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[210.590432] [009/137] (801s left) dumb_buffer (create-valid-dumb)
[210.762455] Starting subtest: create-valid-dumb
[210.763955] Subtest create-valid-dumb: SUCCESS (0.000s)
[210.857027] [010/137] (801s left) device_reset (cold-reset-bound)
[211.074727] Subtest cold-reset-bound: SKIP (0.000s)
[211.156854] [011/137] (801s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[211.433663] Starting subtest: cursorB-vs-flipA-legacy
[211.436379] Subtest cursorB-vs-flipA-legacy: SKIP (0.004s)
[211.526548] [012/137] (800s left) gem_create (busy-create)
[211.709811] Starting subtest: busy-create
[211.711977] Starting dynamic subtest: smem0
[243.973890] Dynamic subtest smem0: SUCCESS (32.265s)
[243.975870] Subtest busy-create: SUCCESS (32.265s)
[244.174089] [013/137] (768s left) syncobj_wait (wait-delayed-signal)
[244.346750] Starting subtest: wait-delayed-signal
[244.454577] Subtest wait-delayed-signal: SUCCESS (0.106s)
[244.541666] [014/137] (767s left) gem_mmap_wc (coherency)
[244.715131] Starting subtest: coherency
[244.862510] Subtest coherency: SUCCESS (0.147s)
[244.956112] [015/137] (767s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[245.236257] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[247.775895] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (2.538s)
[247.889981] [016/137] (764s left) kms_vblank (pipe-b-ts-continuation-dpms-suspend)
[248.158054] Starting subtest: pipe-B-ts-continuation-dpms-suspend
[265.436554] Subtest pipe-B-ts-continuation-dpms-suspend: SUCCESS (2.071s)
[269.939682] [017/137] (761s left) gem_tiled_blits (interruptible)
[270.487733] Starting subtest: interruptible
[322.788445] Subtest interruptible: SUCCESS (52.300s)
[322.900480] [018/137] (708s left) kms_frontbuffer_tracking (fbc-1p-shrfb-fliptrack-mmap-gtt)
[323.298045] Starting subtest: fbc-1p-shrfb-fliptrack-mmap-gtt
[326.332169] Subtest fbc-1p-shrfb-fliptrack-mmap-gtt: SUCCESS (3.032s)
[326.498968] [019/137] (705s left) kms_ccs (pipe-d-crc-primary-rotation-180-yf_tiled_ccs)
[326.964740] Subtest pipe-D-crc-primary-rotation-180-yf_tiled_ccs: SKIP (0.000s)
[327.273255] [020/137] (704s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-pgflip-blt)
[327.551608] Starting subtest: psr-1p-primscrn-shrfb-pgflip-blt
[327.554183] Subtest psr-1p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[327.642311] [021/137] (704s left) kms_ccs (pipe-d-bad-pixel-format-4_tiled_dg2_mc_ccs)
[328.136405] Subtest pipe-D-bad-pixel-format-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[328.450333] [022/137] (703s left) kms_getfb (getfb-handle-zero)
[328.622752] Starting subtest: getfb-handle-zero
[328.624357] Subtest getfb-handle-zero: SUCCESS (0.000s)
[328.721389] [023/137] (703s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180)
[329.013042] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180
[330.563482] Subtest x-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (1.548s)
[330.719311] [024/137] (701s left) vc4/vc4_purgeable_bo (free-purged-bo)
[330.963149] Subtest free-purged-bo: SKIP (0.000s)
[331.033234] [025/137] (700s left) gem_exec_create (madvise)
[331.225919] Starting subtest: madvise
[331.227374] Starting dynamic subtest: smem
[351.377802] Dynamic subtest smem: SUCCESS (20.151s)
[351.379606] Subtest madvise: SUCCESS (20.151s)
[352.933095] [026/137] (679s left) gem_exec_fence (submit-chain)
[353.254689] Starting subtest: submit-chain
[353.281409] Subtest submit-chain: SUCCESS (0.027s)
[353.917552] [027/137] (678s left) gem_mmap_gtt (close-race)
[354.089092] Starting subtest: close-race
[374.169938] Subtest close-race: SUCCESS (20.080s)
[374.266408] [028/137] (657s left) kms_invalid_mode (zero-clock)
[374.534944] Starting subtest: zero-clock
[374.537129] Starting dynamic subtest: DP-1-pipe-A
[374.627926] Dynamic subtest DP-1-pipe-A: SUCCESS (0.096s)
[374.651373] Starting dynamic subtest: DP-1-pipe-B
[374.652775] Dynamic subtest DP-1-pipe-B: SUCCESS (0.002s)
[374.674339] Starting dynamic subtest: DP-1-pipe-C
[374.676003] Dynamic subtest DP-1-pipe-C: SUCCESS (0.002s)
[374.677620] Subtest zero-clock: SUCCESS (0.106s)
[374.958920] [029/137] (657s left) gem_softpin (safe-alignment)
[375.141045] Starting subtest: safe-alignment
[375.172080] Subtest safe-alignment: SUCCESS (0.031s)
[375.290941] [030/137] (656s left) vc4/vc4_tiling (set-get)
[375.519811] Subtest set-get: SKIP (0.000s)
[375.590933] [031/137] (656s left) kms_pipe_b_c_ivb (disable-pipe-b-enable-pipe-c)
[375.760708] Subtest disable-pipe-B-enable-pipe-C: SKIP (0.000s)
[375.810892] [032/137] (656s left) kms_vblank (pipe-c-wait-forked-hang)
[376.077950] Starting subtest: pipe-C-wait-forked-hang
[383.727524] Subtest pipe-C-wait-forked-hang: SUCCESS (7.652s)
[384.061235] [033/137] (648s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[384.343901] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[384.346493] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[384.443407] [034/137] (647s left) kms_ccs (pipe-b-crc-primary-basic-y_tiled_gen12_rc_ccs_cc)
[384.857335] Starting subtest: pipe-B-crc-primary-basic-y_tiled_gen12_rc_ccs_cc
[384.860066] Subtest pipe-B-crc-primary-basic-y_tiled_gen12_rc_ccs_cc: SKIP (0.001s)
[385.247167] [035/137] (647s left) i915_module_load (reload-no-display)
[385.412523] Starting subtest: reload-no-display
[389.145834] Subtest reload-no-display: SUCCESS (3.732s)
[389.175955] [036/137] (643s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[392.461753] Starting subtest: addfb25-framebuffer-vs-set-tiling
[392.463177] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[392.791068] [037/137] (639s left) gem_userptr_blits (create-destroy-unsync)
[393.004870] Subtest create-destroy-unsync: SKIP (0.000s)
[393.152195] [038/137] (639s left) kms_atomic (plane-primary-overlay-mutable-zpos)
[393.550027] Starting subtest: plane-primary-overlay-mutable-zpos
[393.555117] Subtest plane-primary-overlay-mutable-zpos: SKIP (0.000s)
[393.976069] [039/137] (638s left) gem_madvise (dontneed-before-exec)
[394.144791] Starting subtest: dontneed-before-exec
[394.158483] Subtest dontneed-before-exec: SUCCESS (0.011s)
[394.240670] [040/137] (638s left) kms_flip (plain-flip-interruptible)
[394.506997] Starting subtest: plain-flip-interruptible
[394.511739] Starting dynamic subtest: A-DP1
[395.662261] Dynamic subtest A-DP1: SUCCESS (1.151s)
[395.684206] Starting dynamic subtest: B-DP1
[396.763361] Dynamic subtest B-DP1: SUCCESS (1.100s)
[396.788643] Starting dynamic subtest: C-DP1
[397.882411] Dynamic subtest C-DP1: SUCCESS (1.117s)
[397.884067] Subtest plain-flip-interruptible: SUCCESS (3.377s)
[398.286413] [041/137] (634s left) kms_chamelium_edid (hdmi-edid-stress-resolution-non-4k)
[398.644779] Subtest hdmi-edid-stress-resolution-non-4k: SKIP (0.000s)
[398.928073] [042/137] (633s left) perf (polling-small-buf)
[399.204087] Starting subtest: polling-small-buf
[399.348635] Subtest polling-small-buf: SUCCESS (0.144s)
[399.403009] [043/137] (633s left) i915_pm_rps (engine-order)
[399.582395] Starting subtest: engine-order
[414.402135] Subtest engine-order: FAIL (14.797s)
[415.005440] [044/137] (617s left) gem_exec_reloc (basic-wc-noreloc)
[415.196189] Starting subtest: basic-wc-noreloc
[415.208482] Subtest basic-wc-noreloc: SUCCESS (0.011s)
[415.388750] [045/137] (617s left) gem_exec_parallel (userptr)
[415.578242] Starting subtest: userptr
[415.579934] Starting dynamic subtest: rcs0
[418.942458] Dynamic subtest rcs0: SUCCESS (3.363s)
[418.963135] Starting dynamic subtest: bcs0
[422.463383] Dynamic subtest bcs0: SUCCESS (3.519s)
[422.485594] Starting dynamic subtest: vcs0
[425.858382] Dynamic subtest vcs0: SUCCESS (3.394s)
[425.879760] Starting dynamic subtest: vecs0
[429.248398] Dynamic subtest vecs0: SUCCESS (3.388s)
[429.250113] Subtest userptr: SUCCESS (13.669s)
[429.455410] [046/137] (603s left) gem_flink_basic (bad-open)
[429.625099] Starting subtest: bad-open
[429.626625] Subtest bad-open: SUCCESS (0.000s)
[429.703419] [047/137] (603s left) v3d/v3d_perfmon (create-perfmon-invalid-counters)
[429.934652] Subtest create-perfmon-invalid-counters: SKIP (0.000s)
[430.004254] [048/137] (602s left) gen7_exec_parse (chained-batch)
[430.182855] Subtest chained-batch: SKIP (0.000s)
[430.241791] [049/137] (602s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-mmap-wc)
[430.518419] Starting subtest: fbc-1p-offscren-pri-indfb-draw-mmap-wc
[432.334244] Subtest fbc-1p-offscren-pri-indfb-draw-mmap-wc: SUCCESS (1.818s)
[432.474043] [050/137] (600s left) syncobj_timeline (wait-all-for-submit-complex)
[432.648528] Starting subtest: wait-all-for-submit-complex
[432.867520] Subtest wait-all-for-submit-complex: SUCCESS (0.216s)
[432.953832] [051/137] (599s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[433.232759] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[433.235436] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[433.340523] [052/137] (599s left) kms_psr (psr2_primary_blt)
[433.512609] Subtest psr2_primary_blt: SKIP (0.000s)
[433.573798] [053/137] (599s left) kms_ccs (pipe-a-random-ccs-data-yf_tiled_ccs)
[433.949666] Starting subtest: pipe-A-random-ccs-data-yf_tiled_ccs
[434.390884] Subtest pipe-A-random-ccs-data-yf_tiled_ccs: SUCCESS (0.440s)
[434.948065] [054/137] (598s left) kms_ccs (pipe-c-crc-primary-basic-y_tiled_gen12_rc_ccs)
[435.408395] Starting subtest: pipe-C-crc-primary-basic-y_tiled_gen12_rc_ccs
[435.411137] Subtest pipe-C-crc-primary-basic-y_tiled_gen12_rc_ccs: SKIP (0.002s)
[435.756723] [055/137] (597s left) kms_psr (psr2_cursor_render)
[435.928648] Subtest psr2_cursor_render: SKIP (0.000s)
[435.975727] [056/137] (597s left) perf (non-sampling-read-error)
[436.222228] Starting subtest: non-sampling-read-error
[436.266122] Subtest non-sampling-read-error: SUCCESS (0.045s)
[436.321019] [057/137] (596s left) syncobj_wait (invalid-multi-wait-all-unsubmitted)
[436.491810] Starting subtest: invalid-multi-wait-all-unsubmitted
[436.493500] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[436.573169] [058/137] (596s left) syncobj_wait (single-wait-signaled)
[436.743030] Starting subtest: single-wait-signaled
[436.744329] Subtest single-wait-signaled: SUCCESS (0.001s)
[436.839514] [059/137] (596s left) perf (rc6-disable)
[437.087754] Starting subtest: rc6-disable
[437.324167] Subtest rc6-disable: SUCCESS (0.238s)
[437.370755] [060/137] (595s left) perf (invalid-oa-metric-set-id)
[437.623281] Starting subtest: invalid-oa-metric-set-id
[437.667440] Subtest invalid-oa-metric-set-id: SUCCESS (0.044s)
[437.721077] [061/137] (595s left) gem_exec_flush (basic-uc-ro-default)
[437.912992] Starting subtest: basic-uc-ro-default
[443.367631] Subtest basic-uc-ro-default: SUCCESS (5.455s)
[443.572252] [062/137] (589s left) kms_chamelium_hpd (hdmi-hpd-after-suspend)
[443.932449] Subtest hdmi-hpd-after-suspend: SKIP (0.000s)
[444.221724] [063/137] (589s left) gem_lmem_evict (dontneed-evict-race)
[444.398684] Subtest dontneed-evict-race: SKIP (0.000s)
[444.450175] [064/137] (588s left) kms_writeback (writeback-invalid-parameters)
[444.805464] Subtest writeback-invalid-parameters: SKIP (0.000s)
[444.849654] [065/137] (588s left) gem_exec_whisper (basic-contexts-priority)
[445.039357] Starting subtest: basic-contexts-priority
[460.503293] Subtest basic-contexts-priority: SUCCESS (15.461s)
[460.700287] [066/137] (572s left) kms_plane_scaling (planes-upscale-factor-0-25)
[460.968134] Starting subtest: planes-upscale-factor-0-25
[460.969543] Starting dynamic subtest: pipe-A-DP-1
[461.106916] Dynamic subtest pipe-A-DP-1: SUCCESS (0.140s)
[461.131368] Starting dynamic subtest: pipe-B-DP-1
[461.454887] Dynamic subtest pipe-B-DP-1: SUCCESS (0.345s)
[461.481271] Starting dynamic subtest: pipe-C-DP-1
[461.556358] Dynamic subtest pipe-C-DP-1: SKIP (0.095s)
[461.557758] Subtest planes-upscale-factor-0-25: SUCCESS (0.589s)
[461.952010] [067/137] (571s left) gem_close (basic)
[462.130980] Starting subtest: basic
[462.132548] Subtest basic: SUCCESS (0.001s)
[462.234843] [068/137] (571s left) kms_cursor_crc (cursor-sliding-64x64)
[462.505392] Starting subtest: cursor-sliding-64x64
[462.515998] Starting dynamic subtest: pipe-A-DP-1
[466.192040] Dynamic subtest pipe-A-DP-1: SUCCESS (3.674s)
[466.282066] Starting dynamic subtest: pipe-B-DP-1
[470.010355] Dynamic subtest pipe-B-DP-1: SUCCESS (3.755s)
[470.087717] Starting dynamic subtest: pipe-C-DP-1
[473.836060] Dynamic subtest pipe-C-DP-1: SUCCESS (3.769s)
[473.837346] Subtest cursor-sliding-64x64: SUCCESS (11.330s)
[474.206760] [069/137] (559s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[474.493294] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[474.495715] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[474.584712] [070/137] (559s left) gem_exec_balancer (parallel-bb-first)
[478.671330] Subtest parallel-bb-first: SKIP (0.000s)
[478.767442] [071/137] (554s left) gem_ctx_persistence (engines-persistence)
[478.952266] Starting subtest: engines-persistence
[478.953918] Starting dynamic subtest: rcs0
[479.720304] Dynamic subtest rcs0: SUCCESS (0.766s)
[479.742730] Starting dynamic subtest: bcs0
[480.486892] Dynamic subtest bcs0: SUCCESS (0.765s)
[480.509438] Starting dynamic subtest: vcs0
[481.257534] Dynamic subtest vcs0: SUCCESS (0.769s)
[481.281840] Starting dynamic subtest: vecs0
[482.022219] Dynamic subtest vecs0: SUCCESS (0.763s)
[482.023541] Subtest engines-persistence: SUCCESS (3.070s)
[482.116505] [072/137] (551s left) kms_chamelium_color (ctm-max)
[482.386681] Subtest ctm-max: SKIP (0.000s)
[482.436243] [073/137] (551s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[482.710405] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu
[482.713030] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[482.817947] [074/137] (551s left) v3d/v3d_submit_cl (multisync-out-syncs)
[483.045149] Subtest multisync-out-syncs: SKIP (0.000s)
[483.117727] [075/137] (550s left) i915_pm_rpm (reg-read-ioctl)
[484.885487] Starting subtest: reg-read-ioctl
[485.613217] Subtest reg-read-ioctl: SUCCESS (0.728s)
[486.093444] [076/137] (547s left) gem_exec_schedule (submit-late-slice)
[486.299172] Starting subtest: submit-late-slice
[486.303754] Starting dynamic subtest: rcs0
[486.363039] Dynamic subtest rcs0: SUCCESS (0.060s)
[486.388302] Starting dynamic subtest: bcs0
[486.414951] Dynamic subtest bcs0: SUCCESS (0.051s)
[486.440705] Starting dynamic subtest: vcs0
[486.464049] Dynamic subtest vcs0: SUCCESS (0.047s)
[486.489787] Starting dynamic subtest: vecs0
[486.512883] Dynamic subtest vecs0: SUCCESS (0.048s)
[486.514214] Subtest submit-late-slice: SUCCESS (0.213s)
[486.859968] [077/137] (547s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[487.150219] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[488.701590] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.550s)
[488.843431] [078/137] (545s left) kms_frontbuffer_tracking (fbc-modesetfrombusy)
[489.124027] Starting subtest: fbc-modesetfrombusy
[490.311321] Subtest fbc-modesetfrombusy: SUCCESS (1.189s)
[490.439110] [079/137] (543s left) kms_universal_plane (universal-plane-pageflip-windowed-pipe-a)
[490.706965] Starting subtest: universal-plane-pageflip-windowed-pipe-A
[491.107481] Subtest universal-plane-pageflip-windowed-pipe-A: SUCCESS (0.404s)
[491.420675] [080/137] (542s left) kms_plane_scaling (planes-downscale-factor-0-25)
[491.698084] Starting subtest: planes-downscale-factor-0-25
[491.702814] Starting dynamic subtest: pipe-A-DP-1
[491.872260] Dynamic subtest pipe-A-DP-1: SKIP (0.169s)
[491.895162] Starting dynamic subtest: pipe-B-DP-1
[492.027613] Dynamic subtest pipe-B-DP-1: SKIP (0.148s)
[492.053760] Starting dynamic subtest: pipe-C-DP-1
[492.175973] Dynamic subtest pipe-C-DP-1: SKIP (0.149s)
[492.178472] Subtest planes-downscale-factor-0-25: SKIP (0.476s)
[492.290297] [081/137] (541s left) api_intel_bb (full-batch)
[492.466528] Starting subtest: full-batch
[492.473091] Subtest full-batch: SUCCESS (0.009s)
[492.587347] [082/137] (541s left) kms_lease (simple_lease)
[492.865891] Starting subtest: simple_lease
[492.867846] Starting dynamic subtest: pipe-A-DP-1
[493.379160] Dynamic subtest pipe-A-DP-1: SUCCESS (0.512s)
[493.409522] Starting dynamic subtest: pipe-B-DP-1
[493.967410] Dynamic subtest pipe-B-DP-1: SUCCESS (0.583s)
[493.998914] Starting dynamic subtest: pipe-C-DP-1
[494.547212] Dynamic subtest pipe-C-DP-1: SUCCESS (0.576s)
[494.549262] Subtest simple_lease: SUCCESS (1.679s)
[494.854436] [083/137] (539s left) drm_fdinfo (busy-hang)
[495.034448] Starting subtest: busy-hang
[495.035966] Starting dynamic subtest: rcs0
[496.066186] Dynamic subtest rcs0: SUCCESS (1.030s)
[496.091807] Starting dynamic subtest: bcs0
[497.108402] Dynamic subtest bcs0: SUCCESS (1.042s)
[497.134135] Starting dynamic subtest: vcs0
[498.141723] Dynamic subtest vcs0: SUCCESS (1.032s)
[498.165913] Starting dynamic subtest: vecs0
[499.178476] Dynamic subtest vecs0: SUCCESS (1.036s)
[499.179871] Subtest busy-hang: SUCCESS (4.144s)
[499.269020] [084/137] (534s left) kms_plane_scaling (plane-downscale-with-modifiers-factor-0-75)
[499.531949] Starting subtest: plane-downscale-with-modifiers-factor-0-75
[499.533327] Starting dynamic subtest: pipe-A-DP-1
[500.476393] Dynamic subtest pipe-A-DP-1: SUCCESS (0.944s)
[500.502313] Starting dynamic subtest: pipe-B-DP-1
[501.687793] Dynamic subtest pipe-B-DP-1: SUCCESS (1.208s)
[501.713224] Starting dynamic subtest: pipe-C-DP-1
[502.586952] Dynamic subtest pipe-C-DP-1: SUCCESS (0.896s)
[502.588235] Subtest plane-downscale-with-modifiers-factor-0-75: SUCCESS (3.059s)
[502.999747] [085/137] (531s left) kms_ccs (pipe-b-bad-pixel-format-y_tiled_gen12_mc_ccs)
[503.400116] Starting subtest: pipe-B-bad-pixel-format-y_tiled_gen12_mc_ccs
[503.402721] Subtest pipe-B-bad-pixel-format-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[503.813114] [086/137] (530s left) gem_mmap_wc (read)
[503.988600] Starting subtest: read
[504.121245] Subtest read: SUCCESS (0.133s)
[504.212214] [087/137] (530s left) kms_ccs (pipe-a-crc-primary-basic-y_tiled_ccs)
[504.576736] Starting subtest: pipe-A-crc-primary-basic-y_tiled_ccs
[505.228086] Subtest pipe-A-crc-primary-basic-y_tiled_ccs: SUCCESS (0.649s)
[505.746276] [088/137] (528s left) kms_addfb_basic (bad-pitch-0)
[505.921077] Starting subtest: bad-pitch-0
[505.922589] Subtest bad-pitch-0: SUCCESS (0.000s)
[506.311475] [089/137] (528s left) i915_hangman (detector)
[506.509999] Starting subtest: detector
[506.511501] Starting dynamic subtest: rcs0
[516.162349] Dynamic subtest rcs0: SUCCESS (9.652s)
[516.186280] Starting dynamic subtest: bcs0
[526.027184] Dynamic subtest bcs0: SUCCESS (9.864s)
[526.050882] Starting dynamic subtest: vcs0
[536.020288] Dynamic subtest vcs0: SUCCESS (9.992s)
[536.046705] Starting dynamic subtest: vecs0
[546.090334] Dynamic subtest vecs0: SUCCESS (10.069s)
[546.091890] Subtest detector: SUCCESS (39.581s)
[546.211939] [090/137] (488s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-pwrite)
[546.493809] Starting subtest: psr-1p-offscren-pri-indfb-draw-pwrite
[546.496784] Subtest psr-1p-offscren-pri-indfb-draw-pwrite: SKIP (0.000s)
[546.594949] [091/137] (487s left) i915_pm_rpm (debugfs-forcewake-user)
[548.344278] Starting subtest: debugfs-forcewake-user
[559.005229] Subtest debugfs-forcewake-user: SUCCESS (10.661s)
[559.476408] [092/137] (475s left) gem_exec_reloc (basic-cpu-read)
[559.657011] Starting subtest: basic-cpu-read
[559.678497] Subtest basic-cpu-read: SUCCESS (0.021s)
[559.875984] [093/137] (474s left) gem_pxp (fail-invalid-protected-context)
[562.568293] Subtest fail-invalid-protected-context: SKIP (0.000s)
[562.655682] [094/137] (471s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[562.917224] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[562.918499] Starting dynamic subtest: pipe-A-valid-mode
[563.589797] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.671s)
[563.591013] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SUCCESS (0.674s)
[563.921028] [095/137] (470s left) syncobj_timeline (invalid-single-wait-all-unsubmitted)
[564.099009] Starting subtest: invalid-single-wait-all-unsubmitted
[564.100784] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[564.187150] [096/137] (470s left) gem_mmap_offset (perf)
[564.354322] Starting subtest: perf
[564.356081] Starting dynamic subtest: smem0
[564.651185] Dynamic subtest smem0: SUCCESS (0.295s)
[564.652511] Subtest perf: SUCCESS (0.297s)
[564.754001] [097/137] (470s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-mmap-wc)
[565.029777] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-mmap-wc
[565.032397] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[565.137709] [098/137] (469s left) gem_exec_fair (basic-throttle)
[565.340691] Starting subtest: basic-throttle
[565.344825] Starting dynamic subtest: rcs0
[570.395997] Dynamic subtest rcs0: SUCCESS (5.052s)
[570.397330] Subtest basic-throttle: SUCCESS (5.054s)
[570.603013] [099/137] (464s left) kms_ccs (pipe-a-bad-aux-stride-y_tiled_gen12_mc_ccs)
[570.992068] Starting subtest: pipe-A-bad-aux-stride-y_tiled_gen12_mc_ccs
[570.994641] Subtest pipe-A-bad-aux-stride-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[571.397891] [100/137] (463s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-gtt)
[571.672838] Starting subtest: fbcpsr-rgb101010-draw-mmap-gtt
[571.675409] Subtest fbcpsr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[571.781204] [101/137] (463s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[572.067430] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[572.070195] Subtest psr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[572.164970] [102/137] (462s left) kms_flip (flip-vs-panning)
[572.416897] Starting subtest: flip-vs-panning
[572.418217] Starting dynamic subtest: A-DP1
[582.998963] Dynamic subtest A-DP1: SUCCESS (10.581s)
[583.022991] Starting dynamic subtest: B-DP1
[593.518114] Dynamic subtest B-DP1: SUCCESS (10.517s)
[593.541988] Starting dynamic subtest: C-DP1
[604.036187] Dynamic subtest C-DP1: SUCCESS (10.516s)
[604.037867] Subtest flip-vs-panning: SUCCESS (31.624s)
[604.441114] [103/137] (430s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[604.741927] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[604.744805] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[604.858815] [104/137] (430s left) kms_async_flips (crc)
[605.133284] Starting subtest: crc
[605.134589] Starting dynamic subtest: pipe-A-DP-1
[607.724139] Dynamic subtest pipe-A-DP-1: SUCCESS (2.589s)
[607.746729] Starting dynamic subtest: pipe-B-DP-1
[610.241381] Dynamic subtest pipe-B-DP-1: SUCCESS (2.513s)
[610.268699] Starting dynamic subtest: pipe-C-DP-1
[612.757718] Dynamic subtest pipe-C-DP-1: SUCCESS (2.511s)
[612.759236] Subtest crc: SUCCESS (7.625s)
[613.236620] [105/137] (421s left) kms_dp_tiled_display (basic-test-pattern)
[613.512387] Starting subtest: basic-test-pattern
[613.514988] Subtest basic-test-pattern: SKIP (0.000s)
[613.603715] [106/137] (421s left) kms_atomic_interruptible (legacy-pageflip)
[613.877807] Starting subtest: legacy-pageflip
[613.882068] Starting dynamic subtest: DP-1-pipe-A
[620.405125] Dynamic subtest DP-1-pipe-A: SUCCESS (6.523s)
[620.406464] Subtest legacy-pageflip: SUCCESS (6.526s)
[620.747555] [107/137] (414s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-wc)
[621.039175] Starting subtest: psr-rgb101010-draw-mmap-wc
[621.042147] Subtest psr-rgb101010-draw-mmap-wc: SKIP (0.000s)
[621.146959] [108/137] (414s left) gem_partial_pwrite_pread (reads-uncached)
[621.327871] Starting subtest: reads-uncached
[627.854898] Subtest reads-uncached: SUCCESS (6.527s)
[627.977139] [109/137] (407s left) vgem_basic (setversion)
[628.171064] Starting subtest: setversion
[628.173612] Subtest setversion: SUCCESS (0.000s)
[628.243095] [110/137] (407s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[628.517555] Starting subtest: basic-flip-after-cursor-atomic
[629.584259] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.070s)
[629.922547] [111/137] (405s left) gem_mmap_wc (write-read-distinct)
[630.096695] Starting subtest: write-read-distinct
[630.130868] Subtest write-read-distinct: SUCCESS (0.032s)
[630.223368] [112/137] (405s left) gem_ctx_persistence (legacy-engines-queued)
[630.405045] Starting subtest: legacy-engines-queued
[630.409722] Starting dynamic subtest: render
[630.463186] Dynamic subtest render: SUCCESS (0.054s)
[630.491024] Starting dynamic subtest: bsd
[630.520256] Dynamic subtest bsd: SUCCESS (0.056s)
[630.548844] Starting dynamic subtest: blt
[630.665458] Dynamic subtest blt: SUCCESS (0.143s)
[630.691140] Starting dynamic subtest: vebox
[630.730641] Dynamic subtest vebox: SUCCESS (0.062s)
[630.732073] Subtest legacy-engines-queued: SUCCESS (0.324s)
[630.819699] [113/137] (404s left) gem_exec_balancer (hang)
[634.911246] Starting subtest: hang
[638.565158] Subtest hang: SUCCESS (3.655s)
[638.670275] [114/137] (396s left) kms_big_fb (yf-tiled-64bpp-rotate-270)
[638.962529] Starting subtest: yf-tiled-64bpp-rotate-270
[638.965135] Subtest yf-tiled-64bpp-rotate-270: SKIP (0.000s)
[639.071192] [115/137] (396s left) kms_big_fb (y-tiled-addfb-size-overflow)
[639.368723] Starting subtest: y-tiled-addfb-size-overflow
[639.370265] Subtest y-tiled-addfb-size-overflow: SUCCESS (0.002s)
[639.471398] [116/137] (396s left) kms_addfb_basic (clobberred-modifier)
[639.641971] Starting subtest: clobberred-modifier
[639.643377] Subtest clobberred-modifier: SUCCESS (0.000s)
[640.038321] [117/137] (395s left) kms_fbcon_fbt (psr)
[640.217157] Starting subtest: psr
[640.220185] Subtest psr: SKIP (0.001s)
[640.316843] [118/137] (395s left) v3d/v3d_submit_csd (single-out-sync)
[640.546414] Subtest single-out-sync: SKIP (0.000s)
[640.621537] [119/137] (395s left) kms_ccs (pipe-c-crc-sprite-planes-basic-y_tiled_ccs)
[641.109185] Starting subtest: pipe-C-crc-sprite-planes-basic-y_tiled_ccs
[641.114372] Subtest pipe-C-crc-sprite-planes-basic-y_tiled_ccs: SKIP (0.004s)
[641.438080] [120/137] (394s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[641.612125] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[641.613899] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[641.704765] [121/137] (394s left) syncobj_timeline (multi-wait-all-available-submitted)
[641.877931] Starting subtest: multi-wait-all-available-submitted
[641.882485] Subtest multi-wait-all-available-submitted: SUCCESS (0.002s)
[641.973529] [122/137] (393s left) api_intel_allocator (execbuf-with-allocator)
[642.143882] Starting subtest: execbuf-with-allocator
[642.155249] Subtest execbuf-with-allocator: SUCCESS (0.012s)
[642.271047] [123/137] (393s left) gem_pxp (verify-pxp-stale-buf-execution)
[644.965051] Subtest verify-pxp-stale-buf-execution: SKIP (0.000s)
[645.053159] [124/137] (390s left) kms_ccs (pipe-c-bad-pixel-format-y_tiled_gen12_rc_ccs_cc)
[645.500660] Starting subtest: pipe-C-bad-pixel-format-y_tiled_gen12_rc_ccs_cc
[645.503404] Subtest pipe-C-bad-pixel-format-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[645.861961] [125/137] (390s left) kms_flip (2x-nonexisting-fb-interruptible)
[646.120672] Starting subtest: 2x-nonexisting-fb-interruptible
[646.126005] Subtest 2x-nonexisting-fb-interruptible: SKIP (0.003s)
[646.230967] [126/137] (389s left) gem_render_copy (y-tiled-ccs-to-y-tiled-ccs)
[646.416480] Starting subtest: y-tiled-ccs-to-y-tiled-ccs
[646.418037] Starting dynamic subtest: smem
[646.728957] Dynamic subtest smem: SUCCESS (0.310s)
[646.730296] Subtest y-tiled-ccs-to-y-tiled-ccs: SUCCESS (0.312s)
[646.930259] [127/137] (389s left) kms_flip (basic-flip-vs-modeset)
[647.188291] Starting subtest: basic-flip-vs-modeset
[647.189846] Starting dynamic subtest: A-DP1
[648.459529] Dynamic subtest A-DP1: SUCCESS (1.270s)
[648.483509] Starting dynamic subtest: B-DP1
[649.581329] Dynamic subtest B-DP1: SUCCESS (1.119s)
[649.607896] Starting dynamic subtest: C-DP1
[650.705270] Dynamic subtest C-DP1: SUCCESS (1.122s)
[650.707285] Subtest basic-flip-vs-modeset: SUCCESS (3.520s)
[651.065754] [128/137] (384s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling)
[651.328590] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling
[651.329846] Starting dynamic subtest: pipe-A-valid-mode
[651.421155] Dynamic subtest pipe-A-valid-mode: SKIP (0.090s)
[651.424775] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SKIP (0.093s)
[651.740125] [129/137] (384s left) kms_cursor_crc (cursor-rapid-movement-32x10)
[652.015744] Starting subtest: cursor-rapid-movement-32x10
[652.225598] Subtest cursor-rapid-movement-32x10: SKIP (0.206s)
[652.357138] [130/137] (383s left) sysfs_timeslice_duration (idempotent)
[652.538300] Starting subtest: idempotent
[652.542970] Starting dynamic subtest: vecs0
[652.544774] Dynamic subtest vecs0: SUCCESS (0.002s)
[652.570713] Starting dynamic subtest: vcs0
[652.571928] Dynamic subtest vcs0: SUCCESS (0.001s)
[652.595310] Starting dynamic subtest: bcs0
[652.596655] Dynamic subtest bcs0: SUCCESS (0.002s)
[652.620886] Starting dynamic subtest: rcs0
[652.622161] Dynamic subtest rcs0: SUCCESS (0.002s)
[652.623420] Subtest idempotent: SUCCESS (0.014s)
[652.656369] [131/137] (383s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-move)
[652.939892] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-move
[652.943154] Subtest fbcpsr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[653.040099] [132/137] (383s left) kms_setmode (clone-exclusive-crtc)
[653.211425] Starting subtest: clone-exclusive-crtc
[653.215230] Subtest clone-exclusive-crtc: SKIP (0.000s)
[653.306217] [133/137] (382s left) sw_sync (alloc_timeline)
[653.473785] Starting subtest: alloc_timeline
[653.475228] Subtest alloc_timeline: SUCCESS (0.000s)
[653.540017] [134/137] (382s left) kms_big_fb (4-tiled-addfb)
[653.832698] Starting subtest: 4-tiled-addfb
[653.836243] Subtest 4-tiled-addfb: SKIP (0.000s)
[653.940136] [135/137] (382s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled)
[654.112983] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled
[654.221829] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.107s)
[654.306731] [136/137] (382s left) gem_cs_tlb (engines)
[654.506811] Starting subtest: engines
[654.508445] Starting dynamic subtest: rcs0
[656.674004] Dynamic subtest rcs0: SUCCESS (2.161s)
[656.699586] Starting dynamic subtest: bcs0
[658.845515] Dynamic subtest bcs0: SUCCESS (2.172s)
[658.873324] Starting dynamic subtest: vcs0
[661.008487] Dynamic subtest vcs0: SUCCESS (2.162s)
[661.038782] Starting dynamic subtest: vecs0
[663.172795] Dynamic subtest vecs0: SUCCESS (2.161s)
[663.174319] Subtest engines: SUCCESS (8.662s)
[663.272471] [137/137] (373s left) gem_exec_schedule (pi-common)
[663.601105] Starting subtest: pi-common
[663.602693] Starting dynamic subtest: rcs0
[663.703855] Dynamic subtest rcs0: SUCCESS (0.099s)
[663.731844] Starting dynamic subtest: bcs0
[663.788760] Dynamic subtest bcs0: SUCCESS (0.088s)
[663.815242] Starting dynamic subtest: vcs0
[663.876631] Dynamic subtest vcs0: SUCCESS (0.085s)
[663.902533] Starting dynamic subtest: vecs0
[663.963173] Dynamic subtest vecs0: SUCCESS (0.085s)
[663.964427] Subtest pi-common: SUCCESS (0.362s)
[664.149923] Closing watchdogs
Done.