[51.090833] Initializing watchdogs
[51.091082]   /dev/watchdog0
[51.106712] [001/138] (960s left) kms_chamelium_edid (dp-edid-read)
[51.478940] Subtest dp-edid-read: SKIP (0.000s)
[51.769468] [002/138] (959s left) syncobj_wait (multi-wait-for-submit-submitted-signaled)
[51.914651] Starting subtest: multi-wait-for-submit-submitted-signaled
[51.916416] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.001s)
[51.997532] [003/138] (959s left) gem_exec_whisper (basic-contexts-priority-all)
[52.178974] Starting subtest: basic-contexts-priority-all
[76.052906] Subtest basic-contexts-priority-all: SUCCESS (23.873s)
[76.250634] [004/138] (935s left) gem_exec_reloc (basic-write-cpu)
[76.401710] Starting subtest: basic-write-cpu
[76.421445] Subtest basic-write-cpu: SUCCESS (0.018s)
[76.601071] [005/138] (935s left) kms_psr (cursor_plane_onoff)
[76.749894] Subtest cursor_plane_onoff: SKIP (0.000s)
[76.803289] [006/138] (934s left) kms_ccs (pipe-b-crc-primary-basic-4-tiled-mtl-mc-ccs)
[77.186158] Starting subtest: pipe-B-crc-primary-basic-4-tiled-mtl-mc-ccs
[77.188942] Subtest pipe-B-crc-primary-basic-4-tiled-mtl-mc-ccs: SKIP (0.001s)
[77.590148] [007/138] (934s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[77.832709] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[77.834122] Starting dynamic subtest: pipe-A-valid-mode
[77.941216] Dynamic subtest pipe-A-valid-mode: SKIP (0.105s)
[77.945652] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.107s)
[78.262565] [008/138] (933s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc)
[78.510556] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc
[78.513270] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.001s)
[78.612595] [009/138] (933s left) kms_big_fb (4-tiled-8bpp-rotate-90)
[78.876883] Starting subtest: 4-tiled-8bpp-rotate-90
[78.879982] Subtest 4-tiled-8bpp-rotate-90: SKIP (0.000s)
[78.962558] [010/138] (932s left) kms_vblank (ts-continuation-modeset-rpm)
[79.203337] Starting subtest: ts-continuation-modeset-rpm
[79.204674] Starting dynamic subtest: pipe-A-DP-1
[81.142734] Dynamic subtest pipe-A-DP-1: SUCCESS (1.936s)
[81.170481] Starting dynamic subtest: pipe-C-DP-1
[82.166237] Dynamic subtest pipe-C-DP-1: SUCCESS (1.019s)
[82.167677] Subtest ts-continuation-modeset-rpm: SUCCESS (2.963s)
[82.565123] [011/138] (929s left) gem_exec_params (no-blt)
[82.717652] Starting subtest: no-blt
[82.719296] Subtest no-blt: SUCCESS (0.003s)
[82.830973] [012/138] (929s left) kms_flip (2x-plain-flip-fb-recreate)
[83.053298] Starting subtest: 2x-plain-flip-fb-recreate
[83.055848] Subtest 2x-plain-flip-fb-recreate: SKIP (0.003s)
[83.179144] [013/138] (928s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[83.426286] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[83.428806] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[83.514973] [014/138] (928s left) i915_query (query-topology-known-pci-ids)
[83.655141] Starting subtest: query-topology-known-pci-ids
[83.657833] Subtest query-topology-known-pci-ids: SKIP (0.000s)
[83.734849] [015/138] (928s left) dumb_buffer (map-invalid-size)
[83.871637] Starting subtest: map-invalid-size
[83.873347] Subtest map-invalid-size: SUCCESS (0.000s)
[83.965198] [016/138] (928s left) i915_suspend (basic-s3-without-i915)
[84.102698] Starting subtest: basic-s3-without-i915
[104.606236] Subtest basic-s3-without-i915: SUCCESS (3.495s)
[108.752537] [017/138] (924s left) kms_cursor_crc (cursor-rapid-movement-64x21)
[109.015291] Starting subtest: cursor-rapid-movement-64x21
[109.050650] Starting dynamic subtest: pipe-A-DP-1
[109.816933] Dynamic subtest pipe-A-DP-1: SUCCESS (0.761s)
[109.902944] Starting dynamic subtest: pipe-C-DP-1
[110.497017] Dynamic subtest pipe-C-DP-1: SUCCESS (0.613s)
[110.498293] Subtest cursor-rapid-movement-64x21: SUCCESS (1.482s)
[110.865590] [018/138] (922s left) kms_async_flips (alternate-sync-async-flip)
[111.107388] Starting subtest: alternate-sync-async-flip
[111.252201] Starting dynamic subtest: pipe-A-DP-1
[113.648804] Dynamic subtest pipe-A-DP-1: SUCCESS (2.395s)
[113.868902] Starting dynamic subtest: pipe-B-DP-1
[116.127581] Dynamic subtest pipe-B-DP-1: SUCCESS (2.283s)
[116.344700] Starting dynamic subtest: pipe-C-DP-1
[118.601596] Dynamic subtest pipe-C-DP-1: SUCCESS (2.282s)
[118.602931] Subtest alternate-sync-async-flip: SUCCESS (7.493s)
[119.099240] [019/138] (914s left) kms_ccs (pipe-b-crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[119.532862] Starting subtest: pipe-B-crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[119.537250] Subtest pipe-B-crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SKIP (0.005s)
[119.923155] [020/138] (913s left) kms_chamelium_color (ctm-limited-range)
[120.165572] Subtest ctm-limited-range: SKIP (0.000s)
[120.224216] [021/138] (913s left) i915_pm_rpm (gem-execbuf)
[121.943170] Starting subtest: gem-execbuf
[121.945785] Starting dynamic subtest: smem0
[123.505725] Dynamic subtest smem0: SUCCESS (1.565s)
[123.508460] Subtest gem-execbuf: SUCCESS (1.565s)
[124.009794] [022/138] (909s left) vgem_basic (debugfs)
[124.237894] Starting subtest: debugfs
[124.239566] Subtest debugfs: SUCCESS (0.002s)
[124.308141] [023/138] (909s left) kms_addfb_basic (unused-modifier)
[124.453746] Starting subtest: unused-modifier
[124.455099] Subtest unused-modifier: SUCCESS (0.000s)
[124.641590] [024/138] (908s left) vgem_basic (dmabuf-fence-before)
[124.788779] Starting subtest: dmabuf-fence-before
[124.790385] Subtest dmabuf-fence-before: SUCCESS (0.003s)
[124.859608] [025/138] (908s left) perf_pmu (busy-idle-no-semaphores)
[125.008551] Starting subtest: busy-idle-no-semaphores
[125.010126] Starting dynamic subtest: rcs0
[125.652429] Dynamic subtest rcs0: SUCCESS (0.642s)
[125.671759] Starting dynamic subtest: bcs0
[126.285404] Dynamic subtest bcs0: SUCCESS (0.632s)
[126.305532] Starting dynamic subtest: vcs0
[126.918619] Dynamic subtest vcs0: SUCCESS (0.632s)
[126.938256] Starting dynamic subtest: vecs0
[127.548216] Dynamic subtest vecs0: SUCCESS (0.627s)
[127.549689] Subtest busy-idle-no-semaphores: SUCCESS (2.538s)
[127.640548] [026/138] (905s left) kms_color (invalid-ctm-matrix-sizes)
[127.887700] Starting subtest: invalid-ctm-matrix-sizes
[127.889146] Starting dynamic subtest: pipe-A
[127.893251] Dynamic subtest pipe-A: SUCCESS (0.008s)
[127.913520] Starting dynamic subtest: pipe-B
[127.914975] Dynamic subtest pipe-B: SUCCESS (0.010s)
[127.940121] Starting dynamic subtest: pipe-C
[127.941531] Dynamic subtest pipe-C: SUCCESS (0.015s)
[127.942796] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.035s)
[128.010295] [027/138] (905s left) kms_big_fb (x-tiled-8bpp-rotate-270)
[128.269395] Starting subtest: x-tiled-8bpp-rotate-270
[128.362660] Subtest x-tiled-8bpp-rotate-270: SKIP (0.091s)
[128.475057] [028/138] (905s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-25)
[128.713437] Starting subtest: planes-upscale-20x20-downscale-factor-0-25
[128.726672] Starting dynamic subtest: pipe-A-DP-1
[128.766228] Dynamic subtest pipe-A-DP-1: SKIP (0.037s)
[128.798574] Starting dynamic subtest: pipe-B-DP-1
[128.864279] Dynamic subtest pipe-B-DP-1: SKIP (0.086s)
[128.875971] Subtest planes-upscale-20x20-downscale-factor-0-25: SKIP (0.160s)
[128.992223] [029/138] (904s left) gem_lmem_swapping (parallel-random-engines)
[129.144252] Subtest parallel-random-engines: SKIP (0.000s)
[129.191962] [030/138] (904s left) api_intel_bb (object-reloc-purge-cache)
[129.335763] Starting subtest: object-reloc-purge-cache
[129.349697] Subtest object-reloc-purge-cache: SUCCESS (0.013s)
[129.457234] [031/138] (904s left) kms_frontbuffer_tracking (psr-1p-rte)
[129.713882] Starting subtest: psr-1p-rte
[129.716404] Subtest psr-1p-rte: SKIP (0.000s)
[129.808630] [032/138] (903s left) kms_display_modes (mst-extended-mode-negative)
[130.045340] Starting subtest: mst-extended-mode-negative
[130.048169] Subtest mst-extended-mode-negative: SKIP (0.000s)
[130.108758] [033/138] (903s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[130.250540] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[130.251962] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.002s)
[130.340753] [034/138] (903s left) gem_exec_schedule (preemptive-hang)
[130.627041] Starting subtest: preemptive-hang
[130.628608] Starting dynamic subtest: rcs0
[136.355917] Dynamic subtest rcs0: SUCCESS (5.728s)
[136.382823] Starting dynamic subtest: bcs0
[142.362098] Dynamic subtest bcs0: SUCCESS (6.004s)
[142.391775] Starting dynamic subtest: vcs0
[148.387512] Dynamic subtest vcs0: SUCCESS (6.024s)
[148.410717] Starting dynamic subtest: vecs0
[154.330826] Dynamic subtest vecs0: SUCCESS (5.941s)
[154.332690] Subtest preemptive-hang: SUCCESS (23.701s)
[154.543804] [035/138] (879s left) kms_plane_scaling (plane-downscale-factor-0-25-with-rotation)
[154.785337] Starting subtest: plane-downscale-factor-0-25-with-rotation
[154.796255] Starting dynamic subtest: pipe-A-DP-1
[154.846245] Dynamic subtest pipe-A-DP-1: SKIP (0.048s)
[154.873659] Starting dynamic subtest: pipe-B-DP-1
[154.955684] Dynamic subtest pipe-B-DP-1: SKIP (0.097s)
[154.983143] Starting dynamic subtest: pipe-C-DP-1
[155.069894] Dynamic subtest pipe-C-DP-1: SKIP (0.101s)
[155.074488] Subtest plane-downscale-factor-0-25-with-rotation: SKIP (0.281s)
[155.175059] [036/138] (878s left) syncobj_timeline (multi-wait-available-signaled)
[155.317410] Starting subtest: multi-wait-available-signaled
[155.320905] Subtest multi-wait-available-signaled: SUCCESS (0.002s)
[155.409597] [037/138] (878s left) syncobj_eventfd (invalid-bad-pad)
[155.553604] Starting subtest: invalid-bad-pad
[155.555033] Subtest invalid-bad-pad: SUCCESS (0.000s)
[155.641444] [038/138] (878s left) v3d/v3d_perfmon (get-values-invalid-pointer)
[155.839866] Subtest get-values-invalid-pointer: SKIP (0.000s)
[155.907915] [039/138] (878s left) i915_hangman (gt-engine-error)
[156.077528] Starting subtest: gt-engine-error
[156.079154] Starting dynamic subtest: rcs0
[156.167294] Dynamic subtest rcs0: SUCCESS (0.089s)
[156.190033] Starting dynamic subtest: bcs0
[156.192566] Dynamic subtest bcs0: SKIP (0.003s)
[156.216375] Starting dynamic subtest: vcs0
[156.256986] Dynamic subtest vcs0: SUCCESS (0.085s)
[156.278449] Starting dynamic subtest: vecs0
[156.346245] Dynamic subtest vecs0: SUCCESS (0.087s)
[156.348234] Subtest gt-engine-error: SUCCESS (0.269s)
[156.477426] [040/138] (877s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-wc)
[156.728893] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-wc
[156.731453] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[156.842162] [041/138] (877s left) gem_exec_gttfill (basic)
[157.010734] Starting subtest: basic
[184.801655] Subtest basic: SUCCESS (27.789s)
[184.996273] [042/138] (849s left) gem_exec_fence (syncobj-timeline-chain-engines)
[185.869725] Starting subtest: syncobj-timeline-chain-engines
[185.997732] Subtest syncobj-timeline-chain-engines: SUCCESS (0.129s)
[186.221849] [043/138] (848s left) kms_ccs (pipe-c-crc-primary-basic-4-tiled-dg2-rc-ccs-cc)
[186.679274] Starting subtest: pipe-C-crc-primary-basic-4-tiled-dg2-rc-ccs-cc
[186.681807] Subtest pipe-C-crc-primary-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.001s)
[187.048163] [044/138] (847s left) kms_flip (flip-vs-panning-vs-hang)
[187.278469] Starting subtest: flip-vs-panning-vs-hang
[187.280071] Starting dynamic subtest: A-DP1
[200.910672] Dynamic subtest A-DP1: SUCCESS (13.631s)
[200.932824] Starting dynamic subtest: C-DP1
[213.899368] Dynamic subtest C-DP1: SUCCESS (12.986s)
[213.900984] Subtest flip-vs-panning-vs-hang: SUCCESS (26.624s)
[214.329814] [045/138] (820s left) gem_softpin (evict-prime)
[214.480628] Starting subtest: evict-prime
[214.482105] Starting dynamic subtest: rcs0
[215.599295] Dynamic subtest rcs0: SUCCESS (1.117s)
[215.642770] Starting dynamic subtest: bcs0
[216.716192] Dynamic subtest bcs0: SUCCESS (1.113s)
[216.754933] Starting dynamic subtest: vcs0
[217.826336] Dynamic subtest vcs0: SUCCESS (1.110s)
[217.857221] Starting dynamic subtest: vecs0
[218.934269] Dynamic subtest vecs0: SUCCESS (1.106s)
[218.964156] Starting dynamic subtest: all
[220.050276] Dynamic subtest all: SUCCESS (1.115s)
[220.051678] Subtest evict-prime: SUCCESS (5.570s)
[220.145594] [046/138] (814s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[220.386462] Starting subtest: flip-vs-cursor-busy-crc-atomic
[221.180925] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.795s)
[221.537966] [047/138] (812s left) gem_exec_schedule (reorder-wide)
[221.856471] Starting subtest: reorder-wide
[221.858438] Starting dynamic subtest: rcs0
[222.028486] Dynamic subtest rcs0: SUCCESS (0.173s)
[222.056371] Starting dynamic subtest: bcs0
[222.374424] Dynamic subtest bcs0: SUCCESS (0.344s)
[222.407879] Starting dynamic subtest: vcs0
[222.548871] Dynamic subtest vcs0: SUCCESS (0.173s)
[222.580377] Starting dynamic subtest: vecs0
[222.719675] Dynamic subtest vecs0: SUCCESS (0.170s)
[222.721041] Subtest reorder-wide: SUCCESS (0.865s)
[222.955206] [048/138] (811s left) gem_ctx_persistence (engines-cleanup)
[223.106535] Starting subtest: engines-cleanup
[223.108110] Starting dynamic subtest: rcs0
[223.150363] Dynamic subtest rcs0: SUCCESS (0.042s)
[223.175078] Starting dynamic subtest: bcs0
[223.186278] Dynamic subtest bcs0: SUCCESS (0.035s)
[223.209815] Starting dynamic subtest: vcs0
[223.227646] Dynamic subtest vcs0: SUCCESS (0.038s)
[223.251792] Starting dynamic subtest: vecs0
[223.266045] Dynamic subtest vecs0: SUCCESS (0.038s)
[223.267758] Subtest engines-cleanup: SUCCESS (0.160s)
[223.351372] [049/138] (811s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[223.585703] Starting subtest: blocking-absolute-wf_vblank-interruptible
[223.589994] Starting dynamic subtest: A-DP1
[234.265521] Dynamic subtest A-DP1: SUCCESS (10.675s)
[234.288593] Starting dynamic subtest: B-DP1
[244.860153] Dynamic subtest B-DP1: SUCCESS (10.592s)
[244.883228] Starting dynamic subtest: C-DP1
[255.495794] Dynamic subtest C-DP1: SUCCESS (10.636s)
[255.499353] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (31.912s)
[255.929475] [050/138] (778s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[256.176633] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[256.178321] Starting dynamic subtest: pipe-A-valid-mode
[256.893814] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.717s)
[256.895289] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SUCCESS (0.720s)
[257.246837] [051/138] (777s left) kms_flip (basic-flip-vs-dpms)
[257.467893] Starting subtest: basic-flip-vs-dpms
[257.469327] Starting dynamic subtest: A-DP1
[258.728541] Dynamic subtest A-DP1: SUCCESS (1.255s)
[258.753598] Starting dynamic subtest: B-DP1
[259.887447] Dynamic subtest B-DP1: SUCCESS (1.160s)
[259.911973] Starting dynamic subtest: C-DP1
[261.047034] Dynamic subtest C-DP1: SUCCESS (1.159s)
[261.050638] Subtest basic-flip-vs-dpms: SUCCESS (3.584s)
[261.419754] [052/138] (773s left) kms_busy (extended-modeset-hang-oldfb)
[261.675149] Starting subtest: extended-modeset-hang-oldfb
[261.678771] Starting dynamic subtest: pipe-A
[269.437279] Dynamic subtest pipe-A: SUCCESS (7.760s)
[269.468226] Starting dynamic subtest: pipe-C
[277.402232] Dynamic subtest pipe-C: SUCCESS (7.958s)
[277.403668] Subtest extended-modeset-hang-oldfb: SUCCESS (15.729s)
[277.760544] [053/138] (756s left) gem_render_copy (x-tiled)
[277.923206] Starting subtest: x-tiled
[277.924698] Starting dynamic subtest: smem
[278.205086] Dynamic subtest smem: SUCCESS (0.279s)
[278.206375] Subtest x-tiled: SUCCESS (0.281s)
[278.412188] [054/138] (756s left) kms_pm_rpm (modeset-pc8-residency-stress)
[280.154469] Starting subtest: modeset-pc8-residency-stress
[280.158898] Subtest modeset-pc8-residency-stress: SKIP (0.000s)
[280.651309] [055/138] (754s left) gem_userptr_blits (mmap-offset-banned)
[280.835744] Starting subtest: mmap-offset-banned
[280.837552] Starting dynamic subtest: gtt
[280.839506] Dynamic subtest gtt: SUCCESS (0.001s)
[280.864200] Starting dynamic subtest: wb
[280.865529] Dynamic subtest wb: SUCCESS (0.001s)
[280.888927] Starting dynamic subtest: wc
[280.890388] Dynamic subtest wc: SUCCESS (0.001s)
[280.914888] Starting dynamic subtest: uc
[280.916281] Dynamic subtest uc: SUCCESS (0.000s)
[280.917469] Subtest mmap-offset-banned: SUCCESS (0.009s)
[280.986530] [056/138] (753s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-msflip-blt)
[281.253487] Starting subtest: fbc-2p-scndscrn-shrfb-msflip-blt
[281.256066] Subtest fbc-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[281.350136] [057/138] (753s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[281.600086] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-gtt
[281.602702] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[281.699537] [058/138] (753s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled-ccs)
[281.861325] Starting subtest: yf-tiled-ccs-to-yf-tiled-ccs
[281.862797] Starting dynamic subtest: smem
[282.320083] Dynamic subtest smem: SUCCESS (0.456s)
[282.321470] Subtest yf-tiled-ccs-to-yf-tiled-ccs: SUCCESS (0.457s)
[282.518590] [059/138] (752s left) kms_ccs (pipe-a-crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[282.864381] Starting subtest: pipe-A-crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[282.866915] Subtest pipe-A-crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SKIP (0.001s)
[283.324520] [060/138] (751s left) gem_wait (invalid-buf)
[283.474912] Starting subtest: invalid-buf
[283.476484] Subtest invalid-buf: SUCCESS (0.000s)
[283.756389] [061/138] (751s left) kms_ccs (pipe-a-crc-primary-basic-4-tiled-dg2-rc-ccs)
[284.086455] Starting subtest: pipe-A-crc-primary-basic-4-tiled-dg2-rc-ccs
[284.088994] Subtest pipe-A-crc-primary-basic-4-tiled-dg2-rc-ccs: SKIP (0.001s)
[284.545774] [062/138] (750s left) vc4/vc4_purgeable_bo (free-purged-bo)
[284.739867] Subtest free-purged-bo: SKIP (0.000s)
[284.813702] [063/138] (750s left) kms_ccs (pipe-c-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc)
[285.289318] Starting subtest: pipe-C-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc
[285.294101] Subtest pipe-C-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[285.623290] [064/138] (749s left) v3d/v3d_submit_csd (bad-multisync-in-sync)
[285.815592] Subtest bad-multisync-in-sync: SKIP (0.000s)
[285.889238] [065/138] (749s left) gem_mmap_gtt (basic-small-bo-tiledy)
[286.027972] Starting subtest: basic-small-bo-tiledY
[286.663791] Subtest basic-small-bo-tiledY: SUCCESS (0.633s)
[286.865343] [066/138] (748s left) kms_ccs (pipe-a-bad-pixel-format-4-tiled-dg2-rc-ccs)
[287.244688] Starting subtest: pipe-A-bad-pixel-format-4-tiled-dg2-rc-ccs
[287.247472] Subtest pipe-A-bad-pixel-format-4-tiled-dg2-rc-ccs: SKIP (0.000s)
[287.819082] [067/138] (747s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[288.064463] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu
[288.067336] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[288.166944] [068/138] (747s left) gem_ctx_freq (sysfs)
[288.324181] Starting subtest: sysfs
[288.326061] Starting dynamic subtest: gt0
[293.284228] Dynamic subtest gt0: SUCCESS (4.959s)
[293.285870] Subtest sysfs: SUCCESS (4.961s)
[293.387436] [069/138] (742s left) kms_pm_dc (dc5-dpms)
[294.667740] Starting subtest: dc5-dpms
[296.267091] Subtest dc5-dpms: SUCCESS (1.597s)
[296.439416] [070/138] (739s left) kms_ccs (pipe-b-bad-aux-stride-4-tiled-mtl-rc-ccs)
[296.873964] Starting subtest: pipe-B-bad-aux-stride-4-tiled-mtl-rc-ccs
[296.876633] Subtest pipe-B-bad-aux-stride-4-tiled-mtl-rc-ccs: SKIP (0.000s)
[297.266705] [071/138] (738s left) gem_mmap_offset (close-race)
[297.413273] Starting subtest: close-race
[317.533232] Subtest close-race: SUCCESS (20.121s)
[317.647458] [072/138] (717s left) gem_exec_params (mmapped)
[317.798753] Starting subtest: mmapped
[317.802474] Subtest mmapped: SUCCESS (0.005s)
[317.923424] [073/138] (717s left) drm_fdinfo (virtual-busy)
[318.072909] Starting subtest: virtual-busy
[320.251532] Subtest virtual-busy: SUCCESS (2.177s)
[320.345973] [074/138] (715s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[320.598250] Starting subtest: fbc-rgb565-draw-blt
[322.820648] Subtest fbc-rgb565-draw-blt: SUCCESS (2.224s)
[322.965892] [075/138] (712s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[323.301397] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[323.596089] [076/138] (712s left) kms_getfb (getfb2-handle-closed)
[323.739295] Starting subtest: getfb2-handle-closed
[323.740907] Subtest getfb2-handle-closed: SUCCESS (0.000s)
[323.837429] [077/138] (711s left) kms_ccs (pipe-d-crc-primary-rotation-180-y-tiled-gen12-mc-ccs)
[324.324524] Subtest pipe-D-crc-primary-rotation-180-y-tiled-gen12-mc-ccs: SKIP (0.000s)
[324.653742] [078/138] (711s left) kms_ccs (pipe-d-crc-primary-basic-4-tiled-dg2-mc-ccs)
[325.139286] Subtest pipe-D-crc-primary-basic-4-tiled-dg2-mc-ccs: SKIP (0.000s)
[325.462201] [079/138] (710s left) vc4/vc4_create_bo (create-bo-0)
[325.671566] Subtest create-bo-0: SKIP (0.000s)
[325.745477] [080/138] (710s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-gtt)
[325.994306] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-gtt
[325.996770] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[326.095560] [081/138] (709s left) gem_exec_flush (basic-wb-ro-before-default)
[326.264220] Starting subtest: basic-wb-ro-before-default
[331.726029] Subtest basic-wb-ro-before-default: SUCCESS (5.462s)
[331.945479] [082/138] (704s left) gem_eio (throttle)
[332.118285] Starting subtest: throttle
[332.159269] Subtest throttle: SUCCESS (0.042s)
[332.362954] [083/138] (703s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-pwrite)
[332.622376] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-pwrite
[332.625536] Subtest fbc-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[332.729642] [084/138] (703s left) gem_exec_fair (basic-none-solo)
[332.901117] Starting subtest: basic-none-solo
[332.902503] Starting dynamic subtest: rcs0
[341.669467] Dynamic subtest rcs0: SUCCESS (8.765s)
[341.670967] Subtest basic-none-solo: SUCCESS (8.768s)
[341.881322] [085/138] (694s left) kms_ccs (pipe-c-crc-primary-basic-y-tiled-gen12-rc-ccs-cc)
[342.325962] Starting subtest: pipe-C-crc-primary-basic-y-tiled-gen12-rc-ccs-cc
[342.328621] Subtest pipe-C-crc-primary-basic-y-tiled-gen12-rc-ccs-cc: SKIP (0.001s)
[342.689669] [086/138] (693s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[342.941547] Starting subtest: psr-rgb565-draw-pwrite
[342.944740] Subtest psr-rgb565-draw-pwrite: SKIP (0.000s)
[343.039443] [087/138] (693s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-downscaling)
[343.277572] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-downscaling
[343.278953] Starting dynamic subtest: pipe-A-valid-mode
[344.032743] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.753s)
[344.037199] Subtest flip-64bpp-ytile-to-16bpp-ytile-downscaling: SUCCESS (0.757s)
[344.393659] [088/138] (691s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff)
[344.651099] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff
[347.249346] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (2.599s)
[347.414671] [089/138] (688s left) kms_rotation_crc (primary-y-tiled-reflect-x-0)
[347.655287] Starting subtest: primary-y-tiled-reflect-x-0
[347.657906] Subtest primary-y-tiled-reflect-x-0: SKIP (0.003s)
[347.742673] [090/138] (688s left) kms_ccs (pipe-d-bad-aux-stride-y-tiled-gen12-rc-ccs-cc)
[348.220410] Subtest pipe-D-bad-aux-stride-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s)
[348.542848] [091/138] (687s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[348.812485] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip
[348.835649] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.020s)
[348.942412] [092/138] (687s left) kms_setmode (invalid-clone-exclusive-crtc)
[349.088128] Starting subtest: invalid-clone-exclusive-crtc
[349.093040] Subtest invalid-clone-exclusive-crtc: SKIP (0.001s)
[349.179557] [093/138] (687s left) gem_busy (close-race)
[349.346981] Starting subtest: close-race
[371.490606] Subtest close-race: SUCCESS (22.144s)
[371.881636] [094/138] (664s left) gem_readwrite (read-bad-handle)
[372.135792] Starting subtest: read-bad-handle
[372.138762] Subtest read-bad-handle: SUCCESS (0.000s)
[372.242708] [095/138] (664s left) gen9_exec_parse (secure-batches)
[372.416434] Starting subtest: secure-batches
[372.417819] Subtest secure-batches: SUCCESS (0.000s)
[372.661702] [096/138] (663s left) kms_psr (psr2_suspend)
[372.804364] Subtest psr2_suspend: SKIP (0.000s)
[372.861848] [097/138] (663s left) gem_ctx_shared (q-out-order)
[373.026500] Starting subtest: Q-out-order
[373.027943] Starting dynamic subtest: rcs0
[373.128783] Dynamic subtest rcs0: SUCCESS (0.099s)
[373.158100] Starting dynamic subtest: bcs0
[373.242465] Dynamic subtest bcs0: SUCCESS (0.111s)
[373.270595] Starting dynamic subtest: vcs0
[373.333966] Dynamic subtest vcs0: SUCCESS (0.089s)
[373.363518] Starting dynamic subtest: vecs0
[373.425624] Dynamic subtest vecs0: SUCCESS (0.089s)
[373.427373] Subtest Q-out-order: SUCCESS (0.399s)
[373.661899] [098/138] (663s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-msflip-blt)
[373.918270] Starting subtest: fbc-2p-primscrn-indfb-msflip-blt
[373.921112] Subtest fbc-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[374.027223] [099/138] (662s left) kms_ccs (pipe-b-random-ccs-data-y-tiled-ccs)
[374.432823] Starting subtest: pipe-B-random-ccs-data-y-tiled-ccs
[374.884707] Subtest pipe-B-random-ccs-data-y-tiled-ccs: SUCCESS (0.452s)
[375.421714] [100/138] (661s left) gem_pxp (verify-pxp-execution-after-suspend-resume)
[378.088681] Subtest verify-pxp-execution-after-suspend-resume: SKIP (0.000s)
[378.169900] [101/138] (658s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-plflip-blt)
[378.415393] Starting subtest: fbcpsr-2p-scndscrn-shrfb-plflip-blt
[378.419263] Subtest fbcpsr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[378.520707] [102/138] (658s left) kms_vblank (crtc-id)
[378.754706] Starting subtest: crtc-id
[378.758704] Starting dynamic subtest: pipe-A-DP-1
[379.073132] Dynamic subtest pipe-A-DP-1: SUCCESS (0.315s)
[379.103123] Starting dynamic subtest: pipe-C-DP-1
[379.562781] Dynamic subtest pipe-C-DP-1: SUCCESS (0.483s)
[379.564510] Subtest crtc-id: SUCCESS (0.810s)
[379.918324] [103/138] (657s left) syncobj_timeline (invalid-multi-wait-unsubmitted)
[380.080131] Starting subtest: invalid-multi-wait-unsubmitted
[380.081568] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[380.165529] [104/138] (656s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-move)
[380.417674] Starting subtest: fbc-2p-primscrn-spr-indfb-move
[380.420243] Subtest fbc-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[380.532873] [105/138] (656s left) kms_lease (master-vs-lease)
[380.771255] Starting subtest: master-vs-lease
[380.772758] Subtest master-vs-lease: SUCCESS (0.003s)
[380.865965] [106/138] (656s left) kms_big_fb (linear-16bpp-rotate-0)
[381.144420] Starting subtest: linear-16bpp-rotate-0
[385.888616] Subtest linear-16bpp-rotate-0: SUCCESS (4.742s)
[386.217099] [107/138] (650s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[386.461671] Starting subtest: cursorB-vs-flipB-legacy
[386.464765] Subtest cursorB-vs-flipB-legacy: SKIP (0.003s)
[386.565344] [108/138] (650s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[386.833056] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[386.836114] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[386.948963] [109/138] (650s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc)
[387.193908] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc
[387.196414] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[387.315251] [110/138] (649s left) kms_cursor_crc (cursor-onscreen-128x128)
[387.566592] Starting subtest: cursor-onscreen-128x128
[387.576406] Starting dynamic subtest: pipe-A-DP-1
[389.920349] Dynamic subtest pipe-A-DP-1: SUCCESS (2.340s)
[390.021473] Starting dynamic subtest: pipe-C-DP-1
[392.441831] Dynamic subtest pipe-C-DP-1: SUCCESS (2.448s)
[392.443172] Subtest cursor-onscreen-128x128: SUCCESS (4.876s)
[392.823853] [111/138] (644s left) kms_properties (get_properties-sanity-non-atomic)
[393.071279] Starting subtest: get_properties-sanity-non-atomic
[393.076319] Subtest get_properties-sanity-non-atomic: SUCCESS (0.003s)
[393.169129] [112/138] (644s left) gem_mmap_gtt (hang-user)
[393.312866] Starting subtest: hang-user
[408.212390] Subtest hang-user: SUCCESS (14.899s)
[408.335346] [113/138] (629s left) prime_busy (hang)
[408.492696] Starting subtest: hang
[408.497827] Starting dynamic subtest: rcs0
[414.912692] Dynamic subtest rcs0: SUCCESS (6.413s)
[414.937939] Starting dynamic subtest: bcs0
[421.888180] Dynamic subtest bcs0: SUCCESS (6.973s)
[421.913341] Starting dynamic subtest: vcs0
[428.928333] Dynamic subtest vcs0: SUCCESS (7.037s)
[428.954157] Starting dynamic subtest: vecs0
[435.903790] Dynamic subtest vecs0: SUCCESS (6.974s)
[435.905455] Subtest hang: SUCCESS (27.409s)
[436.002760] [114/138] (601s left) gem_vm_create (execbuf)
[436.151690] Starting subtest: execbuf
[436.160326] Subtest execbuf: SUCCESS (0.009s)
[436.284279] [115/138] (601s left) api_intel_allocator (fork-simple-stress-signal)
[436.426992] Starting subtest: fork-simple-stress-signal
[441.970559] Subtest fork-simple-stress-signal: SUCCESS (5.544s)
[442.070547] [116/138] (595s left) kms_plane (plane-panning-bottom-right-suspend)
[442.304885] Starting subtest: plane-panning-bottom-right-suspend
[442.309471] Starting dynamic subtest: pipe-A
[460.720624] Dynamic subtest pipe-A: SUCCESS (2.099s)
[460.806282] Starting dynamic subtest: pipe-B
[479.659753] Dynamic subtest pipe-B: SUCCESS (2.152s)
[479.661305] Subtest plane-panning-bottom-right-suspend: SUCCESS (4.261s)
[485.031183] [117/138] (590s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[485.292634] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[485.295208] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[485.407021] [118/138] (590s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[485.655521] Starting subtest: primary-4-tiled-reflect-x-180
[485.658483] Subtest primary-4-tiled-reflect-x-180: SKIP (0.003s)
[485.752590] [119/138] (590s left) gem_create (create-massive)
[485.894806] Starting subtest: create-massive
[485.896248] Subtest create-massive: SUCCESS (0.000s)
[486.119712] [120/138] (589s left) gem_exec_nop (basic-series)
[486.287769] Starting subtest: basic-series
[489.354200] Subtest basic-series: SUCCESS (3.066s)
[489.586998] [121/138] (586s left) kms_writeback (writeback-check-output-xrgb2101010)
[489.922566] Subtest writeback-check-output-XRGB2101010: SKIP (0.000s)
[489.987148] [122/138] (585s left) kms_atomic_interruptible (legacy-cursor)
[490.228554] Starting subtest: legacy-cursor
[490.232892] Starting dynamic subtest: pipe-A-DP-1
[496.767803] Dynamic subtest pipe-A-DP-1: SUCCESS (6.533s)
[496.770276] Subtest legacy-cursor: SUCCESS (6.539s)
[497.112263] [123/138] (578s left) gem_exec_balancer (invalid-bonds)
[497.285367] Starting subtest: invalid-bonds
[497.298803] Subtest invalid-bonds: SUCCESS (0.014s)
[501.227422] [124/138] (574s left) gem_exec_fence (basic-wait-all)
[501.397608] Starting subtest: basic-wait-all
[501.424232] Subtest basic-wait-all: SUCCESS (0.029s)
[502.244905] [125/138] (573s left) kms_big_fb (y-tiled-32bpp-rotate-270)
[502.519378] Starting subtest: y-tiled-32bpp-rotate-270
[507.465653] Subtest y-tiled-32bpp-rotate-270: SUCCESS (4.944s)
[507.994465] [126/138] (568s left) sysfs_heartbeat_interval (precise)
[508.146033] Starting subtest: precise
[508.150284] Starting dynamic subtest: vecs0
[509.661279] Dynamic subtest vecs0: SUCCESS (1.510s)
[509.689797] Starting dynamic subtest: vcs0
[511.168038] Dynamic subtest vcs0: SUCCESS (1.504s)
[511.194191] Starting dynamic subtest: bcs0
[512.667457] Dynamic subtest bcs0: SUCCESS (1.499s)
[512.699258] Starting dynamic subtest: rcs0
[514.173165] Dynamic subtest rcs0: SUCCESS (1.502s)
[514.174820] Subtest precise: SUCCESS (6.029s)
[514.277637] [127/138] (561s left) kms_ccs (pipe-b-bad-rotation-90-yf-tiled-ccs)
[514.662931] Starting subtest: pipe-B-bad-rotation-90-yf-tiled-ccs
[515.145522] Subtest pipe-B-bad-rotation-90-yf-tiled-ccs: SUCCESS (0.477s)
[515.648097] [128/138] (560s left) kms_cursor_crc (cursor-onscreen-512x170)
[515.903600] Starting subtest: cursor-onscreen-512x170
[515.906191] Subtest cursor-onscreen-512x170: SKIP (0.003s)
[516.009293] [129/138] (560s left) gem_exec_balancer (hog)
[516.176785] Starting subtest: hog
[519.892845] Subtest hog: SUCCESS (3.714s)
[523.827401] [130/138] (552s left) kms_psr2_su (frontbuffer-xrgb8888)
[523.969977] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[524.027847] [131/138] (552s left) gem_workarounds (basic-read-fd)
[524.204706] Starting subtest: basic-read-fd
[524.226956] Subtest basic-read-fd: SUCCESS (0.023s)
[524.343124] [132/138] (552s left) syncobj_timeline (single-wait-all-available-submitted)
[524.486888] Starting subtest: single-wait-all-available-submitted
[524.488826] Subtest single-wait-all-available-submitted: SUCCESS (0.001s)
[524.576436] [133/138] (551s left) kms_invalid_mode (zero-hdisplay)
[524.821863] Starting subtest: zero-hdisplay
[524.824004] Starting dynamic subtest: pipe-A-DP-1
[524.911998] Dynamic subtest pipe-A-DP-1: SUCCESS (0.087s)
[524.941859] Starting dynamic subtest: pipe-B-DP-1
[524.944656] Dynamic subtest pipe-B-DP-1: SUCCESS (0.002s)
[524.971717] Starting dynamic subtest: pipe-C-DP-1
[524.974550] Dynamic subtest pipe-C-DP-1: SUCCESS (0.002s)
[524.977410] Subtest zero-hdisplay: SUCCESS (0.106s)
[525.251270] [134/138] (551s left) prime_mmap (test_correct_cpu_write)
[525.394211] Starting subtest: test_correct_cpu_write
[525.395776] Starting dynamic subtest: test_correct_cpu_write-smem
[525.397263] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.001s)
[525.398853] Subtest test_correct_cpu_write: SUCCESS (0.005s)
[525.519618] [135/138] (551s left) kms_addfb_basic (clobberred-modifier)
[525.661633] Starting subtest: clobberred-modifier
[525.663037] Subtest clobberred-modifier: SUCCESS (0.000s)
[525.868570] [136/138] (550s left) gem_exec_reloc (basic-gtt-wc-active)
[526.021626] Starting subtest: basic-gtt-wc-active
[526.163589] Subtest basic-gtt-wc-active: SUCCESS (0.138s)
[526.353312] [137/138] (550s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-fullscreen)
[526.598585] Starting subtest: psr-2p-primscrn-spr-indfb-fullscreen
[526.601248] Subtest psr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[526.718905] [138/138] (550s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-render)
[526.962023] Starting subtest: fbcpsr-rgb565-draw-render
[526.964738] Subtest fbcpsr-rgb565-draw-render: SKIP (0.000s)
[527.064982] Closing watchdogs
Done.