[44.000986] Initializing watchdogs
[44.001230]   /dev/watchdog0
[44.033963] [001/142] (960s left) gem_exec_reloc (basic-wc-read-noreloc)
[44.328341] Starting subtest: basic-wc-read-noreloc
[44.347873] Subtest basic-wc-read-noreloc: SUCCESS (0.019s)
[44.548352] [002/142] (960s left) kms_psr_stress_test (flip-primary-invalidate-overlay)
[44.813712] Subtest flip-primary-invalidate-overlay: SKIP (0.000s)
[44.896840] [003/142] (959s left) kms_ccs (pipe-a-crc-sprite-planes-basic-4_tiled_mtl_mc_ccs)
[45.333593] Starting subtest: pipe-A-crc-sprite-planes-basic-4_tiled_mtl_mc_ccs
[45.375506] Subtest pipe-A-crc-sprite-planes-basic-4_tiled_mtl_mc_ccs: SKIP (0.005s)
[45.782286] [004/142] (959s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted)
[46.015096] Starting subtest: multi-wait-for-submit-unsubmitted-submitted
[46.092757] Subtest multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.105s)
[46.191419] [005/142] (958s left) gem_exec_create (madvise)
[46.423045] Starting subtest: madvise
[46.442577] Starting dynamic subtest: smem
[66.655151] Dynamic subtest smem: SUCCESS (20.152s)
[66.677219] Subtest madvise: SUCCESS (20.152s)
[68.044607] [006/142] (936s left) syncobj_wait (wait-all-for-submit-delayed-submit)
[68.266500] Starting subtest: wait-all-for-submit-delayed-submit
[68.347580] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.105s)
[68.453448] [007/142] (936s left) kms_ccs (pipe-d-crc-primary-rotation-180-y_tiled_gen12_mc_ccs)
[69.003592] Subtest pipe-D-crc-primary-rotation-180-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[69.317440] [008/142] (935s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy)
[69.703081] Starting subtest: fbcpsr-modesetfrombusy
[69.740147] Subtest fbcpsr-modesetfrombusy: SKIP (0.000s)
[69.827816] [009/142] (935s left) kms_vblank (pipe-a-ts-continuation-dpms-suspend)
[70.126117] Starting subtest: pipe-A-ts-continuation-dpms-suspend
[87.169633] Subtest pipe-A-ts-continuation-dpms-suspend: SUCCESS (1.931s)
[92.622549] [010/142] (932s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[93.003950] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[93.041372] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[93.128324] [011/142] (932s left) kms_ccs (pipe-a-bad-aux-stride-y_tiled_gen12_rc_ccs_cc)
[93.550684] Starting subtest: pipe-A-bad-aux-stride-y_tiled_gen12_rc_ccs_cc
[93.592257] Subtest pipe-A-bad-aux-stride-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[93.984921] [012/142] (931s left) kms_ccs (pipe-b-missing-ccs-buffer-y_tiled_gen12_mc_ccs)
[94.442734] Starting subtest: pipe-B-missing-ccs-buffer-y_tiled_gen12_mc_ccs
[94.481453] Subtest pipe-B-missing-ccs-buffer-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[94.832832] [013/142] (930s left) syncobj_timeline (single-wait-available-submitted)
[95.068261] Starting subtest: single-wait-available-submitted
[95.088930] Subtest single-wait-available-submitted: SUCCESS (0.000s)
[95.176023] [014/142] (930s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-render)
[95.550925] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-render
[95.590177] Subtest fbcpsr-1p-offscren-pri-indfb-draw-render: SKIP (0.000s)
[95.671331] [015/142] (930s left) prime_vgem (coherency-blt)
[96.039063] Starting subtest: coherency-blt
[99.661835] Subtest coherency-blt: SUCCESS (3.480s)
[99.765028] [016/142] (926s left) kms_ccs (pipe-b-crc-primary-basic-4_tiled_mtl_mc_ccs)
[100.207002] Starting subtest: pipe-B-crc-primary-basic-4_tiled_mtl_mc_ccs
[100.249130] Subtest pipe-B-crc-primary-basic-4_tiled_mtl_mc_ccs: SKIP (0.001s)
[100.630469] [017/142] (925s left) gem_lmem_swapping (heavy-verify-multi)
[100.883874] Subtest heavy-verify-multi: SKIP (0.000s)
[100.969250] [018/142] (925s left) kms_plane (plane-position-hole)
[101.244839] Starting subtest: plane-position-hole
[101.263686] Starting dynamic subtest: pipe-A-planes
[102.802045] Dynamic subtest pipe-A-planes: SUCCESS (1.530s)
[102.849659] Starting dynamic subtest: pipe-B-planes
[104.482869] Dynamic subtest pipe-B-planes: SUCCESS (1.679s)
[104.510450] Subtest plane-position-hole: SUCCESS (3.214s)
[104.804499] [019/142] (921s left) kms_rotation_crc (primary-y-tiled-reflect-x-270)
[105.103466] Starting subtest: primary-y-tiled-reflect-x-270
[105.143262] Subtest primary-y-tiled-reflect-x-270: SKIP (0.003s)
[105.233323] [020/142] (920s left) gem_fenced_exec_thrash (no-spare-fences-busy-interruptible)
[105.463884] Starting subtest: no-spare-fences-busy-interruptible
[107.657114] Subtest no-spare-fences-busy-interruptible: SUCCESS (2.192s)
[107.818697] [021/142] (918s left) i915_pm_rps (fence-order)
[108.057712] Starting subtest: fence-order
[111.238106] Subtest fence-order: SUCCESS (3.186s)
[111.473415] [022/142] (914s left) syncobj_wait (multi-wait-submitted)
[111.699607] Starting subtest: multi-wait-submitted
[111.779195] Subtest multi-wait-submitted: SUCCESS (0.104s)
[111.891007] [023/142] (914s left) gem_softpin (allocator-evict)
[112.124425] Starting subtest: allocator-evict
[112.145301] Starting dynamic subtest: rcs0
[148.341900] Dynamic subtest rcs0: SUCCESS (36.242s)
[148.379306] Starting dynamic subtest: bcs0
[186.092646] Dynamic subtest bcs0: SUCCESS (37.750s)
[186.135984] Starting dynamic subtest: vcs0
[223.221460] Dynamic subtest vcs0: SUCCESS (37.128s)
[223.261097] Starting dynamic subtest: vecs0
[260.607161] Dynamic subtest vecs0: SUCCESS (37.386s)
[260.625990] Subtest allocator-evict: SUCCESS (148.507s)
[260.726054] [024/142] (765s left) syncobj_timeline (etime-single-wait-for-submit-unsubmitted)
[261.114518] Starting subtest: etime-single-wait-for-submit-unsubmitted
[261.226315] Subtest etime-single-wait-for-submit-unsubmitted: SUCCESS (0.107s)
[261.338893] [025/142] (765s left) kms_draw_crc (draw-method-blt)
[261.711147] Starting subtest: draw-method-blt
[261.731535] Starting dynamic subtest: xrgb8888-untiled
[262.035463] Dynamic subtest xrgb8888-untiled: SUCCESS (0.331s)
[262.075329] Starting dynamic subtest: rgb565-untiled
[262.334867] Dynamic subtest rgb565-untiled: SUCCESS (0.299s)
[262.375568] Starting dynamic subtest: xrgb2101010-untiled
[262.642764] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.300s)
[262.683204] Starting dynamic subtest: xrgb8888-xtiled
[262.794827] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.166s)
[262.835936] Starting dynamic subtest: rgb565-xtiled
[262.966489] Dynamic subtest rgb565-xtiled: SUCCESS (0.166s)
[263.006523] Starting dynamic subtest: xrgb2101010-xtiled
[263.132446] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.166s)
[263.175989] Starting dynamic subtest: xrgb8888-ytiled
[263.316511] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.183s)
[263.355936] Starting dynamic subtest: rgb565-ytiled
[263.483911] Dynamic subtest rgb565-ytiled: SUCCESS (0.166s)
[263.526868] Starting dynamic subtest: xrgb2101010-ytiled
[263.661898] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.183s)
[263.683252] Subtest draw-method-blt: SUCCESS (1.964s)
[263.808264] [026/142] (762s left) kms_pm_dc (dc6-dpms)
[265.127394] Starting subtest: dc6-dpms
[265.170370] Subtest dc6-dpms: SKIP (0.000s)
[265.308118] [027/142] (761s left) gem_close_race (multigpu-basic-threads)
[265.563909] Starting subtest: multigpu-basic-threads
[265.603676] Subtest multigpu-basic-threads: SKIP (0.000s)
[265.807486] [028/142] (761s left) drm_fdinfo (all-busy-idle-check-all)
[266.041947] Starting subtest: all-busy-idle-check-all
[266.646063] Subtest all-busy-idle-check-all: SUCCESS (0.628s)
[266.760175] [029/142] (760s left) gem_pxp (verify-pxp-execution-after-suspend-resume)
[269.708458] Subtest verify-pxp-execution-after-suspend-resume: SKIP (0.000s)
[269.821404] [030/142] (757s left) kms_getfb (getfb2-into-addfb2)
[270.053283] Starting subtest: getfb2-into-addfb2
[270.074275] Subtest getfb2-into-addfb2: SUCCESS (0.000s)
[270.162270] [031/142] (756s left) gem_render_copy (yf-tiled-to-vebox-y-tiled)
[270.390603] Starting subtest: yf-tiled-to-vebox-y-tiled
[270.448907] Subtest yf-tiled-to-vebox-y-tiled: SKIP (0.000s)
[270.633012] [032/142] (756s left) v3d/v3d_submit_cl (job-perfmon)
[270.957396] Subtest job-perfmon: SKIP (0.000s)
[271.070863] [033/142] (756s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[271.441841] Starting subtest: x-tiled-16bpp-rotate-90
[271.520773] Subtest x-tiled-16bpp-rotate-90: SKIP (0.083s)
[271.636693] [034/142] (755s left) kms_getfb (getfb-handle-zero)
[271.863062] Starting subtest: getfb-handle-zero
[271.884886] Subtest getfb-handle-zero: SUCCESS (0.000s)
[271.973655] [035/142] (755s left) v3d/v3d_wait_bo (bad-pad)
[272.251917] Subtest bad-pad: SKIP (0.000s)
[272.341354] [036/142] (755s left) gem_exec_balancer (individual)
[272.572584] Starting subtest: individual
[276.601202] Subtest individual: SUCCESS (3.837s)
[280.667565] [037/142] (747s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[280.964811] Starting subtest: plane-scaler-unity-scaling-with-rotation
[280.984144] Starting dynamic subtest: pipe-A-DP-1
[281.132562] Dynamic subtest pipe-A-DP-1: SKIP (0.137s)
[281.178464] Starting dynamic subtest: pipe-B-DP-1
[281.526044] Dynamic subtest pipe-B-DP-1: SKIP (0.387s)
[281.570051] Starting dynamic subtest: pipe-C-DP-1
[281.932843] Dynamic subtest pipe-C-DP-1: SKIP (0.403s)
[281.970549] Subtest plane-scaler-unity-scaling-with-rotation: SKIP (0.936s)
[282.312924] [038/142] (745s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[282.692624] Starting subtest: psr-1p-primscrn-cur-indfb-move
[282.734016] Subtest psr-1p-primscrn-cur-indfb-move: SKIP (0.000s)
[282.819164] [039/142] (745s left) gem_exec_schedule (implicit-read-write)
[283.220944] Starting subtest: implicit-read-write
[283.239378] Starting dynamic subtest: rcs0
[283.257819] Dynamic subtest rcs0: SUCCESS (0.150s)
[283.297982] Starting dynamic subtest: bcs0
[283.351757] Dynamic subtest bcs0: SUCCESS (0.142s)
[283.394175] Starting dynamic subtest: vcs0
[283.471116] Dynamic subtest vcs0: SUCCESS (0.126s)
[283.511643] Starting dynamic subtest: vecs0
[283.598716] Dynamic subtest vecs0: SUCCESS (0.125s)
[283.620961] Subtest implicit-read-write: SUCCESS (0.546s)
[283.995350] [040/142] (743s left) vc4/vc4_wait_bo (bad-bo)
[284.271889] Subtest bad-bo: SKIP (0.000s)
[284.360342] [041/142] (743s left) gem_exec_store (cachelines)
[284.658494] Starting subtest: cachelines
[284.679998] Starting dynamic subtest: rcs0
[284.700740] Dynamic subtest rcs0: SUCCESS (0.055s)
[284.741186] Starting dynamic subtest: bcs0
[284.760084] Dynamic subtest bcs0: SUCCESS (0.036s)
[284.797866] Starting dynamic subtest: vcs0
[284.816441] Dynamic subtest vcs0: SUCCESS (0.035s)
[284.857737] Starting dynamic subtest: vecs0
[284.879311] Dynamic subtest vecs0: SUCCESS (0.027s)
[284.900671] Subtest cachelines: SUCCESS (0.154s)
[284.989473] [042/142] (743s left) kms_lease (cursor-implicit-plane)
[285.285322] Starting subtest: cursor-implicit-plane
[285.304008] Starting dynamic subtest: pipe-A-DP-1
[285.557738] Dynamic subtest pipe-A-DP-1: SUCCESS (0.257s)
[285.616139] Starting dynamic subtest: pipe-B-DP-1
[285.976412] Dynamic subtest pipe-B-DP-1: SUCCESS (0.410s)
[286.032005] Starting dynamic subtest: pipe-C-DP-1
[286.403695] Dynamic subtest pipe-C-DP-1: SUCCESS (0.443s)
[286.425852] Subtest cursor-implicit-plane: SUCCESS (1.117s)
[286.748820] [043/142] (741s left) kms_frontbuffer_tracking (fbc-stridechange)
[287.119857] Starting subtest: fbc-stridechange
[288.703262] Subtest fbc-stridechange: SUCCESS (1.650s)
[288.882352] [044/142] (739s left) kms_vblank (pipe-d-ts-continuation-modeset-hang)
[289.199890] Subtest pipe-D-ts-continuation-modeset-hang: SKIP (0.000s)
[289.371557] [045/142] (739s left) prime_self_import (basic-with_fd_dup)
[289.592137] Starting subtest: basic-with_fd_dup
[289.630819] Subtest basic-with_fd_dup: SUCCESS (0.006s)
[289.719150] [046/142] (738s left) vc4/vc4_purgeable_bo (mark-unpurgeable-purged)
[290.008512] Subtest mark-unpurgeable-purged: SKIP (0.000s)
[290.100876] [047/142] (738s left) gem_mmap_offset (bad-flags)
[290.330906] Starting subtest: bad-flags
[290.351885] Subtest bad-flags: SUCCESS (0.000s)
[290.439563] [048/142] (738s left) syncobj_timeline (multi-wait-all-signaled)
[290.670856] Starting subtest: multi-wait-all-signaled
[290.689741] Subtest multi-wait-all-signaled: SUCCESS (0.002s)
[290.775341] [049/142] (737s left) gem_mmap_gtt (basic-small-copy)
[291.010482] Starting subtest: basic-small-copy
[293.735587] Subtest basic-small-copy: SUCCESS (2.758s)
[293.850971] [050/142] (734s left) gem_exec_whisper (basic-fds)
[294.242943] Starting subtest: basic-fds
[305.881923] Subtest basic-fds: SUCCESS (11.650s)
[306.089246] [051/142] (722s left) v3d/v3d_create_bo (create-bo-zeroed)
[306.399943] Subtest create-bo-zeroed: SKIP (0.000s)
[306.489895] [052/142] (722s left) kms_cursor_crc (cursor-offscreen-64x64)
[306.892964] Starting subtest: cursor-offscreen-64x64
[306.913652] Starting dynamic subtest: pipe-A-DP-1
[309.540706] Dynamic subtest pipe-A-DP-1: SUCCESS (2.644s)
[309.642724] Starting dynamic subtest: pipe-C-DP-1
[312.231318] Dynamic subtest pipe-C-DP-1: SUCCESS (2.595s)
[312.258279] Subtest cursor-offscreen-64x64: SUCCESS (5.316s)
[312.601180] [053/142] (716s left) kms_flip (dpms-vs-vblank-race)
[312.943559] Starting subtest: dpms-vs-vblank-race
[312.967134] Starting dynamic subtest: A-DP1
[317.257611] Dynamic subtest A-DP1: SUCCESS (4.334s)
[317.299215] Starting dynamic subtest: B-DP1
[321.455291] Dynamic subtest B-DP1: SUCCESS (4.194s)
[321.495861] Starting dynamic subtest: C-DP1
[325.625063] Dynamic subtest C-DP1: SUCCESS (4.174s)
[325.647705] Subtest dpms-vs-vblank-race: SUCCESS (12.710s)
[326.033326] [054/142] (703s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[326.830968] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[326.857453] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[326.972470] [055/142] (702s left) kms_ccs (pipe-d-ccs-on-another-bo-yf_tiled_ccs)
[327.583023] Subtest pipe-D-ccs-on-another-bo-yf_tiled_ccs: SKIP (0.000s)
[327.913267] [056/142] (701s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[328.252260] Starting subtest: extended-modeset-hang-oldfb-with-reset
[328.271412] Starting dynamic subtest: pipe-A
[330.192560] Dynamic subtest pipe-A: SUCCESS (1.936s)
[330.238051] Starting dynamic subtest: pipe-C
[332.291776] Dynamic subtest pipe-C: SUCCESS (2.089s)
[332.313731] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (4.036s)
[332.654007] [057/142] (696s left) kms_cursor_crc (cursor-random-128x42)
[332.981990] Starting subtest: cursor-random-128x42
[333.002985] Starting dynamic subtest: pipe-A-DP-1
[336.768538] Dynamic subtest pipe-A-DP-1: SUCCESS (3.787s)
[336.864406] Starting dynamic subtest: pipe-C-DP-1
[340.752260] Dynamic subtest pipe-C-DP-1: SUCCESS (3.918s)
[340.771873] Subtest cursor-random-128x42: SUCCESS (7.787s)
[341.139390] [058/142] (688s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-mmap-cpu)
[341.513622] Starting subtest: fbc-1p-offscren-pri-indfb-draw-mmap-cpu
[343.286271] Subtest fbc-1p-offscren-pri-indfb-draw-mmap-cpu: SUCCESS (1.844s)
[343.447711] [059/142] (686s left) kms_rotation_crc (primary-x-tiled-reflect-x-180)
[343.752299] Starting subtest: primary-x-tiled-reflect-x-180
[343.790474] Subtest primary-x-tiled-reflect-x-180: SKIP (0.003s)
[343.887478] [060/142] (685s left) kms_vblank (pipe-c-wait-forked-busy-hang)
[344.196567] Starting subtest: pipe-C-wait-forked-busy-hang
[351.224022] Subtest pipe-C-wait-forked-busy-hang: SUCCESS (6.916s)
[351.484713] [061/142] (678s left) gem_fence_thrash (bo-write-verify-threaded-y)
[351.699897] Starting subtest: bo-write-verify-threaded-y
[354.098714] Subtest bo-write-verify-threaded-y: SUCCESS (2.246s)
[354.192287] [062/142] (675s left) kms_ccs (pipe-a-crc-primary-basic-4_tiled_dg2_rc_ccs)
[354.587645] Starting subtest: pipe-A-crc-primary-basic-4_tiled_dg2_rc_ccs
[354.630101] Subtest pipe-A-crc-primary-basic-4_tiled_dg2_rc_ccs: SKIP (0.001s)
[355.066505] [063/142] (674s left) kms_universal_plane (disable-primary-vs-flip-pipe-b)
[355.369769] Starting subtest: disable-primary-vs-flip-pipe-B
[356.326481] Subtest disable-primary-vs-flip-pipe-B: SUCCESS (0.946s)
[356.697522] [064/142] (673s left) kms_flip (flip-vs-suspend-interruptible)
[357.018252] Starting subtest: flip-vs-suspend-interruptible
[357.040455] Starting dynamic subtest: A-DP1
[409.183169] Dynamic subtest A-DP1: SUCCESS (5.231s)
[409.226355] Starting dynamic subtest: B-DP1
[462.165526] Dynamic subtest B-DP1: SUCCESS (5.110s)
[462.210673] Starting dynamic subtest: C-DP1
[515.143147] Dynamic subtest C-DP1: SUCCESS (5.001s)
[515.165291] Subtest flip-vs-suspend-interruptible: SUCCESS (15.355s)
[522.746449] [065/142] (657s left) gem_barrier_race (remote-request)
[523.064114] Starting subtest: remote-request
[523.082291] Starting dynamic subtest: rcs0
[528.264581] Dynamic subtest rcs0: SUCCESS (5.235s)
[528.284784] Subtest remote-request: SUCCESS (5.238s)
[528.385364] [066/142] (651s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[528.742786] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[528.784783] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[528.882573] [067/142] (651s left) kms_psr (psr2_no_drrs)
[529.140329] Subtest psr2_no_drrs: SKIP (0.000s)
[529.231937] [068/142] (651s left) kms_vblank (pipe-a-query-forked-busy)
[529.528750] Starting subtest: pipe-A-query-forked-busy
[531.986599] Subtest pipe-A-query-forked-busy: SUCCESS (2.451s)
[532.342412] [069/142] (648s left) kms_frontbuffer_tracking (fbc-1p-shrfb-fliptrack-mmap-gtt)
[532.701278] Starting subtest: fbc-1p-shrfb-fliptrack-mmap-gtt
[535.673585] Subtest fbc-1p-shrfb-fliptrack-mmap-gtt: SUCCESS (3.028s)
[535.855333] [070/142] (644s left) gem_mmap_wc (bad-offset)
[536.092353] Starting subtest: bad-offset
[536.116704] Subtest bad-offset: SUCCESS (0.001s)
[536.210367] [071/142] (644s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[536.571654] Starting subtest: 4-tiled-16bpp-rotate-270
[536.608363] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.000s)
[536.705381] [072/142] (644s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[537.040154] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[537.116968] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.003s)
[537.215071] [073/142] (643s left) kms_ccs (pipe-a-random-ccs-data-y_tiled_gen12_rc_ccs_cc)
[537.623410] Starting subtest: pipe-A-random-ccs-data-y_tiled_gen12_rc_ccs_cc
[537.683008] Subtest pipe-A-random-ccs-data-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[538.104233] [074/142] (642s left) i915_pm_rpm (modeset-non-lpsp-stress)
[539.986202] Starting subtest: modeset-non-lpsp-stress
[546.352694] Subtest modeset-non-lpsp-stress: SUCCESS (6.356s)
[546.558171] [075/142] (634s left) kms_plane_scaling (planes-upscale-20x20)
[546.871880] Starting subtest: planes-upscale-20x20
[546.892994] Starting dynamic subtest: pipe-A-DP-1
[546.960064] Dynamic subtest pipe-A-DP-1: SUCCESS (0.068s)
[547.021757] Starting dynamic subtest: pipe-B-DP-1
[547.321589] Dynamic subtest pipe-B-DP-1: SUCCESS (0.354s)
[547.364581] Starting dynamic subtest: pipe-C-DP-1
[547.429350] Dynamic subtest pipe-C-DP-1: SKIP (0.094s)
[547.451430] Subtest planes-upscale-20x20: SUCCESS (0.532s)
[547.837088] [076/142] (633s left) gem_ctx_sseu (mmap-args)
[548.095239] Subtest mmap-args: SKIP (0.000s)
[548.189427] [077/142] (633s left) kms_mmap_write_crc (main)
[548.519904] Starting subtest: main
[548.541761] Starting dynamic subtest: pipe-A-DP-1
[551.917178] Dynamic subtest pipe-A-DP-1: SUCCESS (3.403s)
[551.936388] Subtest main: SUCCESS (3.407s)
[552.339387] [078/142] (629s left) perf_pmu (busy)
[552.581269] Starting subtest: busy
[552.602698] Starting dynamic subtest: rcs0
[553.109254] Dynamic subtest rcs0: SUCCESS (0.548s)
[553.150752] Starting dynamic subtest: bcs0
[553.651021] Dynamic subtest bcs0: SUCCESS (0.542s)
[553.694335] Starting dynamic subtest: vcs0
[554.195434] Dynamic subtest vcs0: SUCCESS (0.545s)
[554.237039] Starting dynamic subtest: vecs0
[554.746047] Dynamic subtest vecs0: SUCCESS (0.540s)
[554.767182] Subtest busy: SUCCESS (2.185s)
[554.880101] [079/142] (626s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[555.298119] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[555.632171] [080/142] (625s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[555.993433] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[556.031736] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[556.135871] [081/142] (625s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[556.472467] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[556.511321] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[556.611844] [082/142] (625s left) kms_ccs (pipe-d-random-ccs-data-4_tiled_dg2_rc_ccs_cc)
[557.174374] Subtest pipe-D-random-ccs-data-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[557.503046] [083/142] (624s left) i915_module_load (resize-bar)
[557.724443] Starting subtest: resize-bar
[557.781819] Subtest resize-bar: SKIP (0.013s)
[557.881456] [084/142] (624s left) gem_partial_pwrite_pread (writes-after-reads)
[558.119620] Starting subtest: writes-after-reads
[578.420960] Subtest writes-after-reads: SUCCESS (20.309s)
[578.554042] [085/142] (603s left) kms_ccs (pipe-d-bad-pixel-format-4_tiled_dg2_mc_ccs)
[579.097608] Subtest pipe-D-bad-pixel-format-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[579.433205] [086/142] (602s left) i915_hangman (detector)
[579.677616] Starting subtest: detector
[579.699791] Starting dynamic subtest: rcs0
[589.276024] Dynamic subtest rcs0: SUCCESS (9.604s)
[589.318253] Starting dynamic subtest: bcs0
[599.264959] Dynamic subtest bcs0: SUCCESS (9.857s)
[599.307227] Starting dynamic subtest: vcs0
[609.313558] Dynamic subtest vcs0: SUCCESS (10.146s)
[609.353110] Starting dynamic subtest: vecs0
[619.277666] Dynamic subtest vecs0: SUCCESS (9.992s)
[619.296321] Subtest detector: SUCCESS (39.614s)
[619.431987] [087/142] (562s left) api_intel_allocator (standalone)
[619.665709] Starting subtest: standalone
[619.709171] Subtest standalone: SUCCESS (0.058s)
[619.811268] [088/142] (562s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[620.117531] Starting subtest: basic-busy-flip-before-cursor-legacy
[620.157345] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[620.256791] [089/142] (562s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-blt)
[620.604589] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-blt
[622.866206] Subtest fbc-1p-primscrn-pri-indfb-draw-blt: SUCCESS (2.310s)
[623.040473] [090/142] (559s left) kms_content_protection (legacy)
[623.410962] Starting subtest: legacy
[623.585843] Starting dynamic subtest: pipe-A-DP-1
[744.052378] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[750.741893] Closing watchdogs
[750.749661] Initializing watchdogs
[750.749837]   /dev/watchdog0
[750.794240] [091/142] (431s left) kms_getfb (getfb-handle-valid)
[751.033282] Starting subtest: getfb-handle-valid
[751.054886] Subtest getfb-handle-valid: SUCCESS (0.000s)
[751.155162] [092/142] (431s left) kms_flip (2x-nonexisting-fb-interruptible)
[751.447063] Starting subtest: 2x-nonexisting-fb-interruptible
[751.483979] Subtest 2x-nonexisting-fb-interruptible: SKIP (0.003s)
[751.585201] [093/142] (431s left) kms_addfb_basic (addfb25-modifier-no-flag)
[751.814295] Starting subtest: addfb25-modifier-no-flag
[751.836238] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[752.027109] [094/142] (430s left) gem_exec_fair (basic-deadline)
[752.443904] Starting subtest: basic-deadline
[769.723130] Subtest basic-deadline: FAIL (16.143s)
[769.817173] [095/142] (413s left) gem_exec_fence (submit)
[770.189470] Starting subtest: submit
[770.211991] Starting dynamic subtest: rcs0
[770.233448] Dynamic subtest rcs0: SUCCESS (0.025s)
[770.278379] Starting dynamic subtest: bcs0
[770.299205] Dynamic subtest bcs0: SUCCESS (0.022s)
[770.341785] Starting dynamic subtest: vcs0
[770.360558] Dynamic subtest vcs0: SUCCESS (0.019s)
[770.402165] Starting dynamic subtest: vecs0
[770.421931] Dynamic subtest vecs0: SUCCESS (0.021s)
[770.442034] Subtest submit: SUCCESS (0.097s)
[770.999911] [096/142] (412s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted)
[771.226211] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[771.246132] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.001s)
[771.347581] [097/142] (411s left) syncobj_timeline (wait-all-for-submit-delayed-submit)
[771.584735] Starting subtest: wait-all-for-submit-delayed-submit
[771.654696] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.103s)
[771.778883] [098/142] (411s left) perf_pmu (rc6-all-gts)
[771.992184] Starting subtest: rc6-all-gts
[772.029217] Subtest rc6-all-gts: SKIP (0.000s)
[772.147726] [099/142] (411s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-render)
[772.481368] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-render
[772.518375] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[772.620107] [100/142] (410s left) kms_chamelium_hpd (vga-hpd)
[773.050351] Subtest vga-hpd: SKIP (0.000s)
[773.353005] [101/142] (410s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary)
[773.705339] Starting subtest: psr-shrfb-scaledprimary
[773.747589] Subtest psr-shrfb-scaledprimary: SKIP (0.000s)
[773.844732] [102/142] (409s left) kms_vblank (pipe-a-accuracy-idle)
[774.129565] Starting subtest: pipe-A-accuracy-idle
[776.410254] Subtest pipe-A-accuracy-idle: SUCCESS (2.278s)
[776.757076] [103/142] (407s left) kms_setmode (clone-exclusive-crtc)
[776.994845] Starting subtest: clone-exclusive-crtc
[777.052667] Subtest clone-exclusive-crtc: SKIP (0.000s)
[777.151236] [104/142] (406s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[777.482702] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[777.523577] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[777.624064] [105/142] (406s left) dmabuf_sync_file (import-multiple-read-only)
[777.859247] Starting subtest: import-multiple-read-only
[777.880252] Subtest import-multiple-read-only: SUCCESS (0.014s)
[777.973646] [106/142] (406s left) gem_mmap_gtt (big-copy-xy)
[778.192056] Starting subtest: big-copy-XY
[790.706719] Subtest big-copy-XY: SUCCESS (12.552s)
[790.826340] [107/142] (393s left) kms_getfb (getfb-addfb-different-handles)
[791.190201] Starting subtest: getfb-addfb-different-handles
[791.208905] Subtest getfb-addfb-different-handles: SUCCESS (0.000s)
[791.307222] [108/142] (393s left) syncobj_wait (wait-all-for-submit-snapshot)
[791.583471] Starting subtest: wait-all-for-submit-snapshot
[791.670863] Subtest wait-all-for-submit-snapshot: SUCCESS (0.082s)
[791.792045] [109/142] (392s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0)
[792.207571] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0
[792.265907] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.004s)
[792.385842] [110/142] (392s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[793.213068] Starting subtest: syncobj-timeline-invalid-wait
[793.234719] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.004s)
[793.475662] [111/142] (391s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[793.863375] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu
[793.902131] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[793.996426] [112/142] (390s left) kms_vblank (pipe-a-ts-continuation-idle-hang)
[794.290629] Starting subtest: pipe-A-ts-continuation-idle-hang
[801.013314] Subtest pipe-A-ts-continuation-idle-hang: SUCCESS (6.598s)
[801.252377] [113/142] (383s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[801.554310] Starting subtest: flip-vs-cursor-busy-crc-atomic
[802.548688] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.981s)
[802.900278] [114/142] (381s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions)
[803.193572] Starting subtest: cursorB-vs-flipB-atomic-transitions
[803.235698] Subtest cursorB-vs-flipB-atomic-transitions: SKIP (0.002s)
[803.341265] [115/142] (381s left) kms_ccs (pipe-c-ccs-on-another-bo-4_tiled_mtl_rc_ccs)
[803.852830] Starting subtest: pipe-C-ccs-on-another-bo-4_tiled_mtl_rc_ccs
[803.894187] Subtest pipe-C-ccs-on-another-bo-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[804.221658] [116/142] (380s left) syncobj_timeline (invalid-signal-bad-pad)
[804.454740] Starting subtest: invalid-signal-bad-pad
[804.476030] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[804.575067] [117/142] (380s left) drm_read (empty-nonblock)
[805.010646] Starting subtest: empty-nonblock
[805.029451] Subtest empty-nonblock: SUCCESS (0.000s)
[805.152201] [118/142] (380s left) kms_ccs (pipe-c-missing-ccs-buffer-4_tiled_mtl_mc_ccs)
[805.661424] Starting subtest: pipe-C-missing-ccs-buffer-4_tiled_mtl_mc_ccs
[805.700739] Subtest pipe-C-missing-ccs-buffer-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[806.031872] [119/142] (379s left) kms_sequence (get-busy)
[806.336722] Starting subtest: get-busy
[806.355349] Starting dynamic subtest: DP-1-pipe-A
[808.801064] Dynamic subtest DP-1-pipe-A: SUCCESS (2.455s)
[808.845317] Starting dynamic subtest: DP-1-pipe-B
[811.424726] Dynamic subtest DP-1-pipe-B: SUCCESS (2.618s)
[811.469206] Starting dynamic subtest: DP-1-pipe-C
[814.062577] Dynamic subtest DP-1-pipe-C: SUCCESS (2.640s)
[814.083944] Subtest get-busy: SUCCESS (7.725s)
[814.398210] [120/142] (371s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-downscaling)
[814.705607] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-downscaling
[814.724474] Starting dynamic subtest: pipe-A-valid-mode
[815.352872] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.641s)
[815.372120] Subtest flip-64bpp-xtile-to-16bpp-xtile-downscaling: SUCCESS (0.645s)
[815.703345] [121/142] (369s left) kms_atomic_interruptible (atomic-setmode)
[816.007560] Starting subtest: atomic-setmode
[816.027633] Starting dynamic subtest: DP-1-pipe-A
[822.561117] Dynamic subtest DP-1-pipe-A: SUCCESS (6.534s)
[822.581677] Subtest atomic-setmode: SUCCESS (6.539s)
[822.906381] [122/142] (362s left) gem_exec_schedule (preempt-queue-contexts)
[823.306181] Starting subtest: preempt-queue-contexts
[823.325048] Starting dynamic subtest: rcs0
[824.110621] Dynamic subtest rcs0: SUCCESS (0.962s)
[824.165347] Starting dynamic subtest: bcs0
[825.096766] Dynamic subtest bcs0: SUCCESS (0.984s)
[825.154737] Starting dynamic subtest: vcs0
[826.048280] Dynamic subtest vcs0: SUCCESS (0.952s)
[826.105818] Starting dynamic subtest: vecs0
[827.027938] Dynamic subtest vecs0: SUCCESS (0.975s)
[827.048371] Subtest preempt-queue-contexts: SUCCESS (3.884s)
[827.421125] [123/142] (358s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[827.801237] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[827.838197] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[827.937625] [124/142] (357s left) gen7_exec_parse (chained-batch)
[828.197179] Subtest chained-batch: SKIP (0.000s)
[828.290461] [125/142] (357s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[828.588356] Starting subtest: addfb25-y-tiled-legacy
[828.606893] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[828.730300] [126/142] (357s left) kms_chamelium_color (ctm-red-to-blue)
[829.052222] Subtest ctm-red-to-blue: SKIP (0.000s)
[829.149088] [127/142] (356s left) template (b)
[829.372686] Starting subtest: B
[829.391188] Subtest B: SUCCESS (0.000s)
[829.492641] [128/142] (356s left) gem_exec_reloc (basic-write-cpu-active)
[829.726097] Starting subtest: basic-write-cpu-active
[829.839989] Subtest basic-write-cpu-active: SUCCESS (0.135s)
[830.059932] [129/142] (356s left) kms_cursor_edge_walk (128x128-left-edge)
[830.365415] Starting subtest: 128x128-left-edge
[830.384823] Starting dynamic subtest: pipe-A-DP-1
[834.051168] Dynamic subtest pipe-A-DP-1: SUCCESS (3.668s)
[834.101472] Starting dynamic subtest: pipe-C-DP-1
[837.527017] Dynamic subtest pipe-C-DP-1: SUCCESS (3.480s)
[837.552070] Subtest 128x128-left-edge: SUCCESS (7.158s)
[837.876415] [130/142] (348s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[838.264627] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-cpu
[838.316040] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[838.433310] [131/142] (348s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-mmap-cpu)
[838.817710] Starting subtest: psr-1p-offscren-pri-indfb-draw-mmap-cpu
[838.858639] Subtest psr-1p-offscren-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[838.953337] [132/142] (347s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt)
[839.320550] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt
[841.471478] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SUCCESS (2.215s)
[841.645924] [133/142] (345s left) gem_ctx_isolation (nonpriv)
[841.881517] Starting subtest: nonpriv
[841.902793] Starting dynamic subtest: rcs0
[844.419123] Dynamic subtest rcs0: SUCCESS (2.371s)
[844.461583] Starting dynamic subtest: bcs0
[847.511936] Dynamic subtest bcs0: SUCCESS (3.088s)
[847.556683] Starting dynamic subtest: vcs0
[849.835961] Dynamic subtest vcs0: SUCCESS (2.318s)
[849.877650] Starting dynamic subtest: vecs0
[852.153660] Dynamic subtest vecs0: SUCCESS (2.312s)
[852.175211] Subtest nonpriv: SUCCESS (10.100s)
[852.272203] [134/142] (334s left) gem_ctx_persistence (userptr)
[852.505930] Starting subtest: userptr
[852.615891] Subtest userptr: SUCCESS (0.123s)
[852.736651] [135/142] (334s left) gem_exec_nop (basic-sequential)
[853.129346] Starting subtest: basic-sequential
[856.119954] Subtest basic-sequential: SUCCESS (3.122s)
[856.364292] [136/142] (330s left) kms_ccs (pipe-c-bad-aux-stride-y_tiled_ccs)
[856.885985] Starting subtest: pipe-C-bad-aux-stride-y_tiled_ccs
[856.928939] Subtest pipe-C-bad-aux-stride-y_tiled_ccs: SKIP (0.000s)
[857.249679] [137/142] (329s left) gem_vm_create (execbuf)
[857.489032] Starting subtest: execbuf
[857.507533] Subtest execbuf: SUCCESS (0.011s)
[857.634793] [138/142] (329s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[857.963136] Starting subtest: yf-tiled-64bpp-rotate-0
[857.999734] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[858.101960] [139/142] (329s left) kms_ccs (pipe-b-bad-rotation-90-4_tiled_mtl_rc_ccs_cc)
[858.528880] Starting subtest: pipe-B-bad-rotation-90-4_tiled_mtl_rc_ccs_cc
[858.570648] Subtest pipe-B-bad-rotation-90-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[858.980654] [140/142] (328s left) kms_flip (2x-busy-flip)
[859.304856] Starting subtest: 2x-busy-flip
[859.341928] Subtest 2x-busy-flip: SKIP (0.003s)
[859.440616] [141/142] (328s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-onoff)
[859.813369] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-onoff
[859.853950] Subtest fbcpsr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[859.953763] [142/142] (327s left) vgem_basic (setversion)
[860.190364] Starting subtest: setversion
[860.231208] Subtest setversion: SUCCESS (0.000s)
[860.303164] Closing watchdogs
Done.