[48.562543] Initializing watchdogs
[48.562602]   /dev/watchdog0
[48.577515] [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]
[48.583098] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[48.585585] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[48.587389] [001/133] (960s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt)
[48.681460] Starting subtest: fbcpsr-farfromfence-mmap-gtt
[48.682872] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s)
[48.800343] [002/133] (960s left) syncobj_timeline (wait-for-submit-delayed-submit)
[48.833429] Starting subtest: wait-for-submit-delayed-submit
[48.935896] Subtest wait-for-submit-delayed-submit: SUCCESS (0.102s)
[49.017383] [003/133] (960s left) kms_chamelium_hpd (dp-hpd)
[49.157050] Subtest dp-hpd: SKIP (0.000s)
[49.244953] [004/133] (959s left) kms_cursor_edge_walk (256x256-top-bottom)
[49.337621] Starting subtest: 256x256-top-bottom
[49.338381] Starting dynamic subtest: pipe-A-HDMI-A-1
[51.107478] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.769s)
[51.113421] Starting dynamic subtest: pipe-D-HDMI-A-1
[52.772438] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.663s)
[52.773170] Subtest 256x256-top-bottom: SUCCESS (3.435s)
[52.900723] [005/133] (956s left) gem_mmap_offset (clear)
[52.938763] Starting subtest: clear
[52.939504] Starting dynamic subtest: smem0
[75.029296] Dynamic subtest smem0: SUCCESS (22.087s)
[75.029992] Subtest clear: SUCCESS (22.087s)
[75.178499] [006/133] (934s left) kms_joiner (basic-force-big-joiner)
[75.319258] Starting subtest: basic-force-big-joiner
[75.320607] Subtest basic-force-big-joiner: SKIP (0.000s)
[75.394587] [007/133] (933s left) gem_exec_balancer (waits)
[75.437208] Starting subtest: waits
[82.358339] Subtest waits: SUCCESS (6.912s)
[82.553037] [008/133] (926s left) gem_exec_whisper (basic-queues)
[82.594694] Starting subtest: basic-queues
[84.374831] Subtest basic-queues: SUCCESS (1.779s)
[84.565136] [009/133] (924s left) gem_exec_suspend (basic-s3)
[84.605474] Starting subtest: basic-S3
[84.606161] Starting dynamic subtest: smem
[101.981921] Dynamic subtest smem: SUCCESS (2.814s)
[101.982693] Subtest basic-S3: SUCCESS (2.815s)
[111.188742] [010/133] (907s left) kms_psr (psr2-sprite-render)
[111.280068] Subtest psr2-sprite-render: SKIP (0.000s)
[111.322092] [011/133] (907s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[111.411314] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[111.412635] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[111.526295] [012/133] (906s left) kms_content_protection (atomic)
[111.619728] Starting subtest: atomic
[111.633714] Subtest atomic: SKIP (0.013s)
[111.750703] [013/133] (906s left) kms_async_flips (invalid-async-flip)
[111.834260] Starting subtest: invalid-async-flip
[111.947625] Starting dynamic subtest: pipe-A-HDMI-A-1
[112.077501] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.130s)
[112.207855] Starting dynamic subtest: pipe-B-HDMI-A-1
[112.290894] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.092s)
[112.397410] Starting dynamic subtest: pipe-C-HDMI-A-1
[112.495776] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.107s)
[112.614736] Starting dynamic subtest: pipe-D-HDMI-A-1
[112.702190] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.098s)
[112.702930] Subtest invalid-async-flip: SUCCESS (0.868s)
[112.943648] [014/133] (905s left) kms_flip (blocking-absolute-wf_vblank)
[113.040982] Starting subtest: blocking-absolute-wf_vblank
[113.042493] Starting dynamic subtest: A-HDMI-A1
[120.761037] Dynamic subtest A-HDMI-A1: SUCCESS (7.695s)
[120.768181] Starting dynamic subtest: B-HDMI-A1
[128.430884] Dynamic subtest B-HDMI-A1: SUCCESS (7.658s)
[128.438748] Starting dynamic subtest: C-HDMI-A1
[136.089041] Dynamic subtest C-HDMI-A1: SUCCESS (7.656s)
[136.097125] Starting dynamic subtest: D-HDMI-A1
[143.728897] Dynamic subtest D-HDMI-A1: SUCCESS (7.666s)
[143.730728] Subtest blocking-absolute-wf_vblank: SUCCESS (30.682s)
[143.921315] [015/133] (874s left) gem_exec_schedule (reorder-wide)
[144.093958] Starting subtest: reorder-wide
[144.095045] Starting dynamic subtest: rcs0
[144.201309] Dynamic subtest rcs0: SUCCESS (0.108s)
[144.211916] Starting dynamic subtest: bcs0
[144.273466] Dynamic subtest bcs0: SUCCESS (0.072s)
[144.284317] Starting dynamic subtest: vcs0
[144.346430] Dynamic subtest vcs0: SUCCESS (0.072s)
[144.357176] Starting dynamic subtest: vcs1
[144.421942] Dynamic subtest vcs1: SUCCESS (0.075s)
[144.432814] Starting dynamic subtest: vecs0
[144.495150] Dynamic subtest vecs0: SUCCESS (0.072s)
[144.496114] Subtest reorder-wide: SUCCESS (0.403s)
[144.694244] [FACT gem_exec_schedule (reorder-wide)] new: kernel.kmod_is_loaded.vgem: true
[144.698377] [016/133] (873s left) gem_watchdog (default-virtual)
[144.736451] Starting subtest: default-virtual
[145.749799] Subtest default-virtual: SUCCESS (1.012s)
[145.873832] [017/133] (872s left) gem_pwrite (basic-self)
[145.905849] Starting subtest: basic-self
[145.944418] Subtest basic-self: SUCCESS (0.039s)
[146.069662] [018/133] (872s left) kms_psr (fbc-psr2-cursor-mmap-gtt)
[146.155787] Subtest fbc-psr2-cursor-mmap-gtt: SKIP (0.000s)
[146.200641] [019/133] (872s left) kms_cursor_legacy (forked-move)
[146.286515] Starting subtest: forked-move
[146.287251] Starting dynamic subtest: pipe-A
[151.713988] Dynamic subtest pipe-A: SUCCESS (5.421s)
[151.722670] Starting dynamic subtest: pipe-B
[157.163513] Dynamic subtest pipe-B: SUCCESS (5.425s)
[157.172310] Starting dynamic subtest: pipe-C
[162.602034] Dynamic subtest pipe-C: SUCCESS (5.439s)
[162.609943] Starting dynamic subtest: pipe-D
[168.020891] Dynamic subtest pipe-D: SUCCESS (5.418s)
[168.030295] Starting dynamic subtest: all-pipes
[173.455762] Dynamic subtest all-pipes: SUCCESS (5.435s)
[173.456698] Subtest forked-move: SUCCESS (27.141s)
[173.520850] [020/133] (845s left) vgem_basic (debugfs)
[173.559920] Starting subtest: debugfs
[173.560608] Subtest debugfs: SUCCESS (0.001s)
[173.640825] [021/133] (845s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-pwrite)
[173.730394] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-pwrite
[173.731778] Subtest fbc-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[173.852543] [022/133] (845s left) kms_plane_lowres (tiling-yf)
[173.936566] Starting subtest: tiling-yf
[173.937941] Subtest tiling-yf: SKIP (0.000s)
[174.023593] [023/133] (844s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[174.107212] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[174.116286] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.009s)
[174.206460] [024/133] (844s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-upscaling)
[174.290273] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-upscaling
[174.290967] Starting dynamic subtest: pipe-A-valid-mode
[175.117024] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.826s)
[175.117705] Subtest flip-64bpp-xtile-to-32bpp-xtile-upscaling: SUCCESS (0.828s)
[175.289581] [025/133] (843s left) kms_vrr (flip-basic)
[175.379827] Starting subtest: flip-basic
[175.381911] Subtest flip-basic: SKIP (0.000s)
[175.447690] [026/133] (843s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[175.535246] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[175.536580] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[175.646414] [027/133] (843s left) kms_flip (modeset-vs-vblank-race-interruptible)
[175.735481] Starting subtest: modeset-vs-vblank-race-interruptible
[175.737542] Starting dynamic subtest: A-HDMI-A1
[178.617253] Dynamic subtest A-HDMI-A1: SUCCESS (2.880s)
[178.628576] Starting dynamic subtest: B-HDMI-A1
[181.453331] Dynamic subtest B-HDMI-A1: SUCCESS (2.835s)
[181.462445] Starting dynamic subtest: C-HDMI-A1
[184.271953] Dynamic subtest C-HDMI-A1: SUCCESS (2.817s)
[184.283552] Starting dynamic subtest: D-HDMI-A1
[187.140622] Dynamic subtest D-HDMI-A1: SUCCESS (2.864s)
[187.141328] Subtest modeset-vs-vblank-race-interruptible: SUCCESS (11.405s)
[187.325306] [028/133] (831s left) kms_addfb_basic (bad-pitch-63)
[187.368587] Starting subtest: bad-pitch-63
[187.369274] Subtest bad-pitch-63: SUCCESS (0.000s)
[187.520843] [029/133] (831s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[187.613056] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[187.614487] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[187.731046] [030/133] (831s left) syncobj_wait (invalid-reset-one-illegal-handle)
[187.765051] Starting subtest: invalid-reset-one-illegal-handle
[187.765753] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.000s)
[187.853921] [031/133] (831s left) gem_ctx_persistence (legacy-engines-queued)
[187.890922] Starting subtest: legacy-engines-queued
[187.892725] Starting dynamic subtest: render
[187.972217] Dynamic subtest render: SUCCESS (0.080s)
[187.991353] Starting dynamic subtest: bsd1
[188.043326] Dynamic subtest bsd1: SUCCESS (0.068s)
[188.062562] Starting dynamic subtest: bsd2
[188.119222] Dynamic subtest bsd2: SUCCESS (0.073s)
[188.138398] Starting dynamic subtest: blt
[188.194260] Dynamic subtest blt: SUCCESS (0.072s)
[188.213134] Starting dynamic subtest: vebox
[188.267219] Dynamic subtest vebox: SUCCESS (0.070s)
[188.270380] Subtest legacy-engines-queued: SUCCESS (0.377s)
[188.364060] [032/133] (830s left) syncobj_wait (invalid-single-wait-unsubmitted)
[188.397049] Starting subtest: invalid-single-wait-unsubmitted
[188.397755] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[188.473471] [033/133] (830s left) gem_pxp (verify-pxp-stale-ctx-execution)
[188.574149] Starting subtest: verify-pxp-stale-ctx-execution
[189.640142] Subtest verify-pxp-stale-ctx-execution: SUCCESS (1.065s)
[189.768926] [034/133] (829s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[189.802355] Starting subtest: cpuset-basic-small-copy-odd
[193.386031] Subtest cpuset-basic-small-copy-odd: SUCCESS (3.583s)
[193.460012] [035/133] (826s left) kms_cursor_crc (cursor-onscreen-512x170)
[193.603170] Starting subtest: cursor-onscreen-512x170
[193.604650] Subtest cursor-onscreen-512x170: SKIP (0.001s)
[193.713645] [036/133] (825s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[193.802789] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-cpu
[193.804247] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[193.940736] [037/133] (825s left) kms_chamelium_color (ctm-0-25)
[194.026662] Subtest ctm-0-25: SKIP (0.000s)
[194.071672] [038/133] (825s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[194.112149] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[194.215616] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.103s)
[194.297556] [039/133] (825s left) gem_exec_fence (syncobj-timeline-unused-fence)
[194.764406] Starting subtest: syncobj-timeline-unused-fence
[194.765417] Subtest syncobj-timeline-unused-fence: SUCCESS (0.005s)
[194.954808] [040/133] (824s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[195.042814] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[195.044256] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[195.165080] [041/133] (824s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[195.253804] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[195.255219] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[195.365771] [042/133] (824s left) kms_chamelium_frames (hdmi-cmp-planar-formats)
[195.507547] Subtest hdmi-cmp-planar-formats: SKIP (0.000s)
[195.602535] [043/133] (824s left) kms_plane_scaling (plane-downscale-factor-0-5-with-pixel-format)
[195.689212] Starting subtest: plane-downscale-factor-0-5-with-pixel-format
[195.689958] Starting dynamic subtest: pipe-A
[196.928407] Dynamic subtest pipe-A: SUCCESS (1.239s)
[196.938704] Starting dynamic subtest: pipe-B
[198.155160] Dynamic subtest pipe-B: SUCCESS (1.226s)
[198.164776] Starting dynamic subtest: pipe-C
[198.357377] Dynamic subtest pipe-C: SUCCESS (0.201s)
[198.367786] Starting dynamic subtest: pipe-D
[198.558976] Dynamic subtest pipe-D: SUCCESS (0.201s)
[198.559733] Subtest plane-downscale-factor-0-5-with-pixel-format: SUCCESS (2.870s)
[198.748464] [044/133] (821s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[198.795717] Starting subtest: y-tiled-to-vebox-x-tiled
[198.796745] Starting dynamic subtest: smem
[198.884155] Dynamic subtest smem: SUCCESS (0.095s)
[198.884948] Subtest y-tiled-to-vebox-x-tiled: SUCCESS (0.096s)
[199.074388] [045/133] (820s left) gen9_exec_parse (batch-without-end)
[199.110192] Subtest batch-without-end: SKIP (0.000s)
[199.151480] [046/133] (820s left) syncobj_timeline (etime-single-wait-submitted)
[199.185075] Starting subtest: etime-single-wait-submitted
[199.288707] Subtest etime-single-wait-submitted: SUCCESS (0.102s)
[199.379619] [047/133] (820s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[199.477243] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip
[199.478690] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[199.588130] [048/133] (820s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[199.677209] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[199.678713] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[199.784398] [049/133] (820s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-rc-ccs)
[199.872397] Starting subtest: crc-primary-suspend-y-tiled-gen12-rc-ccs
[199.874675] Starting dynamic subtest: pipe-A-HDMI-A-1
[216.554169] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.389s)
[216.563107] Starting dynamic subtest: pipe-B-HDMI-A-1
[233.556709] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (1.366s)
[233.565910] Starting dynamic subtest: pipe-C-HDMI-A-1
[250.540451] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.334s)
[250.549504] Starting dynamic subtest: pipe-D-HDMI-A-1
[266.546736] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.332s)
[266.547410] Subtest crc-primary-suspend-y-tiled-gen12-rc-ccs: SUCCESS (5.429s)
[274.746603] [050/133] (753s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[274.836773] Starting subtest: plane-use-after-nonblocking-unbind
[274.837515] Starting dynamic subtest: pipe-A-HDMI-A-1
[274.890717] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.054s)
[274.961295] Starting dynamic subtest: pipe-B-HDMI-A-1
[275.130223] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.178s)
[275.187536] Subtest plane-use-after-nonblocking-unbind: SUCCESS (0.352s)
[275.350048] [051/133] (752s left) gem_partial_pwrite_pread (reads-uncached)
[275.388625] Starting subtest: reads-uncached
[277.293129] Subtest reads-uncached: SUCCESS (1.904s)
[277.434089] [052/133] (750s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[277.467357] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[277.468117] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[277.558865] [053/133] (750s left) i915_pm_rc6_residency (rc6-fence)
[277.590749] Starting subtest: rc6-fence
[277.595824] Starting dynamic subtest: gt0
[295.887830] Dynamic subtest gt0: SUCCESS (18.266s)
[295.888828] Subtest rc6-fence: SUCCESS (18.272s)
[295.952618] [054/133] (732s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[296.042590] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[296.043982] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[296.146562] [055/133] (732s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[296.234063] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[296.235470] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[296.365890] [056/133] (732s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[296.458867] Starting subtest: yf-tiled-16bpp-rotate-180
[296.460239] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[296.581465] [057/133] (731s left) gem_ctx_param (root-set)
[296.613640] Starting subtest: root-set
[296.614350] Subtest root-set: SUCCESS (0.000s)
[296.705567] [058/133] (731s left) gem_exec_fence (syncobj-invalid-wait)
[297.021988] Starting subtest: syncobj-invalid-wait
[297.022813] Subtest syncobj-invalid-wait: SUCCESS (0.000s)
[297.337596] [059/133] (731s left) kms_lease (invalid-create-leases)
[297.423030] Starting subtest: invalid-create-leases
[297.425888] Subtest invalid-create-leases: SUCCESS (0.003s)
[297.504077] [060/133] (731s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[297.588447] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[297.590658] Starting dynamic subtest: pipe-A-HDMI-A-1
[297.597648] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[297.607682] Starting dynamic subtest: pipe-B-HDMI-A-1
[297.614595] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[297.624302] Starting dynamic subtest: pipe-C-HDMI-A-1
[297.631164] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[297.640377] Starting dynamic subtest: pipe-D-HDMI-A-1
[297.647650] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.000s)
[297.649089] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SKIP (0.007s)
[297.825236] [061/133] (730s left) kms_sequence (queue-busy)
[297.911927] Starting subtest: queue-busy
[297.913741] Starting dynamic subtest: pipe-A-HDMI-A-1
[299.204581] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.291s)
[299.215016] Starting dynamic subtest: pipe-B-HDMI-A-1
[300.474231] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (1.268s)
[300.483815] Starting dynamic subtest: pipe-C-HDMI-A-1
[301.743118] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.266s)
[301.753701] Starting dynamic subtest: pipe-D-HDMI-A-1
[303.002285] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.257s)
[303.003099] Subtest queue-busy: SUCCESS (5.089s)
[303.131970] [062/133] (725s left) prime_vgem (basic-fence-blt)
[303.196461] Starting subtest: basic-fence-blt
[303.276140] Subtest basic-fence-blt: SUCCESS (0.079s)
[303.347167] [063/133] (725s left) i915_suspend (basic-s3-without-i915)
[303.377742] Starting subtest: basic-s3-without-i915