[44.678694] Initializing watchdogs
[44.678757]   /dev/watchdog0
[44.694333] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:9a49 Intel Tigerlake (Gen12) TigerLake-LP GT2 [Iris Xe Graphics]
[44.700694] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[44.703254] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[44.705171] [001/132] (960s left) gem_exec_params (dr4-dirt)
[44.746481] Starting subtest: DR4-dirt
[44.747193] Subtest DR4-dirt: SUCCESS (0.000s)
[44.833472] [002/132] (960s left) gem_wait (invalid-buf)
[44.871885] Starting subtest: invalid-buf
[44.872614] Subtest invalid-buf: SUCCESS (0.000s)
[45.083329] [003/132] (960s left) gem_exec_whisper (basic-forked)
[45.126896] Starting subtest: basic-forked
[46.692127] Subtest basic-forked: SUCCESS (1.565s)
[46.884460] [004/132] (958s left) kms_pm_dc (dc5-psr)
[47.978078] Starting subtest: dc5-psr
[47.979445] Subtest dc5-psr: SKIP (0.000s)
[48.034865] [005/132] (957s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[48.124487] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[48.125882] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[48.211683] [006/132] (957s left) kms_getfb (getfb-handle-not-fb)
[48.295491] Starting subtest: getfb-handle-not-fb
[48.296191] Subtest getfb-handle-not-fb: SUCCESS (0.000s)
[48.397829] [007/132] (957s left) syncobj_eventfd (timeline-wait-signaled)
[48.431747] Starting subtest: timeline-wait-signaled
[48.432514] Subtest timeline-wait-signaled: SUCCESS (0.001s)
[48.498752] [008/132] (956s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[48.587560] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[48.588982] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[48.689171] [009/132] (956s left) gem_pread (bench)
[48.722830] Starting subtest: bench
[49.544496] Subtest bench: SUCCESS (0.813s)
[49.637341] [010/132] (955s left) prime_mmap (test_map_unmap)
[49.670185] Starting subtest: test_map_unmap
[49.670906] Starting dynamic subtest: test_map_unmap-smem
[49.672335] Dynamic subtest test_map_unmap-smem: SUCCESS (0.002s)
[49.673092] Subtest test_map_unmap: SUCCESS (0.002s)
[49.762022] [011/132] (955s left) syncobj_wait (invalid-signal-one-illegal-handle)
[49.796139] Starting subtest: invalid-signal-one-illegal-handle
[49.796873] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[49.856263] [012/132] (955s left) gem_exec_schedule (u-semaphore-user)
[49.902479] Starting subtest: u-semaphore-user
[49.910326] Subtest u-semaphore-user: SUCCESS (0.008s)
[50.205024] [013/132] (955s left) kms_cursor_legacy (cursorb-vs-flipa-atomic)
[50.292061] Starting subtest: cursorB-vs-flipA-atomic
[50.294289] Subtest cursorB-vs-flipA-atomic: SKIP (0.002s)
[50.353573] [014/132] (955s left) gem_exec_params (invalid-batch-start-offset)
[50.392092] Starting subtest: invalid-batch-start-offset
[50.395918] Subtest invalid-batch-start-offset: SUCCESS (0.003s)
[50.509377] [015/132] (955s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[50.597430] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[50.598807] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[50.691350] [016/132] (955s left) prime_self_import (basic-with_one_bo)
[50.722835] Starting subtest: basic-with_one_bo
[50.725801] Subtest basic-with_one_bo: SUCCESS (0.003s)
[50.797422] [017/132] (955s left) kms_sequence (get-busy)
[50.880825] Starting subtest: get-busy
[50.881513] Starting dynamic subtest: pipe-A-HDMI-A-1
[52.194720] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.313s)
[52.204282] Starting dynamic subtest: pipe-B-HDMI-A-1
[53.464018] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (1.267s)
[53.472184] Starting dynamic subtest: pipe-C-HDMI-A-1
[54.734455] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.269s)
[54.741151] Starting dynamic subtest: pipe-D-HDMI-A-1
[56.010859] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.276s)
[56.011672] Subtest get-busy: SUCCESS (5.130s)
[56.124120] [018/132] (949s left) kms_psr (psr-cursor-plane-onoff)
[56.212488] Subtest psr-cursor-plane-onoff: SKIP (0.000s)
[56.250102] [019/132] (949s left) kms_color_pipeline (plane-lut1d)
[56.337037] Starting subtest: plane-lut1d
[56.423501] Starting dynamic subtest: pipe-A-plane-0
[56.523686] Dynamic subtest pipe-A-plane-0: SUCCESS (0.100s)
[56.536880] Starting dynamic subtest: pipe-A-plane-1
[56.615605] Dynamic subtest pipe-A-plane-1: SUCCESS (0.091s)
[56.625887] Starting dynamic subtest: pipe-A-plane-2
[56.715203] Dynamic subtest pipe-A-plane-2: SUCCESS (0.099s)
[56.931215] Starting dynamic subtest: pipe-B-plane-0
[57.009435] Dynamic subtest pipe-B-plane-0: SUCCESS (0.084s)
[57.021891] Starting dynamic subtest: pipe-B-plane-1
[57.101161] Dynamic subtest pipe-B-plane-1: SUCCESS (0.091s)
[57.113456] Starting dynamic subtest: pipe-B-plane-2
[57.201253] Dynamic subtest pipe-B-plane-2: SUCCESS (0.099s)
[57.389346] Starting dynamic subtest: pipe-C-plane-0
[57.474864] Dynamic subtest pipe-C-plane-0: SUCCESS (0.092s)
[57.485790] Starting dynamic subtest: pipe-C-plane-1
[57.574981] Dynamic subtest pipe-C-plane-1: SUCCESS (0.099s)
[57.584745] Starting dynamic subtest: pipe-C-plane-2
[57.666527] Dynamic subtest pipe-C-plane-2: SUCCESS (0.091s)
[57.869048] Starting dynamic subtest: pipe-D-plane-0
[57.955844] Dynamic subtest pipe-D-plane-0: SUCCESS (0.092s)
[57.965572] Starting dynamic subtest: pipe-D-plane-1
[58.055723] Dynamic subtest pipe-D-plane-1: SUCCESS (0.099s)
[58.067776] Starting dynamic subtest: pipe-D-plane-2
[58.155928] Dynamic subtest pipe-D-plane-2: SUCCESS (0.099s)
[58.205244] Subtest plane-lut1d: SUCCESS (1.868s)
[58.304015] [020/132] (947s left) api_intel_bb (full-batch)
[58.337771] Starting subtest: full-batch
[58.340842] Subtest full-batch: SUCCESS (0.004s)
[58.448767] [021/132] (947s left) kms_psr (psr-sprite-render)
[58.537101] Subtest psr-sprite-render: SKIP (0.000s)
[58.575060] [022/132] (947s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[58.662419] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[58.663776] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[58.757843] [023/132] (947s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[58.845387] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[58.846755] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[58.940486] [024/132] (947s left) kms_vrr (seamless-rr-switch-vrr)
[59.024604] Starting subtest: seamless-rr-switch-vrr
[59.026599] Subtest seamless-rr-switch-vrr: SKIP (0.000s)
[59.078625] [025/132] (947s left) gem_userptr_blits (create-destroy-unsync)
[59.117793] Subtest create-destroy-unsync: SKIP (0.000s)
[59.232462] [026/132] (946s left) core_hotunplug (unbind-rebind)
[59.297428] Starting subtest: unbind-rebind
[62.375398] Subtest unbind-rebind: SUCCESS (3.078s)
[62.406752] [027/132] (943s left) kms_plane (pixel-format-y-tiled-modifier)
[62.496481] Starting subtest: pixel-format-y-tiled-modifier
[62.525378] Starting dynamic subtest: pipe-A-plane-0
[63.034134] Dynamic subtest pipe-A-plane-0: SUCCESS (0.508s)
[63.044922] Starting dynamic subtest: pipe-A-plane-3
[63.459181] Dynamic subtest pipe-A-plane-3: SUCCESS (0.424s)
[63.468640] Starting dynamic subtest: pipe-A-plane-5
[63.792555] Dynamic subtest pipe-A-plane-5: SUCCESS (0.333s)
[63.808579] Starting dynamic subtest: pipe-A-plane-7
[63.810163] Dynamic subtest pipe-A-plane-7: SKIP (0.000s)
[63.950447] Starting dynamic subtest: pipe-B-plane-0
[64.410846] Dynamic subtest pipe-B-plane-0: SUCCESS (0.467s)
[64.421873] Starting dynamic subtest: pipe-B-plane-3
[64.852493] Dynamic subtest pipe-B-plane-3: SUCCESS (0.441s)
[64.864484] Starting dynamic subtest: pipe-B-plane-5
[65.185960] Dynamic subtest pipe-B-plane-5: SUCCESS (0.332s)
[65.202411] Starting dynamic subtest: pipe-B-plane-7
[65.203893] Dynamic subtest pipe-B-plane-7: SKIP (0.000s)
[65.258965] Subtest pixel-format-y-tiled-modifier: SUCCESS (2.762s)
[65.433635] [028/132] (940s left) gem_render_copy (y-tiled-ccs-to-yf-tiled)
[65.476573] Starting subtest: y-tiled-ccs-to-yf-tiled
[65.477260] Starting dynamic subtest: smem
[65.552808] Dynamic subtest smem: SUCCESS (0.076s)
[65.553484] Subtest y-tiled-ccs-to-yf-tiled: SUCCESS (0.076s)
[65.744993] [029/132] (940s left) gem_exec_params (invalid-fence-in)
[65.781480] Starting subtest: invalid-fence-in
[65.783011] Subtest invalid-fence-in: SUCCESS (0.001s)
[65.877165] [030/132] (940s left) api_intel_bb (crc32)
[65.909880] Starting subtest: crc32
[65.911355] Subtest crc32: SKIP (0.000s)
[65.998957] [031/132] (940s left) gem_mmap_gtt (cpuset-medium-copy)
[66.031473] Starting subtest: cpuset-medium-copy
[72.490281] Subtest cpuset-medium-copy: SUCCESS (6.457s)
[72.553435] [032/132] (933s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted)
[72.625339] Starting subtest: multi-wait-for-submit-unsubmitted-submitted
[72.726686] Subtest multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.101s)
[72.813283] [033/132] (933s left) syncobj_timeline (wait-all-delayed-signal)
[72.848037] Starting subtest: wait-all-delayed-signal
[72.950577] Subtest wait-all-delayed-signal: SUCCESS (0.102s)
[73.025227] [034/132] (933s left) gem_exec_balancer (bonded-chain)
[73.067349] Starting subtest: bonded-chain
[80.428835] Subtest bonded-chain: SUCCESS (7.353s)
[80.614378] [035/132] (925s left) gem_exec_schedule (independent)
[80.657040] Starting subtest: independent
[80.657802] Starting dynamic subtest: rcs0
[80.760950] Dynamic subtest rcs0: SUCCESS (0.103s)
[80.777155] Starting dynamic subtest: bcs0
[80.877392] Dynamic subtest bcs0: SUCCESS (0.115s)
[80.892825] Starting dynamic subtest: vcs0
[80.981559] Dynamic subtest vcs0: SUCCESS (0.103s)
[80.998063] Starting dynamic subtest: vcs1
[81.086438] Dynamic subtest vcs1: SUCCESS (0.104s)
[81.102217] Starting dynamic subtest: vecs0
[81.200954] Dynamic subtest vecs0: SUCCESS (0.114s)
[81.202222] Subtest independent: SUCCESS (0.544s)
[81.495109] [036/132] (925s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-upscaling)
[81.586790] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-upscaling
[81.588249] Subtest flip-32bpp-4tile-to-64bpp-4tile-upscaling: SKIP (0.000s)
[81.640799] [037/132] (924s left) kms_plane (pixel-format-yf-tiled-modifier)
[81.726433] Starting subtest: pixel-format-yf-tiled-modifier
[81.727775] Subtest pixel-format-yf-tiled-modifier: SKIP (0.000s)
[81.784554] [038/132] (924s left) syncobj_timeline (reset-unsignaled)
[81.817577] Starting subtest: reset-unsignaled
[81.818266] Subtest reset-unsignaled: SUCCESS (0.000s)
[81.877942] [039/132] (924s left) gem_exec_reloc (basic-write-read)
[81.917609] Starting subtest: basic-write-read
[81.922309] Subtest basic-write-read: SUCCESS (0.005s)
[82.056194] [040/132] (924s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt)
[82.145575] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt
[82.146953] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[82.247522] [041/132] (924s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-blt)
[82.334434] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-blt
[82.335779] Subtest fbc-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[82.418863] [042/132] (924s left) kms_plane_scaling (plane-upscale-factor-0-25-with-pixel-format)
[82.510206] Starting subtest: plane-upscale-factor-0-25-with-pixel-format
[82.511030] Starting dynamic subtest: pipe-A
[83.609887] Dynamic subtest pipe-A: SUCCESS (1.107s)
[83.618248] Starting dynamic subtest: pipe-B
[84.779031] Dynamic subtest pipe-B: SUCCESS (1.169s)
[84.787237] Starting dynamic subtest: pipe-C
[84.980144] Dynamic subtest pipe-C: SUCCESS (0.201s)
[84.988239] Starting dynamic subtest: pipe-D
[85.182111] Dynamic subtest pipe-D: SUCCESS (0.201s)
[85.182913] Subtest plane-upscale-factor-0-25-with-pixel-format: SUCCESS (2.679s)
[85.358181] [043/132] (921s left) kms_vblank (wait-busy-hang)
[85.449983] Starting subtest: wait-busy-hang
[85.450804] Starting dynamic subtest: pipe-A-HDMI-A-1
[99.471174] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (14.020s)
[99.483042] Starting dynamic subtest: pipe-D-HDMI-A-1
[113.801766] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (14.324s)
[113.802504] Subtest wait-busy-hang: SUCCESS (28.352s)
[113.946036] [044/132] (892s left) gem_userptr_blits (process-exit)
[113.993643] Starting subtest: process-exit
[114.002710] Subtest process-exit: SUCCESS (0.009s)
[114.125141] [045/132] (892s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[114.223532] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[114.224882] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[114.315896] [046/132] (892s left) kms_cdclk (plane-scaling)
[114.349366] Subtest plane-scaling: SKIP (0.000s)
[114.389279] [047/132] (892s left) i915_pm_rpm (gem-evict-pwrite)
[115.798462] Starting subtest: gem-evict-pwrite
[117.077307] Subtest gem-evict-pwrite: SUCCESS (1.279s)
[117.252737] [048/132] (889s left) gem_exec_params (invalid-bsd2-flag-on-vebox)
[117.289613] Starting subtest: invalid-bsd2-flag-on-vebox
[117.290366] Subtest invalid-bsd2-flag-on-vebox: SUCCESS (0.000s)
[117.388369] [049/132] (889s left) kms_frontbuffer_tracking (psr-1p-rte)
[117.477073] Starting subtest: psr-1p-rte
[117.478492] Subtest psr-1p-rte: SKIP (0.000s)
[117.585951] [050/132] (889s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-upscaling)
[117.669801] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-upscaling
[117.671146] Subtest flip-64bpp-4tile-to-32bpp-4tile-upscaling: SKIP (0.000s)
[117.723762] [051/132] (889s left) i915_suspend (basic-s3-without-i915)
[117.755817] Starting subtest: basic-s3-without-i915