[86.999084] Initializing watchdogs
[86.999310]   /dev/watchdog0
[87.039609] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[87.054377] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[87.063516] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[87.069276] [001/132] (960s left) gem_create (create-clear)
[87.181791] Starting subtest: create-clear
[87.187056] Starting dynamic subtest: smem0
[120.037241] Dynamic subtest smem0: SUCCESS (32.835s)
[120.039164] Subtest create-clear: SUCCESS (32.836s)
[120.301594] [002/132] (927s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[120.403503] Starting subtest: cpuset-medium-copy-XY
[131.828853] Subtest cpuset-medium-copy-XY: SUCCESS (11.419s)
[131.980083] [003/132] (915s left) gem_exec_fence (syncobj-timeline-multiple-ext-nodes)
[132.904563] Starting subtest: syncobj-timeline-multiple-ext-nodes
[132.905942] Subtest syncobj-timeline-multiple-ext-nodes: SUCCESS (0.004s)
[133.177979] [FACT gem_exec_fence (syncobj-timeline-multiple-ext-nodes)] new: kernel.kmod_is_loaded.vgem: true
[133.184095] [004/132] (914s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-signa
[133.289704] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-signaled
[133.402448] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-signaled: SUCCESS (0.110s)
[133.578837] [005/132] (914s left) kms_pm_rpm (fences-dpms)
[135.535973] Starting subtest: fences-dpms
[138.583127] Subtest fences-dpms: SUCCESS (3.048s)
[138.852958] [006/132] (909s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted)
[138.963830] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[138.965157] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.002s)
[139.101993] [007/132] (909s left) kms_psr (psr-primary-mmap-cpu)
[139.290263] Subtest psr-primary-mmap-cpu: SKIP (0.000s)
[139.398745] [008/132] (908s left) kms_plane (pixel-format-4-tiled-bmg-ccs-modifier-source-clamping)
[139.587421] Starting subtest: pixel-format-4-tiled-bmg-ccs-modifier-source-clamping
[139.589586] Subtest pixel-format-4-tiled-bmg-ccs-modifier-source-clamping: SKIP (0.000s)
[139.735719] [009/132] (908s left) gem_eio (in-flight-contexts-immediate)
[139.869608] Starting subtest: in-flight-contexts-immediate
[151.959412] Subtest in-flight-contexts-immediate: SUCCESS (12.092s)
[152.201818] [010/132] (896s left) kms_big_fb (yf-tiled-8bpp-rotate-90)
[152.399436] Starting subtest: yf-tiled-8bpp-rotate-90
[152.406216] Subtest yf-tiled-8bpp-rotate-90: SKIP (0.000s)
[152.537742] [011/132] (895s left) kms_cursor_crc (cursor-rapid-movement-32x32)
[152.722070] Starting subtest: cursor-rapid-movement-32x32
[152.880200] Subtest cursor-rapid-movement-32x32: SKIP (0.157s)
[153.052628] [012/132] (895s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-fullscreen)
[153.255638] Starting subtest: fbc-1p-primscrn-spr-indfb-fullscreen
[155.313147] Subtest fbc-1p-primscrn-spr-indfb-fullscreen: SUCCESS (2.050s)
[155.585971] [013/132] (893s left) gem_exec_schedule (pi-distinct-iova)
[155.851730] Starting subtest: pi-distinct-iova
[155.854051] Starting dynamic subtest: rcs0
[155.907802] Dynamic subtest rcs0: SUCCESS (0.059s)
[155.934108] Starting dynamic subtest: bcs0
[155.988120] Dynamic subtest bcs0: SUCCESS (0.078s)
[156.012095] Starting dynamic subtest: vcs0
[156.059692] Dynamic subtest vcs0: SUCCESS (0.062s)
[156.090184] Starting dynamic subtest: vecs0
[156.103645] Dynamic subtest vecs0: SUCCESS (0.051s)
[156.104957] Subtest pi-distinct-iova: SUCCESS (0.256s)
[156.381906] [014/132] (892s left) kms_big_fb (x-tiled-32bpp-rotate-90)
[156.583073] Starting subtest: x-tiled-32bpp-rotate-90
[156.912695] Subtest x-tiled-32bpp-rotate-90: SKIP (0.331s)
[157.186638] [015/132] (891s left) gem_lmem_swapping (heavy-verify-random-ccs)
[157.306722] Subtest heavy-verify-random-ccs: SKIP (0.000s)
[157.421840] [016/132] (891s left) api_intel_bb (blit-noreloc-keep-cache)
[157.521295] Starting subtest: blit-noreloc-keep-cache
[157.534103] Subtest blit-noreloc-keep-cache: SUCCESS (0.016s)
[157.688308] [017/132] (891s left) sysfs_heartbeat_interval (idempotent)
[157.798039] Starting subtest: idempotent
[157.800246] Starting dynamic subtest: vecs0
[157.804286] Dynamic subtest vecs0: SUCCESS (0.004s)
[157.833252] Starting dynamic subtest: vcs0
[157.834380] Dynamic subtest vcs0: SUCCESS (0.004s)
[157.859980] Starting dynamic subtest: bcs0
[157.861236] Dynamic subtest bcs0: SUCCESS (0.005s)
[157.884987] Starting dynamic subtest: rcs0
[157.886228] Dynamic subtest rcs0: SUCCESS (0.004s)
[157.887419] Subtest idempotent: SUCCESS (0.023s)
[157.972030] [018/132] (891s left) kms_getfb (getfb2-handle-closed)
[158.152301] Starting subtest: getfb2-handle-closed
[158.153666] Subtest getfb2-handle-closed: SUCCESS (0.001s)
[158.302742] [019/132] (890s left) kms_cursor_legacy (torture-move)
[158.483283] Starting subtest: torture-move
[158.486049] Starting dynamic subtest: pipe-A
[164.955620] Dynamic subtest pipe-A: SUCCESS (6.471s)
[164.989055] Starting dynamic subtest: pipe-B
[171.540426] Dynamic subtest pipe-B: SUCCESS (6.582s)
[171.567045] Starting dynamic subtest: pipe-C
[176.998126] Dynamic subtest pipe-C: SUCCESS (5.459s)
[177.020220] Starting dynamic subtest: all-pipes
[182.558861] Dynamic subtest all-pipes: SUCCESS (5.559s)
[182.564060] Subtest torture-move: SUCCESS (24.074s)
[182.706964] [020/132] (866s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[182.903065] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[182.911043] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.003s)
[183.053540] [021/132] (866s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-pgflip-blt)
[183.246614] Starting subtest: fbc-2p-primscrn-shrfb-pgflip-blt
[186.264032] Subtest fbc-2p-primscrn-shrfb-pgflip-blt: SUCCESS (3.012s)
[186.453147] [022/132] (863s left) gem_exec_nop (basic-parallel)
[186.579979] Starting subtest: basic-parallel
[189.690295] Subtest basic-parallel: SUCCESS (3.115s)
[189.969165] [023/132] (859s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-gtt)
[190.168501] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-gtt
[192.660101] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-gtt: SUCCESS (2.490s)
[192.970846] [024/132] (856s left) gem_ctx_param (invalid-get-ringsize)
[193.068396] Starting subtest: invalid-get-ringsize
[193.070845] Subtest invalid-get-ringsize: SUCCESS (0.000s)
[193.220137] [025/132] (856s left) syncobj_wait (multi-wait-submitted-signaled)
[193.324074] Starting subtest: multi-wait-submitted-signaled
[193.326087] Subtest multi-wait-submitted-signaled: SUCCESS (0.002s)
[193.479555] [026/132] (856s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs)
[193.659321] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs
[193.661193] Starting dynamic subtest: pipe-A-HDMI-A-1
[193.667255] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.001s)
[193.710059] Starting dynamic subtest: pipe-A-HDMI-A-2
[193.718072] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.001s)
[193.758058] Starting dynamic subtest: pipe-B-HDMI-A-1
[193.767084] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s)
[193.796106] Starting dynamic subtest: pipe-B-HDMI-A-2
[193.800600] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.001s)
[193.824191] Starting dynamic subtest: pipe-C-HDMI-A-1
[193.828471] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s)
[193.850268] Starting dynamic subtest: pipe-C-HDMI-A-2
[193.854645] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.001s)
[193.857408] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs: SKIP (0.017s)
[194.667771] [027/132] (855s left) kms_vrr (flip-suspend)
[194.847101] Starting subtest: flip-suspend
[194.853224] Subtest flip-suspend: SKIP (0.000s)
[194.987453] [028/132] (855s left) gem_exec_reloc (basic-gtt-read)
[195.101773] Starting subtest: basic-gtt-read
[195.122073] Subtest basic-gtt-read: SUCCESS (0.018s)
[195.347488] [029/132] (854s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[195.555085] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-gtt
[195.558772] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[195.699893] [030/132] (854s left) kms_dsc (dsc-fractional-bpp-with-bpc)
[195.886084] Subtest dsc-fractional-bpp-with-bpc: SKIP (0.000s)
[195.991790] [031/132] (854s left) gem_exec_params (invalid-batch-start-offset)
[196.102102] Starting subtest: invalid-batch-start-offset
[196.108313] Subtest invalid-batch-start-offset: SUCCESS (0.006s)
[196.298415] [032/132] (854s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render)
[196.485267] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render
[196.490147] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[196.634532] [033/132] (853s left) gem_exec_params (secure-non-root)
[196.742937] Starting subtest: secure-non-root
[196.746801] Subtest secure-non-root: SKIP (0.000s)
[196.902627] [034/132] (853s left) gem_exec_reloc (basic-cpu-active)
[197.013895] Starting subtest: basic-cpu-active
[197.158181] Subtest basic-cpu-active: SUCCESS (0.142s)
[197.368893] [035/132] (853s left) kms_cursor_crc (cursor-onscreen-max-size)
[197.583160] Starting subtest: cursor-onscreen-max-size
[197.586855] Subtest cursor-onscreen-max-size: SKIP (0.000s)
[197.734889] [036/132] (853s left) gem_ctx_persistence (legacy-engines-mixed)
[197.845062] Starting subtest: legacy-engines-mixed
[197.847208] Starting dynamic subtest: render
[197.885621] Dynamic subtest render: SUCCESS (0.039s)
[197.917193] Starting dynamic subtest: bsd
[197.923137] Dynamic subtest bsd: SUCCESS (0.034s)
[197.944680] Starting dynamic subtest: blt
[197.964203] Dynamic subtest blt: SUCCESS (0.040s)
[197.990111] Starting dynamic subtest: vebox
[197.998738] Dynamic subtest vebox: SUCCESS (0.034s)
[198.000442] Subtest legacy-engines-mixed: SUCCESS (0.156s)
[198.154967] [037/132] (852s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[198.357182] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-wc
[198.359959] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[198.512834] [038/132] (852s left) syncobj_timeline (etime-multi-wait-all-submitted-signaled)
[198.612995] Starting subtest: etime-multi-wait-all-submitted-signaled
[198.722579] Subtest etime-multi-wait-all-submitted-signaled: SUCCESS (0.106s)
[198.896942] [039/132] (852s left) gem_userptr_blits (map-fixed-invalidate-overlap)
[199.033041] Starting subtest: map-fixed-invalidate-overlap
[199.034289] Starting dynamic subtest: gtt
[199.272134] Dynamic subtest gtt: SUCCESS (0.239s)
[199.295627] Starting dynamic subtest: wb
[199.432163] Dynamic subtest wb: SUCCESS (0.158s)
[199.453858] Starting dynamic subtest: wc
[199.589706] Dynamic subtest wc: SUCCESS (0.158s)
[199.614367] Starting dynamic subtest: uc
[199.752207] Dynamic subtest uc: SUCCESS (0.158s)
[199.757166] Subtest map-fixed-invalidate-overlap: SUCCESS (0.718s)
[199.935105] [040/132] (851s left) kms_invalid_mode (bad-hsync-end)
[200.114554] Starting subtest: bad-hsync-end
[200.119212] Starting dynamic subtest: pipe-A-HDMI-A-1
[200.521075] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.410s)
[200.574432] Starting dynamic subtest: pipe-A-HDMI-A-2
[200.580120] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.004s)
[200.625063] Starting dynamic subtest: pipe-B-HDMI-A-1
[200.630302] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.007s)
[200.664112] Starting dynamic subtest: pipe-B-HDMI-A-2
[200.669351] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.004s)
[200.711131] Starting dynamic subtest: pipe-C-HDMI-A-1
[200.716232] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.003s)
[200.749243] Starting dynamic subtest: pipe-C-HDMI-A-2
[200.750450] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.003s)
[200.751590] Subtest bad-hsync-end: SUCCESS (0.455s)
[200.911086] [041/132] (850s left) i915_drm_fdinfo (busy-idle-check-all)
[201.027631] Starting subtest: busy-idle-check-all
[201.028983] Starting dynamic subtest: rcs0
[201.652440] Dynamic subtest rcs0: SUCCESS (0.628s)
[201.678487] Starting dynamic subtest: bcs0
[202.288251] Dynamic subtest bcs0: SUCCESS (0.630s)
[202.313719] Starting dynamic subtest: vcs0
[202.917103] Dynamic subtest vcs0: SUCCESS (0.627s)
[202.939236] Starting dynamic subtest: vecs0
[203.552149] Dynamic subtest vecs0: SUCCESS (0.633s)
[203.553522] Subtest busy-idle-check-all: SUCCESS (2.524s)
[203.693970] [042/132] (847s left) gem_wait (wait)
[203.826943] Starting subtest: wait
[203.828275] Starting dynamic subtest: all-engines
[204.858333] Dynamic subtest all-engines: SUCCESS (1.030s)
[204.879717] Starting dynamic subtest: rcs0
[205.884118] Dynamic subtest rcs0: SUCCESS (1.025s)
[205.905148] Starting dynamic subtest: bcs0
[206.908581] Dynamic subtest bcs0: SUCCESS (1.025s)
[206.933324] Starting dynamic subtest: vcs0
[207.937210] Dynamic subtest vcs0: SUCCESS (1.024s)
[207.958712] Starting dynamic subtest: vecs0
[208.961137] Dynamic subtest vecs0: SUCCESS (1.025s)
[208.968136] Subtest wait: SUCCESS (5.136s)
[209.305812] [043/132] (842s left) i915_fb_tiling (basic-x-tiling)
[209.411997] Starting subtest: basic-x-tiling
[209.413321] Subtest basic-x-tiling: SUCCESS (0.002s)
[209.561420] [044/132] (842s left) gem_workarounds (suspend-resume)
[209.694119] Starting subtest: suspend-resume