[86.683597] Initializing watchdogs
[86.683771]   /dev/watchdog0
[86.724638] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[86.740118] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[86.746658] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[86.751710] [001/133] (960s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[87.355189] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[87.603454] [002/133] (959s left) gem_exec_params (sol-reset-invalid)
[87.718237] Starting subtest: sol-reset-invalid
[87.726638] Subtest sol-reset-invalid: SUCCESS (0.006s)
[87.870608] [003/133] (959s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0)
[88.068502] Starting subtest: linear-max-hw-stride-32bpp-rotate-0
[89.899022] Subtest linear-max-hw-stride-32bpp-rotate-0: SUCCESS (1.834s)
[90.169260] [004/133] (957s left) kms_flip (2x-plain-flip)
[90.336591] Starting subtest: 2x-plain-flip
[90.343350] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[91.734587] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (1.392s)
[91.753300] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[93.087859] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (1.349s)
[93.105413] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[94.446506] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (1.355s)
[94.447717] Subtest 2x-plain-flip: SUCCESS (4.113s)
[95.053954] [005/133] (952s left) api_intel_allocator (reloc-allocator)
[95.164046] Starting subtest: reloc-allocator
[95.165100] Starting dynamic subtest: basic
[95.167204] Dynamic subtest basic: SUCCESS (0.010s)
[95.184034] Starting dynamic subtest: parallel-one
[95.185087] Dynamic subtest parallel-one: SUCCESS (0.017s)
[95.202039] Starting dynamic subtest: print
[95.202885] Dynamic subtest print: SUCCESS (0.000s)
[95.228857] Starting dynamic subtest: reuse
[95.229727] Dynamic subtest reuse: SUCCESS (0.000s)
[95.245291] Starting dynamic subtest: fork-reopen-allocator
[95.246268] Dynamic subtest fork-reopen-allocator: SUCCESS (0.038s)
[95.247352] Subtest reloc-allocator: SUCCESS (0.070s)
[95.404205] [006/133] (952s left) i915_drm_fdinfo (busy-idle-check-all)
[95.510703] Starting subtest: busy-idle-check-all
[95.516100] Starting dynamic subtest: rcs0
[96.149043] Dynamic subtest rcs0: SUCCESS (0.637s)
[96.164675] Starting dynamic subtest: bcs0
[96.786481] Dynamic subtest bcs0: SUCCESS (0.635s)
[96.802524] Starting dynamic subtest: vcs0
[97.418181] Dynamic subtest vcs0: SUCCESS (0.630s)
[97.433681] Starting dynamic subtest: vecs0
[98.050444] Dynamic subtest vecs0: SUCCESS (0.631s)
[98.051340] Subtest busy-idle-check-all: SUCCESS (2.538s)
[98.207703] [007/133] (949s left) gem_set_tiling_vs_gtt
[98.752579] [008/133] (949s left) sysfs_preempt_timeout (invalid)
[98.861060] Starting subtest: invalid
[98.862076] Starting dynamic subtest: vecs0
[98.865135] Dynamic subtest vecs0: SUCCESS (0.001s)
[98.880804] Starting dynamic subtest: vcs0
[98.881911] Dynamic subtest vcs0: SUCCESS (0.002s)
[98.896916] Starting dynamic subtest: bcs0
[98.897999] Dynamic subtest bcs0: SUCCESS (0.001s)
[98.914476] Starting dynamic subtest: rcs0
[98.915647] Dynamic subtest rcs0: SUCCESS (0.001s)
[98.916931] Subtest invalid: SUCCESS (0.011s)
[99.037219] [009/133] (948s left) i915_query (hwconfig_table)
[99.133129] Starting subtest: hwconfig_table
[99.136744] Subtest hwconfig_table: SKIP (0.000s)
[99.287220] [010/133] (948s left) kms_flip (2x-blocking-absolute-wf_vblank)
[99.458789] Starting subtest: 2x-blocking-absolute-wf_vblank
[99.465234] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[110.225407] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (10.758s)
[110.244109] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[120.929685] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (10.698s)
[120.945725] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[131.627615] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (10.693s)
[131.628650] Subtest 2x-blocking-absolute-wf_vblank: SUCCESS (32.163s)
[132.248505] [011/133] (915s left) gem_ctx_param (invalid-param-set)
[132.345905] Starting subtest: invalid-param-set
[132.348696] Subtest invalid-param-set: SUCCESS (0.000s)
[132.490751] [012/133] (915s left) gem_mmap_gtt (medium-copy-odd)
[132.586230] Starting subtest: medium-copy-odd
[137.524490] Subtest medium-copy-odd: SUCCESS (4.935s)
[137.712339] [013/133] (910s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[138.020092] Starting subtest: 4-tiled-8bpp-rotate-0
[138.023061] Subtest 4-tiled-8bpp-rotate-0: SKIP (0.000s)
[138.164694] [014/133] (910s left) sw_sync (sync_random_merge)
[138.269481] Starting subtest: sync_random_merge
[138.470383] Subtest sync_random_merge: SUCCESS (0.201s)
[138.619819] [015/133] (909s left) gem_ctx_create (basic)
[138.739562] Starting subtest: basic
[138.742252] Subtest basic: SUCCESS (0.000s)
[139.007931] [016/133] (909s left) kms_chamelium_frames (dp-crc-multiple)
[139.549833] Subtest dp-crc-multiple: SKIP (0.000s)
[139.803362] [017/133] (908s left) syncobj_wait (single-wait-for-submit-signaled)
[139.908458] Starting subtest: single-wait-for-submit-signaled
[139.910616] Subtest single-wait-for-submit-signaled: SUCCESS (0.001s)
[140.051810] [018/133] (908s left) kms_cursor_crc (cursor-random-max-size)
[140.260731] Starting subtest: cursor-random-max-size
[140.264715] Subtest cursor-random-max-size: SKIP (0.000s)
[140.419303] [019/133] (908s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[140.617581] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-cpu
[140.619888] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[140.785136] [020/133] (907s left) gem_lmem_swapping (random)
[140.898463] Subtest random: SKIP (0.000s)
[140.979482] [021/133] (907s left) kms_plane_cursor (primary)
[141.154048] Starting subtest: primary
[141.158595] Starting dynamic subtest: pipe-A-HDMI-A-1-size-64
[143.764086] Dynamic subtest pipe-A-HDMI-A-1-size-64: SUCCESS (2.608s)
[143.832047] Starting dynamic subtest: pipe-A-HDMI-A-1-size-128
[146.250046] Dynamic subtest pipe-A-HDMI-A-1-size-128: SUCCESS (2.432s)
[146.318434] Starting dynamic subtest: pipe-A-HDMI-A-1-size-256
[148.715077] Dynamic subtest pipe-A-HDMI-A-1-size-256: SUCCESS (2.416s)
[149.023921] Starting dynamic subtest: pipe-B-HDMI-A-2-size-64
[151.469764] Dynamic subtest pipe-B-HDMI-A-2-size-64: SUCCESS (2.496s)
[151.518435] Starting dynamic subtest: pipe-B-HDMI-A-2-size-128
[153.936032] Dynamic subtest pipe-B-HDMI-A-2-size-128: SUCCESS (2.432s)
[153.982849] Starting dynamic subtest: pipe-B-HDMI-A-2-size-256
[156.399692] Dynamic subtest pipe-B-HDMI-A-2-size-256: SUCCESS (2.433s)
[156.642321] Starting dynamic subtest: pipe-C-HDMI-A-1-size-64
[159.138792] Dynamic subtest pipe-C-HDMI-A-1-size-64: SUCCESS (2.511s)
[159.187233] Starting dynamic subtest: pipe-C-HDMI-A-1-size-128
[161.602407] Dynamic subtest pipe-C-HDMI-A-1-size-128: SUCCESS (2.432s)
[161.653844] Starting dynamic subtest: pipe-C-HDMI-A-1-size-256
[164.070033] Dynamic subtest pipe-C-HDMI-A-1-size-256: SUCCESS (2.432s)
[164.298909] Subtest primary: SUCCESS (23.148s)
[164.607642] [022/133] (884s left) perf_pmu (most-busy-idle-check-all)
[164.722737] Starting subtest: most-busy-idle-check-all
[164.723651] Starting dynamic subtest: rcs0
[165.354072] Dynamic subtest rcs0: SUCCESS (0.630s)
[165.373763] Starting dynamic subtest: bcs0
[165.983043] Dynamic subtest bcs0: SUCCESS (0.627s)
[166.002872] Starting dynamic subtest: vcs0
[166.606608] Dynamic subtest vcs0: SUCCESS (0.623s)
[166.625092] Starting dynamic subtest: vecs0
[167.233905] Dynamic subtest vecs0: SUCCESS (0.625s)
[167.235189] Subtest most-busy-idle-check-all: SUCCESS (2.513s)
[167.383083] [023/133] (881s left) gem_ctx_persistence (legacy-engines-cleanup)
[167.488852] Starting subtest: legacy-engines-cleanup
[167.491859] Starting dynamic subtest: render
[167.519235] Dynamic subtest render: SUCCESS (0.028s)
[167.543866] Starting dynamic subtest: bsd
[167.553078] Dynamic subtest bsd: SUCCESS (0.029s)
[167.571755] Starting dynamic subtest: blt
[167.576181] Dynamic subtest blt: SUCCESS (0.024s)
[167.599936] Starting dynamic subtest: vebox
[167.601217] Dynamic subtest vebox: SUCCESS (0.018s)
[167.604449] Subtest legacy-engines-cleanup: SUCCESS (0.112s)
[167.744437] [024/133] (881s left) gem_partial_pwrite_pread (writes-after-reads-display)
[167.852687] Starting subtest: writes-after-reads-display
[187.804299] Subtest writes-after-reads-display: SUCCESS (19.953s)
[187.976570] [025/133] (861s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-plflip-blt)
[188.175651] Starting subtest: fbc-2p-scndscrn-indfb-plflip-blt
[190.889884] Subtest fbc-2p-scndscrn-indfb-plflip-blt: SUCCESS (2.710s)
[191.101960] [026/133] (858s left) gem_close_race (multigpu-basic-threads)
[191.230803] Starting subtest: multigpu-basic-threads
[191.232594] Subtest multigpu-basic-threads: SKIP (0.000s)
[191.499570] [027/133] (857s left) gem_userptr_blits (unsync-unmap-after-close)
[191.607902] Subtest unsync-unmap-after-close: SKIP (0.000s)
[191.815499] [028/133] (857s left) kms_psr (psr-no-drrs)
[192.004414] Subtest psr-no-drrs: SKIP (0.000s)
[192.101384] [029/133] (857s left) kms_big_fb (linear-32bpp-rotate-0)
[192.292855] Starting subtest: linear-32bpp-rotate-0
[197.070260] Subtest linear-32bpp-rotate-0: SUCCESS (4.774s)
[197.459763] [030/133] (852s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc)
[197.655240] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc
[197.657057] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.001s)
[197.825877] [031/133] (851s left) syncobj_timeline (host-signal-points)
[197.931564] Starting subtest: host-signal-points
[197.952947] Subtest host-signal-points: SUCCESS (0.022s)
[198.103477] [032/133] (851s left) kms_frontbuffer_tracking (fbcpsr-indfb-scaledprimary)
[198.283649] Starting subtest: fbcpsr-indfb-scaledprimary
[198.286251] Subtest fbcpsr-indfb-scaledprimary: SKIP (0.000s)
[198.471344] [033/133] (851s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[198.669874] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen
[198.673493] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[198.810981] [034/133] (851s left) kms_vblank (wait-busy)
[198.987441] Starting subtest: wait-busy
[198.994596] Starting dynamic subtest: pipe-A-HDMI-A-1
[201.870147] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.876s)
[201.920591] Starting dynamic subtest: pipe-A-HDMI-A-2
[204.471660] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.596s)
[204.521349] Starting dynamic subtest: pipe-C-HDMI-A-1
[207.061102] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.577s)
[207.106787] Starting dynamic subtest: pipe-C-HDMI-A-2
[209.639747] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.572s)
[209.640969] Subtest wait-busy: SUCCESS (10.652s)
[209.939805] [035/133] (840s left) kms_ccs (bad-pixel-format-4-tiled-dg2-mc-ccs)
[210.112384] Starting subtest: bad-pixel-format-4-tiled-dg2-mc-ccs
[210.122433] Starting dynamic subtest: pipe-A-HDMI-A-1
[210.132513] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[210.171529] Starting dynamic subtest: pipe-A-HDMI-A-2
[210.183680] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.001s)
[210.235229] Starting dynamic subtest: pipe-B-HDMI-A-1
[210.243986] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[210.263215] Starting dynamic subtest: pipe-B-HDMI-A-2
[210.273157] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.000s)
[210.291900] Starting dynamic subtest: pipe-C-HDMI-A-1
[210.302401] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[210.321764] Starting dynamic subtest: pipe-C-HDMI-A-2
[210.330953] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.000s)
[210.332652] Subtest bad-pixel-format-4-tiled-dg2-mc-ccs: SKIP (0.021s)
[211.097440] [036/133] (838s left) kms_vrr (flip-suspend)
[211.293086] Starting subtest: flip-suspend
[211.298952] Subtest flip-suspend: SKIP (0.001s)
[211.461988] [037/133] (838s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[211.654273] Starting subtest: fbcpsr-slowdraw
[211.656156] Subtest fbcpsr-slowdraw: SKIP (0.000s)
[211.830468] [038/133] (838s left) gem_exec_flush (basic-wb-ro-default)
[211.953089] Starting subtest: basic-wb-ro-default
[217.363608] Subtest basic-wb-ro-default: SUCCESS (5.413s)
[217.654700] [039/133] (832s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[217.823920] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (0.000s)
[217.925579] [040/133] (832s left) gem_exec_params (rel-constants-invalid-ring)
[218.036796] Starting subtest: rel-constants-invalid-ring
[218.038754] Subtest rel-constants-invalid-ring: SUCCESS (0.001s)
[218.218701] [041/133] (832s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[218.317702] Starting subtest: single-wait-all-for-submit-available-submitted
[218.320412] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.001s)
[218.470813] [042/133] (832s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-blt)
[218.671788] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-blt
[221.090738] Subtest fbc-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (2.420s)
[221.362211] [043/133] (829s left) kms_cursor_legacy (2x-long-cursor-vs-flip-legacy)
[221.541014] Starting subtest: 2x-long-cursor-vs-flip-legacy
[272.748895] Subtest 2x-long-cursor-vs-flip-legacy: SUCCESS (51.204s)
[273.041147] [044/133] (777s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[273.234072] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[275.720970] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SUCCESS (2.487s)
[275.957654] [045/133] (774s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt)
[276.147474] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt
[276.151251] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[276.307634] [046/133] (774s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[276.413691] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[276.518995] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.106s)
[276.678284] [047/133] (774s left) i915_hangman (gt-error-state-capture)
[276.811371] Starting subtest: gt-error-state-capture
[276.812546] Starting dynamic subtest: rcs0
[286.068526] Dynamic subtest rcs0: SUCCESS (9.258s)
[286.097073] Starting dynamic subtest: bcs0
[295.997435] Dynamic subtest bcs0: SUCCESS (9.925s)
[296.043720] Starting dynamic subtest: vcs0
[306.068530] Dynamic subtest vcs0: SUCCESS (10.065s)
[306.094848] Starting dynamic subtest: vecs0
[316.057404] Dynamic subtest vecs0: SUCCESS (9.986s)
[316.063398] Subtest gt-error-state-capture: SUCCESS (39.252s)
[316.240193] [048/133] (734s left) gem_exec_async (concurrent-writes)
[316.358066] Starting subtest: concurrent-writes
[316.360355] Starting dynamic subtest: rcs0
[316.377665] Dynamic subtest rcs0: SUCCESS (0.019s)
[316.407856] Starting dynamic subtest: bcs0
[316.413101] Dynamic subtest bcs0: SUCCESS (0.013s)
[316.434761] Starting dynamic subtest: vcs0
[316.436728] Dynamic subtest vcs0: SUCCESS (0.013s)
[316.455321] Starting dynamic subtest: vecs0
[316.456283] Dynamic subtest vecs0: SUCCESS (0.013s)
[316.457376] Subtest concurrent-writes: SUCCESS (0.067s)
[316.710134] [049/133] (734s left) gem_eio (in-flight-suspend)
[316.839602] Starting subtest: in-flight-suspend