[57.775821] Initializing watchdogs
[57.775872]   /dev/watchdog0
[57.791154] [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.795715] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.797973] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.801837] [001/133] (960s left) kms_frontbuffer_tracking (fbcpsr-1p-indfb-fliptrack-mmap-gtt)
[57.928386] Starting subtest: fbcpsr-1p-indfb-fliptrack-mmap-gtt
[57.932879] Subtest fbcpsr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[58.038447] [002/133] (960s left) kms_chamelium_audio (hdmi-audio-edid)
[58.439100] Subtest hdmi-audio-edid: SKIP (0.000s)
[59.231067] [003/133] (959s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-blt)
[59.344136] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-blt
[59.348455] Subtest fbc-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[59.438854] [004/133] (958s left) kms_big_fb (x-tiled-64bpp-rotate-0)
[59.540535] Starting subtest: x-tiled-64bpp-rotate-0
[61.127156] Subtest x-tiled-64bpp-rotate-0: SUCCESS (1.579s)
[61.306123] [005/133] (957s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[61.410959] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[61.415635] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[61.517695] [006/133] (956s left) syncobj_wait (single-wait-all-submitted)
[61.580050] Starting subtest: single-wait-all-submitted
[61.690920] Subtest single-wait-all-submitted: SUCCESS (0.108s)
[61.776885] [007/133] (956s left) kms_flip (nonexisting-fb-interruptible)
[61.881348] Starting subtest: nonexisting-fb-interruptible
[61.883760] Starting dynamic subtest: A-eDP1
[63.068898] Dynamic subtest A-eDP1: SUCCESS (1.182s)
[63.079215] Starting dynamic subtest: B-eDP1
[64.212639] Dynamic subtest B-eDP1: SUCCESS (1.140s)
[64.222069] Starting dynamic subtest: C-eDP1
[65.340585] Dynamic subtest C-eDP1: SUCCESS (1.123s)
[65.350569] Starting dynamic subtest: D-eDP1
[66.467718] Dynamic subtest D-eDP1: SUCCESS (1.123s)
[66.473745] Subtest nonexisting-fb-interruptible: SUCCESS (4.580s)
[67.642106] [008/133] (950s left) kms_async_flips (test-time-stamp)
[67.743876] Starting subtest: test-time-stamp
[68.124207] Starting dynamic subtest: pipe-A-eDP-1
[68.940689] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.816s)
[69.329808] Starting dynamic subtest: pipe-B-eDP-1
[70.086799] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.760s)
[70.475354] Starting dynamic subtest: pipe-C-eDP-1
[71.227910] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.758s)
[71.593900] Starting dynamic subtest: pipe-D-eDP-1
[72.359915] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.771s)
[72.362403] Subtest test-time-stamp: SUCCESS (4.610s)
[73.512584] [009/133] (945s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[73.604781] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen
[73.609070] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[73.706604] [010/133] (944s left) api_intel_bb (misplaced-blitter)
[73.762259] Starting subtest: misplaced-blitter
[73.767452] Subtest misplaced-blitter: SUCCESS (0.007s)
[73.913281] [011/133] (944s left) gem_tiled_pread_pwrite
[74.028048] [012/133] (944s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[74.129811] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[74.134070] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[74.219676] [013/133] (944s left) gem_create (create-ext-placement-each)
[74.394785] Starting subtest: create-ext-placement-each
[74.396816] Subtest create-ext-placement-each: SUCCESS (0.000s)
[74.485928] [014/133] (944s left) kms_cursor_legacy (nonblocking-modeset-vs-cursor-atomic)
[74.607617] Starting subtest: nonblocking-modeset-vs-cursor-atomic
[76.014421] Subtest nonblocking-modeset-vs-cursor-atomic: SUCCESS (1.404s)
[76.884883] [015/133] (941s left) kms_force_connector_basic (prune-stale-modes)
[76.967829] Starting subtest: prune-stale-modes
[77.410475] Subtest prune-stale-modes: SUCCESS (0.445s)
[77.546855] [016/133] (941s left) kms_vblank (query-forked-busy)
[77.642280] Starting subtest: query-forked-busy
[77.644286] Starting dynamic subtest: pipe-A-eDP-1
[79.515922] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.873s)
[79.531929] Starting dynamic subtest: pipe-D-eDP-1
[82.037796] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.517s)
[82.040085] Subtest query-forked-busy: SUCCESS (4.394s)
[82.901242] [017/133] (936s left) gem_lmem_swapping (heavy-random)
[82.951718] Subtest heavy-random: SKIP (0.000s)
[83.000241] [018/133] (936s left) api_intel_allocator (simple-allocator)
[83.045124] Starting subtest: simple-allocator
[83.047906] Starting dynamic subtest: basic
[83.050379] Dynamic subtest basic: SUCCESS (0.004s)
[83.064240] Starting dynamic subtest: parallel-one
[83.258549] Dynamic subtest parallel-one: SUCCESS (0.210s)
[83.267492] Starting dynamic subtest: print
[83.269765] Dynamic subtest print: SUCCESS (0.000s)
[83.277732] Starting dynamic subtest: reuse
[83.279656] Dynamic subtest reuse: SUCCESS (0.000s)
[83.286977] Starting dynamic subtest: reserve
[83.288900] Dynamic subtest reserve: SUCCESS (0.000s)
[83.296114] Starting dynamic subtest: fork-reopen-allocator
[83.298082] Dynamic subtest fork-reopen-allocator: SUCCESS (0.014s)
[83.299957] Subtest simple-allocator: SUCCESS (0.231s)
[83.391422] [019/133] (935s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[83.513196] Starting subtest: 2x-long-flip-vs-cursor-atomic
[83.517717] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.001s)
[83.654936] [020/133] (935s left) gem_exec_suspend (basic)
[83.732994] Starting subtest: basic
[83.735228] Starting dynamic subtest: smem
[85.311927] Dynamic subtest smem: SUCCESS (1.579s)
[85.314724] Subtest basic: SUCCESS (1.580s)
[85.492398] [021/133] (933s left) gem_exec_parallel (basic)
[85.554215] Starting subtest: basic
[85.557236] Starting dynamic subtest: rcs0
[87.083224] Dynamic subtest rcs0: SUCCESS (1.528s)
[87.093074] Starting dynamic subtest: bcs0
[88.591320] Dynamic subtest bcs0: SUCCESS (1.507s)
[88.599547] Starting dynamic subtest: vcs0
[90.248947] Dynamic subtest vcs0: SUCCESS (1.655s)
[90.257381] Starting dynamic subtest: vcs1
[92.286590] Dynamic subtest vcs1: SUCCESS (2.036s)
[92.297469] Starting dynamic subtest: vecs0
[94.269176] Dynamic subtest vecs0: SUCCESS (1.981s)
[94.278364] Starting dynamic subtest: ccs0
[95.763744] Dynamic subtest ccs0: SUCCESS (1.494s)
[95.766509] Subtest basic: SUCCESS (10.208s)
[95.957417] [022/133] (923s left) kms_flip (flip-vs-fences)
[96.057830] Starting subtest: flip-vs-fences
[96.062179] Subtest flip-vs-fences: SKIP (0.000s)
[96.168053] [023/133] (923s left) gem_exec_schedule (out-order)
[96.227953] Starting subtest: out-order
[96.230198] Starting dynamic subtest: rcs0
[96.327406] Dynamic subtest rcs0: SUCCESS (0.107s)
[96.335342] Starting dynamic subtest: vcs0
[96.412922] Dynamic subtest vcs0: SUCCESS (0.085s)
[96.427318] Starting dynamic subtest: vcs1
[96.495254] Dynamic subtest vcs1: SUCCESS (0.081s)
[96.506210] Starting dynamic subtest: vecs0
[96.569130] Dynamic subtest vecs0: SUCCESS (0.074s)
[96.580318] Starting dynamic subtest: ccs0
[96.653609] Dynamic subtest ccs0: SUCCESS (0.083s)
[96.656530] Subtest out-order: SUCCESS (0.433s)
[98.404154] [024/133] (920s left) gem_busy (close-race)
[98.464437] Starting subtest: close-race
[122.242755] Subtest close-race: SUCCESS (23.775s)
[122.584521] [FACT gem_busy (close-race)] new: kernel.kmod_is_loaded.vgem: true
[122.588927] [025/133] (896s left) gem_eio (throttle)
[122.861070] Starting subtest: throttle
[123.180860] Subtest throttle: SUCCESS (0.321s)
[123.302159] [026/133] (896s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[123.407095] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[123.409396] Starting dynamic subtest: pipe-A-default-mode
[124.543576] Dynamic subtest pipe-A-default-mode: SKIP (1.134s)
[124.547302] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.136s)
[124.691125] [027/133] (894s left) i915_hangman (error-state-basic)
[124.746744] Starting subtest: error-state-basic
[124.885777] Subtest error-state-basic: SUCCESS (0.140s)
[124.963749] [028/133] (894s left) kms_ccs (random-ccs-data-4-tiled-dg2-mc-ccs)
[125.056486] Starting subtest: random-ccs-data-4-tiled-dg2-mc-ccs
[125.058870] Starting dynamic subtest: pipe-A-eDP-1
[125.084286] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[125.095027] Starting dynamic subtest: pipe-B-eDP-1
[125.117486] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[125.127796] Starting dynamic subtest: pipe-C-eDP-1
[125.151415] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[125.161655] Starting dynamic subtest: pipe-D-eDP-1
[125.185986] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[125.190109] Subtest random-ccs-data-4-tiled-dg2-mc-ccs: SKIP (0.003s)
[127.319592] [029/133] (892s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-modifiers)
[127.419046] Starting subtest: plane-scaler-with-clipping-clamping-modifiers
[127.421457] Starting dynamic subtest: pipe-A
[127.756562] Dynamic subtest pipe-A: SUCCESS (0.340s)
[127.765710] Starting dynamic subtest: pipe-B
[129.213017] Dynamic subtest pipe-B: SUCCESS (1.456s)
[129.221997] Starting dynamic subtest: pipe-C
[130.684351] Dynamic subtest pipe-C: SUCCESS (1.471s)
[130.695792] Starting dynamic subtest: pipe-D
[132.070814] Dynamic subtest pipe-D: SUCCESS (1.386s)
[132.073591] Subtest plane-scaler-with-clipping-clamping-modifiers: SUCCESS (4.654s)
[133.232246] [030/133] (886s left) gem_ccs (block-multicopy-inplace)
[133.295791] Starting subtest: block-multicopy-inplace
[133.300159] Subtest block-multicopy-inplace: SKIP (0.000s)
[133.402015] [031/133] (886s left) sysfs_heartbeat_interval (precise)
[133.473143] Starting subtest: precise
[133.475185] Starting dynamic subtest: vecs0
[141.307235] Dynamic subtest vecs0: SUCCESS (7.836s)
[141.319078] Starting dynamic subtest: ccs0
[142.497021] Dynamic subtest ccs0: SUCCESS (1.188s)
[142.511077] Starting dynamic subtest: vcs0
[150.276397] Dynamic subtest vcs0: SUCCESS (7.777s)
[150.288864] Starting dynamic subtest: bcs0
[151.396614] Dynamic subtest bcs0: SUCCESS (1.119s)
[151.405901] Starting dynamic subtest: rcs0
[152.467353] Dynamic subtest rcs0: SUCCESS (1.068s)
[152.478605] Starting dynamic subtest: vcs1
[160.322169] Dynamic subtest vcs1: SUCCESS (7.854s)
[160.324718] Subtest precise: SUCCESS (26.851s)
[160.413415] [032/133] (859s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-y-tiled)
[160.486014] Starting subtest: y-tiled-mc-ccs-to-vebox-y-tiled
[160.490701] Subtest y-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[160.673744] [033/133] (858s left) syncobj_wait (single-wait-for-submit-unsubmitted)
[160.740047] Starting subtest: single-wait-for-submit-unsubmitted
[160.849573] Subtest single-wait-for-submit-unsubmitted: SUCCESS (0.107s)
[160.923162] [034/133] (858s left) gen9_exec_parse (allowed-single)
[160.981648] Subtest allowed-single: SKIP (0.000s)
[161.054213] [035/133] (858s left) gem_exec_schedule (submit-late-slice)
[161.126611] Starting subtest: submit-late-slice
[161.128930] Starting dynamic subtest: rcs0
[161.181796] Dynamic subtest rcs0: SUCCESS (0.062s)
[161.191533] Starting dynamic subtest: bcs0
[161.240493] Dynamic subtest bcs0: SUCCESS (0.057s)
[161.252947] Starting dynamic subtest: vcs0
[161.299967] Dynamic subtest vcs0: SUCCESS (0.059s)
[161.312304] Starting dynamic subtest: vcs1
[161.357829] Dynamic subtest vcs1: SUCCESS (0.057s)
[161.369669] Starting dynamic subtest: vecs0
[161.409031] Dynamic subtest vecs0: SUCCESS (0.051s)
[161.419838] Starting dynamic subtest: ccs0
[161.474882] Dynamic subtest ccs0: SUCCESS (0.065s)
[161.477907] Subtest submit-late-slice: SUCCESS (0.355s)
[162.401229] [036/133] (857s left) gem_exec_flush (basic-wb-set-default)
[162.457821] Starting subtest: basic-wb-set-default
[167.951069] Subtest basic-wb-set-default: SUCCESS (5.472s)
[168.130146] [037/133] (851s left) kms_plane (pixel-format-linear-modifier-source-clamping)
[168.218096] Starting subtest: pixel-format-linear-modifier-source-clamping
[168.243693] Starting dynamic subtest: pipe-A-plane-0
[171.921091] Dynamic subtest pipe-A-plane-0: SUCCESS (3.678s)
[171.930577] Starting dynamic subtest: pipe-A-plane-3
[173.610185] Dynamic subtest pipe-A-plane-3: SUCCESS (1.688s)
[174.671982] Starting dynamic subtest: pipe-B-plane-0
[178.261773] Dynamic subtest pipe-B-plane-0: SUCCESS (3.600s)
[178.271401] Starting dynamic subtest: pipe-B-plane-3
[179.995140] Dynamic subtest pipe-B-plane-3: SUCCESS (1.732s)
[180.316224] Subtest pixel-format-linear-modifier-source-clamping: SUCCESS (12.099s)
[181.113418] [038/133] (838s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling)
[181.204833] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling
[181.209174] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SKIP (0.000s)
[181.278391] [039/133] (838s left) gem_exec_big (single)
[181.336777] Starting subtest: single
[184.703224] Subtest single: FAIL (3.308s)
[184.748497] [040/133] (835s left) gem_pread (bench)
[184.849670] Subtest bench: SKIP (0.000s)
[184.905786] [041/133] (835s left) kms_sequence (get-forked)
[185.010951] Starting subtest: get-forked
[185.013313] Starting dynamic subtest: pipe-A-eDP-1
[186.929077] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.917s)
[186.939635] Starting dynamic subtest: pipe-B-eDP-1
[189.518793] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.590s)
[189.528198] Starting dynamic subtest: pipe-C-eDP-1
[192.071272] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.550s)
[192.082082] Starting dynamic subtest: pipe-D-eDP-1
[194.603270] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.530s)
[194.605632] Subtest get-forked: SUCCESS (9.592s)
[195.415420] [042/133] (824s left) kms_psr (fbc-psr-basic)
[195.513418] Starting subtest: fbc-psr-basic
[195.515324] Starting dynamic subtest: eDP-1
[195.519272] Dynamic subtest eDP-1: SKIP (0.000s)
[195.523221] Subtest fbc-psr-basic: SKIP (0.001s)
[195.603235] [043/133] (824s left) kms_big_fb (yf-tiled-8bpp-rotate-90)
[195.709621] Starting subtest: yf-tiled-8bpp-rotate-90
[195.713776] Subtest yf-tiled-8bpp-rotate-90: SKIP (0.000s)
[195.810198] [044/133] (824s left) gem_softpin (allocator-basic)
[195.867834] Starting subtest: allocator-basic
[195.939703] Subtest allocator-basic: SUCCESS (0.071s)
[196.047604] [045/133] (824s left) kms_properties (plane-properties-legacy)
[196.140948] Starting subtest: plane-properties-legacy
[196.143208] Starting dynamic subtest: pipe-A-eDP-1
[196.716283] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.574s)
[196.725725] Starting dynamic subtest: pipe-B-eDP-1
[197.946708] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.231s)
[197.958258] Starting dynamic subtest: pipe-C-eDP-1
[199.254110] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.307s)
[199.264109] Starting dynamic subtest: pipe-D-eDP-1
[200.556029] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.301s)
[200.558258] Subtest plane-properties-legacy: SUCCESS (4.417s)
[201.402829] [046/133] (818s left) kms_cursor_crc (cursor-offscreen-256x256)
[201.648744] Starting subtest: cursor-offscreen-256x256
[201.653797] Starting dynamic subtest: pipe-A-eDP-1
[203.606387] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.953s)
[203.636630] Starting dynamic subtest: pipe-D-eDP-1
[206.174684] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.545s)
[206.176893] Subtest cursor-offscreen-256x256: SUCCESS (4.526s)
[207.007108] [047/133] (813s left) kms_flip (2x-flip-vs-panning)
[207.100325] Starting subtest: 2x-flip-vs-panning
[207.111579] Subtest 2x-flip-vs-panning: SKIP (0.011s)
[207.193920] [048/133] (813s left) prime_mmap_kms (buffer-sharing)
[207.280196] Starting subtest: buffer-sharing
[207.712571] Subtest buffer-sharing: SUCCESS (0.432s)
[208.506661] [049/133] (811s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted-submitted)
[208.568785] Starting subtest: etime-multi-wait-for-submit-unsubmitted-submitted
[208.675513] Subtest etime-multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.104s)
[208.752579] [050/133] (811s left) kms_content_protection (atomic-dpms)
[208.893660] Starting subtest: atomic-dpms
[208.898407] Subtest atomic-dpms: SKIP (0.000s)
[209.023980] [051/133] (811s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area)
[209.131472] Starting subtest: pr-overlay-plane-update-sf-dmg-area
[209.135803] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.000s)
[209.207742] [052/133] (811s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[209.314362] Starting subtest: fbc-rgb565-draw-mmap-wc
[213.272687] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (3.962s)
[214.419100] [053/133] (806s left) kms_psr (fbc-pr-sprite-mmap-cpu)
[214.517086] Starting subtest: fbc-pr-sprite-mmap-cpu
[214.521543] Subtest fbc-pr-sprite-mmap-cpu: SKIP (0.000s)
[214.599193] [054/133] (805s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[214.705625] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[214.709871] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[214.813935] [055/133] (805s left) gem_exec_fence (syncobj-export)
[215.268075] Starting subtest: syncobj-export
[215.274466] Subtest syncobj-export: SUCCESS (0.010s)
[216.402479] [056/133] (804s left) gem_ctx_sseu (invalid-sseu)
[216.471835] Subtest invalid-sseu: SKIP (0.000s)
[216.530904] [057/133] (804s left) kms_psr (pr-sprite-plane-move)
[216.630961] Starting subtest: pr-sprite-plane-move
[216.634960] Subtest pr-sprite-plane-move: SKIP (0.000s)
[216.719289] [058/133] (803s left) gem_eio (wait-immediate)
[216.939813] Starting subtest: wait-immediate
[217.301818] Subtest wait-immediate: SUCCESS (0.362s)
[217.452891] [059/133] (803s left) kms_async_flips (async-flip-with-page-flip-events-tiled)
[217.546493] Starting subtest: async-flip-with-page-flip-events-tiled
[217.922158] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[220.711636] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.735s)
[220.724374] Starting dynamic subtest: pipe-A-eDP-1-4
[222.885145] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.173s)
[222.895640] Starting dynamic subtest: pipe-A-eDP-1-4
[224.843101] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.009s)
[224.852267] Starting dynamic subtest: pipe-A-eDP-1-x
[227.085850] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.189s)
[227.408510] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[230.198185] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.744s)
[230.208979] Starting dynamic subtest: pipe-B-eDP-1-4
[232.394879] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.196s)
[232.404632] Starting dynamic subtest: pipe-B-eDP-1-4
[234.353499] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.009s)
[234.363613] Starting dynamic subtest: pipe-B-eDP-1-x
[236.617113] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.211s)
[236.929809] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[239.706488] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.731s)
[239.717646] Starting dynamic subtest: pipe-C-eDP-1-4
[241.881264] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.174s)
[241.890381] Starting dynamic subtest: pipe-C-eDP-1-4
[243.839936] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[243.849247] Starting dynamic subtest: pipe-C-eDP-1-x
[246.070072] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.177s)
[246.383755] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[249.145387] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.769s)
[249.157703] Starting dynamic subtest: pipe-D-eDP-1-4
[251.372022] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.173s)
[251.383643] Starting dynamic subtest: pipe-D-eDP-1-4
[253.330319] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[253.341736] Starting dynamic subtest: pipe-D-eDP-1-x
[255.561736] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.177s)
[255.564748] Subtest async-flip-with-page-flip-events-tiled: SUCCESS (37.962s)
[256.698156] [060/133] (764s left) gem_exec_balancer (full-pulse)
[256.762520] Starting subtest: full-pulse
[261.171270] Subtest full-pulse: SUCCESS (4.410s)
[261.498396] [061/133] (759s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-factor-0-25)
[261.609112] Starting subtest: planes-downscale-factor-0-5-upscale-factor-0-25
[261.611413] Starting dynamic subtest: pipe-A
[261.632302] Dynamic subtest pipe-A: SKIP (0.018s)
[261.642669] Starting dynamic subtest: pipe-B
[261.660798] Dynamic subtest pipe-B: SKIP (0.030s)
[261.670648] Starting dynamic subtest: pipe-C
[261.695029] Dynamic subtest pipe-C: SKIP (0.030s)
[261.716731] Starting dynamic subtest: pipe-D
[261.725597] Dynamic subtest pipe-D: SKIP (0.030s)
[261.729488] Subtest planes-downscale-factor-0-5-upscale-factor-0-25: SKIP (0.111s)
[261.855984] [062/133] (759s left) gem_exec_params (no-blt)
[261.917450] Starting subtest: no-blt
[261.923063] Subtest no-blt: SUCCESS (0.002s)
[262.037895] [063/133] (758s left) kms_plane_alpha_blend (coverage-vs-premult-vs-constant)
[262.140265] Starting subtest: coverage-vs-premult-vs-constant
[262.430731] Starting dynamic subtest: pipe-A-eDP-1
[263.004681] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.576s)
[263.015637] Starting dynamic subtest: pipe-D-eDP-1
[264.289653] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.281s)
[264.291998] Subtest coverage-vs-premult-vs-constant: SUCCESS (2.149s)
[265.101312] [064/133] (755s left) syncobj_wait (single-wait-for-submit-submitted)
[265.163716] Starting subtest: single-wait-for-submit-submitted
[265.264538] Subtest single-wait-for-submit-submitted: SUCCESS (0.102s)
[265.341148] [065/133] (755s left) kms_pm_backlight (fade-with-dpms)
[265.437366] Starting subtest: fade-with-dpms
[267.703729] Starting dynamic subtest: eDP-1
[270.382864] Dynamic subtest eDP-1: SUCCESS (2.678s)
[270.385286] Subtest fade-with-dpms: SUCCESS (4.947s)
[271.208250] [066/133] (749s left) kms_psr (psr-cursor-plane-move)
[271.300268] Starting subtest: psr-cursor-plane-move
[271.302556] Starting dynamic subtest: eDP-1
[273.067560] Dynamic subtest eDP-1: SUCCESS (1.768s)
[273.069872] Subtest psr-cursor-plane-move: SUCCESS (1.769s)
[273.908063] [067/133] (747s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-blt)
[274.008445] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-blt
[274.012756] Subtest fbc-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[274.120326] [068/133] (747s left) gem_exec_flush (basic-batch-kernel-default-wb)
[274.196636] Starting subtest: basic-batch-kernel-default-wb
[281.443204] Subtest basic-batch-kernel-default-wb: SUCCESS (7.247s)
[281.634273] [069/133] (739s left) syncobj_basic (bad-destroy-pad)
[281.689673] Starting subtest: bad-destroy-pad
[281.691741] Subtest bad-destroy-pad: SUCCESS (0.000s)
[281.789997] [070/133] (739s left) kms_chamelium_hpd (vga-hpd-for-each-pipe)
[282.182931] Subtest vga-hpd-for-each-pipe: SKIP (0.000s)
[282.974499] [071/133] (738s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[283.070658] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[283.872932] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.803s)
[284.038273] [072/133] (737s left) i915_drm_fdinfo (memory-info-resident)
[284.085075] Starting subtest: memory-info-resident
[284.087389] Starting dynamic subtest: smem0
[286.593566] Dynamic subtest smem0: SUCCESS (2.508s)
[286.595786] Subtest memory-info-resident: SUCCESS (2.509s)
[286.688303] [073/133] (734s left) gem_userptr_blits (invalid-null-pointer)
[286.762851] Starting subtest: invalid-null-pointer
[286.764515] Subtest invalid-null-pointer: SUCCESS (0.001s)
[286.909390] [074/133] (734s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[287.011947] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[287.015329] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[287.129663] [075/133] (734s left) gem_exec_reloc (basic-scanout)
[287.187275] Subtest basic-scanout: SKIP (0.000s)
[287.260155] [076/133] (734s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[287.348399] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-gtt
[287.352139] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[287.447235] [077/133] (734s left) syncobj_timeline (multi-wait-available-unsubmitted-signaled)
[287.497857] Starting subtest: multi-wait-available-unsubmitted-signaled
[287.499716] Subtest multi-wait-available-unsubmitted-signaled: SUCCESS (0.000s)
[287.583877] [078/133] (734s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[287.687607] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[287.689396] Starting dynamic subtest: pipe-A-eDP-1
[287.706244] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[287.717243] Starting dynamic subtest: pipe-B-eDP-1
[287.732948] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[287.741796] Starting dynamic subtest: pipe-C-eDP-1
[287.757070] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[287.766300] Starting dynamic subtest: pipe-D-eDP-1
[287.782244] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[287.783801] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.008s)
[289.943360] [079/133] (731s left) kms_getfb (getfb2-handle-zero)
[290.044109] Starting subtest: getfb2-handle-zero
[290.046243] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[290.141374] [080/133] (731s left) kms_psr (fbc-pr-cursor-plane-move)
[290.240867] Starting subtest: fbc-pr-cursor-plane-move
[290.245224] Subtest fbc-pr-cursor-plane-move: SKIP (0.000s)
[290.316503] [081/133] (731s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[290.411625] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[290.415940] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[290.515042] [082/133] (731s left) i915_pm_rpm (system-suspend)
[292.443054] Starting subtest: system-suspend
[310.343634] Subtest system-suspend: SUCCESS (3.023s)
[310.729113] [083/133] (711s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[310.854683] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[311.645822] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.782s)
[311.810781] [084/133] (710s left) kms_rotation_crc (primary-y-tiled-reflect-x-270)
[311.908545] Starting subtest: primary-y-tiled-reflect-x-270
[311.912617] Subtest primary-y-tiled-reflect-x-270: SKIP (0.001s)
[311.984901] [085/133] (709s left) gem_ctx_param (invalid-get-ringsize)
[312.038473] Starting subtest: invalid-get-ringsize
[312.040816] Subtest invalid-get-ringsize: SUCCESS (0.001s)
[312.157879] [086/133] (709s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs-cc)
[312.250003] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs-cc
[312.252298] Starting dynamic subtest: pipe-A-eDP-1
[312.261540] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[312.273923] Starting dynamic subtest: pipe-B-eDP-1
[312.283964] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[312.297111] Starting dynamic subtest: pipe-C-eDP-1
[312.305798] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[312.316787] Starting dynamic subtest: pipe-D-eDP-1
[312.325512] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[312.329985] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s)
[314.564674] [087/133] (707s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[314.660697] Starting subtest: bad-pixel-format-y-tiled-ccs
[314.663092] Starting dynamic subtest: pipe-A-eDP-1
[314.686567] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[314.700397] Starting dynamic subtest: pipe-B-eDP-1
[314.723078] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[314.733479] Starting dynamic subtest: pipe-C-eDP-1
[314.755283] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[314.765494] Starting dynamic subtest: pipe-D-eDP-1
[314.787747] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[314.791962] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.005s)
[316.964830] [088/133] (705s left) gem_exec_whisper (basic-fds-all)
[317.045361] Starting subtest: basic-fds-all
[328.284662] Subtest basic-fds-all: SUCCESS (11.193s)
[328.468221] [089/133] (693s left) core_setmaster (master-drop-set-shared-fd)
[328.563307] Starting subtest: master-drop-set-shared-fd
[328.582584] Subtest master-drop-set-shared-fd: SUCCESS (0.021s)
[328.623359] [090/133] (693s left) sw_sync (sync_multi_consumer)
[328.663352] Starting subtest: sync_multi_consumer
[329.102568] Subtest sync_multi_consumer: SUCCESS (0.438s)
[329.168157] [091/133] (693s left) prime_busy (before-wait)
[329.216536] Starting subtest: before-wait
[329.221457] Starting dynamic subtest: rcs0
[329.253747] Dynamic subtest rcs0: SUCCESS (0.032s)
[329.265415] Starting dynamic subtest: bcs0
[329.307810] Dynamic subtest bcs0: SUCCESS (0.054s)
[329.319354] Starting dynamic subtest: vcs0
[329.350322] Dynamic subtest vcs0: SUCCESS (0.042s)
[329.361079] Starting dynamic subtest: vcs1
[329.392646] Dynamic subtest vcs1: SUCCESS (0.042s)
[329.403362] Starting dynamic subtest: vecs0
[329.434200] Dynamic subtest vecs0: SUCCESS (0.041s)
[329.445009] Starting dynamic subtest: ccs0
[329.477612] Dynamic subtest ccs0: SUCCESS (0.043s)
[329.584861] Subtest before-wait: SUCCESS (0.366s)
[329.662744] [092/133] (692s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[329.776402] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[329.780761] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[329.870240] [093/133] (692s left) kms_cursor_crc (cursor-random-128x42)
[330.091405] Starting subtest: cursor-random-128x42
[330.137834] Subtest cursor-random-128x42: SKIP (0.041s)
[330.331558] [094/133] (691s left) kms_lease (lease-unleased-connector)
[330.442436] Starting subtest: lease-unleased-connector
[330.444611] Starting dynamic subtest: pipe-A-eDP-1
[330.446945] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.006s)
[330.459443] Starting dynamic subtest: pipe-B-eDP-1
[330.461836] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.006s)
[330.473856] Starting dynamic subtest: pipe-C-eDP-1
[330.475873] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.007s)
[330.486833] Starting dynamic subtest: pipe-D-eDP-1
[330.489118] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.007s)
[330.491403] Subtest lease-unleased-connector: SUCCESS (0.031s)
[330.584161] [095/133] (691s left) gem_exec_reloc (basic-write-wc-active)
[330.643862] Subtest basic-write-wc-active: SKIP (0.000s)
[330.721559] [096/133] (691s left) gem_userptr_blits (access-control)
[330.790810] Starting subtest: access-control
[330.794359] Subtest access-control: SKIP (0.000s)
[330.935760] [097/133] (691s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-sf)
[331.033716] Starting subtest: fbc-psr2-cursor-plane-move-continuous-sf
[331.035682] Starting dynamic subtest: pipe-A-eDP-1
[331.120392] Dynamic subtest pipe-A-eDP-1: SKIP (0.089s)
[331.133674] Starting dynamic subtest: pipe-B-eDP-1
[332.250012] Dynamic subtest pipe-B-eDP-1: SKIP (1.126s)
[332.254350] Subtest fbc-psr2-cursor-plane-move-continuous-sf: SKIP (1.218s)
[333.510801] [098/133] (688s left) kms_flip (dpms-off-confusion)
[333.612520] Starting subtest: dpms-off-confusion
[333.614803] Starting dynamic subtest: A-eDP1
[342.314600] Dynamic subtest A-eDP1: SUCCESS (8.663s)
[342.327864] Starting dynamic subtest: B-eDP1
[351.024233] Dynamic subtest B-eDP1: SUCCESS (8.710s)
[351.038035] Starting dynamic subtest: C-eDP1
[359.712623] Dynamic subtest C-eDP1: SUCCESS (8.676s)
[359.724164] Starting dynamic subtest: D-eDP1
[368.407147] Dynamic subtest D-eDP1: SUCCESS (8.688s)
[368.409587] Subtest dpms-off-confusion: SUCCESS (34.749s)
[369.522864] [099/133] (653s left) kms_dp_link_training (uhbr-mst)
[369.621127] Starting subtest: uhbr-mst
[369.629610] Subtest uhbr-mst: SKIP (0.000s)
[369.696977] [100/133] (652s left) kms_big_fb (y-tiled-16bpp-rotate-270)
[369.801490] Starting subtest: y-tiled-16bpp-rotate-270
[369.805650] Subtest y-tiled-16bpp-rotate-270: SKIP (0.000s)
[369.903618] [101/133] (652s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[369.999999] Starting subtest: 4-tiled-32bpp-rotate-180
[371.457728] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.457s)
[371.580953] [102/133] (651s left) perf_pmu (multi-client)
[371.623963] Starting subtest: multi-client
[371.625541] Starting dynamic subtest: rcs0
[372.674908] Dynamic subtest rcs0: SUCCESS (1.051s)
[372.689078] Starting dynamic subtest: bcs0
[373.726341] Dynamic subtest bcs0: SUCCESS (1.048s)
[373.740588] Starting dynamic subtest: vcs0
[374.781005] Dynamic subtest vcs0: SUCCESS (1.050s)
[374.797522] Starting dynamic subtest: vcs1
[376.209118] Dynamic subtest vcs1: SUCCESS (1.425s)
[376.220936] Starting dynamic subtest: vecs0
[378.197543] Dynamic subtest vecs0: SUCCESS (1.985s)
[378.211291] Starting dynamic subtest: ccs0
[380.175932] Dynamic subtest ccs0: SUCCESS (1.975s)
[380.178873] Subtest multi-client: SUCCESS (8.552s)
[380.296690] [103/133] (642s left) kms_color_pipeline (plane-lut1d-pre-ctm3x4)
[380.434728] Starting subtest: plane-lut1d-pre-ctm3x4
[380.570201] Starting dynamic subtest: pipe-A-plane-0
[380.736488] Dynamic subtest pipe-A-plane-0: SUCCESS (0.166s)
[380.747673] Starting dynamic subtest: pipe-A-plane-1
[380.892198] Dynamic subtest pipe-A-plane-1: SUCCESS (0.155s)
[380.903625] Starting dynamic subtest: pipe-A-plane-2
[381.081833] Dynamic subtest pipe-A-plane-2: SUCCESS (0.188s)
[382.303677] Starting dynamic subtest: pipe-B-plane-0
[382.460758] Dynamic subtest pipe-B-plane-0: SUCCESS (0.166s)
[382.474250] Starting dynamic subtest: pipe-B-plane-1
[382.627895] Dynamic subtest pipe-B-plane-1: SUCCESS (0.166s)
[382.639092] Starting dynamic subtest: pipe-B-plane-2
[382.783299] Dynamic subtest pipe-B-plane-2: SUCCESS (0.155s)
[384.010866] Starting dynamic subtest: pipe-C-plane-0
[384.153942] Dynamic subtest pipe-C-plane-0: SUCCESS (0.155s)
[384.164965] Starting dynamic subtest: pipe-C-plane-1
[384.309699] Dynamic subtest pipe-C-plane-1: SUCCESS (0.155s)
[384.326202] Starting dynamic subtest: pipe-C-plane-2
[384.464910] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s)
[385.665805] Starting dynamic subtest: pipe-D-plane-0
[385.800932] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s)
[385.812421] Starting dynamic subtest: pipe-D-plane-1
[385.967136] Dynamic subtest pipe-D-plane-1: SUCCESS (0.166s)
[385.978404] Starting dynamic subtest: pipe-D-plane-2
[386.122639] Dynamic subtest pipe-D-plane-2: SUCCESS (0.155s)
[386.409451] Subtest plane-lut1d-pre-ctm3x4: SUCCESS (5.974s)
[387.210271] [104/133] (635s left) kms_invalid_mode (overflow-vrefresh)
[387.315290] Starting subtest: overflow-vrefresh
[387.317606] Starting dynamic subtest: pipe-A-eDP-1
[387.600234] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.286s)
[387.611537] Starting dynamic subtest: pipe-B-eDP-1
[387.613423] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[387.623658] Starting dynamic subtest: pipe-C-eDP-1
[387.625788] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[387.636353] Starting dynamic subtest: pipe-D-eDP-1
[387.638703] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[387.640950] Subtest overflow-vrefresh: SUCCESS (0.292s)
[388.407749] [105/133] (634s left) syncobj_wait (invalid-signal-illegal-handle)
[388.476332] Starting subtest: invalid-signal-illegal-handle
[388.478567] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[388.573892] [106/133] (634s left) syncobj_timeline (single-wait-all-signaled)
[388.623625] Starting subtest: single-wait-all-signaled
[388.625804] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[388.719333] [107/133] (634s left) kms_dsc (dsc-with-formats)
[388.812299] Starting subtest: dsc-with-formats
[388.852112] Subtest dsc-with-formats: SKIP (0.001s)
[388.892803] [108/133] (634s left) gem_lmem_swapping (random)
[388.938579] Subtest random: SKIP (0.000s)
[388.983517] [109/133] (633s left) kms_chamelium_frames (dp-crc-multiple)
[389.370237] Subtest dp-crc-multiple: SKIP (0.000s)
[390.181416] [110/133] (632s left) kms_plane_multiple (tiling-y)
[390.282320] Starting subtest: tiling-y
[390.286523] Subtest tiling-y: SKIP (0.000s)
[390.357475] [111/133] (632s left) gem_readwrite (new-obj)
[390.419387] Subtest new-obj: SKIP (0.000s)
[390.477135] [112/133] (632s left) kms_addfb_basic (small-bo)
[390.565211] Starting subtest: small-bo
[390.567123] Subtest small-bo: SUCCESS (0.000s)
[390.663266] [113/133] (632s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-pwrite)
[390.760622] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-pwrite
[390.764756] Subtest psr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[390.863111] [114/133] (632s left) gem_tiled_wb
[391.001442] [115/133] (632s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[391.100357] Starting subtest: fbcpsr-slowdraw
[416.207230] Subtest fbcpsr-slowdraw: SUCCESS (25.110s)
[416.335284] [116/133] (606s left) gem_exec_params (dr4-dirt)
[416.386058] Starting subtest: DR4-dirt
[416.388399] Subtest DR4-dirt: SUCCESS (0.000s)
[416.497838] [117/133] (606s left) gem_mmap_wc (read-write-distinct)
[416.572460] Subtest read-write-distinct: SKIP (0.000s)
[416.633840] [118/133] (606s left) i915_selftest (perf)
[420.036673] Starting subtest: perf
[420.040091] Starting dynamic subtest: engine_cs
[424.415286] Dynamic subtest engine_cs: SUCCESS (4.378s)
[424.426282] Starting dynamic subtest: request
[431.712566] Dynamic subtest request: SUCCESS (7.296s)
[431.726647] Starting dynamic subtest: migrate
[436.051929] Dynamic subtest migrate: SUCCESS (4.338s)
[436.062970] Starting dynamic subtest: region
[441.618269] Dynamic subtest region: SUCCESS (5.565s)
[441.620907] Subtest perf: SUCCESS (21.582s)
[441.650819] [FACT i915_selftest (perf)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[441.651567] [FACT i915_selftest (perf)] deleted: kernel.kmod_is_loaded.i915: true
[441.654737] [119/133] (581s left) perf (gen12-mi-rpc)
[443.265168] Starting subtest: gen12-mi-rpc
[443.267347] Starting dynamic subtest: rcs0
[443.289829] Dynamic subtest rcs0: SUCCESS (0.025s)
[443.292241] Subtest gen12-mi-rpc: SUCCESS (0.026s)
[443.336271] [FACT perf (gen12-mi-rpc)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[443.337636] [FACT perf (gen12-mi-rpc)] new: kernel.kmod_is_loaded.i915: true
[443.341104] [120/133] (580s left) gem_pxp (hw-rejects-pxp-buffer)
[443.503106] Starting subtest: hw-rejects-pxp-buffer
[443.507646] Subtest hw-rejects-pxp-buffer: SKIP (0.000s)
[443.620421] [121/133] (579s left) kms_psr (psr-suspend)
[443.709792] Starting subtest: psr-suspend
[443.711957] Starting dynamic subtest: eDP-1
[460.964622] Dynamic subtest eDP-1: SUCCESS (2.903s)
[460.967024] Subtest psr-suspend: SUCCESS (2.904s)
[461.858151] [122/133] (561s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite)
[461.959237] Starting subtest: fbc-rgb101010-draw-pwrite
[467.383914] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (5.370s)
[468.512231] [123/133] (555s left) kms_pm_rpm (system-suspend-modeset)
[470.655777] Starting subtest: system-suspend-modeset
[493.306897] Subtest system-suspend-modeset: SUCCESS (7.773s)
[493.692085] [124/133] (529s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[493.803675] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[493.807907] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[493.910828] [125/133] (529s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[494.013719] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[494.017918] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[494.138068] [126/133] (529s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[494.211813] Starting subtest: etime-multi-wait-all-for-submit-submitted
[494.317781] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.107s)
[494.397370] [127/133] (529s left) gem_ctx_persistence (smoketest)
[494.458579] Starting subtest: smoketest
[534.237652] Subtest smoketest: SUCCESS (39.723s)
[534.316765] [128/133] (489s left) kms_cursor_crc (cursor-tearing-framebuffer-change)
[534.442813] Starting subtest: cursor-tearing-framebuffer-change
[534.445175] Starting dynamic subtest: pipe-A-eDP-1
[535.008104] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.563s)
[535.019839] Starting dynamic subtest: pipe-D-eDP-1
[536.267432] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.259s)
[536.269807] Subtest cursor-tearing-framebuffer-change: SUCCESS (1.825s)
[537.093525] [129/133] (486s left) gem_mmap_offset (open-flood)
[537.142883] Starting subtest: open-flood
[558.634958] Subtest open-flood: SUCCESS (21.492s)
[558.758020] [130/133] (465s left) syncobj_timeline (invalid-transfer-bad-pad)
[558.828578] Starting subtest: invalid-transfer-bad-pad
[558.830539] Subtest invalid-transfer-bad-pad: SUCCESS (0.000s)
[558.939409] [131/133] (464s left) kms_flip (2x-plain-flip)
[559.036581] Starting subtest: 2x-plain-flip
[559.044989] Subtest 2x-plain-flip: SKIP (0.011s)
[559.152946] [132/133] (464s left) kms_addfb_basic (unused-modifier)
[559.216291] Starting subtest: unused-modifier
[559.218450] Subtest unused-modifier: SUCCESS (0.000s)
[559.348868] [133/133] (464s left) gem_userptr_blits (nohangcheck)
[559.422042] Starting subtest: nohangcheck
[559.668328] Subtest nohangcheck: SUCCESS (0.242s)
[559.792446] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_chamelium_audio
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_big_fb
results: parsing output: 4/ for test: kms_big_fb
results: parsing output: 5/ for test: syncobj_wait
results: parsing output: 6/ for test: kms_flip
results: parsing output: 7/ for test: kms_async_flips
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: api_intel_bb
results: parsing output: 10/ for test: gem_tiled_pread_pwrite
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: gem_create
results: parsing output: 13/ for test: kms_cursor_legacy
results: parsing output: 14/ for test: kms_force_connector_basic
results: parsing output: 15/ for test: kms_vblank
results: parsing output: 16/ for test: gem_lmem_swapping
results: parsing output: 17/ for test: api_intel_allocator
results: parsing output: 18/ for test: kms_cursor_legacy
results: parsing output: 19/ for test: gem_exec_suspend
results: parsing output: 20/ for test: gem_exec_parallel
results: parsing output: 21/ for test: kms_flip
results: parsing output: 22/ for test: gem_exec_schedule
results: parsing output: 23/ for test: gem_busy
results: parsing output: 24/ for test: gem_eio
results: parsing output: 25/ for test: kms_flip_scaled_crc
results: parsing output: 26/ for test: i915_hangman
results: parsing output: 27/ for test: kms_ccs
results: parsing output: 28/ for test: kms_plane_scaling
results: parsing output: 29/ for test: gem_ccs
results: parsing output: 30/ for test: sysfs_heartbeat_interval
results: parsing output: 31/ for test: gem_render_copy
results: parsing output: 32/ for test: syncobj_wait
results: parsing output: 33/ for test: gen9_exec_parse
results: parsing output: 34/ for test: gem_exec_schedule
results: parsing output: 35/ for test: gem_exec_flush
results: parsing output: 36/ for test: kms_plane
results: parsing output: 37/ for test: kms_flip_scaled_crc
results: parsing output: 38/ for test: gem_exec_big
results: parsing output: 39/ for test: gem_pread
results: parsing output: 40/ for test: kms_sequence
results: parsing output: 41/ for test: kms_psr
results: parsing output: 42/ for test: kms_big_fb
results: parsing output: 43/ for test: gem_softpin
results: parsing output: 44/ for test: kms_properties
results: parsing output: 45/ for test: kms_cursor_crc
results: parsing output: 46/ for test: kms_flip
results: parsing output: 47/ for test: prime_mmap_kms
results: parsing output: 48/ for test: syncobj_timeline
results: parsing output: 49/ for test: kms_content_protection
results: parsing output: 50/ for test: kms_psr2_sf
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_psr
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: gem_exec_fence
results: parsing output: 55/ for test: gem_ctx_sseu
results: parsing output: 56/ for test: kms_psr
results: parsing output: 57/ for test: gem_eio
results: parsing output: 58/ for test: kms_async_flips
results: parsing output: 59/ for test: gem_exec_balancer
results: parsing output: 60/ for test: kms_plane_scaling
results: parsing output: 61/ for test: gem_exec_params
results: parsing output: 62/ for test: kms_plane_alpha_blend
results: parsing output: 63/ for test: syncobj_wait
results: parsing output: 64/ for test: kms_pm_backlight
results: parsing output: 65/ for test: kms_psr
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: gem_exec_flush
results: parsing output: 68/ for test: syncobj_basic
results: parsing output: 69/ for test: kms_chamelium_hpd
results: parsing output: 70/ for test: kms_big_fb
results: parsing output: 71/ for test: i915_drm_fdinfo
results: parsing output: 72/ for test: gem_userptr_blits
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: gem_exec_reloc
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: syncobj_timeline
results: parsing output: 77/ for test: kms_ccs
results: parsing output: 78/ for test: kms_getfb
results: parsing output: 79/ for test: kms_psr
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: i915_pm_rpm
results: parsing output: 82/ for test: kms_big_fb
results: parsing output: 83/ for test: kms_rotation_crc
results: parsing output: 84/ for test: gem_ctx_param
results: parsing output: 85/ for test: kms_ccs
results: parsing output: 86/ for test: kms_ccs
results: parsing output: 87/ for test: gem_exec_whisper
results: parsing output: 88/ for test: core_setmaster
results: parsing output: 89/ for test: sw_sync
results: parsing output: 90/ for test: prime_busy
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_cursor_crc
results: parsing output: 93/ for test: kms_lease
results: parsing output: 94/ for test: gem_exec_reloc
results: parsing output: 95/ for test: gem_userptr_blits
results: parsing output: 96/ for test: kms_psr2_sf
results: parsing output: 97/ for test: kms_flip
results: parsing output: 98/ for test: kms_dp_link_training
results: parsing output: 99/ for test: kms_big_fb
results: parsing output: 100/ for test: kms_big_fb
results: parsing output: 101/ for test: perf_pmu
results: parsing output: 102/ for test: kms_color_pipeline
results: parsing output: 103/ for test: kms_invalid_mode
results: parsing output: 104/ for test: syncobj_wait
results: parsing output: 105/ for test: syncobj_timeline
results: parsing output: 106/ for test: kms_dsc
results: parsing output: 107/ for test: gem_lmem_swapping
results: parsing output: 108/ for test: kms_chamelium_frames
results: parsing output: 109/ for test: kms_plane_multiple
results: parsing output: 110/ for test: gem_readwrite
results: parsing output: 111/ for test: kms_addfb_basic
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: gem_tiled_wb
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: gem_exec_params
results: parsing output: 116/ for test: gem_mmap_wc
results: parsing output: 117/ for test: i915_selftest
results: parsing output: 118/ for test: perf
results: parsing output: 119/ for test: gem_pxp
results: parsing output: 120/ for test: kms_psr
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_pm_rpm
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: syncobj_timeline
results: parsing output: 126/ for test: gem_ctx_persistence
results: parsing output: 127/ for test: kms_cursor_crc
results: parsing output: 128/ for test: gem_mmap_offset
results: parsing output: 129/ for test: syncobj_timeline
results: parsing output: 130/ for test: kms_flip
results: parsing output: 131/ for test: kms_addfb_basic
results: parsing output: 132/ for test: gem_userptr_blits
Done.