[50.556439] Initializing watchdogs
[50.556500]   /dev/watchdog0
[50.570777] [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]
[50.576235] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[50.578637] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[50.580814] [001/133] (960s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt)
[50.675718] Starting subtest: fbcpsr-farfromfence-mmap-gtt
[50.677066] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s)
[50.801210] [002/133] (960s left) syncobj_timeline (wait-for-submit-delayed-submit)
[50.832801] Starting subtest: wait-for-submit-delayed-submit
[50.935260] Subtest wait-for-submit-delayed-submit: SUCCESS (0.104s)
[51.022963] [003/133] (960s left) kms_chamelium_hpd (dp-hpd)
[51.167833] Subtest dp-hpd: SKIP (0.000s)
[51.251231] [004/133] (959s left) kms_cursor_edge_walk (256x256-top-bottom)
[51.335774] Starting subtest: 256x256-top-bottom
[51.337362] Starting dynamic subtest: pipe-A-HDMI-A-1
[53.062700] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.761s)
[53.070290] Starting dynamic subtest: pipe-D-HDMI-A-1
[54.701906] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.664s)
[54.702683] Subtest 256x256-top-bottom: SUCCESS (3.428s)
[54.825525] [005/133] (956s left) gem_mmap_offset (clear)
[54.862200] Starting subtest: clear
[54.863381] Starting dynamic subtest: smem0
[77.355594] Dynamic subtest smem0: SUCCESS (22.585s)
[77.356272] Subtest clear: SUCCESS (22.585s)
[77.480780] [006/133] (933s left) kms_joiner (basic-force-big-joiner)
[77.627285] Starting subtest: basic-force-big-joiner
[77.628610] Subtest basic-force-big-joiner: SKIP (0.000s)
[77.694528] [007/133] (933s left) gem_exec_balancer (waits)
[77.737517] Starting subtest: waits
[84.612377] Subtest waits: SUCCESS (6.867s)
[84.804293] [008/133] (926s left) gem_exec_whisper (basic-queues)
[84.847368] Starting subtest: basic-queues
[86.477795] Subtest basic-queues: SUCCESS (1.629s)
[86.659366] [009/133] (924s left) gem_exec_suspend (basic-s3)
[86.700391] Starting subtest: basic-S3
[86.701079] Starting dynamic subtest: smem
[104.861629] Dynamic subtest smem: SUCCESS (2.691s)
[104.862342] Subtest basic-S3: SUCCESS (2.691s)
[114.061327] [010/133] (906s left) kms_psr (psr2-sprite-render)
[114.149248] Subtest psr2-sprite-render: SKIP (0.000s)
[114.188886] [011/133] (906s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[114.274014] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[114.275346] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[114.380458] [012/133] (906s left) kms_content_protection (atomic)
[114.471354] Starting subtest: atomic
[114.484109] Subtest atomic: SKIP (0.012s)
[114.623480] [013/133] (905s left) kms_async_flips (invalid-async-flip)
[114.705429] Starting subtest: invalid-async-flip
[114.825752] Starting dynamic subtest: pipe-A-HDMI-A-1
[114.952785] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.131s)
[115.081082] Starting dynamic subtest: pipe-B-HDMI-A-1
[115.174386] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.103s)
[115.287390] Starting dynamic subtest: pipe-C-HDMI-A-1
[115.399629] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.122s)
[115.505707] Starting dynamic subtest: pipe-D-HDMI-A-1
[115.590795] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.096s)
[115.591512] Subtest invalid-async-flip: SUCCESS (0.912s)
[115.811326] [014/133] (904s left) kms_flip (blocking-absolute-wf_vblank)
[115.901325] Starting subtest: blocking-absolute-wf_vblank
[115.902808] Starting dynamic subtest: A-HDMI-A1
[123.646894] Dynamic subtest A-HDMI-A1: SUCCESS (7.871s)
[123.656314] Starting dynamic subtest: B-HDMI-A1
[131.342547] Dynamic subtest B-HDMI-A1: SUCCESS (7.739s)
[131.356546] Starting dynamic subtest: C-HDMI-A1
[139.062374] Dynamic subtest C-HDMI-A1: SUCCESS (7.738s)
[139.072740] Starting dynamic subtest: D-HDMI-A1
[146.722764] Dynamic subtest D-HDMI-A1: SUCCESS (7.658s)
[146.724631] Subtest blocking-absolute-wf_vblank: SUCCESS (31.015s)
[146.877648] [015/133] (873s left) gem_exec_schedule (reorder-wide)
[147.051327] Starting subtest: reorder-wide
[147.052392] Starting dynamic subtest: rcs0
[147.157293] Dynamic subtest rcs0: SUCCESS (0.106s)
[147.166899] Starting dynamic subtest: bcs0
[147.231714] Dynamic subtest bcs0: SUCCESS (0.074s)
[147.242537] Starting dynamic subtest: vcs0
[147.305604] Dynamic subtest vcs0: SUCCESS (0.073s)
[147.315359] Starting dynamic subtest: vcs1
[147.378526] Dynamic subtest vcs1: SUCCESS (0.072s)
[147.388710] Starting dynamic subtest: vecs0
[147.452630] Dynamic subtest vecs0: SUCCESS (0.073s)
[147.453613] Subtest reorder-wide: SUCCESS (0.402s)
[147.630004] [FACT gem_exec_schedule (reorder-wide)] new: kernel.kmod_is_loaded.vgem: true
[147.632620] [016/133] (873s left) gem_watchdog (default-virtual)
[147.673221] Starting subtest: default-virtual
[148.944625] Subtest default-virtual: SUCCESS (1.262s)
[149.063080] [017/133] (871s left) gem_pwrite (basic-self)
[149.096796] Starting subtest: basic-self
[149.133584] Subtest basic-self: SUCCESS (0.038s)
[149.240758] [018/133] (871s left) kms_psr (fbc-psr2-cursor-mmap-gtt)
[149.329543] Subtest fbc-psr2-cursor-mmap-gtt: SKIP (0.000s)
[149.377165] [019/133] (871s left) kms_cursor_legacy (forked-move)
[149.466901] Starting subtest: forked-move
[149.467631] Starting dynamic subtest: pipe-A
[154.913349] Dynamic subtest pipe-A: SUCCESS (5.411s)
[154.921771] Starting dynamic subtest: pipe-B
[160.344089] Dynamic subtest pipe-B: SUCCESS (5.430s)
[160.353916] Starting dynamic subtest: pipe-C
[165.778076] Dynamic subtest pipe-C: SUCCESS (5.434s)
[165.786861] Starting dynamic subtest: pipe-D
[171.214762] Dynamic subtest pipe-D: SUCCESS (5.437s)
[171.224200] Starting dynamic subtest: all-pipes
[176.667530] Dynamic subtest all-pipes: SUCCESS (5.452s)
[176.668417] Subtest forked-move: SUCCESS (27.168s)
[176.730683] [020/133] (844s left) vgem_basic (debugfs)
[176.771228] Starting subtest: debugfs
[176.771935] Subtest debugfs: SUCCESS (0.000s)
[176.849127] [021/133] (844s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-pwrite)
[176.937859] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-pwrite
[176.939287] Subtest fbc-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[177.040680] [022/133] (843s left) kms_plane_lowres (tiling-yf)
[177.128709] Starting subtest: tiling-yf
[177.130055] Subtest tiling-yf: SKIP (0.000s)
[177.203668] [023/133] (843s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[177.287548] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[177.296395] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.009s)
[177.388965] [024/133] (843s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-upscaling)
[177.473028] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-upscaling
[177.474701] Starting dynamic subtest: pipe-A-valid-mode
[178.296893] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.823s)
[178.297550] Subtest flip-64bpp-xtile-to-32bpp-xtile-upscaling: SUCCESS (0.824s)
[178.443797] [025/133] (842s left) kms_vrr (flip-basic)
[178.529707] Starting subtest: flip-basic
[178.531808] Subtest flip-basic: SKIP (0.000s)
[178.618998] [026/133] (842s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[178.707722] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[178.709148] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[178.828943] [027/133] (842s left) kms_flip (modeset-vs-vblank-race-interruptible)
[178.918025] Starting subtest: modeset-vs-vblank-race-interruptible
[178.921726] Starting dynamic subtest: A-HDMI-A1
[181.782826] Dynamic subtest A-HDMI-A1: SUCCESS (2.862s)
[181.791047] Starting dynamic subtest: B-HDMI-A1
[184.639698] Dynamic subtest B-HDMI-A1: SUCCESS (2.857s)
[184.650959] Starting dynamic subtest: C-HDMI-A1
[187.442542] Dynamic subtest C-HDMI-A1: SUCCESS (2.803s)
[187.451172] Starting dynamic subtest: D-HDMI-A1
[190.268480] Dynamic subtest D-HDMI-A1: SUCCESS (2.826s)
[190.270962] Subtest modeset-vs-vblank-race-interruptible: SUCCESS (11.356s)
[190.444987] [028/133] (830s left) kms_addfb_basic (bad-pitch-63)
[190.487792] Starting subtest: bad-pitch-63
[190.488474] Subtest bad-pitch-63: SUCCESS (0.000s)
[190.657710] [029/133] (830s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[190.747007] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[190.748392] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[190.874233] [030/133] (830s left) syncobj_wait (invalid-reset-one-illegal-handle)
[190.907741] Starting subtest: invalid-reset-one-illegal-handle
[190.908429] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.000s)
[190.997316] [031/133] (830s left) gem_ctx_persistence (legacy-engines-queued)
[191.035079] Starting subtest: legacy-engines-queued
[191.035822] Starting dynamic subtest: render
[191.089568] Dynamic subtest render: SUCCESS (0.054s)
[191.106196] Starting dynamic subtest: bsd1
[191.147654] Dynamic subtest bsd1: SUCCESS (0.056s)
[191.164807] Starting dynamic subtest: bsd2
[191.203684] Dynamic subtest bsd2: SUCCESS (0.054s)
[191.220544] Starting dynamic subtest: blt
[191.280637] Dynamic subtest blt: SUCCESS (0.074s)
[191.293985] Starting dynamic subtest: vebox
[191.334740] Dynamic subtest vebox: SUCCESS (0.051s)
[191.335777] Subtest legacy-engines-queued: SUCCESS (0.302s)
[191.432175] [032/133] (829s left) syncobj_wait (invalid-single-wait-unsubmitted)
[191.466556] Starting subtest: invalid-single-wait-unsubmitted
[191.467296] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[191.555581] [033/133] (829s left) gem_pxp (verify-pxp-stale-ctx-execution)
[191.656607] Starting subtest: verify-pxp-stale-ctx-execution
[192.721181] Subtest verify-pxp-stale-ctx-execution: SUCCESS (1.064s)
[192.844056] [034/133] (828s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[192.877519] Starting subtest: cpuset-basic-small-copy-odd
[196.471969] Subtest cpuset-basic-small-copy-odd: SUCCESS (3.596s)
[196.544395] [035/133] (824s left) kms_cursor_crc (cursor-onscreen-512x170)
[196.687218] Starting subtest: cursor-onscreen-512x170
[196.688532] Subtest cursor-onscreen-512x170: SKIP (0.001s)
[196.806423] [036/133] (824s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[196.894766] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-cpu
[196.896109] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[197.006102] [037/133] (824s left) kms_chamelium_color (ctm-0-25)
[197.091823] Subtest ctm-0-25: SKIP (0.000s)
[197.131876] [038/133] (824s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[197.172397] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[197.276640] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.104s)
[197.376096] [039/133] (824s left) gem_exec_fence (syncobj-timeline-unused-fence)
[197.829549] Starting subtest: syncobj-timeline-unused-fence
[197.830385] Subtest syncobj-timeline-unused-fence: SUCCESS (0.006s)
[198.029381] [040/133] (823s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[198.115928] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[198.117241] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[198.230101] [041/133] (823s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[198.323721] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[198.325043] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[198.432334] [042/133] (823s left) kms_chamelium_frames (hdmi-cmp-planar-formats)
[198.566549] Subtest hdmi-cmp-planar-formats: SKIP (0.000s)
[198.662320] [043/133] (823s left) kms_plane_scaling (plane-downscale-factor-0-5-with-pixel-format)
[198.748544] Starting subtest: plane-downscale-factor-0-5-with-pixel-format
[198.749283] Starting dynamic subtest: pipe-A
[199.998001] Dynamic subtest pipe-A: SUCCESS (1.250s)
[200.009533] Starting dynamic subtest: pipe-B
[201.232146] Dynamic subtest pipe-B: SUCCESS (1.233s)
[201.241891] Starting dynamic subtest: pipe-C
[201.435098] Dynamic subtest pipe-C: SUCCESS (0.201s)
[201.443075] Starting dynamic subtest: pipe-D
[201.636031] Dynamic subtest pipe-D: SUCCESS (0.200s)
[201.636750] Subtest plane-downscale-factor-0-5-with-pixel-format: SUCCESS (2.889s)
[201.806514] [044/133] (820s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[201.848507] Starting subtest: y-tiled-to-vebox-x-tiled
[201.849233] Starting dynamic subtest: smem
[201.944089] Dynamic subtest smem: SUCCESS (0.095s)
[201.944926] Subtest y-tiled-to-vebox-x-tiled: SUCCESS (0.096s)
[202.136316] [045/133] (819s left) gen9_exec_parse (batch-without-end)
[202.176087] Subtest batch-without-end: SKIP (0.000s)
[202.217968] [046/133] (819s left) syncobj_timeline (etime-single-wait-submitted)
[202.250814] Starting subtest: etime-single-wait-submitted
[202.356856] Subtest etime-single-wait-submitted: SUCCESS (0.105s)
[202.452414] [047/133] (819s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[202.550134] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip
[202.551485] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[202.668886] [048/133] (819s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[202.756890] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[202.758250] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[202.877139] [049/133] (819s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-rc-ccs)
[202.963088] Starting subtest: crc-primary-suspend-y-tiled-gen12-rc-ccs
[202.964864] Starting dynamic subtest: pipe-A-HDMI-A-1
[219.470328] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.372s)
[219.478993] Starting dynamic subtest: pipe-B-HDMI-A-1
[236.433792] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (1.333s)
[236.446442] Starting dynamic subtest: pipe-C-HDMI-A-1
[252.452213] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.314s)
[252.464426] Starting dynamic subtest: pipe-D-HDMI-A-1
[269.465499] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.345s)
[269.466196] Subtest crc-primary-suspend-y-tiled-gen12-rc-ccs: SUCCESS (5.372s)
[277.748016] [050/133] (752s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[277.834400] Starting subtest: plane-use-after-nonblocking-unbind
[277.836472] Starting dynamic subtest: pipe-A-HDMI-A-1
[277.888792] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.055s)
[277.952862] Starting dynamic subtest: pipe-B-HDMI-A-1
[278.096082] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.157s)
[278.134649] Subtest plane-use-after-nonblocking-unbind: SUCCESS (0.310s)
[278.294070] [051/133] (751s left) gem_partial_pwrite_pread (reads-uncached)
[278.331112] Starting subtest: reads-uncached
[280.149057] Subtest reads-uncached: SUCCESS (1.869s)
[280.263744] [052/133] (749s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[280.296997] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[280.297681] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[280.373956] [053/133] (749s left) i915_pm_rc6_residency (rc6-fence)
[280.407060] Starting subtest: rc6-fence
[280.411829] Starting dynamic subtest: gt0
[298.704841] Dynamic subtest gt0: SUCCESS (18.449s)
[298.705800] Subtest rc6-fence: SUCCESS (18.457s)
[298.766073] [054/133] (731s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[298.855714] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[298.857120] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[298.965377] [055/133] (731s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[299.053815] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[299.055125] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[299.173202] [056/133] (731s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[299.265069] Starting subtest: yf-tiled-16bpp-rotate-180
[299.266452] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[299.363270] [057/133] (731s left) gem_ctx_param (root-set)
[299.396927] Starting subtest: root-set
[299.397606] Subtest root-set: SUCCESS (0.000s)
[299.479759] [058/133] (730s left) gem_exec_fence (syncobj-invalid-wait)
[299.800109] Starting subtest: syncobj-invalid-wait
[299.800857] Subtest syncobj-invalid-wait: SUCCESS (0.000s)
[300.110882] [059/133] (730s left) kms_lease (invalid-create-leases)
[300.197120] Starting subtest: invalid-create-leases
[300.200845] Subtest invalid-create-leases: SUCCESS (0.004s)
[300.281478] [060/133] (730s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[300.367412] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[300.368104] Starting dynamic subtest: pipe-A-HDMI-A-1
[300.374845] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[300.382628] Starting dynamic subtest: pipe-B-HDMI-A-1
[300.389377] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[300.396995] Starting dynamic subtest: pipe-C-HDMI-A-1
[300.403459] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[300.410892] Starting dynamic subtest: pipe-D-HDMI-A-1
[300.417458] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.000s)
[300.418781] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SKIP (0.007s)
[300.623454] [061/133] (729s left) kms_sequence (queue-busy)
[300.709043] Starting subtest: queue-busy
[300.710840] Starting dynamic subtest: pipe-A-HDMI-A-1
[301.997299] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.288s)
[302.007514] Starting dynamic subtest: pipe-B-HDMI-A-1
[303.256503] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (1.260s)
[303.266759] Starting dynamic subtest: pipe-C-HDMI-A-1
[304.534390] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.278s)
[304.545458] Starting dynamic subtest: pipe-D-HDMI-A-1
[305.792091] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.258s)
[305.792810] Subtest queue-busy: SUCCESS (5.092s)
[305.913914] [062/133] (724s left) prime_vgem (basic-fence-blt)
[305.957773] Starting subtest: basic-fence-blt
[306.037463] Subtest basic-fence-blt: SUCCESS (0.079s)
[306.123451] [063/133] (724s left) i915_suspend (basic-s3-without-i915)
[306.155110] Starting subtest: basic-s3-without-i915