[57.351417] Initializing watchdogs
[57.351461]   /dev/watchdog0
[57.365858] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.370082] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.372391] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.376098] [001/132] (960s left) kms_cursor_edge_walk (128x128-left-edge)
[57.496529] Starting subtest: 128x128-left-edge
[57.498725] Starting dynamic subtest: pipe-A-eDP-1
[60.931223] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.455s)
[60.938782] Starting dynamic subtest: pipe-D-eDP-1
[64.047057] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.127s)
[64.048930] Subtest 128x128-left-edge: SUCCESS (6.584s)
[64.874744] [002/132] (953s left) kms_psr (psr2-cursor-mmap-gtt)
[64.980652] Starting subtest: psr2-cursor-mmap-gtt
[64.983056] Starting dynamic subtest: eDP-1
[66.739347] Dynamic subtest eDP-1: SUCCESS (1.762s)
[66.741701] Subtest psr2-cursor-mmap-gtt: SUCCESS (1.762s)
[67.651127] [003/132] (950s left) kms_cursor_legacy (single-move)
[67.813539] Starting subtest: single-move
[67.815517] Starting dynamic subtest: pipe-A
[73.190058] Dynamic subtest pipe-A: SUCCESS (5.380s)
[73.198723] Starting dynamic subtest: pipe-B
[78.564974] Dynamic subtest pipe-B: SUCCESS (5.379s)
[78.573772] Starting dynamic subtest: pipe-C
[83.948406] Dynamic subtest pipe-C: SUCCESS (5.382s)
[83.957042] Starting dynamic subtest: pipe-D
[89.327782] Dynamic subtest pipe-D: SUCCESS (5.378s)
[89.337053] Starting dynamic subtest: all-pipes
[94.717450] Dynamic subtest all-pipes: SUCCESS (5.385s)
[94.719844] Subtest single-move: SUCCESS (26.908s)
[94.879904] [004/132] (923s left) dumb_buffer (invalid-bpp)
[94.938961] Starting subtest: invalid-bpp
[94.941293] Subtest invalid-bpp: SUCCESS (0.000s)
[95.081498] [005/132] (922s left) kms_fbcon_fbt (fbc)
[95.138465] Starting subtest: fbc
[101.465494] Subtest fbc: SUCCESS (6.325s)
[102.551882] [006/132] (915s left) gen9_exec_parse (bb-start-far)
[102.618922] Subtest bb-start-far: SKIP (0.000s)
[102.711860] [007/132] (915s left) kms_vblank (ts-continuation-dpms-suspend)
[102.810955] Starting subtest: ts-continuation-dpms-suspend
[102.812850] Starting dynamic subtest: pipe-A-eDP-1
[120.993727] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.007s)
[121.006530] Starting dynamic subtest: pipe-D-eDP-1
[139.012886] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.919s)
[139.015144] Subtest ts-continuation-dpms-suspend: SUCCESS (8.929s)
[148.844923] [008/132] (878s left) gem_tiled_swapping (non-threaded)
[148.910239] Subtest non-threaded: SKIP (0.000s)
[148.968172] [009/132] (878s left) api_intel_bb (object-noreloc-keep-cache-simple)
[149.030186] Starting subtest: object-noreloc-keep-cache-simple
[149.037999] Subtest object-noreloc-keep-cache-simple: SUCCESS (0.010s)
[149.188011] [010/132] (878s left) kms_psr (fbc-pr-cursor-mmap-cpu)
[149.288525] Starting subtest: fbc-pr-cursor-mmap-cpu
[149.292857] Subtest fbc-pr-cursor-mmap-cpu: SKIP (0.000s)
[149.376118] [011/132] (877s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-move)
[149.468930] Starting subtest: psr-1p-primscrn-spr-indfb-move
[156.955850] Subtest psr-1p-primscrn-spr-indfb-move: SUCCESS (7.642s)
[157.132137] [012/132] (870s left) kms_frontbuffer_tracking (fbcpsr-tiling-4)
[157.252052] Starting subtest: fbcpsr-tiling-4
[164.357846] Subtest fbcpsr-tiling-4: SUCCESS (7.161s)
[164.537025] [013/132] (862s left) kms_invalid_mode (overflow-vrefresh)
[164.644928] Starting subtest: overflow-vrefresh
[164.647095] Starting dynamic subtest: pipe-A-eDP-1
[164.926695] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.284s)
[164.935976] Starting dynamic subtest: pipe-B-eDP-1
[164.937857] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[164.945124] Starting dynamic subtest: pipe-C-eDP-1
[164.947429] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[164.955611] Starting dynamic subtest: pipe-D-eDP-1
[164.958232] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[164.960582] Subtest overflow-vrefresh: SUCCESS (0.289s)
[165.740113] [014/132] (861s left) gem_mmap (basic-small-bo)
[165.804083] Subtest basic-small-bo: SKIP (0.000s)
[165.864112] [015/132] (861s left) syncobj_timeline (single-wait-available-signaled)
[165.921761] Starting subtest: single-wait-available-signaled
[165.924058] Subtest single-wait-available-signaled: SUCCESS (0.000s)
[166.046930] [016/132] (861s left) prime_mmap_kms (buffer-sharing)
[166.148485] Starting subtest: buffer-sharing
[166.608009] Subtest buffer-sharing: SUCCESS (0.461s)
[167.460268] [017/132] (860s left) kms_async_flips (async-flip-with-page-flip-events-tiled)
[167.555756] Starting subtest: async-flip-with-page-flip-events-tiled
[167.933428] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[170.715093] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.788s)
[170.723491] Starting dynamic subtest: pipe-A-eDP-1-4
[172.895624] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.184s)
[172.904257] Starting dynamic subtest: pipe-A-eDP-1-4
[174.901199] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.008s)
[174.910217] Starting dynamic subtest: pipe-A-eDP-1-x
[177.076708] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.178s)
[177.449064] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[180.224629] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.784s)
[180.233784] Starting dynamic subtest: pipe-B-eDP-1-4
[182.422160] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.195s)
[182.435664] Starting dynamic subtest: pipe-B-eDP-1-4
[184.432842] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.008s)
[184.441542] Starting dynamic subtest: pipe-B-eDP-1-x
[186.646183] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.212s)
[187.043755] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[189.756734] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.720s)
[189.767439] Starting dynamic subtest: pipe-C-eDP-1-4
[191.932341] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.175s)
[191.942968] Starting dynamic subtest: pipe-C-eDP-1-4
[193.942706] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.010s)
[193.951604] Starting dynamic subtest: pipe-C-eDP-1-x
[196.122599] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.178s)
[196.485059] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[199.304535] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.825s)
[199.315469] Starting dynamic subtest: pipe-D-eDP-1-4
[201.477795] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.173s)
[201.487000] Starting dynamic subtest: pipe-D-eDP-1-4
[203.487480] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[203.498339] Starting dynamic subtest: pipe-D-eDP-1-x
[205.665715] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.178s)
[205.667897] Subtest async-flip-with-page-flip-events-tiled: SUCCESS (38.128s)
[206.920886] [018/132] (820s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-blt)
[207.029089] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-blt
[207.033185] Subtest fbc-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[207.162447] [019/132] (820s left) gem_wait (write-busy)
[207.238485] Starting subtest: write-busy
[207.240709] Starting dynamic subtest: all-engines
[207.875755] Dynamic subtest all-engines: SUCCESS (0.639s)
[207.892884] Starting dynamic subtest: rcs0
[208.428259] Dynamic subtest rcs0: SUCCESS (0.551s)
[208.443728] Starting dynamic subtest: bcs0
[208.975545] Dynamic subtest bcs0: SUCCESS (0.547s)
[208.990436] Starting dynamic subtest: vcs0
[210.206852] Dynamic subtest vcs0: SUCCESS (1.229s)
[210.221808] Starting dynamic subtest: vcs1
[212.190715] Dynamic subtest vcs1: SUCCESS (1.983s)
[212.206281] Starting dynamic subtest: vecs0
[214.174551] Dynamic subtest vecs0: SUCCESS (1.982s)
[214.189950] Starting dynamic subtest: ccs0
[214.724084] Dynamic subtest ccs0: SUCCESS (0.550s)
[214.726549] Subtest write-busy: SUCCESS (7.490s)
[214.939036] [FACT gem_wait (write-busy)] new: kernel.kmod_is_loaded.vgem: true
[214.942139] [020/132] (812s left) gem_render_copy (y-tiled-ccs-to-y-tiled-mc-ccs)
[215.010691] Starting subtest: y-tiled-ccs-to-y-tiled-mc-ccs
[215.015147] Subtest y-tiled-ccs-to-y-tiled-mc-ccs: SKIP (0.000s)
[215.224492] [021/132] (812s left) kms_psr (psr-sprite-mmap-gtt)
[215.323488] Starting subtest: psr-sprite-mmap-gtt
[215.325724] Starting dynamic subtest: eDP-1
[216.827785] Dynamic subtest eDP-1: SKIP (1.502s)
[216.832292] Subtest psr-sprite-mmap-gtt: SKIP (1.504s)
[217.030831] [022/132] (810s left) kms_frontbuffer_tracking (fbcpsr-suspend)
[217.139449] Starting subtest: fbcpsr-suspend
[258.079048] Subtest fbcpsr-suspend: SUCCESS (11.594s)
[263.271852] [023/132] (769s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[263.383196] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[269.799267] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.559s)
[269.938473] [024/132] (762s left) kms_async_flips (invalid-async-flip-atomic)
[270.037524] Starting subtest: invalid-async-flip-atomic
[270.417991] Starting dynamic subtest: pipe-A-eDP-1
[271.261749] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.855s)
[271.639372] Starting dynamic subtest: pipe-B-eDP-1
[272.404032] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.779s)
[272.768607] Starting dynamic subtest: pipe-C-eDP-1
[273.533510] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.780s)
[273.902281] Starting dynamic subtest: pipe-D-eDP-1
[274.662534] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.775s)
[274.664897] Subtest invalid-async-flip-atomic: SUCCESS (4.674s)
[275.828785] [025/132] (757s left) kms_ccs (bad-pixel-format-y-tiled-gen12-mc-ccs)
[275.921985] Starting subtest: bad-pixel-format-y-tiled-gen12-mc-ccs
[275.924246] Starting dynamic subtest: pipe-A-eDP-1
[275.945697] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[275.955948] Starting dynamic subtest: pipe-B-eDP-1
[275.976320] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[275.984360] Starting dynamic subtest: pipe-C-eDP-1
[276.004913] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[276.013420] Starting dynamic subtest: pipe-D-eDP-1
[276.034911] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[276.039158] Subtest bad-pixel-format-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[278.178433] [026/132] (754s left) perf_pmu (idle)
[278.228159] Starting subtest: idle
[278.230465] Starting dynamic subtest: rcs0
[278.788636] Dynamic subtest rcs0: SUCCESS (0.562s)
[278.799175] Starting dynamic subtest: bcs0
[279.320204] Dynamic subtest bcs0: SUCCESS (0.527s)
[279.331355] Starting dynamic subtest: vcs0
[279.843908] Dynamic subtest vcs0: SUCCESS (0.528s)
[279.855630] Starting dynamic subtest: vcs1
[280.375252] Dynamic subtest vcs1: SUCCESS (0.526s)
[280.386607] Starting dynamic subtest: vecs0
[280.899574] Dynamic subtest vecs0: SUCCESS (0.528s)
[280.910172] Starting dynamic subtest: ccs0
[281.426970] Dynamic subtest ccs0: SUCCESS (0.521s)
[281.429495] Subtest idle: SUCCESS (3.207s)
[281.565361] [027/132] (751s left) syncobj_timeline (invalid-signal-one-illegal-handle)
[281.623306] Starting subtest: invalid-signal-one-illegal-handle
[281.625750] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[281.743192] [028/132] (751s left) kms_pm_dc (dc3co-vpb-simulation)
[282.862329] Starting subtest: dc3co-vpb-simulation
[291.770175] Subtest dc3co-vpb-simulation: SKIP (8.921s)
[291.929015] [029/132] (741s left) gen9_exec_parse (shadow-peek)
[292.014444] Subtest shadow-peek: SKIP (0.000s)
[292.078955] [030/132] (740s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf)
[297.092651] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf
[297.094798] Starting dynamic subtest: pipe-A-eDP-1
[297.876634] Dynamic subtest pipe-A-eDP-1: SKIP (0.780s)
[297.886623] Starting dynamic subtest: pipe-B-eDP-1
[299.001873] Dynamic subtest pipe-B-eDP-1: SKIP (1.121s)
[299.006316] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf: SKIP (1.908s)
[300.209036] [031/132] (732s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[300.311999] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[300.314191] Starting dynamic subtest: pipe-A-default-mode
[301.442283] Dynamic subtest pipe-A-default-mode: SKIP (1.129s)
[301.446721] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.130s)
[301.626836] [032/132] (731s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[301.738663] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[301.742888] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[301.889527] [033/132] (731s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-render)
[301.982822] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-render
[301.987169] Subtest fbc-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[302.130908] [034/132] (731s left) gem_exec_schedule (reorder-wide)
[302.312010] Subtest reorder-wide: SKIP (0.000s)
[302.516180] [035/132] (730s left) kms_prop_blob (blob-prop-lifetime)
[302.574802] Starting subtest: blob-prop-lifetime
[302.576719] Subtest blob-prop-lifetime: SUCCESS (0.001s)
[302.712651] [036/132] (730s left) perf_pmu (invalid-init)
[302.756313] Starting subtest: invalid-init
[302.758646] Subtest invalid-init: SUCCESS (0.000s)
[302.898346] [037/132] (730s left) kms_flip (basic-flip-vs-modeset)
[302.989284] Starting subtest: basic-flip-vs-modeset
[302.991472] Starting dynamic subtest: A-eDP1
[305.624062] Dynamic subtest A-eDP1: SUCCESS (2.631s)
[305.634134] Starting dynamic subtest: B-eDP1
[307.786105] Dynamic subtest B-eDP1: SUCCESS (2.161s)
[307.798221] Starting dynamic subtest: C-eDP1
[309.950923] Dynamic subtest C-eDP1: SUCCESS (2.164s)
[309.961087] Starting dynamic subtest: D-eDP1
[312.126732] Dynamic subtest D-eDP1: SUCCESS (2.174s)
[312.128665] Subtest basic-flip-vs-modeset: SUCCESS (9.136s)
[312.978961] [038/132] (720s left) i915_query (query-topology-unsupported)
[313.046390] Starting subtest: query-topology-unsupported
[313.050849] Subtest query-topology-unsupported: SKIP (0.000s)
[313.170345] [039/132] (720s left) kms_addfb_basic (small-bo)
[313.268288] Starting subtest: small-bo
[313.270479] Subtest small-bo: SUCCESS (0.000s)
[313.407263] [040/132] (720s left) sw_sync (alloc_fence)
[313.446930] Starting subtest: alloc_fence
[313.449211] Subtest alloc_fence: SUCCESS (0.000s)
[313.526417] [041/132] (720s left) kms_color_pipeline (plane-lut1d-pre-ctm3x4)
[313.614229] Starting subtest: plane-lut1d-pre-ctm3x4
[313.630077] Starting dynamic subtest: pipe-A-eDP-1
[314.286665] Dynamic subtest pipe-A-eDP-1: FAIL (0.332s)
[315.008400] Starting dynamic subtest: pipe-B-eDP-1
[315.861810] Dynamic subtest pipe-B-eDP-1: FAIL (0.322s)
[316.430942] Starting dynamic subtest: pipe-C-eDP-1
[317.251194] Dynamic subtest pipe-C-eDP-1: FAIL (0.235s)
[317.759787] Starting dynamic subtest: pipe-D-eDP-1
[318.575701] Dynamic subtest pipe-D-eDP-1: FAIL (0.235s)
[318.577732] Subtest plane-lut1d-pre-ctm3x4: FAIL (4.677s)
[319.168180] [042/132] (714s left) gem_lmem_swapping (parallel-random)
[319.208652] Subtest parallel-random: SKIP (0.000s)
[319.253740] [043/132] (714s left) kms_plane_alpha_blend (alpha-transparent-fb)
[319.348850] Starting subtest: alpha-transparent-fb
[319.684948] Starting dynamic subtest: pipe-A-eDP-1
[320.306541] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.621s)
[320.316440] Starting dynamic subtest: pipe-D-eDP-1
[321.740793] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.432s)
[321.743008] Subtest alpha-transparent-fb: SUCCESS (2.393s)
[322.646923] [044/132] (711s left) kms_big_fb (linear-addfb)
[322.771776] Starting subtest: linear-addfb
[322.773720] Subtest linear-addfb: SUCCESS (0.000s)
[322.911676] [045/132] (710s left) syncobj_timeline (invalid-wait-illegal-handle)
[322.975476] Starting subtest: invalid-wait-illegal-handle
[322.977789] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[323.122799] [046/132] (710s left) gem_exec_params (sol-reset-invalid)
[323.187575] Starting subtest: sol-reset-invalid
[323.189802] Subtest sol-reset-invalid: SUCCESS (0.003s)
[324.682292] [047/132] (709s left) gem_flink_basic (bad-flink)
[324.743052] Starting subtest: bad-flink
[324.745331] Subtest bad-flink: SUCCESS (0.000s)
[324.878787] [048/132] (709s left) gem_exec_fence (syncobj-timeline-repeat)
[325.443021] Starting subtest: syncobj-timeline-repeat
[325.675456] Subtest syncobj-timeline-repeat: SUCCESS (0.232s)
[326.673547] [049/132] (707s left) syncobj_wait (signal)
[326.744045] Starting subtest: signal
[326.746297] Subtest signal: SUCCESS (0.000s)
[326.853928] [050/132] (707s left) gem_exec_schedule (implicit-write-read)
[326.930768] Starting subtest: implicit-write-read
[326.933064] Starting dynamic subtest: rcs0
[327.113828] Dynamic subtest rcs0: SUCCESS (0.192s)
[327.127887] Starting dynamic subtest: vcs0
[327.251963] Dynamic subtest vcs0: SUCCESS (0.138s)
[327.262930] Starting dynamic subtest: vcs1
[327.361991] Dynamic subtest vcs1: SUCCESS (0.109s)
[327.376009] Starting dynamic subtest: vecs0
[327.466700] Dynamic subtest vecs0: SUCCESS (0.103s)
[327.478996] Starting dynamic subtest: ccs0
[327.605622] Dynamic subtest ccs0: SUCCESS (0.138s)
[327.608060] Subtest implicit-write-read: SUCCESS (0.685s)
[328.660837] [051/132] (705s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-wc)
[328.755751] Starting subtest: fbcpsr-rgb101010-draw-mmap-wc
[335.440542] Subtest fbcpsr-rgb101010-draw-mmap-wc: SUCCESS (6.688s)
[335.605845] [052/132] (698s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs)
[335.722562] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs
[335.724861] Starting dynamic subtest: pipe-A-eDP-1
[335.747351] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[335.758271] Starting dynamic subtest: pipe-B-eDP-1
[335.778741] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[335.787733] Starting dynamic subtest: pipe-C-eDP-1
[335.808830] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[335.817726] Starting dynamic subtest: pipe-D-eDP-1
[335.838810] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[335.842970] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.006s)
[337.936424] [053/132] (696s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[338.314460] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[339.125634] [054/132] (695s left) gem_ctx_engines (independent)
[339.195979] Starting subtest: independent
[339.198470] Starting dynamic subtest: rcs0
[339.331552] Dynamic subtest rcs0: SUCCESS (0.133s)
[339.344095] Starting dynamic subtest: bcs0
[339.439777] Dynamic subtest bcs0: SUCCESS (0.109s)
[339.452198] Starting dynamic subtest: vcs0
[339.538868] Dynamic subtest vcs0: SUCCESS (0.099s)
[339.548595] Starting dynamic subtest: vcs1
[339.655007] Dynamic subtest vcs1: SUCCESS (0.114s)
[339.665826] Starting dynamic subtest: vecs0
[339.768253] Dynamic subtest vecs0: SUCCESS (0.113s)
[339.778252] Starting dynamic subtest: ccs0
[339.892212] Dynamic subtest ccs0: SUCCESS (0.123s)
[339.904106] Starting dynamic subtest: all-engines
[340.385669] Dynamic subtest all-engines: SUCCESS (0.493s)
[340.388110] Subtest independent: SUCCESS (1.190s)
[340.749404] [055/132] (693s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0)
[340.856912] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0
[341.890865] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.035s)
[342.071039] [056/132] (692s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-plflip-blt)
[342.174907] Starting subtest: fbc-1p-primscrn-shrfb-plflip-blt
[346.773330] Subtest fbc-1p-primscrn-shrfb-plflip-blt: SUCCESS (4.604s)
[347.971690] [057/132] (686s left) syncobj_wait (wait-for-submit-complex)
[348.027292] Starting subtest: wait-for-submit-complex
[348.088259] Subtest wait-for-submit-complex: SUCCESS (0.062s)
[348.194212] [058/132] (686s left) gem_eio (context-create)
[348.439200] Starting subtest: context-create
[348.779148] Subtest context-create: SUCCESS (0.341s)
[348.934861] [059/132] (685s left) gem_exec_balancer (bonded-chain)
[348.992567] Starting subtest: bonded-chain
[355.949145] Subtest bonded-chain: SUCCESS (6.956s)
[356.287643] [060/132] (678s left) api_intel_allocator (simple-allocator)
[356.346305] Starting subtest: simple-allocator
[356.348487] Starting dynamic subtest: basic
[356.352940] Dynamic subtest basic: SUCCESS (0.006s)
[356.364295] Starting dynamic subtest: parallel-one
[356.536549] Dynamic subtest parallel-one: SUCCESS (0.183s)
[356.546418] Starting dynamic subtest: print
[356.548677] Dynamic subtest print: SUCCESS (0.000s)
[356.557972] Starting dynamic subtest: reuse
[356.560346] Dynamic subtest reuse: SUCCESS (0.000s)
[356.569408] Starting dynamic subtest: reserve
[356.571549] Dynamic subtest reserve: SUCCESS (0.000s)
[356.580652] Starting dynamic subtest: fork-reopen-allocator
[356.582885] Dynamic subtest fork-reopen-allocator: SUCCESS (0.014s)
[356.585104] Subtest simple-allocator: SUCCESS (0.207s)
[356.713139] [061/132] (677s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-cpu)
[356.813504] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-cpu
[360.829295] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (4.018s)
[361.985525] [062/132] (672s left) gem_exec_basic (basic)
[362.057319] Starting subtest: basic
[362.059778] Starting dynamic subtest: rcs0-smem
[362.062223] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[362.073752] Starting dynamic subtest: bcs0-smem
[362.075717] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[362.084783] Starting dynamic subtest: vcs0-smem
[362.086815] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[362.095806] Starting dynamic subtest: vcs1-smem
[362.097793] Dynamic subtest vcs1-smem: SUCCESS (0.001s)
[362.106665] Starting dynamic subtest: vecs0-smem
[362.108660] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[362.117829] Starting dynamic subtest: ccs0-smem
[362.119775] Dynamic subtest ccs0-smem: SUCCESS (0.001s)
[362.121705] Subtest basic: SUCCESS (0.014s)
[363.593216] [063/132] (670s left) gem_mmap_gtt (basic-small-bo-tiledy)
[363.639685] Subtest basic-small-bo-tiledY: SKIP (0.000s)
[363.714728] [064/132] (670s left) gem_mmap_wc (bad-size)
[363.767481] Subtest bad-size: SKIP (0.000s)
[363.838725] [065/132] (670s left) sw_sync (sync_multi_consumer)
[363.876608] Starting subtest: sync_multi_consumer
[364.308654] Subtest sync_multi_consumer: SUCCESS (0.430s)
[364.401799] [066/132] (670s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[364.504645] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip
[364.508914] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[364.666665] [067/132] (670s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[364.776184] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[364.780568] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[364.887482] [068/132] (669s left) syncobj_wait (multi-wait-for-submit-signaled)
[364.940001] Starting subtest: multi-wait-for-submit-signaled
[364.942281] Subtest multi-wait-for-submit-signaled: SUCCESS (0.000s)
[365.053501] [069/132] (669s left) gem_mmap_gtt (cpuset-big-copy)
[365.117532] Subtest cpuset-big-copy: SKIP (0.000s)
[365.180325] [070/132] (669s left) prime_busy (hang-wait)
[365.239855] Starting subtest: hang-wait
[365.242218] Starting dynamic subtest: rcs0
[391.012192] Dynamic subtest rcs0: SUCCESS (25.761s)
[391.022929] Starting dynamic subtest: bcs0
[398.168884] Dynamic subtest bcs0: SUCCESS (7.156s)
[398.178952] Starting dynamic subtest: vcs0
[405.140495] Dynamic subtest vcs0: SUCCESS (6.971s)
[405.151885] Starting dynamic subtest: vcs1
[412.184694] Dynamic subtest vcs1: SUCCESS (7.041s)
[412.203266] Starting dynamic subtest: vecs0
[419.153447] Dynamic subtest vecs0: SUCCESS (6.970s)
[419.163271] Starting dynamic subtest: ccs0
[432.989922] Dynamic subtest ccs0: SUCCESS (13.836s)
[432.992026] Subtest hang-wait: SUCCESS (67.746s)
[433.145665] [071/132] (601s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[433.251696] Starting subtest: fbc-rgb565-draw-pwrite
[437.820722] Subtest fbc-rgb565-draw-pwrite: SUCCESS (4.572s)
[438.982434] [072/132] (595s left) kms_cursor_crc (cursor-offscreen-128x128)
[439.209158] Starting subtest: cursor-offscreen-128x128
[439.211415] Starting dynamic subtest: pipe-A-eDP-1
[441.140502] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.931s)
[441.174498] Starting dynamic subtest: pipe-D-eDP-1
[443.725637] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.559s)
[443.727958] Subtest cursor-offscreen-128x128: SUCCESS (4.516s)
[444.623041] [073/132] (590s left) gem_ctx_param (invalid-set-no-zeromap)
[444.679719] Starting subtest: invalid-set-no-zeromap
[444.682026] Subtest invalid-set-no-zeromap: SUCCESS (0.000s)
[444.779828] [074/132] (590s left) kms_rotation_crc (primary-yf-tiled-reflect-x-180)
[444.877296] Starting subtest: primary-yf-tiled-reflect-x-180
[444.936222] Subtest primary-yf-tiled-reflect-x-180: SKIP (0.059s)
[445.063181] [075/132] (590s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[445.174785] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[449.617707] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.447s)
[450.825199] [076/132] (584s left) kms_selftest (drm_cmdline_parser)
[450.867899] Starting subtest: drm_cmdline_parser
[450.905222] Starting dynamic subtest: drm_test_cmdline_force_d_only
[451.049255] Dynamic subtest drm_test_cmdline_force_d_only: SUCCESS (0.143s)
[451.060768] Starting dynamic subtest: drm_test_cmdline_force_D_only_dvi
[451.182437] Dynamic subtest drm_test_cmdline_force_D_only_dvi: SUCCESS (0.133s)
[451.194051] Starting dynamic subtest: drm_test_cmdline_force_D_only_hdmi
[451.309314] Dynamic subtest drm_test_cmdline_force_D_only_hdmi: SUCCESS (0.126s)
[451.321071] Starting dynamic subtest: drm_test_cmdline_force_D_only_not_digital
[451.427265] Dynamic subtest drm_test_cmdline_force_D_only_not_digital: SUCCESS (0.118s)
[451.436527] Starting dynamic subtest: drm_test_cmdline_force_e_only
[451.569284] Dynamic subtest drm_test_cmdline_force_e_only: SUCCESS (0.141s)
[451.580054] Starting dynamic subtest: drm_test_cmdline_res
[451.672448] Dynamic subtest drm_test_cmdline_res: SUCCESS (0.104s)
[451.681945] Starting dynamic subtest: drm_test_cmdline_res_vesa
[451.808465] Dynamic subtest drm_test_cmdline_res_vesa: SUCCESS (0.133s)
[451.819581] Starting dynamic subtest: drm_test_cmdline_res_vesa_rblank
[451.918752] Dynamic subtest drm_test_cmdline_res_vesa_rblank: SUCCESS (0.111s)
[451.928081] Starting dynamic subtest: drm_test_cmdline_res_rblank
[452.027513] Dynamic subtest drm_test_cmdline_res_rblank: SUCCESS (0.107s)
[452.038611] Starting dynamic subtest: drm_test_cmdline_res_bpp
[452.165298] Dynamic subtest drm_test_cmdline_res_bpp: SUCCESS (0.136s)
[452.176017] Starting dynamic subtest: drm_test_cmdline_res_refresh
[452.248556] Dynamic subtest drm_test_cmdline_res_refresh: SUCCESS (0.083s)
[452.259048] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh
[452.368392] Dynamic subtest drm_test_cmdline_res_bpp_refresh: SUCCESS (0.119s)
[452.386515] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced
[452.511244] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced: SUCCESS (0.142s)
[452.522486] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_margins
[452.641297] Dynamic subtest drm_test_cmdline_res_bpp_refresh_margins: SUCCESS (0.129s)
[452.651278] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_off
[452.786560] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_off: SUCCESS (0.144s)
[452.801747] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on
[452.930595] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on: SUCCESS (0.144s)
[452.940007] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_analog
[453.052933] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_analog: SUCCESS (0.121s)
[453.064497] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_digital
[453.124282] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_digital: SUCCESS (0.072s)
[453.135218] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on
[453.268484] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on: SUCCESS (0.143s)
[453.278115] Starting dynamic subtest: drm_test_cmdline_res_margins_force_on
[453.386172] Dynamic subtest drm_test_cmdline_res_margins_force_on: SUCCESS (0.117s)
[453.397100] Starting dynamic subtest: drm_test_cmdline_res_vesa_margins
[453.493199] Dynamic subtest drm_test_cmdline_res_vesa_margins: SUCCESS (0.107s)
[453.504418] Starting dynamic subtest: drm_test_cmdline_name
[453.629594] Dynamic subtest drm_test_cmdline_name: SUCCESS (0.133s)
[453.641038] Starting dynamic subtest: drm_test_cmdline_name_bpp
[453.759329] Dynamic subtest drm_test_cmdline_name_bpp: SUCCESS (0.128s)
[453.770504] Starting dynamic subtest: drm_test_cmdline_name_option
[453.888042] Dynamic subtest drm_test_cmdline_name_option: SUCCESS (0.128s)
[453.898712] Starting dynamic subtest: drm_test_cmdline_name_bpp_option
[454.024195] Dynamic subtest drm_test_cmdline_name_bpp_option: SUCCESS (0.135s)
[454.035289] Starting dynamic subtest: drm_test_cmdline_rotate_0
[454.131441] Dynamic subtest drm_test_cmdline_rotate_0: SUCCESS (0.107s)
[454.143294] Starting dynamic subtest: drm_test_cmdline_rotate_90
[454.256116] Dynamic subtest drm_test_cmdline_rotate_90: SUCCESS (0.123s)
[454.265328] Starting dynamic subtest: drm_test_cmdline_rotate_180
[454.385097] Dynamic subtest drm_test_cmdline_rotate_180: SUCCESS (0.129s)
[454.394353] Starting dynamic subtest: drm_test_cmdline_rotate_270
[454.469351] Dynamic subtest drm_test_cmdline_rotate_270: SUCCESS (0.084s)
[454.478273] Starting dynamic subtest: drm_test_cmdline_hmirror
[454.600331] Dynamic subtest drm_test_cmdline_hmirror: SUCCESS (0.128s)
[454.610902] Starting dynamic subtest: drm_test_cmdline_vmirror
[454.689553] Dynamic subtest drm_test_cmdline_vmirror: SUCCESS (0.090s)
[454.698917] Starting dynamic subtest: drm_test_cmdline_margin_options
[454.812546] Dynamic subtest drm_test_cmdline_margin_options: SUCCESS (0.119s)
[454.827891] Starting dynamic subtest: drm_test_cmdline_multiple_options
[454.940784] Dynamic subtest drm_test_cmdline_multiple_options: SUCCESS (0.127s)
[454.956373] Starting dynamic subtest: drm_test_cmdline_bpp_extra_and_option
[455.036301] Dynamic subtest drm_test_cmdline_bpp_extra_and_option: SUCCESS (0.097s)
[455.046562] Starting dynamic subtest: drm_test_cmdline_extra_and_option
[455.152815] Dynamic subtest drm_test_cmdline_extra_and_option: SUCCESS (0.115s)
[455.163434] Starting dynamic subtest: drm_test_cmdline_freestanding_options
[455.279151] Dynamic subtest drm_test_cmdline_freestanding_options: SUCCESS (0.125s)
[455.288869] Starting dynamic subtest: drm_test_cmdline_freestanding_force_e_and_options
[455.407286] Dynamic subtest drm_test_cmdline_freestanding_force_e_and_options: SUCCESS (0.128s)
[455.416309] Starting dynamic subtest: drm_test_cmdline_panel_orientation
[455.491136] Dynamic subtest drm_test_cmdline_panel_orientation: SUCCESS (0.084s)
[455.500458] Starting dynamic subtest: drm_test_cmdline_invalid
[455.639710] Dynamic subtest drm_test_cmdline_invalid: SUCCESS (0.149s)
[455.650071] Starting dynamic subtest: drm_test_cmdline_tv_options
[455.784076] Dynamic subtest drm_test_cmdline_tv_options: SUCCESS (0.140s)
[455.786020] Subtest drm_cmdline_parser: SUCCESS (4.917s)
[456.016551] [077/132] (579s left) gem_ctx_param (set-priority-not-supported)
[456.077640] Starting subtest: set-priority-not-supported
[456.082199] Subtest set-priority-not-supported: SKIP (0.000s)
[456.177422] [078/132] (579s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-render)
[456.281471] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-render
[456.285355] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[456.414066] [079/132] (578s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180)
[456.517324] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180
[456.521259] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[456.654732] [080/132] (578s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[456.757419] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[457.838358] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.080s)
[457.997153] [081/132] (577s left) kms_plane_multiple (tiling-4)
[458.084250] Starting subtest: tiling-4
[458.086176] Starting dynamic subtest: pipe-A-eDP-1
[461.913801] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.830s)
[461.926025] Starting dynamic subtest: pipe-B-eDP-1
[466.332845] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.418s)
[466.343314] Starting dynamic subtest: pipe-C-eDP-1
[470.764346] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.430s)
[470.774320] Starting dynamic subtest: pipe-D-eDP-1
[475.084747] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.320s)
[475.086722] Subtest tiling-4: SUCCESS (17.003s)
[475.918756] [082/132] (559s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-mc-ccs)
[476.018424] Starting subtest: missing-ccs-buffer-y-tiled-gen12-mc-ccs
[476.020691] Starting dynamic subtest: pipe-A-eDP-1
[476.043261] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[476.054227] Starting dynamic subtest: pipe-B-eDP-1
[476.075409] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[476.084560] Starting dynamic subtest: pipe-C-eDP-1
[476.106274] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[476.116588] Starting dynamic subtest: pipe-D-eDP-1
[476.138079] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[476.142386] Subtest missing-ccs-buffer-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[478.275772] [083/132] (557s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[478.655964] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[479.459188] [084/132] (555s left) perf (gen12-group-exclusive-stream-ctx-handle)
[479.600964] Starting subtest: gen12-group-exclusive-stream-ctx-handle
[479.627359] Subtest gen12-group-exclusive-stream-ctx-handle: SUCCESS (0.025s)
[479.676604] [085/132] (555s left) kms_setmode (basic)
[481.513481] Starting subtest: basic
[481.845798] Starting dynamic subtest: pipe-A-eDP-1
[483.894181] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.045s)
[484.235898] Starting dynamic subtest: pipe-B-eDP-1
[486.326445] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.095s)
[486.641857] Subtest basic: SUCCESS (5.127s)
[487.498353] [086/132] (548s left) kms_psr (fbc-psr-primary-render)
[487.599882] Starting subtest: fbc-psr-primary-render
[487.602049] Starting dynamic subtest: eDP-1
[487.606375] Dynamic subtest eDP-1: SKIP (0.000s)
[487.610660] Subtest fbc-psr-primary-render: SKIP (0.001s)
[487.697758] [087/132] (547s left) gem_create (create-valid-nonaligned)
[487.756904] Starting subtest: create-valid-nonaligned
[487.758874] Subtest create-valid-nonaligned: SUCCESS (0.000s)
[487.973688] [088/132] (547s left) kms_atomic_transition (plane-all-transition-fencing)
[488.062161] Starting subtest: plane-all-transition-fencing
[488.064465] Starting dynamic subtest: pipe-A-eDP-1
[490.558110] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.496s)
[490.876305] Starting dynamic subtest: pipe-B-eDP-1
[494.120967] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.253s)
[494.417290] Subtest plane-all-transition-fencing: SUCCESS (6.354s)
[495.265326] [089/132] (540s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-msflip-blt)
[495.373563] Starting subtest: fbcpsr-2p-scndscrn-indfb-msflip-blt
[495.377839] Subtest fbcpsr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[495.497911] [090/132] (540s left) kms_lease (lease-unleased-connector)
[495.590167] Starting subtest: lease-unleased-connector
[495.592479] Starting dynamic subtest: pipe-A-eDP-1
[495.597584] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.007s)
[495.608247] Starting dynamic subtest: pipe-B-eDP-1
[495.610440] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.007s)
[495.620181] Starting dynamic subtest: pipe-C-eDP-1
[495.622502] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.009s)
[495.632192] Starting dynamic subtest: pipe-D-eDP-1
[495.634125] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.008s)
[495.636056] Subtest lease-unleased-connector: SUCCESS (0.038s)
[495.771374] [091/132] (539s left) drm_read (short-buffer-nonblock)
[495.919105] Starting subtest: short-buffer-nonblock
[495.921078] Subtest short-buffer-nonblock: SUCCESS (0.000s)
[496.083400] [092/132] (539s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[496.177992] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-wc
[502.792157] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.614s)
[502.954597] [093/132] (532s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs)
[503.067220] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs
[503.069524] Starting dynamic subtest: pipe-A-eDP-1
[503.126917] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[503.138450] Starting dynamic subtest: pipe-B-eDP-1
[503.192705] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[503.204290] Starting dynamic subtest: pipe-C-eDP-1
[503.262121] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[503.273141] Starting dynamic subtest: pipe-D-eDP-1
[503.330005] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[503.333986] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs: SKIP (0.009s)
[505.346495] [094/132] (530s left) kms_pm_rpm (fences-dpms)
[507.700211] Starting subtest: fences-dpms
[507.705487] Subtest fences-dpms: SKIP (0.000s)
[508.105398] [095/132] (527s left) i915_getparams_basic (basic-subslice-total)
[508.164087] Starting subtest: basic-subslice-total
[508.168492] Subtest basic-subslice-total: SUCCESS (0.000s)
[508.283485] [096/132] (527s left) gem_exec_fence (submit67)
[508.459783] Starting subtest: submit67
[508.464555] Subtest submit67: SKIP (0.000s)
[510.659106] [097/132] (525s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5)
[510.757519] Starting subtest: planes-unity-scaling-downscale-factor-0-5
[510.759957] Starting dynamic subtest: pipe-A
[510.777745] Dynamic subtest pipe-A: SKIP (0.018s)
[510.787907] Starting dynamic subtest: pipe-B
[510.811181] Dynamic subtest pipe-B: SKIP (0.032s)
[510.820943] Starting dynamic subtest: pipe-C
[510.845467] Dynamic subtest pipe-C: SKIP (0.032s)
[510.855079] Starting dynamic subtest: pipe-D
[510.877490] Dynamic subtest pipe-D: SKIP (0.032s)
[510.881187] Subtest planes-unity-scaling-downscale-factor-0-5: SKIP (0.117s)
[511.018638] [098/132] (525s left) sw_sync (sync_busy)
[511.055047] Starting subtest: sync_busy
[511.057365] Subtest sync_busy: SUCCESS (0.002s)
[511.140632] [099/132] (524s left) i915_pm_rpm (sysfs-read)
[512.669553] Starting subtest: sysfs-read
[513.839074] Subtest sysfs-read: SUCCESS (1.166s)
[514.236555] [100/132] (521s left) kms_flip (flip-vs-dpms-on-nop-interruptible)
[514.344704] Starting subtest: flip-vs-dpms-on-nop-interruptible
[514.346943] Starting dynamic subtest: A-eDP1
[516.191463] Dynamic subtest A-eDP1: SUCCESS (1.845s)
[516.203701] Starting dynamic subtest: B-eDP1
[518.024768] Dynamic subtest B-eDP1: SUCCESS (1.831s)
[518.037462] Starting dynamic subtest: C-eDP1
[519.845065] Dynamic subtest C-eDP1: SUCCESS (1.819s)
[519.855007] Starting dynamic subtest: D-eDP1
[521.666272] Dynamic subtest D-eDP1: SUCCESS (1.819s)
[521.668835] Subtest flip-vs-dpms-on-nop-interruptible: SUCCESS (7.323s)
[522.878623] [101/132] (513s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[522.991336] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[522.995602] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[523.114298] [102/132] (513s left) prime_mmap_coherency (write)
[523.168909] Starting subtest: write
[528.546432] Subtest write: SUCCESS (5.374s)
[528.726165] [103/132] (507s left) gem_mmap_wc (read)
[528.803184] Subtest read: SKIP (0.000s)
[528.880977] [104/132] (507s left) sysfs_timeslice_duration (timeout)
[528.944809] Starting subtest: timeout
[528.947184] Starting dynamic subtest: vecs0
[538.578967] Dynamic subtest vecs0: SUCCESS (9.628s)
[538.591178] Starting dynamic subtest: ccs0
[539.979438] Dynamic subtest ccs0: SUCCESS (1.399s)
[539.992018] Starting dynamic subtest: vcs0
[549.533594] Dynamic subtest vcs0: SUCCESS (9.550s)
[549.549925] Starting dynamic subtest: bcs0
[550.215824] Dynamic subtest bcs0: SUCCESS (0.683s)
[550.227550] Starting dynamic subtest: rcs0
[551.514884] Dynamic subtest rcs0: SUCCESS (1.295s)
[551.527360] Starting dynamic subtest: vcs1
[561.559624] Dynamic subtest vcs1: SUCCESS (10.041s)
[561.562124] Subtest timeout: SUCCESS (32.610s)
[561.658575] [105/132] (474s left) gem_softpin (allocator-nopin)
[561.722974] Starting subtest: allocator-nopin
[561.831738] Subtest allocator-nopin: SUCCESS (0.107s)
[562.007748] [106/132] (474s left) gem_mmap_gtt (isolation)
[562.071126] Subtest isolation: SKIP (0.000s)
[562.136650] [107/132] (474s left) kms_flip (flip-vs-absolute-wf_vblank)
[562.236381] Starting subtest: flip-vs-absolute-wf_vblank
[562.238570] Starting dynamic subtest: A-eDP1
[571.207580] Dynamic subtest A-eDP1: SUCCESS (8.973s)
[571.220387] Starting dynamic subtest: B-eDP1
[580.143505] Dynamic subtest B-eDP1: SUCCESS (8.934s)
[580.158554] Starting dynamic subtest: C-eDP1
[589.056303] Dynamic subtest C-eDP1: SUCCESS (8.910s)
[589.068708] Starting dynamic subtest: D-eDP1
[597.981313] Dynamic subtest D-eDP1: SUCCESS (8.923s)
[597.986665] Subtest flip-vs-absolute-wf_vblank: SUCCESS (35.753s)
[599.217425] [108/132] (437s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-pwrite)
[599.323679] Starting subtest: psr-2p-primscrn-spr-indfb-draw-pwrite
[599.327966] Subtest psr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[599.432961] [109/132] (437s left) syncobj_eventfd (invalid-bad-pad)
[599.484708] Starting subtest: invalid-bad-pad
[599.486956] Subtest invalid-bad-pad: SUCCESS (0.000s)
[599.627506] [110/132] (436s left) kms_big_fb (yf-tiled-32bpp-rotate-180)
[599.734737] Starting subtest: yf-tiled-32bpp-rotate-180
[599.738950] Subtest yf-tiled-32bpp-rotate-180: SKIP (0.000s)
[599.871929] [111/132] (436s left) prime_self_import (export-vs-gem_close-race)
[599.914317] Starting subtest: export-vs-gem_close-race
[605.015549] Subtest export-vs-gem_close-race: SUCCESS (5.100s)
[605.119115] [112/132] (431s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[605.178299] Starting subtest: mixed-tiled-to-y-tiled-ccs
[605.182947] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s)
[605.393338] [113/132] (431s left) prime_mmap (test_correct)
[605.447787] Starting subtest: test_correct
[605.449814] Starting dynamic subtest: test_correct-smem
[605.479249] Dynamic subtest test_correct-smem: SUCCESS (0.034s)
[605.481716] Subtest test_correct: SUCCESS (0.035s)
[605.622632] [114/132] (431s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area)
[610.666225] Starting subtest: pr-overlay-plane-update-sf-dmg-area
[610.671598] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.002s)
[611.539202] [115/132] (425s left) gem_exec_schedule (u-submit-early-slice)
[611.604149] Starting subtest: u-submit-early-slice
[611.606469] Starting dynamic subtest: rcs0
[611.642872] Dynamic subtest rcs0: SUCCESS (0.047s)
[611.654780] Starting dynamic subtest: bcs0
[611.683450] Dynamic subtest bcs0: SUCCESS (0.040s)
[611.693611] Starting dynamic subtest: vcs0
[611.728727] Dynamic subtest vcs0: SUCCESS (0.044s)
[611.740045] Starting dynamic subtest: vcs1
[611.770931] Dynamic subtest vcs1: SUCCESS (0.041s)
[611.781538] Starting dynamic subtest: vecs0
[611.816285] Dynamic subtest vecs0: SUCCESS (0.045s)
[611.826807] Starting dynamic subtest: ccs0
[611.857628] Dynamic subtest ccs0: SUCCESS (0.041s)
[611.859980] Subtest u-submit-early-slice: SUCCESS (0.263s)
[613.621941] [116/132] (423s left) dumb_buffer (map-valid)
[613.680485] Starting subtest: map-valid
[613.682728] Subtest map-valid: SUCCESS (0.000s)
[613.812563] [117/132] (423s left) kms_atomic_interruptible (legacy-cursor)
[613.908898] Starting subtest: legacy-cursor
[613.911142] Starting dynamic subtest: pipe-A-eDP-1
[621.346990] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.436s)
[621.349587] Subtest legacy-cursor: SUCCESS (7.438s)
[622.208617] [118/132] (414s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff)
[622.318925] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff
[626.316205] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (4.002s)
[627.488479] [119/132] (409s left) sysfs_heartbeat_interval (mixed)
[627.546302] Starting subtest: mixed
[627.548617] Starting dynamic subtest: vecs0
[632.787445] Dynamic subtest vecs0: SUCCESS (5.241s)
[632.800474] Starting dynamic subtest: ccs0
[638.041567] Dynamic subtest ccs0: SUCCESS (5.253s)
[638.054183] Starting dynamic subtest: vcs0
[643.258466] Dynamic subtest vcs0: SUCCESS (5.216s)
[643.269540] Starting dynamic subtest: bcs0
[648.479079] Dynamic subtest bcs0: SUCCESS (5.219s)
[648.490735] Starting dynamic subtest: rcs0
[653.694896] Dynamic subtest rcs0: SUCCESS (5.215s)
[653.706706] Starting dynamic subtest: vcs1
[658.915974] Dynamic subtest vcs1: SUCCESS (5.220s)
[658.918742] Subtest mixed: SUCCESS (31.374s)
[659.021006] [120/132] (377s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[659.095846] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[659.165851] [121/132] (377s left) kms_dsc (dsc-with-output-formats)
[659.258925] Starting subtest: dsc-with-output-formats
[659.279939] Subtest dsc-with-output-formats: SKIP (0.000s)
[659.357460] [122/132] (377s left) kms_ccs (random-ccs-data-yf-tiled-ccs)
[659.448003] Starting subtest: random-ccs-data-yf-tiled-ccs
[659.450242] Starting dynamic subtest: pipe-A-eDP-1
[659.474829] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[659.487264] Starting dynamic subtest: pipe-B-eDP-1
[659.511046] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[659.520750] Starting dynamic subtest: pipe-C-eDP-1
[659.543814] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[659.552987] Starting dynamic subtest: pipe-D-eDP-1
[659.576854] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[659.581148] Subtest random-ccs-data-yf-tiled-ccs: SKIP (0.004s)
[661.719135] [123/132] (375s left) kms_psr (fbc-psr2-cursor-plane-move)
[661.831848] Starting subtest: fbc-psr2-cursor-plane-move
[661.834194] Starting dynamic subtest: eDP-1
[661.838481] Dynamic subtest eDP-1: SKIP (0.000s)
[661.842351] Subtest fbc-psr2-cursor-plane-move: SKIP (0.003s)
[661.931069] [124/132] (375s left) i915_suspend (basic-s2idle-without-i915)
[661.972396] Starting subtest: basic-s2idle-without-i915
[681.939189] Subtest basic-s2idle-without-i915: SUCCESS (4.659s)
[691.079143] [125/132] (355s left) gem_exec_reloc (basic-cpu-gtt)
[691.155900] Subtest basic-cpu-gtt: SKIP (0.000s)
[691.235752] [126/132] (354s left) gem_fenced_exec_thrash (no-spare-fences-busy)
[691.309320] Subtest no-spare-fences-busy: SKIP (0.000s)
[691.388622] [127/132] (354s left) gem_ctx_persistence (userptr)
[691.450585] Starting subtest: userptr
[691.720412] Subtest userptr: SUCCESS (0.280s)
[691.822528] [128/132] (354s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180)
[691.933376] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180
[691.937818] Subtest y-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[692.067482] [129/132] (354s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[692.326370] Starting subtest: cursor-rapid-movement-512x512
[692.330186] Subtest cursor-rapid-movement-512x512: SKIP (0.001s)
[692.512323] [130/132] (353s left) kms_content_protection (atomic-hdcp14)
[692.646577] Starting subtest: atomic-hdcp14
[692.650202] Subtest atomic-hdcp14: SKIP (0.000s)
[692.809736] [131/132] (353s left) syncobj_wait (wait-all-complex)
[692.858880] Starting subtest: wait-all-complex
[693.071591] Subtest wait-all-complex: SUCCESS (0.217s)
[693.169606] [132/132] (353s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[693.232828] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[693.333841] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.104s)
[693.437128] Closing watchdogs
results: parsing output: 0/ for test: kms_cursor_edge_walk
results: parsing output: 1/ for test: kms_psr
results: parsing output: 2/ for test: kms_cursor_legacy
results: parsing output: 3/ for test: dumb_buffer
results: parsing output: 4/ for test: kms_fbcon_fbt
results: parsing output: 5/ for test: gen9_exec_parse
results: parsing output: 6/ for test: kms_vblank
results: parsing output: 7/ for test: gem_tiled_swapping
results: parsing output: 8/ for test: api_intel_bb
results: parsing output: 9/ for test: kms_psr
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: kms_invalid_mode
results: parsing output: 13/ for test: gem_mmap
results: parsing output: 14/ for test: syncobj_timeline
results: parsing output: 15/ for test: prime_mmap_kms
results: parsing output: 16/ for test: kms_async_flips
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: gem_wait
results: parsing output: 19/ for test: gem_render_copy
results: parsing output: 20/ for test: kms_psr
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: kms_async_flips
results: parsing output: 24/ for test: kms_ccs
results: parsing output: 25/ for test: perf_pmu
results: parsing output: 26/ for test: syncobj_timeline
results: parsing output: 27/ for test: kms_pm_dc
results: parsing output: 28/ for test: gen9_exec_parse
results: parsing output: 29/ for test: kms_psr2_sf
results: parsing output: 30/ for test: kms_flip_scaled_crc
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: gem_exec_schedule
results: parsing output: 34/ for test: kms_prop_blob
results: parsing output: 35/ for test: perf_pmu
results: parsing output: 36/ for test: kms_flip
results: parsing output: 37/ for test: i915_query
results: parsing output: 38/ for test: kms_addfb_basic
results: parsing output: 39/ for test: sw_sync
results: parsing output: 40/ for test: kms_color_pipeline
results: parsing output: 41/ for test: gem_lmem_swapping
results: parsing output: 42/ for test: kms_plane_alpha_blend
results: parsing output: 43/ for test: kms_big_fb
results: parsing output: 44/ for test: syncobj_timeline
results: parsing output: 45/ for test: gem_exec_params
results: parsing output: 46/ for test: gem_flink_basic
results: parsing output: 47/ for test: gem_exec_fence
results: parsing output: 48/ for test: syncobj_wait
results: parsing output: 49/ for test: gem_exec_schedule
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: kms_ccs
results: parsing output: 52/ for test: kms_chamelium_frames
results: parsing output: 53/ for test: gem_ctx_engines
results: parsing output: 54/ for test: kms_big_fb
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: syncobj_wait
results: parsing output: 57/ for test: gem_eio
results: parsing output: 58/ for test: gem_exec_balancer
results: parsing output: 59/ for test: api_intel_allocator
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: gem_exec_basic
results: parsing output: 62/ for test: gem_mmap_gtt
results: parsing output: 63/ for test: gem_mmap_wc
results: parsing output: 64/ for test: sw_sync
results: parsing output: 65/ for test: kms_big_fb
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: syncobj_wait
results: parsing output: 68/ for test: gem_mmap_gtt
results: parsing output: 69/ for test: prime_busy
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_cursor_crc
results: parsing output: 72/ for test: gem_ctx_param
results: parsing output: 73/ for test: kms_rotation_crc
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_selftest
results: parsing output: 76/ for test: gem_ctx_param
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_big_fb
results: parsing output: 79/ for test: kms_big_fb
results: parsing output: 80/ for test: kms_plane_multiple
results: parsing output: 81/ for test: kms_ccs
results: parsing output: 82/ for test: kms_chamelium_hpd
results: parsing output: 83/ for test: perf
results: parsing output: 84/ for test: kms_setmode
results: parsing output: 85/ for test: kms_psr
results: parsing output: 86/ for test: gem_create
results: parsing output: 87/ for test: kms_atomic_transition
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_lease
results: parsing output: 90/ for test: drm_read
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_ccs
results: parsing output: 93/ for test: kms_pm_rpm
results: parsing output: 94/ for test: i915_getparams_basic
results: parsing output: 95/ for test: gem_exec_fence
results: parsing output: 96/ for test: kms_plane_scaling
results: parsing output: 97/ for test: sw_sync
results: parsing output: 98/ for test: i915_pm_rpm
results: parsing output: 99/ for test: kms_flip
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: prime_mmap_coherency
results: parsing output: 102/ for test: gem_mmap_wc
results: parsing output: 103/ for test: sysfs_timeslice_duration
results: parsing output: 104/ for test: gem_softpin
results: parsing output: 105/ for test: gem_mmap_gtt
results: parsing output: 106/ for test: kms_flip
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: syncobj_eventfd
results: parsing output: 109/ for test: kms_big_fb
results: parsing output: 110/ for test: prime_self_import
results: parsing output: 111/ for test: gem_render_copy
results: parsing output: 112/ for test: prime_mmap
results: parsing output: 113/ for test: kms_psr2_sf
results: parsing output: 114/ for test: gem_exec_schedule
results: parsing output: 115/ for test: dumb_buffer
results: parsing output: 116/ for test: kms_atomic_interruptible
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: sysfs_heartbeat_interval
results: parsing output: 119/ for test: gem_exec_reloc
results: parsing output: 120/ for test: kms_dsc
results: parsing output: 121/ for test: kms_ccs
results: parsing output: 122/ for test: kms_psr
results: parsing output: 123/ for test: i915_suspend
results: parsing output: 124/ for test: gem_exec_reloc
results: parsing output: 125/ for test: gem_fenced_exec_thrash
results: parsing output: 126/ for test: gem_ctx_persistence
results: parsing output: 127/ for test: kms_big_fb
results: parsing output: 128/ for test: kms_cursor_crc
results: parsing output: 129/ for test: kms_content_protection
results: parsing output: 130/ for test: syncobj_wait
results: parsing output: 131/ for test: syncobj_timeline
Done.