[57.926801] Initializing watchdogs
[57.926845]   /dev/watchdog0
[57.942410] [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.946988] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.949418] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.952807] [001/156] (960s left) kms_addfb_basic (basic)
[58.024121] Starting subtest: basic
[58.026375] Subtest basic: SUCCESS (0.000s)
[58.179833] [002/156] (960s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[58.285600] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[64.850930] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.564s)
[64.960677] [003/156] (953s left) gem_userptr_blits (stress-mm-invalidate-close)
[65.028082] Starting subtest: stress-mm-invalidate-close
[67.177163] Subtest stress-mm-invalidate-close: SUCCESS (2.148s)
[67.284014] [004/156] (951s left) api_intel_bb (crc32)
[67.334776] Starting subtest: crc32
[67.337186] Starting dynamic subtest: rcs0-smem0
[67.638231] Dynamic subtest rcs0-smem0: SUCCESS (0.302s)
[67.649056] Starting dynamic subtest: bcs0-smem0
[67.962396] Dynamic subtest bcs0-smem0: SUCCESS (0.323s)
[67.973967] Starting dynamic subtest: vcs0-smem0
[68.322490] Dynamic subtest vcs0-smem0: SUCCESS (0.359s)
[68.331787] Starting dynamic subtest: vcs1-smem0
[68.653965] Dynamic subtest vcs1-smem0: SUCCESS (0.329s)
[68.664543] Starting dynamic subtest: vecs0-smem0
[69.032350] Dynamic subtest vecs0-smem0: SUCCESS (0.377s)
[69.044643] Starting dynamic subtest: ccs0-smem0
[69.356633] Dynamic subtest ccs0-smem0: SUCCESS (0.323s)
[69.359589] Subtest crc32: SUCCESS (2.020s)
[69.469791] [005/156] (949s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-pwrite)
[69.576456] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-pwrite
[69.580643] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[69.668206] [006/156] (948s left) kms_flip (plain-flip-ts-check-interruptible)
[69.754489] Starting subtest: plain-flip-ts-check-interruptible
[69.756770] Starting dynamic subtest: A-eDP1
[78.609541] Dynamic subtest A-eDP1: SUCCESS (8.848s)
[78.620458] Starting dynamic subtest: B-eDP1
[87.445655] Dynamic subtest B-eDP1: SUCCESS (8.833s)
[87.456398] Starting dynamic subtest: C-eDP1
[96.262766] Dynamic subtest C-eDP1: SUCCESS (8.814s)
[96.272813] Starting dynamic subtest: D-eDP1
[105.096885] Dynamic subtest D-eDP1: SUCCESS (8.833s)
[105.101631] Subtest plain-flip-ts-check-interruptible: SUCCESS (35.338s)
[106.502737] [007/156] (912s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-onoff)
[106.602373] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-onoff
[106.606811] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[106.694781] [008/156] (912s left) perf (mi-rpc)
[106.816847] Starting subtest: mi-rpc
[106.820577] Subtest mi-rpc: SKIP (0.000s)
[106.861558] [009/156] (911s left) kms_flip (2x-flip-vs-suspend)
[106.971433] Starting subtest: 2x-flip-vs-suspend
[106.975612] Subtest 2x-flip-vs-suspend: SKIP (0.002s)
[107.076496] [010/156] (911s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[107.176740] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[110.785871] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (3.615s)
[111.994776] [011/156] (906s left) gem_exec_gttfill (engines)
[112.052672] Starting subtest: engines
[112.054578] Starting dynamic subtest: rcs0
[134.906969] Dynamic subtest rcs0: SUCCESS (22.853s)
[134.918195] Starting dynamic subtest: bcs0
[158.183861] Dynamic subtest bcs0: SUCCESS (23.274s)
[158.194538] Starting dynamic subtest: vcs0
[181.447538] Dynamic subtest vcs0: SUCCESS (23.261s)
[181.457993] Starting dynamic subtest: vcs1
[204.717419] Dynamic subtest vcs1: SUCCESS (23.268s)
[204.731291] Starting dynamic subtest: vecs0
[227.976349] Dynamic subtest vecs0: SUCCESS (23.257s)
[227.984901] Starting dynamic subtest: ccs0
[251.257353] Dynamic subtest ccs0: SUCCESS (23.280s)
[251.259832] Subtest engines: SUCCESS (139.196s)
[251.457228] [012/156] (767s left) gem_exec_balancer (fairslice)
[251.554653] Starting subtest: fairslice
[254.500443] Subtest fairslice: SUCCESS (2.944s)
[255.299709] [013/156] (763s left) kms_invalid_mode (clock-too-high)
[255.403236] Starting subtest: clock-too-high
[255.405420] Starting dynamic subtest: pipe-A-eDP-1
[255.687425] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s)
[255.697081] Starting dynamic subtest: pipe-B-eDP-1
[255.701284] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[255.710098] Starting dynamic subtest: pipe-C-eDP-1
[255.714621] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[255.722984] Starting dynamic subtest: pipe-D-eDP-1
[255.727492] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[255.729763] Subtest clock-too-high: SUCCESS (0.289s)
[256.502575] [014/156] (762s left) gem_ctx_persistence (engines-mixed)
[256.574249] Starting subtest: engines-mixed
[256.576768] Starting dynamic subtest: rcs0
[256.659417] Dynamic subtest rcs0: SUCCESS (0.085s)
[256.670633] Starting dynamic subtest: bcs0
[256.707469] Dynamic subtest bcs0: SUCCESS (0.046s)
[256.717377] Starting dynamic subtest: vcs0
[256.777412] Dynamic subtest vcs0: SUCCESS (0.070s)
[256.787897] Starting dynamic subtest: vcs1
[256.842325] Dynamic subtest vcs1: SUCCESS (0.064s)
[256.853316] Starting dynamic subtest: vecs0
[256.895775] Dynamic subtest vecs0: SUCCESS (0.053s)
[256.906770] Starting dynamic subtest: ccs0
[256.946819] Dynamic subtest ccs0: SUCCESS (0.050s)
[256.949356] Subtest engines-mixed: SUCCESS (0.373s)
[257.024605] [015/156] (761s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[257.121184] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[257.125623] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[257.222178] [016/156] (761s left) perf (non-zero-reason)
[257.340249] Starting subtest: non-zero-reason
[257.342799] Starting dynamic subtest: 0-rcs0
[263.323877] Dynamic subtest 0-rcs0: SUCCESS (5.981s)
[263.336649] Starting dynamic subtest: 1-vecs0
[267.487374] Dynamic subtest 1-vecs0: SUCCESS (4.163s)
[267.490335] Subtest non-zero-reason: SUCCESS (10.145s)
[267.535906] [017/156] (751s left) gen9_exec_parse (bb-start-param)
[267.599780] Subtest bb-start-param: SKIP (0.000s)
[267.678480] [018/156] (751s left) gem_spin_batch (user-each)
[267.742601] Starting subtest: user-each
[271.023821] Subtest user-each: SUCCESS (3.273s)
[272.397357] [019/156] (746s left) kms_color (ctm-0-50)
[272.493877] Starting subtest: ctm-0-50
[272.495843] Starting dynamic subtest: pipe-A-eDP-1
[276.066510] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.573s)
[276.076939] Starting dynamic subtest: pipe-B-eDP-1
[280.352616] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.286s)
[280.361093] Starting dynamic subtest: pipe-C-eDP-1
[284.360100] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.004s)
[284.369265] Starting dynamic subtest: pipe-D-eDP-1
[288.365895] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.007s)
[288.368494] Subtest ctm-0-50: SUCCESS (15.872s)
[289.185278] [020/156] (730s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted)
[289.312110] Starting subtest: dmabuf-shared-protected-dst-is-context-refcounted
[289.445910] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SUCCESS (0.136s)
[289.569715] [021/156] (729s left) kms_big_fb (x-tiled-8bpp-rotate-180)
[289.671042] Starting subtest: x-tiled-8bpp-rotate-180
[290.906442] Subtest x-tiled-8bpp-rotate-180: SUCCESS (1.236s)
[291.048851] [022/156] (728s left) gem_ctx_persistence (idempotent)
[291.111079] Starting subtest: idempotent
[291.113146] Subtest idempotent: SUCCESS (0.000s)
[291.228123] [023/156] (728s left) gem_exec_whisper (basic-queues-forked-all)
[291.293536] Starting subtest: basic-queues-forked-all
[309.756092] Subtest basic-queues-forked-all: SUCCESS (18.459s)
[309.940281] [024/156] (709s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt)
[310.045343] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt
[310.049497] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[310.141013] [025/156] (709s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping)
[310.241318] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping
[310.266791] Starting dynamic subtest: pipe-A-plane-0
[310.556704] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[310.569723] Starting dynamic subtest: pipe-A-plane-3
[310.789648] Dynamic subtest pipe-A-plane-3: SUCCESS (0.233s)
[311.872896] Starting dynamic subtest: pipe-B-plane-0
[312.175785] Dynamic subtest pipe-B-plane-0: SUCCESS (0.311s)
[312.185337] Starting dynamic subtest: pipe-B-plane-3
[312.409954] Dynamic subtest pipe-B-plane-3: SUCCESS (0.233s)
[312.724943] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping: SUCCESS (2.484s)
[313.536910] [026/156] (705s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-sf)
[313.659373] Starting subtest: pr-overlay-plane-move-continuous-exceed-sf
[313.664059] Subtest pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[313.726851] [027/156] (705s left) gem_exec_whisper (basic-fds-forked-all)
[313.792703] Starting subtest: basic-fds-forked-all
[333.637059] Subtest basic-fds-forked-all: SUCCESS (19.841s)
[333.820165] [028/156] (685s left) gem_exec_fence (syncobj-backward-timeline-chain-engines)
[334.514446] Starting subtest: syncobj-backward-timeline-chain-engines
[334.674814] Subtest syncobj-backward-timeline-chain-engines: SUCCESS (0.163s)
[336.412306] [FACT gem_exec_fence (syncobj-backward-timeline-chain-engines)] new: kernel.kmod_is_loaded.vgem: true
[336.415720] [029/156] (683s left) kms_plane_alpha_blend (alpha-7efc)
[336.519061] Starting subtest: alpha-7efc
[336.823621] Starting dynamic subtest: pipe-A-eDP-1
[337.422527] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.598s)
[337.452483] Starting dynamic subtest: pipe-D-eDP-1
[338.766080] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.322s)
[338.768429] Subtest alpha-7efc: SUCCESS (2.249s)
[339.603309] [030/156] (679s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[339.700138] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[339.704318] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.000s)
[339.774768] [031/156] (679s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[339.828157] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[339.928282] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.101s)
[340.021400] [032/156] (679s left) kms_async_flips (async-flip-hang)
[340.115429] Starting subtest: async-flip-hang
[340.493530] Starting dynamic subtest: pipe-A-eDP-1
[355.744115] Dynamic subtest pipe-A-eDP-1: SUCCESS (15.249s)
[356.119741] Starting dynamic subtest: pipe-B-eDP-1
[371.738718] Dynamic subtest pipe-B-eDP-1: SUCCESS (15.624s)
[372.129243] Starting dynamic subtest: pipe-C-eDP-1
[387.738503] Dynamic subtest pipe-C-eDP-1: SUCCESS (15.613s)
[388.118638] Starting dynamic subtest: pipe-D-eDP-1
[403.733646] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.620s)
[403.736054] Subtest async-flip-hang: SUCCESS (63.616s)
[404.959982] [033/156] (614s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-shrfb-draw-pwrite)
[405.072030] Starting subtest: hdr-2p-primscrn-pri-shrfb-draw-pwrite
[405.076088] Subtest hdr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[405.168084] [034/156] (614s left) kms_ccs (crc-primary-basic-yf-tiled-ccs)
[405.267745] Starting subtest: crc-primary-basic-yf-tiled-ccs
[405.269889] Starting dynamic subtest: pipe-A-eDP-1
[405.292666] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[405.304200] Starting dynamic subtest: pipe-B-eDP-1
[405.325300] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[405.335668] Starting dynamic subtest: pipe-C-eDP-1
[405.357540] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[405.366898] Starting dynamic subtest: pipe-D-eDP-1
[405.389315] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[405.393366] Subtest crc-primary-basic-yf-tiled-ccs: SKIP (0.007s)
[407.553697] [035/156] (612s left) gem_busy (busy)
[407.614608] Starting subtest: busy
[407.616613] Starting dynamic subtest: all-engines
[407.683646] Dynamic subtest all-engines: SUCCESS (0.072s)
[407.693164] Starting dynamic subtest: rcs0
[409.282917] Dynamic subtest rcs0: SUCCESS (1.598s)
[409.291968] Starting dynamic subtest: bcs0
[409.329153] Dynamic subtest bcs0: SUCCESS (0.043s)
[409.337469] Starting dynamic subtest: vcs0
[409.368939] Dynamic subtest vcs0: SUCCESS (0.038s)
[409.383988] Starting dynamic subtest: vcs1
[411.333533] Dynamic subtest vcs1: SUCCESS (1.962s)
[411.343807] Starting dynamic subtest: vecs0
[413.315619] Dynamic subtest vecs0: SUCCESS (1.981s)
[413.326825] Starting dynamic subtest: ccs0
[415.300355] Dynamic subtest ccs0: SUCCESS (1.985s)
[415.302458] Subtest busy: SUCCESS (7.687s)
[415.655428] [036/156] (604s left) perf (low-oa-exponent-permissions)
[415.735948] Starting subtest: low-oa-exponent-permissions
[415.766897] Subtest low-oa-exponent-permissions: SUCCESS (0.034s)
[415.809143] [037/156] (604s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-pwrite)
[415.913687] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-pwrite
[415.918219] Subtest psr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[416.021763] [038/156] (603s left) gem_exec_schedule (implicit-write-read)
[416.099156] Starting subtest: implicit-write-read
[416.101475] Starting dynamic subtest: rcs0
[416.202339] Dynamic subtest rcs0: SUCCESS (0.113s)
[416.212935] Starting dynamic subtest: vcs0
[416.288732] Dynamic subtest vcs0: SUCCESS (0.086s)
[416.299918] Starting dynamic subtest: vcs1
[416.375486] Dynamic subtest vcs1: SUCCESS (0.086s)
[416.386127] Starting dynamic subtest: vecs0
[416.455057] Dynamic subtest vecs0: SUCCESS (0.078s)
[416.465342] Starting dynamic subtest: ccs0
[416.540095] Dynamic subtest ccs0: SUCCESS (0.084s)
[416.542636] Subtest implicit-write-read: SUCCESS (0.452s)
[417.369163] [039/156] (602s left) kms_cursor_crc (cursor-sliding-128x128)
[417.580859] Starting subtest: cursor-sliding-128x128
[417.585874] Starting dynamic subtest: pipe-A-eDP-1
[420.831950] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.247s)
[420.860893] Starting dynamic subtest: pipe-D-eDP-1
[424.716410] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.862s)
[424.718829] Subtest cursor-sliding-128x128: SUCCESS (7.135s)
[425.584428] [040/156] (594s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs)
[425.676877] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs
[425.679201] Starting dynamic subtest: pipe-A-eDP-1
[442.392874] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.540s)
[442.403656] Starting dynamic subtest: pipe-B-eDP-1
[459.354418] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.450s)
[459.366245] Starting dynamic subtest: pipe-C-eDP-1
[476.336730] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.315s)
[476.348098] Starting dynamic subtest: pipe-D-eDP-1
[493.314518] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.309s)
[493.317468] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs: SUCCESS (8.619s)
[494.484784] [041/156] (525s left) kms_psr (psr-sprite-plane-move)
[494.584098] Starting subtest: psr-sprite-plane-move
[494.586629] Starting dynamic subtest: eDP-1
[496.316150] Dynamic subtest eDP-1: SUCCESS (1.724s)
[496.318690] Subtest psr-sprite-plane-move: SUCCESS (1.724s)
[497.156750] [042/156] (522s left) prime_busy (before)
[497.217205] Starting subtest: before
[497.222724] Starting dynamic subtest: rcs0
[497.251298] Dynamic subtest rcs0: SUCCESS (0.031s)
[497.262097] Starting dynamic subtest: bcs0
[497.312510] Dynamic subtest bcs0: SUCCESS (0.060s)
[497.323425] Starting dynamic subtest: vcs0
[497.353304] Dynamic subtest vcs0: SUCCESS (0.040s)
[497.363435] Starting dynamic subtest: vcs1
[497.396666] Dynamic subtest vcs1: SUCCESS (0.042s)
[497.407669] Starting dynamic subtest: vecs0
[497.438940] Dynamic subtest vecs0: SUCCESS (0.041s)
[497.449504] Starting dynamic subtest: ccs0
[497.480883] Dynamic subtest ccs0: SUCCESS (0.041s)
[497.589184] Subtest before: SUCCESS (0.369s)
[497.661757] [043/156] (522s left) kms_cursor_edge_walk (256x256-left-edge)
[497.748609] Starting subtest: 256x256-left-edge
[497.751315] Starting dynamic subtest: pipe-A-eDP-1
[501.239388] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.480s)
[501.250491] Starting dynamic subtest: pipe-D-eDP-1
[504.374488] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.126s)
[504.376978] Subtest 256x256-left-edge: SUCCESS (6.609s)
[505.211777] [044/156] (514s left) kms_chamelium_hpd (hdmi-hpd-storm)
[505.613990] Subtest hdmi-hpd-storm: SKIP (0.000s)
[506.409558] [045/156] (513s left) intel_hwmon (hwmon-read)
[506.471530] Subtest hwmon-read: SKIP (0.000s)
[506.531284] [046/156] (513s left) kms_big_fb (yf-tiled-64bpp-rotate-180)
[506.630047] Starting subtest: yf-tiled-64bpp-rotate-180
[506.635442] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s)
[506.717317] [047/156] (513s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[506.812588] Starting subtest: hdr-1p-primscrn-spr-indfb-draw-mmap-wc
[506.818156] Subtest hdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[506.908829] [048/156] (513s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[507.036372] Starting subtest: short-busy-flip-before-cursor-toggle
[507.040946] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[507.182039] [049/156] (513s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-shrfb-msflip-blt)
[507.280888] Starting subtest: fbcpsrhdr-2p-primscrn-shrfb-msflip-blt
[507.286411] Subtest fbcpsrhdr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[507.383465] [050/156] (513s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-indfb-draw-pwrite)
[507.475403] Starting subtest: psrhdr-1p-offscreen-pri-indfb-draw-pwrite
[507.479654] Subtest psrhdr-1p-offscreen-pri-indfb-draw-pwrite: SKIP (0.000s)
[507.585140] [051/156] (512s left) device_reset (cold-reset-bound)
[507.630206] Starting subtest: cold-reset-bound
[507.635188] Subtest cold-reset-bound: SKIP (0.000s)
[507.705099] [052/156] (512s left) kms_vblank (wait-forked-busy)
[507.788523] Starting subtest: wait-forked-busy
[507.790574] Starting dynamic subtest: pipe-A-eDP-1
[509.668107] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.875s)
[509.683588] Starting dynamic subtest: pipe-D-eDP-1
[512.300226] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.628s)
[512.303043] Subtest wait-forked-busy: SUCCESS (4.507s)
[513.118472] [053/156] (507s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[513.220443] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[513.225574] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[513.322785] [054/156] (507s left) kms_big_fb (linear-8bpp-rotate-270)
[513.416156] Starting subtest: linear-8bpp-rotate-270
[513.440507] Subtest linear-8bpp-rotate-270: SKIP (0.021s)
[513.541396] [055/156] (507s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-wc)
[513.635075] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-wc
[513.640401] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[513.743575] [056/156] (506s left) kms_ccs (crc-sprite-planes-basic-4-tiled-lnl-ccs)
[513.835271] Starting subtest: crc-sprite-planes-basic-4-tiled-lnl-ccs
[513.839862] Subtest crc-sprite-planes-basic-4-tiled-lnl-ccs: SKIP (0.000s)
[514.955789] [057/156] (505s left) gem_mmap_gtt (basic-small-copy-xy)
[515.008555] Subtest basic-small-copy-XY: SKIP (0.000s)
[515.064205] [058/156] (505s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-onoff)
[515.162409] Starting subtest: hdr-2p-scndscrn-spr-indfb-onoff
[515.167434] Subtest hdr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[515.251868] [059/156] (505s left) gem_ctx_param (basic)
[515.308115] Starting subtest: basic
[515.310718] Subtest basic: SUCCESS (0.001s)
[515.415632] [060/156] (505s left) gem_eio (wait-immediate)
[515.637407] Starting subtest: wait-immediate
[516.006148] Subtest wait-immediate: SUCCESS (0.370s)
[516.150951] [061/156] (504s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-render)
[516.254416] Starting subtest: psr-2p-primscrn-spr-indfb-draw-render
[516.258476] Subtest psr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[516.335830] [062/156] (504s left) kms_psr (fbc-psr2-cursor-render)
[516.426269] Starting subtest: fbc-psr2-cursor-render
[516.428428] Starting dynamic subtest: eDP-1
[516.432391] Dynamic subtest eDP-1: SKIP (0.000s)
[516.436290] Subtest fbc-psr2-cursor-render: SKIP (0.001s)
[516.501173] [063/156] (504s left) gem_userptr_blits (sync-overlap)
[516.564501] Starting subtest: sync-overlap
[516.566797] Subtest sync-overlap: SUCCESS (0.000s)
[516.695218] [064/156] (504s left) kms_content_protection (lic-type-0-hdcp14)
[516.850207] Starting subtest: lic-type-0-hdcp14
[516.855399] Subtest lic-type-0-hdcp14: SKIP (0.000s)
[516.965443] [065/156] (504s left) syncobj_wait (multi-wait-signaled)
[517.015650] Starting subtest: multi-wait-signaled
[517.018381] Subtest multi-wait-signaled: SUCCESS (0.000s)
[517.110274] [066/156] (504s left) kms_atomic_transition (plane-all-modeset-transition-internal-panels)
[517.203372] Starting subtest: plane-all-modeset-transition-internal-panels
[517.205483] Starting dynamic subtest: pipe-A-eDP-1
[518.508014] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.303s)
[518.790072] Starting dynamic subtest: pipe-B-eDP-1
[519.830702] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.050s)
[520.103413] Subtest plane-all-modeset-transition-internal-panels: SUCCESS (2.900s)
[520.943729] [067/156] (500s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-draw-render)
[521.052468] Starting subtest: psrhdr-1p-primscrn-spr-indfb-draw-render
[521.057494] Subtest psrhdr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[521.155110] [068/156] (500s left) sysfs_heartbeat_interval (precise)
[521.208416] Starting subtest: precise
[521.211506] Starting dynamic subtest: vecs0
[529.253678] Dynamic subtest vecs0: SUCCESS (8.039s)
[529.266497] Starting dynamic subtest: ccs0
[530.401554] Dynamic subtest ccs0: SUCCESS (1.147s)
[530.412357] Starting dynamic subtest: vcs0
[538.279218] Dynamic subtest vcs0: SUCCESS (7.872s)
[538.291436] Starting dynamic subtest: bcs0
[539.387610] Dynamic subtest bcs0: SUCCESS (1.107s)
[539.401876] Starting dynamic subtest: rcs0
[540.480618] Dynamic subtest rcs0: SUCCESS (1.090s)
[540.494975] Starting dynamic subtest: vcs1
[548.258314] Dynamic subtest vcs1: SUCCESS (7.772s)
[548.261320] Subtest precise: SUCCESS (27.042s)
[548.355181] [069/156] (472s left) kms_frontbuffer_tracking (psrhdr-rgb101010-draw-mmap-wc)
[548.463661] Starting subtest: psrhdr-rgb101010-draw-mmap-wc
[548.468632] Subtest psrhdr-rgb101010-draw-mmap-wc: SKIP (0.000s)
[548.548654] [070/156] (472s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[548.641430] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[555.725817] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (7.088s)
[555.839341] [071/156] (465s left) kms_multipipe_modeset (basic-max-pipe-crc-check)
[555.928483] Starting subtest: basic-max-pipe-crc-check
[555.933417] Subtest basic-max-pipe-crc-check: SKIP (0.000s)
[555.976527] [072/156] (465s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[556.068411] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[556.073658] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[556.156298] [073/156] (465s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[556.267438] Starting subtest: 2x-long-flip-vs-cursor-atomic
[556.272818] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.001s)
[556.414099] [074/156] (465s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-indfb-draw-mmap-wc)
[556.506450] Starting subtest: hdr-1p-offscreen-pri-indfb-draw-mmap-wc
[556.511568] Subtest hdr-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[556.612320] [075/156] (464s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-submitted-signaled)
[556.657279] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[556.659663] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[556.774172] [076/156] (464s left) kms_rotation_crc (primary-x-tiled-reflect-x-0)
[556.863305] Starting subtest: primary-x-tiled-reflect-x-0
[563.970169] Subtest primary-x-tiled-reflect-x-0: SUCCESS (7.105s)
[565.236462] [077/156] (456s left) gem_exec_schedule (u-semaphore-resolve)
[565.316386] Starting subtest: u-semaphore-resolve
[565.341947] Subtest u-semaphore-resolve: SUCCESS (0.037s)
[565.669463] [078/156] (455s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[565.765172] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[565.770060] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[565.866805] [079/156] (455s left) kms_hdmi_inject (inject-4k)
[566.209443] Starting subtest: inject-4k
[566.561638] Subtest inject-4k: SUCCESS (0.354s)
[567.107398] [080/156] (454s left) kms_flip (flip-vs-wf_vblank-interruptible)
[567.203422] Starting subtest: flip-vs-wf_vblank-interruptible
[567.206053] Starting dynamic subtest: A-eDP1
[569.088126] Dynamic subtest A-eDP1: SUCCESS (1.883s)
[569.101242] Starting dynamic subtest: B-eDP1
[570.976640] Dynamic subtest B-eDP1: SUCCESS (1.885s)
[570.990921] Starting dynamic subtest: C-eDP1
[572.876662] Dynamic subtest C-eDP1: SUCCESS (1.895s)
[572.889156] Starting dynamic subtest: D-eDP1
[574.772943] Dynamic subtest D-eDP1: SUCCESS (1.896s)
[574.779625] Subtest flip-vs-wf_vblank-interruptible: SUCCESS (7.572s)
[576.003223] [081/156] (445s left) syncobj_wait (invalid-multi-wait-unsubmitted)
[576.061111] Starting subtest: invalid-multi-wait-unsubmitted
[576.063624] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[576.163254] [082/156] (445s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[576.261190] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc
[576.266565] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[576.341929] [083/156] (445s left) kms_fbcon_fbt (fbc-suspend)
[576.390278] Starting subtest: fbc-suspend
[625.251578] Subtest fbc-suspend: SUCCESS (19.048s)
[626.619229] [084/156] (395s left) kms_ccs (random-ccs-data-4-tiled-lnl-ccs)
[626.721653] Starting subtest: random-ccs-data-4-tiled-lnl-ccs
[626.726037] Subtest random-ccs-data-4-tiled-lnl-ccs: SKIP (0.000s)
[627.853917] [085/156] (394s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[627.910589] Starting subtest: x-tiled-to-vebox-y-tiled
[627.915387] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[628.094088] [086/156] (393s left) perf_pmu (busy-idle-no-semaphores)
[628.137725] Starting subtest: busy-idle-no-semaphores
[628.139609] Starting dynamic subtest: rcs0
[628.797143] Dynamic subtest rcs0: SUCCESS (0.657s)
[628.808736] Starting dynamic subtest: bcs0
[629.451179] Dynamic subtest bcs0: SUCCESS (0.650s)
[629.464779] Starting dynamic subtest: vcs0
[630.106898] Dynamic subtest vcs0: SUCCESS (0.651s)
[630.120753] Starting dynamic subtest: vcs1
[631.618909] Dynamic subtest vcs1: SUCCESS (1.507s)
[631.635712] Starting dynamic subtest: vecs0
[633.669819] Dynamic subtest vecs0: SUCCESS (2.045s)
[633.682610] Starting dynamic subtest: ccs0
[635.654357] Dynamic subtest ccs0: SUCCESS (1.978s)
[635.657223] Subtest busy-idle-no-semaphores: SUCCESS (7.503s)
[635.787080] [087/156] (386s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-shrfb-draw-render)
[635.893539] Starting subtest: hdr-1p-offscreen-pri-shrfb-draw-render
[635.898035] Subtest hdr-1p-offscreen-pri-shrfb-draw-render: SKIP (0.000s)
[635.991863] [088/156] (386s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[636.080610] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[636.083029] Starting dynamic subtest: pipe-A-default-mode
[638.829957] Dynamic subtest pipe-A-default-mode: SUCCESS (2.748s)
[638.832340] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SUCCESS (2.749s)
[639.638118] [089/156] (382s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-shrfb-msflip-blt)
[639.736515] Starting subtest: fbcpsrhdr-1p-primscrn-shrfb-msflip-blt
[639.740938] Subtest fbcpsrhdr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[639.859093] [090/156] (382s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[639.953954] Starting subtest: plane-upscale-20x20-with-rotation
[639.956259] Starting dynamic subtest: pipe-A
[640.179144] Dynamic subtest pipe-A: SUCCESS (0.227s)
[640.190926] Starting dynamic subtest: pipe-B
[641.487780] Dynamic subtest pipe-B: SUCCESS (1.307s)
[641.499503] Starting dynamic subtest: pipe-C
[642.790428] Dynamic subtest pipe-C: SUCCESS (1.301s)
[642.801619] Starting dynamic subtest: pipe-D
[644.084903] Dynamic subtest pipe-D: SUCCESS (1.293s)
[644.087209] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.130s)
[645.242276] [091/156] (377s left) kms_flip (dpms-off-confusion)
[645.339809] Starting subtest: dpms-off-confusion
[645.342183] Starting dynamic subtest: A-eDP1
[654.010060] Dynamic subtest A-eDP1: SUCCESS (8.664s)
[654.023926] Starting dynamic subtest: B-eDP1
[662.727447] Dynamic subtest B-eDP1: SUCCESS (8.714s)
[662.738905] Starting dynamic subtest: C-eDP1
[671.409760] Dynamic subtest C-eDP1: SUCCESS (8.677s)
[671.421210] Starting dynamic subtest: D-eDP1
[680.115485] Dynamic subtest D-eDP1: SUCCESS (8.700s)
[680.121495] Subtest dpms-off-confusion: SUCCESS (34.766s)
[681.363975] [092/156] (340s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-sf)
[681.464508] Starting subtest: fbc-pr-cursor-plane-move-continuous-sf
[681.468868] Subtest fbc-pr-cursor-plane-move-continuous-sf: SKIP (0.000s)
[681.538175] [093/156] (340s left) kms_pm_rpm (modeset-lpsp-stress-no-wait)
[683.172260] Starting subtest: modeset-lpsp-stress-no-wait
[693.694625] Subtest modeset-lpsp-stress-no-wait: SUCCESS (10.519s)
[693.775510] [094/156] (328s left) gen7_exec_parse (basic-offset)
[693.827445] Subtest basic-offset: SKIP (0.000s)
[693.898966] [095/156] (328s left) i915_pm_freq_api (freq-suspend)
[693.963167] Starting subtest: freq-suspend
[693.965067] Starting dynamic subtest: gt0
[710.167676] Dynamic subtest gt0: SUCCESS (1.238s)
[710.185913] Starting dynamic subtest: gt1
[726.175112] Dynamic subtest gt1: SUCCESS (1.226s)
[726.177186] Subtest freq-suspend: SUCCESS (2.466s)
[726.261638] [096/156] (296s left) gem_ctx_param (invalid-param-get)
[726.330805] Starting subtest: invalid-param-get
[726.333015] Subtest invalid-param-get: SUCCESS (0.001s)
[726.445410] [097/156] (296s left) gem_eio (in-flight-10ms)
[726.655581] Starting subtest: in-flight-10ms
[735.365943] Subtest in-flight-10ms: SUCCESS (8.668s)
[735.498081] [098/156] (287s left) kms_atomic_transition (plane-all-transition-nonblocking-fencing)
[735.601580] Starting subtest: plane-all-transition-nonblocking-fencing
[735.603870] Starting dynamic subtest: pipe-A-eDP-1
[738.101990] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.497s)
[738.410965] Starting dynamic subtest: pipe-B-eDP-1
[741.635378] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.231s)
[741.935273] Subtest plane-all-transition-nonblocking-fencing: SUCCESS (6.321s)
[742.849431] [099/156] (279s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[742.963768] Starting subtest: yf-tiled-16bpp-rotate-180
[742.967989] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[743.074793] [100/156] (279s left) api_intel_bb (full-batch)
[743.132295] Starting subtest: full-batch
[743.136976] Subtest full-batch: SUCCESS (0.006s)
[743.271659] [101/156] (279s left) kms_frontbuffer_tracking (fbchdr-rgb101010-draw-blt)
[743.369571] Starting subtest: fbchdr-rgb101010-draw-blt
[743.374095] Subtest fbchdr-rgb101010-draw-blt: SKIP (0.000s)
[743.458683] [102/156] (279s left) kms_psr2_su (page_flip-xrgb8888)
[743.528093] Subtest page_flip-XRGB8888: SKIP (0.000s)
[743.587067] [103/156] (279s left) kms_psr (fbc-psr-cursor-render)
[743.678552] Starting subtest: fbc-psr-cursor-render
[743.680805] Starting dynamic subtest: eDP-1
[743.684922] Dynamic subtest eDP-1: SKIP (0.000s)
[743.688911] Subtest fbc-psr-cursor-render: SKIP (0.001s)
[743.757511] [104/156] (279s left) kms_universal_plane (universal-plane-sanity)
[743.843397] Starting subtest: universal-plane-sanity
[743.845329] Starting dynamic subtest: pipe-A-eDP-1
[744.449749] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.606s)
[744.462391] Starting dynamic subtest: pipe-B-eDP-1
[745.721297] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.268s)
[745.733053] Starting dynamic subtest: pipe-C-eDP-1
[746.897575] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.174s)
[746.912425] Starting dynamic subtest: pipe-D-eDP-1
[748.092173] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.190s)
[748.094341] Subtest universal-plane-sanity: SUCCESS (4.244s)
[748.928430] [105/156] (273s left) gem_mmap_gtt (basic-small-bo-tiledy)
[748.976292] Subtest basic-small-bo-tiledY: SKIP (0.000s)
[749.032426] [106/156] (273s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-blt)
[749.135113] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-blt
[749.139586] Subtest fbc-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[749.236246] [107/156] (273s left) kms_big_fb (y-tiled-16bpp-rotate-0)
[749.336911] Starting subtest: y-tiled-16bpp-rotate-0
[749.341373] Subtest y-tiled-16bpp-rotate-0: SKIP (0.000s)
[749.438847] [108/156] (273s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[749.530153] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen
[756.279853] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (6.749s)
[756.422038] [109/156] (266s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-indfb-plflip-blt)
[756.531319] Starting subtest: fbchdr-1p-primscrn-indfb-plflip-blt
[756.535755] Subtest fbchdr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[756.636670] [110/156] (266s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-render)
[756.732272] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-render
[756.736259] Subtest psrhdr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[756.836592] [111/156] (266s left) syncobj_eventfd (invalid-bad-flags)
[756.898228] Starting subtest: invalid-bad-flags
[756.900593] Subtest invalid-bad-flags: SUCCESS (0.000s)
[757.000291] [112/156] (266s left) kms_cursor_crc (cursor-offscreen-max-size)
[757.341060] Starting subtest: cursor-offscreen-max-size
[757.347169] Subtest cursor-offscreen-max-size: SKIP (0.000s)
[757.462352] [113/156] (265s left) kms_flip (basic-flip-vs-dpms)
[757.560918] Starting subtest: basic-flip-vs-dpms
[757.562676] Starting dynamic subtest: A-eDP1
[760.090780] Dynamic subtest A-eDP1: SUCCESS (2.527s)
[760.106699] Starting dynamic subtest: B-eDP1
[762.318222] Dynamic subtest B-eDP1: SUCCESS (2.223s)
[762.330780] Starting dynamic subtest: C-eDP1
[764.475192] Dynamic subtest C-eDP1: SUCCESS (2.156s)
[764.488192] Starting dynamic subtest: D-eDP1
[766.680554] Dynamic subtest D-eDP1: SUCCESS (2.203s)
[766.682863] Subtest basic-flip-vs-dpms: SUCCESS (9.119s)
[767.547113] [114/156] (255s left) kms_pm_dc (dc3co-vpb-simulation)
[768.667496] Starting subtest: dc3co-vpb-simulation
[768.669891] Starting dynamic subtest: psr2
[768.674057] Dynamic subtest psr2: SKIP (0.000s)
[768.686596] Starting dynamic subtest: pr
[768.691070] Dynamic subtest pr: SKIP (0.000s)
[768.695735] Subtest dc3co-vpb-simulation: SKIP (0.002s)
[768.759739] [115/156] (254s left) gem_eio (wait-wedge-immediate)
[768.992424] Starting subtest: wait-wedge-immediate
[770.840410] Subtest wait-wedge-immediate: SUCCESS (1.847s)
[770.995725] [116/156] (252s left) kms_psr (pr-primary-mmap-cpu)
[771.087601] Starting subtest: pr-primary-mmap-cpu
[771.092132] Subtest pr-primary-mmap-cpu: SKIP (0.000s)
[771.162276] [117/156] (252s left) kms_feature_discovery (psr1)
[771.254513] Starting subtest: psr1
[771.256853] Subtest psr1: SUCCESS (0.000s)
[771.330078] [118/156] (252s left) syncobj_timeline (invalid-wait-bad-flags)
[771.376116] Starting subtest: invalid-wait-bad-flags
[771.378551] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[771.470559] [119/156] (252s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[771.560144] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu
[771.564597] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[771.661905] [120/156] (251s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[771.747623] Starting subtest: extended-pageflip-modeset-hang-oldfb
[771.750245] Starting dynamic subtest: pipe-A
[779.653061] Dynamic subtest pipe-A: SUCCESS (7.901s)
[779.665698] Starting dynamic subtest: pipe-D
[788.321881] Dynamic subtest pipe-D: SUCCESS (8.663s)
[788.324158] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.569s)
[789.145145] [121/156] (234s left) gem_create (create-ext-cpu-access-big)
[789.322260] Starting subtest: create-ext-cpu-access-big
[789.327010] Subtest create-ext-cpu-access-big: SKIP (0.001s)
[789.396567] [122/156] (234s left) gem_softpin (32b-excludes-last-page)
[789.458406] Starting subtest: 32b-excludes-last-page
[789.463994] Subtest 32b-excludes-last-page: SUCCESS (0.003s)
[789.589215] [123/156] (234s left) kms_psr (psr-cursor-render)
[789.690721] Starting subtest: psr-cursor-render
[789.693005] Starting dynamic subtest: eDP-1
[791.405149] Dynamic subtest eDP-1: SUCCESS (1.713s)
[791.407184] Subtest psr-cursor-render: SUCCESS (1.714s)
[792.242632] [124/156] (231s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-render)
[792.335314] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-render
[792.339773] Subtest hdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[792.425408] [125/156] (231s left) prime_mmap (test_dup)
[792.472587] Starting subtest: test_dup
[792.474953] Starting dynamic subtest: test_dup-smem
[792.505872] Dynamic subtest test_dup-smem: SUCCESS (0.033s)
[792.508326] Subtest test_dup: SUCCESS (0.035s)
[792.585657] [126/156] (231s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-onoff)
[792.692217] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-onoff
[792.696191] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[792.806236] [127/156] (231s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[792.900657] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[792.904890] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[793.008950] [128/156] (230s left) kms_getfb (getfb2-accept-nv12)
[793.101223] Starting subtest: getfb2-accept-nv12
[793.103535] Subtest getfb2-accept-nv12: SUCCESS (0.001s)
[793.214719] [129/156] (230s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled)
[793.263186] Starting subtest: multi-wait-all-for-submit-submitted-signaled
[793.365742] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.102s)
[793.444590] [130/156] (230s left) syncobj_wait (single-wait-submitted)
[793.491143] Starting subtest: single-wait-submitted
[793.597938] Subtest single-wait-submitted: SUCCESS (0.104s)
[793.694421] [131/156] (230s left) kms_big_fb (4-tiled-addfb-size-offset-overflow)
[793.797202] Starting subtest: 4-tiled-addfb-size-offset-overflow
[793.799499] Subtest 4-tiled-addfb-size-offset-overflow: SUCCESS (0.000s)
[793.893547] [132/156] (230s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-wc)
[793.992777] Starting subtest: fbc-rgb101010-draw-mmap-wc
[796.483563] Subtest fbc-rgb101010-draw-mmap-wc: SUCCESS (2.495s)
[797.655329] [133/156] (226s left) gem_mmap_gtt (fault-concurrent-x)
[797.710568] Subtest fault-concurrent-X: SKIP (0.000s)
[797.769812] [134/156] (226s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[797.869825] Starting subtest: fbchdr-2p-primscrn-cur-indfb-draw-mmap-gtt
[797.873878] Subtest fbchdr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[797.973400] [135/156] (226s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-indfb-msflip-blt)
[798.067430] Starting subtest: fbcpsrhdr-2p-scndscrn-indfb-msflip-blt
[798.071508] Subtest fbcpsrhdr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[798.177698] [136/156] (226s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-render)
[798.274116] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-render
[798.278540] Subtest psrhdr-1p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[798.382201] [137/156] (225s left) gem_exec_reloc (basic-write-gtt)
[798.434411] Subtest basic-write-gtt: SKIP (0.000s)
[798.509226] [138/156] (225s left) i915_drm_fdinfo (virtual-busy-all)
[798.549823] Starting subtest: virtual-busy-all
[798.554237] Subtest virtual-busy-all: SKIP (0.000s)
[798.619161] [139/156] (225s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-render)
[798.715841] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-render
[798.719201] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[798.806639] [140/156] (225s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-cpu)
[798.912182] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-cpu
[798.915423] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[799.017151] [141/156] (225s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[799.122342] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[799.125763] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[799.240477] [142/156] (225s left) kms_big_fb (4-tiled-16bpp-rotate-0)
[799.346367] Starting subtest: 4-tiled-16bpp-rotate-0
[800.639323] Subtest 4-tiled-16bpp-rotate-0: SUCCESS (1.292s)
[800.773204] [143/156] (223s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[800.856675] Starting subtest: bad-rotation-90-yf-tiled-ccs
[800.858641] Starting dynamic subtest: pipe-A-eDP-1
[800.878613] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[800.892263] Starting dynamic subtest: pipe-B-eDP-1
[800.910675] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[800.921393] Starting dynamic subtest: pipe-C-eDP-1
[800.939910] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[800.950907] Starting dynamic subtest: pipe-D-eDP-1
[800.969876] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[800.973313] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.004s)
[803.152362] [144/156] (221s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc)
[803.245398] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc
[803.250001] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[803.356916] [145/156] (221s left) gem_ctx_param (set-priority-invalid-size)
[803.406562] Starting subtest: set-priority-invalid-size
[803.408690] Subtest set-priority-invalid-size: SUCCESS (0.000s)
[803.498930] [146/156] (221s left) prime_vgem (basic-fence-mmap)
[803.578697] Starting subtest: basic-fence-mmap
[803.583122] Subtest basic-fence-mmap: SKIP (0.000s)
[803.671493] [147/156] (221s left) gem_exec_async (concurrent-writes)
[803.728260] Starting subtest: concurrent-writes
[803.730552] Starting dynamic subtest: rcs0
[803.744154] Dynamic subtest rcs0: SUCCESS (0.016s)
[803.756239] Starting dynamic subtest: bcs0
[803.758564] Dynamic subtest bcs0: SUCCESS (0.007s)
[803.769794] Starting dynamic subtest: vcs0
[803.771979] Dynamic subtest vcs0: SUCCESS (0.005s)
[803.783247] Starting dynamic subtest: vcs1
[803.785458] Dynamic subtest vcs1: SUCCESS (0.005s)
[803.796618] Starting dynamic subtest: vecs0
[803.798798] Dynamic subtest vecs0: SUCCESS (0.006s)
[803.809885] Starting dynamic subtest: ccs0
[803.812291] Dynamic subtest ccs0: SUCCESS (0.006s)
[803.814606] Subtest concurrent-writes: SUCCESS (0.049s)
[804.025954] [148/156] (220s left) syncobj_timeline (single-wait-all-signaled)
[804.073106] Starting subtest: single-wait-all-signaled
[804.075122] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[804.165399] [149/156] (220s left) gem_exec_balancer (sliced)
[804.218766] Starting subtest: sliced
[804.222459] Subtest sliced: SKIP (0.000s)
[804.519098] [150/156] (220s left) prime_mmap (test_forked)
[804.581702] Starting subtest: test_forked
[804.584078] Starting dynamic subtest: test_forked-smem
[804.619052] Dynamic subtest test_forked-smem: SUCCESS (0.039s)
[804.621679] Subtest test_forked: SUCCESS (0.041s)
[804.740798] [151/156] (220s left) kms_chamelium_edid (dp-edid-stress-resolution-4k)
[805.130849] Subtest dp-edid-stress-resolution-4k: SKIP (0.000s)
[805.928439] [152/156] (219s left) i915_pm_rc6_residency (rc6-idle)
[805.993780] Starting subtest: rc6-idle
[806.018740] Starting dynamic subtest: gt0-rcs0
[830.470094] Dynamic subtest gt0-rcs0: SUCCESS (24.448s)
[830.481981] Starting dynamic subtest: gt0-bcs0
[854.902524] Dynamic subtest gt0-bcs0: SUCCESS (24.433s)
[854.914087] Starting dynamic subtest: gt0-ccs0
[879.361809] Dynamic subtest gt0-ccs0: SUCCESS (24.454s)
[879.375929] Starting dynamic subtest: gt1-vcs0
[885.535864] Dynamic subtest gt1-vcs0: SUCCESS (6.168s)
[885.551066] Starting dynamic subtest: gt1-vecs0
[891.708580] Dynamic subtest gt1-vecs0: SUCCESS (6.168s)
[891.711209] Subtest rc6-idle: SUCCESS (85.709s)
[891.793724] [153/156] (133s left) gem_exec_parallel (fds)
[891.850881] Starting subtest: fds
[891.853074] Starting dynamic subtest: rcs0
[896.181545] Dynamic subtest rcs0: SUCCESS (4.332s)
[896.195579] Starting dynamic subtest: bcs0
[900.326060] Dynamic subtest bcs0: SUCCESS (4.141s)
[900.337991] Starting dynamic subtest: vcs0
[904.040560] Dynamic subtest vcs0: SUCCESS (3.710s)
[904.054915] Starting dynamic subtest: vcs1
[908.012303] Dynamic subtest vcs1: SUCCESS (3.968s)
[908.026038] Starting dynamic subtest: vecs0
[912.008285] Dynamic subtest vecs0: SUCCESS (3.994s)
[912.023213] Starting dynamic subtest: ccs0
[915.978103] Dynamic subtest ccs0: SUCCESS (3.966s)
[915.980780] Subtest fds: SUCCESS (24.123s)
[916.181790] [154/156] (108s left) kms_plane (pixel-format-4-tiled-modifier-source-clamping)
[916.274622] Starting subtest: pixel-format-4-tiled-modifier-source-clamping
[916.303389] Starting dynamic subtest: pipe-A-plane-0
[916.714414] Dynamic subtest pipe-A-plane-0: SUCCESS (0.411s)
[916.727075] Starting dynamic subtest: pipe-A-plane-3
[917.048013] Dynamic subtest pipe-A-plane-3: SUCCESS (0.332s)
[918.204803] Starting dynamic subtest: pipe-B-plane-0
[918.605943] Dynamic subtest pipe-B-plane-0: SUCCESS (0.411s)
[918.619056] Starting dynamic subtest: pipe-B-plane-3
[918.941227] Dynamic subtest pipe-B-plane-3: SUCCESS (0.333s)
[919.279172] Subtest pixel-format-4-tiled-modifier-source-clamping: SUCCESS (3.004s)
[920.151368] [155/156] (104s left) gem_mmap_offset (blt-coherency)
[920.204685] Starting subtest: blt-coherency
[920.211237] Subtest blt-coherency: SUCCESS (0.009s)
[920.344676] [156/156] (104s left) kms_plane (plane-position-covered)
[920.432357] Starting subtest: plane-position-covered
[920.963356] Starting dynamic subtest: pipe-A-plane-1
[922.298383] Dynamic subtest pipe-A-plane-1: SUCCESS (1.335s)
[922.310371] Starting dynamic subtest: pipe-A-plane-2
[923.609624] Dynamic subtest pipe-A-plane-2: SUCCESS (1.309s)
[923.622173] Starting dynamic subtest: pipe-A-plane-3
[924.879556] Dynamic subtest pipe-A-plane-3: SUCCESS (1.271s)
[924.893476] Starting dynamic subtest: pipe-A-plane-4
[926.201725] Dynamic subtest pipe-A-plane-4: SUCCESS (1.318s)
[926.214361] Starting dynamic subtest: pipe-A-plane-5
[926.227417] Dynamic subtest pipe-A-plane-5: SUCCESS (0.026s)
[927.427152] Starting dynamic subtest: pipe-B-plane-1
[928.705692] Dynamic subtest pipe-B-plane-1: SUCCESS (1.287s)
[928.719892] Starting dynamic subtest: pipe-B-plane-2
[929.976991] Dynamic subtest pipe-B-plane-2: SUCCESS (1.272s)
[929.991909] Starting dynamic subtest: pipe-B-plane-3
[931.334548] Dynamic subtest pipe-B-plane-3: SUCCESS (1.358s)
[931.349478] Starting dynamic subtest: pipe-B-plane-4
[932.602513] Dynamic subtest pipe-B-plane-4: SUCCESS (1.267s)
[932.615115] Starting dynamic subtest: pipe-B-plane-5
[932.632093] Dynamic subtest pipe-B-plane-5: SUCCESS (0.027s)
[932.634346] Subtest plane-position-covered: SUCCESS (12.196s)
[933.452453] Closing watchdogs
results: parsing output: 0/ for test: kms_addfb_basic
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: gem_userptr_blits
results: parsing output: 3/ for test: api_intel_bb
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: kms_flip
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: perf
results: parsing output: 8/ for test: kms_flip
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: gem_exec_gttfill
results: parsing output: 11/ for test: gem_exec_balancer
results: parsing output: 12/ for test: kms_invalid_mode
results: parsing output: 13/ for test: gem_ctx_persistence
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: perf
results: parsing output: 16/ for test: gen9_exec_parse
results: parsing output: 17/ for test: gem_spin_batch
results: parsing output: 18/ for test: kms_color
results: parsing output: 19/ for test: gem_pxp
results: parsing output: 20/ for test: kms_big_fb
results: parsing output: 21/ for test: gem_ctx_persistence
results: parsing output: 22/ for test: gem_exec_whisper
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: kms_plane
results: parsing output: 25/ for test: kms_psr2_sf
results: parsing output: 26/ for test: gem_exec_whisper
results: parsing output: 27/ for test: gem_exec_fence
results: parsing output: 28/ for test: kms_plane_alpha_blend
results: parsing output: 29/ for test: kms_flip_scaled_crc
results: parsing output: 30/ for test: syncobj_timeline
results: parsing output: 31/ for test: kms_async_flips
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_ccs
results: parsing output: 34/ for test: gem_busy
results: parsing output: 35/ for test: perf
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: gem_exec_schedule
results: parsing output: 38/ for test: kms_cursor_crc
results: parsing output: 39/ for test: kms_ccs
results: parsing output: 40/ for test: kms_psr
results: parsing output: 41/ for test: prime_busy
results: parsing output: 42/ for test: kms_cursor_edge_walk
results: parsing output: 43/ for test: kms_chamelium_hpd
results: parsing output: 44/ for test: intel_hwmon
results: parsing output: 45/ for test: kms_big_fb
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_cursor_legacy
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: device_reset
results: parsing output: 51/ for test: kms_vblank
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_big_fb
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: kms_ccs
results: parsing output: 56/ for test: gem_mmap_gtt
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: gem_ctx_param
results: parsing output: 59/ for test: gem_eio
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_psr
results: parsing output: 62/ for test: gem_userptr_blits
results: parsing output: 63/ for test: kms_content_protection
results: parsing output: 64/ for test: syncobj_wait
results: parsing output: 65/ for test: kms_atomic_transition
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: sysfs_heartbeat_interval
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_multipipe_modeset
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: kms_cursor_legacy
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: syncobj_timeline
results: parsing output: 75/ for test: kms_rotation_crc
results: parsing output: 76/ for test: gem_exec_schedule
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_hdmi_inject
results: parsing output: 79/ for test: kms_flip
results: parsing output: 80/ for test: syncobj_wait
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_fbcon_fbt
results: parsing output: 83/ for test: kms_ccs
results: parsing output: 84/ for test: gem_render_copy
results: parsing output: 85/ for test: perf_pmu
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_flip_scaled_crc
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_plane_scaling
results: parsing output: 90/ for test: kms_flip
results: parsing output: 91/ for test: kms_psr2_sf
results: parsing output: 92/ for test: kms_pm_rpm
results: parsing output: 93/ for test: gen7_exec_parse
results: parsing output: 94/ for test: i915_pm_freq_api
results: parsing output: 95/ for test: gem_ctx_param
results: parsing output: 96/ for test: gem_eio
results: parsing output: 97/ for test: kms_atomic_transition
results: parsing output: 98/ for test: kms_big_fb
results: parsing output: 99/ for test: api_intel_bb
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_psr2_su
results: parsing output: 102/ for test: kms_psr
results: parsing output: 103/ for test: kms_universal_plane
results: parsing output: 104/ for test: gem_mmap_gtt
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_big_fb
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: syncobj_eventfd
results: parsing output: 111/ for test: kms_cursor_crc
results: parsing output: 112/ for test: kms_flip
results: parsing output: 113/ for test: kms_pm_dc
results: parsing output: 114/ for test: gem_eio
results: parsing output: 115/ for test: kms_psr
results: parsing output: 116/ for test: kms_feature_discovery
results: parsing output: 117/ for test: syncobj_timeline
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_busy
results: parsing output: 120/ for test: gem_create
results: parsing output: 121/ for test: gem_softpin
results: parsing output: 122/ for test: kms_psr
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: prime_mmap
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_getfb
results: parsing output: 128/ for test: syncobj_wait
results: parsing output: 129/ for test: syncobj_wait
results: parsing output: 130/ for test: kms_big_fb
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: gem_mmap_gtt
results: parsing output: 133/ for test: kms_frontbuffer_tracking
results: parsing output: 134/ for test: kms_frontbuffer_tracking
results: parsing output: 135/ for test: kms_frontbuffer_tracking
results: parsing output: 136/ for test: gem_exec_reloc
results: parsing output: 137/ for test: i915_drm_fdinfo
results: parsing output: 138/ for test: kms_frontbuffer_tracking
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: kms_frontbuffer_tracking
results: parsing output: 141/ for test: kms_big_fb
results: parsing output: 142/ for test: kms_ccs
results: parsing output: 143/ for test: kms_frontbuffer_tracking
results: parsing output: 144/ for test: gem_ctx_param
results: parsing output: 145/ for test: prime_vgem
results: parsing output: 146/ for test: gem_exec_async
results: parsing output: 147/ for test: syncobj_timeline
results: parsing output: 148/ for test: gem_exec_balancer
results: parsing output: 149/ for test: prime_mmap
results: parsing output: 150/ for test: kms_chamelium_edid
results: parsing output: 151/ for test: i915_pm_rc6_residency
results: parsing output: 152/ for test: gem_exec_parallel
results: parsing output: 153/ for test: kms_plane
results: parsing output: 154/ for test: gem_mmap_offset
results: parsing output: 155/ for test: kms_plane
Done.