[44.830912] Initializing watchdogs
[44.830976]   /dev/watchdog0
[44.846080] [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.851520] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[44.854423] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[44.856330] [001/133] (960s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt)
[44.950601] Starting subtest: fbcpsr-farfromfence-mmap-gtt
[44.952029] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s)
[45.045634] [002/133] (960s left) syncobj_timeline (wait-for-submit-delayed-submit)
[45.079481] Starting subtest: wait-for-submit-delayed-submit
[45.182713] Subtest wait-for-submit-delayed-submit: SUCCESS (0.102s)
[45.271493] [003/133] (960s left) kms_chamelium_hpd (dp-hpd)
[45.404558] Subtest dp-hpd: SKIP (0.000s)
[45.490925] [004/133] (959s left) kms_cursor_edge_walk (256x256-top-bottom)
[45.577337] Starting subtest: 256x256-top-bottom
[45.578024] Starting dynamic subtest: pipe-A-HDMI-A-1
[47.342429] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.743s)
[47.348307] Starting dynamic subtest: pipe-D-HDMI-A-1
[49.050944] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.665s)
[49.051677] Subtest 256x256-top-bottom: SUCCESS (3.410s)
[49.169127] [005/133] (956s left) gem_mmap_offset (clear)
[49.206917] Starting subtest: clear
[49.207714] Starting dynamic subtest: smem0
[72.123357] Dynamic subtest smem0: SUCCESS (22.750s)
[72.124054] Subtest clear: SUCCESS (22.750s)
[72.248721] [006/133] (933s left) kms_joiner (basic-force-big-joiner)
[72.383165] Starting subtest: basic-force-big-joiner
[72.384477] Subtest basic-force-big-joiner: SKIP (0.000s)
[72.441590] [007/133] (933s left) gem_exec_balancer (waits)
[72.485526] Starting subtest: waits
[79.414568] Subtest waits: SUCCESS (6.916s)
[79.607013] [008/133] (926s left) gem_exec_whisper (basic-queues)
[79.648103] Starting subtest: basic-queues
[81.401828] Subtest basic-queues: SUCCESS (1.751s)
[81.601176] [009/133] (924s left) gem_exec_suspend (basic-s3)
[81.640595] Starting subtest: basic-S3
[81.641293] Starting dynamic subtest: smem
[98.847032] Dynamic subtest smem: SUCCESS (2.818s)
[98.847719] Subtest basic-S3: SUCCESS (2.818s)
[107.047096] [010/133] (906s left) kms_psr (psr2-sprite-render)
[107.138869] Subtest psr2-sprite-render: SKIP (0.000s)
[107.172712] [011/133] (906s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[107.259519] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[107.260920] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[107.383578] [012/133] (906s left) kms_content_protection (atomic)
[107.475876] Starting subtest: atomic
[107.489791] Subtest atomic: SKIP (0.012s)
[107.610855] [013/133] (906s left) kms_async_flips (invalid-async-flip)
[107.697513] Starting subtest: invalid-async-flip
[107.821443] Starting dynamic subtest: pipe-A-HDMI-A-1
[107.957701] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.131s)
[108.085369] Starting dynamic subtest: pipe-B-HDMI-A-1
[108.194422] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.112s)
[108.304589] Starting dynamic subtest: pipe-C-HDMI-A-1
[108.400341] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.102s)
[108.519895] Starting dynamic subtest: pipe-D-HDMI-A-1
[108.605956] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.091s)
[108.606774] Subtest invalid-async-flip: SUCCESS (0.883s)
[108.823145] [014/133] (905s left) kms_flip (blocking-absolute-wf_vblank)
[108.915412] Starting subtest: blocking-absolute-wf_vblank
[108.916955] Starting dynamic subtest: A-HDMI-A1
[116.681342] Dynamic subtest A-HDMI-A1: SUCCESS (7.619s)
[116.688508] Starting dynamic subtest: B-HDMI-A1
[124.387732] Dynamic subtest B-HDMI-A1: SUCCESS (7.674s)
[124.396449] Starting dynamic subtest: C-HDMI-A1
[132.091420] Dynamic subtest C-HDMI-A1: SUCCESS (7.657s)
[132.099726] Starting dynamic subtest: D-HDMI-A1
[139.748069] Dynamic subtest D-HDMI-A1: SUCCESS (7.649s)
[139.749143] Subtest blocking-absolute-wf_vblank: SUCCESS (30.608s)
[139.930137] [015/133] (873s left) gem_exec_schedule (reorder-wide)
[140.105673] Starting subtest: reorder-wide
[140.106627] Starting dynamic subtest: rcs0
[140.211220] Dynamic subtest rcs0: SUCCESS (0.105s)
[140.222317] Starting dynamic subtest: bcs0
[140.283146] Dynamic subtest bcs0: SUCCESS (0.071s)
[140.294067] Starting dynamic subtest: vcs0
[140.356799] Dynamic subtest vcs0: SUCCESS (0.073s)
[140.366607] Starting dynamic subtest: vcs1
[140.429304] Dynamic subtest vcs1: SUCCESS (0.072s)
[140.440919] Starting dynamic subtest: vecs0
[140.501658] Dynamic subtest vecs0: SUCCESS (0.072s)
[140.502787] Subtest reorder-wide: SUCCESS (0.396s)
[140.694114] [FACT gem_exec_schedule (reorder-wide)] new: kernel.kmod_is_loaded.vgem: true
[140.697512] [016/133] (873s left) gem_watchdog (default-virtual)
[140.735404] Starting subtest: default-virtual
[142.005458] Subtest default-virtual: SUCCESS (1.262s)
[142.125173] [017/133] (871s left) gem_pwrite (basic-self)
[142.156961] Starting subtest: basic-self
[142.196128] Subtest basic-self: SUCCESS (0.039s)
[142.311420] [018/133] (871s left) kms_psr (fbc-psr2-cursor-mmap-gtt)
[142.397431] Subtest fbc-psr2-cursor-mmap-gtt: SKIP (0.000s)
[142.445198] [019/133] (871s left) kms_cursor_legacy (forked-move)
[142.532375] Starting subtest: forked-move
[142.533081] Starting dynamic subtest: pipe-A
[147.965748] Dynamic subtest pipe-A: SUCCESS (5.423s)
[147.974981] Starting dynamic subtest: pipe-B
[153.411548] Dynamic subtest pipe-B: SUCCESS (5.422s)
[153.419480] Starting dynamic subtest: pipe-C
[158.830138] Dynamic subtest pipe-C: SUCCESS (5.417s)
[158.838333] Starting dynamic subtest: pipe-D
[164.257830] Dynamic subtest pipe-D: SUCCESS (5.426s)
[164.267046] Starting dynamic subtest: all-pipes
[169.704886] Dynamic subtest all-pipes: SUCCESS (5.444s)
[169.705984] Subtest forked-move: SUCCESS (27.135s)
[169.749536] [020/133] (844s left) vgem_basic (debugfs)
[169.786887] Starting subtest: debugfs
[169.787588] Subtest debugfs: SUCCESS (0.000s)
[169.867820] [021/133] (844s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-pwrite)
[169.956360] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-pwrite
[169.957688] Subtest fbc-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[170.069569] [022/133] (844s left) kms_plane_lowres (tiling-yf)
[170.153653] Starting subtest: tiling-yf
[170.155053] Subtest tiling-yf: SKIP (0.000s)
[170.242428] [023/133] (843s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[170.326101] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[170.334719] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.009s)
[170.434279] [024/133] (843s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-upscaling)
[170.518331] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-upscaling
[170.519021] Starting dynamic subtest: pipe-A-valid-mode
[171.305838] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.786s)
[171.306567] Subtest flip-64bpp-xtile-to-32bpp-xtile-upscaling: SUCCESS (0.788s)
[171.443684] [025/133] (842s left) kms_vrr (flip-basic)
[171.532678] Starting subtest: flip-basic
[171.534672] Subtest flip-basic: SKIP (0.000s)
[171.616372] [026/133] (842s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[171.710341] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[171.711660] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[171.829895] [027/133] (842s left) kms_flip (modeset-vs-vblank-race-interruptible)
[171.917151] Starting subtest: modeset-vs-vblank-race-interruptible
[171.919689] Starting dynamic subtest: A-HDMI-A1
[174.816252] Dynamic subtest A-HDMI-A1: SUCCESS (2.896s)
[174.824892] Starting dynamic subtest: B-HDMI-A1
[177.690830] Dynamic subtest B-HDMI-A1: SUCCESS (2.872s)
[177.699496] Starting dynamic subtest: C-HDMI-A1
[180.577060] Dynamic subtest C-HDMI-A1: SUCCESS (2.883s)
[180.585568] Starting dynamic subtest: D-HDMI-A1
[183.398369] Dynamic subtest D-HDMI-A1: SUCCESS (2.816s)
[183.399224] Subtest modeset-vs-vblank-race-interruptible: SUCCESS (11.478s)
[183.615471] [028/133] (830s left) kms_addfb_basic (bad-pitch-63)
[183.659171] Starting subtest: bad-pitch-63
[183.659900] Subtest bad-pitch-63: SUCCESS (0.000s)
[183.829214] [029/133] (830s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[183.917524] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[183.918899] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[184.029056] [030/133] (830s left) syncobj_wait (invalid-reset-one-illegal-handle)
[184.063464] Starting subtest: invalid-reset-one-illegal-handle
[184.064147] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.000s)
[184.152483] [031/133] (830s left) gem_ctx_persistence (legacy-engines-queued)
[184.189677] Starting subtest: legacy-engines-queued
[184.191432] Starting dynamic subtest: render
[184.229330] Dynamic subtest render: SUCCESS (0.038s)
[184.243321] Starting dynamic subtest: bsd1
[184.268407] Dynamic subtest bsd1: SUCCESS (0.036s)
[184.284345] Starting dynamic subtest: bsd2
[184.329467] Dynamic subtest bsd2: SUCCESS (0.058s)
[184.348005] Starting dynamic subtest: blt
[184.388385] Dynamic subtest blt: SUCCESS (0.056s)
[184.405433] Starting dynamic subtest: vebox
[184.437430] Dynamic subtest vebox: SUCCESS (0.046s)
[184.438478] Subtest legacy-engines-queued: SUCCESS (0.248s)
[184.538913] [032/133] (830s left) syncobj_wait (invalid-single-wait-unsubmitted)
[184.573269] Starting subtest: invalid-single-wait-unsubmitted
[184.574013] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[184.653388] [033/133] (829s left) gem_pxp (verify-pxp-stale-ctx-execution)
[184.756005] Starting subtest: verify-pxp-stale-ctx-execution
[185.819965] Subtest verify-pxp-stale-ctx-execution: SUCCESS (1.065s)
[185.964476] [034/133] (828s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[185.998181] Starting subtest: cpuset-basic-small-copy-odd
[189.586506] Subtest cpuset-basic-small-copy-odd: SUCCESS (3.586s)
[189.663948] [035/133] (825s left) kms_cursor_crc (cursor-onscreen-512x170)
[189.810139] Starting subtest: cursor-onscreen-512x170
[189.811489] Subtest cursor-onscreen-512x170: SKIP (0.001s)
[189.941134] [036/133] (824s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[190.030334] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-cpu
[190.031691] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[190.157445] [037/133] (824s left) kms_chamelium_color (ctm-0-25)
[190.243542] Subtest ctm-0-25: SKIP (0.000s)
[190.289356] [038/133] (824s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[190.332292] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[190.446229] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.107s)
[190.532113] [039/133] (824s left) gem_exec_fence (syncobj-timeline-unused-fence)
[191.000382] Starting subtest: syncobj-timeline-unused-fence
[191.001405] Subtest syncobj-timeline-unused-fence: SUCCESS (0.005s)
[191.177696] [040/133] (823s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[191.264907] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[191.266262] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[191.374792] [041/133] (823s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[191.462318] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[191.463687] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[191.584253] [042/133] (823s left) kms_chamelium_frames (hdmi-cmp-planar-formats)
[191.718290] Subtest hdmi-cmp-planar-formats: SKIP (0.000s)
[191.819526] [043/133] (823s left) kms_plane_scaling (plane-downscale-factor-0-5-with-pixel-format)
[191.906973] Starting subtest: plane-downscale-factor-0-5-with-pixel-format
[191.907764] Starting dynamic subtest: pipe-A
[193.178361] Dynamic subtest pipe-A: SUCCESS (1.270s)
[193.189134] Starting dynamic subtest: pipe-B
[194.428418] Dynamic subtest pipe-B: SUCCESS (1.249s)
[194.441337] Starting dynamic subtest: pipe-C
[194.630223] Dynamic subtest pipe-C: SUCCESS (0.201s)
[194.641255] Starting dynamic subtest: pipe-D
[194.831325] Dynamic subtest pipe-D: SUCCESS (0.200s)
[194.832078] Subtest plane-downscale-factor-0-5-with-pixel-format: SUCCESS (2.924s)
[195.017109] [044/133] (820s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[195.058072] Starting subtest: y-tiled-to-vebox-x-tiled
[195.058811] Starting dynamic subtest: smem
[195.153515] Dynamic subtest smem: SUCCESS (0.094s)
[195.154225] Subtest y-tiled-to-vebox-x-tiled: SUCCESS (0.096s)
[195.339831] [045/133] (819s left) gen9_exec_parse (batch-without-end)
[195.377817] Subtest batch-without-end: SKIP (0.000s)
[195.421851] [046/133] (819s left) syncobj_timeline (etime-single-wait-submitted)
[195.454950] Starting subtest: etime-single-wait-submitted
[195.566979] Subtest etime-single-wait-submitted: SUCCESS (0.104s)
[195.657834] [047/133] (819s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[195.760195] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip
[195.761682] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[195.881667] [048/133] (819s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[195.969744] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[195.971119] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[196.082740] [049/133] (819s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-rc-ccs)
[196.167455] Starting subtest: crc-primary-suspend-y-tiled-gen12-rc-ccs
[196.168186] Starting dynamic subtest: pipe-A-HDMI-A-1
[212.474932] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.380s)
[212.485061] Starting dynamic subtest: pipe-B-HDMI-A-1
[229.452454] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (1.325s)
[229.463448] Starting dynamic subtest: pipe-C-HDMI-A-1
[245.481316] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.347s)
[245.493824] Starting dynamic subtest: pipe-D-HDMI-A-1
[262.474395] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.379s)
[262.475103] Subtest crc-primary-suspend-y-tiled-gen12-rc-ccs: SUCCESS (5.441s)
[271.737250] [050/133] (752s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[271.830215] Starting subtest: plane-use-after-nonblocking-unbind
[271.831076] Starting dynamic subtest: pipe-A-HDMI-A-1
[271.887876] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.055s)
[271.957741] Starting dynamic subtest: pipe-B-HDMI-A-1
[272.117058] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.163s)
[272.158517] Subtest plane-use-after-nonblocking-unbind: SUCCESS (0.319s)
[272.318183] [051/133] (752s left) gem_partial_pwrite_pread (reads-uncached)
[272.359713] Starting subtest: reads-uncached
[274.330138] Subtest reads-uncached: SUCCESS (1.924s)
[274.470711] [052/133] (750s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[274.505374] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[274.506114] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[274.597530] [053/133] (749s left) i915_pm_rc6_residency (rc6-fence)
[274.631275] Starting subtest: rc6-fence
[274.636055] Starting dynamic subtest: gt0
[293.051732] Dynamic subtest gt0: SUCCESS (18.249s)
[293.052751] Subtest rc6-fence: SUCCESS (18.255s)
[293.114381] [054/133] (731s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[293.205985] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[293.207334] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[293.319028] [055/133] (731s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[293.406547] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[293.408002] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[293.527237] [056/133] (731s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[293.622093] Starting subtest: yf-tiled-16bpp-rotate-180
[293.623551] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[293.729656] [057/133] (730s left) gem_ctx_param (root-set)
[293.763276] Starting subtest: root-set
[293.763988] Subtest root-set: SUCCESS (0.000s)
[293.842792] [058/133] (730s left) gem_exec_fence (syncobj-invalid-wait)
[294.166232] Starting subtest: syncobj-invalid-wait
[294.166981] Subtest syncobj-invalid-wait: SUCCESS (0.000s)
[294.483132] [059/133] (730s left) kms_lease (invalid-create-leases)
[294.567244] Starting subtest: invalid-create-leases
[294.570937] Subtest invalid-create-leases: SUCCESS (0.003s)
[294.640585] [060/133] (730s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[294.725249] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[294.725963] Starting dynamic subtest: pipe-A-HDMI-A-1
[294.732546] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[294.740031] Starting dynamic subtest: pipe-B-HDMI-A-1
[294.746531] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[294.753861] Starting dynamic subtest: pipe-C-HDMI-A-1
[294.760392] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[294.767601] Starting dynamic subtest: pipe-D-HDMI-A-1
[294.774377] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.000s)
[294.775728] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SKIP (0.007s)
[294.968864] [061/133] (729s left) kms_sequence (queue-busy)
[295.054201] Starting subtest: queue-busy
[295.054860] Starting dynamic subtest: pipe-A-HDMI-A-1
[296.352673] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.296s)
[296.362566] Starting dynamic subtest: pipe-B-HDMI-A-1
[297.639488] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (1.281s)
[297.648264] Starting dynamic subtest: pipe-C-HDMI-A-1
[298.922541] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.279s)
[298.932074] Starting dynamic subtest: pipe-D-HDMI-A-1
[300.208453] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.282s)
[300.209203] Subtest queue-busy: SUCCESS (5.148s)
[300.334722] [062/133] (724s left) prime_vgem (basic-fence-blt)
[300.418582] Starting subtest: basic-fence-blt
[300.497914] Subtest basic-fence-blt: SUCCESS (0.080s)
[300.632013] [063/133] (724s left) i915_suspend (basic-s3-without-i915)
[300.664129] Starting subtest: basic-s3-without-i915