[88.149219] Initializing watchdogs
[88.149387]   /dev/watchdog0
[88.186404] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[88.201203] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[88.207399] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[88.215541] [001/131] (960s left) gem_exec_nop (basic-sequential)
[88.372517] Starting subtest: basic-sequential
[91.459566] Subtest basic-sequential: SUCCESS (3.097s)
[91.752976] [002/131] (957s left) kms_cursor_legacy (2x-long-cursor-vs-flip-atomic)
[91.933807] Starting subtest: 2x-long-cursor-vs-flip-atomic
[143.548978] Subtest 2x-long-cursor-vs-flip-atomic: SUCCESS (51.610s)
[143.848700] [003/131] (905s left) gem_mmap_wc (write-read-distinct)
[143.958053] Starting subtest: write-read-distinct
[143.961510] Subtest write-read-distinct: SUCCESS (0.009s)
[144.129323] [004/131] (904s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[144.308446] Starting subtest: nonblocking-crc-frame-sequence
[144.312086] Starting dynamic subtest: pipe-A-HDMI-A-1
[145.334686] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.021s)
[145.366627] Starting dynamic subtest: pipe-B-HDMI-A-2
[146.086502] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.741s)
[146.104289] Starting dynamic subtest: pipe-C-HDMI-A-1
[146.784303] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.698s)
[146.820600] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.479s)
[147.089559] [005/131] (901s left) i915_drm_fdinfo (virtual-busy)
[147.210302] Starting subtest: virtual-busy
[149.376463] Subtest virtual-busy: SUCCESS (2.166s)
[149.535317] [006/131] (899s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[149.733949] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[149.741075] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[149.905672] [007/131] (899s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-wc)
[150.103496] Starting subtest: fbcpsr-rgb101010-draw-mmap-wc
[150.110732] Subtest fbcpsr-rgb101010-draw-mmap-wc: SKIP (0.000s)
[150.269620] [008/131] (899s left) sysfs_defaults (readonly)
[150.380054] Starting subtest: readonly
[150.383289] Starting dynamic subtest: vecs0
[150.386677] Dynamic subtest vecs0: SUCCESS (0.002s)
[150.404076] Starting dynamic subtest: vcs0
[150.407398] Dynamic subtest vcs0: SUCCESS (0.002s)
[150.424971] Starting dynamic subtest: bcs0
[150.429112] Dynamic subtest bcs0: SUCCESS (0.002s)
[150.446965] Starting dynamic subtest: rcs0
[150.450934] Dynamic subtest rcs0: SUCCESS (0.001s)
[150.455218] Subtest readonly: SUCCESS (0.011s)
[150.551443] [009/131] (898s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted-signal
[150.656559] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted-signaled
[150.761297] Subtest etime-multi-wait-all-available-unsubmitted-submitted-signaled: SUCCESS (0.104s)
[150.921630] [010/131] (898s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-pwrite)
[151.112106] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-pwrite
[151.119121] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-pwrite: SKIP (0.000s)
[151.288963] [011/131] (898s left) syncobj_timeline (reset-signaled)
[151.394866] Starting subtest: reset-signaled
[151.398392] Subtest reset-signaled: SUCCESS (0.002s)
[151.574221] [012/131] (898s left) gem_exec_reloc (basic-wc-gtt)
[151.694412] Starting subtest: basic-wc-gtt
[151.714966] Subtest basic-wc-gtt: SUCCESS (0.019s)
[151.955683] [013/131] (897s left) gen9_exec_parse (batch-zero-length)
[152.088856] Starting subtest: batch-zero-length
[152.093287] Subtest batch-zero-length: SUCCESS (0.002s)
[152.371810] [014/131] (897s left) gem_ctx_shared (detached-shared-gtt)
[152.494192] Starting subtest: detached-shared-gtt
[154.646792] Subtest detached-shared-gtt: SUCCESS (2.151s)
[154.921099] [015/131] (895s left) perf_pmu (rc6-all-gts)
[155.031291] Starting subtest: rc6-all-gts
[155.040502] Subtest rc6-all-gts: SKIP (0.000s)
[155.213454] [016/131] (894s left) kms_plane (plane-position-covered)
[155.389622] Starting subtest: plane-position-covered
[156.110329] Starting dynamic subtest: pipe-A-plane-1
[156.641429] Dynamic subtest pipe-A-plane-1: SUCCESS (0.526s)
[156.672035] Starting dynamic subtest: pipe-A-plane-2
[157.271872] Dynamic subtest pipe-A-plane-2: SUCCESS (0.544s)
[157.337495] Starting dynamic subtest: pipe-A-plane-3
[157.833624] Dynamic subtest pipe-A-plane-3: SUCCESS (0.557s)
[157.901070] Starting dynamic subtest: pipe-A-plane-4
[158.034596] Dynamic subtest pipe-A-plane-4: SUCCESS (0.009s)
[158.357911] Starting dynamic subtest: pipe-B-plane-1
[158.697191] Dynamic subtest pipe-B-plane-1: SUCCESS (0.514s)
[158.747598] Starting dynamic subtest: pipe-B-plane-2
[159.218400] Dynamic subtest pipe-B-plane-2: SUCCESS (0.521s)
[159.256026] Starting dynamic subtest: pipe-B-plane-3
[159.753992] Dynamic subtest pipe-B-plane-3: SUCCESS (0.532s)
[159.775509] Starting dynamic subtest: pipe-B-plane-4
[159.783743] Dynamic subtest pipe-B-plane-4: SUCCESS (0.014s)
[159.789351] Subtest plane-position-covered: SUCCESS (4.379s)
[160.086854] [017/131] (890s left) gem_mmap_gtt (fault-concurrent)
[160.200464] Starting subtest: fault-concurrent
[163.695283] Subtest fault-concurrent: SUCCESS (3.496s)
[163.884285] [018/131] (886s left) kms_psr (psr-sprite-render)
[164.067428] Subtest psr-sprite-render: SKIP (0.000s)
[164.165588] [019/131] (886s left) gem_mmap_gtt (hang-user)
[164.275561] Starting subtest: hang-user
[179.016061] Subtest hang-user: SUCCESS (14.742s)
[179.188091] [020/131] (871s left) template (a)
[179.299071] Starting subtest: A
[179.302638] Subtest A: SUCCESS (0.000s)
[179.468634] [021/131] (871s left) kms_cursor_legacy (cursora-vs-flipb-atomic)
[179.651277] Starting subtest: cursorA-vs-flipB-atomic
[190.896917] Subtest cursorA-vs-flipB-atomic: SUCCESS (11.245s)
[191.231326] [022/131] (859s left) kms_rotation_crc (primary-rotation-90)
[191.426293] Starting subtest: primary-rotation-90
[206.119101] Subtest primary-rotation-90: SUCCESS (14.694s)
[206.384330] [023/131] (844s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)
[206.577587] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite
[209.604068] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (3.028s)
[209.885392] [024/131] (840s left) kms_universal_plane (universal-plane-sanity)
[210.069899] Starting subtest: universal-plane-sanity
[210.073373] Starting dynamic subtest: pipe-A-HDMI-A-1
[210.902634] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.826s)
[210.925164] Starting dynamic subtest: pipe-B-HDMI-A-2
[211.427249] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.519s)
[211.449125] Starting dynamic subtest: pipe-C-HDMI-A-1
[211.992236] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.519s)
[211.998361] Subtest universal-plane-sanity: SUCCESS (1.878s)
[212.258240] [025/131] (838s left) gem_exec_schedule (u-fairslice-all)
[212.401116] Starting subtest: u-fairslice-all
[214.507033] Subtest u-fairslice-all: SUCCESS (2.107s)
[214.923151] [026/131] (836s left) gem_exec_fence (nb-await)
[215.176653] Starting subtest: nb-await
[215.180259] Starting dynamic subtest: rcs0
[215.249781] Dynamic subtest rcs0: SUCCESS (0.072s)
[215.269186] Starting dynamic subtest: bcs0
[215.320921] Dynamic subtest bcs0: SUCCESS (0.067s)
[215.340910] Starting dynamic subtest: vcs0
[215.393509] Dynamic subtest vcs0: SUCCESS (0.071s)
[215.413771] Starting dynamic subtest: vecs0
[215.461783] Dynamic subtest vecs0: SUCCESS (0.066s)
[215.466129] Subtest nb-await: SUCCESS (0.287s)
[216.258319] [FACT gem_exec_fence (nb-await)] new: kernel.kmod_is_loaded.vgem: true
[216.265233] [027/131] (834s left) kms_hdr (invalid-hdr)
[216.441099] Starting subtest: invalid-hdr
[244.153753] Subtest invalid-hdr: SKIP (27.665s)
[244.596145] [028/131] (806s left) kms_debugfs (display-on-read-all)
[244.816759] Starting subtest: display-on-read-all
[245.219871] Subtest display-on-read-all: SUCCESS (0.404s)
[245.470647] [029/131] (805s left) kms_atomic (plane-primary-overlay-mutable-zpos)
[245.653271] Starting subtest: plane-primary-overlay-mutable-zpos
[246.575890] Subtest plane-primary-overlay-mutable-zpos: SKIP (0.915s)
[247.105859] [030/131] (804s left) i915_pm_rps (engine-order)
[247.227699] Starting subtest: engine-order
[258.505239] Subtest engine-order: SUCCESS (11.265s)
[258.760107] [031/131] (792s left) i915_drm_fdinfo (virtual-busy-hang-all)
[258.874099] Starting subtest: virtual-busy-hang-all
[258.878325] Subtest virtual-busy-hang-all: SUCCESS (0.002s)
[259.051982] [032/131] (792s left) kms_psr (fbc-psr-primary-mmap-cpu)
[259.236139] Subtest fbc-psr-primary-mmap-cpu: SKIP (0.000s)
[259.354522] [033/131] (792s left) gem_exec_schedule (out-order)
[259.495519] Starting subtest: out-order
[259.499502] Starting dynamic subtest: rcs0
[259.610456] Dynamic subtest rcs0: SUCCESS (0.110s)
[259.636912] Starting dynamic subtest: bcs0
[259.745216] Dynamic subtest bcs0: SUCCESS (0.127s)
[259.773593] Starting dynamic subtest: vcs0
[259.858997] Dynamic subtest vcs0: SUCCESS (0.116s)
[259.886396] Starting dynamic subtest: vecs0
[259.979856] Dynamic subtest vecs0: SUCCESS (0.115s)
[259.983955] Subtest out-order: SUCCESS (0.485s)
[260.402306] [034/131] (791s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-upscaling)
[260.631750] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-upscaling
[260.639543] Starting dynamic subtest: pipe-A-valid-mode
[261.017153] Dynamic subtest pipe-A-valid-mode: SKIP (0.381s)
[261.061387] Subtest flip-64bpp-yftile-to-32bpp-yftile-upscaling: SKIP (0.387s)
[261.293525] [035/131] (790s left) core_getclient (basic)
[261.401725] Starting subtest: basic
[261.405167] Subtest basic: SUCCESS (0.000s)
[261.589816] [036/131] (790s left) prime_busy (after-wait)
[261.701209] Starting subtest: after-wait
[261.715508] Starting dynamic subtest: rcs0
[261.728111] Dynamic subtest rcs0: SUCCESS (0.011s)
[261.749678] Starting dynamic subtest: bcs0
[261.753332] Dynamic subtest bcs0: SUCCESS (0.022s)
[261.774996] Starting dynamic subtest: vcs0
[261.778484] Dynamic subtest vcs0: SUCCESS (0.022s)
[261.800517] Starting dynamic subtest: vecs0
[261.804561] Dynamic subtest vecs0: SUCCESS (0.007s)
[261.899862] Subtest after-wait: SUCCESS (0.199s)
[262.075280] [037/131] (790s left) gem_exec_schedule (u-submit-golden-slice)
[262.208532] Starting subtest: u-submit-golden-slice
[262.212038] Starting dynamic subtest: rcs0
[262.256382] Dynamic subtest rcs0: SUCCESS (0.047s)
[262.277876] Starting dynamic subtest: bcs0
[262.306722] Dynamic subtest bcs0: SUCCESS (0.046s)
[262.329092] Starting dynamic subtest: vcs0
[262.358831] Dynamic subtest vcs0: SUCCESS (0.048s)
[262.380148] Starting dynamic subtest: vecs0
[262.414216] Dynamic subtest vecs0: SUCCESS (0.052s)
[262.417493] Subtest u-submit-golden-slice: SUCCESS (0.209s)
[262.841139] [038/131] (789s left) gem_eio (in-flight-contexts-immediate)
[262.976290] Starting subtest: in-flight-contexts-immediate
[274.968055] Subtest in-flight-contexts-immediate: SUCCESS (11.993s)
[275.240213] [039/131] (777s left) gem_pxp (verify-pxp-key-change-after-suspend-resume)
[277.882992] Starting subtest: verify-pxp-key-change-after-suspend-resume
[277.890443] Subtest verify-pxp-key-change-after-suspend-resume: SKIP (0.000s)
[278.059681] [040/131] (774s left) gem_softpin (evict-active-interruptible)
[278.184761] Starting subtest: evict-active-interruptible
[278.190031] Subtest evict-active-interruptible: SUCCESS (0.012s)
[278.374616] [041/131] (774s left) kms_big_fb (4-tiled-8bpp-rotate-180)
[278.572423] Starting subtest: 4-tiled-8bpp-rotate-180
[278.578941] Subtest 4-tiled-8bpp-rotate-180: SKIP (0.000s)
[278.757774] [042/131] (773s left) gem_userptr_blits (relocations)
[278.881461] Starting subtest: relocations
[278.887943] Subtest relocations: SKIP (0.003s)
[279.109218] [043/131] (773s left) kms_busy (extended-pageflip-hang-newfb)
[279.301377] Starting subtest: extended-pageflip-hang-newfb
[279.305279] Starting dynamic subtest: pipe-A
[283.883984] Dynamic subtest pipe-A: SUCCESS (4.575s)
[283.907749] Starting dynamic subtest: pipe-C
[287.882346] Dynamic subtest pipe-C: SUCCESS (3.994s)
[287.886890] Subtest extended-pageflip-hang-newfb: SUCCESS (8.588s)
[288.247208] [044/131] (764s left) syncobj_wait (single-wait-for-submit-unsubmitted)
[288.365049] Starting subtest: single-wait-for-submit-unsubmitted
[288.466235] Subtest single-wait-for-submit-unsubmitted: SUCCESS (0.103s)
[288.643116] [045/131] (764s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-move)
[288.844969] Starting subtest: fbc-1p-primscrn-spr-indfb-move
[292.130258] Subtest fbc-1p-primscrn-spr-indfb-move: SUCCESS (3.292s)
[292.441954] [046/131] (760s left) kms_plane_cursor (primary)
[292.626126] Starting subtest: primary
[292.650214] Starting dynamic subtest: pipe-A-HDMI-A-1-size-64
[295.230116] Dynamic subtest pipe-A-HDMI-A-1-size-64: SUCCESS (2.607s)
[295.304566] Starting dynamic subtest: pipe-A-HDMI-A-1-size-128
[297.713073] Dynamic subtest pipe-A-HDMI-A-1-size-128: SUCCESS (2.431s)
[297.785574] Starting dynamic subtest: pipe-A-HDMI-A-1-size-256
[300.179552] Dynamic subtest pipe-A-HDMI-A-1-size-256: SUCCESS (2.413s)
[300.457614] Starting dynamic subtest: pipe-B-HDMI-A-2-size-64
[302.906066] Dynamic subtest pipe-B-HDMI-A-2-size-64: SUCCESS (2.491s)
[302.964539] Starting dynamic subtest: pipe-B-HDMI-A-2-size-128
[305.356248] Dynamic subtest pipe-B-HDMI-A-2-size-128: SUCCESS (2.413s)
[305.409500] Starting dynamic subtest: pipe-B-HDMI-A-2-size-256
[307.822310] Dynamic subtest pipe-B-HDMI-A-2-size-256: SUCCESS (2.432s)
[308.086444] Starting dynamic subtest: pipe-C-HDMI-A-1-size-64
[310.538172] Dynamic subtest pipe-C-HDMI-A-1-size-64: SUCCESS (2.494s)
[310.592748] Starting dynamic subtest: pipe-C-HDMI-A-1-size-128
[313.004897] Dynamic subtest pipe-C-HDMI-A-1-size-128: SUCCESS (2.431s)
[313.056861] Starting dynamic subtest: pipe-C-HDMI-A-1-size-256
[315.471200] Dynamic subtest pipe-C-HDMI-A-1-size-256: SUCCESS (2.432s)
[315.695365] Subtest primary: SUCCESS (23.075s)
[316.007280] [047/131] (737s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[316.221973] Starting subtest: psr-1p-primscrn-spr-indfb-draw-render
[316.228124] Subtest psr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[316.407611] [048/131] (736s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[316.624535] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[316.631232] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[316.805917] [049/131] (736s left) gem_exec_balancer (bonded-true-hang)
[317.048203] Starting subtest: bonded-true-hang
[321.058055] Subtest bonded-true-hang: SUCCESS (4.009s)
[321.255552] [050/131] (732s left) gem_exec_fence (syncobj-timeline-repeat)
[321.919984] Starting subtest: syncobj-timeline-repeat
[323.091541] Subtest syncobj-timeline-repeat: SUCCESS (1.172s)
[323.386813] [051/131] (730s left) syncobj_timeline (etime-multi-wait-for-submit-submitted)
[323.493561] Starting subtest: etime-multi-wait-for-submit-submitted
[323.600063] Subtest etime-multi-wait-for-submit-submitted: SUCCESS (0.107s)
[323.787473] [052/131] (729s left) kms_pipe_b_c_ivb (pipe-b-double-modeset-then-modeset-pipe-c)
[323.896019] Subtest pipe-B-double-modeset-then-modeset-pipe-C: SKIP (0.000s)
[324.027936] [053/131] (729s left) syncobj_wait (multi-wait-submitted)
[324.134454] Starting subtest: multi-wait-submitted
[324.240073] Subtest multi-wait-submitted: SUCCESS (0.105s)
[324.422238] [054/131] (729s left) gem_ppgtt (blt-vs-render-ctx0)
[324.534815] Starting subtest: blt-vs-render-ctx0
[357.730097] Subtest blt-vs-render-ctx0: SUCCESS (33.196s)
[357.931831] [055/131] (696s left) kms_cdclk (mode-transition)
[358.130377] Subtest mode-transition: SKIP (0.000s)
[358.252963] [056/131] (695s left) kms_vrr (max-min)
[358.453045] Starting subtest: max-min
[358.466800] Subtest max-min: SKIP (0.000s)
[358.644107] [057/131] (695s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-pgflip-blt)
[358.852188] Starting subtest: psr-2p-scndscrn-shrfb-pgflip-blt
[358.860169] Subtest psr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[359.039315] [058/131] (695s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-upscaling)
[359.273732] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-upscaling
[359.280253] Starting dynamic subtest: pipe-A-valid-mode
[360.215452] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.980s)
[360.218825] Subtest flip-32bpp-ytile-to-64bpp-ytile-upscaling: SUCCESS (0.987s)
[360.578646] [059/131] (693s left) kms_psr (fbc-pr-no-drrs)
[360.781791] Subtest fbc-pr-no-drrs: SKIP (0.000s)
[360.892538] [060/131] (693s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[361.095060] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[361.104836] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[372.184220] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (11.078s)
[372.213319] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[383.136761] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (10.946s)
[383.167084] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[394.082557] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (10.940s)
[394.086844] Subtest 2x-plain-flip-fb-recreate-interruptible: SUCCESS (32.988s)
[394.702552] [061/131] (659s left) drm_read (short-buffer-nonblock)
[395.142135] Starting subtest: short-buffer-nonblock
[395.146762] Subtest short-buffer-nonblock: SUCCESS (0.000s)
[395.430219] [062/131] (659s left) syncobj_wait (multi-wait-submitted-signaled)
[395.559452] Starting subtest: multi-wait-submitted-signaled
[395.562901] Subtest multi-wait-submitted-signaled: SUCCESS (0.002s)
[395.746887] [063/131] (659s left) kms_flip (2x-flip-vs-suspend)
[395.932229] Starting subtest: 2x-flip-vs-suspend
[395.935384] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2