[45.460756] Initializing watchdogs
[45.460822]   /dev/watchdog0
[45.477738] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:9a49 Intel Tigerlake (Gen12) TigerLake-LP GT2 [Iris Xe Graphics]
[45.484048] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[45.487288] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[45.490247] [001/156] (960s left) kms_content_protection (dp-mst-lic-type-1)
[45.607871] Starting subtest: dp-mst-lic-type-1
[45.610348] Subtest dp-mst-lic-type-1: SKIP (0.000s)
[45.695695] [002/156] (960s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted)
[45.739042] Starting subtest: etime-multi-wait-all-available-unsubmitted
[45.845144] Subtest etime-multi-wait-all-available-unsubmitted: SUCCESS (0.107s)
[45.905489] [003/156] (960s left) gem_exec_params (no-bsd)
[45.950712] Starting subtest: no-bsd
[45.951488] Subtest no-bsd: SUCCESS (0.001s)
[46.062759] [004/156] (960s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[46.157188] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt
[46.158573] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[46.254863] [005/156] (959s left) kms_psr (fbc-pr-dpms)
[46.346370] Subtest fbc-pr-dpms: SKIP (0.000s)
[46.387995] [006/156] (959s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[46.481463] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu
[46.482871] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[46.568593] [007/156] (959s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[46.659062] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[46.660414] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[46.751389] [008/156] (959s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[46.891042] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[46.983483] [009/156] (959s left) i915_drm_fdinfo (busy)
[47.024355] Starting subtest: busy
[47.025131] Starting dynamic subtest: rcs0
[47.547355] Dynamic subtest rcs0: SUCCESS (0.534s)
[47.556448] Starting dynamic subtest: bcs0
[48.071335] Dynamic subtest bcs0: SUCCESS (0.533s)
[48.080851] Starting dynamic subtest: vcs0
[48.595347] Dynamic subtest vcs0: SUCCESS (0.533s)
[48.604593] Starting dynamic subtest: vcs1
[49.125578] Dynamic subtest vcs1: SUCCESS (0.538s)
[49.134648] Starting dynamic subtest: vecs0
[49.648224] Dynamic subtest vecs0: SUCCESS (0.531s)
[49.649160] Subtest busy: SUCCESS (2.672s)
[49.735355] [010/156] (956s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[49.824546] Starting subtest: flip-vs-cursor-busy-crc-atomic
[50.164009] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.344s)
[50.343153] [011/156] (956s left) drm_read (short-buffer-wakeup)
[50.458599] Starting subtest: short-buffer-wakeup
[82.584846] Subtest short-buffer-wakeup: SUCCESS (32.214s)
[82.695896] [012/156] (923s left) kms_busy (extended-pageflip-hang-newfb)
[82.789597] Starting subtest: extended-pageflip-hang-newfb
[82.790364] Starting dynamic subtest: pipe-A
[96.940401] Dynamic subtest pipe-A: SUCCESS (14.150s)
[96.949280] Starting dynamic subtest: pipe-D
[110.754974] Dynamic subtest pipe-D: SUCCESS (13.812s)
[110.756592] Subtest extended-pageflip-hang-newfb: SUCCESS (27.968s)
[110.950693] [013/156] (895s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[110.989890] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[111.093594] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.104s)
[111.156620] [014/156] (895s left) kms_getfb (getfb-handle-valid)
[111.246430] Starting subtest: getfb-handle-valid
[111.247173] Subtest getfb-handle-valid: SUCCESS (0.000s)
[111.352779] [015/156] (895s left) kms_lease (setcrtc-implicit-plane)
[111.446770] Starting subtest: setcrtc-implicit-plane
[111.447501] Starting dynamic subtest: pipe-A-HDMI-A-1
[111.641652] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.194s)
[111.648958] Starting dynamic subtest: pipe-B-HDMI-A-1
[111.809838] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.167s)
[111.818290] Starting dynamic subtest: pipe-C-HDMI-A-1
[111.982866] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.172s)
[111.991268] Starting dynamic subtest: pipe-D-HDMI-A-1
[112.179558] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.195s)
[112.182506] Subtest setcrtc-implicit-plane: SUCCESS (0.734s)
[112.361982] [016/156] (894s left) gem_wait (busy)
[112.410283] Starting subtest: busy
[112.411021] Starting dynamic subtest: all-engines
[112.931133] Dynamic subtest all-engines: SUCCESS (0.521s)
[112.937408] Starting dynamic subtest: rcs0
[113.455197] Dynamic subtest rcs0: SUCCESS (0.523s)
[113.461816] Starting dynamic subtest: bcs0
[113.970898] Dynamic subtest bcs0: SUCCESS (0.515s)
[113.977496] Starting dynamic subtest: vcs0
[114.484908] Dynamic subtest vcs0: SUCCESS (0.514s)
[114.491486] Starting dynamic subtest: vcs1
[114.997858] Dynamic subtest vcs1: SUCCESS (0.513s)
[115.004386] Starting dynamic subtest: vecs0
[115.514104] Dynamic subtest vecs0: SUCCESS (0.516s)
[115.514859] Subtest busy: SUCCESS (3.104s)
[115.705539] [017/156] (890s left) gem_eio (wait-wedge-1us)
[115.753453] Starting subtest: wait-wedge-1us
[116.008646] Subtest wait-wedge-1us: SUCCESS (0.255s)
[116.128142] [018/156] (890s left) kms_cursor_crc (cursor-tearing-framebuffer-change)
[116.218585] Starting subtest: cursor-tearing-framebuffer-change
[116.219311] Starting dynamic subtest: pipe-A-HDMI-A-1
[116.469769] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.250s)
[116.477231] Starting dynamic subtest: pipe-D-HDMI-A-1
[116.688794] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.218s)
[116.690270] Subtest cursor-tearing-framebuffer-change: SUCCESS (0.470s)
[116.839804] [019/156] (889s left) kms_async_flips (async-flip-with-page-flip-events-linear-atomic)
[116.930821] Starting subtest: async-flip-with-page-flip-events-linear-atomic
[117.059888] Starting dynamic subtest: pipe-A-HDMI-A-1
[119.155169] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.087s)
[119.287877] Starting dynamic subtest: pipe-B-HDMI-A-1
[121.367284] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.078s)
[121.475796] Starting dynamic subtest: pipe-C-HDMI-A-1
[123.546556] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.069s)
[123.656778] Starting dynamic subtest: pipe-D-HDMI-A-1
[125.738730] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (2.079s)
[125.739485] Subtest async-flip-with-page-flip-events-linear-atomic: SUCCESS (8.800s)
[125.964884] [020/156] (880s left) kms_cursor_legacy (cursorb-vs-flipb-toggle)
[126.059990] Starting subtest: cursorB-vs-flipB-toggle
[126.062362] Subtest cursorB-vs-flipB-toggle: SKIP (0.001s)
[126.128184] [021/156] (880s left) kms_big_fb (yf-tiled-16bpp-rotate-270)
[126.228436] Starting subtest: yf-tiled-16bpp-rotate-270
[126.229908] Subtest yf-tiled-16bpp-rotate-270: SKIP (0.000s)
[126.323482] [022/156] (880s left) kms_flip (bo-too-big)
[126.418507] Starting subtest: bo-too-big
[126.420615] Starting dynamic subtest: A-HDMI-A1
[126.767447] Dynamic subtest A-HDMI-A1: SUCCESS (0.347s)
[126.778554] Starting dynamic subtest: B-HDMI-A1
[127.092842] Dynamic subtest B-HDMI-A1: SUCCESS (0.323s)
[127.102561] Starting dynamic subtest: C-HDMI-A1
[127.415518] Dynamic subtest C-HDMI-A1: SUCCESS (0.323s)
[127.425832] Starting dynamic subtest: D-HDMI-A1
[127.735173] Dynamic subtest D-HDMI-A1: SUCCESS (0.318s)
[127.737669] Subtest bo-too-big: SUCCESS (1.320s)
[127.896765] [023/156] (878s left) gem_mmap_gtt (basic-read)
[127.934807] Starting subtest: basic-read
[127.947378] Subtest basic-read: SUCCESS (0.013s)
[128.039016] [024/156] (878s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-indfb-draw-render)
[128.134540] Starting subtest: hdr-1p-primscrn-pri-indfb-draw-render
[128.136050] Subtest hdr-1p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[128.219610] [025/156] (878s left) syncobj_timeline (multi-wait-all-available-signaled)
[128.253329] Starting subtest: multi-wait-all-available-signaled
[128.254905] Subtest multi-wait-all-available-signaled: SUCCESS (0.001s)
[128.328445] [026/156] (878s left) gem_exec_alignment (forked)
[128.366633] Starting subtest: forked
[166.814713] Subtest forked: SUCCESS (38.440s)
[166.931764] [027/156] (840s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-onoff)
[167.074809] Starting subtest: fbchdr-2p-primscrn-cur-indfb-onoff
[167.076247] Subtest fbchdr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[167.170118] [028/156] (839s left) kms_addfb_basic (unused-offsets)
[167.203608] Starting subtest: unused-offsets
[167.204343] Subtest unused-offsets: SUCCESS (0.000s)
[167.355657] [029/156] (839s left) kms_color (ctm-0-50)
[167.443718] Starting subtest: ctm-0-50
[167.444436] Starting dynamic subtest: pipe-A-HDMI-A-1
[168.305045] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.862s)
[168.312633] Starting dynamic subtest: pipe-B-HDMI-A-1
[169.117460] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.812s)
[169.124666] Starting dynamic subtest: pipe-C-HDMI-A-1
[169.953864] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.835s)
[169.961283] Starting dynamic subtest: pipe-D-HDMI-A-1
[170.770095] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.816s)
[170.770878] Subtest ctm-0-50: SUCCESS (3.327s)
[170.927956] [030/156] (836s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[171.026459] Starting subtest: psr-rgb565-draw-mmap-gtt
[171.027874] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[171.119542] [031/156] (835s left) kms_atomic_interruptible (universal-setplane-primary)
[171.215347] Starting subtest: universal-setplane-primary
[171.217083] Starting dynamic subtest: pipe-A-HDMI-A-1
[177.465545] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (6.249s)
[177.466316] Subtest universal-setplane-primary: SUCCESS (6.251s)
[177.619343] [032/156] (829s left) syncobj_basic (bad-handle-to-fd)
[177.655166] Starting subtest: bad-handle-to-fd
[177.656026] Subtest bad-handle-to-fd: SUCCESS (0.000s)
[177.711106] [033/156] (829s left) gem_mmap_gtt (cpuset-big-copy-odd)
[177.744380] Starting subtest: cpuset-big-copy-odd
[191.760928] Subtest cpuset-big-copy-odd: SUCCESS (14.016s)
[191.844218] [034/156] (815s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[191.990746] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt
[191.992213] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[192.077280] [035/156] (815s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-shrfb-draw-pwrite)
[192.170813] Starting subtest: fbchdr-1p-primscrn-pri-shrfb-draw-pwrite
[192.172221] Subtest fbchdr-1p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[192.263963] [036/156] (815s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[192.364136] Starting subtest: x-tiled-16bpp-rotate-90
[192.446568] Subtest x-tiled-16bpp-rotate-90: SKIP (0.081s)
[192.566937] [037/156] (814s left) kms_cursor_crc (cursor-rapid-movement-64x21)
[192.662131] Starting subtest: cursor-rapid-movement-64x21
[192.666954] Starting dynamic subtest: pipe-A-HDMI-A-1
[192.928590] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.261s)
[192.963643] Starting dynamic subtest: pipe-D-HDMI-A-1
[193.170373] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.213s)
[193.171148] Subtest cursor-rapid-movement-64x21: SUCCESS (0.508s)
[193.341067] [038/156] (814s left) gem_eio (in-flight-suspend)
[193.388502] Starting subtest: in-flight-suspend
[230.415756] Subtest in-flight-suspend: SUCCESS (7.839s)
[240.552312] [FACT gem_eio (in-flight-suspend)] new: kernel.kmod_is_loaded.vgem: true
[240.590499] [039/156] (776s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[240.638893] Starting subtest: writes-after-reads-uncached
[252.717255] Subtest writes-after-reads-uncached: SUCCESS (12.203s)
[252.827445] [040/156] (764s left) gem_exec_schedule (preempt-queue)
[252.874680] Starting subtest: preempt-queue
[252.875371] Starting dynamic subtest: rcs0
[252.981649] Dynamic subtest rcs0: SUCCESS (0.108s)
[252.991341] Starting dynamic subtest: bcs0
[253.096764] Dynamic subtest bcs0: SUCCESS (0.115s)
[253.105837] Starting dynamic subtest: vcs0
[253.200996] Dynamic subtest vcs0: SUCCESS (0.104s)
[253.210559] Starting dynamic subtest: vcs1
[253.307727] Dynamic subtest vcs1: SUCCESS (0.106s)
[253.317232] Starting dynamic subtest: vecs0
[253.415144] Dynamic subtest vecs0: SUCCESS (0.107s)
[253.415981] Subtest preempt-queue: SUCCESS (0.544s)
[253.714686] [041/156] (763s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-onoff)
[253.808319] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-onoff
[253.809700] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[253.904448] [042/156] (763s left) kms_psr (pr-cursor-plane-move)
[253.995482] Subtest pr-cursor-plane-move: SKIP (0.000s)
[254.040584] [043/156] (763s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-mmap-wc)
[254.131347] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-mmap-wc
[254.132847] Subtest fbchdr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[254.238136] [044/156] (763s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[254.330408] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[254.331899] Subtest hdr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[254.422949] [045/156] (763s left) kms_plane_scaling (planes-downscale-factor-0-5-unity-scaling)
[254.510545] Starting subtest: planes-downscale-factor-0-5-unity-scaling
[254.511438] Starting dynamic subtest: pipe-A
[254.658268] Dynamic subtest pipe-A: SUCCESS (0.148s)
[254.667980] Starting dynamic subtest: pipe-B
[254.809402] Dynamic subtest pipe-B: SUCCESS (0.151s)
[254.821299] Starting dynamic subtest: pipe-C
[254.942185] Dynamic subtest pipe-C: SUCCESS (0.132s)
[254.953708] Starting dynamic subtest: pipe-D
[255.075216] Dynamic subtest pipe-D: SUCCESS (0.133s)
[255.075906] Subtest planes-downscale-factor-0-5-unity-scaling: SUCCESS (0.567s)
[255.272758] [046/156] (762s left) gem_eio (wait-1us)
[255.315116] Starting subtest: wait-1us
[255.464840] Subtest wait-1us: SUCCESS (0.150s)
[255.589714] [047/156] (762s left) gem_userptr_blits (process-exit-busy)
[255.635282] Starting subtest: process-exit-busy
[255.648943] Subtest process-exit-busy: SUCCESS (0.014s)
[255.767138] [048/156] (762s left) kms_chamelium_audio (hdmi-audio-edid)
[255.912310] Subtest hdmi-audio-edid: SKIP (0.000s)
[256.016444] [049/156] (761s left) kms_invalid_mode (bad-hsync-end)
[256.110112] Starting subtest: bad-hsync-end
[256.110888] Starting dynamic subtest: pipe-A-HDMI-A-1
[256.164474] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.054s)
[256.174186] Starting dynamic subtest: pipe-B-HDMI-A-1
[256.174897] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.001s)
[256.183210] Starting dynamic subtest: pipe-C-HDMI-A-1
[256.183925] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.001s)
[256.192402] Starting dynamic subtest: pipe-D-HDMI-A-1
[256.193105] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.001s)
[256.193793] Subtest bad-hsync-end: SUCCESS (0.064s)
[256.329824] [050/156] (761s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[256.373354] Starting subtest: yf-tiled-to-vebox-yf-tiled
[256.374100] Starting dynamic subtest: smem
[256.441811] Dynamic subtest smem: SUCCESS (0.068s)
[256.442603] Subtest yf-tiled-to-vebox-yf-tiled: SUCCESS (0.069s)
[256.632113] [051/156] (761s left) gem_partial_pwrite_pread (reads-uncached)
[256.670700] Starting subtest: reads-uncached
[258.411756] Subtest reads-uncached: SUCCESS (1.746s)
[258.517095] [052/156] (759s left) kms_feature_discovery (psr1)
[258.608285] Starting subtest: psr1
[258.609708] Subtest psr1: SKIP (0.000s)
[258.687085] [053/156] (759s left) gem_exec_balancer (sliced)
[258.732004] Starting subtest: sliced
[260.204218] Subtest sliced: SUCCESS (1.467s)
[260.378028] [054/156] (757s left) kms_plane_multiple (2x-tiling-4)
[260.471299] Starting subtest: 2x-tiling-4
[260.472802] Subtest 2x-tiling-4: SKIP (0.000s)
[260.535455] [055/156] (757s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-upscaling)
[260.622941] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-upscaling
[260.624343] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-upscaling: SKIP (0.000s)
[260.696812] [056/156] (757s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions-varying-size)
[260.785425] Starting subtest: short-flip-before-cursor-atomic-transitions-varying-size
[261.313218] Subtest short-flip-before-cursor-atomic-transitions-varying-size: SUCCESS (0.528s)
[261.458226] [057/156] (756s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[261.551419] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[261.552839] Subtest fbchdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[261.638025] [058/156] (756s left) syncobj_timeline (signal-point-0)
[261.672849] Starting subtest: signal-point-0
[261.673580] Subtest signal-point-0: SUCCESS (0.001s)
[261.740452] [059/156] (756s left) kms_frontbuffer_tracking (psr-suspend)
[261.832491] Starting subtest: psr-suspend
[261.833906] Subtest psr-suspend: SKIP (0.000s)
[261.931130] [060/156] (756s left) kms_psr (psr-sprite-plane-onoff)
[262.019714] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[262.066213] [061/156] (756s left) kms_lease (empty-lease)
[262.155100] Starting subtest: empty-lease
[262.155830] Starting dynamic subtest: pipe-A-HDMI-A-1
[262.156550] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.001s)
[262.166876] Starting dynamic subtest: pipe-B-HDMI-A-1
[262.167614] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.001s)
[262.176246] Starting dynamic subtest: pipe-C-HDMI-A-1
[262.176943] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.001s)
[262.185284] Starting dynamic subtest: pipe-D-HDMI-A-1
[262.185987] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.000s)
[262.186677] Subtest empty-lease: SUCCESS (0.010s)
[262.238630] [062/156] (756s left) perf_pmu (enable-race)
[262.276296] Starting subtest: enable-race
[262.277040] Starting dynamic subtest: rcs0
[274.869754] Dynamic subtest rcs0: SUCCESS (12.580s)
[274.887306] Starting dynamic subtest: bcs0
[287.447891] Dynamic subtest bcs0: SUCCESS (12.575s)
[287.463731] Starting dynamic subtest: vcs0
[300.020910] Dynamic subtest vcs0: SUCCESS (12.578s)
[300.038970] Starting dynamic subtest: vcs1
[312.592752] Dynamic subtest vcs1: SUCCESS (12.574s)
[312.610381] Starting dynamic subtest: vecs0
[325.148837] Dynamic subtest vecs0: SUCCESS (12.586s)
[325.149555] Subtest enable-race: SUCCESS (62.897s)
[325.223171] [063/156] (693s left) api_intel_bb (reset-flags)
[325.258415] Starting subtest: reset-flags
[325.264183] Subtest reset-flags: SUCCESS (0.006s)
[325.373359] [064/156] (693s left) gem_exec_whisper (basic-normal-all)
[325.417949] Starting subtest: basic-normal-all
[326.570772] Subtest basic-normal-all: SUCCESS (1.153s)
[326.765353] [065/156] (691s left) gem_mmap_gtt (basic-small-bo-tiledy)
[326.799863] Starting subtest: basic-small-bo-tiledY
[326.927519] Subtest basic-small-bo-tiledY: SUCCESS (0.128s)
[327.053081] [066/156] (691s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[327.210003] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[327.211359] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[327.320057] [067/156] (691s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[327.413136] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[327.414494] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[327.510975] [068/156] (691s left) kms_big_fb (y-tiled-8bpp-rotate-180)
[327.609181] Starting subtest: y-tiled-8bpp-rotate-180
[328.659256] Subtest y-tiled-8bpp-rotate-180: SUCCESS (1.051s)
[328.809671] [069/156] (689s left) kms_dirtyfb (psr-dirtyfb-ioctl)
[328.900023] Starting subtest: psr-dirtyfb-ioctl
[328.904230] Subtest psr-dirtyfb-ioctl: SKIP (0.000s)
[328.980219] [070/156] (689s left) gem_exec_gttfill (basic)
[329.026655] Starting subtest: basic
[332.430284] Subtest basic: SUCCESS (3.402s)
[332.642829] [071/156] (686s left) gem_ctx_persistence (heartbeat-hostile)
[332.748047] Starting subtest: heartbeat-hostile
[334.261719] Subtest heartbeat-hostile: SUCCESS (1.512s)
[334.339149] [072/156] (684s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[334.439594] Starting subtest: fbcpsrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu
[334.440985] Subtest fbcpsrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[334.533931] [073/156] (684s left) gem_ccs (large-ctrl-surf-copy)
[334.574344] Starting subtest: large-ctrl-surf-copy
[334.575850] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[334.633106] [074/156] (684s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-indfb-draw-blt)
[334.725675] Starting subtest: hdr-2p-primscrn-pri-indfb-draw-blt
[334.727146] Subtest hdr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[334.807919] [075/156] (684s left) gem_pxp (hw-rejects-pxp-buffer)
[334.913527] Starting subtest: hw-rejects-pxp-buffer
[334.915792] Subtest hw-rejects-pxp-buffer: SKIP (0.000s)
[334.988228] [076/156] (684s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[335.080482] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[335.081927] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[335.176352] [077/156] (683s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[335.269775] Starting subtest: psrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt
[335.271190] Subtest psrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[335.376683] [078/156] (683s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[335.471756] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[335.473222] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[335.578517] [079/156] (683s left) perf (gen12-invalid-class-instance)
[335.688878] Starting subtest: gen12-invalid-class-instance
[335.699315] Subtest gen12-invalid-class-instance: SUCCESS (0.010s)
[335.740692] [080/156] (683s left) gem_render_copy (mixed-tiled-to-yf-tiled-ccs)
[335.781418] Starting subtest: mixed-tiled-to-yf-tiled-ccs
[335.782192] Starting dynamic subtest: smem
[335.861734] Dynamic subtest smem: SUCCESS (0.080s)
[335.862485] Subtest mixed-tiled-to-yf-tiled-ccs: SUCCESS (0.081s)
[336.041814] [081/156] (683s left) gem_ctx_param (non-root-set)
[336.075900] Starting subtest: non-root-set
[336.093734] Subtest non-root-set: SUCCESS (0.018s)
[336.188146] [082/156] (683s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-msflip-blt)
[336.280591] Starting subtest: fbc-1p-primscrn-indfb-msflip-blt
[337.598076] Subtest fbc-1p-primscrn-indfb-msflip-blt: SUCCESS (1.317s)
[337.718995] [083/156] (681s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-mc-ccs)
[337.810082] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-mc-ccs
[337.811913] Starting dynamic subtest: pipe-A-HDMI-A-1
[337.818950] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.001s)
[337.828611] Starting dynamic subtest: pipe-B-HDMI-A-1
[337.835496] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s)
[337.844745] Starting dynamic subtest: pipe-C-HDMI-A-1
[337.851346] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s)
[337.860393] Starting dynamic subtest: pipe-D-HDMI-A-1
[337.867883] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.001s)
[337.869216] Subtest crc-primary-rotation-180-y-tiled-gen12-mc-ccs: SKIP (0.010s)
[338.064687] [084/156] (681s left) gem_ctx_bad_destroy (invalid-pad)
[338.101931] Starting subtest: invalid-pad
[338.102748] Subtest invalid-pad: SUCCESS (0.000s)
[338.162020] [085/156] (681s left) syncobj_wait (multi-wait-all-for-submit-submitted)
[338.198865] Starting subtest: multi-wait-all-for-submit-submitted
[338.305733] Subtest multi-wait-all-for-submit-submitted: SUCCESS (0.106s)
[338.377644] [086/156] (681s left) syncobj_timeline (wait-all-snapshot)
[338.413250] Starting subtest: wait-all-snapshot
[338.495224] Subtest wait-all-snapshot: SUCCESS (0.082s)
[338.594626] [087/156] (680s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[338.683945] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[338.700077] Starting dynamic subtest: pipe-A-plane-0
[338.916748] Dynamic subtest pipe-A-plane-0: SUCCESS (0.216s)
[338.931582] Starting dynamic subtest: pipe-A-plane-3
[339.091846] Dynamic subtest pipe-A-plane-3: SUCCESS (0.173s)
[339.111517] Starting dynamic subtest: pipe-A-plane-5
[339.258729] Dynamic subtest pipe-A-plane-5: SUCCESS (0.164s)
[339.418918] Starting dynamic subtest: pipe-B-plane-0
[339.592992] Dynamic subtest pipe-B-plane-0: SUCCESS (0.183s)
[339.611817] Starting dynamic subtest: pipe-B-plane-3
[339.759715] Dynamic subtest pipe-B-plane-3: SUCCESS (0.164s)
[339.778767] Starting dynamic subtest: pipe-B-plane-5
[339.926554] Dynamic subtest pipe-B-plane-5: SUCCESS (0.164s)
[340.002037] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SUCCESS (1.316s)
[340.162031] [088/156] (679s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-move)
[340.260426] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-move
[340.261840] Subtest psrhdr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[340.354773] [089/156] (679s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-shrfb-plflip-blt)
[340.447710] Starting subtest: hdr-2p-scndscrn-shrfb-plflip-blt
[340.449136] Subtest hdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[340.545634] [090/156] (679s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-onoff)
[340.638415] Starting subtest: fbc-2p-primscrn-spr-indfb-onoff
[340.639854] Subtest fbc-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[340.728824] [091/156] (678s left) kms_plane_scaling (plane-upscale-20x20-with-pixel-format)
[340.822659] Starting subtest: plane-upscale-20x20-with-pixel-format
[340.823549] Starting dynamic subtest: pipe-A
[341.948451] Dynamic subtest pipe-A: SUCCESS (1.126s)
[341.967165] Starting dynamic subtest: pipe-B
[343.141345] Dynamic subtest pipe-B: SUCCESS (1.191s)
[343.152065] Starting dynamic subtest: pipe-C
[343.359706] Dynamic subtest pipe-C: SUCCESS (0.217s)
[343.371676] Starting dynamic subtest: pipe-D
[343.570029] Dynamic subtest pipe-D: SUCCESS (0.209s)
[343.570813] Subtest plane-upscale-20x20-with-pixel-format: SUCCESS (2.749s)
[343.761631] [092/156] (675s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-downscaling)
[343.854971] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-downscaling
[343.855677] Starting dynamic subtest: pipe-A-valid-mode
[345.118746] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.263s)
[345.119476] Subtest flip-64bpp-xtile-to-32bpp-xtile-downscaling: SUCCESS (1.265s)
[345.282714] [093/156] (674s left) gem_exec_reloc (basic-gtt-read-noreloc)
[345.325139] Starting subtest: basic-gtt-read-noreloc
[345.329749] Subtest basic-gtt-read-noreloc: SUCCESS (0.004s)
[345.447085] [094/156] (674s left) gem_tiled_blits (normal)
[345.584216] Starting subtest: normal
[355.541230] Subtest normal: SUCCESS (9.956s)
[355.647798] [095/156] (664s left) gem_render_copy_redux (flink-interruptible)
[355.747212] Starting subtest: flink-interruptible
[356.536856] Subtest flink-interruptible: SUCCESS (0.791s)
[356.661452] [096/156] (663s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-shrfb-draw-pwrite)
[356.763566] Starting subtest: fbcpsrhdr-1p-offscreen-pri-shrfb-draw-pwrite
[356.764990] Subtest fbcpsrhdr-1p-offscreen-pri-shrfb-draw-pwrite: SKIP (0.000s)
[356.863083] [097/156] (663s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-pwrite)
[356.954415] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-pwrite
[356.955823] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[357.063895] [098/156] (662s left) kms_vrr (lobf)
[357.153817] Starting subtest: lobf
[357.155302] Subtest lobf: SKIP (0.000s)
[357.216297] [099/156] (662s left) kms_plane_alpha_blend (alpha-7efc)
[357.304988] Starting subtest: alpha-7efc
[357.377615] Starting dynamic subtest: pipe-A-HDMI-A-1
[357.672782] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.295s)
[357.711707] Starting dynamic subtest: pipe-D-HDMI-A-1
[358.182676] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.483s)
[358.183390] Subtest alpha-7efc: SUCCESS (0.878s)
[358.352092] [100/156] (661s left) gem_ctx_persistence (engines-mixed-process)
[358.394932] Starting subtest: engines-mixed-process
[358.395668] Starting dynamic subtest: rcs0
[358.761696] Dynamic subtest rcs0: SUCCESS (0.367s)
[358.774184] Starting dynamic subtest: bcs0
[359.114893] Dynamic subtest bcs0: SUCCESS (0.351s)
[359.132812] Starting dynamic subtest: vcs0
[359.472775] Dynamic subtest vcs0: SUCCESS (0.355s)
[359.486028] Starting dynamic subtest: vcs1
[359.825842] Dynamic subtest vcs1: SUCCESS (0.350s)
[359.841200] Starting dynamic subtest: vecs0
[360.185663] Dynamic subtest vecs0: SUCCESS (0.357s)
[360.186725] Subtest engines-mixed-process: SUCCESS (1.792s)
[360.280329] [101/156] (659s left) gem_tiled_partial_pwrite_pread (reads)
[360.320005] Starting subtest: reads
[360.714768] Subtest reads: SUCCESS (0.394s)
[360.856106] [102/156] (659s left) perf (gen8-unprivileged-single-ctx-counters)
[360.948531] Starting subtest: gen8-unprivileged-single-ctx-counters
[360.950019] Subtest gen8-unprivileged-single-ctx-counters: SKIP (0.000s)
[360.989938] [103/156] (659s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[361.084451] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[361.085969] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[361.188074] [104/156] (659s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-onoff)
[361.281132] Starting subtest: fbc-1p-primscrn-cur-indfb-onoff
[362.638454] Subtest fbc-1p-primscrn-cur-indfb-onoff: SUCCESS (1.357s)
[362.778637] [105/156] (657s left) gem_exec_whisper (basic-queues-priority)
[362.822984] Starting subtest: basic-queues-priority
[365.047878] Subtest basic-queues-priority: SUCCESS (2.215s)
[365.231310] [106/156] (655s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-cpu)
[365.325314] Starting subtest: fbc-rgb101010-draw-mmap-cpu
[365.705544] Subtest fbc-rgb101010-draw-mmap-cpu: SUCCESS (0.380s)
[365.830735] [107/156] (654s left) kms_ccs (bad-aux-stride-y-tiled-gen12-rc-ccs-cc)
[365.921566] Starting subtest: bad-aux-stride-y-tiled-gen12-rc-ccs-cc
[365.922324] Starting dynamic subtest: pipe-A-HDMI-A-1
[365.928891] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.002s)
[365.942196] Starting dynamic subtest: pipe-B-HDMI-A-1
[365.948618] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.002s)
[365.962116] Starting dynamic subtest: pipe-C-HDMI-A-1
[365.968386] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.002s)
[365.981175] Starting dynamic subtest: pipe-D-HDMI-A-1
[365.987083] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.002s)
[365.987759] Subtest bad-aux-stride-y-tiled-gen12-rc-ccs-cc: SUCCESS (0.016s)
[366.202098] [108/156] (654s left) kms_psr (fbc-psr2-cursor-plane-move)
[366.291157] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s)
[366.340020] [109/156] (654s left) kms_psr (psr-primary-mmap-cpu)
[366.431029] Subtest psr-primary-mmap-cpu: SKIP (0.000s)
[366.472048] [110/156] (654s left) gem_create (create-size-update)
[366.507249] Starting subtest: create-size-update
[366.507945] Subtest create-size-update: SUCCESS (0.000s)
[366.722822] [111/156] (653s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[366.815088] Starting subtest: psrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[366.816497] Subtest psrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[366.923431] [112/156] (653s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[367.014335] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (0.000s)
[367.063829] [113/156] (653s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-onoff)
[367.157669] Starting subtest: hdr-2p-primscrn-cur-indfb-onoff
[367.159112] Subtest hdr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[367.252920] [114/156] (653s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[367.353853] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip
[367.355276] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[367.445164] [115/156] (653s left) sriov_basic (bind-unbind-vf)
[367.481747] Starting subtest: bind-unbind-vf
[367.485050] Starting dynamic subtest: vf-1
[367.515877] Dynamic subtest vf-1: FAIL (0.021s)
[367.525511] Starting dynamic subtest: vf-2
[367.547751] Dynamic subtest vf-2: FAIL (0.006s)
[367.557317] Starting dynamic subtest: vf-3
[367.594225] Dynamic subtest vf-3: FAIL (0.007s)
[367.604030] Starting dynamic subtest: vf-4
[367.625806] Dynamic subtest vf-4: FAIL (0.006s)
[367.636860] Starting dynamic subtest: vf-5
[367.658556] Dynamic subtest vf-5: FAIL (0.006s)
[367.669509] Starting dynamic subtest: vf-6
[367.690882] Dynamic subtest vf-6: FAIL (0.006s)
[367.699762] Starting dynamic subtest: vf-7
[367.721207] Dynamic subtest vf-7: FAIL (0.008s)
[367.731761] Starting dynamic subtest: vf-random
[367.752993] Dynamic subtest vf-random: FAIL (0.007s)
[367.763446] Starting dynamic subtest: vf-last
[367.786535] Dynamic subtest vf-last: FAIL (0.007s)
[367.787213] Subtest bind-unbind-vf: FAIL (0.084s)
[367.820627] [116/156] (652s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[367.908949] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[367.909664] Starting dynamic subtest: pipe-A-HDMI-A-1
[367.926801] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.002s)
[367.936629] Starting dynamic subtest: pipe-B-HDMI-A-1
[367.954023] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.002s)
[367.963563] Starting dynamic subtest: pipe-C-HDMI-A-1
[367.980426] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.002s)
[367.992043] Starting dynamic subtest: pipe-D-HDMI-A-1
[368.009926] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.002s)
[368.011352] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SKIP (0.013s)
[368.177028] [117/156] (652s left) perf (missing-sample-flags)
[368.253786] Starting subtest: missing-sample-flags
[368.254473] Subtest missing-sample-flags: SUCCESS (0.000s)
[368.300762] [118/156] (652s left) gem_set_tiling_vs_blt (tiled-to-untiled)
[368.339774] Starting subtest: tiled-to-untiled
[368.462218] Subtest tiled-to-untiled: SUCCESS (0.113s)
[368.581405] [119/156] (652s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[368.672295] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[368.673657] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[368.780663] [120/156] (652s left) kms_frontbuffer_tracking (fbcpsrhdr-tiling-linear)
[368.872527] Starting subtest: fbcpsrhdr-tiling-linear
[368.873963] Subtest fbcpsrhdr-tiling-linear: SKIP (0.000s)
[368.982975] [121/156] (651s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[369.074973] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[369.075678] Starting dynamic subtest: pipe-A-valid-mode
[369.910274] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.835s)
[369.911104] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SUCCESS (0.837s)
[370.075598] [122/156] (650s left) kms_pm_rpm (pm-caching)
[371.497283] Starting subtest: pm-caching
[377.668970] Subtest pm-caching: SUCCESS (6.171s)
[377.840532] [123/156] (643s left) gem_mmap_offset (pf-nonblock)
[377.880009] Starting subtest: pf-nonblock
[377.883309] Subtest pf-nonblock: SUCCESS (0.004s)
[377.989667] [124/156] (643s left) i915_suspend (basic-s2idle-without-i915)
[378.021988] Starting subtest: basic-s2idle-without-i915
[396.434432] Subtest basic-s2idle-without-i915: SUCCESS (3.145s)
[403.523040] [125/156] (624s left) kms_frontbuffer_tracking (psr-farfromfence-mmap-gtt)
[403.620115] Starting subtest: psr-farfromfence-mmap-gtt
[403.621506] Subtest psr-farfromfence-mmap-gtt: SKIP (0.000s)
[403.715321] [126/156] (624s left) i915_pm_freq_api (freq-suspend)
[403.754595] Subtest freq-suspend: SKIP (0.000s)
[403.798066] [127/156] (624s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[403.888249] Starting subtest: psrhdr-1p-primscrn-cur-indfb-draw-mmap-cpu
[403.889702] Subtest psrhdr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[403.983857] [128/156] (624s left) gem_exec_schedule (u-semaphore-resolve)
[404.033522] Starting subtest: u-semaphore-resolve
[404.055947] Subtest u-semaphore-resolve: SUCCESS (0.024s)
[404.376405] [129/156] (623s left) gem_spin_batch (user-each)
[404.419097] Starting subtest: user-each
[407.677663] Subtest user-each: SUCCESS (3.288s)
[407.845384] [130/156] (620s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs)
[407.934645] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs
[407.935362] Starting dynamic subtest: pipe-A-HDMI-A-1
[407.942041] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[407.950551] Starting dynamic subtest: pipe-B-HDMI-A-1
[407.957268] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[407.965563] Starting dynamic subtest: pipe-C-HDMI-A-1
[407.972124] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[407.981057] Starting dynamic subtest: pipe-D-HDMI-A-1
[407.987974] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.000s)
[407.989298] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs: SKIP (0.007s)
[408.187706] [131/156] (620s left) gem_exec_await (wide-all)
[408.264955] Starting subtest: wide-all
[429.892433] Subtest wide-all: SUCCESS (21.710s)
[430.101800] [132/156] (598s left) kms_frontbuffer_tracking (fbchdr-rgb101010-draw-blt)
[430.243450] Starting subtest: fbchdr-rgb101010-draw-blt
[430.244813] Subtest fbchdr-rgb101010-draw-blt: SKIP (0.000s)
[430.345501] [133/156] (598s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-blt)
[430.436963] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-blt
[430.438482] Subtest psrhdr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[430.537563] [134/156] (598s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[430.627671] Starting subtest: basic-busy-flip-before-cursor-atomic
[430.629156] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[430.691374] [135/156] (597s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[430.785165] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[430.786561] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[430.884632] [136/156] (597s left) kms_pm_rpm (dpms-lpsp)
[432.302866] Starting subtest: dpms-lpsp
[432.396759] Subtest dpms-lpsp: SUCCESS (0.094s)
[432.519814] [137/156] (596s left) kms_properties (invalid-properties-legacy)
[432.609857] Starting subtest: invalid-properties-legacy
[432.815581] Subtest invalid-properties-legacy: SUCCESS (0.198s)
[432.884885] [138/156] (595s left) api_intel_allocator (simple-allocator)
[432.919876] Starting subtest: simple-allocator
[432.920970] Starting dynamic subtest: basic
[432.921747] Dynamic subtest basic: SUCCESS (0.002s)
[432.932415] Starting dynamic subtest: parallel-one
[433.106148] Dynamic subtest parallel-one: SUCCESS (0.175s)
[433.116145] Starting dynamic subtest: print
[433.116842] Dynamic subtest print: SUCCESS (0.000s)
[433.126784] Starting dynamic subtest: reuse
[433.127481] Dynamic subtest reuse: SUCCESS (0.000s)
[433.136683] Starting dynamic subtest: reserve
[433.137381] Dynamic subtest reserve: SUCCESS (0.000s)
[433.145992] Starting dynamic subtest: fork-reopen-allocator
[433.146898] Dynamic subtest fork-reopen-allocator: SUCCESS (0.018s)
[433.147730] Subtest simple-allocator: SUCCESS (0.202s)
[433.187588] [139/156] (595s left) kms_big_fb (yf-tiled-8bpp-rotate-90)
[433.288069] Starting subtest: yf-tiled-8bpp-rotate-90
[433.289451] Subtest yf-tiled-8bpp-rotate-90: SKIP (0.000s)
[433.395043] [140/156] (595s left) kms_atomic (test-only)
[433.483901] Starting subtest: test-only
[433.539517] Starting dynamic subtest: pipe-A-HDMI-A-1
[433.945425] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.406s)
[433.946187] Subtest test-only: SUCCESS (0.463s)
[434.096606] [141/156] (594s left) kms_sequence (get-busy)
[434.190679] Starting subtest: get-busy
[434.192530] Starting dynamic subtest: pipe-A-HDMI-A-1
[435.478175] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.287s)
[435.489674] Starting dynamic subtest: pipe-B-HDMI-A-1
[436.751948] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (1.273s)
[436.765060] Starting dynamic subtest: pipe-C-HDMI-A-1
[438.044424] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.289s)
[438.057215] Starting dynamic subtest: pipe-D-HDMI-A-1
[439.330540] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.283s)
[439.331287] Subtest get-busy: SUCCESS (5.140s)
[439.453697] [142/156] (589s left) vgem_basic (bad-handle)
[439.497177] Starting subtest: bad-handle
[439.497955] Subtest bad-handle: SUCCESS (0.000s)
[439.555082] [143/156] (589s left) syncobj_wait (wait-for-submit-delayed-submit)
[439.596284] Starting subtest: wait-for-submit-delayed-submit
[439.698964] Subtest wait-for-submit-delayed-submit: SUCCESS (0.102s)
[439.790636] [144/156] (589s left) gem_exec_balancer (full)
[439.835020] Starting subtest: full
[441.438371] Subtest full: SUCCESS (1.601s)
[441.633926] [145/156] (587s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[441.728135] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-mmap-wc
[441.729504] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[441.828807] [146/156] (587s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-indfb-draw-pwrite)
[441.920997] Starting subtest: fbcpsrhdr-2p-primscrn-pri-indfb-draw-pwrite
[441.922489] Subtest fbcpsrhdr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[442.008502] [147/156] (587s left) gem_exec_params (mmapped)
[442.047112] Starting subtest: mmapped
[442.049684] Subtest mmapped: SUCCESS (0.003s)
[442.155462] [148/156] (586s left) gen9_exec_parse (bb-start-out)
[442.193471] Subtest bb-start-out: SKIP (0.000s)
[442.239771] [149/156] (586s left) kms_cursor_crc (cursor-offscreen-512x512)
[442.333294] Starting subtest: cursor-offscreen-512x512
[442.334697] Subtest cursor-offscreen-512x512: SKIP (0.001s)
[442.427024] [150/156] (586s left) i915_pm_rpm (reg-read-ioctl)
[443.825878] Starting subtest: reg-read-ioctl
[446.264533] Subtest reg-read-ioctl: SUCCESS (2.437s)
[446.453732] [151/156] (582s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area)
[446.547238] Subtest pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[446.597056] [152/156] (582s left) kms_psr (fbc-psr-primary-render)
[446.686005] Subtest fbc-psr-primary-render: SKIP (0.000s)
[446.733517] [153/156] (582s left) kms_chamelium_frames (dp-frame-dump)
[446.875487] Subtest dp-frame-dump: SKIP (0.000s)
[446.977984] [154/156] (582s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-pwrite)
[447.074081] Starting subtest: psr-1p-primscrn-spr-indfb-draw-pwrite
[447.075452] Subtest psr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[447.183095] [155/156] (582s left) gem_exec_fence (syncobj-timeline-chain-engines)
[447.638887] Starting subtest: syncobj-timeline-chain-engines
[447.706045] Subtest syncobj-timeline-chain-engines: SUCCESS (0.078s)
[447.899194] [156/156] (581s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[447.991240] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-cpu
[447.992687] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[448.086437] Closing watchdogs
results: parsing output: 0/ for test: kms_content_protection
results: parsing output: 1/ for test: syncobj_timeline
results: parsing output: 2/ for test: gem_exec_params
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_psr
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_chamelium_hpd
results: parsing output: 8/ for test: i915_drm_fdinfo
results: parsing output: 9/ for test: kms_cursor_legacy
results: parsing output: 10/ for test: drm_read
results: parsing output: 11/ for test: kms_busy
results: parsing output: 12/ for test: syncobj_timeline
results: parsing output: 13/ for test: kms_getfb
results: parsing output: 14/ for test: kms_lease
results: parsing output: 15/ for test: gem_wait
results: parsing output: 16/ for test: gem_eio
results: parsing output: 17/ for test: kms_cursor_crc
results: parsing output: 18/ for test: kms_async_flips
results: parsing output: 19/ for test: kms_cursor_legacy
results: parsing output: 20/ for test: kms_big_fb
results: parsing output: 21/ for test: kms_flip
results: parsing output: 22/ for test: gem_mmap_gtt
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: syncobj_timeline
results: parsing output: 25/ for test: gem_exec_alignment
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: kms_addfb_basic
results: parsing output: 28/ for test: kms_color
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_atomic_interruptible
results: parsing output: 31/ for test: syncobj_basic
results: parsing output: 32/ for test: gem_mmap_gtt
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: kms_big_fb
results: parsing output: 36/ for test: kms_cursor_crc
results: parsing output: 37/ for test: gem_eio
results: parsing output: 38/ for test: gem_partial_pwrite_pread
results: parsing output: 39/ for test: gem_exec_schedule
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_psr
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_plane_scaling
results: parsing output: 45/ for test: gem_eio
results: parsing output: 46/ for test: gem_userptr_blits
results: parsing output: 47/ for test: kms_chamelium_audio
results: parsing output: 48/ for test: kms_invalid_mode
results: parsing output: 49/ for test: gem_render_copy
results: parsing output: 50/ for test: gem_partial_pwrite_pread
results: parsing output: 51/ for test: kms_feature_discovery
results: parsing output: 52/ for test: gem_exec_balancer
results: parsing output: 53/ for test: kms_plane_multiple
results: parsing output: 54/ for test: kms_flip_scaled_crc
results: parsing output: 55/ for test: kms_cursor_legacy
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: syncobj_timeline
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_psr
results: parsing output: 60/ for test: kms_lease
results: parsing output: 61/ for test: perf_pmu
results: parsing output: 62/ for test: api_intel_bb
results: parsing output: 63/ for test: gem_exec_whisper
results: parsing output: 64/ for test: gem_mmap_gtt
results: parsing output: 65/ for test: kms_big_fb
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_big_fb
results: parsing output: 68/ for test: kms_dirtyfb
results: parsing output: 69/ for test: gem_exec_gttfill
results: parsing output: 70/ for test: gem_ctx_persistence
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: gem_ccs
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: gem_pxp
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: perf
results: parsing output: 79/ for test: gem_render_copy
results: parsing output: 80/ for test: gem_ctx_param
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_ccs
results: parsing output: 83/ for test: gem_ctx_bad_destroy
results: parsing output: 84/ for test: syncobj_wait
results: parsing output: 85/ for test: syncobj_timeline
results: parsing output: 86/ for test: kms_plane
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_plane_scaling
results: parsing output: 91/ for test: kms_flip_scaled_crc
results: parsing output: 92/ for test: gem_exec_reloc
results: parsing output: 93/ for test: gem_tiled_blits
results: parsing output: 94/ for test: gem_render_copy_redux
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_vrr
results: parsing output: 98/ for test: kms_plane_alpha_blend
results: parsing output: 99/ for test: gem_ctx_persistence
results: parsing output: 100/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 101/ for test: perf
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: gem_exec_whisper
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_ccs
results: parsing output: 107/ for test: kms_psr
results: parsing output: 108/ for test: kms_psr
results: parsing output: 109/ for test: gem_create
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_psr2_sf
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_big_fb
results: parsing output: 114/ for test: sriov_basic
results: parsing output: 115/ for test: kms_ccs
results: parsing output: 116/ for test: perf
results: parsing output: 117/ for test: gem_set_tiling_vs_blt
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: kms_flip_scaled_crc
results: parsing output: 121/ for test: kms_pm_rpm
results: parsing output: 122/ for test: gem_mmap_offset
results: parsing output: 123/ for test: i915_suspend
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: i915_pm_freq_api
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: gem_exec_schedule
results: parsing output: 128/ for test: gem_spin_batch
results: parsing output: 129/ for test: kms_ccs
results: parsing output: 130/ for test: gem_exec_await
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: kms_cursor_legacy
results: parsing output: 134/ for test: kms_frontbuffer_tracking
results: parsing output: 135/ for test: kms_pm_rpm
results: parsing output: 136/ for test: kms_properties
results: parsing output: 137/ for test: api_intel_allocator
results: parsing output: 138/ for test: kms_big_fb
results: parsing output: 139/ for test: kms_atomic
results: parsing output: 140/ for test: kms_sequence
results: parsing output: 141/ for test: vgem_basic
results: parsing output: 142/ for test: syncobj_wait
results: parsing output: 143/ for test: gem_exec_balancer
results: parsing output: 144/ for test: kms_frontbuffer_tracking
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: gem_exec_params
results: parsing output: 147/ for test: gen9_exec_parse
results: parsing output: 148/ for test: kms_cursor_crc
results: parsing output: 149/ for test: i915_pm_rpm
results: parsing output: 150/ for test: kms_psr2_sf
results: parsing output: 151/ for test: kms_psr
results: parsing output: 152/ for test: kms_chamelium_frames
results: parsing output: 153/ for test: kms_frontbuffer_tracking
results: parsing output: 154/ for test: gem_exec_fence
results: parsing output: 155/ for test: kms_frontbuffer_tracking
Done.