[56.767393] Initializing watchdogs
[56.767442]   /dev/watchdog0
[56.790291] [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]
[56.794540] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.796957] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.800830] [001/133] (960s left) gem_exec_gttfill (engines)
[56.889897] Starting subtest: engines
[56.892330] Starting dynamic subtest: rcs0
[80.315775] Dynamic subtest rcs0: SUCCESS (23.426s)
[80.323601] Starting dynamic subtest: bcs0
[103.588825] Dynamic subtest bcs0: SUCCESS (23.271s)
[103.597244] Starting dynamic subtest: vcs0
[126.946437] Dynamic subtest vcs0: SUCCESS (23.355s)
[126.954027] Starting dynamic subtest: vcs1
[150.254440] Dynamic subtest vcs1: SUCCESS (23.307s)
[150.261828] Starting dynamic subtest: vecs0
[173.503521] Dynamic subtest vecs0: SUCCESS (23.246s)
[173.512998] Starting dynamic subtest: ccs0
[196.794472] Dynamic subtest ccs0: SUCCESS (23.287s)
[196.797654] Subtest engines: SUCCESS (139.899s)
[196.981912] [002/133] (820s left) perf (gen12-unprivileged-single-ctx-counters)
[197.166977] Starting subtest: gen12-unprivileged-single-ctx-counters
[197.169317] Starting dynamic subtest: rcs0
[197.675053] Dynamic subtest rcs0: SUCCESS (0.510s)
[197.677496] Subtest gen12-unprivileged-single-ctx-counters: SUCCESS (0.511s)
[197.724388] [003/133] (819s left) kms_joiner (basic-ultra-joiner)
[197.862691] Starting subtest: basic-ultra-joiner
[197.866840] Subtest basic-ultra-joiner: SKIP (0.000s)
[197.923820] [004/133] (819s left) prime_mmap (test_aperture_limit)
[197.986968] Starting subtest: test_aperture_limit
[197.988629] Starting dynamic subtest: test_aperture_limit-smem
[197.997067] Dynamic subtest test_aperture_limit-smem: SKIP (0.009s)
[198.000551] Subtest test_aperture_limit: SKIP (0.009s)
[198.094883] [005/133] (819s left) kms_plane (plane-position-hole)
[198.190770] Starting subtest: plane-position-hole
[198.754467] Starting dynamic subtest: pipe-A-plane-1
[200.013978] Dynamic subtest pipe-A-plane-1: SUCCESS (1.259s)
[200.023423] Starting dynamic subtest: pipe-A-plane-2
[201.250619] Dynamic subtest pipe-A-plane-2: SUCCESS (1.235s)
[201.258864] Starting dynamic subtest: pipe-A-plane-3
[202.477542] Dynamic subtest pipe-A-plane-3: SUCCESS (1.227s)
[202.487307] Starting dynamic subtest: pipe-A-plane-4
[203.690708] Dynamic subtest pipe-A-plane-4: SUCCESS (1.212s)
[203.702212] Starting dynamic subtest: pipe-A-plane-5
[203.717638] Dynamic subtest pipe-A-plane-5: SUCCESS (0.026s)
[204.854360] Starting dynamic subtest: pipe-B-plane-1
[206.117379] Dynamic subtest pipe-B-plane-1: SUCCESS (1.268s)
[206.126161] Starting dynamic subtest: pipe-B-plane-2
[207.405558] Dynamic subtest pipe-B-plane-2: SUCCESS (1.288s)
[207.415604] Starting dynamic subtest: pipe-B-plane-3
[208.647847] Dynamic subtest pipe-B-plane-3: SUCCESS (1.243s)
[208.657186] Starting dynamic subtest: pipe-B-plane-4
[209.867616] Dynamic subtest pipe-B-plane-4: SUCCESS (1.218s)
[209.877086] Starting dynamic subtest: pipe-B-plane-5
[209.896885] Dynamic subtest pipe-B-plane-5: SUCCESS (0.027s)
[209.899305] Subtest plane-position-hole: SUCCESS (11.702s)
[210.691243] [006/133] (806s left) kms_async_flips (alternate-sync-async-flip-atomic)
[210.793332] Starting subtest: alternate-sync-async-flip-atomic
[211.166464] Starting dynamic subtest: pipe-A-eDP-1
[213.977971] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.813s)
[214.358649] Starting dynamic subtest: pipe-B-eDP-1
[217.112951] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.762s)
[217.490755] Starting dynamic subtest: pipe-C-eDP-1
[220.249292] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.766s)
[220.617061] Starting dynamic subtest: pipe-D-eDP-1
[223.377471] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.770s)
[223.379951] Subtest alternate-sync-async-flip-atomic: SUCCESS (12.586s)
[224.554857] [007/133] (792s left) kms_cursor_crc (cursor-random-64x21)
[224.770819] Starting subtest: cursor-random-64x21
[224.823474] Subtest cursor-random-64x21: SKIP (0.047s)
[225.038287] [008/133] (792s left) gem_exec_flush (basic-batch-kernel-default-cmd)
[225.122392] Starting subtest: basic-batch-kernel-default-cmd
[225.127081] Subtest basic-batch-kernel-default-cmd: SKIP (0.000s)
[225.303024] [009/133] (792s left) gem_userptr_blits (map-fixed-invalidate)
[225.381274] Starting subtest: map-fixed-invalidate
[225.386123] Subtest map-fixed-invalidate: SKIP (0.000s)
[225.503064] [010/133] (792s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled)
[225.571556] Starting subtest: multi-wait-for-submit-submitted-signaled
[225.574061] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.002s)
[225.673844] [011/133] (792s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[225.789583] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[226.614010] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (0.825s)
[226.783440] [012/133] (790s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions-varying-size)
[226.921668] Starting subtest: cursor-vs-flip-atomic-transitions-varying-size
[258.140606] Subtest cursor-vs-flip-atomic-transitions-varying-size: SUCCESS (31.219s)
[258.976322] [013/133] (758s left) kms_psr (psr2-basic)
[259.069842] Starting subtest: psr2-basic
[259.072122] Starting dynamic subtest: eDP-1
[260.648080] Dynamic subtest eDP-1: SUCCESS (1.577s)
[260.649937] Subtest psr2-basic: SUCCESS (1.578s)
[261.448237] [014/133] (756s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[261.554835] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[261.559222] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[261.649718] [015/133] (756s left) kms_big_fb (linear-16bpp-rotate-180)
[261.743587] Starting subtest: linear-16bpp-rotate-180
[263.167221] Subtest linear-16bpp-rotate-180: SUCCESS (1.423s)
[263.291179] [016/133] (754s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[263.384278] Starting subtest: bad-pixel-format-y-tiled-ccs
[263.386211] Starting dynamic subtest: pipe-A-eDP-1
[263.405739] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[263.416796] Starting dynamic subtest: pipe-B-eDP-1
[263.434686] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[263.441950] Starting dynamic subtest: pipe-C-eDP-1
[263.459608] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[263.468365] Starting dynamic subtest: pipe-D-eDP-1
[263.486785] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[263.490328] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.004s)
[265.533035] [017/133] (752s left) kms_plane (pixel-format-4-tiled-lnl-ccs-modifier-source-clamping)
[265.623827] Starting subtest: pixel-format-4-tiled-lnl-ccs-modifier-source-clamping
[265.627718] Subtest pixel-format-4-tiled-lnl-ccs-modifier-source-clamping: SKIP (0.000s)
[265.704820] [018/133] (752s left) i915_drm_fdinfo (most-busy-idle-check-all)
[265.745544] Starting subtest: most-busy-idle-check-all
[265.747424] Starting dynamic subtest: rcs0
[265.751811] Dynamic subtest rcs0: SKIP (0.004s)
[265.761760] Starting dynamic subtest: bcs0
[265.765932] Dynamic subtest bcs0: SKIP (0.000s)
[265.773344] Starting dynamic subtest: vcs0
[265.777345] Dynamic subtest vcs0: SKIP (0.001s)
[265.784822] Starting dynamic subtest: vcs1
[265.789065] Dynamic subtest vcs1: SKIP (0.000s)
[265.796267] Starting dynamic subtest: vecs0
[265.800503] Dynamic subtest vecs0: SKIP (0.001s)
[265.807933] Starting dynamic subtest: ccs0
[265.812212] Dynamic subtest ccs0: SKIP (0.001s)
[265.816586] Subtest most-busy-idle-check-all: SKIP (0.012s)
[265.850903] [019/133] (752s left) perf_pmu (rc6-suspend)
[265.895197] Starting subtest: rc6-suspend
[288.607230] Subtest rc6-suspend: SUCCESS (8.388s)
[289.631275] [020/133] (728s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[289.756905] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[289.761264] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[289.851960] [021/133] (728s left) kms_psr (fbc-psr2-sprite-plane-onoff)
[289.944365] Starting subtest: fbc-psr2-sprite-plane-onoff
[289.946655] Starting dynamic subtest: eDP-1
[289.951194] Dynamic subtest eDP-1: SKIP (0.000s)
[289.955599] Subtest fbc-psr2-sprite-plane-onoff: SKIP (0.001s)
[290.029462] [022/133] (728s left) kms_big_fb (4-tiled-16bpp-rotate-0)
[290.129969] Starting subtest: 4-tiled-16bpp-rotate-0
[291.409723] Subtest 4-tiled-16bpp-rotate-0: SUCCESS (1.280s)
[291.518994] [023/133] (726s left) gem_workarounds (reset-fd)
[291.577304] Starting subtest: reset-fd
[291.766561] Subtest reset-fd: SUCCESS (0.189s)
[291.902244] [024/133] (726s left) kms_fence_pin_leak
[292.060606] [025/133] (726s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[292.130333] Starting subtest: y-tiled-to-vebox-y-tiled
[292.134255] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[292.310425] [026/133] (725s left) kms_cdclk (mode-transition)
[292.400198] Starting subtest: mode-transition
[292.402511] Starting dynamic subtest: pipe-A-eDP-1
[292.698398] Dynamic subtest pipe-A-eDP-1: SKIP (0.293s)
[292.708269] Starting dynamic subtest: pipe-B-eDP-1
[292.712745] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[292.721749] Starting dynamic subtest: pipe-C-eDP-1
[292.726663] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[292.735952] Starting dynamic subtest: pipe-D-eDP-1
[292.740630] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[292.745678] Subtest mode-transition: SKIP (0.300s)
[293.534885] [027/133] (724s left) gem_exec_params (dr4-dirt)
[293.584481] Starting subtest: DR4-dirt
[293.587314] Subtest DR4-dirt: SUCCESS (0.000s)
[293.678229] [028/133] (724s left) syncobj_wait (multi-wait-all-submitted)
[293.742574] Starting subtest: multi-wait-all-submitted
[293.847425] Subtest multi-wait-all-submitted: SUCCESS (0.103s)
[293.937622] [029/133] (724s left) gem_exec_store (basic)
[293.998194] Starting subtest: basic
[294.094374] Subtest basic: SUCCESS (0.097s)
[294.279182] [030/133] (724s left) gem_ctx_persistence (saturated-hostile)
[294.341403] Starting subtest: saturated-hostile
[294.344010] Starting dynamic subtest: rcs0
[295.159685] Dynamic subtest rcs0: SUCCESS (0.818s)
[295.170353] Starting dynamic subtest: bcs0
[295.963363] Dynamic subtest bcs0: SUCCESS (0.803s)
[295.976181] Starting dynamic subtest: vcs0
[296.760346] Dynamic subtest vcs0: SUCCESS (0.795s)
[296.772897] Starting dynamic subtest: vcs1
[297.555395] Dynamic subtest vcs1: SUCCESS (0.794s)
[297.567280] Starting dynamic subtest: vecs0
[298.326429] Dynamic subtest vecs0: SUCCESS (0.770s)
[298.339225] Starting dynamic subtest: ccs0
[299.167420] Dynamic subtest ccs0: SUCCESS (0.839s)
[299.170566] Subtest saturated-hostile: SUCCESS (4.828s)
[299.249295] [031/133] (719s left) kms_psr (psr-cursor-plane-onoff)
[299.344661] Starting subtest: psr-cursor-plane-onoff
[299.347193] Starting dynamic subtest: eDP-1
[301.029773] Dynamic subtest eDP-1: SUCCESS (1.683s)
[301.032184] Subtest psr-cursor-plane-onoff: SUCCESS (1.684s)
[301.888682] [032/133] (716s left) i915_hangman (detector)
[301.958443] Starting subtest: detector
[301.960733] Starting dynamic subtest: rcs0
[313.192187] Dynamic subtest rcs0: SUCCESS (11.235s)
[313.205116] Starting dynamic subtest: bcs0
[325.223747] Dynamic subtest bcs0: SUCCESS (12.029s)
[325.235963] Starting dynamic subtest: vcs0
[337.127824] Dynamic subtest vcs0: SUCCESS (11.901s)
[337.141245] Starting dynamic subtest: vcs1
[348.136751] Dynamic subtest vcs1: SUCCESS (11.007s)
[348.148492] Starting dynamic subtest: vecs0
[359.144804] Dynamic subtest vecs0: SUCCESS (11.005s)
[359.157289] Starting dynamic subtest: ccs0
[370.215919] Dynamic subtest ccs0: SUCCESS (11.068s)
[370.219151] Subtest detector: SUCCESS (68.260s)
[370.295919] [033/133] (648s left) kms_force_connector_basic (force-connector-state)
[370.377729] Starting subtest: force-connector-state
[370.995480] Subtest force-connector-state: SUCCESS (0.620s)
[371.929789] [034/133] (646s left) kms_universal_plane (universal-plane-sanity)
[372.021802] Starting subtest: universal-plane-sanity
[372.024454] Starting dynamic subtest: pipe-A-eDP-1
[372.633808] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.609s)
[372.645760] Starting dynamic subtest: pipe-B-eDP-1
[373.827501] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.195s)
[373.838812] Starting dynamic subtest: pipe-C-eDP-1
[374.991562] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.163s)
[375.005643] Starting dynamic subtest: pipe-D-eDP-1
[376.183738] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.191s)
[376.186746] Subtest universal-plane-sanity: SUCCESS (4.163s)
[377.005681] [035/133] (641s left) dmabuf_sync_file (import-multiple-read-write)
[377.131790] Starting subtest: import-multiple-read-write
[377.134748] Subtest import-multiple-read-write: SUCCESS (0.006s)
[377.185532] [FACT dmabuf_sync_file (import-multiple-read-write)] new: kernel.kmod_is_loaded.vgem: true
[377.189222] [036/133] (641s left) kms_pm_dc (dc5-dpms-negative)
[378.301799] Starting subtest: dc5-dpms-negative
[378.307367] Subtest dc5-dpms-negative: SKIP (0.000s)
[378.379731] [037/133] (640s left) gem_render_copy_redux (flink)
[378.435145] Starting subtest: flink
[379.296988] Subtest flink: SUCCESS (0.862s)
[379.421342] [038/133] (639s left) kms_cursor_crc (cursor-onscreen-64x64)
[379.609592] Starting subtest: cursor-onscreen-64x64
[379.614605] Starting dynamic subtest: pipe-A-eDP-1
[381.775397] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.161s)
[381.806748] Starting dynamic subtest: pipe-D-eDP-1
[384.681731] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.883s)
[384.684677] Subtest cursor-onscreen-64x64: SUCCESS (5.071s)
[385.521930] [039/133] (633s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-sf)
[385.623455] Starting subtest: fbc-pr-cursor-plane-move-continuous-sf
[385.628766] Subtest fbc-pr-cursor-plane-move-continuous-sf: SKIP (0.000s)
[385.706103] [040/133] (633s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[385.806477] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[394.508366] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.702s)
[394.610942] [041/133] (624s left) gem_mmap_wc (bad-offset)
[394.676574] Subtest bad-offset: SKIP (0.000s)
[394.738174] [042/133] (624s left) gem_softpin (evict-single-offset)
[394.796184] Starting subtest: evict-single-offset
[420.234138] Subtest evict-single-offset: SUCCESS (25.432s)
[420.330508] [043/133] (598s left) kms_content_protection (dp-mst-type-1-suspend-resume)
[420.539189] Starting subtest: dp-mst-type-1-suspend-resume
[420.544654] Subtest dp-mst-type-1-suspend-resume: SKIP (0.000s)
[420.658646] [044/133] (598s left) gem_ctx_shared (q-out-order)
[420.731739] Starting subtest: Q-out-order
[420.734118] Starting dynamic subtest: rcs0
[420.846521] Dynamic subtest rcs0: SUCCESS (0.116s)
[420.860080] Starting dynamic subtest: vcs0
[420.936726] Dynamic subtest vcs0: SUCCESS (0.090s)
[420.954170] Starting dynamic subtest: vcs1
[421.020508] Dynamic subtest vcs1: SUCCESS (0.083s)
[421.036941] Starting dynamic subtest: vecs0
[421.100271] Dynamic subtest vecs0: SUCCESS (0.079s)
[421.117360] Starting dynamic subtest: ccs0
[421.195298] Dynamic subtest ccs0: SUCCESS (0.094s)
[421.198244] Subtest Q-out-order: SUCCESS (0.466s)
[422.302211] [045/133] (596s left) gem_softpin (softpin)
[422.366537] Starting subtest: softpin
[454.836919] Subtest softpin: SUCCESS (32.463s)
[454.948364] [046/133] (564s left) kms_plane_alpha_blend (coverage-7efc)
[455.053777] Starting subtest: coverage-7efc
[455.341351] Starting dynamic subtest: pipe-A-eDP-1
[455.905156] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.563s)
[455.932351] Starting dynamic subtest: pipe-D-eDP-1
[457.202020] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.274s)
[457.204584] Subtest coverage-7efc: SUCCESS (2.148s)
[458.014804] [047/133] (561s left) kms_busy (extended-pageflip-hang-oldfb)
[458.106473] Starting subtest: extended-pageflip-hang-oldfb
[458.109007] Starting dynamic subtest: pipe-A
[458.540530] Dynamic subtest pipe-A: SUCCESS (0.430s)
[458.551265] Starting dynamic subtest: pipe-D
[459.708236] Dynamic subtest pipe-D: SUCCESS (1.165s)
[459.710663] Subtest extended-pageflip-hang-oldfb: SUCCESS (1.599s)
[460.505011] [048/133] (558s left) kms_big_fb (y-tiled-addfb)
[460.614633] Starting subtest: y-tiled-addfb
[460.620519] Subtest y-tiled-addfb: SKIP (0.000s)
[460.712558] [049/133] (558s left) kms_plane_multiple (tiling-none)
[460.814568] Starting subtest: tiling-none
[460.817327] Starting dynamic subtest: pipe-A-eDP-1
[464.650743] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.837s)
[464.661535] Starting dynamic subtest: pipe-B-eDP-1
[469.272554] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.618s)
[469.283204] Starting dynamic subtest: pipe-C-eDP-1
[473.824637] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.552s)
[473.835669] Starting dynamic subtest: pipe-D-eDP-1
[478.349477] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.522s)
[478.352301] Subtest tiling-none: SUCCESS (17.535s)
[479.213284] [050/133] (540s left) kms_chamelium_frames (dp-crc-fast)
[479.595995] Subtest dp-crc-fast: SKIP (0.000s)
[480.395720] [051/133] (538s left) i915_suspend (fence-restore-tiled2untiled)
[480.447668] Starting subtest: fence-restore-tiled2untiled
[480.453418] Subtest fence-restore-tiled2untiled: SKIP (0.000s)
[480.521324] [052/133] (538s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[480.616888] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu
[487.024678] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (6.411s)
[487.115611] [053/133] (532s left) kms_chamelium_hpd (hdmi-hpd-fast)
[487.508007] Subtest hdmi-hpd-fast: SKIP (0.000s)
[488.287729] [054/133] (531s left) kms_psr (fbc-psr2-primary-mmap-cpu)
[488.386772] Starting subtest: fbc-psr2-primary-mmap-cpu
[488.389725] Starting dynamic subtest: eDP-1
[488.395620] Dynamic subtest eDP-1: SKIP (0.000s)
[488.401729] Subtest fbc-psr2-primary-mmap-cpu: SKIP (0.001s)
[488.459828] [055/133] (531s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-fully-sf)
[488.556431] Starting subtest: psr2-overlay-plane-move-continuous-exceed-fully-sf
[488.558940] Starting dynamic subtest: pipe-A-eDP-1
[505.045632] Dynamic subtest pipe-A-eDP-1: SUCCESS (16.492s)
[505.058307] Starting dynamic subtest: pipe-B-eDP-1
[522.280055] Dynamic subtest pipe-B-eDP-1: SUCCESS (17.234s)
[522.282790] Subtest psr2-overlay-plane-move-continuous-exceed-fully-sf: SUCCESS (33.729s)
[523.118389] [056/133] (496s left) api_intel_bb (object-reloc-keep-cache)
[523.170243] Starting subtest: object-reloc-keep-cache
[523.177252] Subtest object-reloc-keep-cache: SKIP (0.005s)
[523.302614] [057/133] (496s left) kms_flip (absolute-wf_vblank)
[523.392976] Starting subtest: absolute-wf_vblank
[523.395601] Starting dynamic subtest: A-eDP1
[532.129327] Dynamic subtest A-eDP1: SUCCESS (8.735s)
[532.150655] Starting dynamic subtest: B-eDP1
[540.832739] Dynamic subtest B-eDP1: SUCCESS (8.700s)
[540.843045] Starting dynamic subtest: C-eDP1
[549.526530] Dynamic subtest C-eDP1: SUCCESS (8.691s)
[549.538159] Starting dynamic subtest: D-eDP1
[558.232613] Dynamic subtest D-eDP1: SUCCESS (8.705s)
[558.238503] Subtest absolute-wf_vblank: SUCCESS (34.843s)
[559.411803] [058/133] (460s left) gem_exec_schedule (preempt-other-chain)
[559.497201] Starting subtest: preempt-other-chain
[559.499503] Starting dynamic subtest: rcs0
[559.571361] Dynamic subtest rcs0: SUCCESS (0.082s)
[559.581514] Starting dynamic subtest: bcs0
[559.654575] Dynamic subtest bcs0: SUCCESS (0.083s)
[559.666786] Starting dynamic subtest: vcs0
[559.740982] Dynamic subtest vcs0: SUCCESS (0.086s)
[559.751634] Starting dynamic subtest: vcs1
[559.822982] Dynamic subtest vcs1: SUCCESS (0.080s)
[559.833281] Starting dynamic subtest: vecs0
[559.916668] Dynamic subtest vecs0: SUCCESS (0.092s)
[559.933687] Starting dynamic subtest: ccs0
[559.997274] Dynamic subtest ccs0: SUCCESS (0.079s)
[560.000407] Subtest preempt-other-chain: SUCCESS (0.508s)
[561.300985] [059/133] (458s left) gem_exec_schedule (u-fairslice)
[561.379463] Starting subtest: u-fairslice
[561.381719] Starting dynamic subtest: rcs0
[563.393568] Dynamic subtest rcs0: SUCCESS (2.022s)
[563.405295] Starting dynamic subtest: bcs0
[565.421367] Dynamic subtest bcs0: SUCCESS (2.026s)
[565.431997] Starting dynamic subtest: vcs0
[567.441512] Dynamic subtest vcs0: SUCCESS (2.019s)
[567.451985] Starting dynamic subtest: vcs1
[569.464293] Dynamic subtest vcs1: SUCCESS (2.022s)
[569.475105] Starting dynamic subtest: vecs0
[571.487288] Dynamic subtest vecs0: SUCCESS (2.022s)
[571.497333] Starting dynamic subtest: ccs0
[573.511573] Dynamic subtest ccs0: SUCCESS (2.024s)
[573.514006] Subtest u-fairslice: SUCCESS (12.141s)
[573.811256] [060/133] (445s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[573.917561] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-gtt
[573.922583] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[574.016150] [061/133] (445s left) kms_cursor_crc (cursor-sliding-256x85)
[574.301400] Starting subtest: cursor-sliding-256x85
[574.348462] Subtest cursor-sliding-256x85: SKIP (0.042s)
[574.537584] [062/133] (445s left) gem_shrink (reclaim)
[574.595366] Starting subtest: reclaim
[577.059384] Subtest reclaim: SUCCESS (2.464s)
[577.210605] [063/133] (442s left) syncobj_timeline (wait-for-submit-complex)
[577.264974] Starting subtest: wait-for-submit-complex
[577.325103] Subtest wait-for-submit-complex: SUCCESS (0.062s)
[577.406564] [064/133] (442s left) kms_dsc (dsc-with-formats)
[577.502679] Starting subtest: dsc-with-formats
[577.548560] Subtest dsc-with-formats: SKIP (0.001s)
[577.591064] [065/133] (442s left) kms_cursor_legacy (flip-vs-cursor-atomic)
[577.712326] Starting subtest: flip-vs-cursor-atomic
[584.759249] Subtest flip-vs-cursor-atomic: SUCCESS (7.047s)
[585.615505] [066/133] (434s left) kms_vblank (query-forked-busy)
[585.703525] Starting subtest: query-forked-busy
[585.705675] Starting dynamic subtest: pipe-A-eDP-1
[587.577620] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.874s)
[587.593362] Starting dynamic subtest: pipe-D-eDP-1
[590.124044] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.543s)
[590.126777] Subtest query-forked-busy: SUCCESS (4.420s)
[591.000712] [067/133] (428s left) kms_lease (lease-unleased-connector)
[591.101515] Starting subtest: lease-unleased-connector
[591.103677] Starting dynamic subtest: pipe-A-eDP-1
[591.109612] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.006s)
[591.120087] Starting dynamic subtest: pipe-B-eDP-1
[591.122481] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.011s)
[591.132806] Starting dynamic subtest: pipe-C-eDP-1
[591.135381] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.011s)
[591.149046] Starting dynamic subtest: pipe-D-eDP-1
[591.151519] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.012s)
[591.154049] Subtest lease-unleased-connector: SUCCESS (0.053s)
[591.260606] [068/133] (428s left) kms_addfb_basic (bad-pitch-63)
[591.304277] Starting subtest: bad-pitch-63
[591.306502] Subtest bad-pitch-63: SUCCESS (0.000s)
[591.443074] [069/133] (428s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[591.536911] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[591.541946] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[591.624387] [070/133] (428s left) gem_exec_params (dr1-dirt)
[591.679539] Starting subtest: DR1-dirt
[591.682053] Subtest DR1-dirt: SUCCESS (0.000s)
[591.764146] [071/133] (428s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[591.857340] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[591.862153] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[591.937156] [072/133] (428s left) kms_rmfb (rmfb-ioctl)
[592.025096] Starting subtest: rmfb-ioctl
[592.027014] Starting dynamic subtest: pipe-A-eDP-1
[592.130424] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.106s)
[592.143192] Starting dynamic subtest: pipe-B-eDP-1
[593.331958] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.198s)
[593.341678] Starting dynamic subtest: pipe-C-eDP-1
[594.516459] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.184s)
[594.526942] Starting dynamic subtest: pipe-D-eDP-1
[595.716470] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.199s)
[595.719165] Subtest rmfb-ioctl: SUCCESS (3.693s)
[596.901135] [073/133] (423s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-wc)
[596.998130] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-wc
[603.573285] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.577s)
[603.702464] [074/133] (416s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[603.792890] Starting subtest: fbc-rgb565-draw-blt
[607.949267] Subtest fbc-rgb565-draw-blt: SUCCESS (4.159s)
[609.116062] [075/133] (411s left) kms_ccs (crc-primary-basic-yf-tiled-ccs)
[609.212413] Starting subtest: crc-primary-basic-yf-tiled-ccs
[609.214971] Starting dynamic subtest: pipe-A-eDP-1
[609.241598] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[609.252596] Starting dynamic subtest: pipe-B-eDP-1
[609.279151] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[609.288606] Starting dynamic subtest: pipe-C-eDP-1
[609.314601] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[609.323779] Starting dynamic subtest: pipe-D-eDP-1
[609.349380] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[609.354377] Subtest crc-primary-basic-yf-tiled-ccs: SKIP (0.006s)
[611.503248] [076/133] (408s left) i915_pm_rpm (sysfs-read)
[613.354147] Starting subtest: sysfs-read
[614.522725] Subtest sysfs-read: SUCCESS (1.169s)
[614.908903] [077/133] (405s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[615.009938] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[615.012140] Starting dynamic subtest: pipe-A-default-mode
[617.707376] Dynamic subtest pipe-A-default-mode: SUCCESS (2.697s)
[617.710054] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SUCCESS (2.699s)
[618.498269] [078/133] (401s left) gem_unfence_active_buffers
[618.654311] [079/133] (401s left) gem_exec_suspend (basic)
[618.716740] Starting subtest: basic
[618.719450] Starting dynamic subtest: smem
[620.234323] Dynamic subtest smem: SUCCESS (1.516s)
[620.237158] Subtest basic: SUCCESS (1.518s)
[620.419746] [080/133] (400s left) gem_exec_whisper (basic-sync)
[620.483511] Starting subtest: basic-sync
[632.732244] Subtest basic-sync: SUCCESS (12.246s)
[632.921862] [081/133] (387s left) gem_exec_params (invalid-bsd-ring)
[632.977233] Starting subtest: invalid-bsd-ring
[632.979741] Subtest invalid-bsd-ring: SUCCESS (0.000s)
[633.073483] [082/133] (387s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[633.180302] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[633.185237] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.000s)
[633.253647] [083/133] (387s left) gem_fenced_exec_thrash (no-spare-fences-busy-interruptible)
[633.315658] Subtest no-spare-fences-busy-interruptible: SKIP (0.000s)
[633.380515] [084/133] (387s left) gem_exec_fence (syncobj-signal)
[633.846809] Starting subtest: syncobj-signal
[633.849666] Subtest syncobj-signal: SUCCESS (0.006s)
[635.300904] [085/133] (385s left) i915_query (query-topology-coherent-slice-mask)
[635.360940] Starting subtest: query-topology-coherent-slice-mask
[635.366028] Subtest query-topology-coherent-slice-mask: SKIP (0.000s)
[635.456844] [086/133] (385s left) syncobj_eventfd (invalid-illegal-handle)
[635.506563] Starting subtest: invalid-illegal-handle
[635.509564] Subtest invalid-illegal-handle: SUCCESS (0.000s)
[635.621904] [087/133] (385s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[635.731909] Starting subtest: psr2-cursor-plane-update-sf
[635.734153] Starting dynamic subtest: pipe-A-eDP-1
[636.291402] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.562s)
[636.302354] Starting dynamic subtest: pipe-B-eDP-1
[637.554767] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.262s)
[637.557745] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.827s)
[638.398698] [088/133] (382s left) kms_flip (flip-vs-expired-vblank)
[638.488626] Starting subtest: flip-vs-expired-vblank
[638.491433] Starting dynamic subtest: A-eDP1
[647.171295] Dynamic subtest A-eDP1: SUCCESS (8.683s)
[647.181137] Starting dynamic subtest: B-eDP1
[655.860302] Dynamic subtest B-eDP1: SUCCESS (8.687s)
[655.871910] Starting dynamic subtest: C-eDP1
[664.552470] Dynamic subtest C-eDP1: SUCCESS (8.689s)
[664.563285] Starting dynamic subtest: D-eDP1
[673.259425] Dynamic subtest D-eDP1: SUCCESS (8.705s)
[673.265131] Subtest flip-vs-expired-vblank: SUCCESS (34.775s)
[674.511314] [089/133] (346s left) gem_create (create-massive)
[674.575098] Starting subtest: create-massive
[674.577765] Subtest create-massive: SUCCESS (0.000s)
[674.772207] [090/133] (346s left) kms_flip (flip-vs-wf_vblank-interruptible)
[674.880360] Starting subtest: flip-vs-wf_vblank-interruptible
[674.883041] Starting dynamic subtest: A-eDP1
[676.815638] Dynamic subtest A-eDP1: SUCCESS (1.935s)
[676.832402] Starting dynamic subtest: B-eDP1
[678.705297] Dynamic subtest B-eDP1: SUCCESS (1.887s)
[678.718039] Starting dynamic subtest: C-eDP1
[680.606655] Dynamic subtest C-eDP1: SUCCESS (1.899s)
[680.617590] Starting dynamic subtest: D-eDP1
[682.490546] Dynamic subtest D-eDP1: SUCCESS (1.882s)
[682.493186] Subtest flip-vs-wf_vblank-interruptible: SUCCESS (7.614s)
[683.694030] [091/133] (337s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-cpu)
[683.790120] Starting subtest: psr-rgb101010-draw-mmap-cpu
[690.712400] Subtest psr-rgb101010-draw-mmap-cpu: SUCCESS (6.926s)
[690.828158] [092/133] (330s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[690.886416] Subtest cpuset-medium-copy-XY: SKIP (0.000s)
[690.943300] [093/133] (330s left) gem_mmap_gtt (isolation)
[690.999213] Subtest isolation: SKIP (0.000s)
[691.065666] [094/133] (330s left) kms_color_pipeline (plane-lut1d-post-ctm3x4)
[691.166476] Starting subtest: plane-lut1d-post-ctm3x4
[691.346708] Starting dynamic subtest: pipe-A-plane-0
[691.750147] Dynamic subtest pipe-A-plane-0: FAIL (0.216s)
[691.760489] Starting dynamic subtest: pipe-A-plane-1
[691.966127] Dynamic subtest pipe-A-plane-1: FAIL (0.179s)
[691.975281] Starting dynamic subtest: pipe-A-plane-2
[692.229483] Dynamic subtest pipe-A-plane-2: FAIL (0.177s)
[693.096645] Starting dynamic subtest: pipe-B-plane-0
[693.573768] Dynamic subtest pipe-B-plane-0: FAIL (0.192s)
[693.584533] Starting dynamic subtest: pipe-B-plane-1
[693.937465] Dynamic subtest pipe-B-plane-1: FAIL (0.158s)
[693.947405] Starting dynamic subtest: pipe-B-plane-2
[694.280584] Dynamic subtest pipe-B-plane-2: FAIL (0.672s)
[695.346750] Starting dynamic subtest: pipe-C-plane-0
[695.912859] Dynamic subtest pipe-C-plane-0: FAIL (0.128s)
[695.924157] Starting dynamic subtest: pipe-C-plane-1
[696.369470] Dynamic subtest pipe-C-plane-1: FAIL (0.706s)
[696.379167] Starting dynamic subtest: pipe-C-plane-2
[696.817413] Dynamic subtest pipe-C-plane-2: FAIL (0.153s)
[697.555479] Starting dynamic subtest: pipe-D-plane-0
[698.239318] Dynamic subtest pipe-D-plane-0: FAIL (0.140s)
[698.251010] Starting dynamic subtest: pipe-D-plane-1
[698.783513] Dynamic subtest pipe-D-plane-1: FAIL (0.695s)
[698.795309] Starting dynamic subtest: pipe-D-plane-2
[699.330079] Dynamic subtest pipe-D-plane-2: FAIL (0.640s)
[699.332330] Subtest plane-lut1d-post-ctm3x4: FAIL (8.153s)
[700.201756] [095/133] (320s left) kms_prime (d3hot)
[700.300540] Subtest D3hot: SKIP (0.000s)
[700.417335] [096/133] (320s left) gem_exec_reloc (basic-wc-gtt)
[700.479233] Subtest basic-wc-gtt: SKIP (0.000s)
[700.543203] [097/133] (320s left) gem_exec_balancer (parallel)
[700.808527] Starting subtest: parallel
[701.728870] Subtest parallel: SUCCESS (0.920s)
[702.262453] [098/133] (319s left) kms_ccs (crc-primary-rotation-180-4-tiled-bmg-ccs)
[702.361466] Starting subtest: crc-primary-rotation-180-4-tiled-bmg-ccs
[702.366510] Subtest crc-primary-rotation-180-4-tiled-bmg-ccs: SKIP (0.000s)
[703.492917] [099/133] (317s left) kms_flip (plain-flip-interruptible)
[703.586411] Starting subtest: plain-flip-interruptible
[703.588702] Starting dynamic subtest: A-eDP1
[705.271174] Dynamic subtest A-eDP1: SUCCESS (1.684s)
[705.283852] Starting dynamic subtest: B-eDP1
[706.962627] Dynamic subtest B-eDP1: SUCCESS (1.690s)
[706.973256] Starting dynamic subtest: C-eDP1
[708.566760] Dynamic subtest C-eDP1: SUCCESS (1.602s)
[708.577553] Starting dynamic subtest: D-eDP1
[710.259954] Dynamic subtest D-eDP1: SUCCESS (1.692s)
[710.265575] Subtest plain-flip-interruptible: SUCCESS (6.678s)
[711.421141] [100/133] (310s left) kms_ccs (missing-ccs-buffer-y-tiled-ccs)
[711.513574] Starting subtest: missing-ccs-buffer-y-tiled-ccs
[711.516312] Starting dynamic subtest: pipe-A-eDP-1
[711.541251] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[711.552061] Starting dynamic subtest: pipe-B-eDP-1
[711.578552] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[711.588444] Starting dynamic subtest: pipe-C-eDP-1
[711.613515] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[711.623528] Starting dynamic subtest: pipe-D-eDP-1
[711.651056] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[711.655292] Subtest missing-ccs-buffer-y-tiled-ccs: SKIP (0.004s)
[713.793386] [101/133] (307s left) gem_ctx_param (vm)
[713.848467] Starting subtest: vm
[713.917468] Subtest vm: SUCCESS (0.069s)
[714.040099] [102/133] (307s left) kms_properties (plane-properties-legacy)
[714.130724] Starting subtest: plane-properties-legacy
[714.132833] Starting dynamic subtest: pipe-A-eDP-1
[714.694518] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.563s)
[714.705348] Starting dynamic subtest: pipe-B-eDP-1
[715.927491] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.228s)
[715.938501] Starting dynamic subtest: pipe-C-eDP-1
[717.225135] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.298s)
[717.235497] Starting dynamic subtest: pipe-D-eDP-1
[718.524599] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.299s)
[718.527528] Subtest plane-properties-legacy: SUCCESS (4.395s)
[719.407409] [103/133] (302s left) gem_pxp (reject-modify-context-protection-off-3)
[719.523957] Starting subtest: reject-modify-context-protection-off-3
[719.526530] Subtest reject-modify-context-protection-off-3: SUCCESS (0.002s)
[719.628963] [104/133] (301s left) prime_self_import (basic-with_one_bo_two_files)
[719.671638] Starting subtest: basic-with_one_bo_two_files
[719.685552] Subtest basic-with_one_bo_two_files: SUCCESS (0.015s)
[719.781398] [105/133] (301s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-20x20)
[719.872920] Starting subtest: planes-downscale-factor-0-75-upscale-20x20
[719.875521] Starting dynamic subtest: pipe-A
[719.935375] Dynamic subtest pipe-A: SUCCESS (0.065s)
[719.945327] Starting dynamic subtest: pipe-B
[721.089472] Dynamic subtest pipe-B: SUCCESS (1.153s)
[721.099593] Starting dynamic subtest: pipe-C
[722.166212] Dynamic subtest pipe-C: SUCCESS (1.076s)
[722.177311] Starting dynamic subtest: pipe-D
[723.264021] Dynamic subtest pipe-D: SUCCESS (1.097s)
[723.266589] Subtest planes-downscale-factor-0-75-upscale-20x20: SUCCESS (3.394s)
[724.410489] [106/133] (297s left) kms_big_fb (4-tiled-16bpp-rotate-90)
[724.534484] Starting subtest: 4-tiled-16bpp-rotate-90
[724.564677] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.028s)
[724.683487] [107/133] (297s left) gen9_exec_parse (batch-invalid-length)
[724.749560] Subtest batch-invalid-length: SKIP (0.000s)
[724.822242] [108/133] (296s left) syncobj_timeline (invalid-signal-one-illegal-handle)
[724.881075] Starting subtest: invalid-signal-one-illegal-handle
[724.883627] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[724.979170] [109/133] (296s left) kms_big_fb (x-tiled-64bpp-rotate-180)
[725.075004] Starting subtest: x-tiled-64bpp-rotate-180
[726.720323] Subtest x-tiled-64bpp-rotate-180: SUCCESS (1.645s)
[726.877561] [110/133] (294s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt)
[726.977346] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt
[726.982499] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[727.080330] [111/133] (294s left) gem_exec_params (invalid-ring2)
[727.135430] Starting subtest: invalid-ring2
[727.137875] Subtest invalid-ring2: SUCCESS (0.000s)
[727.236964] [112/133] (294s left) gem_vm_create (invalid-create)
[727.293307] Starting subtest: invalid-create
[727.298233] Subtest invalid-create: SUCCESS (0.002s)
[727.391026] [113/133] (294s left) kms_addfb_basic (bad-pitch-128)
[727.445137] Starting subtest: bad-pitch-128
[727.447171] Subtest bad-pitch-128: SUCCESS (0.000s)
[727.573830] [114/133] (294s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[727.623464] Starting subtest: single-wait-all-for-submit-available-submitted
[727.625429] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[727.714719] [115/133] (294s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[727.827308] Starting subtest: cursorB-vs-flipA-toggle
[727.831458] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s)
[727.987576] [116/133] (294s left) prime_vgem (fence-write-hang)
[728.059044] Subtest fence-write-hang: SKIP (0.000s)
[728.162574] [117/133] (294s left) fbdev (eof)
[728.200395] Starting subtest: eof
[728.202746] Subtest eof: SUCCESS (0.000s)
[728.273797] [118/133] (293s left) kms_cursor_legacy (2x-flip-vs-cursor-atomic)
[728.383342] Starting subtest: 2x-flip-vs-cursor-atomic
[728.387493] Subtest 2x-flip-vs-cursor-atomic: SKIP (0.001s)
[728.528102] [119/133] (293s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[728.634155] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt
[728.638883] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[728.768908] [120/133] (293s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-blt)
[728.858395] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-blt
[735.723170] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (6.866s)
[735.863074] [121/133] (286s left) prime_mmap_kms (buffer-sharing)
[735.954319] Starting subtest: buffer-sharing
[736.392761] Subtest buffer-sharing: SUCCESS (0.437s)
[737.187821] [122/133] (285s left) gem_exec_fence (basic-await)
[737.371945] Starting subtest: basic-await
[737.374434] Starting dynamic subtest: rcs0
[737.448634] Dynamic subtest rcs0: SUCCESS (0.076s)
[737.462117] Starting dynamic subtest: bcs0
[737.524307] Dynamic subtest bcs0: SUCCESS (0.074s)
[737.537521] Starting dynamic subtest: vcs0
[737.597516] Dynamic subtest vcs0: SUCCESS (0.072s)
[737.610746] Starting dynamic subtest: vcs1
[737.671650] Dynamic subtest vcs1: SUCCESS (0.072s)
[737.682994] Starting dynamic subtest: vecs0
[737.745732] Dynamic subtest vecs0: SUCCESS (0.072s)
[737.758317] Starting dynamic subtest: ccs0
[737.819328] Dynamic subtest ccs0: SUCCESS (0.072s)
[737.822201] Subtest basic-await: SUCCESS (0.448s)
[739.307176] [123/133] (283s left) prime_busy (before-wait)
[739.368228] Starting subtest: before-wait
[739.370672] Starting dynamic subtest: rcs0
[739.395472] Dynamic subtest rcs0: SUCCESS (0.025s)
[739.407262] Starting dynamic subtest: bcs0
[739.435619] Dynamic subtest bcs0: SUCCESS (0.040s)
[739.446414] Starting dynamic subtest: vcs0
[739.478657] Dynamic subtest vcs0: SUCCESS (0.042s)
[739.489760] Starting dynamic subtest: vcs1
[739.521990] Dynamic subtest vcs1: SUCCESS (0.042s)
[739.533428] Starting dynamic subtest: vecs0
[739.567016] Dynamic subtest vecs0: SUCCESS (0.041s)
[739.578016] Starting dynamic subtest: ccs0
[739.605333] Dynamic subtest ccs0: SUCCESS (0.041s)
[739.710613] Subtest before-wait: SUCCESS (0.344s)
[739.808863] [124/133] (282s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[739.940201] Starting subtest: short-busy-flip-before-cursor-toggle
[739.944961] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[740.092226] [125/133] (282s left) syncobj_timeline (invalid-multi-wait-unsubmitted)
[740.140500] Starting subtest: invalid-multi-wait-unsubmitted
[740.143350] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[740.242156] [126/133] (282s left) gem_mmap_wc (invalid-flags)
[740.306760] Subtest invalid-flags: SKIP (0.000s)
[740.371576] [127/133] (282s left) kms_big_fb (yf-tiled-64bpp-rotate-270)
[740.464362] Starting subtest: yf-tiled-64bpp-rotate-270
[740.468781] Subtest yf-tiled-64bpp-rotate-270: SKIP (0.000s)
[740.559420] [128/133] (282s left) kms_tv_load_detect (load-detect)
[740.624579] Subtest load-detect: SKIP (0.000s)
[740.686905] [129/133] (282s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[740.789882] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt
[740.795041] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[740.907619] [130/133] (281s left) syncobj_wait (wait-any-complex)
[740.959550] Starting subtest: wait-any-complex
[741.020911] Subtest wait-any-complex: SUCCESS (0.063s)
[741.102307] [131/133] (281s left) sw_sync (sync_expired_merge)
[741.143527] Starting subtest: sync_expired_merge
[742.215488] Subtest sync_expired_merge: SUCCESS (1.074s)
[742.278729] [132/133] (280s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-msflip-blt)
[742.378445] Starting subtest: fbc-2p-scndscrn-shrfb-msflip-blt
[742.382926] Subtest fbc-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[742.469891] [133/133] (280s left) kms_psr (psr-suspend)
[742.568424] Starting subtest: psr-suspend
[742.570719] Starting dynamic subtest: eDP-1
[760.855924] Dynamic subtest eDP-1: SUCCESS (3.013s)
[760.858311] Subtest psr-suspend: SUCCESS (3.014s)
[761.681394] Closing watchdogs
results: parsing output: 0/ for test: gem_exec_gttfill
results: parsing output: 1/ for test: perf
results: parsing output: 2/ for test: kms_joiner
results: parsing output: 3/ for test: prime_mmap
results: parsing output: 4/ for test: kms_plane
results: parsing output: 5/ for test: kms_async_flips
results: parsing output: 6/ for test: kms_cursor_crc
results: parsing output: 7/ for test: gem_exec_flush
results: parsing output: 8/ for test: gem_userptr_blits
results: parsing output: 9/ for test: syncobj_timeline
results: parsing output: 10/ for test: kms_big_fb
results: parsing output: 11/ for test: kms_cursor_legacy
results: parsing output: 12/ for test: kms_psr
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_big_fb
results: parsing output: 15/ for test: kms_ccs
results: parsing output: 16/ for test: kms_plane
results: parsing output: 17/ for test: i915_drm_fdinfo
results: parsing output: 18/ for test: perf_pmu
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: kms_psr
results: parsing output: 21/ for test: kms_big_fb
results: parsing output: 22/ for test: gem_workarounds
results: parsing output: 23/ for test: kms_fence_pin_leak
results: parsing output: 24/ for test: gem_render_copy
results: parsing output: 25/ for test: kms_cdclk
results: parsing output: 26/ for test: gem_exec_params
results: parsing output: 27/ for test: syncobj_wait
results: parsing output: 28/ for test: gem_exec_store
results: parsing output: 29/ for test: gem_ctx_persistence
results: parsing output: 30/ for test: kms_psr
results: parsing output: 31/ for test: i915_hangman
results: parsing output: 32/ for test: kms_force_connector_basic
results: parsing output: 33/ for test: kms_universal_plane
results: parsing output: 34/ for test: dmabuf_sync_file
results: parsing output: 35/ for test: kms_pm_dc
results: parsing output: 36/ for test: gem_render_copy_redux
results: parsing output: 37/ for test: kms_cursor_crc
results: parsing output: 38/ for test: kms_psr2_sf
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: gem_mmap_wc
results: parsing output: 41/ for test: gem_softpin
results: parsing output: 42/ for test: kms_content_protection
results: parsing output: 43/ for test: gem_ctx_shared
results: parsing output: 44/ for test: gem_softpin
results: parsing output: 45/ for test: kms_plane_alpha_blend
results: parsing output: 46/ for test: kms_busy
results: parsing output: 47/ for test: kms_big_fb
results: parsing output: 48/ for test: kms_plane_multiple
results: parsing output: 49/ for test: kms_chamelium_frames
results: parsing output: 50/ for test: i915_suspend
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_chamelium_hpd
results: parsing output: 53/ for test: kms_psr
results: parsing output: 54/ for test: kms_psr2_sf
results: parsing output: 55/ for test: api_intel_bb
results: parsing output: 56/ for test: kms_flip
results: parsing output: 57/ for test: gem_exec_schedule
results: parsing output: 58/ for test: gem_exec_schedule
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: kms_cursor_crc
results: parsing output: 61/ for test: gem_shrink
results: parsing output: 62/ for test: syncobj_timeline
results: parsing output: 63/ for test: kms_dsc
results: parsing output: 64/ for test: kms_cursor_legacy
results: parsing output: 65/ for test: kms_vblank
results: parsing output: 66/ for test: kms_lease
results: parsing output: 67/ for test: kms_addfb_basic
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: gem_exec_params
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_rmfb
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: kms_ccs
results: parsing output: 75/ for test: i915_pm_rpm
results: parsing output: 76/ for test: kms_flip_scaled_crc
results: parsing output: 77/ for test: gem_unfence_active_buffers
results: parsing output: 78/ for test: gem_exec_suspend
results: parsing output: 79/ for test: gem_exec_whisper
results: parsing output: 80/ for test: gem_exec_params
results: parsing output: 81/ for test: kms_flip_scaled_crc
results: parsing output: 82/ for test: gem_fenced_exec_thrash
results: parsing output: 83/ for test: gem_exec_fence
results: parsing output: 84/ for test: i915_query
results: parsing output: 85/ for test: syncobj_eventfd
results: parsing output: 86/ for test: kms_psr2_sf
results: parsing output: 87/ for test: kms_flip
results: parsing output: 88/ for test: gem_create
results: parsing output: 89/ for test: kms_flip
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: gem_mmap_gtt
results: parsing output: 92/ for test: gem_mmap_gtt
results: parsing output: 93/ for test: kms_color_pipeline
results: parsing output: 94/ for test: kms_prime
results: parsing output: 95/ for test: gem_exec_reloc
results: parsing output: 96/ for test: gem_exec_balancer
results: parsing output: 97/ for test: kms_ccs
results: parsing output: 98/ for test: kms_flip
results: parsing output: 99/ for test: kms_ccs
results: parsing output: 100/ for test: gem_ctx_param
results: parsing output: 101/ for test: kms_properties
results: parsing output: 102/ for test: gem_pxp
results: parsing output: 103/ for test: prime_self_import
results: parsing output: 104/ for test: kms_plane_scaling
results: parsing output: 105/ for test: kms_big_fb
results: parsing output: 106/ for test: gen9_exec_parse
results: parsing output: 107/ for test: syncobj_timeline
results: parsing output: 108/ for test: kms_big_fb
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: gem_exec_params
results: parsing output: 111/ for test: gem_vm_create
results: parsing output: 112/ for test: kms_addfb_basic
results: parsing output: 113/ for test: syncobj_timeline
results: parsing output: 114/ for test: kms_cursor_legacy
results: parsing output: 115/ for test: prime_vgem
results: parsing output: 116/ for test: fbdev
results: parsing output: 117/ for test: kms_cursor_legacy
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: prime_mmap_kms
results: parsing output: 121/ for test: gem_exec_fence
results: parsing output: 122/ for test: prime_busy
results: parsing output: 123/ for test: kms_cursor_legacy
results: parsing output: 124/ for test: syncobj_timeline
results: parsing output: 125/ for test: gem_mmap_wc
results: parsing output: 126/ for test: kms_big_fb
results: parsing output: 127/ for test: kms_tv_load_detect
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: syncobj_wait
results: parsing output: 130/ for test: sw_sync
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: kms_psr
Done.