[109.113043] Initializing watchdogs
[109.113253]   /dev/watchdog0
[109.155273] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[109.171369] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[109.178882] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[109.186141] [001/133] (960s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-plflip-blt)
[109.412593] Starting subtest: fbcpsr-1p-primscrn-shrfb-plflip-blt
[109.419193] Subtest fbcpsr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[109.570570] [002/133] (960s left) kms_concurrent (multi-plane-atomic-lowres)
[109.765221] Starting subtest: multi-plane-atomic-lowres
[109.768910] Starting dynamic subtest: pipe-A-HDMI-A-1
[110.920077] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.152s)
[110.966401] Starting dynamic subtest: pipe-A-HDMI-A-2
[111.661853] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.736s)
[111.697089] Starting dynamic subtest: pipe-B-HDMI-A-1
[112.402041] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.735s)
[112.429391] Starting dynamic subtest: pipe-B-HDMI-A-2
[113.157065] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.750s)
[113.196261] Starting dynamic subtest: pipe-C-HDMI-A-1
[113.978084] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.819s)
[114.022853] Starting dynamic subtest: pipe-C-HDMI-A-2
[114.678103] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.697s)
[114.681484] Subtest multi-plane-atomic-lowres: SUCCESS (4.914s)
[115.001635] [003/133] (954s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[115.124604] Starting subtest: invalid-single-wait-unsubmitted
[115.127454] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.001s)
[115.253255] [004/133] (954s left) gem_exec_reloc (basic-wc-cpu-active)
[115.384357] Starting subtest: basic-wc-cpu-active
[115.538516] Subtest basic-wc-cpu-active: SUCCESS (0.151s)
[115.753800] [005/133] (954s left) sw_sync (sync_multi_producer_single_consumer)
[115.858383] Starting subtest: sync_multi_producer_single_consumer
[116.939127] Subtest sync_multi_producer_single_consumer: SUCCESS (1.079s)
[117.056148] [006/133] (953s left) kms_chamelium_edid (dp-edid-change-during-suspend)
[117.620646] Subtest dp-edid-change-during-suspend: SKIP (0.000s)
[117.893463] [007/133] (952s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[118.024787] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[118.124118] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.101s)
[118.388303] [008/133] (951s left) kms_psr2_sf (fbc-pr-cursor-plane-update-sf)
[118.812246] Subtest fbc-pr-cursor-plane-update-sf: SKIP (0.000s)
[119.052281] [009/133] (951s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[119.379367] Starting subtest: single-wait-for-submit-available-submitted
[119.381154] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.001s)
[119.535976] [010/133] (951s left) gem_exec_flush (basic-wb-rw-default)
[119.663329] Starting subtest: basic-wb-rw-default
[125.079974] Subtest basic-wb-rw-default: SUCCESS (5.418s)
[125.327922] [011/133] (945s left) gem_exec_schedule (pi-distinct-iova)
[125.592101] Starting subtest: pi-distinct-iova
[125.595815] Starting dynamic subtest: rcs0
[125.662629] Dynamic subtest rcs0: SUCCESS (0.068s)
[125.690165] Starting dynamic subtest: bcs0
[125.754922] Dynamic subtest bcs0: SUCCESS (0.090s)
[125.786151] Starting dynamic subtest: vcs0
[125.826131] Dynamic subtest vcs0: SUCCESS (0.072s)
[125.859255] Starting dynamic subtest: vecs0
[125.898713] Dynamic subtest vecs0: SUCCESS (0.069s)
[125.900544] Subtest pi-distinct-iova: SUCCESS (0.307s)
[126.196128] [012/133] (944s left) gem_ctx_param (set-priority-invalid-size)
[126.300121] Starting subtest: set-priority-invalid-size
[126.301996] Subtest set-priority-invalid-size: SUCCESS (0.000s)
[126.429981] [013/133] (944s left) kms_properties (get_properties-sanity-atomic)
[126.607081] Starting subtest: get_properties-sanity-atomic
[126.616772] Subtest get_properties-sanity-atomic: SUCCESS (0.009s)
[126.748337] [014/133] (944s left) gem_render_copy (y-tiled-ccs-to-x-tiled)
[126.873165] Starting subtest: y-tiled-ccs-to-x-tiled
[126.876201] Starting dynamic subtest: smem
[127.100151] Dynamic subtest smem: SUCCESS (0.225s)
[127.101947] Subtest y-tiled-ccs-to-x-tiled: SUCCESS (0.226s)
[127.351222] [015/133] (943s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[127.553921] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[127.557409] Subtest psr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[127.697458] [016/133] (943s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-upscaling)
[127.889319] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-upscaling
[127.893160] Subtest flip-64bpp-yftile-to-16bpp-yftile-upscaling: SKIP (0.000s)
[128.013841] [017/133] (943s left) gem_exec_schedule (preempt-self)
[128.154247] Starting subtest: preempt-self
[128.157498] Starting dynamic subtest: rcs0
[128.186382] Dynamic subtest rcs0: SUCCESS (0.030s)
[128.228110] Starting dynamic subtest: bcs0
[128.230448] Dynamic subtest bcs0: SUCCESS (0.032s)
[128.260433] Starting dynamic subtest: vcs0
[128.263533] Dynamic subtest vcs0: SUCCESS (0.027s)
[128.294401] Starting dynamic subtest: vecs0
[128.296620] Dynamic subtest vecs0: SUCCESS (0.036s)
[128.299453] Subtest preempt-self: SUCCESS (0.129s)
[128.667089] [018/133] (942s left) gem_mmap_gtt (big-bo)
[128.767380] Starting subtest: big-bo
[129.496135] Subtest big-bo: SUCCESS (0.729s)
[129.683905] [019/133] (941s left) gem_softpin (full)
[129.930839] Starting subtest: full
[129.950872] Subtest full: SUCCESS (0.019s)
[130.117676] [020/133] (941s left) kms_big_fb (yf-tiled-32bpp-rotate-270)
[130.344935] Starting subtest: yf-tiled-32bpp-rotate-270
[135.564456] Subtest yf-tiled-32bpp-rotate-270: SUCCESS (5.217s)
[135.949847] [021/133] (935s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[136.162236] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[136.166402] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[136.316195] [022/133] (935s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-blt)
[136.519058] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-blt
[139.365673] Subtest fbc-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (2.842s)
[139.649948] [023/133] (932s left) kms_psr2_su (page_flip-xrgb8888)
[139.758110] Subtest page_flip-XRGB8888: SKIP (0.000s)
[139.858063] [024/133] (932s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[140.051164] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[140.059227] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.004s)
[140.184925] [025/133] (931s left) kms_psr (fbc-psr2-cursor-plane-onoff)
[140.378769] Subtest fbc-psr2-cursor-plane-onoff: SKIP (0.000s)
[140.472783] [026/133] (931s left) kms_plane (plane-position-hole-dpms)
[140.660966] Starting subtest: plane-position-hole-dpms
[141.399188] Starting dynamic subtest: pipe-A-plane-1
[142.208433] Dynamic subtest pipe-A-plane-1: SUCCESS (0.811s)
[142.252113] Starting dynamic subtest: pipe-A-plane-2
[143.062892] Dynamic subtest pipe-A-plane-2: SUCCESS (0.851s)
[143.112421] Starting dynamic subtest: pipe-A-plane-3
[143.889720] Dynamic subtest pipe-A-plane-3: SUCCESS (0.824s)
[143.922754] Starting dynamic subtest: pipe-A-plane-4
[143.931587] Dynamic subtest pipe-A-plane-4: SUCCESS (0.009s)
[144.392850] Starting dynamic subtest: pipe-B-plane-1
[145.146127] Dynamic subtest pipe-B-plane-1: SUCCESS (0.800s)
[145.190731] Starting dynamic subtest: pipe-B-plane-2
[145.922093] Dynamic subtest pipe-B-plane-2: SUCCESS (0.774s)
[145.973039] Starting dynamic subtest: pipe-B-plane-3
[146.702387] Dynamic subtest pipe-B-plane-3: SUCCESS (0.774s)
[146.726052] Starting dynamic subtest: pipe-B-plane-4
[146.732060] Dynamic subtest pipe-B-plane-4: SUCCESS (0.007s)
[146.735082] Subtest plane-position-hole-dpms: SUCCESS (6.047s)
[147.097022] [027/133] (925s left) vgem_basic (setversion)
[147.364569] Starting subtest: setversion
[147.369086] Subtest setversion: SUCCESS (0.001s)
[147.489699] [FACT vgem_basic (setversion)] new: kernel.kmod_is_loaded.vgem: true
[147.495434] [028/133] (924s left) kms_flip (2x-flip-vs-dpms-on-nop)
[147.684076] Starting subtest: 2x-flip-vs-dpms-on-nop
[147.686093] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[149.439668] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (1.752s)
[149.467342] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[151.103460] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (1.657s)
[151.130111] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[152.766301] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (1.654s)
[152.771073] Subtest 2x-flip-vs-dpms-on-nop: SUCCESS (5.082s)
[153.375669] [029/133] (918s left) kms_addfb_basic (invalid-get-prop)
[153.488584] Starting subtest: invalid-get-prop
[153.491048] Subtest invalid-get-prop: SUCCESS (0.000s)
[153.725592] [030/133] (918s left) perf_pmu (busy-idle-no-semaphores)
[153.843070] Starting subtest: busy-idle-no-semaphores
[153.845665] Starting dynamic subtest: rcs0
[154.479297] Dynamic subtest rcs0: SUCCESS (0.633s)
[154.505500] Starting dynamic subtest: bcs0
[155.110203] Dynamic subtest bcs0: SUCCESS (0.629s)
[155.137307] Starting dynamic subtest: vcs0
[155.744624] Dynamic subtest vcs0: SUCCESS (0.632s)
[155.769481] Starting dynamic subtest: vecs0
[156.376956] Dynamic subtest vecs0: SUCCESS (0.630s)
[156.379399] Subtest busy-idle-no-semaphores: SUCCESS (2.535s)
[156.540107] [031/133] (916s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-blt)
[156.743955] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-blt
[156.748108] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[156.890485] [032/133] (915s left) gem_softpin (32b-excludes-last-page)
[157.010479] Starting subtest: 32b-excludes-last-page
[157.017385] Subtest 32b-excludes-last-page: SUCCESS (0.005s)
[157.174167] [033/133] (915s left) kms_cursor_edge_walk (256x256-left-edge)
[157.359509] Starting subtest: 256x256-left-edge
[157.362663] Starting dynamic subtest: pipe-A-HDMI-A-1
[161.278094] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (3.916s)
[161.330385] Starting dynamic subtest: pipe-C-HDMI-A-1
[164.693430] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (3.408s)
[164.697252] Subtest 256x256-left-edge: SUCCESS (7.334s)
[164.982873] [034/133] (907s left) kms_dsc (dsc-fractional-bpp)
[165.176126] Subtest dsc-fractional-bpp: SKIP (0.000s)
[165.289214] [035/133] (907s left) gem_ctx_param (invalid-set-ringsize)
[165.394639] Starting subtest: invalid-set-ringsize
[165.397648] Subtest invalid-set-ringsize: SUCCESS (0.000s)
[165.549649] [036/133] (907s left) gem_ccs (ctrl-surf-copy-new-ctx)
[165.661328] Subtest ctrl-surf-copy-new-ctx: SKIP (0.000s)
[165.774585] [037/133] (907s left) kms_vblank (query-busy)
[165.965216] Starting subtest: query-busy
[165.968160] Starting dynamic subtest: pipe-A-HDMI-A-1
[168.852087] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.886s)
[168.904229] Starting dynamic subtest: pipe-A-HDMI-A-2
[171.458524] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.600s)
[171.492610] Starting dynamic subtest: pipe-C-HDMI-A-1
[174.065304] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.597s)
[174.101200] Starting dynamic subtest: pipe-C-HDMI-A-2
[176.670659] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.599s)
[176.672604] Subtest query-busy: SUCCESS (10.708s)
[177.002173] [038/133] (896s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-blt)
[177.286089] Starting subtest: fbc-rgb101010-draw-blt
[180.282609] Subtest fbc-rgb101010-draw-blt: SUCCESS (2.997s)
[180.564502] [039/133] (892s left) gem_exec_balancer (invalid-balancer)
[180.690517] Starting subtest: invalid-balancer
[185.144967] Subtest invalid-balancer: SUCCESS (4.454s)
[185.417808] [040/133] (888s left) gem_ctx_sseu (invalid-args)
[185.533463] Subtest invalid-args: SKIP (0.000s)
[185.653400] [041/133] (887s left) gem_create (create-invalid-size)
[185.758114] Starting subtest: create-invalid-size
[185.760108] Subtest create-invalid-size: SUCCESS (0.000s)
[186.037501] [042/133] (887s left) perf_pmu (frequency)
[186.147892] Starting subtest: frequency
[186.155765] Starting dynamic subtest: gt0
[188.237129] Dynamic subtest gt0: SUCCESS (2.074s)
[188.260998] Starting dynamic subtest: idle-gt0
[188.748883] Dynamic subtest idle-gt0: SUCCESS (0.512s)
[188.750793] Subtest frequency: SUCCESS (2.600s)
[188.898988] [043/133] (884s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[189.009176] Starting subtest: multi-wait-available-submitted-signaled
[189.012328] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.002s)
[189.166600] [044/133] (884s left) kms_psr (fbc-psr-cursor-render)
[189.355175] Subtest fbc-psr-cursor-render: SKIP (0.000s)
[189.474547] [045/133] (884s left) gem_wait (write-wait)
[189.610593] Starting subtest: write-wait
[189.613381] Starting dynamic subtest: all-engines
[190.666958] Dynamic subtest all-engines: SUCCESS (1.052s)
[190.695440] Starting dynamic subtest: rcs0
[191.708050] Dynamic subtest rcs0: SUCCESS (1.040s)
[191.740634] Starting dynamic subtest: bcs0
[192.744226] Dynamic subtest bcs0: SUCCESS (1.033s)
[192.769625] Starting dynamic subtest: vcs0
[193.787631] Dynamic subtest vcs0: SUCCESS (1.042s)
[193.820800] Starting dynamic subtest: vecs0
[194.822886] Dynamic subtest vecs0: SUCCESS (1.034s)
[194.825307] Subtest write-wait: SUCCESS (5.215s)
[195.117450] [046/133] (878s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[195.312089] Starting subtest: fbcpsr-rgb565-draw-blt
[195.319185] Subtest fbcpsr-rgb565-draw-blt: SKIP (0.000s)
[195.494153] [047/133] (878s left) gem_workarounds (suspend-resume-fd)
[195.633121] Starting subtest: suspend-resume-fd