[51.661767] Initializing watchdogs
[51.662326]   /dev/watchdog0
[51.694773] [001/142] (960s left) kms_chamelium_color (ctm-0-50)
[52.242573] Subtest ctm-0-50: SKIP (0.000s)
[52.354132] [002/142] (959s left) kms_ccs (pipe-b-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs)
[53.669332] Starting subtest: pipe-B-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs
[53.685838] Subtest pipe-B-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs: SKIP (0.013s)
[54.702764] [003/142] (957s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[55.054534] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu
[65.274698] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (10.229s)
[65.547453] [004/142] (946s left) kms_cursor_legacy (2x-long-cursor-vs-flip-legacy)
[66.113891] Starting subtest: 2x-long-cursor-vs-flip-legacy
[66.131251] Subtest 2x-long-cursor-vs-flip-legacy: SKIP (0.015s)
[66.444672] [005/142] (946s left) perf (invalid-open-flags)
[67.366718] Starting subtest: invalid-open-flags
[67.370536] Subtest invalid-open-flags: SUCCESS (0.002s)
[67.455446] [006/142] (945s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-mc-ccs)
[67.857666] Starting subtest: y-tiled-ccs-to-yf-tiled-mc-ccs
[67.863541] Subtest y-tiled-ccs-to-yf-tiled-mc-ccs: SKIP (0.000s)
[68.112873] [007/142] (944s left) syncobj_wait (multi-wait-all-signaled)
[68.478800] Starting subtest: multi-wait-all-signaled
[68.482409] Subtest multi-wait-all-signaled: SUCCESS (0.002s)
[68.606579] [008/142] (944s left) gem_render_copy_redux (normal)
[69.027333] Starting subtest: normal
[71.739848] Subtest normal: SUCCESS (2.712s)
[71.894261] [009/142] (940s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[72.415889] Starting subtest: x-tiled-32bpp-rotate-270
[72.476524] Subtest x-tiled-32bpp-rotate-270: SKIP (0.057s)
[72.648889] [010/142] (940s left) api_intel_bb (object-noreloc-purge-cache-simple)
[73.031290] Starting subtest: object-noreloc-purge-cache-simple
[73.046935] Subtest object-noreloc-purge-cache-simple: SUCCESS (0.019s)
[73.209108] [011/142] (939s left) gem_exec_balancer (parallel-ordering)
[74.428308] Starting subtest: parallel-ordering
[80.846404] Subtest parallel-ordering: SUCCESS (6.418s)
[81.008697] [012/142] (931s left) kms_setmode (basic-clone-single-crtc)
[81.387820] Starting subtest: basic-clone-single-crtc
[81.398775] Subtest basic-clone-single-crtc: SKIP (0.003s)
[81.550471] [013/142] (931s left) gen7_exec_parse (bitmasks)
[81.946275] Subtest bitmasks: SKIP (0.000s)
[82.065401] [014/142] (931s left) kms_ccs (pipe-d-crc-primary-rotation-180-y_tiled_gen12_rc_ccs_cc)
[83.203913] Starting subtest: pipe-D-crc-primary-rotation-180-y_tiled_gen12_rc_ccs_cc
[83.210894] Subtest pipe-D-crc-primary-rotation-180-y_tiled_gen12_rc_ccs_cc: SKIP (0.001s)
[83.903074] [015/142] (929s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[84.430783] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip
[84.437987] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[84.587184] [016/142] (928s left) kms_ccs (pipe-c-bad-pixel-format-4_tiled_mtl_mc_ccs)
[85.917645] Starting subtest: pipe-C-bad-pixel-format-4_tiled_mtl_mc_ccs
[85.925369] Subtest pipe-C-bad-pixel-format-4_tiled_mtl_mc_ccs: SUCCESS (0.006s)
[86.931196] [017/142] (926s left) fbdev (info)
[87.288578] Starting subtest: info
[87.292241] Subtest info: SUCCESS (0.000s)
[87.433635] [018/142] (925s left) gem_eio (hibernate)
[88.398730] Starting subtest: hibernate
[113.852565] Subtest hibernate: SUCCESS (25.454s)
[117.340876] [019/142] (897s left) gem_render_copy (y-tiled-mc-ccs-to-yf-tiled-ccs)
[117.743623] Starting subtest: y-tiled-mc-ccs-to-yf-tiled-ccs
[117.749247] Subtest y-tiled-mc-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[118.045717] [020/142] (897s left) gem_lmem_swapping (random-engines)
[118.445140] Subtest random-engines: SKIP (0.000s)
[118.560758] [021/142] (896s left) kms_lease (possible-crtcs-filtering)
[119.043447] Starting subtest: possible-crtcs-filtering
[119.069860] Subtest possible-crtcs-filtering: SUCCESS (0.027s)
[119.236709] [022/142] (896s left) kms_rotation_crc (cursor-rotation-180)
[119.704260] Starting subtest: cursor-rotation-180
[121.848505] Subtest cursor-rotation-180: SUCCESS (2.144s)
[123.316603] [023/142] (892s left) gem_exec_schedule (u-submit-late-slice)
[123.752419] Starting subtest: u-submit-late-slice
[123.755961] Starting dynamic subtest: rcs0
[123.900909] Dynamic subtest rcs0: SUCCESS (0.146s)
[123.944335] Starting dynamic subtest: bcs0
[124.027994] Dynamic subtest bcs0: SUCCESS (0.126s)
[124.071696] Starting dynamic subtest: vcs0
[124.137965] Dynamic subtest vcs0: SUCCESS (0.109s)
[124.170679] Starting dynamic subtest: vcs1
[124.238197] Dynamic subtest vcs1: SUCCESS (0.100s)
[124.274831] Starting dynamic subtest: vecs0
[124.354268] Dynamic subtest vecs0: SUCCESS (0.115s)
[124.397706] Starting dynamic subtest: ccs0
[124.491315] Dynamic subtest ccs0: SUCCESS (0.136s)
[124.495329] Subtest u-submit-late-slice: SUCCESS (0.739s)
[125.681965] [024/142] (889s left) kms_invalid_mode (zero-vdisplay)
[126.165501] Starting subtest: zero-vdisplay
[126.168908] Starting dynamic subtest: eDP-1-pipe-A
[126.557804] Dynamic subtest eDP-1-pipe-A: SUCCESS (0.389s)
[126.608090] Starting dynamic subtest: eDP-1-pipe-B
[126.611819] Dynamic subtest eDP-1-pipe-B: SUCCESS (0.038s)
[126.656167] Starting dynamic subtest: eDP-1-pipe-C
[126.659837] Dynamic subtest eDP-1-pipe-C: SUCCESS (0.038s)
[126.703612] Starting dynamic subtest: eDP-1-pipe-D
[126.707158] Dynamic subtest eDP-1-pipe-D: SUCCESS (0.039s)
[126.710190] Subtest zero-vdisplay: SUCCESS (0.528s)
[127.483448] [025/142] (888s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-wc)
[127.982259] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-wc
[138.261800] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (10.284s)
[138.558612] [026/142] (877s left) perf_pmu (module-unload)
[138.988627] Starting subtest: module-unload
[187.271901] Subtest module-unload: SUCCESS (48.282s)
[187.341624] [027/142] (828s left) kms_psr2_su (page_flip-p010)
[190.314272] Subtest page_flip-P010: SKIP (0.000s)
[190.427387] [028/142] (825s left) kms_writeback (writeback-fb-id)
[190.972314] Subtest writeback-fb-id: SKIP (0.000s)
[191.083980] [029/142] (824s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-pwrite)
[191.590143] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-pwrite
[191.597383] Subtest psr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[191.763127] [030/142] (824s left) gem_tiled_blits (normal)
[192.177668] Subtest normal: SKIP (0.000s)
[192.289666] [031/142] (823s left) gem_flink_basic (bad-open)
[192.655198] Starting subtest: bad-open
[192.659366] Subtest bad-open: SUCCESS (0.000s)
[192.789066] [032/142] (823s left) i915_pm_rpm (gem-execbuf-stress)
[199.162145] Starting subtest: gem-execbuf-stress
[199.165963] Starting dynamic subtest: smem0
[241.423555] Dynamic subtest smem0: SUCCESS (42.259s)
[241.465141] Starting dynamic subtest: extra-wait-smem0
[329.717792] Dynamic subtest extra-wait-smem0: SUCCESS (88.289s)
[329.721399] Subtest gem-execbuf-stress: SUCCESS (130.553s)
[330.287886] [033/142] (686s left) gem_exec_flush (basic-uc-prw-default)
[330.715227] Starting subtest: basic-uc-prw-default
[336.565201] Subtest basic-uc-prw-default: SUCCESS (5.849s)
[336.874456] [034/142] (679s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[337.382712] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[350.666404] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (13.288s)
[350.899151] [035/142] (665s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[351.403841] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc
[351.409778] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.001s)
[351.575118] [036/142] (665s left) kms_ccs (pipe-a-crc-primary-basic-4_tiled_dg2_rc_ccs_cc)
[352.486352] Starting subtest: pipe-A-crc-primary-basic-4_tiled_dg2_rc_ccs_cc
[352.493261] Subtest pipe-A-crc-primary-basic-4_tiled_dg2_rc_ccs_cc: SKIP (0.002s)
[353.945485] [037/142] (662s left) i915_pm_rpm (debugfs-read)
[356.727976] Starting subtest: debugfs-read
[477.655236] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[479.645467] Closing watchdogs
[479.659434] Initializing watchdogs
[479.659796]   /dev/watchdog0
[479.714408] [038/142] (537s left) kms_big_joiner (basic)
[480.192119] Subtest basic: SKIP (0.000s)
[480.320696] [039/142] (536s left) kms_flip (2x-flip-vs-panning)
[480.718536] Starting subtest: 2x-flip-vs-panning
[480.881255] Subtest 2x-flip-vs-panning: SKIP (0.162s)
[481.088358] [040/142] (535s left) i915_hangman (detector)
[481.522123] Starting subtest: detector
[481.525375] Starting dynamic subtest: rcs0
[493.553178] Dynamic subtest rcs0: SUCCESS (12.033s)
[493.597669] Starting dynamic subtest: bcs0
[505.519834] Dynamic subtest bcs0: SUCCESS (11.963s)
[505.564851] Starting dynamic subtest: vcs0
[510.751983] Dynamic subtest vcs0: FAIL (5.178s)
[510.799529] Starting dynamic subtest: vcs1
[515.719913] Dynamic subtest vcs1: FAIL (4.962s)
[515.766739] Starting dynamic subtest: vecs0
[520.358173] Dynamic subtest vecs0: FAIL (4.636s)
[520.404431] Starting dynamic subtest: ccs0
[531.504088] Dynamic subtest ccs0: SUCCESS (11.194s)
[531.507666] Subtest detector: FAIL (49.989s)
[531.708724] [041/142] (485s left) kms_atomic_interruptible (legacy-pageflip)
[532.195773] Starting subtest: legacy-pageflip
[532.205371] Starting dynamic subtest: eDP-1-pipe-A
[540.034615] Dynamic subtest eDP-1-pipe-A: SUCCESS (7.828s)
[540.038404] Subtest legacy-pageflip: SUCCESS (7.838s)
[540.989051] [042/142] (476s left) gem_exec_params (invalid-fence-in-submit)
[541.388328] Starting subtest: invalid-fence-in-submit
[541.405172] Subtest invalid-fence-in-submit: SUCCESS (0.011s)
[541.569335] [043/142] (475s left) gem_mmap_wc (set-cache-level)
[541.945871] Subtest set-cache-level: SKIP (0.000s)
[542.068690] [044/142] (475s left) gem_mmap_wc (close)
[542.432780] Subtest close: SKIP (0.000s)
[542.551225] [045/142] (474s left) gem_exec_reloc (basic-cpu-read)
[542.950835] Subtest basic-cpu-read: SKIP (0.000s)
[543.102752] [046/142] (474s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-mmap-cpu)
[543.607355] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-mmap-cpu
[550.392178] Subtest fbc-1p-offscren-pri-shrfb-draw-mmap-cpu: SUCCESS (6.790s)
[550.595489] [047/142] (467s left) kms_scaling_modes (scaling-mode-full-aspect)
[551.092423] Starting subtest: scaling-mode-full-aspect
[551.101400] Starting dynamic subtest: eDP-1-pipe-A
[551.512474] Dynamic subtest eDP-1-pipe-A: SUCCESS (0.415s)
[551.570343] Starting dynamic subtest: eDP-1-pipe-B
[553.128205] Dynamic subtest eDP-1-pipe-B: SUCCESS (1.607s)
[553.180282] Starting dynamic subtest: eDP-1-pipe-C
[554.822386] Dynamic subtest eDP-1-pipe-C: SUCCESS (1.686s)
[554.873775] Starting dynamic subtest: eDP-1-pipe-D
[556.486081] Dynamic subtest eDP-1-pipe-D: SUCCESS (1.655s)
[556.489534] Subtest scaling-mode-full-aspect: SUCCESS (5.397s)
[558.010494] [048/142] (459s left) gem_exec_balancer (full)
[558.429520] Starting subtest: full
[569.033729] Subtest full: SUCCESS (10.604s)
[570.071524] [049/142] (447s left) kms_content_protection (type1)
[570.771167] Starting subtest: type1
[574.975536] Subtest type1: SKIP (4.202s)
[576.502420] [050/142] (441s left) gem_ctx_param (invalid-param-set)
[576.878341] Starting subtest: invalid-param-set
[576.881950] Subtest invalid-param-set: SUCCESS (0.000s)
[577.021272] [051/142] (440s left) gem_ctx_param (set-priority-invalid-size)
[577.393156] Starting subtest: set-priority-invalid-size
[577.397508] Subtest set-priority-invalid-size: SUCCESS (0.000s)
[577.544122] [052/142] (440s left) kms_flip (flip-vs-dpms-off-vs-modeset-interruptible)
[577.964429] Starting subtest: flip-vs-dpms-off-vs-modeset-interruptible
[578.002822] Starting dynamic subtest: A-eDP1
[582.556229] Dynamic subtest A-eDP1: SUCCESS (4.553s)
[582.603205] Starting dynamic subtest: B-eDP1
[586.229284] Dynamic subtest B-eDP1: SUCCESS (3.668s)
[586.275282] Starting dynamic subtest: C-eDP1
[589.880634] Dynamic subtest C-eDP1: SUCCESS (3.651s)
[589.929698] Starting dynamic subtest: D-eDP1
[593.552546] Dynamic subtest D-eDP1: SUCCESS (3.662s)
[593.575317] Subtest flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (15.610s)
[594.530196] [053/142] (423s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-pwrite)
[595.032071] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-pwrite
[595.038716] Subtest psr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[595.171077] [054/142] (423s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[595.748560] Starting subtest: basic-flip-after-cursor-legacy
[597.158827] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.411s)
[598.125880] [055/142] (420s left) vc4/vc4_purgeable_bo (mark-unpurgeable-check-retained)
[598.598241] Subtest mark-unpurgeable-check-retained: SKIP (0.000s)
[598.740651] [056/142] (419s left) gem_exec_reloc (basic-cpu-read-noreloc)
[599.147637] Subtest basic-cpu-read-noreloc: SKIP (0.000s)
[599.301248] [057/142] (419s left) kms_atomic_transition (plane-toggle-modeset-transition)
[599.792962] Starting subtest: plane-toggle-modeset-transition
[599.798440] Starting dynamic subtest: pipe-A-eDP-1
[614.454265] Dynamic subtest pipe-A-eDP-1: SUCCESS (14.648s)
[614.508816] Starting dynamic subtest: pipe-B-eDP-1
[629.030213] Dynamic subtest pipe-B-eDP-1: SUCCESS (14.574s)
[629.045811] Subtest plane-toggle-modeset-transition: SUCCESS (29.258s)
[629.989189] [058/142] (388s left) gem_exec_whisper (basic-forked)
[630.422340] Starting subtest: basic-forked
[652.131202] Subtest basic-forked: FAIL (21.695s)
[652.743347] [059/142] (365s left) vc4/vc4_wait_seqno (bad-seqno-1ns)
[653.227967] Subtest bad-seqno-1ns: SKIP (0.000s)
[653.377747] [060/142] (365s left) syncobj_timeline (single-wait-for-submit-signaled)
[653.765530] Starting subtest: single-wait-for-submit-signaled
[653.769133] Subtest single-wait-for-submit-signaled: SUCCESS (0.002s)
[653.954109] [061/142] (364s left) syncobj_wait (single-wait-for-submit-submitted)
[654.340893] Starting subtest: single-wait-for-submit-submitted
[654.436769] Subtest single-wait-for-submit-submitted: SUCCESS (0.101s)
[654.627551] [062/142] (364s left) syncobj_wait (single-wait-signaled)
[655.000591] Starting subtest: single-wait-signaled
[655.004864] Subtest single-wait-signaled: SUCCESS (0.001s)
[655.189319] [063/142] (363s left) kms_ccs (pipe-d-bad-rotation-90-y_tiled_gen12_rc_ccs)
[656.848795] Starting subtest: pipe-D-bad-rotation-90-y_tiled_gen12_rc_ccs
[656.855100] Subtest pipe-D-bad-rotation-90-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[657.636790] [064/142] (361s left) syncobj_timeline (multi-wait-submitted-signaled)
[658.026198] Starting subtest: multi-wait-submitted-signaled
[658.029725] Subtest multi-wait-submitted-signaled: SUCCESS (0.003s)
[658.186659] [065/142] (361s left) kms_flip (flip-vs-suspend-interruptible)
[658.610499] Starting subtest: flip-vs-suspend-interruptible
[658.643413] Starting dynamic subtest: A-eDP1
[694.208097] Dynamic subtest A-eDP1: SUCCESS (35.565s)
[694.248769] Starting dynamic subtest: B-eDP1
[729.191233] Dynamic subtest B-eDP1: SUCCESS (34.977s)
[729.227305] Starting dynamic subtest: C-eDP1
[764.202481] Dynamic subtest C-eDP1: SUCCESS (35.008s)
[764.244085] Starting dynamic subtest: D-eDP1
[799.203744] Dynamic subtest D-eDP1: SUCCESS (34.997s)
[799.224308] Subtest flip-vs-suspend-interruptible: SUCCESS (140.614s)
[800.610654] [066/142] (218s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled)
[800.982732] Starting subtest: multi-wait-all-for-submit-submitted-signaled
[801.084719] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.102s)
[801.242935] [067/142] (218s left) kms_big_fb (4-tiled-8bpp-rotate-180)
[801.791131] Starting subtest: 4-tiled-8bpp-rotate-180
[805.658846] Subtest 4-tiled-8bpp-rotate-180: SUCCESS (3.865s)
[805.969864] [068/142] (213s left) gem_mmap_wc (write-wc-read-gtt)
[806.353882] Subtest write-wc-read-gtt: SKIP (0.000s)
[806.482593] [069/142] (213s left) sysfs_timeslice_duration (invalid)
[806.874672] Starting subtest: invalid
[806.884115] Starting dynamic subtest: vecs0
[806.887684] Dynamic subtest vecs0: SUCCESS (0.002s)
[806.943473] Starting dynamic subtest: ccs0
[806.947208] Dynamic subtest ccs0: SUCCESS (0.002s)
[807.001313] Starting dynamic subtest: vcs0
[807.004425] Dynamic subtest vcs0: SUCCESS (0.002s)
[807.055886] Starting dynamic subtest: bcs0
[807.059191] Dynamic subtest bcs0: SUCCESS (0.002s)
[807.110718] Starting dynamic subtest: rcs0
[807.114200] Dynamic subtest rcs0: SUCCESS (0.001s)
[807.165964] Starting dynamic subtest: vcs1
[807.169256] Dynamic subtest vcs1: SUCCESS (0.001s)
[807.172414] Subtest invalid: SUCCESS (0.040s)
[807.247306] [070/142] (212s left) kms_ccs (pipe-b-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc)
[808.530918] Starting subtest: pipe-B-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc
[808.534147] Subtest pipe-B-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.003s)
[809.672544] [071/142] (210s left) kms_plane_scaling (plane-upscale-with-rotation-20x20)
[810.169352] Starting subtest: plane-upscale-with-rotation-20x20
[810.175676] Starting dynamic subtest: pipe-A-eDP-1
[811.028693] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.853s)
[811.091249] Starting dynamic subtest: pipe-B-eDP-1
[813.164289] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.126s)
[813.225502] Starting dynamic subtest: pipe-C-eDP-1
[815.318969] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.143s)
[815.381181] Starting dynamic subtest: pipe-D-eDP-1
[817.406796] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.076s)
[817.410494] Subtest plane-upscale-with-rotation-20x20: SUCCESS (7.243s)
[818.902708] [072/142] (201s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt)
[819.414945] Starting subtest: fbcpsr-farfromfence-mmap-gtt
[819.422226] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s)
[819.589838] [073/142] (200s left) gem_mmap_gtt (basic-small-copy-odd)
[819.960388] Subtest basic-small-copy-odd: SKIP (0.000s)
[820.091555] [074/142] (200s left) gem_ctx_persistence (engines-persistence)
[820.496365] Starting subtest: engines-persistence
[820.499265] Starting dynamic subtest: rcs0
[821.256842] Dynamic subtest rcs0: SUCCESS (0.763s)
[821.315970] Starting dynamic subtest: bcs0
[822.026867] Dynamic subtest bcs0: SUCCESS (0.764s)
[822.056468] Starting dynamic subtest: vcs0
[822.796374] Dynamic subtest vcs0: SUCCESS (0.767s)
[822.855631] Starting dynamic subtest: vcs1
[823.548404] Dynamic subtest vcs1: SUCCESS (0.747s)
[823.606850] Starting dynamic subtest: vecs0
[824.333146] Dynamic subtest vecs0: SUCCESS (0.780s)
[824.393334] Starting dynamic subtest: ccs0
[825.081878] Dynamic subtest ccs0: SUCCESS (0.744s)
[825.085893] Subtest engines-persistence: SUCCESS (4.593s)
[825.307314] [075/142] (194s left) kms_plane_alpha_blend (constant-alpha-min)
[825.793711] Starting subtest: constant-alpha-min
[827.510426] Starting dynamic subtest: pipe-A-eDP-1
[828.871710] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.356s)
[828.926662] Starting dynamic subtest: pipe-D-eDP-1
[830.683997] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.809s)
[830.687771] Subtest constant-alpha-min: SUCCESS (4.891s)
[831.588619] [076/142] (188s left) v3d/v3d_submit_cl (bad-flag)
[831.797277] Subtest bad-flag: SKIP (0.000s)
[831.874294] [077/142] (188s left) kms_ccs (pipe-d-bad-aux-stride-4_tiled_mtl_rc_ccs_cc)
[832.866945] Starting subtest: pipe-D-bad-aux-stride-4_tiled_mtl_rc_ccs_cc
[832.870986] Subtest pipe-D-bad-aux-stride-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.002s)
[833.365432] [078/142] (187s left) gem_mmap_gtt (cpuset-big-copy)
[833.744441] Subtest cpuset-big-copy: SKIP (0.000s)
[833.876903] [079/142] (186s left) perf_pmu (interrupts)
[834.278446] Starting subtest: interrupts
[835.540188] Subtest interrupts: SUCCESS (1.265s)
[835.719162] [080/142] (184s left) syncobj_wait (wait-any-interrupted)
[836.093930] Starting subtest: wait-any-interrupted
[836.196853] Subtest wait-any-interrupted: SUCCESS (0.103s)
[836.357635] [081/142] (184s left) syncobj_wait (multi-wait-signaled)
[836.732725] Starting subtest: multi-wait-signaled
[836.736351] Subtest multi-wait-signaled: SUCCESS (0.002s)
[836.919481] [082/142] (183s left) gem_eio (in-flight-suspend)
[838.504619] Starting subtest: in-flight-suspend
[884.376786] Subtest in-flight-suspend: SUCCESS (45.873s)
[885.724395] [083/142] (135s left) gem_set_tiling_vs_blt (tiled-to-tiled)
[886.126532] Subtest tiled-to-tiled: SKIP (0.000s)
[886.255905] [084/142] (134s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[886.632451] Starting subtest: etime-multi-wait-all-for-submit-submitted
[886.741677] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.109s)
[886.902174] [085/142] (134s left) gem_userptr_blits (input-checking)
[887.300626] Starting subtest: input-checking
[887.303627] Subtest input-checking: SUCCESS (0.000s)
[887.553934] [086/142] (133s left) gem_exec_fence (submit-chain)
[888.075336] Starting subtest: submit-chain
[888.123206] Subtest submit-chain: SUCCESS (0.049s)
[890.000547] [087/142] (131s left) kms_chamelium_edid (vga-edid-read)
[890.888137] Subtest vga-edid-read: SKIP (0.000s)
[891.778889] [088/142] (129s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[892.322841] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip
[892.330056] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[892.511155] [089/142] (128s left) kms_cursor_crc (cursor-random-32x32)
[893.107208] Starting subtest: cursor-random-32x32
[893.348415] Subtest cursor-random-32x32: SKIP (0.235s)
[894.113276] [090/142] (127s left) kms_ccs (pipe-c-crc-primary-rotation-180-yf_tiled_ccs)
[895.526184] Starting subtest: pipe-C-crc-primary-rotation-180-yf_tiled_ccs
[895.533545] Subtest pipe-C-crc-primary-rotation-180-yf_tiled_ccs: SKIP (0.002s)
[896.500644] [091/142] (125s left) kms_frontbuffer_tracking (psr-slowdraw)
[897.013144] Starting subtest: psr-slowdraw
[928.460754] Subtest psr-slowdraw: SUCCESS (31.449s)
[928.724621] [092/142] ( 93s left) kms_flip (2x-wf_vblank-ts-check-interruptible)
[929.136365] Starting subtest: 2x-wf_vblank-ts-check-interruptible
[929.304186] Subtest 2x-wf_vblank-ts-check-interruptible: SKIP (0.163s)
[929.491209] [093/142] ( 92s left) kms_ccs (pipe-d-missing-ccs-buffer-4_tiled_mtl_mc_ccs)
[931.214186] Starting subtest: pipe-D-missing-ccs-buffer-4_tiled_mtl_mc_ccs
[931.383203] Subtest pipe-D-missing-ccs-buffer-4_tiled_mtl_mc_ccs: SUCCESS (0.169s)
[932.058959] [094/142] ( 89s left) perf_pmu (busy-accuracy-2)
[932.452102] Starting subtest: busy-accuracy-2
[932.455468] Starting dynamic subtest: rcs0
[935.553065] Dynamic subtest rcs0: SUCCESS (3.101s)
[935.620121] Starting dynamic subtest: bcs0
[938.675944] Dynamic subtest bcs0: SUCCESS (3.118s)
[938.733856] Starting dynamic subtest: vcs0
[941.779472] Dynamic subtest vcs0: SUCCESS (3.100s)
[941.848498] Starting dynamic subtest: vcs1
[944.897186] Dynamic subtest vcs1: SUCCESS (3.113s)
[944.957388] Starting dynamic subtest: vecs0
[948.008953] Dynamic subtest vecs0: SUCCESS (3.107s)
[948.069578] Starting dynamic subtest: ccs0
[951.127222] Dynamic subtest ccs0: SUCCESS (3.112s)
[951.131226] Subtest busy-accuracy-2: SUCCESS (18.678s)
[951.368552] [095/142] ( 70s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[951.863622] Starting subtest: addfb25-y-tiled-small-legacy
[951.870472] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[952.048984] [096/142] ( 70s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[952.558267] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[952.564800] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[952.742399] [097/142] ( 69s left) kms_color (ctm-red-to-blue)
[953.219770] Starting subtest: ctm-red-to-blue
[953.223396] Starting dynamic subtest: pipe-A
[954.466315] Dynamic subtest pipe-A: SUCCESS (1.242s)
[954.522955] Starting dynamic subtest: pipe-B
[956.295559] Dynamic subtest pipe-B: SUCCESS (1.827s)
[956.359185] Starting dynamic subtest: pipe-C
[957.730494] Dynamic subtest pipe-C: SUCCESS (1.431s)
[957.790677] Starting dynamic subtest: pipe-D
[959.179480] Dynamic subtest pipe-D: SUCCESS (1.440s)
[959.182477] Subtest ctm-red-to-blue: SUCCESS (5.953s)
[960.126971] [098/142] ( 62s left) gem_userptr_blits (mmap-offset-banned)
[960.583397] Starting subtest: mmap-offset-banned
[960.586907] Starting dynamic subtest: gtt
[960.593810] Dynamic subtest gtt: SKIP (0.000s)
[960.650664] Starting dynamic subtest: wb
[960.653925] Dynamic subtest wb: SUCCESS (0.001s)
[960.712947] Starting dynamic subtest: wc
[960.717096] Dynamic subtest wc: SUCCESS (0.001s)
[960.776886] Starting dynamic subtest: uc
[960.780460] Dynamic subtest uc: SUCCESS (0.001s)
[960.783459] Subtest mmap-offset-banned: SUCCESS (0.021s)
[960.860091] [099/142] ( 61s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[961.373143] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[961.380096] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[961.559599] [100/142] ( 61s left) gem_ctx_shared (single-timeline)
[961.981414] Starting subtest: single-timeline
[962.018784] Subtest single-timeline: SUCCESS (0.031s)
[962.349112] [101/142] ( 60s left) kms_ccs (pipe-c-crc-primary-basic-4_tiled_mtl_rc_ccs)
[963.723798] Starting subtest: pipe-C-crc-primary-basic-4_tiled_mtl_rc_ccs
[964.970952] Subtest pipe-C-crc-primary-basic-4_tiled_mtl_rc_ccs: SUCCESS (1.243s)
[966.267263] [102/142] ( 56s left) kms_cursor_crc (cursor-onscreen-32x10)
[966.943132] Starting subtest: cursor-onscreen-32x10
[967.240428] Subtest cursor-onscreen-32x10: SKIP (0.293s)
[968.113526] [103/142] ( 54s left) kms_ccs (pipe-b-bad-pixel-format-4_tiled_mtl_rc_ccs_cc)
[969.219328] Starting subtest: pipe-B-bad-pixel-format-4_tiled_mtl_rc_ccs_cc
[969.222968] Subtest pipe-B-bad-pixel-format-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.002s)
[970.538407] [104/142] ( 52s left) syncobj_basic (bad-pad-handle-to-fd)
[970.918096] Starting subtest: bad-pad-handle-to-fd
[970.922323] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[971.072935] [105/142] ( 52s left) gem_exec_fence (basic-await)
[971.654960] Starting subtest: basic-await
[971.658283] Starting dynamic subtest: rcs0
[971.908932] Dynamic subtest rcs0: SUCCESS (0.253s)
[971.972877] Starting dynamic subtest: bcs0
[972.142496] Dynamic subtest bcs0: SUCCESS (0.229s)
[972.200301] Starting dynamic subtest: vcs0
[972.374534] Dynamic subtest vcs0: SUCCESS (0.228s)
[972.433786] Starting dynamic subtest: vcs1
[972.599876] Dynamic subtest vcs1: SUCCESS (0.221s)
[972.657122] Starting dynamic subtest: vecs0
[972.828421] Dynamic subtest vecs0: SUCCESS (0.225s)
[972.891659] Starting dynamic subtest: ccs0
[973.056358] Dynamic subtest ccs0: SUCCESS (0.224s)
[973.059939] Subtest basic-await: SUCCESS (1.405s)
[975.128971] [106/142] ( 48s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[975.644779] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[975.651860] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[975.841978] [107/142] ( 47s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu)
[976.357978] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu
[992.881890] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu: SUCCESS (16.530s)
[993.185140] [108/142] ( 30s left) kms_ccs (pipe-b-random-ccs-data-y_tiled_gen12_rc_ccs)
[994.395582] Starting subtest: pipe-B-random-ccs-data-y_tiled_gen12_rc_ccs
[994.404714] Subtest pipe-B-random-ccs-data-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[995.576628] [109/142] ( 27s left) kms_pipe_b_c_ivb (from-pipe-c-to-b-with-3-lanes)
[995.953311] Subtest from-pipe-C-to-B-with-3-lanes: SKIP (0.000s)
[996.077905] [110/142] ( 27s left) v3d/v3d_submit_cl (bad-multisync-out-sync)
[996.554550] Subtest bad-multisync-out-sync: SKIP (0.000s)
[996.696649] [111/142] ( 27s left) v3d/v3d_submit_csd (job-perfmon)
[997.173238] Subtest job-perfmon: SKIP (0.000s)
[997.319804] [112/142] ( 26s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[997.849367] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip
[1000.775720] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip: FAIL (2.140s)
[1001.106732] [113/142] ( 22s left) gem_ctx_bad_destroy (invalid-default-ctx)
[1001.486197] Starting subtest: invalid-default-ctx
[1001.490322] Subtest invalid-default-ctx: SUCCESS (0.000s)
[1001.647219] [114/142] ( 22s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[1004.561415] Subtest protected-encrypted-src-copy-not-readible: SKIP (0.000s)
[1004.744415] [115/142] ( 19s left) kms_cursor_edge_walk (64x64-top-edge)
[1005.242647] Starting subtest: 64x64-top-edge
[1005.251628] Starting dynamic subtest: pipe-A-eDP-1
[1009.202233] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.951s)
[1009.266658] Starting dynamic subtest: pipe-D-eDP-1
[1012.665752] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.450s)
[1012.669941] Subtest 64x64-top-edge: SUCCESS (7.422s)
[1013.630347] [116/142] ( 10s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[1014.142619] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-gtt
[1014.149647] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[1014.322191] [117/142] (  9s left) api_intel_bb (render)
[1014.697301] Starting subtest: render
[1014.700304] Starting dynamic subtest: render-none-512
[1015.045938] Dynamic subtest render-none-512: SUCCESS (0.351s)
[1015.102717] Starting dynamic subtest: render-none-1024
[1016.286590] Dynamic subtest render-none-1024: SUCCESS (1.236s)
[1016.345401] Starting dynamic subtest: render-x-512
[1016.634098] Dynamic subtest render-x-512: SUCCESS (0.343s)
[1016.690163] Starting dynamic subtest: render-x-1024
[1017.761153] Dynamic subtest render-x-1024: SUCCESS (1.123s)
[1017.822258] Starting dynamic subtest: render-y-512
[1018.074257] Dynamic subtest render-y-512: SUCCESS (0.305s)
[1018.130917] Starting dynamic subtest: render-y-1024
[1019.200341] Dynamic subtest render-y-1024: SUCCESS (1.122s)
[1019.203888] Subtest render: SUCCESS (4.510s)
[1019.402847] [118/142] (  4s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[1019.785397] Starting subtest: invalid-single-wait-unsubmitted
[1019.788877] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[1019.976440] [119/142] (  4s left) kms_atomic (plane-cursor-legacy)
[1020.786876] Starting subtest: plane-cursor-legacy
[1020.924775] Subtest plane-cursor-legacy: SUCCESS (0.136s)
[1022.271448] [120/142] (  2s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-blt)
[1022.785444] Starting subtest: psr-1p-primscrn-pri-indfb-draw-blt
[1036.419678] Subtest psr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (13.640s)
[1036.660795] Overall timeout time exceeded, stopping.
[1036.662085] Closing watchdogs
Done.