[135.913655] Initializing watchdogs
[135.913827]   /dev/watchdog0
[135.952564] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3184 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 605]
[135.967127] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[135.972941] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[135.978437] [001/133] (960s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[136.179786] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[139.213310] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (3.032s)
[139.485265] [002/133] (957s left) perf_pmu (render-node-busy)
[139.623906] Starting subtest: render-node-busy
[139.624990] Starting dynamic subtest: rcs0
[140.171587] Dynamic subtest rcs0: SUCCESS (0.547s)
[140.189263] Starting dynamic subtest: bcs0
[140.708453] Dynamic subtest bcs0: SUCCESS (0.536s)
[140.726368] Starting dynamic subtest: vcs0
[141.250352] Dynamic subtest vcs0: SUCCESS (0.541s)
[141.267905] Starting dynamic subtest: vecs0
[141.789079] Dynamic subtest vecs0: SUCCESS (0.537s)
[141.790170] Subtest render-node-busy: SUCCESS (2.164s)
[141.908385] [003/133] (954s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-msflip-blt)
[142.088771] Starting subtest: psr-1p-primscrn-shrfb-msflip-blt
[142.090633] Subtest psr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[142.239574] [004/133] (954s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted-signa
[142.337147] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled
[142.443152] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.106s)
[142.572998] [005/133] (954s left) kms_async_flips (async-flip-dpms)
[142.742512] Starting subtest: async-flip-dpms
[143.299194] Starting dynamic subtest: pipe-A-HDMI-A-1
[146.012564] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.712s)
[146.401132] Starting dynamic subtest: pipe-A-HDMI-A-2
[148.589070] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.204s)
[148.918334] Starting dynamic subtest: pipe-B-HDMI-A-1
[151.111304] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.207s)
[151.425028] Starting dynamic subtest: pipe-B-HDMI-A-2
[153.592240] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (2.182s)
[153.902371] Starting dynamic subtest: pipe-C-HDMI-A-1
[156.077180] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.189s)
[156.385677] Starting dynamic subtest: pipe-C-HDMI-A-2
[158.547292] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.177s)
[158.548786] Subtest async-flip-dpms: SUCCESS (15.805s)
[159.071966] [006/133] (937s left) kms_getfb (getfb-handle-zero)
[159.249346] Starting subtest: getfb-handle-zero
[159.250477] Subtest getfb-handle-zero: SUCCESS (0.000s)
[159.388793] [007/133] (937s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[159.490882] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[159.582068] [008/133] (937s left) prime_mmap_coherency (write)
[159.688203] Starting subtest: write
[165.090816] Subtest write: SUCCESS (5.403s)
[165.501538] [009/133] (931s left) kms_content_protection (atomic-dpms)
[165.703697] Starting subtest: atomic-dpms
[165.714249] Subtest atomic-dpms: SKIP (0.010s)
[165.840828] [010/133] (931s left) dmabuf_sync_file (import-multiple-read-only)
[166.018855] Starting subtest: import-multiple-read-only
[166.028409] Subtest import-multiple-read-only: SUCCESS (0.011s)
[166.146965] [FACT dmabuf_sync_file (import-multiple-read-only)] new: kernel.kmod_is_loaded.vgem: true
[166.150544] [011/133] (931s left) sw_sync (sync_merge_invalid)
[166.242352] Starting subtest: sync_merge_invalid
[166.250590] Subtest sync_merge_invalid: SUCCESS (0.008s)
[166.358521] [012/133] (930s left) gem_exec_alignment (forked)
[166.470579] Starting subtest: forked
[212.072851] Subtest forked: SUCCESS (45.605s)
[212.204947] [013/133] (885s left) kms_chamelium_edid (dp-edid-read)
[212.830200] Subtest dp-edid-read: SKIP (0.000s)
[213.075117] [014/133] (884s left) gem_spin_batch (resubmit-all)
[213.191929] Starting subtest: resubmit-all
[213.193142] Starting dynamic subtest: rcs0
[213.204764] Dynamic subtest rcs0: SUCCESS (0.010s)
[213.228835] Starting dynamic subtest: bcs0
[213.229828] Dynamic subtest bcs0: SUCCESS (0.007s)
[213.247573] Starting dynamic subtest: vcs0
[213.248531] Dynamic subtest vcs0: SUCCESS (0.005s)
[213.266735] Starting dynamic subtest: vecs0
[213.267778] Dynamic subtest vecs0: SUCCESS (0.005s)
[213.268707] Subtest resubmit-all: SUCCESS (0.031s)
[213.500823] [015/133] (884s left) syncobj_wait (invalid-reset-one-illegal-handle)
[213.611587] Starting subtest: invalid-reset-one-illegal-handle
[213.612723] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.000s)
[213.752363] [016/133] (883s left) gem_workarounds (suspend-resume-context)
[213.882252] Starting subtest: suspend-resume-context
[230.937584] Subtest suspend-resume-context: SUCCESS (1.808s)
[239.039284] [017/133] (866s left) kms_psr (fbc-psr-primary-render)
[239.222881] Subtest fbc-psr-primary-render: SKIP (0.000s)
[239.333577] [018/133] (866s left) gem_exec_suspend (basic-s0)
[239.458614] Starting subtest: basic-S0
[239.460083] Starting dynamic subtest: smem
[258.421792] Dynamic subtest smem: SUCCESS (4.887s)
[258.423443] Subtest basic-S0: SUCCESS (4.888s)
[265.693704] [019/133] (847s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled)
[265.794531] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled
[265.900566] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.104s)
[266.043215] [020/133] (847s left) syncobj_wait (wait-all-delayed-signal)
[266.141806] Starting subtest: wait-all-delayed-signal
[266.248340] Subtest wait-all-delayed-signal: SUCCESS (0.104s)
[266.413438] [021/133] (846s left) gem_create (create-massive)
[266.507314] Starting subtest: create-massive
[266.510192] Subtest create-massive: SUCCESS (0.000s)
[266.764976] [022/133] (846s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)
[266.934901] Starting subtest: short-flip-after-cursor-atomic-transitions
[268.321426] Subtest short-flip-after-cursor-atomic-transitions: SUCCESS (1.373s)
[268.642298] [023/133] (844s left) template (a)
[268.735349] Starting subtest: A
[268.736536] Subtest A: SUCCESS (0.000s)
[268.888690] [024/133] (844s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[269.078669] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[269.080756] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[269.231725] [025/133] (844s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-msflip-blt)
[269.410262] Starting subtest: fbc-2p-scndscrn-indfb-msflip-blt
[271.995552] Subtest fbc-2p-scndscrn-indfb-msflip-blt: SUCCESS (2.570s)
[272.218310] [026/133] (841s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[272.323098] Starting subtest: writes-after-reads
[275.342907] Subtest writes-after-reads: SUCCESS (3.008s)
[275.515557] [027/133] (838s left) kms_cursor_legacy (cursor-vs-flip-varying-size)
[275.680285] Starting subtest: cursor-vs-flip-varying-size
[320.500457] Subtest cursor-vs-flip-varying-size: SUCCESS (44.791s)
[320.791516] [028/133] (793s left) kms_psr (psr2-sprite-mmap-cpu)
[320.960559] Subtest psr2-sprite-mmap-cpu: SKIP (0.000s)
[321.066830] [029/133] (792s left) gem_mmap_gtt (basic-short)
[321.158909] Starting subtest: basic-short
[321.206048] Subtest basic-short: SUCCESS (0.046s)
[321.360716] [030/133] (792s left) gem_userptr_blits (stress-mm)
[321.482949] Starting subtest: stress-mm
[326.855427] Subtest stress-mm: SUCCESS (5.370s)
[327.044892] [031/133] (787s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt)
[327.224932] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt
[329.307088] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SUCCESS (2.081s)
[329.559649] [032/133] (784s left) i915_drm_fdinfo (memory-info-purgeable)
[329.662251] Starting subtest: memory-info-purgeable
[329.663706] Starting dynamic subtest: smem0
[332.053963] Dynamic subtest smem0: SUCCESS (2.391s)
[332.055113] Subtest memory-info-purgeable: SUCCESS (2.392s)
[332.195170] [033/133] (782s left) kms_psr (fbc-psr2-cursor-render)
[332.361087] Subtest fbc-psr2-cursor-render: SKIP (0.000s)
[332.470452] [034/133] (781s left) kms_frontbuffer_tracking (psr-slowdraw)
[332.654456] Starting subtest: psr-slowdraw
[332.656558] Subtest psr-slowdraw: SKIP (0.000s)
[332.812787] [035/133] (781s left) gem_exec_reloc (basic-write-cpu-active)
[332.920641] Starting subtest: basic-write-cpu-active
[333.061606] Subtest basic-write-cpu-active: SUCCESS (0.141s)
[333.276791] [036/133] (781s left) kms_properties (colorop-properties-atomic)
[333.443337] Starting subtest: colorop-properties-atomic
[333.444424] Starting dynamic subtest: pipe-A-HDMI-A-1
[333.881312] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.436s)
[333.910526] Starting dynamic subtest: pipe-A-HDMI-A-2
[334.213416] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.328s)
[334.239689] Starting dynamic subtest: pipe-B-HDMI-A-1
[334.505361] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.288s)
[334.537796] Starting dynamic subtest: pipe-B-HDMI-A-2
[334.828299] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.317s)
[334.852095] Starting dynamic subtest: pipe-C-HDMI-A-1
[335.138205] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.304s)
[335.167022] Starting dynamic subtest: pipe-C-HDMI-A-2
[335.451356] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.303s)
[335.452474] Subtest colorop-properties-atomic: SUCCESS (2.007s)
[335.750659] [037/133] (778s left) gem_exec_gttfill (all-engines)
[335.869337] Starting subtest: all-engines
[371.713660] Subtest all-engines: SUCCESS (35.839s)
[371.963528] [038/133] (742s left) kms_pm_rpm (fences-dpms)
[373.938380] Starting subtest: fences-dpms
[376.794640] Subtest fences-dpms: SUCCESS (2.857s)
[377.050117] [039/133] (737s left) tools_test (sysfs_l3_parity)
[377.150625] Starting subtest: sysfs_l3_parity
[377.281287] Subtest sysfs_l3_parity: SKIP (0.134s)
[377.403302] [040/133] (737s left) kms_cursor_crc (cursor-onscreen-32x32)
[377.574123] Starting subtest: cursor-onscreen-32x32
[377.721735] Subtest cursor-onscreen-32x32: SKIP (0.144s)
[377.884618] [041/133] (737s left) gem_userptr_blits (map-fixed-invalidate-overlap)
[378.010130] Starting subtest: map-fixed-invalidate-overlap
[378.012354] Starting dynamic subtest: gtt
[378.229276] Dynamic subtest gtt: SUCCESS (0.218s)
[378.254471] Starting dynamic subtest: wb
[378.372224] Dynamic subtest wb: SUCCESS (0.141s)
[378.397571] Starting dynamic subtest: wc
[378.513415] Dynamic subtest wc: SUCCESS (0.139s)
[378.538306] Starting dynamic subtest: uc
[378.655589] Dynamic subtest uc: SUCCESS (0.140s)
[378.657077] Subtest map-fixed-invalidate-overlap: SUCCESS (0.647s)
[378.851562] [042/133] (736s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-downscaling)
[379.019653] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-downscaling
[379.022361] Subtest flip-64bpp-yftile-to-32bpp-yftile-downscaling: SKIP (0.000s)
[379.165871] [043/133] (736s left) kms_fbcon_fbt (fbc)
[379.265431] Starting subtest: fbc
[383.337807] Subtest fbc: SUCCESS (4.072s)
[383.446827] [044/133] (731s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[383.616815] Starting subtest: basic-flip-before-cursor-legacy
[384.935320] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.314s)
[385.229435] [045/133] (730s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[385.413956] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[385.419504] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.003s)
[385.591807] [046/133] (729s left) api_intel_bb (intel-bb-blit-x)
[385.687129] Starting subtest: intel-bb-blit-x
[389.044109] Subtest intel-bb-blit-x: SUCCESS (3.356s)
[389.212451] [047/133] (726s left) kms_big_fb (4-tiled-8bpp-rotate-180)
[389.395836] Starting subtest: 4-tiled-8bpp-rotate-180
[389.397922] Subtest 4-tiled-8bpp-rotate-180: SKIP (0.000s)
[389.546165] [048/133] (726s left) syncobj_timeline (multi-wait-for-submit-signaled)
[389.647518] Starting subtest: multi-wait-for-submit-signaled
[389.650479] Subtest multi-wait-for-submit-signaled: SUCCESS (0.002s)
[389.796623] [049/133] (726s left) kms_psr (fbc-pr-cursor-mmap-gtt)
[389.967914] Subtest fbc-pr-cursor-mmap-gtt: SKIP (0.000s)
[390.071297] [050/133] (725s left) kms_atomic (plane-immutable-zpos)
[390.238984] Starting subtest: plane-immutable-zpos
[390.243410] Starting dynamic subtest: pipe-A-HDMI-A-1
[391.008614] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.765s)
[391.009628] Subtest plane-immutable-zpos: SUCCESS (0.770s)
[391.307390] [051/133] (724s left) gen7_exec_parse (basic-allocation)
[391.408598] Subtest basic-allocation: SKIP (0.000s)
[391.515175] [052/133] (724s left) kms_scaling_modes (scaling-mode-full-aspect)
[391.684366] Starting subtest: scaling-mode-full-aspect
[391.694230] Subtest scaling-mode-full-aspect: SKIP (0.003s)
[391.824749] [053/133] (724s left) api_intel_bb (blit-reloc-keep-cache)
[391.918883] Starting subtest: blit-reloc-keep-cache
[391.934500] Subtest blit-reloc-keep-cache: SUCCESS (0.016s)
[392.109199] [054/133] (724s left) gem_exec_params (invalid-flag)
[392.213707] Starting subtest: invalid-flag
[392.214907] Subtest invalid-flag: SUCCESS (0.000s)
[392.374638] [055/133] (723s left) gem_softpin (evict-snoop-interruptible)
[392.484907] Starting subtest: evict-snoop-interruptible
[392.487310] Subtest evict-snoop-interruptible: SKIP (0.000s)
[392.657953] [056/133] (723s left) kms_dirtyfb (psr-dirtyfb-ioctl)
[392.828730] Starting subtest: psr-dirtyfb-ioctl
[392.836100] Subtest psr-dirtyfb-ioctl: SKIP (0.000s)
[392.975406] [057/133] (723s left) kms_joiner (invalid-modeset-ultra-joiner)
[393.145840] Starting subtest: invalid-modeset-ultra-joiner
[393.149095] Subtest invalid-modeset-ultra-joiner: SKIP (0.000s)
[393.290661] [058/133] (723s left) gem_exec_fence (concurrent)
[393.531496] Starting subtest: concurrent
[393.532541] Starting dynamic subtest: rcs0
[393.573620] Dynamic subtest rcs0: SUCCESS (0.040s)
[393.597703] Starting dynamic subtest: bcs0
[393.609459] Dynamic subtest bcs0: SUCCESS (0.034s)
[393.635802] Starting dynamic subtest: vcs0
[393.645804] Dynamic subtest vcs0: SUCCESS (0.033s)
[393.672897] Starting dynamic subtest: vecs0
[393.680441] Dynamic subtest vecs0: SUCCESS (0.032s)
[393.681780] Subtest concurrent: SUCCESS (0.150s)
[394.376809] [059/133] (722s left) kms_dsc (dsc-with-formats)
[394.545223] Subtest dsc-with-formats: SKIP (0.000s)
[394.657226] [060/133] (722s left) gem_exec_schedule (preempt-other)
[394.781255] Starting subtest: preempt-other
[394.782284] Starting dynamic subtest: rcs0
[394.847269] Dynamic subtest rcs0: SUCCESS (0.065s)
[394.878643] Starting dynamic subtest: bcs0
[394.916544] Dynamic subtest bcs0: SUCCESS (0.068s)
[394.954082] Starting dynamic subtest: vcs0
[394.984881] Dynamic subtest vcs0: SUCCESS (0.065s)
[395.021998] Starting dynamic subtest: vecs0
[395.054878] Dynamic subtest vecs0: SUCCESS (0.067s)
[395.057349] Subtest preempt-other: SUCCESS (0.277s)
[395.475818] [061/133] (721s left) gem_exec_params (secure-non-root)
[395.576674] Starting subtest: secure-non-root
[395.578952] Subtest secure-non-root: SKIP (0.000s)
[395.740709] [062/133] (721s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-pwrite)
[395.924481] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-pwrite
[398.469393] Subtest fbc-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (2.544s)
[398.740102] [063/133] (718s left) kms_flip (2x-flip-vs-panning)
[398.911436] Starting subtest: 2x-flip-vs-panning
[398.914873] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[409.690261] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (10.775s)
[409.718219] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[420.431071] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (10.733s)
[420.459366] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[431.151181] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (10.710s)
[431.153365] Subtest 2x-flip-vs-panning: SUCCESS (32.233s)
[431.735242] [064/133] (685s left) gem_flink_basic (double-flink)
[431.830268] Starting subtest: double-flink
[431.831460] Subtest double-flink: SUCCESS (0.000s)
[431.985274] [065/133] (685s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-sf-dmg-area)
[432.154959] Subtest fbc-psr2-overlay-plane-update-sf-dmg-area: SKIP (0.000s)
[432.259293] [066/133] (685s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render)
[432.439249] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render
[432.441404] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[432.582799] [067/133] (684s left) kms_sequence (queue-idle)
[432.749118] Starting subtest: queue-idle
[432.753269] Starting dynamic subtest: pipe-A-HDMI-A-1
[435.450945] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.700s)
[435.478813] Starting dynamic subtest: pipe-A-HDMI-A-2
[437.907612] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.451s)
[437.935195] Starting dynamic subtest: pipe-B-HDMI-A-1
[440.314069] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.402s)
[440.343168] Starting dynamic subtest: pipe-B-HDMI-A-2
[442.731821] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (2.409s)
[442.758995] Starting dynamic subtest: pipe-C-HDMI-A-1
[445.150155] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.413s)
[445.174883] Starting dynamic subtest: pipe-C-HDMI-A-2
[447.572631] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.417s)
[447.573768] Subtest queue-idle: SUCCESS (14.821s)
[447.849392] [068/133] (669s left) gem_render_copy (y-tiled-ccs-to-yf-tiled)
[447.962457] Starting subtest: y-tiled-ccs-to-yf-tiled
[447.963422] Starting dynamic subtest: smem
[448.291927] Dynamic subtest smem: SUCCESS (0.330s)
[448.296477] Subtest y-tiled-ccs-to-yf-tiled: SUCCESS (0.333s)
[448.551551] [069/133] (669s left) gem_ctx_param (set-priority-invalid-size)
[448.653191] Starting subtest: set-priority-invalid-size
[448.654391] Subtest set-priority-invalid-size: SUCCESS (0.000s)
[448.801505] [070/133] (668s left) kms_big_fb (x-tiled-64bpp-rotate-0)
[448.988850] Starting subtest: x-tiled-64bpp-rotate-0
[455.990310] Subtest x-tiled-64bpp-rotate-0: SUCCESS (6.999s)
[456.764730] [071/133] (661s left) gem_eio (in-flight-suspend)
[456.887239] Starting subtest: in-flight-suspend