[49.801924] Initializing watchdogs
[49.802182]   /dev/watchdog0
[49.814974] [001/142] (960s left) prime_mmap (test_errors)
[49.924131] Starting subtest: test_errors
[49.925755] Starting dynamic subtest: test_errors-smem
[50.197555] Dynamic subtest test_errors-smem: SUCCESS (0.270s)
[50.202280] Starting dynamic subtest: test_errors-lmem0
[50.468159] Dynamic subtest test_errors-lmem0: SUCCESS (0.270s)
[50.469964] Subtest test_errors: SUCCESS (0.541s)
[50.544276] [002/142] (959s left) kms_flip (plain-flip-ts-check-interruptible)
[51.578142] Starting subtest: plain-flip-ts-check-interruptible
[51.589310] Starting dynamic subtest: A-HDMI-A4
[59.719399] Dynamic subtest A-HDMI-A4: SUCCESS (8.130s)
[59.724949] Starting dynamic subtest: B-HDMI-A4
[67.677724] Dynamic subtest B-HDMI-A4: SUCCESS (7.958s)
[67.682950] Starting dynamic subtest: C-HDMI-A4
[75.632614] Dynamic subtest C-HDMI-A4: SUCCESS (7.954s)
[75.638113] Starting dynamic subtest: D-HDMI-A4
[83.582625] Dynamic subtest D-HDMI-A4: SUCCESS (7.948s)
[83.585615] Subtest plain-flip-ts-check-interruptible: SUCCESS (32.003s)
[83.736750] [003/142] (926s left) kms_addfb_basic (small-bo)
[85.713830] Starting subtest: small-bo
[85.715639] Subtest small-bo: SUCCESS (0.000s)
[85.785017] [004/142] (924s left) v3d/v3d_wait_bo (bad-bo)
[85.905092] Subtest bad-bo: SKIP (0.000s)
[85.954582] [005/142] (924s left) gem_exec_schedule (preempt-self)
[86.056635] Starting subtest: preempt-self
[86.058104] Starting dynamic subtest: rcs0
[86.091742] Dynamic subtest rcs0: SUCCESS (0.035s)
[86.097165] Starting dynamic subtest: bcs0
[86.111615] Dynamic subtest bcs0: SUCCESS (0.020s)
[86.117030] Starting dynamic subtest: vcs0
[86.132718] Dynamic subtest vcs0: SUCCESS (0.021s)
[86.138184] Starting dynamic subtest: vcs1
[86.151231] Dynamic subtest vcs1: SUCCESS (0.018s)
[86.156604] Starting dynamic subtest: vecs0
[86.171775] Dynamic subtest vecs0: SUCCESS (0.020s)
[86.173235] Subtest preempt-self: SUCCESS (0.115s)
[86.534583] [006/142] (923s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt)
[88.517696] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt
[88.521246] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[88.601797] [007/142] (921s left) prime_vgem (fence-write-hang)
[88.804253] Subtest fence-write-hang: SKIP (0.000s)
[88.917456] [008/142] (921s left) gem_sync (basic-all)
[89.040865] Starting subtest: basic-all
[91.375056] Subtest basic-all: SUCCESS (2.338s)
[91.552789] [009/142] (918s left) kms_ccs (pipe-c-bad-pixel-format-4_tiled_mtl_mc_ccs)
[93.762011] Starting subtest: pipe-C-bad-pixel-format-4_tiled_mtl_mc_ccs
[93.765657] Subtest pipe-C-bad-pixel-format-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[94.053059] [010/142] (916s left) syncobj_timeline (single-wait-available-submitted)
[94.123913] Starting subtest: single-wait-available-submitted
[94.125437] Subtest single-wait-available-submitted: SUCCESS (0.000s)
[94.186195] [011/142] (916s left) gem_ctx_persistence (legacy-engines-hostile)
[94.258655] Starting subtest: legacy-engines-hostile
[94.260166] Starting dynamic subtest: render
[94.317582] Dynamic subtest render: SUCCESS (0.059s)
[94.322963] Starting dynamic subtest: bsd1
[94.384696] Dynamic subtest bsd1: SUCCESS (0.067s)
[94.390258] Starting dynamic subtest: bsd2
[94.488839] Dynamic subtest bsd2: SUCCESS (0.104s)
[94.495735] Starting dynamic subtest: blt
[94.566282] Dynamic subtest blt: SUCCESS (0.076s)
[94.585021] Starting dynamic subtest: vebox
[94.609942] Dynamic subtest vebox: SUCCESS (0.043s)
[94.611650] Subtest legacy-engines-hostile: SUCCESS (0.351s)
[94.733453] [012/142] (915s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-render)
[96.731437] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-render
[96.734592] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[96.801762] [013/142] (913s left) i915_pm_rpm (gem-idle)
[101.469076] Starting subtest: gem-idle
[110.014021] Subtest gem-idle: SUCCESS (8.547s)
[111.074425] [014/142] (899s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[113.045775] Starting subtest: disable-crc-after-crtc
[113.047533] Starting dynamic subtest: pipe-A-HDMI-A-4
[113.591123] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.544s)
[113.596787] Starting dynamic subtest: pipe-B-HDMI-A-4
[113.947307] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.354s)
[113.954219] Starting dynamic subtest: pipe-C-HDMI-A-4
[115.096942] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (1.147s)
[115.102910] Starting dynamic subtest: pipe-D-HDMI-A-4
[116.398756] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (1.300s)
[116.400428] Subtest disable-crc-after-crtc: SUCCESS (3.353s)
[116.515311] [015/142] (894s left) i915_query (test-query-geometry-subslices)
[116.590607] Starting subtest: test-query-geometry-subslices
[116.593985] Subtest test-query-geometry-subslices: SKIP (0.000s)
[116.648838] [016/142] (893s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[118.623089] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[118.625594] Starting dynamic subtest: pipe-A-valid-mode
[118.672264] Dynamic subtest pipe-A-valid-mode: SKIP (0.047s)
[118.673854] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.048s)
[118.758713] [017/142] (891s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-wc)
[120.741369] Starting subtest: fbcpsr-rgb565-draw-mmap-wc
[120.743506] Subtest fbcpsr-rgb565-draw-mmap-wc: SKIP (0.000s)
[120.824992] [018/142] (889s left) kms_plane_scaling (plane-downscale-with-modifiers-factor-0-25)
[122.818234] Starting subtest: plane-downscale-with-modifiers-factor-0-25
[122.820873] Starting dynamic subtest: pipe-A-HDMI-A-4
[122.862678] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.041s)
[122.867984] Starting dynamic subtest: pipe-B-HDMI-A-4
[122.902191] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.037s)
[122.907394] Starting dynamic subtest: pipe-C-HDMI-A-4
[122.942468] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.038s)
[122.947629] Starting dynamic subtest: pipe-D-HDMI-A-4
[122.982247] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.038s)
[122.984280] Subtest plane-downscale-with-modifiers-factor-0-25: SKIP (0.162s)
[123.075268] [019/142] (887s left) gem_exec_whisper (basic-queues-priority)
[123.153668] Starting subtest: basic-queues-priority
[145.222351] Subtest basic-queues-priority: SUCCESS (22.064s)
[145.440898] [020/142] (865s left) kms_ccs (pipe-d-bad-pixel-format-y_tiled_gen12_rc_ccs)
[147.792657] Starting subtest: pipe-D-bad-pixel-format-y_tiled_gen12_rc_ccs
[147.793687] Subtest pipe-D-bad-pixel-format-y_tiled_gen12_rc_ccs: SUCCESS (0.000s)
[147.982739] [021/142] (862s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[148.053138] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[148.160914] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.106s)
[148.215686] [022/142] (862s left) kms_ccs (pipe-b-crc-primary-basic-y_tiled_gen12_rc_ccs)
[150.335094] Starting subtest: pipe-B-crc-primary-basic-y_tiled_gen12_rc_ccs
[150.688689] Subtest pipe-B-crc-primary-basic-y_tiled_gen12_rc_ccs: SUCCESS (0.353s)
[151.109903] [023/142] (859s left) gem_exec_schedule (u-semaphore-resolve)
[151.190864] Starting subtest: u-semaphore-resolve
[151.244046] Subtest u-semaphore-resolve: SUCCESS (0.053s)
[151.605260] [024/142] (859s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[153.585745] Starting subtest: fbc-farfromfence-mmap-gtt
[153.587897] Subtest fbc-farfromfence-mmap-gtt: SKIP (0.000s)
[153.675094] [025/142] (857s left) gem_exec_flush (basic-uc-ro-default)
[153.777060] Subtest basic-uc-ro-default: SKIP (0.000s)
[153.817063] [026/142] (856s left) kms_cursor_crc (cursor-random-512x512)
[155.981325] Starting subtest: cursor-random-512x512
[155.984385] Subtest cursor-random-512x512: SKIP (0.002s)
[156.137915] [027/142] (854s left) kms_ccs (pipe-c-bad-pixel-format-4_tiled_dg2_mc_ccs)
[158.369469] Starting subtest: pipe-C-bad-pixel-format-4_tiled_dg2_mc_ccs
[158.371421] Subtest pipe-C-bad-pixel-format-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[158.660349] [028/142] (852s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary)
[160.640697] Starting subtest: psr-shrfb-scaledprimary
[160.642411] Subtest psr-shrfb-scaledprimary: SKIP (0.000s)
[160.713843] [029/142] (850s left) kms_vblank (pipe-a-ts-continuation-modeset-hang)
[162.704437] Starting subtest: pipe-A-ts-continuation-modeset-hang
[176.873174] Subtest pipe-A-ts-continuation-modeset-hang: SUCCESS (14.169s)
[176.978292] [030/142] (833s left) kms_lease (implicit-plane-lease)
[178.952462] Starting subtest: implicit-plane-lease
[178.955481] Subtest implicit-plane-lease: SUCCESS (0.002s)
[179.011446] [031/142] (831s left) kms_busy (basic)
[180.983622] Starting subtest: basic
[180.986358] Starting dynamic subtest: flip
[181.653402] Dynamic subtest flip: SUCCESS (0.667s)
[181.658828] Starting dynamic subtest: modeset
[182.265129] Dynamic subtest modeset: SUCCESS (0.612s)
[182.266089] Subtest basic: SUCCESS (1.282s)
[182.420239] [032/142] (828s left) kms_properties (get_properties-sanity-atomic)
[184.395511] Starting subtest: get_properties-sanity-atomic
[184.402998] Subtest get_properties-sanity-atomic: SUCCESS (0.008s)
[184.468752] [033/142] (826s left) kms_vblank (pipe-a-query-forked-hang)
[186.458561] Starting subtest: pipe-A-query-forked-hang
[199.857212] Subtest pipe-A-query-forked-hang: SUCCESS (13.399s)
[199.956372] [034/142] (810s left) gem_exec_reloc (basic-gtt-active)
[200.029163] Subtest basic-gtt-active: SKIP (0.000s)
[200.064623] [035/142] (810s left) gem_exec_whisper (basic-sync-all)
[200.142048] Starting subtest: basic-sync-all
[206.515828] Subtest basic-sync-all: SUCCESS (6.372s)
[206.735543] [036/142] (804s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[208.724317] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[208.726479] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[208.804779] [037/142] (802s left) kms_cursor_edge_walk (128x128-top-bottom)
[210.801463] Starting subtest: 128x128-top-bottom
[210.802473] Starting dynamic subtest: pipe-A-HDMI-A-4
[214.118220] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (3.314s)
[214.127594] Starting dynamic subtest: pipe-D-HDMI-A-4
[217.334803] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (3.215s)
[217.336519] Subtest 128x128-top-bottom: SUCCESS (6.535s)
[217.451257] [038/142] (793s left) kms_atomic_transition (plane-all-transition-fencing)
[219.429508] Starting subtest: plane-all-transition-fencing
[219.430560] Starting dynamic subtest: pipe-A-HDMI-A-4
[223.250511] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (3.820s)
[223.297623] Starting dynamic subtest: pipe-B-HDMI-A-4
[227.174955] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (3.882s)
[227.197646] Subtest plane-all-transition-fencing: SUCCESS (7.770s)
[227.316377] [039/142] (783s left) syncobj_timeline (multi-wait-for-submit-available-submitted)
[227.388194] Starting subtest: multi-wait-for-submit-available-submitted
[227.389218] Subtest multi-wait-for-submit-available-submitted: SUCCESS (0.001s)
[227.449322] [040/142] (783s left) gem_render_copy (yf-tiled)
[227.524473] Starting subtest: yf-tiled
[227.525425] Starting dynamic subtest: smem
[227.607697] Dynamic subtest smem: SUCCESS (0.083s)
[227.613695] Starting dynamic subtest: lmem0
[228.371733] Dynamic subtest lmem0: SUCCESS (0.760s)
[228.372673] Subtest yf-tiled: SUCCESS (0.845s)
[228.566455] [041/142] (782s left) kms_ccs (pipe-a-ccs-on-another-bo-4_tiled_mtl_mc_ccs)
[230.647518] Starting subtest: pipe-A-ccs-on-another-bo-4_tiled_mtl_mc_ccs
[230.652235] Subtest pipe-A-ccs-on-another-bo-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[231.072267] [042/142] (779s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[233.056128] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[236.040136] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (2.989s)
[236.172873] [043/142] (774s left) syncobj_wait (wait-all-for-submit-complex)
[236.246496] Starting subtest: wait-all-for-submit-complex
[236.466399] Subtest wait-all-for-submit-complex: SUCCESS (0.216s)
[236.538400] [044/142] (774s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe)
[238.563317] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s)
[238.620515] [045/142] (772s left) syncobj_timeline (invalid-multi-wait-unsubmitted)
[238.691294] Starting subtest: invalid-multi-wait-unsubmitted
[238.692330] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[238.759779] [046/142] (772s left) syncobj_basic (bad-pad-fd-to-handle)
[238.830251] Starting subtest: bad-pad-fd-to-handle
[238.831063] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s)
[238.892078] [047/142] (772s left) gem_ringfill (legacy-basic)
[239.346996] Starting subtest: legacy-basic
[239.348586] Starting dynamic subtest: default
[239.428624] Dynamic subtest default: SUCCESS (0.080s)
[239.438976] Starting dynamic subtest: render
[239.472937] Dynamic subtest render: SUCCESS (0.042s)
[239.504269] Starting dynamic subtest: bsd
[239.533210] Dynamic subtest bsd: SUCCESS (0.055s)
[239.556317] Starting dynamic subtest: bsd1
[239.586785] Dynamic subtest bsd1: SUCCESS (0.049s)
[239.615871] Starting dynamic subtest: bsd2
[239.642731] Dynamic subtest bsd2: SUCCESS (0.051s)
[239.659222] Starting dynamic subtest: blt
[239.692518] Dynamic subtest blt: SUCCESS (0.046s)
[239.723326] Starting dynamic subtest: vebox
[239.752654] Dynamic subtest vebox: SUCCESS (0.055s)
[239.754337] Subtest legacy-basic: SUCCESS (0.407s)
[239.854555] [048/142] (771s left) gem_ctx_persistence (many-contexts)
[239.936938] Starting subtest: many-contexts
[275.062796] Subtest many-contexts: SUCCESS (35.126s)
[275.140843] [049/142] (735s left) kms_ccs (pipe-a-crc-primary-basic-4_tiled_dg2_rc_ccs_cc)
[277.159644] Starting subtest: pipe-A-crc-primary-basic-4_tiled_dg2_rc_ccs_cc
[277.161398] Subtest pipe-A-crc-primary-basic-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[277.631446] [050/142] (733s left) v3d/v3d_perfmon (destroy-invalid-perfmon)
[277.749013] Subtest destroy-invalid-perfmon: SKIP (0.000s)
[277.798149] [051/142] (733s left) vc4/vc4_wait_bo (bad-pad)
[277.898859] Subtest bad-pad: SKIP (0.000s)
[277.948487] [052/142] (733s left) kms_writeback (writeback-check-output)
[281.823574] Subtest writeback-check-output: SKIP (0.000s)
[281.856585] [053/142] (729s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[283.844816] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[285.014988] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.169s)
[285.135040] [054/142] (726s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs)
[287.426965] Starting subtest: pipe-C-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs
[287.431041] Subtest pipe-C-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs: SKIP (0.003s)
[287.631834] [055/142] (723s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-onoff)
[289.613153] Starting subtest: fbc-1p-primscrn-cur-indfb-onoff
[292.249228] Subtest fbc-1p-primscrn-cur-indfb-onoff: SUCCESS (2.638s)
[292.364700] [056/142] (718s left) gem_eio (in-flight-contexts-immediate)
[292.566579] Starting subtest: in-flight-contexts-immediate
[306.253320] Subtest in-flight-contexts-immediate: SUCCESS (13.687s)
[308.304548] [057/142] (702s left) gem_caching (writes)
[308.377708] Starting subtest: writes
[312.671740] Subtest writes: SUCCESS (4.294s)
[312.777256] [058/142] (698s left) kms_content_protection (dp-mst-type-1)
[314.827157] Starting subtest: dp-mst-type-1
[314.829045] Subtest dp-mst-type-1: SKIP (0.000s)
[314.937047] [059/142] (696s left) gem_mmap_wc (write-wc-read-gtt)
[315.076577] Subtest write-wc-read-gtt: SKIP (0.000s)
[315.109148] [060/142] (696s left) gem_mmap_wc (write-cpu-read-wc-unflushed)
[315.179598] Subtest write-cpu-read-wc-unflushed: SKIP (0.000s)
[315.203617] [061/142] (696s left) kms_vblank (pipe-a-accuracy-idle)
[317.172848] Starting subtest: pipe-A-accuracy-idle
[319.401232] Subtest pipe-A-accuracy-idle: SUCCESS (2.228s)
[319.510202] [062/142] (691s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[321.490212] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[321.492454] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[321.576841] [063/142] (689s left) kms_chamelium_edid (dp-edid-resolution-list)
[323.619283] Subtest dp-edid-resolution-list: SKIP (0.000s)
[323.676520] [064/142] (687s left) gem_exec_reloc (basic-cpu-wc-active)
[323.748368] Subtest basic-cpu-wc-active: SKIP (0.000s)
[323.790976] [065/142] (687s left) syncobj_wait (wait-any-snapshot)
[323.860098] Starting subtest: wait-any-snapshot
[323.941530] Subtest wait-any-snapshot: SUCCESS (0.081s)
[324.026943] [066/142] (687s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[326.033377] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[326.035265] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[326.114876] [067/142] (685s left) kms_vblank (pipe-b-wait-busy-hang)
[328.107733] Starting subtest: pipe-B-wait-busy-hang
[342.875643] Subtest pipe-B-wait-busy-hang: SUCCESS (14.766s)
[342.975251] [068/142] (668s left) syncobj_timeline (invalid-wait-zero-handles)
[343.044733] Starting subtest: invalid-wait-zero-handles
[343.045565] Subtest invalid-wait-zero-handles: SUCCESS (0.000s)
[343.108233] [069/142] (668s left) v3d/v3d_job_submission (threaded-job-submission)
[343.208835] Subtest threaded-job-submission: SKIP (0.000s)
[343.258772] [070/142] (668s left) syncobj_wait (invalid-reset-illegal-handle)
[343.333911] Starting subtest: invalid-reset-illegal-handle
[343.334951] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[343.395920] [071/142] (668s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-upscaling)
[345.389778] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-upscaling
[345.390805] Starting dynamic subtest: pipe-A-valid-mode
[346.316203] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.926s)
[346.317893] Subtest flip-32bpp-ytile-to-64bpp-ytile-upscaling: SUCCESS (0.928s)
[346.457181] [072/142] (665s left) gem_exec_fair (basic-pace)
[346.533355] Subtest basic-pace: SKIP (0.000s)
[346.582324] [073/142] (664s left) kms_psr2_sf (cursor-plane-move-continuous-exceed-fully-sf)
[346.651872] Subtest cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[346.682373] [074/142] (664s left) kms_atomic (test-only)
[348.875296] Starting subtest: test-only
[349.327912] Subtest test-only: SUCCESS (0.452s)
[349.463922] [075/142] (662s left) kms_psr2_su (page_flip-xrgb8888)
[349.533448] Subtest page_flip-XRGB8888: SKIP (0.000s)
[349.564695] [076/142] (662s left) i915_pm_backlight (basic-brightness)
[351.538784] Subtest basic-brightness: SKIP (0.000s)
[351.565013] [077/142] (660s left) kms_plane (pixel-format)
[353.532983] Starting subtest: pixel-format
[353.536160] Starting dynamic subtest: pipe-A-planes
[371.542196] Dynamic subtest pipe-A-planes: SUCCESS (18.007s)
[371.550888] Starting dynamic subtest: pipe-B-planes
[389.592583] Dynamic subtest pipe-B-planes: SUCCESS (18.047s)
[389.593526] Subtest pixel-format: SUCCESS (36.061s)
[389.737602] [078/142] (621s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[389.810430] Subtest writes-after-reads-uncached: SKIP (0.000s)
[389.861008] [079/142] (621s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[391.836551] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[391.838635] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[391.902085] [080/142] (619s left) gem_create (busy-create)
[391.979634] Starting subtest: busy-create
[391.980585] Starting dynamic subtest: lmem0
[424.241257] Dynamic subtest lmem0: SUCCESS (32.261s)
[424.247723] Starting dynamic subtest: smem0
[456.502259] Dynamic subtest smem0: SUCCESS (32.260s)
[456.503208] Subtest busy-create: SUCCESS (64.523s)
[456.768675] [081/142] (554s left) kms_setmode (clone-exclusive-crtc)
[456.871203] Starting subtest: clone-exclusive-crtc
[456.874213] Subtest clone-exclusive-crtc: SKIP (0.000s)
[456.927609] [082/142] (554s left) gem_pxp (create-regular-buffer)
[459.516219] Subtest create-regular-buffer: SKIP (0.000s)
[459.579371] [083/142] (552s left) kms_frontbuffer_tracking (fbc-modesetfrombusy)
[461.561534] Starting subtest: fbc-modesetfrombusy
[464.431660] Subtest fbc-modesetfrombusy: SUCCESS (2.873s)
[464.533377] [084/142] (547s left) gem_cs_tlb (engines)
[464.606319] Starting subtest: engines
[464.607248] Starting dynamic subtest: rcs0
[466.762768] Dynamic subtest rcs0: SUCCESS (2.152s)
[466.769687] Starting dynamic subtest: bcs0
[468.917452] Dynamic subtest bcs0: SUCCESS (2.153s)
[468.924052] Starting dynamic subtest: vcs0
[471.071249] Dynamic subtest vcs0: SUCCESS (2.153s)
[471.077944] Starting dynamic subtest: vcs1
[473.225319] Dynamic subtest vcs1: SUCCESS (2.153s)
[473.234259] Starting dynamic subtest: vecs0
[475.378290] Dynamic subtest vecs0: SUCCESS (2.152s)
[475.379056] Subtest engines: SUCCESS (10.769s)
[475.475197] [085/142] (536s left) kms_rotation_crc (primary-rotation-180)
[477.468753] Starting subtest: primary-rotation-180
[487.742613] Subtest primary-rotation-180: SUCCESS (10.273s)
[487.923734] [086/142] (523s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render)
[489.896373] Starting subtest: psr-1p-primscrn-pri-indfb-draw-render
[489.898305] Subtest psr-1p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[489.956762] [087/142] (521s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[491.943888] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[491.946066] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[492.022990] [088/142] (519s left) gem_render_copy (y-tiled-ccs-to-linear)
[492.124558] Starting subtest: y-tiled-ccs-to-linear
[492.125668] Starting dynamic subtest: smem
[492.213008] Dynamic subtest smem: SUCCESS (0.088s)
[492.219951] Starting dynamic subtest: lmem0
[492.519944] Dynamic subtest lmem0: SUCCESS (0.303s)
[492.520882] Subtest y-tiled-ccs-to-linear: SUCCESS (0.393s)
[492.736906] [089/142] (519s left) kms_big_fb (yf-tiled-32bpp-rotate-270)
[494.717314] Starting subtest: yf-tiled-32bpp-rotate-270
[494.719192] Subtest yf-tiled-32bpp-rotate-270: SKIP (0.000s)
[494.806973] [090/142] (517s left) kms_big_joiner (basic)
[496.823508] Subtest basic: SKIP (0.000s)
[496.848141] [091/142] (514s left) kms_ccs (pipe-d-ccs-on-another-bo-y_tiled_ccs)
[499.220212] Starting subtest: pipe-D-ccs-on-another-bo-y_tiled_ccs
[499.222045] Subtest pipe-D-ccs-on-another-bo-y_tiled_ccs: SKIP (0.000s)
[499.343860] [092/142] (512s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[499.412234] Subtest addfb25-x-tiled-mismatch-legacy: SKIP (0.000s)
[501.410486] [093/142] (510s left) perf_pmu (cpu-hotplug)
[501.518514] Starting subtest: cpu-hotplug
[508.117461] Subtest cpu-hotplug: SUCCESS (6.595s)
[508.288432] [094/142] (503s left) gem_mmap_gtt (basic-read)
[508.362984] Subtest basic-read: SKIP (0.000s)
[508.385785] [095/142] (503s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-mmap-cpu)
[510.367494] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-mmap-cpu
[510.369535] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[510.447736] [096/142] (501s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[512.451283] Starting subtest: primary-4-tiled-reflect-x-180
[512.542403] Subtest primary-4-tiled-reflect-x-180: SKIP (0.092s)
[512.623155] [097/142] (499s left) gem_exec_fence (syncobj-channel)
[513.123505] Starting subtest: syncobj-channel
[514.151594] Subtest syncobj-channel: SUCCESS (1.023s)
[514.505960] [098/142] (497s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5)
[516.505921] Starting subtest: planes-unity-scaling-downscale-factor-0-5
[516.506993] Starting dynamic subtest: pipe-A-HDMI-A-4
[516.646888] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.141s)
[516.657873] Starting dynamic subtest: pipe-B-HDMI-A-4
[516.769625] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.120s)
[516.780513] Starting dynamic subtest: pipe-C-HDMI-A-4
[516.892758] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.120s)
[516.902327] Starting dynamic subtest: pipe-D-HDMI-A-4
[517.014588] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.119s)
[517.015610] Subtest planes-unity-scaling-downscale-factor-0-5: SUCCESS (0.511s)
[517.139392] [099/142] (494s left) perf_pmu (most-busy-idle-check-all)
[517.210821] Starting subtest: most-busy-idle-check-all
[517.211862] Starting dynamic subtest: rcs0
[517.871096] Dynamic subtest rcs0: SUCCESS (0.660s)
[517.880145] Starting dynamic subtest: bcs0
[518.519475] Dynamic subtest bcs0: SUCCESS (0.646s)
[518.538887] Starting dynamic subtest: vcs0
[519.176137] Dynamic subtest vcs0: SUCCESS (0.652s)
[519.187543] Starting dynamic subtest: vcs1
[519.825137] Dynamic subtest vcs1: SUCCESS (0.647s)
[519.838122] Starting dynamic subtest: vecs0
[520.473203] Dynamic subtest vecs0: SUCCESS (0.646s)
[520.474189] Subtest most-busy-idle-check-all: SUCCESS (3.263s)
[520.566049] [100/142] (491s left) kms_universal_plane (universal-plane-pageflip-windowed-pipe-b)
[522.539905] Starting subtest: universal-plane-pageflip-windowed-pipe-B
[523.414089] Subtest universal-plane-pageflip-windowed-pipe-B: SUCCESS (0.872s)
[523.547769] [101/142] (488s left) perf_pmu (faulting-read)
[523.619165] Starting subtest: faulting-read
[523.620140] Starting dynamic subtest: fixed
[523.621177] Dynamic subtest fixed: SUCCESS (0.001s)
[523.622055] Subtest faulting-read: SUCCESS (0.002s)
[523.714450] [102/142] (488s left) kms_ccs (pipe-d-random-ccs-data-4_tiled_mtl_rc_ccs_cc)
[526.077681] Starting subtest: pipe-D-random-ccs-data-4_tiled_mtl_rc_ccs_cc
[526.080681] Subtest pipe-D-random-ccs-data-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[526.222212] [103/142] (485s left) gem_exec_balancer (bonded-chain)
[526.298825] Starting subtest: bonded-chain
[533.623644] Subtest bonded-chain: SUCCESS (7.322s)
[534.014115] [104/142] (478s left) sw_sync (sync_busy)
[534.079914] Starting subtest: sync_busy
[534.082287] Subtest sync_busy: SUCCESS (0.002s)
[534.141772] [105/142] (478s left) kms_flip (2x-modeset-vs-vblank-race-interruptible)
[535.188481] Starting subtest: 2x-modeset-vs-vblank-race-interruptible
[535.214424] Subtest 2x-modeset-vs-vblank-race-interruptible: SKIP (0.025s)
[535.288216] [106/142] (476s left) kms_cursor_legacy (cursora-vs-flipb-varying-size)
[537.292116] Starting subtest: cursorA-vs-flipB-varying-size
[537.294286] Subtest cursorA-vs-flipB-varying-size: SKIP (0.002s)
[537.353349] [107/142] (474s left) feature_discovery (psr2)
[539.340904] Starting subtest: psr2
[539.342915] Subtest psr2: SKIP (0.000s)
[539.402636] [108/142] (472s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[541.383419] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt
[541.385456] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[541.452482] [109/142] (470s left) syncobj_basic (bad-pad-handle-to-fd)
[541.537813] Starting subtest: bad-pad-handle-to-fd
[541.538956] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[541.645122] [110/142] (470s left) kms_ccs (pipe-c-crc-primary-rotation-180-4_tiled_mtl_rc_ccs_cc)
[543.916634] Starting subtest: pipe-C-crc-primary-rotation-180-4_tiled_mtl_rc_ccs_cc
[543.918327] Subtest pipe-C-crc-primary-rotation-180-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[544.175162] [111/142] (468s left) v3d/v3d_submit_csd (valid-multisync-submission)
[544.274414] Subtest valid-multisync-submission: SKIP (0.000s)
[544.325456] [112/142] (467s left) kms_big_fb (x-tiled-32bpp-rotate-0)
[546.313141] Starting subtest: x-tiled-32bpp-rotate-0
[548.160801] Subtest x-tiled-32bpp-rotate-0: SUCCESS (1.844s)
[548.315320] [113/142] (463s left) kms_ccs (pipe-d-crc-primary-basic-y_tiled_gen12_rc_ccs)
[550.653948] Starting subtest: pipe-D-crc-primary-basic-y_tiled_gen12_rc_ccs
[551.002256] Subtest pipe-D-crc-primary-basic-y_tiled_gen12_rc_ccs: SUCCESS (0.349s)
[551.253637] [114/142] (461s left) vc4/vc4_wait_bo (bad-bo)
[551.352517] Subtest bad-bo: SKIP (0.000s)
[551.402942] [115/142] (460s left) kms_frontbuffer_tracking (fbcpsr-tiling-linear)
[553.384197] Starting subtest: fbcpsr-tiling-linear
[553.386108] Subtest fbcpsr-tiling-linear: SKIP (0.000s)
[553.454450] [116/142] (458s left) sw_sync (sync_merge)
[553.541049] Starting subtest: sync_merge
[553.542046] Subtest sync_merge: SUCCESS (0.000s)
[553.586788] [117/142] (458s left) kms_ccs (pipe-b-crc-primary-rotation-180-4_tiled_dg2_rc_ccs)
[555.732122] Starting subtest: pipe-B-crc-primary-rotation-180-4_tiled_dg2_rc_ccs
[555.734158] Subtest pipe-B-crc-primary-rotation-180-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[556.095153] [118/142] (456s left) drm_fdinfo (basics)
[556.165983] Subtest basics: SKIP (0.000s)
[556.186982] [119/142] (456s left) kms_flip (2x-nonexisting-fb)
[557.211437] Starting subtest: 2x-nonexisting-fb
[557.236125] Subtest 2x-nonexisting-fb: SKIP (0.025s)
[557.313037] [120/142] (455s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-cpu)
[559.324837] Starting subtest: fbcpsr-rgb101010-draw-mmap-cpu
[559.326894] Subtest fbcpsr-rgb101010-draw-mmap-cpu: SKIP (0.000s)
[559.429789] [121/142] (453s left) gen9_exec_parse (batch-zero-length)
[559.534072] Subtest batch-zero-length: SKIP (0.000s)
[559.570254] [122/142] (452s left) gem_mmap_gtt (fault-concurrent-x)
[559.639630] Subtest fault-concurrent-X: SKIP (0.000s)
[559.669921] [123/142] (452s left) kms_cursor_crc (cursor-rapid-movement-32x32)
[561.696654] Starting subtest: cursor-rapid-movement-32x32
[561.738441] Subtest cursor-rapid-movement-32x32: SKIP (0.038s)
[562.028176] [124/142] (450s left) kms_universal_plane (universal-plane-pipe-b-sanity)
[564.003041] Starting subtest: universal-plane-pipe-B-sanity
[564.881610] Subtest universal-plane-pipe-B-sanity: SUCCESS (0.880s)
[565.018386] [125/142] (447s left) i915_pm_rc6_residency (rc6-fence)
[565.087286] Starting subtest: rc6-fence
[583.479073] Subtest rc6-fence: SUCCESS (18.392s)
[583.762820] [126/142] (428s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled)
[583.831013] Starting subtest: multi-wait-all-for-submit-submitted-signaled
[583.936440] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.104s)
[584.030517] [127/142] (428s left) gem_exec_capture (capture)
[584.104300] Starting subtest: capture
[584.105124] Starting dynamic subtest: rcs0-smem
[585.009203] Dynamic subtest rcs0-smem: SUCCESS (0.904s)
[585.017655] Starting dynamic subtest: rcs0-lmem0
[586.395294] Dynamic subtest rcs0-lmem0: SUCCESS (1.384s)
[586.403213] Starting dynamic subtest: bcs0-smem
[587.267545] Dynamic subtest bcs0-smem: SUCCESS (0.872s)
[587.275466] Starting dynamic subtest: bcs0-lmem0
[588.136569] Dynamic subtest bcs0-lmem0: SUCCESS (0.867s)
[588.144559] Starting dynamic subtest: vcs0-smem
[589.002867] Dynamic subtest vcs0-smem: SUCCESS (0.866s)
[589.012407] Starting dynamic subtest: vcs0-lmem0
[590.362526] Dynamic subtest vcs0-lmem0: SUCCESS (1.358s)
[590.370520] Starting dynamic subtest: vcs1-smem
[591.217527] Dynamic subtest vcs1-smem: SUCCESS (0.853s)
[591.225593] Starting dynamic subtest: vcs1-lmem0
[592.576819] Dynamic subtest vcs1-lmem0: SUCCESS (1.358s)
[592.584928] Starting dynamic subtest: vecs0-smem
[593.584574] Dynamic subtest vecs0-smem: SUCCESS (1.007s)
[593.592691] Starting dynamic subtest: vecs0-lmem0
[594.440480] Dynamic subtest vecs0-lmem0: SUCCESS (0.855s)
[594.441395] Subtest capture: SUCCESS (10.336s)
[594.539020] [128/142] (418s left) gem_vm_create (execbuf)
[594.610663] Starting subtest: execbuf
[594.617747] Subtest execbuf: SUCCESS (0.007s)
[594.742134] [129/142] (417s left) gem_spin_batch (legacy-resubmit-new)
[594.818714] Starting subtest: legacy-resubmit-new
[594.819689] Starting dynamic subtest: default
[594.835638] Dynamic subtest default: SUCCESS (0.016s)
[594.847897] Starting dynamic subtest: render
[594.848823] Dynamic subtest render: SUCCESS (0.003s)
[594.856895] Starting dynamic subtest: bsd
[594.857678] Dynamic subtest bsd: SUCCESS (0.004s)
[594.865710] Starting dynamic subtest: bsd1
[594.866471] Dynamic subtest bsd1: SUCCESS (0.004s)
[594.874297] Starting dynamic subtest: bsd2
[594.875126] Dynamic subtest bsd2: SUCCESS (0.003s)
[594.882891] Starting dynamic subtest: blt
[594.883721] Dynamic subtest blt: SUCCESS (0.003s)
[594.891300] Starting dynamic subtest: vebox
[594.892136] Dynamic subtest vebox: SUCCESS (0.003s)
[594.892923] Subtest legacy-resubmit-new: SUCCESS (0.046s)
[595.146628] [130/142] (417s left) kms_chamelium_frames (vga-frame-dump)
[597.187657] Subtest vga-frame-dump: SKIP (0.000s)
[597.246511] [131/142] (415s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[599.221254] Starting subtest: basic-busy-flip-before-cursor-legacy
[599.223018] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[599.285108] [132/142] (413s left) gem_render_copy (x-tiled)
[599.366575] Starting subtest: x-tiled
[599.367600] Starting dynamic subtest: smem
[599.447476] Dynamic subtest smem: SUCCESS (0.080s)
[599.455534] Starting dynamic subtest: lmem0
[600.207951] Dynamic subtest lmem0: SUCCESS (0.756s)
[600.209186] Subtest x-tiled: SUCCESS (0.839s)
[600.445390] [133/142] (412s left) gem_ctx_param (set-priority-invalid-size)
[600.514844] Starting subtest: set-priority-invalid-size
[600.515807] Subtest set-priority-invalid-size: SUCCESS (0.000s)
[600.588257] [134/142] (412s left) kms_ccs (pipe-a-crc-sprite-planes-basic-yf_tiled_ccs)
[602.701544] Starting subtest: pipe-A-crc-sprite-planes-basic-yf_tiled_ccs
[602.705863] Subtest pipe-A-crc-sprite-planes-basic-yf_tiled_ccs: SKIP (0.003s)
[603.110367] [135/142] (409s left) kms_universal_plane (universal-plane-pipe-a-functional)
[605.084922] Starting subtest: universal-plane-pipe-A-functional
[607.187914] Subtest universal-plane-pipe-A-functional: SUCCESS (2.103s)
[607.314478] [136/142] (405s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0)
[609.301673] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0
[609.303657] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[609.380714] [137/142] (403s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[611.394050] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[611.396161] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[611.481382] [138/142] (401s left) kms_ccs (pipe-b-bad-aux-stride-y_tiled_ccs)
[613.705598] Starting subtest: pipe-B-bad-aux-stride-y_tiled_ccs
[613.707708] Subtest pipe-B-bad-aux-stride-y_tiled_ccs: SKIP (0.000s)
[614.021850] [139/142] (398s left) gem_exec_schedule (implicit-read-write)
[614.100938] Starting subtest: implicit-read-write
[614.102498] Starting dynamic subtest: rcs0
[614.286655] Dynamic subtest rcs0: SUCCESS (0.182s)
[614.294464] Starting dynamic subtest: bcs0
[614.406864] Dynamic subtest bcs0: SUCCESS (0.122s)
[614.416231] Starting dynamic subtest: vcs0
[614.533475] Dynamic subtest vcs0: SUCCESS (0.125s)
[614.541281] Starting dynamic subtest: vcs1
[614.658239] Dynamic subtest vcs1: SUCCESS (0.124s)
[614.666051] Starting dynamic subtest: vecs0
[614.794228] Dynamic subtest vecs0: SUCCESS (0.135s)
[614.795111] Subtest implicit-read-write: SUCCESS (0.694s)
[615.244543] [140/142] (397s left) api_intel_bb (blit-reloc-purge-cache)
[615.336824] Starting subtest: blit-reloc-purge-cache
[615.338636] Subtest blit-reloc-purge-cache: SKIP (0.000s)
[615.441280] [141/142] (397s left) kms_psr (cursor_mmap_gtt)
[615.538673] Subtest cursor_mmap_gtt: SKIP (0.000s)
[615.562807] [142/142] (397s left) core_auth (getclient-master-drop)
[615.628113] Starting subtest: getclient-master-drop
[615.631223] Subtest getclient-master-drop: SUCCESS (0.003s)
[615.686626] Closing watchdogs
Done.