[88.006128] Initializing watchdogs
[88.006356]   /dev/watchdog0
[88.045622] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[88.060242] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[88.067910] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[88.074280] [001/132] (960s left) perf_pmu (busy-start)
[88.203984] Starting subtest: busy-start
[88.206303] Starting dynamic subtest: rcs0
[90.734655] Dynamic subtest rcs0: SUCCESS (2.531s)
[90.752384] Starting dynamic subtest: bcs0
[93.260301] Dynamic subtest bcs0: SUCCESS (2.526s)
[93.279114] Starting dynamic subtest: vcs0
[95.785097] Dynamic subtest vcs0: SUCCESS (2.522s)
[95.803728] Starting dynamic subtest: vecs0
[98.304456] Dynamic subtest vecs0: SUCCESS (2.520s)
[98.308083] Subtest busy-start: SUCCESS (10.104s)
[98.463093] [002/132] (950s left) kms_rotation_crc (primary-x-tiled-reflect-x-180)
[98.645059] Starting subtest: primary-x-tiled-reflect-x-180
[98.655776] Subtest primary-x-tiled-reflect-x-180: SKIP (0.003s)
[98.792331] [003/132] (949s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[98.997812] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[100.767807] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.764s)
[101.027309] [004/132] (947s left) gem_mmap_wc (write-read)
[101.138544] Starting subtest: write-read
[101.140801] Subtest write-read: SUCCESS (0.011s)
[101.283313] [005/132] (947s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-sf)
[101.487840] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[101.590041] [006/132] (947s left) kms_big_fb (y-tiled-addfb)
[101.788709] Starting subtest: y-tiled-addfb
[101.790693] Subtest y-tiled-addfb: SUCCESS (0.001s)
[101.914900] [007/132] (947s left) gem_vm_create (invalid-create)
[102.026252] Starting subtest: invalid-create
[102.028469] Subtest invalid-create: SUCCESS (0.000s)
[102.166253] [008/132] (946s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[102.351807] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[102.357248] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[113.136897] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (10.774s)
[113.158254] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[123.846867] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (10.704s)
[123.868454] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[134.581078] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (10.727s)
[134.584554] Subtest 2x-flip-vs-expired-vblank-interruptible: SUCCESS (32.221s)
[135.136536] [009/132] (914s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[135.338084] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[135.341263] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[135.484944] [010/132] (913s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0-hflip)
[135.687197] Starting subtest: linear-max-hw-stride-32bpp-rotate-0-hflip
[135.692412] Subtest linear-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.001s)
[135.819889] [011/132] (913s left) gem_userptr_blits (unsync-unmap)
[135.948791] Subtest unsync-unmap: SKIP (0.000s)
[136.124127] [012/132] (913s left) gem_ctx_sseu (mmap-args)
[136.233412] Subtest mmap-args: SKIP (0.000s)
[136.330104] [013/132] (913s left) gem_exec_params (invalid-ring2)
[136.439139] Starting subtest: invalid-ring2
[136.441304] Subtest invalid-ring2: SUCCESS (0.001s)
[136.588120] [014/132] (913s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[136.792110] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[136.796041] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[136.935617] [015/132] (912s left) kms_cursor_crc (cursor-onscreen-32x32)
[137.126079] Starting subtest: cursor-onscreen-32x32
[137.372897] Subtest cursor-onscreen-32x32: SKIP (0.241s)
[137.565166] [016/132] (912s left) gem_exec_params (invalid-fence-in-submit)
[137.686264] Starting subtest: invalid-fence-in-submit
[137.689390] Subtest invalid-fence-in-submit: SUCCESS (0.009s)
[137.819921] [017/132] (912s left) kms_psr (pr-sprite-plane-move)
[138.009685] Subtest pr-sprite-plane-move: SKIP (0.000s)
[138.109691] [018/132] (911s left) gem_mmap_gtt (cpuset-medium-copy)
[138.214057] Starting subtest: cpuset-medium-copy
[146.633113] Subtest cpuset-medium-copy: SUCCESS (8.412s)
[146.796212] [019/132] (903s left) syncobj_wait (wait-for-submit-complex)
[147.039107] Starting subtest: wait-for-submit-complex
[147.107380] Subtest wait-for-submit-complex: SUCCESS (0.068s)
[147.251125] [020/132] (902s left) syncobj_timeline (reset-unsignaled)
[147.360110] Starting subtest: reset-unsignaled
[147.362111] Subtest reset-unsignaled: SUCCESS (0.000s)
[147.518174] [021/132] (902s left) syncobj_wait (reset-during-wait-for-submit)
[147.619957] Starting subtest: reset-during-wait-for-submit
[147.729779] Subtest reset-during-wait-for-submit: SUCCESS (0.105s)
[147.886175] [022/132] (902s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[148.096207] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip
[148.101425] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.001s)
[148.238233] [023/132] (902s left) gem_readwrite (new-obj)
[148.341095] Starting subtest: new-obj
[148.344420] Subtest new-obj: SUCCESS (0.000s)
[148.490198] [024/132] (902s left) gem_mmap_wc (write-prefaulted)
[148.597581] Starting subtest: write-prefaulted
[148.780727] Subtest write-prefaulted: SUCCESS (0.186s)
[148.926216] [025/132] (901s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[149.113141] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[152.638187] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SUCCESS (3.522s)
[152.936756] [026/132] (897s left) core_setmaster (master-drop-set-root)
[153.098090] Starting subtest: master-drop-set-root
[153.127375] Subtest master-drop-set-root: SUCCESS (0.027s)
[153.222041] [027/132] (897s left) gem_exec_reloc (basic-range-active)
[153.338302] Starting subtest: basic-range-active
[153.430977] Subtest basic-range-active: SUCCESS (0.082s)
[153.638087] [028/132] (897s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs)
[153.823708] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs
[153.825266] Starting dynamic subtest: pipe-A-HDMI-A-1
[153.846052] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.002s)
[153.886124] Starting dynamic subtest: pipe-A-HDMI-A-2
[153.908506] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.002s)
[153.946078] Starting dynamic subtest: pipe-B-HDMI-A-1
[153.967526] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.002s)
[153.988415] Starting dynamic subtest: pipe-B-HDMI-A-2
[154.004977] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.002s)
[154.030168] Starting dynamic subtest: pipe-C-HDMI-A-1
[154.044910] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.002s)
[154.064725] Starting dynamic subtest: pipe-C-HDMI-A-2
[154.079873] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.002s)
[154.082889] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs: SKIP (0.028s)
[154.834653] [029/132] (896s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[154.953402] Starting subtest: x-tiled-to-vebox-y-tiled
[154.957333] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[155.204356] [030/132] (895s left) kms_vblank (wait-idle-hang)
[155.389677] Starting subtest: wait-idle-hang
[155.391304] Starting dynamic subtest: pipe-A-HDMI-A-1
[162.903805] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (7.515s)
[162.950338] Starting dynamic subtest: pipe-A-HDMI-A-2
[169.854030] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (6.942s)
[169.885526] Starting dynamic subtest: pipe-C-HDMI-A-1
[176.829380] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (6.965s)
[176.873557] Starting dynamic subtest: pipe-C-HDMI-A-2
[183.852195] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (7.015s)
[183.856344] Subtest wait-idle-hang: SUCCESS (28.469s)
[184.145140] [031/132] (866s left) gem_exec_schedule (preempt-queue-chain)
[184.291395] Starting subtest: preempt-queue-chain
[184.293245] Starting dynamic subtest: rcs0
[184.628979] Dynamic subtest rcs0: SUCCESS (0.334s)
[184.651642] Starting dynamic subtest: bcs0
[184.986519] Dynamic subtest bcs0: SUCCESS (0.357s)
[185.014967] Starting dynamic subtest: vcs0
[185.315847] Dynamic subtest vcs0: SUCCESS (0.329s)
[185.347211] Starting dynamic subtest: vecs0
[185.644319] Dynamic subtest vecs0: SUCCESS (0.329s)
[185.646243] Subtest preempt-queue-chain: SUCCESS (1.353s)
[186.062528] [032/132] (865s left) gem_exec_balancer (persistence)
[186.312706] Starting subtest: persistence
[190.639978] Subtest persistence: SUCCESS (4.326s)
[190.795302] [033/132] (860s left) syncobj_timeline (single-wait-all-available-submitted)
[190.899266] Starting subtest: single-wait-all-available-submitted
[190.901068] Subtest single-wait-all-available-submitted: SUCCESS (0.002s)
[191.029986] [034/132] (860s left) gem_exec_flush (basic-uc-set-default)
[191.151302] Starting subtest: basic-uc-set-default
[196.569124] Subtest basic-uc-set-default: SUCCESS (5.420s)
[196.813301] [035/132] (854s left) kms_content_protection (atomic-dpms)
[197.008477] Starting subtest: atomic-dpms
[197.022465] Subtest atomic-dpms: SKIP (0.010s)
[197.161773] [036/132] (854s left) gem_ctx_persistence (legacy-engines-queued)
[197.277629] Starting subtest: legacy-engines-queued
[197.280124] Starting dynamic subtest: render
[197.327087] Dynamic subtest render: SUCCESS (0.049s)
[197.351514] Starting dynamic subtest: bsd
[197.381222] Dynamic subtest bsd: SUCCESS (0.048s)
[197.406145] Starting dynamic subtest: blt
[197.506614] Dynamic subtest blt: SUCCESS (0.124s)
[197.530997] Starting dynamic subtest: vebox
[197.571033] Dynamic subtest vebox: SUCCESS (0.060s)
[197.574142] Subtest legacy-engines-queued: SUCCESS (0.296s)
[197.745454] [037/132] (853s left) kms_pm_lpsp (kms-lpsp)
[198.934124] Starting subtest: kms-lpsp
[198.938027] Subtest kms-lpsp: SKIP (0.001s)
[199.080973] [038/132] (852s left) gem_fence_thrash (bo-write-verify-none)
[199.180107] Starting subtest: bo-write-verify-none
[200.351069] Subtest bo-write-verify-none: SUCCESS (1.171s)
[200.499561] [039/132] (851s left) kms_psr (fbc-psr2-cursor-mmap-cpu)
[200.685141] Subtest fbc-psr2-cursor-mmap-cpu: SKIP (0.000s)
[200.785998] [040/132] (851s left) kms_psr (psr-primary-mmap-cpu)
[200.963172] Subtest psr-primary-mmap-cpu: SKIP (0.000s)
[201.069806] [041/132] (850s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-upscaling)
[201.254180] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-upscaling
[201.258962] Subtest flip-32bpp-4tile-to-64bpp-4tile-upscaling: SKIP (0.000s)
[201.393928] [042/132] (850s left) kms_plane (plane-panning-top-left)
[201.581120] Starting subtest: plane-panning-top-left
[201.583384] Starting dynamic subtest: pipe-A
[202.818832] Dynamic subtest pipe-A: SUCCESS (1.233s)
[202.843325] Starting dynamic subtest: pipe-B
[203.766635] Dynamic subtest pipe-B: SUCCESS (0.944s)
[203.768193] Subtest plane-panning-top-left: SUCCESS (2.186s)
[204.130181] [043/132] (848s left) gem_userptr_blits (map-fixed-invalidate-busy)
[204.270817] Starting subtest: map-fixed-invalidate-busy
[204.272421] Starting dynamic subtest: gtt
[207.694086] Dynamic subtest gtt: SUCCESS (3.424s)
[207.722604] Starting dynamic subtest: wb
[211.001177] Dynamic subtest wb: SUCCESS (3.304s)
[211.023426] Starting dynamic subtest: wc
[214.287200] Dynamic subtest wc: SUCCESS (3.286s)
[214.312202] Starting dynamic subtest: uc
[217.576094] Dynamic subtest uc: SUCCESS (3.286s)
[217.578134] Subtest map-fixed-invalidate-busy: SUCCESS (13.306s)
[217.773286] [044/132] (834s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[217.976115] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[217.980071] Subtest psr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[218.109640] [045/132] (834s left) prime_vgem (basic-gtt)
[218.336785] Starting subtest: basic-gtt
[218.382015] Subtest basic-gtt: SUCCESS (0.044s)
[218.532895] [FACT prime_vgem (basic-gtt)] new: kernel.kmod_is_loaded.vgem: true
[218.538643] [046/132] (833s left) gem_exec_balancer (bonded-dual)
[218.663496] Starting subtest: bonded-dual
[223.061386] Subtest bonded-dual: SUCCESS (4.398s)
[223.341616] [047/132] (829s left) gem_reg_read (timestamp-moving)
[223.443075] Starting subtest: timestamp-moving
[224.443880] Subtest timestamp-moving: SUCCESS (1.001s)
[224.590284] [048/132] (828s left) kms_lease (lease-uevent)
[224.772020] Starting subtest: lease-uevent
[225.807592] Subtest lease-uevent: SUCCESS (1.035s)
[225.985156] [049/132] (826s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-pwrite)
[226.175367] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-pwrite
[226.178850] Subtest psr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[226.336478] [050/132] (826s left) i915_suspend (fence-restore-untiled)
[226.438259] Starting subtest: fence-restore-untiled