[87.225507] Initializing watchdogs
[87.225697]   /dev/watchdog0
[87.264763] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[87.279767] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[87.287186] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[87.292626] [001/133] (960s left) kms_pm_rpm (pm-caching)
[89.264167] Starting subtest: pm-caching
[90.679877] Subtest pm-caching: SUCCESS (1.413s)
[91.082114] [002/133] (956s left) syncobj_wait (single-wait-for-submit-submitted)
[91.187386] Starting subtest: single-wait-for-submit-submitted
[91.292946] Subtest single-wait-for-submit-submitted: SUCCESS (0.104s)
[91.430149] [003/133] (956s left) gem_exec_schedule (smoketest)
[91.704107] Starting subtest: smoketest
[91.707141] Starting dynamic subtest: rcs0
[97.128778] Dynamic subtest rcs0: SUCCESS (5.421s)
[97.149167] Starting dynamic subtest: bcs0
[102.556225] Dynamic subtest bcs0: SUCCESS (5.426s)
[102.577304] Starting dynamic subtest: vcs0
[107.981124] Dynamic subtest vcs0: SUCCESS (5.423s)
[108.000658] Starting dynamic subtest: vecs0
[113.400168] Dynamic subtest vecs0: SUCCESS (5.418s)
[113.402241] Subtest smoketest: SUCCESS (21.692s)
[113.656209] [004/133] (934s left) gem_tiled_pread_basic (basic)
[113.762059] Starting subtest: basic
[114.094528] Subtest basic: SUCCESS (0.332s)
[114.241055] [005/133] (933s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[114.451176] Starting subtest: yf-tiled-16bpp-rotate-90
[114.810848] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.357s)
[115.037265] [006/133] (933s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[115.786350] Starting subtest: syncobj-timeline-invalid-wait
[115.791264] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.005s)
[116.062512] [FACT gem_exec_fence (syncobj-timeline-invalid-wait)] new: kernel.kmod_is_loaded.vgem: true
[116.068070] [007/133] (932s left) kms_psr (fbc-psr-cursor-plane-move)
[116.261047] Subtest fbc-psr-cursor-plane-move: SKIP (0.000s)
[116.374858] [008/133] (932s left) kms_big_fb (y-tiled-32bpp-rotate-90)
[116.579091] Starting subtest: y-tiled-32bpp-rotate-90
[122.129085] Subtest y-tiled-32bpp-rotate-90: SUCCESS (5.542s)
[122.549341] [009/133] (925s left) gem_mmap_gtt (basic-write-cpu-read-gtt)
[122.656235] Starting subtest: basic-write-cpu-read-gtt
[122.662861] Subtest basic-write-cpu-read-gtt: SKIP (0.000s)
[122.800934] [010/133] (925s left) gem_exec_fence (syncobj-timeline-wait)
[123.468261] Starting subtest: syncobj-timeline-wait
[123.503751] Subtest syncobj-timeline-wait: SUCCESS (0.036s)
[123.774265] [011/133] (924s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-blt)
[123.982207] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-blt
[123.986195] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[124.134326] [012/133] (924s left) kms_async_flips (async-flip-dpms)
[124.318867] Starting subtest: async-flip-dpms
[124.920249] Starting dynamic subtest: pipe-A-HDMI-A-1
[127.621116] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.702s)
[128.031698] Starting dynamic subtest: pipe-A-HDMI-A-2
[130.166110] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.150s)
[130.523599] Starting dynamic subtest: pipe-B-HDMI-A-1
[132.651895] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.145s)
[132.990155] Starting dynamic subtest: pipe-B-HDMI-A-2
[135.104057] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (2.135s)
[135.434362] Starting dynamic subtest: pipe-C-HDMI-A-1
[137.584199] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.165s)
[137.917517] Starting dynamic subtest: pipe-C-HDMI-A-2
[140.072479] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.176s)
[140.074208] Subtest async-flip-dpms: SUCCESS (15.750s)
[140.675794] [013/133] (908s left) kms_setmode (basic-clone-single-crtc)
[140.792612] Starting subtest: basic-clone-single-crtc
[141.289232] Subtest basic-clone-single-crtc: SKIP (0.496s)
[141.584554] [014/133] (907s left) kms_psr (fbc-psr2-no-drrs)
[141.772550] Subtest fbc-psr2-no-drrs: SKIP (0.000s)
[141.870562] [015/133] (907s left) gem_close_race (multigpu-basic-process)
[142.004936] Starting subtest: multigpu-basic-process
[142.008739] Subtest multigpu-basic-process: SKIP (0.000s)
[142.283187] [016/133] (906s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[142.398245] Starting subtest: x-tiled-to-vebox-y-tiled
[142.402535] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[142.666196] [017/133] (906s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[143.242344] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[143.529399] [018/133] (905s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-downscaling)
[143.714764] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-downscaling
[143.718391] Starting dynamic subtest: pipe-A-valid-mode
[144.583221] Dynamic subtest pipe-A-valid-mode: SKIP (0.862s)
[144.586284] Subtest flip-32bpp-xtile-to-64bpp-xtile-downscaling: SKIP (0.865s)
[145.144690] [019/133] (904s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-plflip-blt)
[145.351255] Starting subtest: psr-1p-primscrn-shrfb-plflip-blt
[145.355285] Subtest psr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[145.494256] [020/133] (904s left) gem_mmap_gtt (basic)
[145.602320] Starting subtest: basic
[145.605464] Subtest basic: SUCCESS (0.002s)
[145.768172] [021/133] (903s left) kms_psr (psr-cursor-plane-onoff)
[145.964111] Subtest psr-cursor-plane-onoff: SKIP (0.000s)
[146.066837] [022/133] (903s left) api_intel_bb (reset-bb)
[146.176905] Starting subtest: reset-bb
[146.183263] Subtest reset-bb: SUCCESS (0.006s)
[146.327602] [023/133] (903s left) gem_lmem_swapping (smem-oom)
[146.446123] Subtest smem-oom: SKIP (0.000s)
[146.550130] [024/133] (903s left) gem_flink_basic (bad-open)
[146.657257] Starting subtest: bad-open
[146.659361] Subtest bad-open: SUCCESS (0.000s)
[146.795907] [025/133] (903s left) kms_color_pipeline (plane-lut1d-ctm3x4)
[146.982650] Starting subtest: plane-lut1d-ctm3x4
[148.683587] Subtest plane-lut1d-ctm3x4: SKIP (1.692s)
[148.938158] [026/133] (901s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[149.148866] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[149.154171] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[149.305114] [027/133] (900s left) gem_exec_flush (basic-batch-kernel-default-wb)
[149.432043] Starting subtest: basic-batch-kernel-default-wb
[155.118979] Subtest basic-batch-kernel-default-wb: SUCCESS (5.689s)
[155.374723] [028/133] (894s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render)
[155.572249] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render
[155.580135] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[155.721942] [029/133] (894s left) kms_frontbuffer_tracking (psr-suspend)
[155.968778] Starting subtest: psr-suspend
[155.973715] Subtest psr-suspend: SKIP (0.000s)
[156.122258] [030/133] (894s left) gem_workarounds (basic-read-fd)
[156.259512] Starting subtest: basic-read-fd
[156.281095] Subtest basic-read-fd: SUCCESS (0.021s)
[156.474400] [031/133] (894s left) kms_vblank (ts-continuation-idle)
[156.650076] Starting subtest: ts-continuation-idle
[156.652214] Starting dynamic subtest: pipe-A-HDMI-A-1
[157.342740] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.689s)
[157.387253] Starting dynamic subtest: pipe-A-HDMI-A-2
[157.747300] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.396s)
[157.774412] Starting dynamic subtest: pipe-C-HDMI-A-1
[158.149050] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.393s)
[158.178304] Starting dynamic subtest: pipe-C-HDMI-A-2
[158.561222] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.405s)
[158.564167] Subtest ts-continuation-idle: SUCCESS (1.911s)
[158.887320] [032/133] (891s left) gem_mmap (bad-object)
[158.994315] Starting subtest: bad-object
[158.997113] Subtest bad-object: SUCCESS (0.000s)
[159.154854] [033/133] (891s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[159.344643] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu
[159.347991] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[159.496175] [034/133] (891s left) i915_pm_rps (thresholds-park)
[159.615204] Starting subtest: thresholds-park
[159.617994] Starting dynamic subtest: gt0
[174.424177] Dynamic subtest gt0: SUCCESS (14.808s)
[174.427688] Subtest thresholds-park: SUCCESS (14.811s)
[174.569511] [035/133] (876s left) gem_exec_capture (capture-invisible)
[174.686123] Starting subtest: capture-invisible
[174.688095] Starting dynamic subtest: smem0
[174.692619] Dynamic subtest smem0: SKIP (0.000s)
[174.698701] Subtest capture-invisible: SKIP (0.001s)
[174.835317] [036/133] (876s left) drm_read (invalid-buffer)
[175.280834] Starting subtest: invalid-buffer
[175.282483] Subtest invalid-buffer: SUCCESS (0.000s)
[175.583779] [037/133] (875s left) gem_ctx_persistence (legacy-engines-cleanup)
[175.700179] Starting subtest: legacy-engines-cleanup
[175.705208] Starting dynamic subtest: render
[175.729082] Dynamic subtest render: SUCCESS (0.029s)
[175.760728] Starting dynamic subtest: bsd
[175.764329] Dynamic subtest bsd: SUCCESS (0.020s)
[175.784100] Starting dynamic subtest: blt
[175.786646] Dynamic subtest blt: SUCCESS (0.025s)
[175.807367] Starting dynamic subtest: vebox
[175.815574] Dynamic subtest vebox: SUCCESS (0.029s)
[175.819333] Subtest legacy-engines-cleanup: SUCCESS (0.116s)
[175.967399] [038/133] (875s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[176.170392] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[178.672280] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (2.501s)
[178.966814] [039/133] (872s left) kms_flip (flip-vs-rmfb)
[179.154138] Starting subtest: flip-vs-rmfb
[179.156306] Starting dynamic subtest: A-HDMI-A1
[184.809998] Dynamic subtest A-HDMI-A1: SUCCESS (5.652s)
[184.831098] Starting dynamic subtest: B-HDMI-A1
[190.195310] Dynamic subtest B-HDMI-A1: SUCCESS (5.381s)
[190.217393] Starting dynamic subtest: C-HDMI-A1
[195.585288] Dynamic subtest C-HDMI-A1: SUCCESS (5.385s)
[195.609681] Starting dynamic subtest: A-HDMI-A2
[200.959244] Dynamic subtest A-HDMI-A2: SUCCESS (5.363s)
[200.981307] Starting dynamic subtest: B-HDMI-A2
[206.356235] Dynamic subtest B-HDMI-A2: SUCCESS (5.391s)
[206.380587] Starting dynamic subtest: C-HDMI-A2
[211.759766] Dynamic subtest C-HDMI-A2: SUCCESS (5.398s)
[211.761979] Subtest flip-vs-rmfb: SUCCESS (32.609s)
[212.331437] [040/133] (839s left) kms_chamelium_color (ctm-blue-to-red)
[212.521348] Subtest ctm-blue-to-red: SKIP (0.000s)
[212.640895] [041/133] (838s left) gem_softpin (allocator-nopin)
[212.756078] Starting subtest: allocator-nopin
[212.990091] Subtest allocator-nopin: SUCCESS (0.229s)
[213.167621] [042/133] (838s left) i915_pm_rpm (debugfs-forcewake-user)
[215.104430] Starting subtest: debugfs-forcewake-user
[225.712698] Subtest debugfs-forcewake-user: SUCCESS (10.608s)
[226.142239] [043/133] (825s left) kms_cursor_legacy (cursor-vs-flip-atomic)
[226.341891] Starting subtest: cursor-vs-flip-atomic
[266.282577] Subtest cursor-vs-flip-atomic: SUCCESS (39.940s)
[266.633154] [044/133] (785s left) kms_chamelium_frames (hdmi-crc-multiple)
[267.265078] Subtest hdmi-crc-multiple: SKIP (0.000s)
[267.550545] [045/133] (784s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[267.767378] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu
[270.585964] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (2.818s)
[270.881275] [046/133] (781s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs-cc)
[271.075261] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs-cc
[271.078163] Starting dynamic subtest: pipe-A-HDMI-A-1
[271.085774] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.001s)
[271.126589] Starting dynamic subtest: pipe-A-HDMI-A-2
[271.143076] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.001s)
[271.183118] Starting dynamic subtest: pipe-B-HDMI-A-1
[271.199601] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s)
[271.229140] Starting dynamic subtest: pipe-B-HDMI-A-2
[271.235863] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.001s)
[271.254888] Starting dynamic subtest: pipe-C-HDMI-A-1
[271.262061] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s)
[271.280709] Starting dynamic subtest: pipe-C-HDMI-A-2
[271.287112] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.001s)
[271.290165] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs-cc: SKIP (0.037s)
[272.102290] [047/133] (780s left) gem_workarounds (suspend-resume-fd)
[272.245262] Starting subtest: suspend-resume-fd
[288.739194] Subtest suspend-resume-fd: SUCCESS (1.430s)
[302.974575] [048/133] (763s left) gem_mmap_offset (clear-via-pagefault)
[303.089467] Starting subtest: clear-via-pagefault
[303.092683] Starting dynamic subtest: smem0
[325.354506] Dynamic subtest smem0: SUCCESS (21.919s)
[325.359063] Subtest clear-via-pagefault: SUCCESS (21.922s)
[325.505332] [049/133] (740s left) kms_psr (pr-sprite-blt)
[325.694700] Subtest pr-sprite-blt: SKIP (0.000s)
[325.809082] [050/133] (740s left) gem_exec_whisper (basic-forked)
[325.943806] Starting subtest: basic-forked
[330.703117] Subtest basic-forked: SUCCESS (4.747s)
[330.968985] [051/133] (735s left) gem_request_retire (retire-vma-not-inactive)
[331.087226] Starting subtest: retire-vma-not-inactive
[331.115402] Subtest retire-vma-not-inactive: SUCCESS (0.026s)
[331.283252] [052/133] (735s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-downscaling)
[331.473860] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-downscaling
[331.478566] Subtest flip-64bpp-4tile-to-32bpp-4tile-downscaling: SKIP (0.000s)
[331.616377] [053/133] (735s left) gem_render_linear_blits (basic)
[331.736360] Starting subtest: basic
[331.806255] Subtest basic: SUCCESS (0.068s)
[331.927002] [054/133] (734s left) kms_cursor_legacy (forked-bo)
[332.124024] Starting subtest: forked-bo
[332.128672] Starting dynamic subtest: pipe-A
[337.556359] Dynamic subtest pipe-A: SUCCESS (5.417s)
[337.602158] Starting dynamic subtest: pipe-B
[342.990524] Dynamic subtest pipe-B: SUCCESS (5.430s)
[343.101521] Starting dynamic subtest: pipe-C
[348.403839] Dynamic subtest pipe-C: SUCCESS (5.408s)
[348.470895] Starting dynamic subtest: all-pipes
[353.865716] Dynamic subtest all-pipes: SUCCESS (5.451s)
[353.867658] Subtest forked-bo: SUCCESS (21.726s)
[354.032573] [055/133] (712s left) syncobj_timeline (etime-multi-wait-all-submitted-signaled)
[354.146216] Starting subtest: etime-multi-wait-all-submitted-signaled
[354.252283] Subtest etime-multi-wait-all-submitted-signaled: SUCCESS (0.110s)
[354.399797] [056/133] (712s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[354.615014] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu
[354.620931] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[354.763241] [057/133] (712s left) api_intel_allocator (open-vm)
[354.871848] Starting subtest: open-vm
[354.873724] Subtest open-vm: SUCCESS (0.004s)
[355.062746] [058/133] (712s left) kms_flip (2x-dpms-vs-vblank-race-interruptible)
[355.255596] Starting subtest: 2x-dpms-vs-vblank-race-interruptible
[355.264742] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[359.888866] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (4.616s)
[359.915726] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[364.153050] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (4.260s)
[364.184922] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[368.471795] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (4.302s)
[368.478953] Subtest 2x-dpms-vs-vblank-race-interruptible: SUCCESS (13.205s)
[368.895446] [059/133] (698s left) kms_getfb (getfb-repeated-different-handles)
[369.103742] Starting subtest: getfb-repeated-different-handles
[369.107241] Subtest getfb-repeated-different-handles: SUCCESS (0.000s)
[369.253705] [060/133] (698s left) kms_ccs (crc-sprite-planes-basic-y-tiled-ccs)
[369.439590] Starting subtest: crc-sprite-planes-basic-y-tiled-ccs
[369.451751] Starting dynamic subtest: pipe-A-HDMI-A-1
[370.308235] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.842s)
[370.346700] Starting dynamic subtest: pipe-A-HDMI-A-2
[371.180494] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.846s)
[371.232131] Starting dynamic subtest: pipe-B-HDMI-A-1
[372.076317] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.908s)
[372.108605] Starting dynamic subtest: pipe-B-HDMI-A-2
[372.896528] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.814s)
[372.927116] Starting dynamic subtest: pipe-C-HDMI-A-1
[373.039729] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.013s)
[373.065747] Starting dynamic subtest: pipe-C-HDMI-A-2
[373.129331] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.012s)
[373.130845] Subtest crc-sprite-planes-basic-y-tiled-ccs: SUCCESS (3.472s)
[373.719731] [061/133] (693s left) gem_tiled_fence_blits (basic)
[374.822710] Starting subtest: basic
[374.853987] Subtest basic: SUCCESS (0.033s)
[375.074122] [062/133] (692s left) kms_busy (basic-hang)
[375.401881] Starting subtest: basic-hang
[375.405683] Starting dynamic subtest: flip-pipe-A
[376.460407] Dynamic subtest flip-pipe-A: SUCCESS (1.056s)
[376.497116] Starting dynamic subtest: modeset-pipe-A
[377.872522] Dynamic subtest modeset-pipe-A: SUCCESS (1.405s)
[377.942985] Starting dynamic subtest: flip-pipe-C
[378.509822] Dynamic subtest flip-pipe-C: SUCCESS (0.615s)
[378.544580] Starting dynamic subtest: modeset-pipe-C
[379.846584] Dynamic subtest modeset-pipe-C: SUCCESS (1.332s)
[379.859629] Subtest basic-hang: SUCCESS (4.452s)
[380.266879] [063/133] (687s left) gem_softpin (noreloc-s3)
[380.394316] Starting subtest: noreloc-S3