[50.034334] Initializing watchdogs
[50.034396]   /dev/watchdog0
[50.049873] [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.055897] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[50.058830] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[50.060642] [001/133] (960s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt)
[50.159281] Starting subtest: fbcpsr-farfromfence-mmap-gtt
[50.160768] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s)
[50.267700] [002/133] (960s left) syncobj_timeline (wait-for-submit-delayed-submit)
[50.303048] Starting subtest: wait-for-submit-delayed-submit
[50.406916] Subtest wait-for-submit-delayed-submit: SUCCESS (0.100s)
[50.497106] [003/133] (960s left) kms_chamelium_hpd (dp-hpd)
[50.642024] Subtest dp-hpd: SKIP (0.000s)
[50.732725] [004/133] (959s left) kms_cursor_edge_walk (256x256-top-bottom)
[50.822737] Starting subtest: 256x256-top-bottom
[50.823421] Starting dynamic subtest: pipe-A-HDMI-A-1
[52.649309] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.772s)
[52.656939] Starting dynamic subtest: pipe-D-HDMI-A-1
[54.342275] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.653s)
[54.342996] Subtest 256x256-top-bottom: SUCCESS (3.428s)
[54.459987] [005/133] (956s left) gem_mmap_offset (clear)
[54.497827] Starting subtest: clear
[54.498700] Starting dynamic subtest: smem0
[78.577691] Dynamic subtest smem0: SUCCESS (23.923s)
[78.578358] Subtest clear: SUCCESS (23.923s)
[78.689909] [006/133] (932s left) kms_joiner (basic-force-big-joiner)
[78.832359] Starting subtest: basic-force-big-joiner
[78.833673] Subtest basic-force-big-joiner: SKIP (0.000s)
[78.904826] [007/133] (931s left) gem_exec_balancer (waits)
[78.949548] Starting subtest: waits
[85.900699] Subtest waits: SUCCESS (6.910s)
[86.097927] [008/133] (924s left) gem_exec_whisper (basic-queues)
[86.140411] Starting subtest: basic-queues
[87.895156] Subtest basic-queues: SUCCESS (1.753s)
[88.094629] [009/133] (922s left) gem_exec_suspend (basic-s3)
[88.136117] Starting subtest: basic-S3
[88.136827] Starting dynamic subtest: smem
[105.770910] Dynamic subtest smem: SUCCESS (2.683s)
[105.771654] Subtest basic-S3: SUCCESS (2.683s)
[113.982407] [010/133] (904s left) kms_psr (psr2-sprite-render)
[114.078706] Subtest psr2-sprite-render: SKIP (0.000s)
[114.119353] [011/133] (904s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[114.211503] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[114.213361] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[114.313143] [012/133] (904s left) kms_content_protection (atomic)
[114.408998] Starting subtest: atomic
[114.423020] Subtest atomic: SKIP (0.011s)
[114.532484] [013/133] (904s left) kms_async_flips (invalid-async-flip)
[114.622446] Starting subtest: invalid-async-flip
[114.742832] Starting dynamic subtest: pipe-A-HDMI-A-1
[114.892446] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.144s)
[115.015818] Starting dynamic subtest: pipe-B-HDMI-A-1
[115.103023] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.091s)
[115.223902] Starting dynamic subtest: pipe-C-HDMI-A-1
[115.335378] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.116s)
[115.456095] Starting dynamic subtest: pipe-D-HDMI-A-1
[115.542477] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.091s)
[115.543231] Subtest invalid-async-flip: SUCCESS (0.888s)
[115.784862] [014/133] (903s left) kms_flip (blocking-absolute-wf_vblank)
[115.886368] Starting subtest: blocking-absolute-wf_vblank
[115.888136] Starting dynamic subtest: A-HDMI-A1
[123.671605] Dynamic subtest A-HDMI-A1: SUCCESS (7.607s)
[123.678980] Starting dynamic subtest: B-HDMI-A1
[131.360942] Dynamic subtest B-HDMI-A1: SUCCESS (7.646s)
[131.370964] Starting dynamic subtest: C-HDMI-A1
[139.040872] Dynamic subtest C-HDMI-A1: SUCCESS (7.656s)
[139.051078] Starting dynamic subtest: D-HDMI-A1
[146.707584] Dynamic subtest D-HDMI-A1: SUCCESS (7.656s)
[146.709418] Subtest blocking-absolute-wf_vblank: SUCCESS (30.573s)
[146.908078] [015/133] (872s left) gem_exec_schedule (reorder-wide)
[147.080735] Starting subtest: reorder-wide
[147.081899] Starting dynamic subtest: rcs0
[147.185999] Dynamic subtest rcs0: SUCCESS (0.105s)
[147.198465] Starting dynamic subtest: bcs0
[147.261624] Dynamic subtest bcs0: SUCCESS (0.075s)
[147.274126] Starting dynamic subtest: vcs0
[147.336493] Dynamic subtest vcs0: SUCCESS (0.074s)
[147.347031] Starting dynamic subtest: vcs1
[147.407424] Dynamic subtest vcs1: SUCCESS (0.070s)
[147.417826] Starting dynamic subtest: vecs0
[147.478407] Dynamic subtest vecs0: SUCCESS (0.070s)
[147.479328] Subtest reorder-wide: SUCCESS (0.398s)
[147.672706] [FACT gem_exec_schedule (reorder-wide)] new: kernel.kmod_is_loaded.vgem: true
[147.674633] [016/133] (871s left) gem_watchdog (default-virtual)
[147.712094] Starting subtest: default-virtual
[148.982957] Subtest default-virtual: SUCCESS (1.261s)
[149.088494] [017/133] (870s left) gem_pwrite (basic-self)
[149.119384] Starting subtest: basic-self
[149.157821] Subtest basic-self: SUCCESS (0.038s)
[149.257677] [018/133] (869s left) kms_psr (fbc-psr2-cursor-mmap-gtt)
[149.348042] Subtest fbc-psr2-cursor-mmap-gtt: SKIP (0.000s)
[149.392897] [019/133] (869s left) kms_cursor_legacy (forked-move)
[149.478456] Starting subtest: forked-move
[149.479162] Starting dynamic subtest: pipe-A
[154.927746] Dynamic subtest pipe-A: SUCCESS (5.411s)
[154.935743] Starting dynamic subtest: pipe-B
[160.360339] Dynamic subtest pipe-B: SUCCESS (5.429s)
[160.368181] Starting dynamic subtest: pipe-C
[165.795437] Dynamic subtest pipe-C: SUCCESS (5.434s)
[165.803340] Starting dynamic subtest: pipe-D
[171.220193] Dynamic subtest pipe-D: SUCCESS (5.429s)
[171.228577] Starting dynamic subtest: all-pipes
[176.651779] Dynamic subtest all-pipes: SUCCESS (5.433s)
[176.652733] Subtest forked-move: SUCCESS (27.138s)
[176.691199] [020/133] (842s left) vgem_basic (debugfs)
[176.725746] Starting subtest: debugfs
[176.726425] Subtest debugfs: SUCCESS (0.000s)
[176.803958] [021/133] (842s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-pwrite)
[176.892274] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-pwrite
[176.893613] Subtest fbc-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[177.005629] [022/133] (842s left) kms_plane_lowres (tiling-yf)
[177.089696] Starting subtest: tiling-yf
[177.091066] Subtest tiling-yf: SKIP (0.000s)
[177.178882] [023/133] (842s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[177.262156] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[177.270804] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.009s)
[177.362663] [024/133] (842s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-upscaling)
[177.446201] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-upscaling
[177.446870] Starting dynamic subtest: pipe-A-valid-mode
[178.220992] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.774s)
[178.221662] Subtest flip-64bpp-xtile-to-32bpp-xtile-upscaling: SUCCESS (0.775s)
[178.365576] [025/133] (841s left) kms_vrr (flip-basic)
[178.450542] Starting subtest: flip-basic
[178.452626] Subtest flip-basic: SKIP (0.000s)
[178.538384] [026/133] (841s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[178.625304] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[178.626626] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[178.741291] [027/133] (840s left) kms_flip (modeset-vs-vblank-race-interruptible)
[178.829074] Starting subtest: modeset-vs-vblank-race-interruptible
[178.831678] Starting dynamic subtest: A-HDMI-A1
[181.653977] Dynamic subtest A-HDMI-A1: SUCCESS (2.823s)
[181.662389] Starting dynamic subtest: B-HDMI-A1
[184.468081] Dynamic subtest B-HDMI-A1: SUCCESS (2.814s)
[184.478351] Starting dynamic subtest: C-HDMI-A1
[187.328600] Dynamic subtest C-HDMI-A1: SUCCESS (2.860s)
[187.337522] Starting dynamic subtest: D-HDMI-A1
[190.136620] Dynamic subtest D-HDMI-A1: SUCCESS (2.805s)
[190.137396] Subtest modeset-vs-vblank-race-interruptible: SUCCESS (11.309s)
[190.313036] [028/133] (829s left) kms_addfb_basic (bad-pitch-63)
[190.355806] Starting subtest: bad-pitch-63
[190.356546] Subtest bad-pitch-63: SUCCESS (0.000s)
[190.532572] [029/133] (829s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[190.621432] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[190.622796] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[190.742028] [030/133] (828s left) syncobj_wait (invalid-reset-one-illegal-handle)
[190.776687] Starting subtest: invalid-reset-one-illegal-handle
[190.777356] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.000s)
[190.867523] [031/133] (828s left) gem_ctx_persistence (legacy-engines-queued)
[190.903856] Starting subtest: legacy-engines-queued
[190.904577] Starting dynamic subtest: render
[190.964388] Dynamic subtest render: SUCCESS (0.059s)
[190.980547] Starting dynamic subtest: bsd1
[191.015599] Dynamic subtest bsd1: SUCCESS (0.049s)
[191.032022] Starting dynamic subtest: bsd2
[191.067491] Dynamic subtest bsd2: SUCCESS (0.049s)
[191.084077] Starting dynamic subtest: blt
[191.115413] Dynamic subtest blt: SUCCESS (0.045s)
[191.131737] Starting dynamic subtest: vebox
[191.163443] Dynamic subtest vebox: SUCCESS (0.045s)
[191.164435] Subtest legacy-engines-queued: SUCCESS (0.260s)
[191.250539] [032/133] (828s left) syncobj_wait (invalid-single-wait-unsubmitted)
[191.284337] Starting subtest: invalid-single-wait-unsubmitted
[191.285075] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[191.374091] [033/133] (828s left) gem_pxp (verify-pxp-stale-ctx-execution)
[191.475825] Starting subtest: verify-pxp-stale-ctx-execution
[192.538142] Subtest verify-pxp-stale-ctx-execution: SUCCESS (1.063s)
[192.662805] [034/133] (827s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[192.695303] Starting subtest: cpuset-basic-small-copy-odd
[196.253859] Subtest cpuset-basic-small-copy-odd: SUCCESS (3.558s)
[196.330058] [035/133] (823s left) kms_cursor_crc (cursor-onscreen-512x170)
[196.477336] Starting subtest: cursor-onscreen-512x170
[196.480156] Subtest cursor-onscreen-512x170: SKIP (0.001s)
[196.600706] [036/133] (823s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[196.691346] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-cpu
[196.699609] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[196.817854] [037/133] (823s left) kms_chamelium_color (ctm-0-25)
[196.902588] Subtest ctm-0-25: SKIP (0.000s)
[196.943553] [038/133] (823s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[196.985032] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[197.094975] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.108s)
[197.166644] [039/133] (822s left) gem_exec_fence (syncobj-timeline-unused-fence)
[197.635118] Starting subtest: syncobj-timeline-unused-fence
[197.636015] Subtest syncobj-timeline-unused-fence: SUCCESS (0.005s)
[197.817412] [040/133] (822s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[197.907589] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[197.908937] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[198.035090] [041/133] (822s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[198.123117] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[198.124537] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[198.243172] [042/133] (822s left) kms_chamelium_frames (hdmi-cmp-planar-formats)
[198.381049] Subtest hdmi-cmp-planar-formats: SKIP (0.000s)
[198.475378] [043/133] (821s left) kms_plane_scaling (plane-downscale-factor-0-5-with-pixel-format)
[198.562970] Starting subtest: plane-downscale-factor-0-5-with-pixel-format
[198.563637] Starting dynamic subtest: pipe-A
[199.838751] Dynamic subtest pipe-A: SUCCESS (1.274s)
[199.847248] Starting dynamic subtest: pipe-B
[201.080525] Dynamic subtest pipe-B: SUCCESS (1.241s)
[201.092267] Starting dynamic subtest: pipe-C
[201.282022] Dynamic subtest pipe-C: SUCCESS (0.200s)
[201.293978] Starting dynamic subtest: pipe-D
[201.481763] Dynamic subtest pipe-D: SUCCESS (0.199s)
[201.482443] Subtest plane-downscale-factor-0-5-with-pixel-format: SUCCESS (2.919s)
[201.668702] [044/133] (818s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[201.711213] Starting subtest: y-tiled-to-vebox-x-tiled
[201.711909] Starting dynamic subtest: smem
[201.806072] Dynamic subtest smem: SUCCESS (0.094s)
[201.806793] Subtest y-tiled-to-vebox-x-tiled: SUCCESS (0.095s)
[201.997088] [045/133] (818s left) gen9_exec_parse (batch-without-end)
[202.036137] Subtest batch-without-end: SKIP (0.000s)
[202.079843] [046/133] (818s left) syncobj_timeline (etime-single-wait-submitted)
[202.115335] Starting subtest: etime-single-wait-submitted
[202.230102] Subtest etime-single-wait-submitted: SUCCESS (0.107s)
[202.313593] [047/133] (818s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[202.410276] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip
[202.411642] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[202.530481] [048/133] (817s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[202.617654] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[202.619016] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[202.729687] [049/133] (817s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-rc-ccs)
[202.814947] Starting subtest: crc-primary-suspend-y-tiled-gen12-rc-ccs
[202.816717] Starting dynamic subtest: pipe-A-HDMI-A-1
[219.423732] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.380s)
[219.432162] Starting dynamic subtest: pipe-B-HDMI-A-1
[236.383554] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (1.316s)
[236.392173] Starting dynamic subtest: pipe-C-HDMI-A-1
[252.385007] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.342s)
[252.394058] Starting dynamic subtest: pipe-D-HDMI-A-1
[269.378160] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.339s)
[269.378868] Subtest crc-primary-suspend-y-tiled-gen12-rc-ccs: SUCCESS (5.386s)
[278.622842] [050/133] (750s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[278.717137] Starting subtest: plane-use-after-nonblocking-unbind
[278.719301] Starting dynamic subtest: pipe-A-HDMI-A-1
[278.772583] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.054s)
[278.846689] Starting dynamic subtest: pipe-B-HDMI-A-1
[279.009474] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.170s)
[279.067069] Subtest plane-use-after-nonblocking-unbind: SUCCESS (0.349s)
[279.223575] [051/133] (750s left) gem_partial_pwrite_pread (reads-uncached)
[279.261174] Starting subtest: reads-uncached
[281.276912] Subtest reads-uncached: SUCCESS (1.950s)
[281.421006] [052/133] (748s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[281.456756] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[281.457748] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[281.544102] [053/133] (748s left) i915_pm_rc6_residency (rc6-fence)
[281.577555] Starting subtest: rc6-fence
[281.582594] Starting dynamic subtest: gt0
[300.103228] Dynamic subtest gt0: SUCCESS (18.279s)
[300.104170] Subtest rc6-fence: SUCCESS (18.285s)
[300.172114] [054/133] (729s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[300.263387] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[300.264763] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[300.366843] [055/133] (729s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[300.455441] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[300.456831] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[300.585823] [056/133] (729s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[300.678899] Starting subtest: yf-tiled-16bpp-rotate-180
[300.680192] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[300.803748] [057/133] (729s left) gem_ctx_param (root-set)
[300.836561] Starting subtest: root-set
[300.837230] Subtest root-set: SUCCESS (0.000s)
[300.917109] [058/133] (729s left) gem_exec_fence (syncobj-invalid-wait)
[301.238853] Starting subtest: syncobj-invalid-wait
[301.239616] Subtest syncobj-invalid-wait: SUCCESS (0.000s)
[301.557774] [059/133] (728s left) kms_lease (invalid-create-leases)
[301.643073] Starting subtest: invalid-create-leases
[301.646693] Subtest invalid-create-leases: SUCCESS (0.003s)
[301.725960] [060/133] (728s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[301.810164] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[301.812330] Starting dynamic subtest: pipe-A-HDMI-A-1
[301.819069] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[301.828504] Starting dynamic subtest: pipe-B-HDMI-A-1
[301.835201] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[301.844457] Starting dynamic subtest: pipe-C-HDMI-A-1
[301.851136] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[301.860288] Starting dynamic subtest: pipe-D-HDMI-A-1
[301.867544] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.000s)
[301.868914] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SKIP (0.007s)
[302.065628] [061/133] (728s left) kms_sequence (queue-busy)
[302.150722] Starting subtest: queue-busy
[302.152549] Starting dynamic subtest: pipe-A-HDMI-A-1
[303.440008] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.285s)
[303.451667] Starting dynamic subtest: pipe-B-HDMI-A-1
[304.703435] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (1.259s)
[304.713679] Starting dynamic subtest: pipe-C-HDMI-A-1
[305.997840] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.288s)
[306.007340] Starting dynamic subtest: pipe-D-HDMI-A-1
[307.272826] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.272s)
[307.273571] Subtest queue-busy: SUCCESS (5.112s)
[307.407053] [062/133] (722s left) prime_vgem (basic-fence-blt)
[307.477146] Starting subtest: basic-fence-blt
[307.558487] Subtest basic-fence-blt: SUCCESS (0.081s)
[307.691048] [063/133] (722s left) i915_suspend (basic-s3-without-i915)
[307.722806] Starting subtest: basic-s3-without-i915