[89.452399] Initializing watchdogs
[89.452631]   /dev/watchdog0
[89.493751] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[89.508801] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[89.516910] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[89.522900] [001/156] (960s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-shrfb-plflip-blt)
[89.751100] Starting subtest: fbchdr-2p-scndscrn-shrfb-plflip-blt
[89.756420] Subtest fbchdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[89.908672] [002/156] (960s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[90.121177] Starting subtest: psrhdr-1p-offscreen-pri-indfb-draw-mmap-cpu
[90.124779] Subtest psrhdr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[90.267872] [003/156] (959s left) gem_readwrite (beyond-eob)
[90.376325] Starting subtest: beyond-EOB
[90.379988] Subtest beyond-EOB: SUCCESS (0.000s)
[90.515283] [004/156] (959s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[90.708474] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu
[93.211779] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu: SUCCESS (2.503s)
[93.442283] [005/156] (956s left) syncobj_timeline (multi-wait-submitted-signaled)
[93.552886] Starting subtest: multi-wait-submitted-signaled
[93.558526] Subtest multi-wait-submitted-signaled: SUCCESS (0.004s)
[93.710533] [006/156] (956s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[93.813793] Starting subtest: invalid-single-wait-unsubmitted
[93.815475] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.001s)
[93.951459] [007/156] (956s left) gem_exec_whisper (basic-normal)
[94.099158] Starting subtest: basic-normal
[98.505878] Subtest basic-normal: SUCCESS (4.408s)
[98.767493] [008/156] (951s left) i915_drm_fdinfo (virtual-busy-hang)
[98.880208] Starting subtest: virtual-busy-hang
[103.085955] Subtest virtual-busy-hang: SUCCESS (4.206s)
[103.219812] [009/156] (947s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[103.329179] Starting subtest: etime-single-wait-all-available-unsubmitted
[103.437208] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.104s)
[103.574165] [010/156] (947s left) kms_feature_discovery (display-4x)
[103.764842] Starting subtest: display-4x
[103.770096] Subtest display-4x: SKIP (0.000s)
[103.898910] [011/156] (946s left) gem_eio (context-create)
[104.045064] Starting subtest: context-create
[104.119773] Subtest context-create: SUCCESS (0.074s)
[104.363934] [012/156] (946s left) gem_readwrite (read-write)
[104.469212] Starting subtest: read-write
[104.475551] Subtest read-write: SUCCESS (0.001s)
[104.621063] [013/156] (946s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[104.819079] Starting subtest: hdr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[104.824081] Subtest hdr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[104.954741] [014/156] (946s left) gem_softpin (evict-snoop-interruptible)
[105.090790] Starting subtest: evict-snoop-interruptible
[105.094493] Subtest evict-snoop-interruptible: SKIP (0.000s)
[105.222133] [015/156] (946s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[105.406076] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[105.408254] Starting dynamic subtest: pipe-A
[105.730058] Dynamic subtest pipe-A: SUCCESS (0.327s)
[105.756758] Starting dynamic subtest: pipe-B
[106.142059] Dynamic subtest pipe-B: SUCCESS (0.410s)
[106.167673] Starting dynamic subtest: pipe-C
[106.204058] Dynamic subtest pipe-C: SUCCESS (0.064s)
[106.206194] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SUCCESS (0.803s)
[106.680882] [016/156] (944s left) api_intel_bb (misplaced-blitter)
[106.793251] Starting subtest: misplaced-blitter
[106.807297] Subtest misplaced-blitter: SUCCESS (0.016s)
[106.970295] [017/156] (944s left) kms_flip (blocking-absolute-wf_vblank)
[107.152128] Starting subtest: blocking-absolute-wf_vblank
[107.161116] Starting dynamic subtest: A-HDMI-A1
[113.040724] Dynamic subtest A-HDMI-A1: SUCCESS (5.882s)
[113.063354] Starting dynamic subtest: B-HDMI-A1
[118.456428] Dynamic subtest B-HDMI-A1: SUCCESS (5.411s)
[118.478876] Starting dynamic subtest: C-HDMI-A1
[124.064236] Dynamic subtest C-HDMI-A1: SUCCESS (5.605s)
[124.086527] Starting dynamic subtest: A-HDMI-A2
[129.495562] Dynamic subtest A-HDMI-A2: SUCCESS (5.429s)
[129.523204] Starting dynamic subtest: B-HDMI-A2
[135.062747] Dynamic subtest B-HDMI-A2: SUCCESS (5.564s)
[135.088409] Starting dynamic subtest: C-HDMI-A2
[140.470567] Dynamic subtest C-HDMI-A2: SUCCESS (5.399s)
[140.474075] Subtest blocking-absolute-wf_vblank: SUCCESS (33.313s)
[141.003182] [018/156] (910s left) kms_flip (2x-flip-vs-absolute-wf_vblank)
[141.198235] Starting subtest: 2x-flip-vs-absolute-wf_vblank
[141.200907] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[152.470907] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (11.269s)
[152.494316] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[163.509588] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (11.033s)
[163.534460] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[174.533260] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (11.018s)
[174.534868] Subtest 2x-flip-vs-absolute-wf_vblank: SUCCESS (33.337s)
[175.235300] [019/156] (876s left) gem_exec_await (wide-contexts)
[175.610443] Starting subtest: wide-contexts
[198.066181] Subtest wide-contexts: SUCCESS (22.457s)
[198.328106] [FACT gem_exec_await (wide-contexts)] new: kernel.kmod_is_loaded.vgem: true
[198.337832] [020/156] (853s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area)
[198.677654] Subtest psr2-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[198.787334] [021/156] (853s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[199.006656] Starting subtest: fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-cpu
[199.010703] Subtest fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[199.163236] [022/156] (852s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[199.357295] Starting subtest: basic-flip-before-cursor-legacy
[200.751457] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.395s)
[201.073590] [023/156] (850s left) fbdev (write)
[201.196328] Starting subtest: write
[202.018834] Subtest write: SUCCESS (0.823s)
[202.157468] [024/156] (849s left) gem_exec_flush (basic-wb-rw-default)
[202.289068] Starting subtest: basic-wb-rw-default
[207.703805] Subtest basic-wb-rw-default: SUCCESS (5.415s)
[207.974862] [025/156] (844s left) gem_spin_batch (engines)
[208.102184] Starting subtest: engines
[208.105710] Starting dynamic subtest: rcs0
[211.339188] Dynamic subtest rcs0: SUCCESS (3.233s)
[211.362082] Starting dynamic subtest: bcs0
[214.575156] Dynamic subtest bcs0: SUCCESS (3.237s)
[214.598445] Starting dynamic subtest: vcs0
[217.805941] Dynamic subtest vcs0: SUCCESS (3.229s)
[217.828738] Starting dynamic subtest: vecs0
[221.032530] Dynamic subtest vecs0: SUCCESS (3.228s)
[221.034211] Subtest engines: SUCCESS (12.930s)
[221.339477] [026/156] (830s left) kms_dp_link_training (uhbr-sst)
[221.529158] Starting subtest: uhbr-sst
[221.532421] Subtest uhbr-sst: SKIP (0.000s)
[221.672304] [027/156] (830s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[221.863623] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[221.868857] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[224.344303] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (2.472s)
[224.368213] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[226.434102] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (2.086s)
[226.466307] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[228.562712] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (2.119s)
[228.564360] Subtest 2x-flip-vs-dpms-off-vs-modeset: SUCCESS (6.698s)
[228.943274] [028/156] (823s left) kms_sequence (queue-idle)
[229.149209] Starting subtest: queue-idle
[229.153523] Starting dynamic subtest: pipe-A-HDMI-A-1
[231.871332] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.719s)
[231.919136] Starting dynamic subtest: pipe-A-HDMI-A-2
[234.373529] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.499s)
[234.411494] Starting dynamic subtest: pipe-B-HDMI-A-1
[236.857068] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.477s)
[236.898042] Starting dynamic subtest: pipe-B-HDMI-A-2
[239.283697] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (2.424s)
[239.330270] Starting dynamic subtest: pipe-C-HDMI-A-1
[241.706851] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.416s)
[241.745068] Starting dynamic subtest: pipe-C-HDMI-A-2
[244.147757] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.440s)
[244.149394] Subtest queue-idle: SUCCESS (15.003s)
[244.479431] [029/156] (808s left) perf (non-zero-reason)
[244.777693] Starting subtest: non-zero-reason
[244.780777] Starting dynamic subtest: 0-rcs0
[299.650554] Dynamic subtest 0-rcs0: SUCCESS (54.873s)
[299.653095] Subtest non-zero-reason: SUCCESS (54.875s)
[299.763120] [030/156] (752s left) gem_exec_balancer (full-late-pulse)
[299.891579] Starting subtest: full-late-pulse
[304.535616] Subtest full-late-pulse: SUCCESS (4.643s)
[304.843714] [031/156] (747s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[305.104250] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[305.256226] [032/156] (747s left) kms_frontbuffer_tracking (psrhdr-rgb565-draw-mmap-cpu)
[305.564095] Starting subtest: psrhdr-rgb565-draw-mmap-cpu
[305.569074] Subtest psrhdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[305.729332] [033/156] (747s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-pgflip-blt)
[305.934506] Starting subtest: psr-1p-primscrn-shrfb-pgflip-blt
[305.937888] Subtest psr-1p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[306.091521] [034/156] (747s left) kms_color_pipeline (plane-lut1d-ctm3x4-lut1d)
[306.285854] Starting subtest: plane-lut1d-ctm3x4-lut1d
[308.007867] Subtest plane-lut1d-ctm3x4-lut1d: SKIP (1.720s)
[308.337420] [035/156] (744s left) gem_lmem_swapping (heavy-verify-random)
[308.461163] Subtest heavy-verify-random: SKIP (0.000s)
[308.578853] [036/156] (744s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[308.789516] Starting subtest: hdr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[308.794296] Subtest hdr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[308.953104] [037/156] (744s left) kms_big_fb (y-tiled-32bpp-rotate-90)
[309.169050] Starting subtest: y-tiled-32bpp-rotate-90
[314.185232] Subtest y-tiled-32bpp-rotate-90: SUCCESS (5.015s)
[314.646798] [038/156] (738s left) syncobj_wait (single-wait-all-submitted)
[314.755815] Starting subtest: single-wait-all-submitted
[314.865775] Subtest single-wait-all-submitted: SUCCESS (0.109s)
[315.019451] [039/156] (738s left) kms_pm_rpm (pm-caching)
[316.951700] Starting subtest: pm-caching
[318.333673] Subtest pm-caching: SUCCESS (1.382s)
[318.880787] [040/156] (734s left) kms_big_fb (x-tiled-64bpp-rotate-180)
[319.095461] Starting subtest: x-tiled-64bpp-rotate-180
[323.964094] Subtest x-tiled-64bpp-rotate-180: SUCCESS (4.869s)
[324.512915] [041/156] (729s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[324.734129] Starting subtest: fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-gtt
[324.737828] Subtest fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[324.899797] [042/156] (729s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-draw-render)
[325.103952] Starting subtest: hdr-1p-primscrn-spr-indfb-draw-render
[325.109108] Subtest hdr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[325.266767] [043/156] (728s left) kms_vblank (ts-continuation-dpms-suspend)
[325.444022] Starting subtest: ts-continuation-dpms-suspend
[325.452083] Starting dynamic subtest: pipe-A-HDMI-A-1
[343.931950] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.991s)
[344.004950] Starting dynamic subtest: pipe-A-HDMI-A-2