[37.670675] Initializing watchdogs
[37.670735]   /dev/watchdog0
[37.676334] [001/140] (960s left) kms_ccs (pipe-b-random-ccs-data-y-tiled-gen12-rc-ccs)
[37.928587] Starting subtest: pipe-B-random-ccs-data-y-tiled-gen12-rc-ccs
[37.931293] Subtest pipe-B-random-ccs-data-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[38.127069] [002/140] (960s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[38.275443] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[38.277010] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[38.353813] [003/140] (959s left) syncobj_timeline (reset-during-wait-for-submit)
[38.431663] Starting subtest: reset-during-wait-for-submit
[38.534909] Subtest reset-during-wait-for-submit: SUCCESS (0.103s)
[38.623065] [004/140] (959s left) kms_busy (extended-modeset-hang-oldfb)
[38.750987] Starting subtest: extended-modeset-hang-oldfb
[38.752810] Starting dynamic subtest: pipe-A
[52.287876] Dynamic subtest pipe-A: SUCCESS (13.535s)
[52.295528] Starting dynamic subtest: pipe-D
[66.261495] Dynamic subtest pipe-D: SUCCESS (13.970s)
[66.262699] Subtest extended-modeset-hang-oldfb: SUCCESS (27.510s)
[66.485878] [005/140] (931s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[66.647303] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu
[66.648926] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[66.717672] [006/140] (931s left) gem_exec_params (readonly)
[66.773116] Starting subtest: readonly
[66.775010] Subtest readonly: SUCCESS (0.001s)
[66.853238] [007/140] (931s left) kms_cursor_legacy (cursora-vs-flipb-atomic)
[66.994804] Starting subtest: cursorA-vs-flipB-atomic
[66.997704] Subtest cursorA-vs-flipB-atomic: SKIP (0.001s)
[67.048977] [008/140] (931s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[67.246440] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[67.359023] [009/140] (930s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[67.500890] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-cpu
[67.502463] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[67.584334] [010/140] (930s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[67.733796] Starting subtest: basic-flip-after-cursor-atomic
[68.757595] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.021s)
[68.990448] [011/140] (929s left) perf (invalid-oa-metric-set-id)
[69.352927] Starting subtest: invalid-oa-metric-set-id
[69.367572] Subtest invalid-oa-metric-set-id: SUCCESS (0.015s)
[69.390666] [012/140] (928s left) kms_cursor_legacy (forked-bo)
[69.503861] Starting subtest: forked-bo
[69.504836] Starting dynamic subtest: pipe-A
[74.916181] Dynamic subtest pipe-A: SUCCESS (5.410s)
[74.925987] Starting dynamic subtest: pipe-B
[80.338675] Dynamic subtest pipe-B: SUCCESS (5.423s)
[80.350232] Starting dynamic subtest: pipe-C
[85.783587] Dynamic subtest pipe-C: SUCCESS (5.444s)
[85.797828] Starting dynamic subtest: pipe-D
[91.220852] Dynamic subtest pipe-D: SUCCESS (5.438s)
[91.229735] Starting dynamic subtest: all-pipes
[96.638616] Dynamic subtest all-pipes: SUCCESS (5.418s)
[96.639513] Subtest forked-bo: SUCCESS (27.134s)
[96.707789] [013/140] (901s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[96.860340] Starting subtest: y-tiled-32bpp-rotate-0
[96.862104] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[96.948974] [014/140] (901s left) gem_close_race (multigpu-basic-threads)
[97.037932] Starting subtest: multigpu-basic-threads
[97.039528] Subtest multigpu-basic-threads: SKIP (0.000s)
[97.269180] [015/140] (901s left) kms_invalid_mode (bad-vtotal)
[97.397538] Starting subtest: bad-vtotal
[97.398461] Starting dynamic subtest: pipe-A-DP-4
[97.470447] Dynamic subtest pipe-A-DP-4: SUCCESS (0.073s)
[97.475426] Starting dynamic subtest: pipe-B-DP-4
[97.476218] Dynamic subtest pipe-B-DP-4: SUCCESS (0.001s)
[97.480833] Starting dynamic subtest: pipe-C-DP-4
[97.481653] Dynamic subtest pipe-C-DP-4: SUCCESS (0.000s)
[97.486201] Starting dynamic subtest: pipe-D-DP-4
[97.486998] Dynamic subtest pipe-D-DP-4: SUCCESS (0.001s)
[97.487794] Subtest bad-vtotal: SUCCESS (0.078s)
[97.644100] [016/140] (900s left) kms_setmode (invalid-clone-single-crtc)
[97.709690] Starting subtest: invalid-clone-single-crtc
[97.712063] Subtest invalid-clone-single-crtc: SKIP (0.000s)
[97.760101] [017/140] (900s left) kms_ccs (pipe-d-bad-pixel-format-4-tiled-mtl-rc-ccs)
[98.006365] Starting subtest: pipe-D-bad-pixel-format-4-tiled-mtl-rc-ccs
[98.007933] Subtest pipe-D-bad-pixel-format-4-tiled-mtl-rc-ccs: SKIP (0.000s)
[98.184699] [018/140] (900s left) gem_exec_balancer (parallel-keep-in-fence)
[98.520020] Starting subtest: parallel-keep-in-fence
[100.221867] Subtest parallel-keep-in-fence: SUCCESS (1.717s)
[100.284505] [019/140] (898s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[100.479164] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[100.590629] [020/140] (897s left) gem_lmem_swapping (parallel-random-verify-ccs)
[103.123699] Starting subtest: parallel-random-verify-ccs
[103.124549] Starting dynamic subtest: lmem0
[173.002131] Dynamic subtest lmem0: SUCCESS (69.878s)
[173.003183] Subtest parallel-random-verify-ccs: SUCCESS (69.878s)
[174.339428] [021/140] (824s left) kms_ccs (pipe-c-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs)
[175.303240] Starting subtest: pipe-C-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs
[175.306099] Subtest pipe-C-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs: SKIP (0.002s)
[175.507959] [022/140] (823s left) kms_psr (pr_sprite_blt)
[175.650289] Subtest pr_sprite_blt: SKIP (0.000s)
[175.675341] [023/140] (822s left) kms_fence_pin_leak
[175.743456] [024/140] (822s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[175.865442] Starting subtest: x-tiled-16bpp-rotate-90
[175.920920] Subtest x-tiled-16bpp-rotate-90: SKIP (0.054s)
[176.051650] [025/140] (822s left) gem_ctx_persistence (legacy-engines-queued)
[176.141439] Starting subtest: legacy-engines-queued
[176.142325] Starting dynamic subtest: render
[176.188274] Dynamic subtest render: SUCCESS (0.046s)
[176.193692] Starting dynamic subtest: bsd1
[176.237158] Dynamic subtest bsd1: SUCCESS (0.048s)
[176.242766] Starting dynamic subtest: bsd2
[176.285298] Dynamic subtest bsd2: SUCCESS (0.048s)
[176.294430] Starting dynamic subtest: blt
[176.340432] Dynamic subtest blt: SUCCESS (0.055s)
[176.351514] Starting dynamic subtest: vebox
[176.396756] Dynamic subtest vebox: SUCCESS (0.056s)
[176.398051] Subtest legacy-engines-queued: SUCCESS (0.255s)
[176.499746] [026/140] (822s left) kms_rotation_crc (primary-x-tiled-reflect-x-0)
[176.612654] Starting subtest: primary-x-tiled-reflect-x-0
[186.349866] Subtest primary-x-tiled-reflect-x-0: SUCCESS (9.736s)
[188.583398] [027/140] (810s left) gem_softpin (evict-snoop-interruptible)
[188.684149] Starting subtest: evict-snoop-interruptible
[188.685833] Subtest evict-snoop-interruptible: SKIP (0.000s)
[188.757984] [028/140] (809s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted-submitted)
[188.821429] Starting subtest: multi-wait-all-for-submit-unsubmitted-submitted
[188.921584] Subtest multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.101s)
[188.985868] [029/140] (809s left) i915_pm_rps (thresholds-park)
[189.034579] Starting subtest: thresholds-park
[189.035559] Starting dynamic subtest: gt0
[189.052161] Dynamic subtest gt0: SKIP (0.016s)
[189.053779] Subtest thresholds-park: SKIP (0.017s)
[189.188277] [030/140] (809s left) v3d/v3d_wait_bo (used-bo)
[189.303487] Subtest used-bo: SKIP (0.000s)
[189.360231] [031/140] (809s left) gem_userptr_blits (sync-unmap-cycles)
[189.448076] Starting subtest: sync-unmap-cycles
[194.820979] Subtest sync-unmap-cycles: SUCCESS (5.373s)
[194.935832] [032/140] (803s left) kms_lease (invalid-create-leases)
[195.058324] Starting subtest: invalid-create-leases
[195.062231] Subtest invalid-create-leases: SUCCESS (0.004s)
[195.117133] [033/140] (803s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[195.161295] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[195.266973] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.105s)
[195.356168] [034/140] (803s left) gem_exec_fence (basic-await)
[195.543231] Starting subtest: basic-await
[195.544135] Starting dynamic subtest: rcs0
[195.648181] Dynamic subtest rcs0: SUCCESS (0.116s)
[195.654252] Starting dynamic subtest: bcs0
[195.742465] Dynamic subtest bcs0: SUCCESS (0.094s)
[195.748470] Starting dynamic subtest: vcs0
[195.836550] Dynamic subtest vcs0: SUCCESS (0.094s)
[195.843055] Starting dynamic subtest: vcs1
[195.933055] Dynamic subtest vcs1: SUCCESS (0.096s)
[195.938649] Starting dynamic subtest: vecs0
[196.025804] Dynamic subtest vecs0: SUCCESS (0.092s)
[196.031735] Starting dynamic subtest: vecs1
[196.120439] Dynamic subtest vecs1: SUCCESS (0.094s)
[196.125670] Starting dynamic subtest: ccs0
[196.212459] Dynamic subtest ccs0: SUCCESS (0.092s)
[196.218436] Starting dynamic subtest: ccs1
[196.306815] Dynamic subtest ccs1: SUCCESS (0.094s)
[196.312985] Starting dynamic subtest: ccs2
[196.403195] Dynamic subtest ccs2: SUCCESS (0.096s)
[196.408927] Starting dynamic subtest: ccs3
[196.495120] Dynamic subtest ccs3: SUCCESS (0.092s)
[196.496014] Subtest basic-await: SUCCESS (0.963s)
[197.457336] [035/140] (801s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[197.573409] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[197.585256] Starting dynamic subtest: pipe-A-DP-4
[198.453537] Dynamic subtest pipe-A-DP-4: SUCCESS (0.868s)
[198.472858] Starting dynamic subtest: pipe-B-DP-4
[199.360432] Dynamic subtest pipe-B-DP-4: SUCCESS (0.894s)
[199.380369] Starting dynamic subtest: pipe-C-DP-4
[200.268044] Dynamic subtest pipe-C-DP-4: SUCCESS (0.895s)
[200.287157] Starting dynamic subtest: pipe-D-DP-4
[201.175238] Dynamic subtest pipe-D-DP-4: SUCCESS (0.894s)
[201.176082] Subtest plane-downscale-factor-0-5-with-modifiers: SUCCESS (3.602s)
[201.470430] [036/140] (797s left) kms_ccs (pipe-d-crc-primary-rotation-180-4-tiled-dg2-rc-ccs)
[201.772162] Starting subtest: pipe-D-crc-primary-rotation-180-4-tiled-dg2-rc-ccs
[202.197151] Subtest pipe-D-crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SUCCESS (0.424s)
[202.527547] [037/140] (796s left) syncobj_wait (wait-delayed-signal)
[202.614941] Starting subtest: wait-delayed-signal
[202.715958] Subtest wait-delayed-signal: SUCCESS (0.101s)
[202.782632] [038/140] (796s left) gem_exec_reloc (basic-write-gtt)
[202.834168] Subtest basic-write-gtt: SKIP (0.000s)
[202.864486] [039/140] (796s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[202.985479] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu
[202.987180] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[203.058455] [040/140] (795s left) kms_ccs (pipe-b-ccs-on-another-bo-yf-tiled-ccs)
[203.300630] Starting subtest: pipe-B-ccs-on-another-bo-yf-tiled-ccs
[203.302366] Subtest pipe-B-ccs-on-another-bo-yf-tiled-ccs: SKIP (0.000s)
[203.519896] [041/140] (795s left) kms_plane_scaling (plane-upscale-20x20-with-modifiers)
[203.647516] Starting subtest: plane-upscale-20x20-with-modifiers
[203.659351] Starting dynamic subtest: pipe-A-DP-4
[204.439617] Dynamic subtest pipe-A-DP-4: SUCCESS (0.780s)
[204.460266] Starting dynamic subtest: pipe-B-DP-4
[205.231550] Dynamic subtest pipe-B-DP-4: SUCCESS (0.779s)
[205.252125] Starting dynamic subtest: pipe-C-DP-4
[206.055584] Dynamic subtest pipe-C-DP-4: SUCCESS (0.811s)
[206.082270] Starting dynamic subtest: pipe-D-DP-4
[206.896216] Dynamic subtest pipe-D-DP-4: SUCCESS (0.825s)
[206.897504] Subtest plane-upscale-20x20-with-modifiers: SUCCESS (3.249s)
[207.217912] [042/140] (791s left) kms_chamelium_edid (dp-edid-change-during-suspend)
[207.429126] Subtest dp-edid-change-during-suspend: SKIP (0.000s)
[207.541422] [043/140] (791s left) i915_pm_freq_api (freq-suspend)
[207.609958] Starting subtest: freq-suspend
[207.610943] Starting dynamic subtest: gt0
[225.829442] Dynamic subtest gt0: SUCCESS (4.035s)
[225.830174] Subtest freq-suspend: SUCCESS (4.035s)
[225.888777] [044/140] (787s left) gem_ppgtt (flink-and-close-vma-leak)
[225.937967] Starting subtest: flink-and-close-vma-leak
[225.985507] Subtest flink-and-close-vma-leak: SUCCESS (0.047s)
[226.114403] [045/140] (787s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[226.270807] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[226.272665] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[226.341153] [046/140] (787s left) kms_ccs (pipe-b-bad-pixel-format-4-tiled-mtl-rc-ccs-cc)
[226.587269] Starting subtest: pipe-B-bad-pixel-format-4-tiled-mtl-rc-ccs-cc
[226.589067] Subtest pipe-B-bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SKIP (0.000s)
[226.800218] [047/140] (786s left) gem_userptr_blits (map-fixed-invalidate)
[226.887638] Starting subtest: map-fixed-invalidate
[226.889520] Subtest map-fixed-invalidate: SKIP (0.000s)
[227.001176] [048/140] (786s left) gem_spin_batch (spin-each)
[227.060722] Starting subtest: spin-each
[230.480536] Subtest spin-each: SUCCESS (3.420s)
[230.748162] [049/140] (782s left) gem_mmap_gtt (basic-small-copy-odd)
[230.805583] Subtest basic-small-copy-odd: SKIP (0.000s)
[230.829766] [050/140] (782s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[230.954629] Starting subtest: yf-tiled-16bpp-rotate-180
[230.956320] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[231.043789] [051/140] (782s left) kms_atomic_transition (plane-all-modeset-transition-fencing)
[231.194059] Starting subtest: plane-all-modeset-transition-fencing
[231.196093] Starting dynamic subtest: pipe-A-DP-4
[268.504570] Dynamic subtest pipe-A-DP-4: SUCCESS (37.308s)
[268.637655] Starting dynamic subtest: pipe-B-DP-4
[301.148238] Dynamic subtest pipe-B-DP-4: SUCCESS (32.517s)
[301.237241] Subtest plane-all-modeset-transition-fencing: SUCCESS (70.043s)
[301.454498] [052/140] (712s left) kms_writeback (writeback-invalid-parameters)
[301.673580] Subtest writeback-invalid-parameters: SKIP (0.000s)
[301.696624] [053/140] (711s left) vc4/vc4_tiling (get-after-free)
[301.772829] Subtest get-after-free: SKIP (0.000s)
[301.823980] [054/140] (711s left) sw_sync (sync_multi_timeline_wait)
[301.881164] Starting subtest: sync_multi_timeline_wait
[301.882046] Subtest sync_multi_timeline_wait: SUCCESS (0.000s)
[301.921144] [055/140] (711s left) kms_selftest (drm_damage_helper)
[301.966381] Starting subtest: drm_damage_helper
[302.106883] Starting dynamic subtest: drm_test_damage_iter_no_damage
[302.115873] Dynamic subtest drm_test_damage_iter_no_damage: SUCCESS (0.015s)
[302.141219] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src
[302.142440] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src: SUCCESS (0.000s)
[302.153468] Starting dynamic subtest: drm_test_damage_iter_no_damage_src_moved
[302.154403] Dynamic subtest drm_test_damage_iter_no_damage_src_moved: SUCCESS (0.000s)
[302.162583] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src_moved
[302.163486] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src_moved: SUCCESS (0.000s)
[302.170279] Starting dynamic subtest: drm_test_damage_iter_no_damage_not_visible
[302.171118] Dynamic subtest drm_test_damage_iter_no_damage_not_visible: SUCCESS (0.000s)
[302.177187] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_crtc
[302.177975] Dynamic subtest drm_test_damage_iter_no_damage_no_crtc: SUCCESS (0.000s)
[302.184130] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_fb
[302.184949] Dynamic subtest drm_test_damage_iter_no_damage_no_fb: SUCCESS (0.000s)
[302.191020] Starting dynamic subtest: drm_test_damage_iter_simple_damage
[302.191810] Dynamic subtest drm_test_damage_iter_simple_damage: SUCCESS (0.000s)
[302.197950] Starting dynamic subtest: drm_test_damage_iter_single_damage
[302.198741] Dynamic subtest drm_test_damage_iter_single_damage: SUCCESS (0.000s)
[302.204810] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_src
[302.205610] Dynamic subtest drm_test_damage_iter_single_damage_intersect_src: SUCCESS (0.000s)
[302.211733] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_src
[302.212521] Dynamic subtest drm_test_damage_iter_single_damage_outside_src: SUCCESS (0.000s)
[302.218657] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src
[302.219462] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src: SUCCESS (0.000s)
[302.225587] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_fractional_src
[302.226387] Dynamic subtest drm_test_damage_iter_single_damage_intersect_fractional_src: SUCCESS (0.000s)
[302.232448] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_fractional_src
[302.233214] Dynamic subtest drm_test_damage_iter_single_damage_outside_fractional_src: SUCCESS (0.000s)
[302.240984] Starting dynamic subtest: drm_test_damage_iter_single_damage_src_moved
[302.241762] Dynamic subtest drm_test_damage_iter_single_damage_src_moved: SUCCESS (0.000s)
[302.247895] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src_moved
[302.248652] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src_moved: SUCCESS (0.000s)
[302.254773] Starting dynamic subtest: drm_test_damage_iter_damage
[302.255550] Dynamic subtest drm_test_damage_iter_damage: SUCCESS (0.000s)
[302.261622] Starting dynamic subtest: drm_test_damage_iter_damage_one_intersect
[302.262401] Dynamic subtest drm_test_damage_iter_damage_one_intersect: SUCCESS (0.000s)
[302.268474] Starting dynamic subtest: drm_test_damage_iter_damage_one_outside
[302.269598] Dynamic subtest drm_test_damage_iter_damage_one_outside: SUCCESS (0.000s)
[302.276111] Starting dynamic subtest: drm_test_damage_iter_damage_src_moved
[302.277239] Dynamic subtest drm_test_damage_iter_damage_src_moved: SUCCESS (0.000s)
[302.283477] Starting dynamic subtest: drm_test_damage_iter_damage_not_visible
[302.284309] Dynamic subtest drm_test_damage_iter_damage_not_visible: SUCCESS (0.000s)
[302.285197] Subtest drm_damage_helper: SUCCESS (0.187s)
[302.321204] [056/140] (711s left) v3d/v3d_job_submission (array-job-submission)
[302.389260] Subtest array-job-submission: SKIP (0.000s)
[302.439539] [057/140] (711s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[302.562002] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu
[302.563627] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[302.642806] [058/140] (710s left) kms_atomic_interruptible (universal-setplane-primary)
[302.779837] Starting subtest: universal-setplane-primary
[302.780674] Starting dynamic subtest: pipe-A-DP-4
[309.120858] Dynamic subtest pipe-A-DP-4: SUCCESS (6.339s)
[309.121767] Subtest universal-setplane-primary: SUCCESS (6.341s)
[309.317365] [059/140] (704s left) kms_pm_rpm (modeset-non-lpsp)
[311.786183] Starting subtest: modeset-non-lpsp
[312.301326] Subtest modeset-non-lpsp: SUCCESS (0.515s)
[312.507861] [060/140] (701s left) kms_feature_discovery (display-3x)
[312.636472] Starting subtest: display-3x
[312.638634] Subtest display-3x: SKIP (0.000s)
[312.698113] [061/140] (701s left) gen9_exec_parse (secure-batches)
[312.749587] Subtest secure-batches: SKIP (0.000s)
[312.772394] [062/140] (700s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[312.892311] Starting subtest: blocking-absolute-wf_vblank-interruptible
[312.898638] Starting dynamic subtest: A-DP4
[320.714991] Dynamic subtest A-DP4: SUCCESS (7.816s)
[320.725368] Starting dynamic subtest: B-DP4
[328.454011] Dynamic subtest B-DP4: SUCCESS (7.738s)
[328.461311] Starting dynamic subtest: C-DP4
[336.187704] Dynamic subtest C-DP4: SUCCESS (7.733s)
[336.195299] Starting dynamic subtest: D-DP4
[343.924331] Dynamic subtest D-DP4: SUCCESS (7.736s)
[343.927929] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (31.033s)
[344.188187] [063/140] (669s left) perf (global-sseu-config)
[344.500496] Subtest global-sseu-config: SKIP (0.000s)
[344.527838] [064/140] (669s left) kms_ccs (pipe-a-bad-pixel-format-y-tiled-gen12-mc-ccs)
[344.643781] Starting subtest: pipe-A-bad-pixel-format-y-tiled-gen12-mc-ccs
[344.645612] Subtest pipe-A-bad-pixel-format-y-tiled-gen12-mc-ccs: SKIP (0.000s)
[344.959800] [065/140] (668s left) kms_ccs (pipe-c-ccs-on-another-bo-y-tiled-ccs)
[345.239591] Starting subtest: pipe-C-ccs-on-another-bo-y-tiled-ccs
[345.241370] Subtest pipe-C-ccs-on-another-bo-y-tiled-ccs: SKIP (0.000s)
[345.425687] [066/140] (668s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move)
[345.581731] Starting subtest: fbc-2p-scndscrn-cur-indfb-move
[345.583323] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[345.661837] [067/140] (668s left) kms_pipe_crc_basic (nonblocking-crc)
[345.794131] Starting subtest: nonblocking-crc
[345.796300] Starting dynamic subtest: pipe-A-DP-4
[346.484065] Dynamic subtest pipe-A-DP-4: SUCCESS (0.688s)
[346.495870] Starting dynamic subtest: pipe-B-DP-4
[347.079945] Dynamic subtest pipe-B-DP-4: SUCCESS (0.593s)
[347.091298] Starting dynamic subtest: pipe-C-DP-4
[347.729967] Dynamic subtest pipe-C-DP-4: SUCCESS (0.648s)
[347.742942] Starting dynamic subtest: pipe-D-DP-4
[348.347820] Dynamic subtest pipe-D-DP-4: SUCCESS (0.615s)
[348.348980] Subtest nonblocking-crc: SUCCESS (2.553s)
[348.530261] [068/140] (665s left) prime_mmap (test_errors)
[348.609148] Starting subtest: test_errors
[348.610057] Starting dynamic subtest: test_errors-smem
[348.747968] Dynamic subtest test_errors-smem: SUCCESS (0.139s)
[348.754711] Starting dynamic subtest: test_errors-lmem0
[349.300980] Dynamic subtest test_errors-lmem0: SUCCESS (0.552s)
[349.301964] Subtest test_errors: SUCCESS (0.692s)
[349.382533] [069/140] (664s left) kms_setmode (invalid-clone-exclusive-crtc)
[349.425747] Starting subtest: invalid-clone-exclusive-crtc
[349.428747] Subtest invalid-clone-exclusive-crtc: SKIP (0.000s)
[349.486949] [070/140] (664s left) kms_ccs (pipe-a-crc-sprite-planes-basic-y-tiled-ccs)
[349.707585] Starting subtest: pipe-A-crc-sprite-planes-basic-y-tiled-ccs
[349.710702] Subtest pipe-A-crc-sprite-planes-basic-y-tiled-ccs: SKIP (0.002s)
[349.922451] [071/140] (664s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[350.056847] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[350.058613] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[350.129668] [072/140] (663s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[350.272346] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[352.793810] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (2.523s)
[352.936185] [073/140] (661s left) gem_exec_schedule (out-order)
[352.995977] Starting subtest: out-order
[352.996824] Starting dynamic subtest: rcs0
[353.263223] Dynamic subtest rcs0: SUCCESS (0.261s)
[353.270368] Starting dynamic subtest: bcs0
[353.444352] Dynamic subtest bcs0: SUCCESS (0.178s)
[353.451377] Starting dynamic subtest: vcs0
[353.624222] Dynamic subtest vcs0: SUCCESS (0.187s)
[353.630998] Starting dynamic subtest: vcs1
[353.812667] Dynamic subtest vcs1: SUCCESS (0.188s)
[353.819693] Starting dynamic subtest: vecs0
[354.001733] Dynamic subtest vecs0: SUCCESS (0.188s)
[354.008576] Starting dynamic subtest: vecs1
[354.194400] Dynamic subtest vecs1: SUCCESS (0.192s)
[354.201293] Starting dynamic subtest: ccs0
[354.390949] Dynamic subtest ccs0: SUCCESS (0.196s)
[354.398020] Starting dynamic subtest: ccs1
[354.606426] Dynamic subtest ccs1: SUCCESS (0.215s)
[354.613446] Starting dynamic subtest: ccs2
[354.799485] Dynamic subtest ccs2: SUCCESS (0.192s)
[354.806432] Starting dynamic subtest: ccs3
[354.985668] Dynamic subtest ccs3: SUCCESS (0.186s)
[354.986514] Subtest out-order: SUCCESS (1.990s)
[355.370326] [074/140] (658s left) gem_workarounds (basic-read-context)
[355.424080] Starting subtest: basic-read-context
[355.452389] Subtest basic-read-context: SUCCESS (0.028s)
[355.558136] [075/140] (658s left) vc4/vc4_perfmon (get-values-invalid-pointer)
[355.643844] Subtest get-values-invalid-pointer: SKIP (0.000s)
[355.699329] [076/140] (658s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu)
[355.819815] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-cpu
[358.127194] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (2.310s)
[358.264991] [077/140] (655s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[358.385698] Starting subtest: fbc-rgb565-draw-blt
[360.860666] Subtest fbc-rgb565-draw-blt: SUCCESS (2.475s)
[361.004061] [078/140] (653s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[361.123350] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[361.139848] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.016s)
[361.214239] [079/140] (652s left) kms_big_fb (x-tiled-64bpp-rotate-0)
[361.352457] Starting subtest: x-tiled-64bpp-rotate-0
[363.011046] Subtest x-tiled-64bpp-rotate-0: SUCCESS (1.658s)
[363.132666] [080/140] (651s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[363.253670] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt
[363.255380] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[363.330666] [081/140] (650s left) gem_request_retire (retire-vma-not-inactive)
[363.392945] Starting subtest: retire-vma-not-inactive
[363.502489] Subtest retire-vma-not-inactive: SUCCESS (0.107s)
[363.626126] [082/140] (650s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-msflip-blt)
[363.778727] Starting subtest: fbc-2p-primscrn-shrfb-msflip-blt
[363.780427] Subtest fbc-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[363.864086] [083/140] (650s left) gem_ctx_isolation (nonpriv-switch)
[363.942205] Starting subtest: nonpriv-switch
[363.943027] Starting dynamic subtest: rcs0
[364.644095] Dynamic subtest rcs0: SUCCESS (0.701s)
[364.650980] Starting dynamic subtest: bcs0
[365.417185] Dynamic subtest bcs0: SUCCESS (0.772s)
[365.424241] Starting dynamic subtest: vcs0
[366.215210] Dynamic subtest vcs0: SUCCESS (0.797s)
[366.222298] Starting dynamic subtest: vcs1
[366.973205] Dynamic subtest vcs1: SUCCESS (0.757s)
[366.980312] Starting dynamic subtest: vecs0
[367.746890] Dynamic subtest vecs0: SUCCESS (0.773s)
[367.753879] Starting dynamic subtest: vecs1
[368.506113] Dynamic subtest vecs1: SUCCESS (0.759s)
[368.513069] Starting dynamic subtest: ccs0
[369.294282] Dynamic subtest ccs0: SUCCESS (0.787s)
[369.301357] Starting dynamic subtest: ccs1
[370.078888] Dynamic subtest ccs1: SUCCESS (0.784s)
[370.086071] Starting dynamic subtest: ccs2
[370.851273] Dynamic subtest ccs2: SUCCESS (0.772s)
[370.858308] Starting dynamic subtest: ccs3
[371.631242] Dynamic subtest ccs3: SUCCESS (0.779s)
[371.632199] Subtest nonpriv-switch: SUCCESS (7.689s)
[371.879547] [084/140] (642s left) kms_ccs (pipe-d-bad-rotation-90-y-tiled-ccs)
[372.144866] Starting subtest: pipe-D-bad-rotation-90-y-tiled-ccs
[372.146737] Subtest pipe-D-bad-rotation-90-y-tiled-ccs: SKIP (0.000s)
[372.320669] [085/140] (641s left) kms_dsc (dsc-basic)
[372.474521] Subtest dsc-basic: SKIP (0.000s)
[372.495098] [086/140] (641s left) kms_psr (pr_dpms)
[372.612156] Subtest pr_dpms: SKIP (0.000s)
[372.628100] [087/140] (641s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[372.748485] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[372.750141] Subtest psr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[372.842989] [088/140] (641s left) kms_ccs (pipe-d-random-ccs-data-y-tiled-gen12-rc-ccs-cc)
[373.148868] Starting subtest: pipe-D-random-ccs-data-y-tiled-gen12-rc-ccs-cc
[373.151306] Subtest pipe-D-random-ccs-data-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s)
[373.318476] [089/140] (641s left) v3d/v3d_submit_cl (valid-multisync-submission)
[373.418294] Subtest valid-multisync-submission: SKIP (0.000s)
[373.470816] [090/140] (640s left) sysfs_heartbeat_interval (idempotent)
[373.521826] Starting subtest: idempotent
[373.522801] Starting dynamic subtest: ccs2
[373.524832] Dynamic subtest ccs2: SUCCESS (0.001s)
[373.531885] Starting dynamic subtest: vecs0
[373.532810] Dynamic subtest vecs0: SUCCESS (0.001s)
[373.539758] Starting dynamic subtest: ccs0
[373.540547] Dynamic subtest ccs0: SUCCESS (0.001s)
[373.547082] Starting dynamic subtest: vcs0
[373.548048] Dynamic subtest vcs0: SUCCESS (0.001s)
[373.554839] Starting dynamic subtest: bcs0
[373.555892] Dynamic subtest bcs0: SUCCESS (0.001s)
[373.562407] Starting dynamic subtest: ccs3
[373.563190] Dynamic subtest ccs3: SUCCESS (0.001s)
[373.569750] Starting dynamic subtest: vecs1
[373.570858] Dynamic subtest vecs1: SUCCESS (0.001s)
[373.577701] Starting dynamic subtest: ccs1
[373.578958] Dynamic subtest ccs1: SUCCESS (0.001s)
[373.585648] Starting dynamic subtest: rcs0
[373.586432] Dynamic subtest rcs0: SUCCESS (0.001s)
[373.593153] Starting dynamic subtest: vcs1
[373.594380] Dynamic subtest vcs1: SUCCESS (0.001s)
[373.595171] Subtest idempotent: SUCCESS (0.014s)
[373.617014] [091/140] (640s left) gem_exec_schedule (preempt-hang)
[373.792525] Starting subtest: preempt-hang
[373.794102] Starting dynamic subtest: rcs0
[374.159519] Dynamic subtest rcs0: SUCCESS (0.368s)
[374.166141] Starting dynamic subtest: bcs0
[374.488426] Dynamic subtest bcs0: SUCCESS (0.313s)
[374.494932] Starting dynamic subtest: vcs0
[374.804188] Dynamic subtest vcs0: SUCCESS (0.318s)
[374.811233] Starting dynamic subtest: vcs1
[375.124566] Dynamic subtest vcs1: SUCCESS (0.319s)
[375.131364] Starting dynamic subtest: vecs0
[375.445530] Dynamic subtest vecs0: SUCCESS (0.315s)
[375.452518] Starting dynamic subtest: vecs1
[375.764484] Dynamic subtest vecs1: SUCCESS (0.333s)
[375.771211] Starting dynamic subtest: ccs0
[376.101278] Dynamic subtest ccs0: SUCCESS (0.322s)
[376.115942] Starting dynamic subtest: ccs1
[376.422090] Dynamic subtest ccs1: SUCCESS (0.345s)
[376.429031] Starting dynamic subtest: ccs2
[376.746669] Dynamic subtest ccs2: SUCCESS (0.325s)
[376.753483] Starting dynamic subtest: ccs3
[377.061657] Dynamic subtest ccs3: SUCCESS (0.314s)
[377.062553] Subtest preempt-hang: SUCCESS (3.280s)
[377.318578] [092/140] (637s left) gem_huc_copy (huc-copy)
[377.398979] Starting subtest: huc-copy
[377.403347] Subtest huc-copy: SUCCESS (0.004s)
[377.543289] [093/140] (636s left) kms_atomic (plane-invalid-params-fence)
[377.683470] Starting subtest: plane-invalid-params-fence
[377.685794] Starting dynamic subtest: pipe-A-DP-4
[378.145666] Dynamic subtest pipe-A-DP-4: SUCCESS (0.459s)
[378.146620] Subtest plane-invalid-params-fence: SUCCESS (0.461s)
[378.375484] [094/140] (636s left) kms_psr (psr2_sprite_render)
[378.522041] Subtest psr2_sprite_render: SKIP (0.000s)
[378.552680] [095/140] (635s left) drm_fdinfo (basics)
[378.599281] Starting subtest: basics
[378.600109] Subtest basics: SUCCESS (0.000s)
[378.664541] [096/140] (635s left) api_intel_allocator (reopen)
[378.727813] Starting subtest: reopen
[378.750808] Subtest reopen: SUCCESS (0.023s)
[378.888077] [097/140] (635s left) syncobj_wait (wait-for-submit-complex)
[378.956590] Starting subtest: wait-for-submit-complex
[379.017628] Subtest wait-for-submit-complex: SUCCESS (0.062s)
[379.099515] [098/140] (635s left) syncobj_timeline (invalid-query-one-illegal-handle)
[379.143958] Starting subtest: invalid-query-one-illegal-handle
[379.145018] Subtest invalid-query-one-illegal-handle: SUCCESS (0.000s)
[379.212651] [099/140] (635s left) kms_flip (flip-vs-expired-vblank-interruptible)
[379.334786] Starting subtest: flip-vs-expired-vblank-interruptible
[379.341048] Starting dynamic subtest: A-DP4
[387.171882] Dynamic subtest A-DP4: SUCCESS (7.831s)
[387.181294] Starting dynamic subtest: B-DP4
[394.916103] Dynamic subtest B-DP4: SUCCESS (7.743s)
[394.923035] Starting dynamic subtest: C-DP4
[402.654266] Dynamic subtest C-DP4: SUCCESS (7.736s)
[402.669065] Starting dynamic subtest: D-DP4
[410.391038] Dynamic subtest D-DP4: SUCCESS (7.734s)
[410.391877] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (31.057s)
[410.660110] [100/140] (603s left) gem_cs_tlb (engines)
[410.731944] Starting subtest: engines
[410.732816] Starting dynamic subtest: rcs0
[412.886256] Dynamic subtest rcs0: SUCCESS (2.153s)
[412.893405] Starting dynamic subtest: bcs0
[415.040464] Dynamic subtest bcs0: SUCCESS (2.153s)
[415.047432] Starting dynamic subtest: vcs0
[417.194001] Dynamic subtest vcs0: SUCCESS (2.153s)
[417.201144] Starting dynamic subtest: vcs1
[419.346885] Dynamic subtest vcs1: SUCCESS (2.152s)
[419.353708] Starting dynamic subtest: vecs0
[421.498546] Dynamic subtest vecs0: SUCCESS (2.151s)
[421.505551] Starting dynamic subtest: vecs1
[423.651335] Dynamic subtest vecs1: SUCCESS (2.152s)
[423.658983] Starting dynamic subtest: ccs0
[425.802830] Dynamic subtest ccs0: SUCCESS (2.151s)
[425.809855] Starting dynamic subtest: ccs1
[427.955004] Dynamic subtest ccs1: SUCCESS (2.151s)
[427.961879] Starting dynamic subtest: ccs2
[430.106131] Dynamic subtest ccs2: SUCCESS (2.151s)
[430.113036] Starting dynamic subtest: ccs3
[432.258630] Dynamic subtest ccs3: SUCCESS (2.151s)
[432.259485] Subtest engines: SUCCESS (21.525s)
[432.353487] [101/140] (582s left) kms_ccs (pipe-a-crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[432.596036] Starting subtest: pipe-A-crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[432.599343] Subtest pipe-A-crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SKIP (0.002s)
[432.816759] [102/140] (581s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-pwrite)
[432.972702] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-pwrite
[432.974459] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[433.053007] [103/140] (581s left) kms_vblank (ts-continuation-modeset)
[433.193207] Starting subtest: ts-continuation-modeset
[433.195268] Starting dynamic subtest: pipe-A-DP-4
[433.720432] Dynamic subtest pipe-A-DP-4: SUCCESS (0.523s)
[433.743409] Starting dynamic subtest: pipe-D-DP-4
[434.176852] Dynamic subtest pipe-D-DP-4: SUCCESS (0.446s)
[434.177856] Subtest ts-continuation-modeset: SUCCESS (0.984s)
[434.377645] [104/140] (580s left) kms_async_flips (test-cursor)
[434.514167] Starting subtest: test-cursor
[434.699330] Starting dynamic subtest: pipe-A-DP-4
[434.946340] Dynamic subtest pipe-A-DP-4: SUCCESS (0.247s)
[435.164155] Starting dynamic subtest: pipe-B-DP-4
[435.273227] Dynamic subtest pipe-B-DP-4: SUCCESS (0.117s)
[435.444562] Starting dynamic subtest: pipe-C-DP-4
[435.561346] Dynamic subtest pipe-C-DP-4: SUCCESS (0.123s)
[435.732524] Starting dynamic subtest: pipe-D-DP-4
[435.842455] Dynamic subtest pipe-D-DP-4: SUCCESS (0.117s)
[435.843452] Subtest test-cursor: SUCCESS (1.329s)
[436.249431] [105/140] (578s left) kms_cursor_crc (cursor-sliding-512x170)
[436.580127] Starting subtest: cursor-sliding-512x170
[436.581984] Subtest cursor-sliding-512x170: SKIP (0.001s)
[436.773470] [106/140] (578s left) gen9_exec_parse (unaligned-jump)
[436.844732] Subtest unaligned-jump: SKIP (0.000s)
[436.874619] [107/140] (577s left) kms_frontbuffer_tracking (fbcpsr-tiling-4)
[437.000629] Starting subtest: fbcpsr-tiling-4
[437.002177] Subtest fbcpsr-tiling-4: SKIP (0.000s)
[437.067684] [108/140] (577s left) gem_eio (context-create)
[437.321412] Starting subtest: context-create
[437.815313] Subtest context-create: SUCCESS (0.495s)
[437.950194] [109/140] (576s left) kms_addfb_basic (clobberred-modifier)
[437.996972] Starting subtest: clobberred-modifier
[437.998691] Subtest clobberred-modifier: SKIP (0.000s)
[438.152522] [110/140] (576s left) kms_content_protection (lic)
[438.331120] Starting subtest: lic
[438.366245] Starting dynamic subtest: pipe-A-DP-4
[559.195342] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[559.473303] Closing watchdogs
[559.474164] Initializing watchdogs
[559.474204]   /dev/watchdog0
[559.483822] [111/140] (455s left) gem_pxp (verify-pxp-execution-after-suspend-resume)
[562.056867] Subtest verify-pxp-execution-after-suspend-resume: SKIP (0.000s)
[562.127400] [112/140] (452s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-pwrite)
[562.247725] Starting subtest: fbcpsr-rgb565-draw-pwrite
[562.249389] Subtest fbcpsr-rgb565-draw-pwrite: SKIP (0.000s)
[562.348001] [113/140] (452s left) kms_big_joiner (2x-modeset)
[562.495386] Subtest 2x-modeset: SKIP (0.000s)
[562.528704] [114/140] (452s left) gem_wait (busy)
[562.583205] Starting subtest: busy
[562.584180] Starting dynamic subtest: all-engines
[563.149881] Dynamic subtest all-engines: SUCCESS (0.566s)
[563.157563] Starting dynamic subtest: rcs0
[563.688952] Dynamic subtest rcs0: SUCCESS (0.538s)
[563.696704] Starting dynamic subtest: bcs0
[564.226840] Dynamic subtest bcs0: SUCCESS (0.537s)
[564.234417] Starting dynamic subtest: vcs0
[564.764916] Dynamic subtest vcs0: SUCCESS (0.537s)
[564.772450] Starting dynamic subtest: vcs1
[565.303843] Dynamic subtest vcs1: SUCCESS (0.538s)
[565.311435] Starting dynamic subtest: vecs0
[565.841882] Dynamic subtest vecs0: SUCCESS (0.537s)
[565.849533] Starting dynamic subtest: vecs1
[566.379821] Dynamic subtest vecs1: SUCCESS (0.537s)
[566.387652] Starting dynamic subtest: ccs0
[566.917900] Dynamic subtest ccs0: SUCCESS (0.537s)
[566.925721] Starting dynamic subtest: ccs1
[567.456681] Dynamic subtest ccs1: SUCCESS (0.538s)
[567.464464] Starting dynamic subtest: ccs2
[567.995924] Dynamic subtest ccs2: SUCCESS (0.538s)
[568.003711] Starting dynamic subtest: ccs3
[568.535795] Dynamic subtest ccs3: SUCCESS (0.539s)
[568.536714] Subtest busy: SUCCESS (5.953s)
[568.756144] [115/140] (446s left) kms_ccs (pipe-b-crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[568.992796] Starting subtest: pipe-B-crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[568.994666] Subtest pipe-B-crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SKIP (0.000s)
[569.216770] [116/140] (445s left) gem_exec_store (pages)
[569.301749] Starting subtest: pages
[569.302646] Starting dynamic subtest: rcs0
[569.423897] Dynamic subtest rcs0: SUCCESS (0.122s)
[569.432325] Starting dynamic subtest: bcs0
[569.510126] Dynamic subtest bcs0: SUCCESS (0.086s)
[569.519574] Starting dynamic subtest: vcs0
[569.612380] Dynamic subtest vcs0: SUCCESS (0.102s)
[569.620714] Starting dynamic subtest: vcs1
[569.705305] Dynamic subtest vcs1: SUCCESS (0.092s)
[569.713918] Starting dynamic subtest: vecs0
[569.794331] Dynamic subtest vecs0: SUCCESS (0.088s)
[569.802482] Starting dynamic subtest: vecs1
[569.893312] Dynamic subtest vecs1: SUCCESS (0.098s)
[569.902092] Starting dynamic subtest: ccs0
[569.981453] Dynamic subtest ccs0: SUCCESS (0.087s)
[569.990371] Starting dynamic subtest: ccs1
[570.079383] Dynamic subtest ccs1: SUCCESS (0.097s)
[570.088111] Starting dynamic subtest: ccs2
[570.178442] Dynamic subtest ccs2: SUCCESS (0.098s)
[570.186818] Starting dynamic subtest: ccs3
[570.274616] Dynamic subtest ccs3: SUCCESS (0.095s)
[570.275823] Subtest pages: SUCCESS (0.973s)
[570.533877] [117/140] (444s left) gem_ringfill (basic-all)
[570.933844] Starting subtest: basic-all
[573.470817] Subtest basic-all: SUCCESS (2.537s)
[573.556738] [118/140] (441s left) perf_pmu (module-unload)
[573.620161] Starting subtest: module-unload
[575.434751] Subtest module-unload: FAIL (1.805s)
[575.446346] [119/140] (439s left) kms_big_fb (4-tiled-addfb-size-offset-overflow)
[576.272212] Starting subtest: 4-tiled-addfb-size-offset-overflow
[576.273186] Subtest 4-tiled-addfb-size-offset-overflow: SUCCESS (0.001s)
[576.348857] [120/140] (438s left) fbdev (pan)
[576.416659] Starting subtest: pan
[576.465990] Subtest pan: SUCCESS (0.049s)
[576.542350] [121/140] (438s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[576.690209] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[576.691842] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[576.793827] [122/140] (438s left) prime_mmap (test_forked)
[576.866491] Starting subtest: test_forked
[576.867367] Starting dynamic subtest: test_forked-smem
[576.911838] Dynamic subtest test_forked-smem: SUCCESS (0.045s)
[576.918322] Starting dynamic subtest: test_forked-lmem0
[577.081873] Dynamic subtest test_forked-lmem0: SUCCESS (0.169s)
[577.082758] Subtest test_forked: SUCCESS (0.216s)
[577.177546] [123/140] (438s left) kms_cursor_crc (cursor-random-512x170)
[577.527286] Starting subtest: cursor-random-512x170
[577.529185] Subtest cursor-random-512x170: SKIP (0.001s)
[577.681178] [124/140] (437s left) gem_mmap_wc (write-read-distinct)
[577.761526] Subtest write-read-distinct: SKIP (0.000s)
[577.790289] [125/140] (437s left) gem_ctx_param (invalid-ctx-set)
[577.836842] Starting subtest: invalid-ctx-set
[577.837796] Subtest invalid-ctx-set: SUCCESS (0.000s)
[577.900020] [126/140] (437s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-wc)
[578.036658] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-wc
[578.038278] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[578.120951] [127/140] (437s left) sw_sync (sync_multi_producer_single_consumer)
[578.183583] Starting subtest: sync_multi_producer_single_consumer
[579.261601] Subtest sync_multi_producer_single_consumer: SUCCESS (1.075s)
[579.298618] [128/140] (435s left) gem_exec_capture (capture)
[579.358417] Starting subtest: capture
[579.359246] Starting dynamic subtest: rcs0-smem
[580.264630] Dynamic subtest rcs0-smem: SUCCESS (0.905s)
[580.271284] Starting dynamic subtest: rcs0-lmem0
[581.670593] Dynamic subtest rcs0-lmem0: SUCCESS (1.406s)
[581.676834] Starting dynamic subtest: bcs0-smem
[582.570131] Dynamic subtest bcs0-smem: SUCCESS (0.899s)
[582.576495] Starting dynamic subtest: bcs0-lmem0
[583.469797] Dynamic subtest bcs0-lmem0: SUCCESS (0.899s)
[583.476238] Starting dynamic subtest: vcs0-smem
[584.363545] Dynamic subtest vcs0-smem: SUCCESS (0.893s)
[584.369753] Starting dynamic subtest: vcs0-lmem0
[585.771073] Dynamic subtest vcs0-lmem0: SUCCESS (1.407s)
[585.777417] Starting dynamic subtest: vcs1-smem
[586.671009] Dynamic subtest vcs1-smem: SUCCESS (0.899s)
[586.677465] Starting dynamic subtest: vcs1-lmem0
[587.571651] Dynamic subtest vcs1-lmem0: SUCCESS (0.900s)
[587.577984] Starting dynamic subtest: vecs0-smem
[588.474677] Dynamic subtest vecs0-smem: SUCCESS (0.902s)
[588.480896] Starting dynamic subtest: vecs0-lmem0
[589.883879] Dynamic subtest vecs0-lmem0: SUCCESS (1.409s)
[589.889992] Starting dynamic subtest: vecs1-smem
[590.899655] Dynamic subtest vecs1-smem: SUCCESS (1.015s)
[590.905972] Starting dynamic subtest: vecs1-lmem0
[591.797753] Dynamic subtest vecs1-lmem0: SUCCESS (0.898s)
[591.803876] Starting dynamic subtest: ccs0-smem
[592.702539] Dynamic subtest ccs0-smem: SUCCESS (0.904s)
[592.708932] Starting dynamic subtest: ccs0-lmem0
[593.612802] Dynamic subtest ccs0-lmem0: SUCCESS (0.910s)
[593.618907] Starting dynamic subtest: ccs1-smem
[594.517447] Dynamic subtest ccs1-smem: SUCCESS (0.905s)
[594.523904] Starting dynamic subtest: ccs1-lmem0
[595.925282] Dynamic subtest ccs1-lmem0: SUCCESS (1.407s)
[595.931646] Starting dynamic subtest: ccs2-smem
[596.823829] Dynamic subtest ccs2-smem: SUCCESS (0.898s)
[596.830081] Starting dynamic subtest: ccs2-lmem0
[597.723775] Dynamic subtest ccs2-lmem0: SUCCESS (0.899s)
[597.730011] Starting dynamic subtest: ccs3-smem
[598.624392] Dynamic subtest ccs3-smem: SUCCESS (0.900s)
[598.630759] Starting dynamic subtest: ccs3-lmem0
[599.522679] Dynamic subtest ccs3-lmem0: SUCCESS (0.898s)
[599.523546] Subtest capture: SUCCESS (20.164s)
[599.625719] [129/140] (415s left) gem_mmap_gtt (basic-write-read)
[599.670992] Subtest basic-write-read: SKIP (0.000s)
[599.691739] [130/140] (415s left) gem_exec_gttfill (basic)
[599.743937] Starting subtest: basic
[605.205143] Subtest basic: SUCCESS (5.461s)
[605.416306] [131/140] (409s left) syncobj_timeline (multi-wait-for-submit-available-submitted)
[605.494192] Starting subtest: multi-wait-for-submit-available-submitted
[605.495119] Subtest multi-wait-for-submit-available-submitted: SUCCESS (0.000s)
[605.549044] [132/140] (409s left) gem_pwrite (basic-random)
[605.591673] Subtest basic-random: SKIP (0.000s)
[605.641845] [133/140] (409s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[605.781882] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[605.782797] Starting dynamic subtest: pipe-A-valid-mode
[607.414336] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.632s)
[607.416032] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SUCCESS (1.634s)
[607.632371] [134/140] (407s left) kms_ccs (pipe-c-crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[607.896896] Starting subtest: pipe-C-crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[607.898757] Subtest pipe-C-crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SKIP (0.000s)
[608.089491] [135/140] (407s left) kms_plane_lowres (tiling-yf)
[608.242093] Starting subtest: tiling-yf
[608.243860] Subtest tiling-yf: SKIP (0.000s)
[608.305180] [136/140] (407s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted)
[608.354611] Starting subtest: etime-multi-wait-all-available-unsubmitted
[608.457172] Subtest etime-multi-wait-all-available-unsubmitted: SUCCESS (0.102s)
[608.562993] [137/140] (406s left) debugfs_test (basic-hwmon)
[608.622942] Starting subtest: basic-hwmon
[608.630452] Subtest basic-hwmon: SUCCESS (0.006s)
[608.773176] [138/140] (406s left) kms_pm_rpm (universal-planes-dpms)
[610.771296] Starting subtest: universal-planes-dpms
[610.772629] Starting dynamic subtest: plane-31
[613.077498] Dynamic subtest plane-31: SUCCESS (2.305s)
[613.112010] Starting dynamic subtest: plane-40
[615.580671] Dynamic subtest plane-40: SUCCESS (2.500s)
[615.615312] Starting dynamic subtest: plane-49
[618.013251] Dynamic subtest plane-49: SUCCESS (2.429s)
[618.050842] Starting dynamic subtest: plane-58
[620.519565] Dynamic subtest plane-58: SUCCESS (2.503s)
[620.555130] Starting dynamic subtest: plane-67
[623.022959] Dynamic subtest plane-67: SUCCESS (2.501s)
[623.057647] Starting dynamic subtest: plane-76
[625.400868] Dynamic subtest plane-76: SUCCESS (2.375s)
[625.402809] Subtest universal-planes-dpms: SUCCESS (14.631s)
[625.753895] [139/140] (389s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-plflip-blt)
[625.919782] Starting subtest: psr-2p-scndscrn-indfb-plflip-blt
[625.921471] Subtest psr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[625.990620] [140/140] (389s left) gem_mmap_gtt (flink-race)
[626.052764] Subtest flink-race: SKIP (0.000s)
[626.077794] Closing watchdogs
Done.