[91.550894] Initializing watchdogs
[91.551143]   /dev/watchdog0
[91.593990] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[91.608492] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[91.615081] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[91.622809] [001/131] (960s left) gem_exec_nop (basic-sequential)
[91.783911] Starting subtest: basic-sequential
[94.853417] Subtest basic-sequential: SUCCESS (3.081s)
[95.123098] [002/131] (957s left) kms_cursor_legacy (2x-long-cursor-vs-flip-atomic)
[95.302279] Starting subtest: 2x-long-cursor-vs-flip-atomic
[147.081658] Subtest 2x-long-cursor-vs-flip-atomic: SUCCESS (51.779s)
[147.390791] [003/131] (904s left) gem_mmap_wc (write-read-distinct)
[147.500438] Starting subtest: write-read-distinct
[147.512177] Subtest write-read-distinct: SUCCESS (0.011s)
[147.684259] [004/131] (904s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[147.863266] Starting subtest: nonblocking-crc-frame-sequence
[147.867295] Starting dynamic subtest: pipe-A-HDMI-A-1
[148.973081] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.008s)
[149.039857] Starting dynamic subtest: pipe-B-HDMI-A-2
[149.583693] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.704s)
[149.604217] Starting dynamic subtest: pipe-C-HDMI-A-1
[150.282208] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.691s)
[150.327323] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.416s)
[150.588881] [005/131] (901s left) i915_drm_fdinfo (virtual-busy)
[150.704480] Starting subtest: virtual-busy
[152.859786] Subtest virtual-busy: SUCCESS (2.154s)
[153.023757] [006/131] (899s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[153.231594] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[153.239037] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[153.387614] [007/131] (899s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-wc)
[153.583742] Starting subtest: fbcpsr-rgb101010-draw-mmap-wc
[153.590194] Subtest fbcpsr-rgb101010-draw-mmap-wc: SKIP (0.000s)
[153.757830] [008/131] (898s left) sysfs_defaults (readonly)
[153.871246] Starting subtest: readonly
[153.875193] Starting dynamic subtest: vecs0
[153.878355] Dynamic subtest vecs0: SUCCESS (0.001s)
[153.895972] Starting dynamic subtest: vcs0
[153.899372] Dynamic subtest vcs0: SUCCESS (0.001s)
[153.917424] Starting dynamic subtest: bcs0
[153.921467] Dynamic subtest bcs0: SUCCESS (0.001s)
[153.939558] Starting dynamic subtest: rcs0
[153.943553] Dynamic subtest rcs0: SUCCESS (0.001s)
[153.947551] Subtest readonly: SUCCESS (0.011s)
[154.033590] [009/131] (898s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted-signal
[154.145534] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted-signaled
[154.248353] Subtest etime-multi-wait-all-available-unsubmitted-submitted-signaled: SUCCESS (0.106s)
[154.403810] [010/131] (898s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-pwrite)
[154.603325] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-pwrite
[154.609574] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-pwrite: SKIP (0.000s)
[154.772808] [011/131] (898s left) syncobj_timeline (reset-signaled)
[154.881188] Starting subtest: reset-signaled
[154.884641] Subtest reset-signaled: SUCCESS (0.001s)
[155.041854] [012/131] (898s left) gem_exec_reloc (basic-wc-gtt)
[155.159567] Starting subtest: basic-wc-gtt
[155.178639] Subtest basic-wc-gtt: SUCCESS (0.019s)
[155.423740] [013/131] (897s left) gen9_exec_parse (batch-zero-length)
[155.555338] Starting subtest: batch-zero-length
[155.558856] Subtest batch-zero-length: SUCCESS (0.003s)
[155.837076] [014/131] (897s left) gem_ctx_shared (detached-shared-gtt)
[155.954498] Starting subtest: detached-shared-gtt
[158.109566] Subtest detached-shared-gtt: SUCCESS (2.151s)
[158.389738] [015/131] (894s left) perf_pmu (rc6-all-gts)
[158.499437] Starting subtest: rc6-all-gts
[158.510073] Subtest rc6-all-gts: SKIP (0.000s)
[158.672060] [016/131] (894s left) kms_plane (plane-position-covered)
[158.863773] Starting subtest: plane-position-covered
[159.604366] Starting dynamic subtest: pipe-A-plane-1
[160.134727] Dynamic subtest pipe-A-plane-1: SUCCESS (0.532s)
[160.168622] Starting dynamic subtest: pipe-A-plane-2
[160.707109] Dynamic subtest pipe-A-plane-2: SUCCESS (0.543s)
[160.754498] Starting dynamic subtest: pipe-A-plane-3
[161.239656] Dynamic subtest pipe-A-plane-3: SUCCESS (0.561s)
[161.260068] Starting dynamic subtest: pipe-A-plane-4
[161.268127] Dynamic subtest pipe-A-plane-4: SUCCESS (0.010s)
[161.770898] Starting dynamic subtest: pipe-B-plane-1
[162.239485] Dynamic subtest pipe-B-plane-1: SUCCESS (0.511s)
[162.284139] Starting dynamic subtest: pipe-B-plane-2
[162.758964] Dynamic subtest pipe-B-plane-2: SUCCESS (0.521s)
[162.805750] Starting dynamic subtest: pipe-B-plane-3
[163.284891] Dynamic subtest pipe-B-plane-3: SUCCESS (0.524s)
[163.303388] Starting dynamic subtest: pipe-B-plane-4
[163.310375] Dynamic subtest pipe-B-plane-4: SUCCESS (0.010s)
[163.313255] Subtest plane-position-covered: SUCCESS (4.433s)
[163.597439] [017/131] (889s left) gem_mmap_gtt (fault-concurrent)
[163.709421] Starting subtest: fault-concurrent
[167.141990] Subtest fault-concurrent: SUCCESS (3.430s)
[167.327179] [018/131] (886s left) kms_psr (psr-sprite-render)
[167.502714] Subtest psr-sprite-render: SKIP (0.000s)
[167.606651] [019/131] (886s left) gem_mmap_gtt (hang-user)
[167.711577] Starting subtest: hang-user
[183.055090] Subtest hang-user: SUCCESS (15.333s)
[183.213712] [020/131] (870s left) template (a)
[183.318972] Starting subtest: A
[183.322493] Subtest A: SUCCESS (0.000s)
[183.488032] [021/131] (870s left) kms_cursor_legacy (cursora-vs-flipb-atomic)
[183.663077] Starting subtest: cursorA-vs-flipB-atomic
[194.949468] Subtest cursorA-vs-flipB-atomic: SUCCESS (11.291s)
[195.257277] [022/131] (858s left) kms_rotation_crc (primary-rotation-90)
[195.436053] Starting subtest: primary-rotation-90
[210.083865] Subtest primary-rotation-90: SUCCESS (14.650s)
[210.385286] [023/131] (843s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)
[210.584329] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite
[213.636988] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (3.052s)
[213.919358] [024/131] (840s left) kms_universal_plane (universal-plane-sanity)
[214.100331] Starting subtest: universal-plane-sanity
[214.103769] Starting dynamic subtest: pipe-A-HDMI-A-1
[214.995417] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.780s)
[215.061090] Starting dynamic subtest: pipe-B-HDMI-A-2
[215.489015] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.491s)
[215.555218] Starting dynamic subtest: pipe-C-HDMI-A-1
[215.927206] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.500s)
[215.963574] Subtest universal-plane-sanity: SUCCESS (1.784s)
[216.177890] [025/131] (838s left) gem_exec_schedule (u-fairslice-all)
[216.322381] Starting subtest: u-fairslice-all
[218.422109] Subtest u-fairslice-all: SUCCESS (2.102s)
[218.842123] [026/131] (835s left) gem_exec_fence (nb-await)
[219.092074] Starting subtest: nb-await
[219.096429] Starting dynamic subtest: rcs0
[219.168789] Dynamic subtest rcs0: SUCCESS (0.074s)
[219.188358] Starting dynamic subtest: bcs0
[219.238873] Dynamic subtest bcs0: SUCCESS (0.068s)
[219.259436] Starting dynamic subtest: vcs0
[219.307062] Dynamic subtest vcs0: SUCCESS (0.069s)
[219.326753] Starting dynamic subtest: vecs0
[219.384104] Dynamic subtest vecs0: SUCCESS (0.071s)
[219.388354] Subtest nb-await: SUCCESS (0.291s)
[220.167296] [FACT gem_exec_fence (nb-await)] new: kernel.kmod_is_loaded.vgem: true
[220.173900] [027/131] (834s left) kms_hdr (invalid-hdr)
[220.353782] Starting subtest: invalid-hdr
[247.962365] Subtest invalid-hdr: SKIP (27.560s)
[248.365320] [028/131] (806s left) kms_debugfs (display-on-read-all)
[248.575821] Starting subtest: display-on-read-all
[248.954236] Subtest display-on-read-all: SUCCESS (0.382s)
[249.202577] [029/131] (805s left) kms_atomic (plane-primary-overlay-mutable-zpos)
[249.389539] Starting subtest: plane-primary-overlay-mutable-zpos
[250.314465] Subtest plane-primary-overlay-mutable-zpos: SKIP (0.918s)
[250.802523] [030/131] (803s left) i915_pm_rps (engine-order)
[250.924574] Starting subtest: engine-order
[262.163133] Subtest engine-order: SUCCESS (11.223s)
[262.387191] [031/131] (792s left) i915_drm_fdinfo (virtual-busy-hang-all)
[262.507646] Starting subtest: virtual-busy-hang-all
[262.511585] Subtest virtual-busy-hang-all: SUCCESS (0.001s)
[262.707732] [032/131] (792s left) kms_psr (fbc-psr-primary-mmap-cpu)
[262.902503] Subtest fbc-psr-primary-mmap-cpu: SKIP (0.000s)
[263.019447] [033/131] (792s left) gem_exec_schedule (out-order)
[263.163817] Starting subtest: out-order
[263.166984] Starting dynamic subtest: rcs0
[263.284637] Dynamic subtest rcs0: SUCCESS (0.117s)
[263.316411] Starting dynamic subtest: bcs0
[263.418278] Dynamic subtest bcs0: SUCCESS (0.124s)
[263.439870] Starting dynamic subtest: vcs0
[263.523604] Dynamic subtest vcs0: SUCCESS (0.115s)
[263.558509] Starting dynamic subtest: vecs0
[263.643339] Dynamic subtest vecs0: SUCCESS (0.113s)
[263.651463] Subtest out-order: SUCCESS (0.481s)
[264.069192] [034/131] (791s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-upscaling)
[264.248818] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-upscaling
[264.290527] Starting dynamic subtest: pipe-A-valid-mode
[264.682508] Dynamic subtest pipe-A-valid-mode: SKIP (0.377s)
[264.724362] Subtest flip-64bpp-yftile-to-32bpp-yftile-upscaling: SKIP (0.383s)
[264.944437] [035/131] (790s left) core_getclient (basic)
[265.055579] Starting subtest: basic
[265.059290] Subtest basic: SUCCESS (0.000s)
[265.244171] [036/131] (790s left) prime_busy (after-wait)
[265.350884] Starting subtest: after-wait
[265.363408] Starting dynamic subtest: rcs0
[265.378362] Dynamic subtest rcs0: SUCCESS (0.013s)
[265.400209] Starting dynamic subtest: bcs0
[265.403686] Dynamic subtest bcs0: SUCCESS (0.014s)
[265.426053] Starting dynamic subtest: vcs0
[265.429505] Dynamic subtest vcs0: SUCCESS (0.017s)
[265.452864] Starting dynamic subtest: vecs0
[265.456892] Dynamic subtest vecs0: SUCCESS (0.016s)
[265.549688] Subtest after-wait: SUCCESS (0.196s)
[265.750303] [037/131] (789s left) gem_exec_schedule (u-submit-golden-slice)
[265.884204] Starting subtest: u-submit-golden-slice
[265.887965] Starting dynamic subtest: rcs0
[265.931198] Dynamic subtest rcs0: SUCCESS (0.044s)
[265.953232] Starting dynamic subtest: bcs0
[265.984717] Dynamic subtest bcs0: SUCCESS (0.050s)
[266.012181] Starting dynamic subtest: vcs0
[266.032238] Dynamic subtest vcs0: SUCCESS (0.043s)
[266.055486] Starting dynamic subtest: vecs0
[266.081792] Dynamic subtest vecs0: SUCCESS (0.046s)
[266.085456] Subtest u-submit-golden-slice: SUCCESS (0.200s)
[266.523790] [038/131] (789s left) gem_eio (in-flight-contexts-immediate)
[266.658958] Starting subtest: in-flight-contexts-immediate
[277.982126] Subtest in-flight-contexts-immediate: SUCCESS (11.315s)
[278.257648] [039/131] (777s left) gem_pxp (verify-pxp-key-change-after-suspend-resume)
[280.896078] Starting subtest: verify-pxp-key-change-after-suspend-resume
[280.902884] Subtest verify-pxp-key-change-after-suspend-resume: SKIP (0.002s)
[281.074274] [040/131] (774s left) gem_softpin (evict-active-interruptible)
[281.190889] Starting subtest: evict-active-interruptible
[281.214060] Subtest evict-active-interruptible: SUCCESS (0.022s)
[281.405777] [041/131] (774s left) kms_big_fb (4-tiled-8bpp-rotate-180)
[281.608284] Starting subtest: 4-tiled-8bpp-rotate-180
[281.615253] Subtest 4-tiled-8bpp-rotate-180: SKIP (0.000s)
[281.794093] [042/131] (774s left) gem_userptr_blits (relocations)
[281.921181] Starting subtest: relocations
[281.928062] Subtest relocations: SKIP (0.003s)
[282.156635] [043/131] (773s left) kms_busy (extended-pageflip-hang-newfb)
[282.349392] Starting subtest: extended-pageflip-hang-newfb
[282.352692] Starting dynamic subtest: pipe-A
[286.883765] Dynamic subtest pipe-A: SUCCESS (4.525s)
[286.934707] Starting dynamic subtest: pipe-C
[290.907815] Dynamic subtest pipe-C: SUCCESS (4.014s)
[290.911124] Subtest extended-pageflip-hang-newfb: SUCCESS (8.558s)
[291.269793] [044/131] (764s left) syncobj_wait (single-wait-for-submit-unsubmitted)
[291.385087] Starting subtest: single-wait-for-submit-unsubmitted
[291.488190] Subtest single-wait-for-submit-unsubmitted: SUCCESS (0.101s)
[291.663518] [045/131] (764s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-move)
[291.863331] Starting subtest: fbc-1p-primscrn-spr-indfb-move
[295.084754] Subtest fbc-1p-primscrn-spr-indfb-move: SUCCESS (3.225s)
[295.430971] [046/131] (760s left) kms_plane_cursor (primary)
[295.620240] Starting subtest: primary
[295.626706] Starting dynamic subtest: pipe-A-HDMI-A-1-size-64
[298.217863] Dynamic subtest pipe-A-HDMI-A-1-size-64: SUCCESS (2.597s)
[298.288856] Starting dynamic subtest: pipe-A-HDMI-A-1-size-128
[300.702059] Dynamic subtest pipe-A-HDMI-A-1-size-128: SUCCESS (2.430s)
[300.773751] Starting dynamic subtest: pipe-A-HDMI-A-1-size-256
[303.185231] Dynamic subtest pipe-A-HDMI-A-1-size-256: SUCCESS (2.431s)
[303.465447] Starting dynamic subtest: pipe-B-HDMI-A-2-size-64
[305.923084] Dynamic subtest pipe-B-HDMI-A-2-size-64: SUCCESS (2.494s)
[305.976602] Starting dynamic subtest: pipe-B-HDMI-A-2-size-128
[308.388035] Dynamic subtest pipe-B-HDMI-A-2-size-128: SUCCESS (2.432s)
[308.442173] Starting dynamic subtest: pipe-B-HDMI-A-2-size-256
[310.855167] Dynamic subtest pipe-B-HDMI-A-2-size-256: SUCCESS (2.432s)
[311.154834] Starting dynamic subtest: pipe-C-HDMI-A-1-size-64
[313.598093] Dynamic subtest pipe-C-HDMI-A-1-size-64: SUCCESS (2.497s)
[313.650476] Starting dynamic subtest: pipe-C-HDMI-A-1-size-128
[316.065060] Dynamic subtest pipe-C-HDMI-A-1-size-128: SUCCESS (2.432s)
[316.118669] Starting dynamic subtest: pipe-C-HDMI-A-1-size-256
[318.531467] Dynamic subtest pipe-C-HDMI-A-1-size-256: SUCCESS (2.431s)
[318.760560] Subtest primary: SUCCESS (23.144s)
[319.086316] [047/131] (737s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[319.290463] Starting subtest: psr-1p-primscrn-spr-indfb-draw-render
[319.297407] Subtest psr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[319.466046] [048/131] (737s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[319.670602] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[319.676961] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[319.853629] [049/131] (736s left) gem_exec_balancer (bonded-true-hang)
[320.096632] Starting subtest: bonded-true-hang
[324.093069] Subtest bonded-true-hang: SUCCESS (3.999s)
[324.309862] [050/131] (732s left) gem_exec_fence (syncobj-timeline-repeat)
[324.974263] Starting subtest: syncobj-timeline-repeat
[326.154971] Subtest syncobj-timeline-repeat: SUCCESS (1.181s)
[326.451111] [051/131] (730s left) syncobj_timeline (etime-multi-wait-for-submit-submitted)
[326.570465] Starting subtest: etime-multi-wait-for-submit-submitted
[326.679965] Subtest etime-multi-wait-for-submit-submitted: SUCCESS (0.108s)
[326.864580] [052/131] (730s left) kms_pipe_b_c_ivb (pipe-b-double-modeset-then-modeset-pipe-c)
[326.978147] Subtest pipe-B-double-modeset-then-modeset-pipe-C: SKIP (0.000s)
[327.095959] [053/131] (730s left) syncobj_wait (multi-wait-submitted)
[327.201519] Starting subtest: multi-wait-submitted
[327.311055] Subtest multi-wait-submitted: SUCCESS (0.109s)
[327.499880] [054/131] (729s left) gem_ppgtt (blt-vs-render-ctx0)
[327.615179] Starting subtest: blt-vs-render-ctx0
[360.631959] Subtest blt-vs-render-ctx0: SUCCESS (33.016s)
[360.823911] [055/131] (696s left) kms_cdclk (mode-transition)
[361.071209] Subtest mode-transition: SKIP (0.000s)
[361.181899] [056/131] (696s left) kms_vrr (max-min)
[361.416151] Starting subtest: max-min
[361.428544] Subtest max-min: SKIP (0.001s)
[361.601799] [057/131] (695s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-pgflip-blt)
[361.816757] Starting subtest: psr-2p-scndscrn-shrfb-pgflip-blt
[361.823961] Subtest psr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[361.998628] [058/131] (695s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-upscaling)
[362.182293] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-upscaling
[362.185754] Starting dynamic subtest: pipe-A-valid-mode
[363.176086] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.984s)
[363.179992] Subtest flip-32bpp-ytile-to-64bpp-ytile-upscaling: SUCCESS (0.991s)
[363.503856] [059/131] (694s left) kms_psr (fbc-pr-no-drrs)
[363.703772] Subtest fbc-pr-no-drrs: SKIP (0.000s)
[363.820191] [060/131] (694s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[364.027108] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[364.036853] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[375.064307] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (11.029s)
[375.093201] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[386.029962] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (10.959s)
[386.058332] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[396.965848] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (10.930s)
[396.969195] Subtest 2x-plain-flip-fb-recreate-interruptible: SUCCESS (32.940s)
[397.896149] [061/131] (660s left) drm_read (short-buffer-nonblock)
[398.686622] Starting subtest: short-buffer-nonblock
[398.689953] Subtest short-buffer-nonblock: SUCCESS (0.000s)
[398.805834] [062/131] (659s left) syncobj_wait (multi-wait-submitted-signaled)
[398.935351] Starting subtest: multi-wait-submitted-signaled
[398.938622] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s)
[399.121404] [063/131] (659s left) kms_flip (2x-flip-vs-suspend)
[399.308731] Starting subtest: 2x-flip-vs-suspend
[399.312748] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2