[91.591694] Initializing watchdogs
[91.591866]   /dev/watchdog0
[91.631551] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[91.646317] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[91.653200] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[91.661165] [001/133] (960s left) kms_big_fb (4-tiled-16bpp-rotate-90)
[91.899689] Starting subtest: 4-tiled-16bpp-rotate-90
[91.907975] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.000s)
[92.063874] [002/133] (960s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[92.273611] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-cpu
[92.279988] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[92.429430] [003/133] (959s left) kms_content_protection (uevent)
[92.637128] Starting subtest: uevent
[92.655602] Subtest uevent: SKIP (0.012s)
[92.782312] [004/133] (959s left) kms_plane_alpha_blend (coverage-vs-premult-vs-constant)
[92.964220] Starting subtest: coverage-vs-premult-vs-constant
[93.124061] Starting dynamic subtest: pipe-A-HDMI-A-1
[93.912707] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.784s)
[93.964334] Starting dynamic subtest: pipe-C-HDMI-A-1
[94.586628] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.662s)
[94.591081] Subtest coverage-vs-premult-vs-constant: SUCCESS (1.612s)
[94.912810] [005/133] (957s left) gem_ctx_isolation (preservation)
[95.058718] Starting subtest: preservation
[95.063593] Starting dynamic subtest: rcs0
[95.465687] Dynamic subtest rcs0: SUCCESS (0.406s)
[95.492838] Starting dynamic subtest: bcs0
[96.145625] Dynamic subtest bcs0: SUCCESS (0.671s)
[96.167931] Starting dynamic subtest: vcs0
[96.551158] Dynamic subtest vcs0: SUCCESS (0.414s)
[96.578247] Starting dynamic subtest: vecs0
[96.958482] Dynamic subtest vecs0: SUCCESS (0.405s)
[96.962580] Subtest preservation: SUCCESS (1.903s)
[97.225160] [006/133] (955s left) perf (buffer-fill)
[97.436166] Starting subtest: buffer-fill
[97.440180] Starting dynamic subtest: 0-rcs0
[100.497614] Dynamic subtest 0-rcs0: SUCCESS (3.059s)
[100.501608] Subtest buffer-fill: SUCCESS (3.060s)
[100.593055] [007/133] (952s left) gem_huc_copy (huc-copy)
[100.715171] Starting subtest: huc-copy
[100.722267] Subtest huc-copy: SKIP (0.000s)
[100.871836] [008/133] (951s left) perf (invalid-create-userspace-config)
[101.104689] Starting subtest: invalid-create-userspace-config
[101.113723] Subtest invalid-create-userspace-config: SUCCESS (0.005s)
[101.211880] [009/133] (951s left) kms_prime (d3hot)
[101.428154] Subtest D3hot: SKIP (0.000s)
[101.705633] [FACT kms_prime (d3hot)] new: kernel.kmod_is_loaded.vgem: true
[101.712199] [010/133] (951s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling)
[101.890449] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling
[101.900111] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.000s)
[102.036514] [011/133] (951s left) kms_cursor_legacy (torture-move)
[102.236050] Starting subtest: torture-move
[102.243156] Starting dynamic subtest: pipe-A
[107.892090] Dynamic subtest pipe-A: SUCCESS (5.663s)
[107.928159] Starting dynamic subtest: pipe-B
[114.308257] Dynamic subtest pipe-B: SUCCESS (6.413s)
[114.496285] Starting dynamic subtest: pipe-C
[119.923089] Dynamic subtest pipe-C: SUCCESS (5.612s)
[119.949052] Starting dynamic subtest: all-pipes
[125.451277] Dynamic subtest all-pipes: SUCCESS (5.529s)
[125.455993] Subtest torture-move: SUCCESS (23.226s)
[125.602726] [012/133] (927s left) gem_ctx_persistence (saturated-hostile)
[125.726528] Starting subtest: saturated-hostile
[125.733301] Starting dynamic subtest: rcs0
[126.463397] Dynamic subtest rcs0: SUCCESS (0.737s)
[126.488147] Starting dynamic subtest: bcs0
[127.219213] Dynamic subtest bcs0: SUCCESS (0.755s)
[127.242253] Starting dynamic subtest: vcs0
[127.967615] Dynamic subtest vcs0: SUCCESS (0.748s)
[127.989246] Starting dynamic subtest: vecs0
[128.727275] Dynamic subtest vecs0: SUCCESS (0.758s)
[128.730947] Subtest saturated-hostile: SUCCESS (3.004s)
[128.900338] [013/133] (924s left) sw_sync (sync_multi_consumer_producer)
[129.011213] Starting subtest: sync_multi_consumer_producer
[129.480349] Subtest sync_multi_consumer_producer: SUCCESS (0.470s)
[129.619437] [014/133] (923s left) syncobj_timeline (invalid-multi-wait-unsubmitted-signaled)
[129.735898] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[129.739264] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.003s)
[129.885612] [015/133] (923s left) gem_exec_reloc (basic-cpu-gtt-active)
[130.007984] Starting subtest: basic-cpu-gtt-active
[130.152047] Subtest basic-cpu-gtt-active: SUCCESS (0.144s)
[130.387433] [016/133] (923s left) kms_cursor_legacy (flip-vs-cursor-legacy)
[130.577669] Starting subtest: flip-vs-cursor-legacy
[149.033066] Subtest flip-vs-cursor-legacy: SUCCESS (18.453s)
[149.332563] [017/133] (904s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[149.548342] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[149.565085] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.003s)
[149.698575] [018/133] (904s left) kms_draw_crc (draw-method-mmap-cpu)
[149.890075] Starting subtest: draw-method-mmap-cpu
[149.894517] Starting dynamic subtest: xrgb8888-untiled
[150.350297] Dynamic subtest xrgb8888-untiled: SUCCESS (0.468s)
[150.375206] Starting dynamic subtest: rgb565-untiled
[150.617167] Dynamic subtest rgb565-untiled: SUCCESS (0.264s)
[150.646435] Starting dynamic subtest: xrgb2101010-untiled
[150.886314] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.266s)
[150.921501] Starting dynamic subtest: xbgr16161616f-untiled
[151.166908] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.283s)
[151.186671] Starting dynamic subtest: xrgb8888-xtiled
[151.351272] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.184s)
[151.370844] Starting dynamic subtest: rgb565-xtiled
[151.533388] Dynamic subtest rgb565-xtiled: SUCCESS (0.182s)
[151.553133] Starting dynamic subtest: xrgb2101010-xtiled
[151.696427] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.164s)
[151.717661] Starting dynamic subtest: xbgr16161616f-xtiled
[151.883679] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.182s)
[151.903476] Starting dynamic subtest: xrgb8888-ytiled
[152.066697] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.183s)
[152.086667] Starting dynamic subtest: rgb565-ytiled
[152.250232] Dynamic subtest rgb565-ytiled: SUCCESS (0.182s)
[152.270531] Starting dynamic subtest: xrgb2101010-ytiled
[152.415342] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.165s)
[152.435921] Starting dynamic subtest: xbgr16161616f-ytiled
[152.598453] Dynamic subtest xbgr16161616f-ytiled: SUCCESS (0.182s)
[152.602518] Subtest draw-method-mmap-cpu: SUCCESS (2.718s)
[152.848663] [019/133] (901s left) gem_exec_schedule (noreorder-priority)
[153.120050] Starting subtest: noreorder-priority
[153.124566] Starting dynamic subtest: rcs0
[154.245288] Dynamic subtest rcs0: SUCCESS (1.123s)
[154.267154] Starting dynamic subtest: bcs0
[155.364819] Dynamic subtest bcs0: SUCCESS (1.118s)
[155.386088] Starting dynamic subtest: vcs0
[156.484139] Dynamic subtest vcs0: SUCCESS (1.116s)
[156.557877] Starting dynamic subtest: vecs0
[157.597482] Dynamic subtest vecs0: SUCCESS (1.112s)
[157.601411] Subtest noreorder-priority: SUCCESS (4.478s)
[157.865237] [020/133] (896s left) kms_chamelium_color (ctm-0-75)
[158.056067] Subtest ctm-0-75: SKIP (0.000s)
[158.174586] [021/133] (895s left) kms_psr (fbc-psr2-sprite-render)
[158.364074] Subtest fbc-psr2-sprite-render: SKIP (0.000s)
[158.476886] [022/133] (895s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[158.684078] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[158.694062] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[158.833517] [023/133] (895s left) syncobj_timeline (32bits-limit)
[158.947210] Starting subtest: 32bits-limit
[158.952667] Subtest 32bits-limit: SUCCESS (0.005s)
[159.096669] [024/133] (895s left) kms_frontbuffer_tracking (fbc-tiling-4)
[159.310590] Starting subtest: fbc-tiling-4
[159.318517] Subtest fbc-tiling-4: SKIP (0.000s)
[159.467468] [025/133] (895s left) i915_hangman (engine-engine-hang)
[159.640712] Starting subtest: engine-engine-hang
[159.645386] Starting dynamic subtest: rcs0
[160.220539] Dynamic subtest rcs0: SUCCESS (0.581s)
[160.243419] Starting dynamic subtest: bcs0
[160.802155] Dynamic subtest bcs0: SUCCESS (0.578s)
[160.823733] Starting dynamic subtest: vcs0
[161.373560] Dynamic subtest vcs0: SUCCESS (0.572s)
[161.395914] Starting dynamic subtest: vecs0
[161.942762] Dynamic subtest vecs0: SUCCESS (0.570s)
[161.946373] Subtest engine-engine-hang: SUCCESS (2.307s)
[162.112740] [026/133] (892s left) syncobj_timeline (signal-point-0)
[162.230745] Starting subtest: signal-point-0
[162.234373] Subtest signal-point-0: SUCCESS (0.001s)
[162.382439] [027/133] (892s left) syncobj_timeline (etime-single-wait-for-submit-unsubmitted)
[162.490930] Starting subtest: etime-single-wait-for-submit-unsubmitted
[162.596516] Subtest etime-single-wait-for-submit-unsubmitted: SUCCESS (0.105s)
[162.747786] [028/133] (892s left) kms_pm_dc (dc5-retention-flops)
[163.954837] Starting subtest: dc5-retention-flops
[163.961538] Subtest dc5-retention-flops: SKIP (0.000s)
[164.099602] [029/133] (890s left) gem_exec_params (rs-invalid)
[164.220121] Starting subtest: rs-invalid
[164.228418] Subtest rs-invalid: SUCCESS (0.009s)
[164.379970] [030/133] (890s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[164.583326] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[164.589820] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[164.732755] [031/133] (890s left) gem_exec_fence (basic-await)
[164.998082] Starting subtest: basic-await
[165.001602] Starting dynamic subtest: rcs0
[165.116563] Dynamic subtest rcs0: SUCCESS (0.117s)
[165.138084] Starting dynamic subtest: bcs0
[165.241454] Dynamic subtest bcs0: SUCCESS (0.122s)
[165.265045] Starting dynamic subtest: vcs0
[165.356412] Dynamic subtest vcs0: SUCCESS (0.117s)
[165.380048] Starting dynamic subtest: vecs0
[165.472644] Dynamic subtest vecs0: SUCCESS (0.113s)
[165.477203] Subtest basic-await: SUCCESS (0.474s)
[166.179898] [032/133] (889s left) gem_exec_reloc (basic-gtt-wc-active)
[166.303229] Starting subtest: basic-gtt-wc-active
[166.444737] Subtest basic-gtt-wc-active: SUCCESS (0.141s)
[166.668265] [033/133] (888s left) gen9_exec_parse (allowed-all)
[166.802110] Starting subtest: allowed-all
[166.805727] Subtest allowed-all: SUCCESS (0.003s)
[167.083197] [034/133] (888s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-mc-ccs)
[167.269634] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-mc-ccs
[167.272993] Starting dynamic subtest: pipe-A-HDMI-A-1
[167.466437] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.013s)
[167.491504] Starting dynamic subtest: pipe-A-HDMI-A-2
[167.626758] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.013s)
[167.651415] Starting dynamic subtest: pipe-B-HDMI-A-1
[167.787224] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.012s)
[167.824520] Starting dynamic subtest: pipe-B-HDMI-A-2
[168.013817] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.011s)
[168.046472] Starting dynamic subtest: pipe-C-HDMI-A-1
[168.199226] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.011s)
[168.232418] Starting dynamic subtest: pipe-C-HDMI-A-2
[168.355366] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.012s)
[168.361277] Subtest crc-sprite-planes-basic-4-tiled-dg2-mc-ccs: SKIP (0.090s)
[168.476837] [035/133] (887s left) kms_scaling_modes (scaling-mode-full)
[168.666370] Starting subtest: scaling-mode-full
[168.694419] Subtest scaling-mode-full: SKIP (0.003s)
[168.806360] [036/133] (886s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-cpu)
[169.020273] Starting subtest: fbc-rgb101010-draw-mmap-cpu
[172.009061] Subtest fbc-rgb101010-draw-mmap-cpu: SUCCESS (2.988s)
[172.273533] [037/133] (883s left) kms_psr (fbc-psr2-cursor-mmap-cpu)
[172.474028] Subtest fbc-psr2-cursor-mmap-cpu: SKIP (0.000s)
[172.583842] [038/133] (883s left) gem_caching (reads)
[172.710094] Starting subtest: reads
[179.224700] Subtest reads: SUCCESS (6.514s)
[179.390432] [039/133] (876s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area-big-fb)
[179.595061] Subtest psr2-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.000s)
[179.700291] [040/133] (876s left) gem_exec_whisper (basic-contexts-priority)
[179.837412] Starting subtest: basic-contexts-priority
[187.339916] Subtest basic-contexts-priority: SUCCESS (7.498s)
[187.608338] [041/133] (868s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-blt)
[187.813053] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-blt
[187.824479] Subtest psr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[187.959702] [042/133] (868s left) kms_getfb (getfb2-accept-nv12)
[188.144302] Starting subtest: getfb2-accept-nv12
[188.148297] Subtest getfb2-accept-nv12: SUCCESS (0.003s)
[188.312313] [043/133] (868s left) gem_readwrite (write-bad-handle)
[188.420406] Starting subtest: write-bad-handle
[188.427805] Subtest write-bad-handle: SUCCESS (0.000s)
[188.604775] [044/133] (867s left) kms_frontbuffer_tracking (fbcpsr-1p-indfb-fliptrack-mmap-gtt)
[188.809471] Starting subtest: fbcpsr-1p-indfb-fliptrack-mmap-gtt
[188.817019] Subtest fbcpsr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[188.946021] [045/133] (867s left) kms_universal_plane (disable-primary-vs-flip)
[189.133222] Starting subtest: disable-primary-vs-flip
[189.140334] Starting dynamic subtest: pipe-A-HDMI-A-1
[190.177146] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.038s)
[190.226346] Starting dynamic subtest: pipe-B-HDMI-A-2
[190.872979] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.695s)
[190.915051] Starting dynamic subtest: pipe-C-HDMI-A-1
[191.639192] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.760s)
[191.646381] Subtest disable-primary-vs-flip: SUCCESS (2.508s)
[191.943500] [046/133] (864s left) gen3_render_mixed_blits
[192.182166] [047/133] (864s left) gem_userptr_blits (unsync-unmap)
[192.324170] Subtest unsync-unmap: SKIP (0.000s)
[192.508153] [048/133] (864s left) i915_drm_fdinfo (all-busy-idle-check-all)
[192.621897] Starting subtest: all-busy-idle-check-all
[193.254359] Subtest all-busy-idle-check-all: SUCCESS (0.623s)
[193.403759] [049/133] (863s left) kms_plane_multiple (tiling-y)
[193.589871] Starting subtest: tiling-y
[193.594932] Starting dynamic subtest: pipe-A-HDMI-A-1
[195.674144] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.074s)
[195.711616] Starting dynamic subtest: pipe-A-HDMI-A-2
[197.526277] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (1.852s)
[197.556981] Starting dynamic subtest: pipe-B-HDMI-A-1
[199.429797] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (1.899s)
[199.457554] Starting dynamic subtest: pipe-B-HDMI-A-2
[201.437592] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (1.888s)
[201.536152] Starting dynamic subtest: pipe-C-HDMI-A-1
[203.086248] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.762s)
[203.128257] Starting dynamic subtest: pipe-C-HDMI-A-2
[204.821670] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (1.727s)
[204.826125] Subtest tiling-y: SUCCESS (11.226s)
[205.145161] [050/133] (851s left) kms_plane (plane-panning-bottom-right-suspend)
[205.355806] Starting subtest: plane-panning-bottom-right-suspend
[205.359110] Starting dynamic subtest: pipe-A