[37.642532] Initializing watchdogs
[37.642735]   /dev/watchdog0
[37.660517] [001/138] (960s left) prime_mmap (test_aperture_limit)
[37.825961] Starting subtest: test_aperture_limit
[37.827522] Starting dynamic subtest: test_aperture_limit-smem
[37.854150] Dynamic subtest test_aperture_limit-smem: SUCCESS (0.028s)
[37.855860] Subtest test_aperture_limit: SUCCESS (0.028s)
[37.955508] [002/138] (960s left) gem_render_copy (y-tiled-to-vebox-yf-tiled)
[38.120182] Starting subtest: y-tiled-to-vebox-yf-tiled
[38.122866] Subtest y-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[38.319803] [003/138] (959s left) template (a)
[38.467656] Starting subtest: A
[38.468976] Subtest A: SUCCESS (0.000s)
[38.551062] [004/138] (959s left) vc4/vc4_wait_bo (bad-bo)
[38.755730] Subtest bad-bo: SKIP (0.000s)
[38.817108] [005/138] (959s left) kms_psr (psr2_no_drrs)
[38.955456] Subtest psr2_no_drrs: SKIP (0.000s)
[39.002540] [006/138] (959s left) gem_exec_reloc (basic-wc-gtt-noreloc)
[39.171083] Starting subtest: basic-wc-gtt-noreloc
[39.191919] Subtest basic-wc-gtt-noreloc: SUCCESS (0.021s)
[39.369119] [007/138] (958s left) kms_flip (2x-flip-vs-rmfb)
[39.594986] Starting subtest: 2x-flip-vs-rmfb
[39.597539] Subtest 2x-flip-vs-rmfb: SKIP (0.003s)
[39.702573] [008/138] (958s left) kms_big_fb (x-tiled-8bpp-rotate-90)
[39.967613] Starting subtest: x-tiled-8bpp-rotate-90
[40.100963] Subtest x-tiled-8bpp-rotate-90: SKIP (0.131s)
[40.202767] [009/138] (958s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt)
[40.452679] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt
[42.688443] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SUCCESS (2.238s)
[42.819733] [010/138] (955s left) kms_pm_rpm (modeset-non-lpsp)
[44.891384] Starting subtest: modeset-non-lpsp
[45.536584] Subtest modeset-non-lpsp: SUCCESS (0.642s)
[45.714986] [011/138] (952s left) kms_vblank (ts-continuation-dpms-rpm)
[45.954680] Starting subtest: ts-continuation-dpms-rpm
[45.956082] Starting dynamic subtest: pipe-A-DP-1
[47.838229] Dynamic subtest pipe-A-DP-1: SUCCESS (1.883s)
[47.858951] Starting dynamic subtest: pipe-C-DP-1
[48.832752] Dynamic subtest pipe-C-DP-1: SUCCESS (0.984s)
[48.833974] Subtest ts-continuation-dpms-rpm: SUCCESS (2.876s)
[49.262769] [012/138] (949s left) v3d/v3d_submit_cl (bad-multisync-extension)
[49.447522] Subtest bad-multisync-extension: SKIP (0.000s)
[49.512891] [013/138] (949s left) i915_pm_rpm (debugfs-forcewake-user)
[51.234897] Starting subtest: debugfs-forcewake-user
[61.962531] Subtest debugfs-forcewake-user: SUCCESS (10.730s)
[62.437185] [014/138] (936s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[62.684168] Starting subtest: compare-crc-sanitycheck-nv12
[62.685616] Starting dynamic subtest: pipe-A-DP-1
[62.688247] Dynamic subtest pipe-A-DP-1: SKIP (0.001s)
[62.702985] Starting dynamic subtest: pipe-B-DP-1
[62.705498] Dynamic subtest pipe-B-DP-1: SKIP (0.001s)
[62.719200] Starting dynamic subtest: pipe-C-DP-1
[62.721698] Dynamic subtest pipe-C-DP-1: SKIP (0.002s)
[62.724111] Subtest compare-crc-sanitycheck-nv12: SKIP (0.014s)
[62.766198] [015/138] (935s left) kms_ccs (pipe-c-random-ccs-data-4-tiled-dg2-mc-ccs)
[63.215252] Starting subtest: pipe-C-random-ccs-data-4-tiled-dg2-mc-ccs
[63.218965] Subtest pipe-C-random-ccs-data-4-tiled-dg2-mc-ccs: SKIP (0.000s)
[63.552562] [016/138] (935s left) prime_mmap_coherency (ioctl-errors)
[63.700429] Starting subtest: ioctl-errors
[80.117631] Subtest ioctl-errors: SUCCESS (16.416s)
[80.227289] [017/138] (918s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[80.606461] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[80.608968] Subtest psr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[80.706266] [018/138] (918s left) gem_mmap_wc (bad-size)
[80.845782] Starting subtest: bad-size
[80.847444] Subtest bad-size: SUCCESS (0.001s)
[80.938119] [019/138] (917s left) gem_exec_schedule (timeslicing)
[81.127307] Starting subtest: timeslicing
[81.128767] Starting dynamic subtest: rcs0
[81.137291] Dynamic subtest rcs0: SUCCESS (0.010s)
[81.164793] Starting dynamic subtest: bcs0
[81.166628] Dynamic subtest bcs0: SUCCESS (0.008s)
[81.183377] Starting dynamic subtest: vcs0
[81.184665] Dynamic subtest vcs0: SUCCESS (0.006s)
[81.200655] Starting dynamic subtest: vecs0
[81.201935] Dynamic subtest vecs0: SUCCESS (0.008s)
[81.203288] Subtest timeslicing: SUCCESS (0.034s)
[81.506206] [020/138] (917s left) kms_ccs (pipe-b-random-ccs-data-y-tiled-ccs)
[81.903650] Starting subtest: pipe-B-random-ccs-data-y-tiled-ccs
[82.361611] Subtest pipe-B-random-ccs-data-y-tiled-ccs: SUCCESS (0.456s)
[82.839988] [021/138] (915s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[83.085211] Starting subtest: fbc-indfb-scaledprimary
[86.257424] Subtest fbc-indfb-scaledprimary: SUCCESS (3.171s)
[86.390098] [022/138] (912s left) gen9_exec_parse (bb-start-param)
[86.556203] Starting subtest: bb-start-param
[86.561242] Subtest bb-start-param: SUCCESS (0.005s)
[86.772572] [023/138] (912s left) gem_mmap_gtt (cpuset-basic-small-copy)
[86.913164] Starting subtest: cpuset-basic-small-copy
[92.284923] Subtest cpuset-basic-small-copy: SUCCESS (5.369s)
[92.391435] [024/138] (906s left) kms_psr2_sf (plane-move-sf-dmg-area)
[92.654492] Subtest plane-move-sf-dmg-area: SKIP (0.000s)
[92.706163] [025/138] (906s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-submi
[92.859453] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-submitted
[92.962176] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-submitted: SUCCESS (0.102s)
[93.040274] [026/138] (905s left) gem_exec_store (pages)
[93.207037] Starting subtest: pages
[93.208435] Starting dynamic subtest: rcs0
[93.301727] Dynamic subtest rcs0: SUCCESS (0.095s)
[93.323238] Starting dynamic subtest: bcs0
[93.392777] Dynamic subtest bcs0: SUCCESS (0.090s)
[93.410224] Starting dynamic subtest: vcs0
[93.484622] Dynamic subtest vcs0: SUCCESS (0.089s)
[93.502275] Starting dynamic subtest: vecs0
[93.574126] Dynamic subtest vecs0: SUCCESS (0.091s)
[93.575936] Subtest pages: SUCCESS (0.368s)
[93.776612] [027/138] (905s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[94.031473] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[94.034127] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[94.122040] [028/138] (904s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[94.362674] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[94.365293] Subtest psr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[94.455633] [029/138] (904s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[94.693440] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[94.697748] Starting dynamic subtest: A-DP1
[105.720289] Dynamic subtest A-DP1: SUCCESS (11.023s)
[105.737685] Starting dynamic subtest: B-DP1
[116.428299] Dynamic subtest B-DP1: SUCCESS (10.702s)
[116.447470] Starting dynamic subtest: C-DP1
[127.142462] Dynamic subtest C-DP1: SUCCESS (10.715s)
[127.144122] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (32.449s)
[127.554456] [030/138] (871s left) kms_ccs (pipe-a-random-ccs-data-y-tiled-gen12-mc-ccs)
[127.911162] Starting subtest: pipe-A-random-ccs-data-y-tiled-gen12-mc-ccs
[127.914989] Subtest pipe-A-random-ccs-data-y-tiled-gen12-mc-ccs: SKIP (0.000s)
[128.366164] [031/138] (870s left) gem_flink_basic (flink-lifetime)
[128.503497] Starting subtest: flink-lifetime
[128.506221] Subtest flink-lifetime: SUCCESS (0.003s)
[128.598189] [032/138] (870s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt)
[128.846745] Starting subtest: fbcpsr-farfromfence-mmap-gtt
[128.849587] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s)
[128.933921] [033/138] (870s left) syncobj_wait (invalid-multi-wait-all-unsubmitted)
[129.079932] Starting subtest: invalid-multi-wait-all-unsubmitted
[129.081384] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[129.166356] [034/138] (870s left) kms_chamelium_hpd (dp-hpd-fast)
[129.494327] Subtest dp-hpd-fast: SKIP (0.000s)
[129.776328] [035/138] (869s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[130.023152] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt
[130.025900] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[130.119759] [036/138] (869s left) perf (invalid-oa-metric-set-id)
[130.399688] Starting subtest: invalid-oa-metric-set-id
[130.458761] Subtest invalid-oa-metric-set-id: SUCCESS (0.058s)
[130.502933] [037/138] (868s left) gem_eio (in-flight-internal-immediate)
[130.673236] Starting subtest: in-flight-internal-immediate
[130.762326] Subtest in-flight-internal-immediate: SUCCESS (0.088s)
[130.971078] [038/138] (868s left) kms_cursor_crc (cursor-onscreen-64x21)
[131.215376] Starting subtest: cursor-onscreen-64x21
[131.225669] Starting dynamic subtest: pipe-A-DP-1
[133.563667] Dynamic subtest pipe-A-DP-1: SUCCESS (2.336s)
[133.641805] Starting dynamic subtest: pipe-C-DP-1
[136.064744] Dynamic subtest pipe-C-DP-1: SUCCESS (2.436s)
[136.066413] Subtest cursor-onscreen-64x21: SUCCESS (4.847s)
[136.416186] [039/138] (863s left) i915_pm_rpm (gem-pread)
[138.147558] Starting subtest: gem-pread
[139.060255] Subtest gem-pread: SUCCESS (0.911s)
[139.240601] [040/138] (860s left) kms_feature_discovery (display)
[139.472945] Starting subtest: display
[139.474438] Subtest display: SUCCESS (0.000s)
[139.556261] [041/138] (860s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-move)
[139.796898] Starting subtest: fbc-2p-scndscrn-spr-indfb-move
[139.799395] Subtest fbc-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[139.889201] [042/138] (859s left) gem_exec_schedule (preempt-contexts)
[140.064055] Starting subtest: preempt-contexts
[140.065756] Starting dynamic subtest: rcs0
[140.193469] Dynamic subtest rcs0: SUCCESS (0.128s)
[140.215835] Starting dynamic subtest: bcs0
[140.336585] Dynamic subtest bcs0: SUCCESS (0.141s)
[140.357142] Starting dynamic subtest: vcs0
[140.459973] Dynamic subtest vcs0: SUCCESS (0.121s)
[140.481731] Starting dynamic subtest: vecs0
[140.581489] Dynamic subtest vecs0: SUCCESS (0.123s)
[140.584206] Subtest preempt-contexts: SUCCESS (0.518s)
[140.939844] [043/138] (858s left) kms_rotation_crc (primary-x-tiled-reflect-x-0)
[141.179300] Starting subtest: primary-x-tiled-reflect-x-0
[141.185028] Subtest primary-x-tiled-reflect-x-0: SKIP (0.003s)
[141.271968] [044/138] (858s left) gem_render_tiled_blits (basic)
[141.424672] Starting subtest: basic
[141.528630] Subtest basic: SUCCESS (0.103s)
[141.590064] [045/138] (858s left) gem_ctx_persistence (legacy-engines-hang)
[141.737149] Starting subtest: legacy-engines-hang
[141.738779] Starting dynamic subtest: render
[141.778377] Dynamic subtest render: SUCCESS (0.037s)
[141.802339] Starting dynamic subtest: bsd
[141.820045] Dynamic subtest bsd: SUCCESS (0.041s)
[141.838678] Starting dynamic subtest: blt
[141.841804] Dynamic subtest blt: SKIP (0.005s)
[141.859861] Starting dynamic subtest: vebox
[141.873888] Dynamic subtest vebox: SUCCESS (0.044s)
[141.875209] Subtest legacy-engines-hang: SUCCESS (0.135s)
[141.958515] [046/138] (857s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[142.418905] Starting subtest: pipe-C-crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[142.424219] Subtest pipe-C-crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SKIP (0.004s)
[142.733822] [047/138] (857s left) kms_chamelium_color (ctm-max)
[142.979384] Subtest ctm-max: SKIP (0.000s)
[143.035426] [048/138] (856s left) kms_writeback (writeback-invalid-parameters)
[143.376745] Subtest writeback-invalid-parameters: SKIP (0.000s)
[143.434795] [049/138] (856s left) gem_vm_create (invalid-create)
[143.579129] Starting subtest: invalid-create
[143.580555] Subtest invalid-create: SUCCESS (0.001s)
[143.667534] [050/138] (856s left) gem_mmap_gtt (basic-write-read)
[143.806237] Starting subtest: basic-write-read
[143.839205] Subtest basic-write-read: SUCCESS (0.034s)
[143.932932] [051/138] (856s left) gem_cs_tlb (engines)
[144.082257] Starting subtest: engines
[144.083599] Starting dynamic subtest: rcs0
[146.246783] Dynamic subtest rcs0: SUCCESS (2.163s)
[146.265871] Starting dynamic subtest: bcs0
[148.420136] Dynamic subtest bcs0: SUCCESS (2.169s)
[148.443500] Starting dynamic subtest: vcs0
[150.583281] Dynamic subtest vcs0: SUCCESS (2.164s)
[150.601920] Starting dynamic subtest: vecs0
[152.745874] Dynamic subtest vecs0: SUCCESS (2.163s)
[152.747519] Subtest engines: SUCCESS (8.663s)
[152.849659] [052/138] (847s left) gem_eio (wait-1us)
[153.008466] Starting subtest: wait-1us
[153.083594] Subtest wait-1us: SUCCESS (0.079s)
[153.287163] [053/138] (846s left) kms_big_fb (yf-tiled-32bpp-rotate-270)
[153.548885] Starting subtest: yf-tiled-32bpp-rotate-270
[158.547472] Subtest yf-tiled-32bpp-rotate-270: SUCCESS (4.997s)
[159.069393] [054/138] (841s left) api_intel_bb (lot-of-buffers)