[61.506210] Initializing watchdogs
[61.506274]   /dev/watchdog0
[61.530426] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:56a0 Intel Dg2 (Gen12) DG2 [Arc A770]
[61.541118] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[61.544877] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.547268] [001/132] (960s left) kms_async_flips (test-time-stamp)
[61.730935] Starting subtest: test-time-stamp
[61.957059] Starting dynamic subtest: pipe-A-DP-3
[62.278875] Dynamic subtest pipe-A-DP-3: SUCCESS (0.320s)
[62.520445] Starting dynamic subtest: pipe-B-DP-3
[62.665269] Dynamic subtest pipe-B-DP-3: SUCCESS (0.152s)
[62.889764] Starting dynamic subtest: pipe-C-DP-3
[63.027041] Dynamic subtest pipe-C-DP-3: SUCCESS (0.142s)
[63.265399] Starting dynamic subtest: pipe-D-DP-3
[63.410355] Dynamic subtest pipe-D-DP-3: SUCCESS (0.151s)
[63.411935] Subtest test-time-stamp: SUCCESS (1.683s)
[63.985024] [002/132] (958s left) kms_pm_rpm (pm-tiling)
[66.006749] Starting subtest: pm-tiling
[66.010228] Subtest pm-tiling: SKIP (0.000s)
[66.450470] [003/132] (955s left) perf_pmu (busy-idle)
[66.516826] Starting subtest: busy-idle
[66.517777] Starting dynamic subtest: rcs0
[67.474566] Dynamic subtest rcs0: SUCCESS (0.959s)
[67.483203] Starting dynamic subtest: bcs0
[68.403555] Dynamic subtest bcs0: SUCCESS (0.929s)
[68.437674] Starting dynamic subtest: vcs0
[69.085394] Dynamic subtest vcs0: SUCCESS (0.682s)
[69.096468] Starting dynamic subtest: vcs1
[69.747604] Dynamic subtest vcs1: SUCCESS (0.662s)
[69.760510] Starting dynamic subtest: vecs0
[70.410172] Dynamic subtest vecs0: SUCCESS (0.662s)
[70.423109] Starting dynamic subtest: vecs1
[71.071650] Dynamic subtest vecs1: SUCCESS (0.661s)
[71.084365] Starting dynamic subtest: ccs0
[71.733671] Dynamic subtest ccs0: SUCCESS (0.661s)
[71.735383] Subtest busy-idle: SUCCESS (5.222s)
[71.876358] [004/132] (950s left) kms_psr (pr-primary-blt)
[72.059844] Subtest pr-primary-blt: SKIP (0.000s)
[72.108724] [005/132] (950s left) gem_exec_schedule (preempt-self)
[72.195721] Starting subtest: preempt-self
[72.196605] Starting dynamic subtest: rcs0
[72.252041] Dynamic subtest rcs0: SUCCESS (0.056s)
[72.260960] Starting dynamic subtest: bcs0
[72.282028] Dynamic subtest bcs0: SUCCESS (0.030s)
[72.290741] Starting dynamic subtest: vcs0
[72.313207] Dynamic subtest vcs0: SUCCESS (0.031s)
[72.321912] Starting dynamic subtest: vcs1
[72.345571] Dynamic subtest vcs1: SUCCESS (0.032s)
[72.354312] Starting dynamic subtest: vecs0
[72.374790] Dynamic subtest vecs0: SUCCESS (0.029s)
[72.383526] Starting dynamic subtest: vecs1
[72.405392] Dynamic subtest vecs1: SUCCESS (0.030s)
[72.414142] Starting dynamic subtest: ccs0
[72.435402] Dynamic subtest ccs0: SUCCESS (0.030s)
[72.436257] Subtest preempt-self: SUCCESS (0.240s)
[72.881899] [006/132] (949s left) kms_vrr (lobf)
[73.077277] Starting subtest: lobf
[73.078965] Subtest lobf: SKIP (0.000s)
[73.237206] [007/132] (949s left) kms_ccs (crc-primary-suspend-4-tiled-bmg-ccs)
[73.422380] Starting subtest: crc-primary-suspend-4-tiled-bmg-ccs
[73.424159] Subtest crc-primary-suspend-4-tiled-bmg-ccs: SKIP (0.000s)
[73.726480] [008/132] (948s left) gem_render_copy_redux (interruptible)
[73.803415] Starting subtest: interruptible
[74.548963] Subtest interruptible: SUCCESS (0.746s)
[74.741545] [009/132] (947s left) gen9_exec_parse (valid-registers)
[74.818588] Subtest valid-registers: SKIP (0.000s)
[74.886094] [010/132] (947s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[75.074530] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip
[75.076234] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[75.260996] [011/132] (947s left) syncobj_wait (single-wait-all-for-submit-unsubmitted)
[75.334336] Starting subtest: single-wait-all-for-submit-unsubmitted
[75.437255] Subtest single-wait-all-for-submit-unsubmitted: SUCCESS (0.102s)
[75.591960] [012/132] (947s left) vgem_basic (dmabuf-mmap)
[75.697276] Starting subtest: dmabuf-mmap
[75.704958] Subtest dmabuf-mmap: SUCCESS (0.008s)
[75.849443] [FACT vgem_basic (dmabuf-mmap)] new: kernel.kmod_is_loaded.vgem: true
[75.851266] [013/132] (946s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-upscaling)
[76.035492] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-upscaling
[76.037276] Subtest flip-64bpp-yftile-to-16bpp-yftile-upscaling: SKIP (0.000s)
[76.201286] [014/132] (946s left) gem_exec_params (no-bsd)
[76.283454] Starting subtest: no-bsd
[76.285285] Subtest no-bsd: SUCCESS (0.001s)
[76.493926] [015/132] (946s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[76.685537] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[76.687188] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[76.877730] [016/132] (946s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[77.068327] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-cpu
[77.070018] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[77.275977] [017/132] (945s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[77.463213] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[77.464123] Starting dynamic subtest: pipe-A-DP-3
[77.485221] Dynamic subtest pipe-A-DP-3: SKIP (0.002s)
[77.493849] Starting dynamic subtest: pipe-B-DP-3
[77.514953] Dynamic subtest pipe-B-DP-3: SKIP (0.002s)
[77.523587] Starting dynamic subtest: pipe-C-DP-3
[77.548973] Dynamic subtest pipe-C-DP-3: SKIP (0.002s)
[77.557589] Starting dynamic subtest: pipe-D-DP-3
[77.579141] Dynamic subtest pipe-D-DP-3: SKIP (0.002s)
[77.581342] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SKIP (0.011s)
[77.940494] [018/132] (945s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-pgflip-blt)
[78.129402] Starting subtest: psr-2p-primscrn-indfb-pgflip-blt
[78.131032] Subtest psr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[78.295271] [019/132] (945s left) kms_addfb_basic (clobberred-modifier)
[78.371999] Starting subtest: clobberred-modifier
[78.373765] Subtest clobberred-modifier: SKIP (0.000s)
[78.639081] [020/132] (944s left) kms_chamelium_audio (dp-audio-edid)
[78.900399] Subtest dp-audio-edid: SKIP (0.000s)
[79.053483] [021/132] (944s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[79.133961] Starting subtest: invalid-single-wait-unsubmitted
[79.134882] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[79.310681] [022/132] (944s left) gem_partial_pwrite_pread (writes-after-reads-snoop)
[79.397098] Subtest writes-after-reads-snoop: SKIP (0.000s)
[79.497113] [023/132] (944s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-msflip-blt)
[79.696216] Starting subtest: fbc-1p-primscrn-indfb-msflip-blt
[82.429905] Subtest fbc-1p-primscrn-indfb-msflip-blt: SUCCESS (2.734s)
[82.678675] [024/132] (941s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-signaled)
[82.754070] Starting subtest: multi-wait-for-submit-available-unsubmitted-signaled
[82.754983] Subtest multi-wait-for-submit-available-unsubmitted-signaled: SUCCESS (0.001s)
[82.885726] [025/132] (940s left) kms_hdmi_inject (inject-audio)
[83.060951] Starting subtest: inject-audio
[83.199213] Subtest inject-audio: SUCCESS (0.139s)
[83.554489] [026/132] (940s left) api_intel_bb (delta-check)
[83.641890] Starting subtest: delta-check
[83.672124] Subtest delta-check: SUCCESS (0.031s)
[83.886374] [027/132] (940s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[84.083486] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[84.085235] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[84.253161] [028/132] (939s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[84.443270] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[84.444983] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[84.594733] [029/132] (939s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[84.789238] Starting subtest: 4-tiled-32bpp-rotate-180
[86.548819] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.759s)
[86.760248] [030/132] (937s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-20x20)
[86.954474] Starting subtest: planes-downscale-factor-0-75-upscale-20x20
[86.955311] Starting dynamic subtest: pipe-A
[87.047586] Dynamic subtest pipe-A: SUCCESS (0.093s)
[87.062064] Starting dynamic subtest: pipe-B
[87.260822] Dynamic subtest pipe-B: SUCCESS (0.213s)
[87.275932] Starting dynamic subtest: pipe-C
[87.473813] Dynamic subtest pipe-C: SUCCESS (0.212s)
[87.488594] Starting dynamic subtest: pipe-D
[87.685941] Dynamic subtest pipe-D: SUCCESS (0.212s)
[87.686856] Subtest planes-downscale-factor-0-75-upscale-20x20: SUCCESS (0.732s)
[88.064901] [031/132] (936s left) sw_sync (sync_merge_same)
[88.112551] Starting subtest: sync_merge_same
[88.113470] Subtest sync_merge_same: SUCCESS (0.000s)
[88.258209] [032/132] (936s left) kms_pipe_b_c_ivb (pipe-b-double-modeset-then-modeset-pipe-c)
[88.338157] Subtest pipe-B-double-modeset-then-modeset-pipe-C: SKIP (0.000s)
[88.424521] [033/132] (935s left) gen9_exec_parse (batch-invalid-length)
[88.507230] Subtest batch-invalid-length: SKIP (0.000s)
[88.589121] [034/132] (935s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[88.773898] Starting subtest: plane-scaler-unity-scaling-with-rotation
[88.774604] Starting dynamic subtest: pipe-A
[89.147916] Dynamic subtest pipe-A: SUCCESS (0.375s)
[89.162819] Starting dynamic subtest: pipe-B
[89.613399] Dynamic subtest pipe-B: SUCCESS (0.465s)
[89.630398] Starting dynamic subtest: pipe-C
[90.093588] Dynamic subtest pipe-C: SUCCESS (0.480s)
[90.114585] Starting dynamic subtest: pipe-D
[90.575827] Dynamic subtest pipe-D: SUCCESS (0.481s)
[90.577246] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (1.803s)
[90.918981] [035/132] (933s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[91.109747] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[91.163065] [036/132] (933s left) kms_psr (psr-primary-blt)
[91.358314] Subtest psr-primary-blt: SKIP (0.000s)
[91.412462] [037/132] (933s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[91.498330] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[91.500268] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[91.770567] [038/132] (932s left) syncobj_basic (create-signaled)
[91.857947] Starting subtest: create-signaled
[91.859653] Subtest create-signaled: SUCCESS (0.000s)
[92.050058] [039/132] (932s left) gem_softpin (zero)
[92.133932] Starting subtest: zero
[92.140694] Subtest zero: SUCCESS (0.007s)
[92.350036] [040/132] (932s left) gem_caching (reads)
[92.437223] Starting subtest: reads
[95.465982] Subtest reads: SUCCESS (3.029s)
[95.667275] [041/132] (929s left) gem_exec_schedule (deep)
[95.882143] Starting subtest: deep
[95.883027] Starting dynamic subtest: rcs0
[110.246355] Dynamic subtest rcs0: SUCCESS (14.371s)
[110.257913] Starting dynamic subtest: bcs0
[124.637221] Dynamic subtest bcs0: SUCCESS (14.390s)
[124.647110] Starting dynamic subtest: vcs0
[139.389440] Dynamic subtest vcs0: SUCCESS (14.752s)
[139.399260] Starting dynamic subtest: vcs1
[154.059036] Dynamic subtest vcs1: SUCCESS (14.670s)
[154.068624] Starting dynamic subtest: vecs0
[168.593905] Dynamic subtest vecs0: SUCCESS (14.535s)
[168.603568] Starting dynamic subtest: vecs1
[183.589420] Dynamic subtest vecs1: SUCCESS (14.995s)
[183.599236] Starting dynamic subtest: ccs0
[198.815754] Dynamic subtest ccs0: SUCCESS (15.226s)
[198.816586] Subtest deep: SUCCESS (102.942s)
[199.915636] [042/132] (825s left) gem_exec_whisper (basic-fds-forked)
[200.002188] Starting subtest: basic-fds-forked
[222.900561] Subtest basic-fds-forked: SUCCESS (22.897s)
[223.186130] [043/132] (801s left) gem_ctx_param (invalid-get-no-zeromap)
[223.271481] Starting subtest: invalid-get-no-zeromap
[223.273374] Subtest invalid-get-no-zeromap: SUCCESS (0.001s)
[223.465084] [044/132] (801s left) gem_exec_fence (keep-in-fence)
[223.671379] Starting subtest: keep-in-fence
[223.672306] Starting dynamic subtest: rcs0
[223.786687] Dynamic subtest rcs0: SUCCESS (0.115s)
[223.799215] Starting dynamic subtest: bcs0
[223.840655] Dynamic subtest bcs0: SUCCESS (0.053s)
[223.863119] Starting dynamic subtest: vcs0
[223.905488] Dynamic subtest vcs0: SUCCESS (0.063s)
[223.937328] Starting dynamic subtest: vcs1
[223.977396] Dynamic subtest vcs1: SUCCESS (0.071s)
[223.996450] Starting dynamic subtest: vecs0
[224.036208] Dynamic subtest vecs0: SUCCESS (0.057s)
[224.070913] Starting dynamic subtest: vecs1
[224.108430] Dynamic subtest vecs1: SUCCESS (0.071s)
[224.133545] Starting dynamic subtest: ccs0
[224.337313] Dynamic subtest ccs0: SUCCESS (0.228s)
[224.338372] Subtest keep-in-fence: SUCCESS (0.666s)
[225.086060] [045/132] (800s left) kms_plane_multiple (tiling-none)
[225.271932] Starting subtest: tiling-none
[225.272833] Starting dynamic subtest: pipe-A-DP-3
[226.419487] Dynamic subtest pipe-A-DP-3: SUCCESS (1.147s)
[226.432358] Starting dynamic subtest: pipe-B-DP-3
[227.473245] Dynamic subtest pipe-B-DP-3: SUCCESS (1.052s)
[227.484560] Starting dynamic subtest: pipe-C-DP-3
[228.560901] Dynamic subtest pipe-C-DP-3: SUCCESS (1.087s)
[228.571994] Starting dynamic subtest: pipe-D-DP-3
[229.656151] Dynamic subtest pipe-D-DP-3: SUCCESS (1.093s)
[229.656998] Subtest tiling-none: SUCCESS (4.384s)
[230.001606] [046/132] (795s left) gem_exec_whisper (basic-queues-forked-all)
[230.092908] Starting subtest: basic-queues-forked-all
[252.175881] Subtest basic-queues-forked-all: SUCCESS (22.082s)
[252.452913] [047/132] (772s left) kms_psr (fbc-psr-primary-mmap-cpu)
[252.644385] Subtest fbc-psr-primary-mmap-cpu: SKIP (0.000s)
[252.739798] [048/132] (772s left) kms_plane (pixel-format-yf-tiled-modifier)
[252.922951] Starting subtest: pixel-format-yf-tiled-modifier
[252.924714] Subtest pixel-format-yf-tiled-modifier: SKIP (0.000s)
[253.054074] [049/132] (772s left) gem_exec_params (invalid-fence-in)
[253.135275] Starting subtest: invalid-fence-in
[253.136180] Subtest invalid-fence-in: SUCCESS (0.000s)
[253.307125] [050/132] (772s left) gem_exec_balancer (bonded-semaphore)
[253.398153] Starting subtest: bonded-semaphore
[253.400413] Subtest bonded-semaphore: SKIP (0.000s)
[253.804398] [051/132] (771s left) i915_pm_rpm (gem-execbuf-stress)
[256.005984] Starting subtest: gem-execbuf-stress
[256.007160] Starting dynamic subtest: lmem0
[259.693867] Dynamic subtest lmem0: SUCCESS (3.689s)
[259.739021] Starting dynamic subtest: extra-wait-lmem0
[313.079961] Dynamic subtest extra-wait-lmem0: SUCCESS (53.383s)
[313.107200] Starting dynamic subtest: smem0
[316.703170] Dynamic subtest smem0: SUCCESS (3.621s)
[316.746184] Starting dynamic subtest: extra-wait-smem0
[370.452643] Dynamic subtest extra-wait-smem0: SUCCESS (53.746s)
[370.454266] Subtest gem-execbuf-stress: SUCCESS (114.451s)
[370.847010] [052/132] (654s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[371.038113] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[371.039845] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[371.177278] [053/132] (654s left) kms_flip (flip-vs-blocking-wf-vblank)
[371.358301] Starting subtest: flip-vs-blocking-wf-vblank
[371.362738] Starting dynamic subtest: A-DP3
[379.651307] Dynamic subtest A-DP3: SUCCESS (8.288s)
[379.665801] Starting dynamic subtest: B-DP3
[387.707538] Dynamic subtest B-DP3: SUCCESS (8.055s)
[387.719587] Starting dynamic subtest: C-DP3
[395.756610] Dynamic subtest C-DP3: SUCCESS (8.048s)
[395.768949] Starting dynamic subtest: D-DP3
[403.810564] Dynamic subtest D-DP3: SUCCESS (8.053s)
[403.812709] Subtest flip-vs-blocking-wf-vblank: SUCCESS (32.455s)
[404.159342] [054/132] (621s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-fullscreen)
[404.348928] Starting subtest: fbc-1p-primscrn-spr-indfb-fullscreen
[406.341462] Subtest fbc-1p-primscrn-spr-indfb-fullscreen: SUCCESS (1.993s)
[406.591691] [055/132] (619s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted)
[406.666258] Starting subtest: invalid-multi-wait-all-unsubmitted
[406.667187] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[406.797917] [056/132] (619s left) i915_hangman (gt-error-state-capture)
[406.894916] Starting subtest: gt-error-state-capture
[406.895873] Starting dynamic subtest: rcs0
[417.128517] Dynamic subtest rcs0: SUCCESS (10.233s)
[417.139447] Starting dynamic subtest: bcs0
[427.063073] Dynamic subtest bcs0: SUCCESS (9.934s)
[427.073625] Starting dynamic subtest: vcs0
[437.078501] Dynamic subtest vcs0: SUCCESS (10.015s)
[437.093573] Starting dynamic subtest: vcs1
[447.134631] Dynamic subtest vcs1: SUCCESS (10.055s)
[447.149181] Starting dynamic subtest: vecs0
[457.104559] Dynamic subtest vecs0: SUCCESS (9.969s)
[457.127485] Starting dynamic subtest: vecs1
[467.074840] Dynamic subtest vecs1: SUCCESS (9.969s)
[467.085803] Starting dynamic subtest: ccs0
[477.099578] Dynamic subtest ccs0: SUCCESS (10.024s)
[477.100617] Subtest gt-error-state-capture: SUCCESS (70.206s)
[477.289464] [057/132] (548s left) gem_mmap_wc (write-cpu-read-wc)
[477.365986] Subtest write-cpu-read-wc: SKIP (0.000s)
[477.467600] [058/132] (548s left) gem_pwrite (basic-self)
[477.541166] Subtest basic-self: SKIP (0.000s)
[477.622840] [059/132] (548s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf)
[477.808363] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[477.868751] [060/132] (548s left) kms_async_flips (alternate-sync-async-flip)
[478.050964] Starting subtest: alternate-sync-async-flip
[478.279512] Starting dynamic subtest: pipe-A-DP-3
[480.582020] Dynamic subtest pipe-A-DP-3: SUCCESS (2.302s)
[480.828728] Starting dynamic subtest: pipe-B-DP-3
[482.943995] Dynamic subtest pipe-B-DP-3: SUCCESS (2.126s)
[483.159169] Starting dynamic subtest: pipe-C-DP-3
[485.267038] Dynamic subtest pipe-C-DP-3: SUCCESS (2.118s)
[485.476422] Starting dynamic subtest: pipe-D-DP-3
[487.600675] Dynamic subtest pipe-D-DP-3: SUCCESS (2.133s)
[487.601956] Subtest alternate-sync-async-flip: SUCCESS (9.551s)
[488.146036] [061/132] (538s left) kms_invalid_mode (zero-vdisplay)
[488.343215] Starting subtest: zero-vdisplay
[488.344127] Starting dynamic subtest: pipe-A-DP-3
[488.420948] Dynamic subtest pipe-A-DP-3: SUCCESS (0.076s)
[488.435069] Starting dynamic subtest: pipe-B-DP-3
[488.436187] Dynamic subtest pipe-B-DP-3: SUCCESS (0.001s)
[488.448555] Starting dynamic subtest: pipe-C-DP-3
[488.449798] Dynamic subtest pipe-C-DP-3: SUCCESS (0.001s)
[488.461594] Starting dynamic subtest: pipe-D-DP-3
[488.462487] Dynamic subtest pipe-D-DP-3: SUCCESS (0.001s)
[488.463400] Subtest zero-vdisplay: SUCCESS (0.086s)
[488.686862] [062/132] (537s left) gem_reg_read (bad-register)
[488.764635] Starting subtest: bad-register
[488.765527] Subtest bad-register: SUCCESS (0.000s)
[488.951569] [063/132] (537s left) syncobj_wait (multi-wait-all-submitted)
[489.030442] Starting subtest: multi-wait-all-submitted
[489.133137] Subtest multi-wait-all-submitted: SUCCESS (0.102s)
[489.318455] [064/132] (537s left) kms_plane_alpha_blend (alpha-opaque-fb)
[489.502343] Starting subtest: alpha-opaque-fb
[489.759703] Starting dynamic subtest: pipe-A-DP-3
[490.275845] Dynamic subtest pipe-A-DP-3: SUCCESS (0.516s)
[490.287959] Starting dynamic subtest: pipe-D-DP-3
[490.839450] Dynamic subtest pipe-D-DP-3: SUCCESS (0.562s)
[490.840298] Subtest alpha-opaque-fb: SUCCESS (1.338s)
[491.157770] [065/132] (535s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-pgflip-blt)
[491.347270] Starting subtest: fbcpsr-2p-primscrn-shrfb-pgflip-blt
[491.348972] Subtest fbcpsr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[491.557602] [066/132] (535s left) kms_chamelium_edid (hdmi-edid-change-during-suspend)
[491.817403] Subtest hdmi-edid-change-during-suspend: SKIP (0.000s)
[491.972781] [067/132] (534s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[492.164903] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[493.362592] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (1.197s)
[493.630701] [068/132] (533s left) gem_exec_reloc (basic-wc-cpu-active)
[493.712341] Subtest basic-wc-cpu-active: SKIP (0.000s)
[493.788771] [069/132] (533s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[493.982973] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[493.984781] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[494.177456] [070/132] (532s left) core_setmaster_vs_auth
[494.480588] [071/132] (532s left) kms_hdr (static-swap)
[494.671281] Starting subtest: static-swap
[498.168494] Starting dynamic subtest: pipe-A-DP-3
[499.404280] Dynamic subtest pipe-A-DP-3: SUCCESS (1.237s)
[499.410503] Subtest static-swap: SUCCESS (4.736s)
[499.764781] [072/132] (527s left) kms_cursor_legacy (torture-bo)
[499.948474] Starting subtest: torture-bo
[499.949372] Starting dynamic subtest: pipe-A
[505.527582] Dynamic subtest pipe-A: SUCCESS (5.552s)
[505.538771] Starting dynamic subtest: pipe-B
[510.989469] Dynamic subtest pipe-B: SUCCESS (5.464s)
[511.002191] Starting dynamic subtest: pipe-C
[516.435528] Dynamic subtest pipe-C: SUCCESS (5.445s)
[516.446969] Starting dynamic subtest: pipe-D
[521.897714] Dynamic subtest pipe-D: SUCCESS (5.461s)
[521.910470] Starting dynamic subtest: all-pipes
[527.359586] Dynamic subtest all-pipes: SUCCESS (5.461s)
[527.360439] Subtest torture-bo: SUCCESS (27.412s)
[527.482372] [073/132] (499s left) kms_flip (2x-plain-flip)
[527.696239] Starting subtest: 2x-plain-flip
[527.711524] Subtest 2x-plain-flip: SKIP (0.015s)
[527.861048] [074/132] (499s left) kms_cursor_crc (cursor-random-128x128)
[528.226778] Starting subtest: cursor-random-128x128
[528.231394] Starting dynamic subtest: pipe-A-DP-3
[532.859158] Dynamic subtest pipe-A-DP-3: SUCCESS (4.624s)
[532.910678] Starting dynamic subtest: pipe-D-DP-3
[537.509936] Dynamic subtest pipe-D-DP-3: SUCCESS (4.608s)
[537.511030] Subtest cursor-random-128x128: SUCCESS (9.283s)
[537.958183] [075/132] (489s left) kms_vblank (wait-idle-hang)
[538.151916] Starting subtest: wait-idle-hang
[538.154312] Starting dynamic subtest: pipe-A-DP-3
[564.551458] Dynamic subtest pipe-A-DP-3: SUCCESS (26.398s)
[564.565575] Starting dynamic subtest: pipe-D-DP-3
[589.661244] Dynamic subtest pipe-D-DP-3: SUCCESS (25.106s)
[589.664292] Subtest wait-idle-hang: SUCCESS (51.511s)
[589.875967] [076/132] (437s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-plflip-blt)
[590.065408] Starting subtest: fbc-2p-primscrn-indfb-plflip-blt
[590.067051] Subtest fbc-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[590.260325] [077/132] (437s left) gem_workarounds (reset-fd)
[590.351927] Starting subtest: reset-fd
[590.574580] Subtest reset-fd: SUCCESS (0.223s)
[590.810145] [078/132] (436s left) perf_pmu (rc6)
[590.879770] Starting subtest: rc6
[590.880634] Starting dynamic subtest: gt0
[597.243242] Dynamic subtest gt0: SUCCESS (6.362s)
[597.257505] Starting dynamic subtest: runtime-pm-gt0
[605.326910] Dynamic subtest runtime-pm-gt0: SUCCESS (8.079s)
[605.339753] Starting dynamic subtest: runtime-pm-long-gt0
[618.020536] Dynamic subtest runtime-pm-long-gt0: SUCCESS (12.691s)
[618.033148] Starting dynamic subtest: other-idle-gt0
[618.034931] Dynamic subtest other-idle-gt0: SKIP (0.000s)
[618.035878] Subtest rc6: SUCCESS (27.143s)
[618.708174] [079/132] (409s left) syncobj_wait (invalid-multi-wait-all-unsubmitted)
[618.787396] Starting subtest: invalid-multi-wait-all-unsubmitted
[618.788325] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[618.882383] [080/132] (408s left) gem_mmap_gtt (fault-concurrent-y)
[618.968138] Subtest fault-concurrent-Y: SKIP (0.000s)
[619.033892] [081/132] (408s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted-signa
[619.111362] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled
[619.221579] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.109s)
[619.299757] [082/132] (408s left) kms_frontbuffer_tracking (fbc-2p-shrfb-fliptrack-mmap-gtt)
[619.494168] Starting subtest: fbc-2p-shrfb-fliptrack-mmap-gtt
[619.495831] Subtest fbc-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[619.571527] [083/132] (408s left) api_intel_allocator (two-level-inception-interruptible)
[619.649287] Starting subtest: two-level-inception-interruptible
[625.067034] Subtest two-level-inception-interruptible: SUCCESS (5.417s)
[625.168365] [084/132] (402s left) prime_busy (before-wait)
[625.266355] Starting subtest: before-wait
[625.270221] Starting dynamic subtest: rcs0
[625.321899] Dynamic subtest rcs0: SUCCESS (0.052s)
[625.334539] Starting dynamic subtest: bcs0
[625.384060] Dynamic subtest bcs0: SUCCESS (0.061s)
[625.408874] Starting dynamic subtest: vcs0
[625.431789] Dynamic subtest vcs0: SUCCESS (0.041s)
[625.455066] Starting dynamic subtest: vcs1
[625.467695] Dynamic subtest vcs1: SUCCESS (0.039s)
[625.488777] Starting dynamic subtest: vecs0
[625.509371] Dynamic subtest vecs0: SUCCESS (0.040s)
[625.525882] Starting dynamic subtest: vecs1
[625.551372] Dynamic subtest vecs1: SUCCESS (0.041s)
[625.567893] Starting dynamic subtest: ccs0
[625.591791] Dynamic subtest ccs0: SUCCESS (0.039s)
[625.713830] Subtest before-wait: SUCCESS (0.437s)
[625.837140] [085/132] (402s left) kms_psr2_su (page_flip-xrgb8888)
[625.916169] Subtest page_flip-XRGB8888: SKIP (0.000s)
[625.985367] [086/132] (402s left) gem_exec_flush (basic-wb-pro-default)
[626.068552] Subtest basic-wb-pro-default: SKIP (0.000s)
[626.168245] [087/132] (402s left) gem_close_race (multigpu-basic-threads)
[626.284822] Starting subtest: multigpu-basic-threads
[626.287163] Subtest multigpu-basic-threads: SKIP (0.000s)
[626.548541] [088/132] (401s left) kms_big_fb (x-tiled-8bpp-rotate-270)
[626.741135] Starting subtest: x-tiled-8bpp-rotate-270
[626.808409] Subtest x-tiled-8bpp-rotate-270: SKIP (0.066s)
[626.964599] [089/132] (401s left) kms_ccs (crc-primary-basic-y-tiled-gen12-rc-ccs-cc)
[627.149399] Starting subtest: crc-primary-basic-y-tiled-gen12-rc-ccs-cc
[627.150267] Starting dynamic subtest: pipe-A-DP-3
[627.158372] Dynamic subtest pipe-A-DP-3: SKIP (0.001s)
[627.169549] Starting dynamic subtest: pipe-B-DP-3
[627.177728] Dynamic subtest pipe-B-DP-3: SKIP (0.001s)
[627.188846] Starting dynamic subtest: pipe-C-DP-3
[627.196882] Dynamic subtest pipe-C-DP-3: SKIP (0.001s)
[627.207872] Starting dynamic subtest: pipe-D-DP-3
[627.218125] Dynamic subtest pipe-D-DP-3: SKIP (0.001s)
[627.220021] Subtest crc-primary-basic-y-tiled-gen12-rc-ccs-cc: SKIP (0.009s)
[627.530738] [090/132] (400s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs-cc)
[627.713259] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs-cc
[627.714097] Starting dynamic subtest: pipe-A-DP-3
[627.722282] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[627.733544] Starting dynamic subtest: pipe-B-DP-3
[627.741954] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[627.753112] Starting dynamic subtest: pipe-C-DP-3
[627.761174] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[627.772525] Starting dynamic subtest: pipe-D-DP-3
[627.782547] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[627.784345] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SKIP (0.007s)
[628.063667] [091/132] (400s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-cpu)
[628.253956] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-cpu
[628.255654] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[628.326370] [092/132] (400s left) kms_big_fb (linear-32bpp-rotate-180)
[628.527013] Starting subtest: linear-32bpp-rotate-180
[630.482316] Subtest linear-32bpp-rotate-180: SUCCESS (1.954s)
[630.629802] [093/132] (397s left) kms_cursor_crc (cursor-sliding-64x64)
[630.951600] Starting subtest: cursor-sliding-64x64
[630.956785] Starting dynamic subtest: pipe-A-DP-3
[635.360495] Dynamic subtest pipe-A-DP-3: SUCCESS (4.404s)
[635.396027] Starting dynamic subtest: pipe-D-DP-3
[639.728150] Dynamic subtest pipe-D-DP-3: SUCCESS (4.342s)
[639.729181] Subtest cursor-sliding-64x64: SUCCESS (8.777s)
[640.171382] [094/132] (388s left) gem_exec_endless (dispatch)
[640.266677] Starting subtest: dispatch
[640.267565] Starting dynamic subtest: rcs0
[640.274589] Dynamic subtest rcs0: SUCCESS (0.007s)
[640.287280] Starting dynamic subtest: bcs0
[640.289182] Dynamic subtest bcs0: SUCCESS (0.006s)
[640.300499] Starting dynamic subtest: vcs0
[640.302255] Dynamic subtest vcs0: SUCCESS (0.006s)
[640.313447] Starting dynamic subtest: vcs1
[640.315235] Dynamic subtest vcs1: SUCCESS (0.006s)
[640.326200] Starting dynamic subtest: vecs0
[640.327963] Dynamic subtest vecs0: SUCCESS (0.008s)
[640.346027] Starting dynamic subtest: vecs1
[820.512636] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[940.859525] Timeout. Killing the current test with SIGKILL.
[940.887844] Closing watchdogs
[940.889395] Initializing watchdogs
[940.889435]   /dev/watchdog0
[940.911912] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:56a0 Intel Dg2 (Gen12) DG2 [Arc A770]
[940.925323] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[940.927533] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[940.928134] [FACT before any test] new: kernel.kmod_is_loaded.vgem: true
[940.930961] [095/132] ( 87s left) kms_atomic_transition (plane-all-transition-fencing)
[941.120424] Starting subtest: plane-all-transition-fencing
[941.121295] Starting dynamic subtest: pipe-A-DP-3
[944.906668] Dynamic subtest pipe-A-DP-3: SUCCESS (3.785s)
[945.042558] Starting dynamic subtest: pipe-B-DP-3
[948.937542] Dynamic subtest pipe-B-DP-3: SUCCESS (3.906s)
[949.026945] Subtest plane-all-transition-fencing: SUCCESS (7.907s)
[949.195512] [096/132] ( 79s left) kms_atomic_interruptible (legacy-pageflip)
[949.385286] Starting subtest: legacy-pageflip
[949.387449] Starting dynamic subtest: pipe-A-DP-3
[955.712535] Dynamic subtest pipe-A-DP-3: SUCCESS (6.326s)
[955.713511] Subtest legacy-pageflip: SUCCESS (6.328s)
[955.887681] [097/132] ( 72s left) core_getversion (basic)
[955.950333] Starting subtest: basic
[955.952228] Subtest basic: SUCCESS (0.000s)
[956.016834] [098/132] ( 72s left) gem_userptr_blits (invalid-mmap-offset-unsync)
[956.101907] Subtest invalid-mmap-offset-unsync: SKIP (0.000s)
[956.242695] [099/132] ( 72s left) kms_psr (fbc-psr2-cursor-render)
[956.430239] Subtest fbc-psr2-cursor-render: SKIP (0.000s)
[956.493986] [100/132] ( 72s left) kms_pm_backlight (brightness-with-dpms)
[956.682306] Subtest brightness-with-dpms: SKIP (0.000s)
[956.744253] [101/132] ( 72s left) kms_frontbuffer_tracking (fbcpsr-suspend)
[956.932515] Starting subtest: fbcpsr-suspend
[956.934186] Subtest fbcpsr-suspend: SKIP (0.000s)
[956.996870] [102/132] ( 72s left) kms_chamelium_hpd (dp-hpd-enable-disable-mode)
[957.261329] Subtest dp-hpd-enable-disable-mode: SKIP (0.000s)
[957.411506] [103/132] ( 71s left) gem_exec_params (invalid-bsd1-flag-on-render)
[957.505428] Starting subtest: invalid-bsd1-flag-on-render
[957.506329] Subtest invalid-bsd1-flag-on-render: SUCCESS (0.000s)
[957.597503] [104/132] ( 71s left) vgem_slow (nohang)
[957.652164] Starting subtest: nohang
[967.767459] Subtest nohang: SUCCESS (10.114s)
[967.819814] [105/132] ( 61s left) kms_universal_plane (cursor-fb-leak)
[968.005422] Starting subtest: cursor-fb-leak
[968.006267] Starting dynamic subtest: pipe-A-DP-3
[968.584847] Dynamic subtest pipe-A-DP-3: SUCCESS (0.579s)
[968.607018] Starting dynamic subtest: pipe-B-DP-3
[969.223852] Dynamic subtest pipe-B-DP-3: SUCCESS (0.635s)
[969.259445] Starting dynamic subtest: pipe-C-DP-3
[969.860220] Dynamic subtest pipe-C-DP-3: SUCCESS (0.631s)
[969.873794] Starting dynamic subtest: pipe-D-DP-3
[970.481716] Dynamic subtest pipe-D-DP-3: SUCCESS (0.619s)
[970.482629] Subtest cursor-fb-leak: SUCCESS (2.478s)
[970.813155] [106/132] ( 58s left) sw_sync (sync_expired_merge)
[970.860973] Starting subtest: sync_expired_merge
[971.934878] Subtest sync_expired_merge: SUCCESS (1.074s)
[971.982358] [107/132] ( 57s left) kms_flip (2x-blocking-absolute-wf_vblank)
[972.173320] Starting subtest: 2x-blocking-absolute-wf_vblank
[972.188437] Subtest 2x-blocking-absolute-wf_vblank: SKIP (0.014s)
[972.258518] [108/132] ( 57s left) gem_blits (basic)
[972.367343] Starting subtest: basic
[978.071942] Subtest basic: SUCCESS (5.704s)
[978.177454] [109/132] ( 51s left) kms_psr (psr2-primary-page-flip)
[978.362236] Subtest psr2-primary-page-flip: SKIP (0.000s)
[978.424273] [110/132] ( 51s left) kms_cursor_crc (cursor-onscreen-32x32)
[978.701259] Starting subtest: cursor-onscreen-32x32
[978.752867] Subtest cursor-onscreen-32x32: SKIP (0.049s)
[979.158292] [111/132] ( 50s left) syncobj_timeline (wait-any-interrupted)
[979.241383] Starting subtest: wait-any-interrupted
[979.344833] Subtest wait-any-interrupted: SUCCESS (0.102s)
[979.455614] [112/132] ( 50s left) gem_exec_schedule (lateslice)
[979.542538] Starting subtest: lateslice
[979.543439] Starting dynamic subtest: rcs0
[979.591024] Dynamic subtest rcs0: SUCCESS (0.048s)
[979.604169] Starting dynamic subtest: bcs0
[979.611648] Dynamic subtest bcs0: SUCCESS (0.020s)
[979.625127] Starting dynamic subtest: vcs0
[979.634588] Dynamic subtest vcs0: SUCCESS (0.022s)
[979.647761] Starting dynamic subtest: vcs1
[979.655967] Dynamic subtest vcs1: SUCCESS (0.020s)
[979.669163] Starting dynamic subtest: vecs0
[979.677958] Dynamic subtest vecs0: SUCCESS (0.021s)
[979.691138] Starting dynamic subtest: vecs1
[979.699233] Dynamic subtest vecs1: SUCCESS (0.020s)
[979.712416] Starting dynamic subtest: ccs0
[979.719440] Dynamic subtest ccs0: SUCCESS (0.019s)
[979.720388] Subtest lateslice: SUCCESS (0.177s)
[980.083327] [113/132] ( 49s left) gem_eio (in-flight-internal-1us)
[980.378149] Starting subtest: in-flight-internal-1us
[981.889922] Subtest in-flight-internal-1us: SUCCESS (1.512s)
[982.059721] [114/132] ( 47s left) gem_ctx_shared (exec-shared-gtt)
[982.148274] Starting subtest: exec-shared-gtt
[982.149204] Starting dynamic subtest: rcs0
[982.157073] Dynamic subtest rcs0: SUCCESS (0.008s)
[982.170475] Starting dynamic subtest: bcs0
[982.171430] Dynamic subtest bcs0: SUCCESS (0.006s)
[982.183430] Starting dynamic subtest: vcs0
[982.184262] Dynamic subtest vcs0: SUCCESS (0.007s)
[982.196032] Starting dynamic subtest: vcs1
[982.196982] Dynamic subtest vcs1: SUCCESS (0.008s)
[982.208989] Starting dynamic subtest: vecs0
[982.209945] Dynamic subtest vecs0: SUCCESS (0.006s)
[982.221618] Starting dynamic subtest: vecs1
[982.222682] Dynamic subtest vecs1: SUCCESS (0.007s)
[982.234111] Starting dynamic subtest: ccs0
[982.235399] Dynamic subtest ccs0: SUCCESS (0.008s)
[982.236656] Subtest exec-shared-gtt: SUCCESS (0.059s)
[982.423621] [115/132] ( 47s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[982.611395] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[982.612967] Subtest fbcpsr-rgb101010-draw-pwrite: SKIP (0.000s)
[982.693973] [116/132] ( 47s left) syncobj_wait (multi-wait-for-submit-submitted)
[982.777425] Starting subtest: multi-wait-for-submit-submitted
[982.885497] Subtest multi-wait-for-submit-submitted: SUCCESS (0.107s)
[983.006957] [117/132] ( 47s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-render)
[983.198165] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-render
[985.912256] Subtest fbc-1p-primscrn-spr-indfb-draw-render: SUCCESS (2.714s)
[986.077526] [118/132] ( 43s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-onoff)
[986.266154] Starting subtest: psr-2p-scndscrn-spr-indfb-onoff
[986.267858] Subtest psr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[986.371066] [119/132] ( 43s left) gem_ctx_persistence (heartbeat-hang)
[986.454579] Starting subtest: heartbeat-hang
[986.456675] Subtest heartbeat-hang: SKIP (0.000s)
[986.550936] [120/132] ( 43s left) i915_pm_rps (thresholds)
[986.637121] Starting subtest: thresholds
[986.638827] Subtest thresholds: SKIP (0.000s)
[986.691730] [121/132] ( 43s left) gem_pxp (reject-modify-context-protection-on)
[989.303245] Starting subtest: reject-modify-context-protection-on
[989.305174] Subtest reject-modify-context-protection-on: SKIP (0.000s)
[989.359834] [122/132] ( 40s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-render)
[989.562298] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-render
[989.563873] Subtest fbc-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[989.643441] [123/132] ( 40s left) gem_mmap (bad-size)
[989.721250] Subtest bad-size: SKIP (0.000s)
[989.791970] [124/132] ( 40s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-gtt)
[989.986179] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-gtt
[989.987932] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[990.089248] [125/132] ( 40s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-plflip-blt)
[990.281108] Starting subtest: fbcpsr-1p-primscrn-shrfb-plflip-blt
[990.282734] Subtest fbcpsr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[990.359752] [126/132] ( 40s left) kms_color_pipeline (plane-ctm3x4-lut1d)
[990.547589] Starting subtest: plane-ctm3x4-lut1d
[990.578885] Starting dynamic subtest: pipe-A-DP-3
[990.961275] Dynamic subtest pipe-A-DP-3: SUCCESS (0.382s)
[991.143651] Starting dynamic subtest: pipe-B-DP-3
[991.479855] Dynamic subtest pipe-B-DP-3: SUCCESS (0.348s)
[991.669957] Starting dynamic subtest: pipe-C-DP-3
[991.990607] Dynamic subtest pipe-C-DP-3: SUCCESS (0.332s)
[992.208803] Starting dynamic subtest: pipe-D-DP-3
[992.545526] Dynamic subtest pipe-D-DP-3: SUCCESS (0.348s)
[992.635063] Subtest plane-ctm3x4-lut1d: SUCCESS (2.087s)
[992.844724] [127/132] ( 37s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-upscaling)
[993.036716] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-upscaling
[993.038507] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-upscaling: SKIP (0.000s)
[993.102641] [128/132] ( 37s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc)
[993.295147] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc
[993.296848] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[993.397578] [129/132] ( 37s left) kms_lease (lease-invalid-connector)
[993.583337] Starting subtest: lease-invalid-connector
[993.584204] Starting dynamic subtest: pipe-A-DP-3
[993.585107] Dynamic subtest pipe-A-DP-3: SUCCESS (0.000s)
[993.596924] Starting dynamic subtest: pipe-B-DP-3
[993.597970] Dynamic subtest pipe-B-DP-3: SUCCESS (0.000s)
[993.609587] Starting dynamic subtest: pipe-C-DP-3
[993.610615] Dynamic subtest pipe-C-DP-3: SUCCESS (0.000s)
[993.622081] Starting dynamic subtest: pipe-D-DP-3
[993.623352] Dynamic subtest pipe-D-DP-3: SUCCESS (0.000s)
[993.624627] Subtest lease-invalid-connector: SUCCESS (0.007s)
[993.670639] [130/132] ( 37s left) kms_feature_discovery (chamelium)
[993.860556] Starting subtest: chamelium
[993.862239] Subtest chamelium: SKIP (0.000s)
[993.919093] [131/132] ( 36s left) gem_render_copy (x-tiled)
[994.006578] Starting subtest: x-tiled
[994.007462] Starting dynamic subtest: smem
[994.119914] Dynamic subtest smem: SUCCESS (0.107s)
[994.131650] Starting dynamic subtest: lmem0
[995.492368] Dynamic subtest lmem0: SUCCESS (1.376s)
[995.493301] Subtest x-tiled: SUCCESS (1.486s)
[995.717087] [132/132] ( 35s left) kms_content_protection (atomic-dpms)
[995.972402] Starting subtest: atomic-dpms
[996.004316] Starting dynamic subtest: pipe-A-DP-3
[1117.180218] Dynamic subtest pipe-A-DP-3: FAIL (121.005s)
[1117.181420] Subtest atomic-dpms: FAIL (121.143s)
[1117.293230] Overall timeout time exceeded, stopping.
[1117.329060] Closing watchdogs
results: parsing output: 0/ for test: kms_async_flips
results: parsing output: 1/ for test: kms_pm_rpm
results: parsing output: 2/ for test: perf_pmu
results: parsing output: 3/ for test: kms_psr
results: parsing output: 4/ for test: gem_exec_schedule
results: parsing output: 5/ for test: kms_vrr
results: parsing output: 6/ for test: kms_ccs
results: parsing output: 7/ for test: gem_render_copy_redux
results: parsing output: 8/ for test: gen9_exec_parse
results: parsing output: 9/ for test: kms_big_fb
results: parsing output: 10/ for test: syncobj_wait
results: parsing output: 11/ for test: vgem_basic
results: parsing output: 12/ for test: kms_flip_scaled_crc
results: parsing output: 13/ for test: gem_exec_params
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: kms_ccs
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_addfb_basic
results: parsing output: 19/ for test: kms_chamelium_audio
results: parsing output: 20/ for test: syncobj_timeline
results: parsing output: 21/ for test: gem_partial_pwrite_pread
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: syncobj_timeline
results: parsing output: 24/ for test: kms_hdmi_inject
results: parsing output: 25/ for test: api_intel_bb
results: parsing output: 26/ for test: kms_big_fb
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_big_fb
results: parsing output: 29/ for test: kms_plane_scaling
results: parsing output: 30/ for test: sw_sync
results: parsing output: 31/ for test: kms_pipe_b_c_ivb
results: parsing output: 32/ for test: gen9_exec_parse
results: parsing output: 33/ for test: kms_plane_scaling
results: parsing output: 34/ for test: kms_psr2_sf
results: parsing output: 35/ for test: kms_psr
results: parsing output: 36/ for test: gem_render_copy
results: parsing output: 37/ for test: syncobj_basic
results: parsing output: 38/ for test: gem_softpin
results: parsing output: 39/ for test: gem_caching
results: parsing output: 40/ for test: gem_exec_schedule
results: parsing output: 41/ for test: gem_exec_whisper
results: parsing output: 42/ for test: gem_ctx_param
results: parsing output: 43/ for test: gem_exec_fence
results: parsing output: 44/ for test: kms_plane_multiple
results: parsing output: 45/ for test: gem_exec_whisper
results: parsing output: 46/ for test: kms_psr
results: parsing output: 47/ for test: kms_plane
results: parsing output: 48/ for test: gem_exec_params
results: parsing output: 49/ for test: gem_exec_balancer
results: parsing output: 50/ for test: i915_pm_rpm
results: parsing output: 51/ for test: kms_big_fb
results: parsing output: 52/ for test: kms_flip
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: syncobj_timeline
results: parsing output: 55/ for test: i915_hangman
results: parsing output: 56/ for test: gem_mmap_wc
results: parsing output: 57/ for test: gem_pwrite
results: parsing output: 58/ for test: kms_psr2_sf
results: parsing output: 59/ for test: kms_async_flips
results: parsing output: 60/ for test: kms_invalid_mode
results: parsing output: 61/ for test: gem_reg_read
results: parsing output: 62/ for test: syncobj_wait
results: parsing output: 63/ for test: kms_plane_alpha_blend
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: kms_chamelium_edid
results: parsing output: 66/ for test: kms_big_fb
results: parsing output: 67/ for test: gem_exec_reloc
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: core_setmaster_vs_auth
results: parsing output: 70/ for test: kms_hdr
results: parsing output: 71/ for test: kms_cursor_legacy
results: parsing output: 72/ for test: kms_flip
results: parsing output: 73/ for test: kms_cursor_crc
results: parsing output: 74/ for test: kms_vblank
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: gem_workarounds
results: parsing output: 77/ for test: perf_pmu
results: parsing output: 78/ for test: syncobj_wait
results: parsing output: 79/ for test: gem_mmap_gtt
results: parsing output: 80/ for test: syncobj_timeline
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: api_intel_allocator
results: parsing output: 83/ for test: prime_busy
results: parsing output: 84/ for test: kms_psr2_su
results: parsing output: 85/ for test: gem_exec_flush
results: parsing output: 86/ for test: gem_close_race
results: parsing output: 87/ for test: kms_big_fb
results: parsing output: 88/ for test: kms_ccs
results: parsing output: 89/ for test: kms_ccs
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_big_fb
results: parsing output: 92/ for test: kms_cursor_crc
results: parsing output: 93/ for test: gem_exec_endless
results: parsing output: 94/ for test: kms_atomic_transition
results: parsing output: 95/ for test: kms_atomic_interruptible
results: parsing output: 96/ for test: core_getversion
results: parsing output: 97/ for test: gem_userptr_blits
results: parsing output: 98/ for test: kms_psr
results: parsing output: 99/ for test: kms_pm_backlight
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_chamelium_hpd
results: parsing output: 102/ for test: gem_exec_params
results: parsing output: 103/ for test: vgem_slow
results: parsing output: 104/ for test: kms_universal_plane
results: parsing output: 105/ for test: sw_sync
results: parsing output: 106/ for test: kms_flip
results: parsing output: 107/ for test: gem_blits
results: parsing output: 108/ for test: kms_psr
results: parsing output: 109/ for test: kms_cursor_crc
results: parsing output: 110/ for test: syncobj_timeline
results: parsing output: 111/ for test: gem_exec_schedule
results: parsing output: 112/ for test: gem_eio
results: parsing output: 113/ for test: gem_ctx_shared
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: syncobj_wait
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: gem_ctx_persistence
results: parsing output: 119/ for test: i915_pm_rps
results: parsing output: 120/ for test: gem_pxp
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: gem_mmap
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_color_pipeline
results: parsing output: 126/ for test: kms_flip_scaled_crc
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_lease
results: parsing output: 129/ for test: kms_feature_discovery
results: parsing output: 130/ for test: gem_render_copy
results: parsing output: 131/ for test: kms_content_protection
Done.