[89.835477] Initializing watchdogs
[89.835642]   /dev/watchdog0
[89.874694] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[89.888990] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[89.896942] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[89.904743] [001/133] (960s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[90.497628] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[90.762598] [002/133] (959s left) gem_exec_params (sol-reset-invalid)
[90.887899] Starting subtest: sol-reset-invalid
[90.892240] Subtest sol-reset-invalid: SUCCESS (0.003s)
[91.069070] [003/133] (959s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0)
[91.271394] Starting subtest: linear-max-hw-stride-32bpp-rotate-0
[93.100072] Subtest linear-max-hw-stride-32bpp-rotate-0: SUCCESS (1.830s)
[93.369220] [004/133] (957s left) kms_flip (2x-plain-flip)
[93.558821] Starting subtest: 2x-plain-flip
[93.563492] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[94.970946] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (1.410s)
[94.991139] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[96.322069] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (1.347s)
[96.342156] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[97.651471] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (1.327s)
[97.655628] Subtest 2x-plain-flip: SUCCESS (4.097s)
[98.254497] [005/133] (952s left) api_intel_allocator (reloc-allocator)
[98.376469] Starting subtest: reloc-allocator
[98.382109] Starting dynamic subtest: basic
[98.385381] Dynamic subtest basic: SUCCESS (0.009s)
[98.419057] Starting dynamic subtest: parallel-one
[98.423535] Dynamic subtest parallel-one: SUCCESS (0.009s)
[98.441597] Starting dynamic subtest: print
[98.444890] Dynamic subtest print: SUCCESS (0.000s)
[98.466043] Starting dynamic subtest: reuse
[98.469321] Dynamic subtest reuse: SUCCESS (0.000s)
[98.487779] Starting dynamic subtest: fork-reopen-allocator
[98.493091] Dynamic subtest fork-reopen-allocator: SUCCESS (0.067s)
[98.496968] Subtest reloc-allocator: SUCCESS (0.096s)
[98.641306] [006/133] (952s left) i915_drm_fdinfo (busy-idle-check-all)
[98.758993] Starting subtest: busy-idle-check-all
[98.762897] Starting dynamic subtest: rcs0
[99.393809] Dynamic subtest rcs0: SUCCESS (0.631s)
[99.412803] Starting dynamic subtest: bcs0
[100.024405] Dynamic subtest bcs0: SUCCESS (0.633s)
[100.043294] Starting dynamic subtest: vcs0
[100.652282] Dynamic subtest vcs0: SUCCESS (0.629s)
[100.671388] Starting dynamic subtest: vecs0
[101.283695] Dynamic subtest vecs0: SUCCESS (0.629s)
[101.289724] Subtest busy-idle-check-all: SUCCESS (2.524s)
[101.433654] [007/133] (949s left) gem_set_tiling_vs_gtt
[101.990242] [008/133] (949s left) sysfs_preempt_timeout (invalid)
[102.106410] Starting subtest: invalid
[102.109622] Starting dynamic subtest: vecs0
[102.113282] Dynamic subtest vecs0: SUCCESS (0.001s)
[102.132023] Starting dynamic subtest: vcs0
[102.135533] Dynamic subtest vcs0: SUCCESS (0.001s)
[102.154375] Starting dynamic subtest: bcs0
[102.158350] Dynamic subtest bcs0: SUCCESS (0.001s)
[102.176922] Starting dynamic subtest: rcs0
[102.180152] Dynamic subtest rcs0: SUCCESS (0.001s)
[102.183941] Subtest invalid: SUCCESS (0.008s)
[102.263139] [009/133] (948s left) i915_query (hwconfig_table)
[102.366971] Starting subtest: hwconfig_table
[102.375078] Subtest hwconfig_table: SKIP (0.000s)
[102.523228] [010/133] (948s left) kms_flip (2x-blocking-absolute-wf_vblank)
[102.706395] Starting subtest: 2x-blocking-absolute-wf_vblank
[102.709765] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[113.591990] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (10.885s)
[113.610931] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[124.436330] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (10.842s)
[124.456136] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[135.082596] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (10.642s)
[135.086181] Subtest 2x-blocking-absolute-wf_vblank: SUCCESS (32.383s)
[135.676358] [011/133] (915s left) gem_ctx_param (invalid-param-set)
[135.788159] Starting subtest: invalid-param-set
[135.792527] Subtest invalid-param-set: SUCCESS (0.000s)
[135.936311] [012/133] (915s left) gem_mmap_gtt (medium-copy-odd)
[136.043817] Starting subtest: medium-copy-odd
[141.076620] Subtest medium-copy-odd: SUCCESS (5.029s)
[141.244638] [013/133] (910s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[141.603224] Starting subtest: 4-tiled-8bpp-rotate-0
[141.610273] Subtest 4-tiled-8bpp-rotate-0: SKIP (0.000s)
[141.771204] [014/133] (909s left) sw_sync (sync_random_merge)
[141.899861] Starting subtest: sync_random_merge
[142.083520] Subtest sync_random_merge: SUCCESS (0.187s)
[142.243609] [015/133] (909s left) gem_ctx_create (basic)
[142.381712] Starting subtest: basic
[142.386192] Subtest basic: SUCCESS (0.000s)
[142.641343] [016/133] (909s left) kms_chamelium_frames (dp-crc-multiple)
[143.190555] Subtest dp-crc-multiple: SKIP (0.000s)
[143.451709] [017/133] (908s left) syncobj_wait (single-wait-for-submit-signaled)
[143.565078] Starting subtest: single-wait-for-submit-signaled
[143.568588] Subtest single-wait-for-submit-signaled: SUCCESS (0.001s)
[143.726665] [018/133] (908s left) kms_cursor_crc (cursor-random-max-size)
[143.948871] Starting subtest: cursor-random-max-size
[143.959046] Subtest cursor-random-max-size: SKIP (0.000s)
[144.099057] [019/133] (907s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[144.304847] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-cpu
[144.311419] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[144.478736] [020/133] (907s left) gem_lmem_swapping (random)
[144.595580] Subtest random: SKIP (0.000s)
[144.692772] [021/133] (907s left) kms_plane_cursor (primary)
[144.872342] Starting subtest: primary
[144.881052] Starting dynamic subtest: pipe-A-HDMI-A-1-size-64
[147.480794] Dynamic subtest pipe-A-HDMI-A-1-size-64: SUCCESS (2.603s)
[147.547424] Starting dynamic subtest: pipe-A-HDMI-A-1-size-128
[149.961773] Dynamic subtest pipe-A-HDMI-A-1-size-128: SUCCESS (2.431s)
[150.030220] Starting dynamic subtest: pipe-A-HDMI-A-1-size-256
[152.445063] Dynamic subtest pipe-A-HDMI-A-1-size-256: SUCCESS (2.433s)
[152.729622] Starting dynamic subtest: pipe-B-HDMI-A-2-size-64
[155.200046] Dynamic subtest pipe-B-HDMI-A-2-size-64: SUCCESS (2.500s)
[155.248060] Starting dynamic subtest: pipe-B-HDMI-A-2-size-128
[157.665052] Dynamic subtest pipe-B-HDMI-A-2-size-128: SUCCESS (2.433s)
[157.714445] Starting dynamic subtest: pipe-B-HDMI-A-2-size-256
[160.129326] Dynamic subtest pipe-B-HDMI-A-2-size-256: SUCCESS (2.433s)
[160.407050] Starting dynamic subtest: pipe-C-HDMI-A-1-size-64
[162.890131] Dynamic subtest pipe-C-HDMI-A-1-size-64: SUCCESS (2.518s)
[162.937385] Starting dynamic subtest: pipe-C-HDMI-A-1-size-128
[165.354325] Dynamic subtest pipe-C-HDMI-A-1-size-128: SUCCESS (2.433s)
[165.406036] Starting dynamic subtest: pipe-C-HDMI-A-1-size-256
[167.802976] Dynamic subtest pipe-C-HDMI-A-1-size-256: SUCCESS (2.416s)
[168.021535] Subtest primary: SUCCESS (23.148s)
[168.321908] [022/133] (884s left) perf_pmu (most-busy-idle-check-all)
[168.442576] Starting subtest: most-busy-idle-check-all
[168.445991] Starting dynamic subtest: rcs0
[169.070886] Dynamic subtest rcs0: SUCCESS (0.623s)
[169.106914] Starting dynamic subtest: bcs0
[169.691592] Dynamic subtest bcs0: SUCCESS (0.620s)
[169.712840] Starting dynamic subtest: vcs0
[170.316164] Dynamic subtest vcs0: SUCCESS (0.628s)
[170.339224] Starting dynamic subtest: vecs0
[170.938967] Dynamic subtest vecs0: SUCCESS (0.620s)
[170.942860] Subtest most-busy-idle-check-all: SUCCESS (2.495s)
[171.094354] [023/133] (881s left) gem_ctx_persistence (legacy-engines-cleanup)
[171.207203] Starting subtest: legacy-engines-cleanup
[171.210587] Starting dynamic subtest: render
[171.232955] Dynamic subtest render: SUCCESS (0.020s)
[171.254981] Starting dynamic subtest: bsd
[171.285266] Dynamic subtest bsd: SUCCESS (0.052s)
[171.309891] Starting dynamic subtest: blt
[171.327164] Dynamic subtest blt: SUCCESS (0.038s)
[171.349098] Starting dynamic subtest: vebox
[171.352492] Dynamic subtest vebox: SUCCESS (0.025s)
[171.356602] Subtest legacy-engines-cleanup: SUCCESS (0.147s)
[171.513063] [024/133] (881s left) gem_partial_pwrite_pread (writes-after-reads-display)
[171.625283] Starting subtest: writes-after-reads-display
[191.408165] Subtest writes-after-reads-display: SUCCESS (19.782s)
[191.603325] [025/133] (861s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-plflip-blt)
[191.797888] Starting subtest: fbc-2p-scndscrn-indfb-plflip-blt
[194.448395] Subtest fbc-2p-scndscrn-indfb-plflip-blt: SUCCESS (2.652s)
[194.683246] [026/133] (858s left) gem_close_race (multigpu-basic-threads)
[194.819497] Starting subtest: multigpu-basic-threads
[194.828170] Subtest multigpu-basic-threads: SKIP (0.000s)
[195.121977] [027/133] (857s left) gem_userptr_blits (unsync-unmap-after-close)
[195.245096] Subtest unsync-unmap-after-close: SKIP (0.000s)
[195.464582] [028/133] (857s left) kms_psr (psr-no-drrs)
[195.650470] Subtest psr-no-drrs: SKIP (0.000s)
[195.759780] [029/133] (857s left) kms_big_fb (linear-32bpp-rotate-0)
[195.968093] Starting subtest: linear-32bpp-rotate-0
[200.643087] Subtest linear-32bpp-rotate-0: SUCCESS (4.675s)
[201.046774] [030/133] (852s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc)
[201.251275] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc
[201.258168] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.001s)
[201.427729] [031/133] (851s left) syncobj_timeline (host-signal-points)
[201.534645] Starting subtest: host-signal-points
[201.553802] Subtest host-signal-points: SUCCESS (0.021s)
[201.727404] [032/133] (851s left) kms_frontbuffer_tracking (fbcpsr-indfb-scaledprimary)
[201.928896] Starting subtest: fbcpsr-indfb-scaledprimary
[201.935445] Subtest fbcpsr-indfb-scaledprimary: SKIP (0.000s)
[202.094141] [033/133] (851s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[202.291380] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen
[202.298503] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[202.463209] [034/133] (851s left) kms_vblank (wait-busy)
[202.651480] Starting subtest: wait-busy
[202.654639] Starting dynamic subtest: pipe-A-HDMI-A-1
[205.515866] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.864s)
[205.540065] Starting dynamic subtest: pipe-A-HDMI-A-2
[208.113146] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.596s)
[208.160541] Starting dynamic subtest: pipe-C-HDMI-A-1
[210.681428] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.555s)
[210.720387] Starting dynamic subtest: pipe-C-HDMI-A-2
[213.257768] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.575s)
[213.261323] Subtest wait-busy: SUCCESS (10.613s)
[213.562618] [035/133] (839s left) kms_ccs (bad-pixel-format-4-tiled-dg2-mc-ccs)
[213.744328] Starting subtest: bad-pixel-format-4-tiled-dg2-mc-ccs
[213.747428] Starting dynamic subtest: pipe-A-HDMI-A-1
[213.821973] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.001s)
[213.859769] Starting dynamic subtest: pipe-A-HDMI-A-2
[213.893151] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.000s)
[213.915474] Starting dynamic subtest: pipe-B-HDMI-A-1
[213.949336] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[213.971970] Starting dynamic subtest: pipe-B-HDMI-A-2
[214.004579] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.001s)
[214.026903] Starting dynamic subtest: pipe-C-HDMI-A-1
[214.060486] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s)
[214.083738] Starting dynamic subtest: pipe-C-HDMI-A-2
[214.115978] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.000s)
[214.122568] Subtest bad-pixel-format-4-tiled-dg2-mc-ccs: SKIP (0.023s)
[214.708947] [036/133] (838s left) kms_vrr (flip-suspend)
[214.896853] Starting subtest: flip-suspend
[214.909910] Subtest flip-suspend: SKIP (0.000s)
[215.041866] [037/133] (838s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[215.242817] Starting subtest: fbcpsr-slowdraw
[215.248987] Subtest fbcpsr-slowdraw: SKIP (0.000s)
[215.407815] [038/133] (838s left) gem_exec_flush (basic-wb-ro-default)
[215.534164] Starting subtest: basic-wb-ro-default
[220.946137] Subtest basic-wb-ro-default: SUCCESS (5.414s)
[221.231088] [039/133] (832s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[221.425630] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (0.000s)
[221.537693] [040/133] (832s left) gem_exec_params (rel-constants-invalid-ring)
[221.650400] Starting subtest: rel-constants-invalid-ring
[221.654277] Subtest rel-constants-invalid-ring: SUCCESS (0.001s)
[221.822729] [041/133] (832s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[221.926482] Starting subtest: single-wait-all-for-submit-available-submitted
[221.930618] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.001s)
[222.089623] [042/133] (832s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-blt)
[222.292972] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-blt
[224.726069] Subtest fbc-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (2.436s)
[225.009499] [043/133] (829s left) kms_cursor_legacy (2x-long-cursor-vs-flip-legacy)
[225.202382] Starting subtest: 2x-long-cursor-vs-flip-legacy
[276.270753] Subtest 2x-long-cursor-vs-flip-legacy: SUCCESS (51.067s)
[276.572942] [044/133] (777s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[276.782374] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[279.220059] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SUCCESS (2.441s)
[279.458711] [045/133] (775s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt)
[279.665753] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt
[279.672689] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[279.838235] [046/133] (774s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[279.945470] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[280.049054] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.106s)
[280.222296] [047/133] (774s left) i915_hangman (gt-error-state-capture)
[280.351680] Starting subtest: gt-error-state-capture
[280.355886] Starting dynamic subtest: rcs0
[290.118896] Dynamic subtest rcs0: SUCCESS (9.763s)
[290.144070] Starting dynamic subtest: bcs0
[300.052582] Dynamic subtest bcs0: SUCCESS (9.931s)
[300.076816] Starting dynamic subtest: vcs0
[310.045595] Dynamic subtest vcs0: SUCCESS (9.990s)
[310.073234] Starting dynamic subtest: vecs0
[319.994562] Dynamic subtest vecs0: SUCCESS (9.944s)
[319.998947] Subtest gt-error-state-capture: SUCCESS (39.642s)
[320.188195] [048/133] (734s left) gem_exec_async (concurrent-writes)
[320.317203] Starting subtest: concurrent-writes
[320.320438] Starting dynamic subtest: rcs0
[320.333097] Dynamic subtest rcs0: SUCCESS (0.016s)
[320.356629] Starting dynamic subtest: bcs0
[320.361416] Dynamic subtest bcs0: SUCCESS (0.011s)
[320.385975] Starting dynamic subtest: vcs0
[320.389947] Dynamic subtest vcs0: SUCCESS (0.019s)
[320.432452] Starting dynamic subtest: vecs0
[320.436711] Dynamic subtest vecs0: SUCCESS (0.014s)
[320.440768] Subtest concurrent-writes: SUCCESS (0.068s)
[320.674293] [049/133] (734s left) gem_eio (in-flight-suspend)
[320.809112] Starting subtest: in-flight-suspend