[54.938734] Initializing watchdogs
[54.939299]   /dev/watchdog0
[54.972694] [001/141] (960s left) gem_spin_batch (resubmit)
[55.436475] Starting subtest: resubmit
[55.438866] Starting dynamic subtest: rcs0
[55.476951] Dynamic subtest rcs0: SUCCESS (0.038s)
[55.508829] Starting dynamic subtest: bcs0
[55.512746] Dynamic subtest bcs0: SUCCESS (0.010s)
[55.542824] Starting dynamic subtest: vcs0
[55.546306] Dynamic subtest vcs0: SUCCESS (0.006s)
[55.573821] Starting dynamic subtest: vcs1
[55.577576] Dynamic subtest vcs1: SUCCESS (0.011s)
[55.604551] Starting dynamic subtest: vecs0
[55.607849] Dynamic subtest vecs0: SUCCESS (0.010s)
[55.634064] Starting dynamic subtest: ccs0
[55.637486] Dynamic subtest ccs0: SUCCESS (0.007s)
[55.641514] Subtest resubmit: SUCCESS (0.088s)
[57.692299] [002/141] (957s left) device_reset (unbind-cold-reset-rebind)
[58.499044] Subtest unbind-cold-reset-rebind: SKIP (0.000s)
[58.633766] [003/141] (956s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[59.157379] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt
[59.163936] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[59.295382] [004/141] (956s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[59.727501] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-gtt
[59.734514] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[59.853081] [005/141] (955s left) gem_ctx_exec (basic-invalid-context)
[60.237267] Starting subtest: basic-invalid-context
[60.256106] Subtest basic-invalid-context: SUCCESS (0.019s)
[60.388715] [006/141] (955s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[60.921383] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[60.928212] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[61.070126] [007/141] (954s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-blt)
[61.564352] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-blt
[61.570219] Subtest fbc-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[61.715797] [008/141] (954s left) gem_ccs (block-multicopy-inplace)
[62.104084] Starting subtest: block-multicopy-inplace
[62.110910] Subtest block-multicopy-inplace: SKIP (0.000s)
[62.220318] [009/141] (953s left) kms_content_protection (uevent)
[62.926183] Starting subtest: uevent
[67.117789] Subtest uevent: SKIP (4.188s)
[68.860628] [010/141] (947s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[69.436366] Starting subtest: short-flip-before-cursor-toggle
[71.069278] Subtest short-flip-before-cursor-toggle: SUCCESS (1.628s)
[71.955096] [011/141] (944s left) kms_cursor_crc (cursor-rapid-movement-256x85)
[73.040377] Starting subtest: cursor-rapid-movement-256x85
[73.071888] Starting dynamic subtest: pipe-A-eDP-1
[74.409981] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.339s)
[74.600746] Starting dynamic subtest: pipe-D-eDP-1
[76.939105] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.371s)
[76.942903] Subtest cursor-rapid-movement-256x85: SUCCESS (3.897s)
[77.894363] [012/141] (938s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[78.399223] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc
[94.814831] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (16.420s)
[95.074548] [013/141] (921s left) kms_psr (psr2_sprite_render)
[95.561514] Starting subtest: psr2_sprite_render
[97.847732] Subtest psr2_sprite_render: SUCCESS (2.282s)
[98.782200] [014/141] (917s left) kms_getfb (getfb-handle-protection)
[99.160467] Starting subtest: getfb-handle-protection
[99.222256] Subtest getfb-handle-protection: SUCCESS (0.066s)
[99.385473] [015/141] (917s left) gem_mmap_gtt (cpuset-medium-copy)
[99.747569] Subtest cpuset-medium-copy: SKIP (0.000s)
[99.852297] [016/141] (916s left) syncobj_timeline (wait-for-submit-complex)
[100.224522] Starting subtest: wait-for-submit-complex
[100.289637] Subtest wait-for-submit-complex: SUCCESS (0.065s)
[100.416785] [017/141] (916s left) kms_vblank (pipe-b-ts-continuation-idle)
[100.907208] Starting subtest: pipe-B-ts-continuation-idle
[102.669085] Subtest pipe-B-ts-continuation-idle: SUCCESS (1.763s)
[103.605911] [018/141] (913s left) kms_vblank (pipe-d-ts-continuation-dpms-suspend)
[104.077915] Starting subtest: pipe-D-ts-continuation-dpms-suspend
[123.829980] Subtest pipe-D-ts-continuation-dpms-suspend: SUCCESS (19.755s)
[124.780678] [019/141] (891s left) gem_flink_basic (double-flink)
[125.145441] Starting subtest: double-flink
[125.148965] Subtest double-flink: SUCCESS (0.000s)
[125.309989] [020/141] (891s left) kms_flip (2x-wf_vblank-ts-check)
[125.693835] Starting subtest: 2x-wf_vblank-ts-check
[125.851168] Subtest 2x-wf_vblank-ts-check: SKIP (0.159s)
[126.037552] [021/141] (890s left) gem_fence_thrash (bo-write-verify-threaded-x)
[126.391307] Starting subtest: bo-write-verify-threaded-x
[126.406828] Subtest bo-write-verify-threaded-x: SKIP (0.008s)
[126.503687] [022/141] (890s left) kms_plane_multiple (tiling-4)
[126.978333] Starting subtest: tiling-4
[126.981823] Starting dynamic subtest: pipe-A-eDP-1
[131.693427] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.713s)
[131.733935] Starting dynamic subtest: pipe-B-eDP-1
[137.111790] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.411s)
[137.171594] Starting dynamic subtest: pipe-C-eDP-1
[142.527963] Dynamic subtest pipe-C-eDP-1: SUCCESS (5.406s)
[142.576387] Starting dynamic subtest: pipe-D-eDP-1
[147.733156] Dynamic subtest pipe-D-eDP-1: SUCCESS (5.194s)
[147.736813] Subtest tiling-4: SUCCESS (20.756s)
[148.665395] [023/141] (868s left) i915_pm_rpm (legacy-planes-dpms)
[154.738787] Starting subtest: legacy-planes-dpms
[154.742802] Starting dynamic subtest: plane-40
[179.273317] Dynamic subtest plane-40: SUCCESS (24.534s)
[179.312615] Starting dynamic subtest: plane-49
[203.934420] Dynamic subtest plane-49: SUCCESS (24.658s)
[203.977995] Starting dynamic subtest: plane-58
[228.700501] Dynamic subtest plane-58: SUCCESS (24.762s)
[228.738719] Starting dynamic subtest: plane-67
[257.105882] Dynamic subtest plane-67: SUCCESS (28.402s)
[257.109556] Subtest legacy-planes-dpms: SUCCESS (102.370s)
[258.402446] [024/141] (758s left) kms_ccs (pipe-a-crc-primary-basic-4_tiled_dg2_mc_ccs)
[259.328284] Starting subtest: pipe-A-crc-primary-basic-4_tiled_dg2_mc_ccs
[259.333824] Subtest pipe-A-crc-primary-basic-4_tiled_dg2_mc_ccs: SKIP (0.002s)
[260.804487] [025/141] (756s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled)
[261.185520] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[261.188574] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.003s)
[261.331564] [026/141] (755s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-pwrite)
[261.852183] Starting subtest: psr-1p-primscrn-spr-indfb-draw-pwrite
[272.176755] Subtest psr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (10.332s)
[272.463652] [027/141] (744s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[272.965825] Starting subtest: read-crc-frame-sequence
[272.973450] Starting dynamic subtest: pipe-A-eDP-1
[274.202546] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.228s)
[274.246928] Starting dynamic subtest: pipe-B-eDP-1
[276.137131] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.920s)
[276.180353] Starting dynamic subtest: pipe-C-eDP-1
[278.397221] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.244s)
[278.439937] Starting dynamic subtest: pipe-D-eDP-1
[280.092088] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.690s)
[280.096891] Subtest read-crc-frame-sequence: SUCCESS (7.123s)
[281.026332] [028/141] (736s left) kms_big_fb (4-tiled-16bpp-rotate-90)
[281.580399] Starting subtest: 4-tiled-16bpp-rotate-90
[281.648196] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.060s)
[281.828138] [029/141] (735s left) api_intel_allocator (execbuf-with-allocator)
[282.194486] Starting subtest: execbuf-with-allocator
[282.211565] Subtest execbuf-with-allocator: SUCCESS (0.018s)
[282.379650] [030/141] (735s left) kms_big_fb (y-tiled-32bpp-rotate-270)
[282.911590] Starting subtest: y-tiled-32bpp-rotate-270
[282.918718] Subtest y-tiled-32bpp-rotate-270: SKIP (0.000s)
[283.078285] [031/141] (734s left) gem_exec_whisper (basic-normal)
[283.497608] Starting subtest: basic-normal
[309.228310] Subtest basic-normal: FAIL (25.712s)
[309.716570] [032/141] (708s left) gem_exec_schedule (noreorder-corked)
[310.318373] Starting subtest: noreorder-corked
[310.321985] Starting dynamic subtest: rcs0
[311.493092] Dynamic subtest rcs0: SUCCESS (1.174s)
[311.541602] Starting dynamic subtest: bcs0
[312.653145] Dynamic subtest bcs0: SUCCESS (1.155s)
[312.705738] Starting dynamic subtest: vcs0
[314.561774] Dynamic subtest vcs0: SUCCESS (1.902s)
[314.602499] Starting dynamic subtest: vcs1
[316.484117] Dynamic subtest vcs1: SUCCESS (1.918s)
[316.526431] Starting dynamic subtest: vecs0
[318.533822] Dynamic subtest vecs0: SUCCESS (2.045s)
[318.575412] Starting dynamic subtest: ccs0
[319.667713] Dynamic subtest ccs0: SUCCESS (1.131s)
[319.671995] Subtest noreorder-corked: SUCCESS (9.352s)
[319.978374] [033/141] (697s left) gem_wait (write-busy)
[320.425488] Starting subtest: write-busy
[320.429251] Starting dynamic subtest: all-engines
[321.157393] Dynamic subtest all-engines: SUCCESS (0.730s)
[321.220182] Starting dynamic subtest: rcs0
[321.729576] Dynamic subtest rcs0: SUCCESS (0.568s)
[321.788739] Starting dynamic subtest: bcs0
[322.297601] Dynamic subtest bcs0: SUCCESS (0.564s)
[322.355104] Starting dynamic subtest: vcs0
[323.499106] Dynamic subtest vcs0: SUCCESS (1.196s)
[323.555570] Starting dynamic subtest: vcs1
[325.482695] Dynamic subtest vcs1: SUCCESS (1.978s)
[325.539959] Starting dynamic subtest: vecs0
[327.528474] Dynamic subtest vecs0: SUCCESS (2.043s)
[327.585346] Starting dynamic subtest: ccs0
[328.101483] Dynamic subtest ccs0: SUCCESS (0.569s)
[328.105231] Subtest write-busy: SUCCESS (7.679s)
[328.492604] [034/141] (689s left) kms_ccs (pipe-b-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc)
[329.808296] Starting subtest: pipe-B-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc
[329.824221] Subtest pipe-B-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc: SKIP (0.013s)
[330.874825] [035/141] (687s left) kms_chamelium_color (ctm-red-to-blue)
[331.348423] Subtest ctm-red-to-blue: SKIP (0.000s)
[331.464661] [036/141] (686s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[331.976656] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[331.984313] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[332.144570] [037/141] (686s left) gem_exec_schedule (semaphore-power)
[332.733347] Subtest semaphore-power: SKIP (0.000s)
[332.995549] [038/141] (685s left) kms_lease (lease_invalid_connector)
[333.469949] Starting subtest: lease_invalid_connector
[333.478858] Starting dynamic subtest: pipe-A-eDP-1
[333.482214] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[333.528505] Starting dynamic subtest: pipe-B-eDP-1
[333.533168] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[333.578330] Starting dynamic subtest: pipe-C-eDP-1
[333.581639] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[333.604263] Starting dynamic subtest: pipe-D-eDP-1
[333.607428] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[333.610568] Subtest lease_invalid_connector: SUCCESS (0.037s)
[333.652313] [039/141] (684s left) vc4/vc4_purgeable_bo (mark-purgeable)
[334.125455] Subtest mark-purgeable: SKIP (0.000s)
[334.257383] [040/141] (684s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[334.628376] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[334.735283] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.108s)
[334.884541] [041/141] (683s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[335.395748] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[335.402267] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[335.571161] [042/141] (682s left) gem_ctx_persistence (legacy-engines-hostile)
[335.962608] Starting subtest: legacy-engines-hostile
[335.971778] Starting dynamic subtest: render
[336.047098] Dynamic subtest render: SUCCESS (0.080s)
[336.098086] Starting dynamic subtest: bsd1
[336.101594] Dynamic subtest bsd1: SUCCESS (0.037s)
[336.144888] Starting dynamic subtest: bsd2
[336.158932] Dynamic subtest bsd2: SUCCESS (0.066s)
[336.207236] Starting dynamic subtest: blt
[336.217533] Dynamic subtest blt: SUCCESS (0.053s)
[336.265089] Starting dynamic subtest: vebox
[336.268489] Dynamic subtest vebox: SUCCESS (0.030s)
[336.271884] Subtest legacy-engines-hostile: SUCCESS (0.291s)
[336.434818] [043/141] (682s left) kms_ccs (pipe-b-crc-primary-basic-y_tiled_gen12_mc_ccs)
[337.576662] Starting subtest: pipe-B-crc-primary-basic-y_tiled_gen12_mc_ccs
[337.582729] Subtest pipe-B-crc-primary-basic-y_tiled_gen12_mc_ccs: SKIP (0.002s)
[338.810109] [044/141] (679s left) gem_flink_race (flink_close)
[339.175748] Starting subtest: flink_close
[344.262594] Subtest flink_close: SUCCESS (5.084s)
[344.419716] [045/141] (674s left) gem_exec_reloc (basic-cpu-wc)
[344.821317] Subtest basic-cpu-wc: SKIP (0.000s)
[344.979224] [046/141] (673s left) kms_addfb_basic (unused-modifier)
[345.358692] Starting subtest: unused-modifier
[345.362188] Subtest unused-modifier: SUCCESS (0.000s)
[345.634308] [047/141] (673s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[346.004900] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[346.008174] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[346.139336] [048/141] (672s left) syncobj_wait (wait-all-for-submit-delayed-submit)
[346.508836] Starting subtest: wait-all-for-submit-delayed-submit
[346.613934] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.106s)
[346.775603] [049/141] (672s left) gem_render_copy (yf-tiled-to-vebox-x-tiled)
[347.179351] Starting subtest: yf-tiled-to-vebox-x-tiled
[347.185560] Subtest yf-tiled-to-vebox-x-tiled: SKIP (0.000s)
[347.456652] [050/141] (671s left) kms_ccs (pipe-d-missing-ccs-buffer-4_tiled_mtl_mc_ccs)
[349.087250] Starting subtest: pipe-D-missing-ccs-buffer-4_tiled_mtl_mc_ccs
[349.226119] Subtest pipe-D-missing-ccs-buffer-4_tiled_mtl_mc_ccs: SUCCESS (0.134s)
[349.850357] [051/141] (669s left) kms_vblank (pipe-a-wait-forked)
[350.323323] Starting subtest: pipe-A-wait-forked
[352.802288] Subtest pipe-A-wait-forked: SUCCESS (2.478s)
[353.747440] [052/141] (665s left) kms_ccs (pipe-d-crc-primary-rotation-180-4_tiled_dg2_rc_ccs)
[355.442171] Starting subtest: pipe-D-crc-primary-rotation-180-4_tiled_dg2_rc_ccs
[355.448371] Subtest pipe-D-crc-primary-rotation-180-4_tiled_dg2_rc_ccs: SKIP (0.002s)
[356.150893] [053/141] (663s left) kms_cursor_crc (cursor-onscreen-32x32)
[356.791048] Starting subtest: cursor-onscreen-32x32
[357.087521] Subtest cursor-onscreen-32x32: SKIP (0.291s)
[358.014193] [054/141] (661s left) gem_exec_balancer (parallel-ordering)
[359.241085] Starting subtest: parallel-ordering
[366.613184] Subtest parallel-ordering: SUCCESS (7.372s)
[366.818482] [055/141] (652s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[367.337895] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[367.345277] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[367.520152] [056/141] (652s left) gem_mmap_offset (isolation)
[367.901973] Starting subtest: isolation
[367.935469] Subtest isolation: SUCCESS (0.026s)
[368.102171] [057/141] (651s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[368.609071] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[368.615825] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[368.776098] [058/141] (651s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-msflip-blt)
[369.279472] Starting subtest: psr-2p-scndscrn-shrfb-msflip-blt
[369.286397] Subtest psr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[369.442220] [059/141] (650s left) kms_fbcon_fbt (psr)
[369.845965] Starting subtest: psr
[375.581280] Subtest psr: SUCCESS (5.733s)
[375.779302] [060/141] (644s left) gem_eio (create)
[377.292339] Starting subtest: create
[379.480422] Subtest create: SUCCESS (2.186s)
[380.661446] [061/141] (639s left) core_setmaster_vs_auth
[381.181144] [062/141] (639s left) api_intel_bb (object-noreloc-keep-cache-simple)
[381.554475] Starting subtest: object-noreloc-keep-cache-simple
[381.569514] Subtest object-noreloc-keep-cache-simple: SUCCESS (0.019s)
[381.748636] [063/141] (638s left) gem_pread (uncached)
[382.117857] Subtest uncached: SKIP (0.000s)
[382.254667] [064/141] (638s left) gem_exec_reloc (basic-gtt)
[382.647307] Subtest basic-gtt: SKIP (0.000s)
[382.785671] [065/141] (637s left) gem_mmap_gtt (medium-copy)
[383.157976] Subtest medium-copy: SKIP (0.000s)
[383.287860] [066/141] (637s left) gem_mmap_wc (invalid-flags)
[383.655389] Subtest invalid-flags: SKIP (0.000s)
[383.787246] [067/141] (636s left) kms_frontbuffer_tracking (fbcpsr-1p-indfb-fliptrack-mmap-gtt)
[384.325237] Starting subtest: fbcpsr-1p-indfb-fliptrack-mmap-gtt
[384.332213] Subtest fbcpsr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[384.499383] [068/141] (636s left) kms_psr2_sf (cursor-plane-move-continuous-exceed-sf)
[391.869828] Starting subtest: cursor-plane-move-continuous-exceed-sf
[391.878549] Starting dynamic subtest: pipe-A-eDP-1
[403.507575] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.631s)
[403.567204] Starting dynamic subtest: pipe-B-eDP-1
[415.096928] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.578s)
[415.100556] Subtest cursor-plane-move-continuous-exceed-sf: SUCCESS (23.230s)
[416.043911] [069/141] (604s left) kms_big_fb (linear-8bpp-rotate-180)
[416.585389] Starting subtest: linear-8bpp-rotate-180
[420.654626] Subtest linear-8bpp-rotate-180: SUCCESS (4.069s)
[421.003970] [070/141] (599s left) v3d/v3d_submit_cl (multi-and-single-sync)
[421.487654] Subtest multi-and-single-sync: SKIP (0.000s)
[421.628485] [071/141] (599s left) gem_mmap_gtt (basic-copy)
[421.995971] Subtest basic-copy: SKIP (0.000s)
[422.120397] [072/141] (599s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[422.680405] Starting subtest: basic-flip-after-cursor-legacy
[423.995860] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.312s)
[424.953719] [073/141] (596s left) gem_exec_schedule (deep)
[425.550425] Starting subtest: deep
[425.554428] Starting dynamic subtest: rcs0
[451.839776] Dynamic subtest rcs0: SUCCESS (26.282s)
[451.890715] Starting dynamic subtest: bcs0
[480.698362] Dynamic subtest bcs0: SUCCESS (28.860s)
[480.737652] Starting dynamic subtest: vcs0
[506.609515] Dynamic subtest vcs0: SUCCESS (25.907s)
[506.627794] Starting dynamic subtest: vcs1
[532.366346] Dynamic subtest vcs1: SUCCESS (25.753s)
[532.415321] Starting dynamic subtest: vecs0
[578.267214] Dynamic subtest vecs0: FAIL (45.708s)
[578.317381] Starting dynamic subtest: ccs0
[607.769077] Dynamic subtest ccs0: SUCCESS (29.680s)
[607.774772] Subtest deep: FAIL (182.217s)
[611.077533] [074/141] (410s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-pgflip-blt)
[611.593897] Starting subtest: fbc-1p-primscrn-shrfb-pgflip-blt
[625.691987] Subtest fbc-1p-primscrn-shrfb-pgflip-blt: SUCCESS (14.100s)
[625.973896] [075/141] (395s left) sw_sync (sync_random_merge)
[626.343073] Starting subtest: sync_random_merge
[626.619294] Subtest sync_random_merge: SUCCESS (0.275s)
[626.735993] [076/141] (394s left) vc4/vc4_dmabuf_poll (poll-read-waits-until-write-done)
[627.211777] Subtest poll-read-waits-until-write-done: SKIP (0.000s)
[627.352939] [077/141] (394s left) fbdev (unaligned-read)
[627.728715] Starting subtest: unaligned-read
[627.786359] Subtest unaligned-read: SUCCESS (0.057s)
[627.960273] [078/141] (393s left) kms_vblank (pipe-a-wait-busy-hang)
[628.435873] Starting subtest: pipe-A-wait-busy-hang
[642.856613] Subtest pipe-A-wait-busy-hang: SUCCESS (14.421s)
[643.813999] [079/141] (377s left) gem_set_tiling_vs_blt (untiled-to-tiled)
[644.220820] Subtest untiled-to-tiled: SKIP (0.000s)
[644.342646] [080/141] (377s left) kms_ccs (pipe-c-bad-pixel-format-4_tiled_dg2_rc_ccs)
[645.637421] Starting subtest: pipe-C-bad-pixel-format-4_tiled_dg2_rc_ccs
[645.644285] Subtest pipe-C-bad-pixel-format-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[646.691437] [081/141] (375s left) kms_psr (psr2_primary_blt)
[647.182982] Starting subtest: psr2_primary_blt
[650.488680] Subtest psr2_primary_blt: SUCCESS (3.296s)
[651.428561] [082/141] (370s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[651.917708] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[651.924480] Starting dynamic subtest: pipe-A-default-mode
[653.466384] Dynamic subtest pipe-A-default-mode: SKIP (1.533s)
[653.473302] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.547s)
[653.756534] [083/141] (368s left) syncobj_wait (invalid-wait-zero-handles)
[654.150657] Starting subtest: invalid-wait-zero-handles
[654.154833] Subtest invalid-wait-zero-handles: SUCCESS (0.000s)
[654.314368] [084/141] (367s left) kms_big_fb (yf-tiled-addfb-size-overflow)
[654.852552] Starting subtest: yf-tiled-addfb-size-overflow
[654.859352] Subtest yf-tiled-addfb-size-overflow: SKIP (0.000s)
[655.006225] [085/141] (367s left) kms_writeback (writeback-check-output)
[655.591514] Subtest writeback-check-output: SKIP (0.000s)
[655.716657] [086/141] (366s left) kms_ccs (pipe-b-random-ccs-data-yf_tiled_ccs)
[656.939850] Starting subtest: pipe-B-random-ccs-data-yf_tiled_ccs
[656.948870] Subtest pipe-B-random-ccs-data-yf_tiled_ccs: SKIP (0.000s)
[658.099257] [087/141] (364s left) gem_userptr_blits (stress-mm-invalidate-close)
[658.562822] Starting subtest: stress-mm-invalidate-close
[660.818144] Subtest stress-mm-invalidate-close: SUCCESS (2.253s)
[661.247122] [088/141] (361s left) kms_ccs (pipe-c-random-ccs-data-4_tiled_dg2_mc_ccs)
[662.690819] Starting subtest: pipe-C-random-ccs-data-4_tiled_dg2_mc_ccs
[662.700456] Subtest pipe-C-random-ccs-data-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[663.637605] [089/141] (359s left) syncobj_basic (bad-fd-to-handle)
[664.018144] Starting subtest: bad-fd-to-handle
[664.021739] Subtest bad-fd-to-handle: SUCCESS (0.000s)
[664.173395] [090/141] (358s left) gem_pxp (reject-modify-context-protection-on)
[667.076554] Subtest reject-modify-context-protection-on: SKIP (0.000s)
[667.254184] [091/141] (355s left) syncobj_wait (multi-wait-for-submit-submitted)
[667.640938] Starting subtest: multi-wait-for-submit-submitted
[667.751519] Subtest multi-wait-for-submit-submitted: SUCCESS (0.110s)
[667.938975] [092/141] (355s left) kms_ccs (pipe-d-bad-aux-stride-y_tiled_gen12_rc_ccs)
[669.734452] Starting subtest: pipe-D-bad-aux-stride-y_tiled_gen12_rc_ccs
[669.740706] Subtest pipe-D-bad-aux-stride-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[670.357932] [093/141] (352s left) kms_ccs (pipe-d-bad-rotation-90-4_tiled_dg2_rc_ccs)
[671.973636] Starting subtest: pipe-D-bad-rotation-90-4_tiled_dg2_rc_ccs
[671.980249] Subtest pipe-D-bad-rotation-90-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[672.744971] [094/141] (350s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[673.161750] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[673.328616] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.163s)
[673.504186] [095/141] (349s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[673.992974] Starting subtest: addfb25-y-tiled-legacy
[673.996858] Subtest addfb25-y-tiled-legacy: SUCCESS (0.002s)
[674.169492] [096/141] (349s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[674.700206] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[677.582995] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (2.879s)
[677.880901] [097/141] (345s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[678.371594] Starting subtest: bo-too-small-due-to-tiling
[678.378584] Subtest bo-too-small-due-to-tiling: SKIP (0.000s)
[678.549899] [098/141] (345s left) gem_gpgpu_fill (basic)
[678.932367] Starting subtest: basic
[678.935876] Starting dynamic subtest: smem
[679.071738] Dynamic subtest smem: FAIL (0.069s)
[679.075289] Subtest basic: FAIL (0.073s)
[679.179217] [099/141] (344s left) gem_ctx_persistence (smoketest)
[679.587764] Starting subtest: smoketest
[711.926205] Subtest smoketest: SUCCESS (32.337s)
[712.120765] [100/141] (311s left) gem_linear_blits (interruptible)
[716.437127] Starting subtest: interruptible
[730.976946] Subtest interruptible: SUCCESS (14.540s)
[731.275244] [101/141] (292s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-blt)
[731.941427] Starting subtest: fbcpsr-rgb101010-draw-blt
[737.780067] Subtest fbcpsr-rgb101010-draw-blt: SUCCESS (5.842s)
[737.984180] [102/141] (285s left) kms_ccs (pipe-c-crc-primary-basic-y_tiled_gen12_rc_ccs)
[739.168296] Starting subtest: pipe-C-crc-primary-basic-y_tiled_gen12_rc_ccs
[739.175221] Subtest pipe-C-crc-primary-basic-y_tiled_gen12_rc_ccs: SKIP (0.001s)
[739.796453] [103/141] (284s left) syncobj_timeline (invalid-query-illegal-handle)
[740.183586] Starting subtest: invalid-query-illegal-handle
[740.187268] Subtest invalid-query-illegal-handle: SUCCESS (0.000s)
[740.342142] [104/141] (283s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[740.844642] Starting subtest: fbc-rgb565-draw-blt
[754.492439] Subtest fbc-rgb565-draw-blt: SUCCESS (13.651s)
[754.806160] [105/141] (269s left) kms_cursor_legacy (cursora-vs-flipb-toggle)
[755.374712] Starting subtest: cursorA-vs-flipB-toggle
[755.385579] Subtest cursorA-vs-flipB-toggle: SKIP (0.008s)
[755.705897] [106/141] (268s left) kms_ccs (pipe-a-bad-aux-stride-y_tiled_gen12_mc_ccs)
[756.754624] Starting subtest: pipe-A-bad-aux-stride-y_tiled_gen12_mc_ccs
[756.761106] Subtest pipe-A-bad-aux-stride-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[758.079230] [107/141] (266s left) kms_flip (2x-absolute-wf_vblank)
[758.486398] Starting subtest: 2x-absolute-wf_vblank
[758.649326] Subtest 2x-absolute-wf_vblank: SKIP (0.163s)
[758.860938] [108/141] (265s left) gem_exec_flush (basic-wb-rw-before-default)
[759.282972] Starting subtest: basic-wb-rw-before-default
[765.167108] Subtest basic-wb-rw-before-default: SUCCESS (5.883s)
[765.478199] [109/141] (259s left) perf (global-sseu-config)
[766.392144] Subtest global-sseu-config: SKIP (0.000s)
[766.507126] [110/141] (258s left) kms_panel_fitting (atomic-fastset)
[766.990405] Starting subtest: atomic-fastset
[767.000183] Starting dynamic subtest: pipe-A-eDP-1
[767.422823] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.426s)
[767.485926] Starting dynamic subtest: pipe-B-eDP-1
[769.010165] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.575s)
[769.070527] Starting dynamic subtest: pipe-C-eDP-1
[770.423136] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.409s)
[770.482109] Starting dynamic subtest: pipe-D-eDP-1
[771.883050] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.456s)
[771.887396] Subtest atomic-fastset: SUCCESS (4.891s)
[773.492050] [111/141] (251s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[774.368703] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[775.247364] [112/141] (249s left) kms_ccs (pipe-b-bad-pixel-format-y_tiled_ccs)
[776.329254] Starting subtest: pipe-B-bad-pixel-format-y_tiled_ccs
[776.335749] Subtest pipe-B-bad-pixel-format-y_tiled_ccs: SKIP (0.000s)
[777.602700] [113/141] (247s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[778.087760] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[778.092331] Starting dynamic subtest: pipe-A-eDP-1
[779.612942] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.521s)
[779.673961] Starting dynamic subtest: pipe-B-eDP-1
[782.449849] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.827s)
[782.513594] Starting dynamic subtest: pipe-C-eDP-1
[785.246808] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.786s)
[785.310282] Starting dynamic subtest: pipe-D-eDP-1
[787.996360] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.739s)
[788.000520] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (9.915s)
[789.554521] [114/141] (235s left) gem_exec_fence (syncobj-backward-timeline-chain-engines)
[791.737664] Starting subtest: syncobj-backward-timeline-chain-engines
[792.083155] Subtest syncobj-backward-timeline-chain-engines: SUCCESS (0.346s)
[811.704599] [115/141] (213s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[812.130466] Starting subtest: y-tiled-to-vebox-x-tiled
[812.137237] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[812.446633] [116/141] (212s left) kms_ccs (pipe-a-missing-ccs-buffer-y_tiled_gen12_rc_ccs)
[813.463973] Starting subtest: pipe-A-missing-ccs-buffer-y_tiled_gen12_rc_ccs
[813.469370] Subtest pipe-A-missing-ccs-buffer-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[814.867281] [117/141] (210s left) drm_fdinfo (all-busy-check-all)
[815.260926] Starting subtest: all-busy-check-all
[815.273074] Subtest all-busy-check-all: SKIP (0.013s)
[815.438513] [118/141] (210s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-plflip-blt)
[815.983880] Starting subtest: fbcpsr-2p-scndscrn-indfb-plflip-blt
[815.990269] Subtest fbcpsr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[816.171821] [119/141] (209s left) kms_flip (flip-vs-suspend)
[816.559249] Starting subtest: flip-vs-suspend
[816.589271] Starting dynamic subtest: A-eDP1
[852.349103] Dynamic subtest A-eDP1: SUCCESS (35.760s)
[852.404843] Starting dynamic subtest: B-eDP1
[887.190793] Dynamic subtest B-eDP1: SUCCESS (34.839s)
[887.244402] Starting dynamic subtest: C-eDP1
[922.400758] Dynamic subtest C-eDP1: SUCCESS (35.205s)
[922.455973] Starting dynamic subtest: D-eDP1
[957.367053] Dynamic subtest D-eDP1: SUCCESS (34.963s)
[957.389567] Subtest flip-vs-suspend: SUCCESS (140.833s)
[958.969841] [120/141] ( 66s left) kms_flip (2x-dpms-vs-vblank-race)
[959.353093] Starting subtest: 2x-dpms-vs-vblank-race
[959.506940] Subtest 2x-dpms-vs-vblank-race: SKIP (0.156s)
[959.716261] [121/141] ( 66s left) prime_busy (before)
[960.089192] Starting subtest: before
[960.119579] Starting dynamic subtest: rcs0
[960.153125] Dynamic subtest rcs0: SUCCESS (0.033s)
[960.204636] Starting dynamic subtest: bcs0
[960.207935] Dynamic subtest bcs0: SUCCESS (0.044s)
[960.257957] Starting dynamic subtest: vcs0
[960.260495] Dynamic subtest vcs0: SUCCESS (0.042s)
[960.304199] Starting dynamic subtest: vcs1
[960.307399] Dynamic subtest vcs1: SUCCESS (0.024s)
[960.347690] Starting dynamic subtest: vecs0
[960.350198] Dynamic subtest vecs0: SUCCESS (0.023s)
[960.382446] Starting dynamic subtest: ccs0
[960.384812] Dynamic subtest ccs0: SUCCESS (0.025s)
[960.449650] Subtest before: SUCCESS (0.362s)
[960.586450] [122/141] ( 65s left) i915_hangman (detector)
[960.998362] Starting subtest: detector
[961.001723] Starting dynamic subtest: rcs0
[972.522902] Dynamic subtest rcs0: SUCCESS (11.520s)
[972.574344] Starting dynamic subtest: bcs0
[984.489204] Dynamic subtest bcs0: SUCCESS (11.963s)
[984.543649] Starting dynamic subtest: vcs0
[989.725101] Dynamic subtest vcs0: FAIL (5.176s)
[989.781941] Starting dynamic subtest: vcs1
[994.718268] Dynamic subtest vcs1: FAIL (4.990s)
[994.773107] Starting dynamic subtest: vecs0
[999.661330] Dynamic subtest vecs0: FAIL (4.938s)
[999.717424] Starting dynamic subtest: ccs0
[1011.497872] Dynamic subtest ccs0: SUCCESS (11.885s)
[1011.501565] Subtest detector: FAIL (50.501s)
[1011.741985] [123/141] ( 14s left) kms_psr (cursor_blt)
[1012.235686] Starting subtest: cursor_blt
[1015.431164] Subtest cursor_blt: SUCCESS (3.194s)
[1016.337182] [124/141] (  9s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)
[1016.842142] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite
[1033.217623] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (16.376s)
[1033.500744] Overall timeout time exceeded, stopping.
[1033.502086] Closing watchdogs
Done.