[62.417120] Initializing watchdogs
[62.417189]   /dev/watchdog0
[62.437754] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:56a0 Intel Dg2 (Gen12) DG2 [Arc A770]
[62.448928] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[62.452806] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[62.455078] [001/134] (960s left) kms_cursor_crc (cursor-offscreen-256x85)
[62.897715] Starting subtest: cursor-offscreen-256x85
[62.913154] Starting dynamic subtest: pipe-A-DP-3
[65.426205] Dynamic subtest pipe-A-DP-3: SUCCESS (2.512s)
[65.460909] Starting dynamic subtest: pipe-D-DP-3
[67.907115] Dynamic subtest pipe-D-DP-3: SUCCESS (2.453s)
[67.908019] Subtest cursor-offscreen-256x85: SUCCESS (5.009s)
[68.264477] [002/134] (954s left) gem_mmap_gtt (cpuset-big-copy-odd)
[68.331878] Subtest cpuset-big-copy-odd: SKIP (0.000s)
[68.412093] [003/134] (954s left) gem_ctx_engines (execute-allforone)
[68.496510] Starting subtest: execute-allforone
[68.542253] Subtest execute-allforone: SUCCESS (0.046s)
[68.894940] [004/134] (954s left) perf_pmu (busy-idle)
[68.956493] Starting subtest: busy-idle
[68.957437] Starting dynamic subtest: rcs0
[69.688555] Dynamic subtest rcs0: SUCCESS (0.731s)
[69.700229] Starting dynamic subtest: bcs0
[70.353762] Dynamic subtest bcs0: SUCCESS (0.664s)
[70.365947] Starting dynamic subtest: vcs0
[71.025541] Dynamic subtest vcs0: SUCCESS (0.671s)
[71.037829] Starting dynamic subtest: vcs1
[71.686570] Dynamic subtest vcs1: SUCCESS (0.660s)
[71.698711] Starting dynamic subtest: vecs0
[72.348719] Dynamic subtest vecs0: SUCCESS (0.661s)
[72.361707] Starting dynamic subtest: vecs1
[73.015105] Dynamic subtest vecs1: SUCCESS (0.665s)
[73.028008] Starting dynamic subtest: ccs0
[73.682708] Dynamic subtest ccs0: SUCCESS (0.666s)
[73.684572] Subtest busy-idle: SUCCESS (4.726s)
[73.855480] [005/134] (949s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[74.047946] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[74.736317] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (0.688s)
[75.031568] [006/134] (948s left) api_intel_bb (lot-of-buffers)
[75.106875] Starting subtest: lot-of-buffers
[78.364112] Subtest lot-of-buffers: SUCCESS (3.257s)
[78.676943] [007/134] (944s left) gem_render_copy (yf-tiled-to-vebox-x-tiled)
[78.748386] Starting subtest: yf-tiled-to-vebox-x-tiled
[78.750475] Subtest yf-tiled-to-vebox-x-tiled: SKIP (0.000s)
[78.994259] [008/134] (944s left) kms_plane (plane-panning-top-left)
[79.173920] Starting subtest: plane-panning-top-left
[79.174842] Starting dynamic subtest: pipe-A
[79.970561] Dynamic subtest pipe-A: SUCCESS (0.796s)
[79.983376] Starting dynamic subtest: pipe-B
[80.639013] Dynamic subtest pipe-B: SUCCESS (0.666s)
[80.639949] Subtest plane-panning-top-left: SUCCESS (1.465s)
[80.961984] [009/134] (942s left) kms_psr (fbc-psr-cursor-plane-onoff)
[81.142888] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.000s)
[81.203448] [010/134] (942s left) kms_cursor_crc (cursor-tearing-position-change)
[81.438494] Starting subtest: cursor-tearing-position-change
[81.440750] Starting dynamic subtest: pipe-A-DP-3
[81.928493] Dynamic subtest pipe-A-DP-3: SUCCESS (0.488s)
[81.949124] Starting dynamic subtest: pipe-D-DP-3
[82.386863] Dynamic subtest pipe-D-DP-3: SUCCESS (0.455s)
[82.388121] Subtest cursor-tearing-position-change: SUCCESS (0.948s)
[82.994484] [011/134] (940s left) kms_frontbuffer_tracking (basic)
[83.179482] Starting subtest: basic
[85.906370] Subtest basic: SUCCESS (2.727s)
[86.133912] [012/134] (937s left) gem_spin_batch (spin-each)
[86.212131] Starting subtest: spin-each
[89.551677] Subtest spin-each: SUCCESS (3.338s)
[89.903935] [013/134] (933s left) gem_exec_reloc (basic-gtt-cpu)
[89.977610] Subtest basic-gtt-cpu: SKIP (0.000s)
[90.047492] [014/134] (933s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier)
[90.224514] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier
[90.257096] Starting dynamic subtest: pipe-A-plane-0
[90.259073] Dynamic subtest pipe-A-plane-0: SKIP (0.000s)
[90.268204] Starting dynamic subtest: pipe-A-plane-3
[90.270621] Dynamic subtest pipe-A-plane-3: SKIP (0.000s)
[90.279432] Starting dynamic subtest: pipe-A-plane-5
[90.281218] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[90.488155] Starting dynamic subtest: pipe-B-plane-0
[90.490314] Dynamic subtest pipe-B-plane-0: SKIP (0.000s)
[90.506417] Starting dynamic subtest: pipe-B-plane-3
[90.508538] Dynamic subtest pipe-B-plane-3: SKIP (0.000s)
[90.522285] Starting dynamic subtest: pipe-B-plane-5
[90.524693] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[90.567261] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier: SKIP (0.341s)
[90.853470] [015/134] (933s left) gem_render_copy (y-tiled-ccs-to-linear)
[90.938428] Starting subtest: y-tiled-ccs-to-linear
[90.940452] Subtest y-tiled-ccs-to-linear: SKIP (0.000s)
[91.205355] [016/134] (932s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[91.393565] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[91.395255] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[91.602683] [017/134] (932s left) kms_psr (pr-sprite-render)
[91.777091] Subtest pr-sprite-render: SKIP (0.000s)
[91.829076] [018/134] (932s left) gen9_exec_parse (shadow-peek)
[91.905284] Subtest shadow-peek: SKIP (0.000s)
[91.979081] [019/134] (932s left) perf (invalid-remove-userspace-config)
[92.394740] Starting subtest: invalid-remove-userspace-config
[92.403606] Subtest invalid-remove-userspace-config: SUCCESS (0.009s)
[92.448153] [020/134] (931s left) gem_ctx_param (invalid-size-set)
[92.518379] Starting subtest: invalid-size-set
[92.520172] Subtest invalid-size-set: SUCCESS (0.001s)
[92.705528] [021/134] (931s left) kms_ccs (bad-rotation-90-y-tiled-gen12-rc-ccs-cc)
[92.883210] Starting subtest: bad-rotation-90-y-tiled-gen12-rc-ccs-cc
[92.884212] Starting dynamic subtest: pipe-A-DP-3
[92.892894] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[92.909553] Starting dynamic subtest: pipe-B-DP-3
[92.918150] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[92.926949] Starting dynamic subtest: pipe-C-DP-3
[92.935103] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[92.944201] Starting dynamic subtest: pipe-D-DP-3
[92.955163] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[92.957024] Subtest bad-rotation-90-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[93.348845] [022/134] (931s left) gem_exec_params (invalid-bsd2-flag-on-blt)
[93.426792] Starting subtest: invalid-bsd2-flag-on-blt
[93.427768] Subtest invalid-bsd2-flag-on-blt: SUCCESS (0.000s)
[93.582683] [023/134] (930s left) gem_render_copy (yf-tiled-ccs-to-linear)
[93.659483] Starting subtest: yf-tiled-ccs-to-linear
[93.661425] Subtest yf-tiled-ccs-to-linear: SKIP (0.000s)
[93.897221] [024/134] (930s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted)
[93.971256] Starting subtest: etime-multi-wait-for-submit-unsubmitted
[94.078077] Subtest etime-multi-wait-for-submit-unsubmitted: SUCCESS (0.106s)
[94.186645] [025/134] (930s left) gem_exec_fence (invalid-timeline-fence-array)
[94.936154] Starting subtest: invalid-timeline-fence-array
[94.943171] Subtest invalid-timeline-fence-array: SUCCESS (0.010s)
[95.217064] [FACT gem_exec_fence (invalid-timeline-fence-array)] new: kernel.kmod_is_loaded.vgem: true
[95.218946] [026/134] (929s left) kms_chamelium_edid (dp-edid-read)
[95.498457] Subtest dp-edid-read: SKIP (0.000s)
[95.650102] [027/134] (928s left) gem_exec_params (invalid-bsd1-flag-on-render)
[95.735319] Starting subtest: invalid-bsd1-flag-on-render
[95.736187] Subtest invalid-bsd1-flag-on-render: SUCCESS (0.000s)
[95.890524] [028/134] (928s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180)
[96.091097] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180
[97.328219] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (1.236s)
[97.559884] [029/134] (927s left) kms_ccs (crc-primary-rotation-180-y-tiled-ccs)
[97.747126] Starting subtest: crc-primary-rotation-180-y-tiled-ccs
[97.748028] Starting dynamic subtest: pipe-A-DP-3
[97.756257] Dynamic subtest pipe-A-DP-3: SKIP (0.001s)
[97.765517] Starting dynamic subtest: pipe-B-DP-3
[97.773846] Dynamic subtest pipe-B-DP-3: SKIP (0.001s)
[97.783067] Starting dynamic subtest: pipe-C-DP-3
[97.791198] Dynamic subtest pipe-C-DP-3: SKIP (0.001s)
[97.800603] Starting dynamic subtest: pipe-D-DP-3
[97.811402] Dynamic subtest pipe-D-DP-3: SKIP (0.001s)
[97.813286] Subtest crc-primary-rotation-180-y-tiled-ccs: SKIP (0.006s)
[98.239084] [030/134] (926s left) gem_eio (kms)
[98.625981] Starting subtest: kms
[129.977100] Subtest kms: SUCCESS (31.350s)
[130.368620] [031/134] (894s left) kms_cursor_legacy (torture-move)
[130.563744] Starting subtest: torture-move
[130.564587] Starting dynamic subtest: pipe-A
[136.110419] Dynamic subtest pipe-A: SUCCESS (5.541s)
[136.120772] Starting dynamic subtest: pipe-B
[141.726598] Dynamic subtest pipe-B: SUCCESS (5.620s)
[141.737520] Starting dynamic subtest: pipe-C
[147.276437] Dynamic subtest pipe-C: SUCCESS (5.549s)
[147.286424] Starting dynamic subtest: pipe-D
[152.717197] Dynamic subtest pipe-D: SUCCESS (5.438s)
[152.727469] Starting dynamic subtest: all-pipes
[158.165820] Dynamic subtest all-pipes: SUCCESS (5.450s)
[158.166631] Subtest torture-move: SUCCESS (27.601s)
[158.399897] [032/134] (866s left) kms_ccs (bad-pixel-format-yf-tiled-ccs)
[158.595679] Starting subtest: bad-pixel-format-yf-tiled-ccs
[158.596552] Starting dynamic subtest: pipe-A-DP-3
[158.605049] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[158.615121] Starting dynamic subtest: pipe-B-DP-3
[158.623604] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[158.633497] Starting dynamic subtest: pipe-C-DP-3
[158.641650] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[158.651476] Starting dynamic subtest: pipe-D-DP-3
[158.661423] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[158.663387] Subtest bad-pixel-format-yf-tiled-ccs: SKIP (0.005s)
[159.073452] [033/134] (866s left) gem_exec_capture (pi)
[159.159157] Starting subtest: pi
[159.160087] Starting dynamic subtest: rcs0
[165.135293] Dynamic subtest rcs0: SUCCESS (5.976s)
[165.145674] Starting dynamic subtest: bcs0
[170.861374] Dynamic subtest bcs0: SUCCESS (5.725s)
[170.871805] Starting dynamic subtest: vcs0
[176.602391] Dynamic subtest vcs0: SUCCESS (5.740s)
[176.613053] Starting dynamic subtest: vcs1
[182.470429] Dynamic subtest vcs1: SUCCESS (5.869s)
[182.480769] Starting dynamic subtest: vecs0
[188.421621] Dynamic subtest vecs0: SUCCESS (5.950s)
[188.432804] Starting dynamic subtest: vecs1
[194.149124] Dynamic subtest vecs1: SUCCESS (5.727s)
[194.159571] Starting dynamic subtest: ccs0
[199.920236] Dynamic subtest ccs0: SUCCESS (5.771s)
[199.921464] Subtest pi: SUCCESS (40.761s)
[200.125573] [034/134] (825s left) syncobj_timeline (wait-all-snapshot)
[200.264176] Starting subtest: wait-all-snapshot
[200.347046] Subtest wait-all-snapshot: SUCCESS (0.082s)
[200.493057] [035/134] (824s left) kms_async_flips (test-cursor-atomic)
[200.686992] Starting subtest: test-cursor-atomic
[200.918038] Starting dynamic subtest: pipe-A-DP-3
[201.228229] Dynamic subtest pipe-A-DP-3: SUCCESS (0.310s)
[201.470515] Starting dynamic subtest: pipe-B-DP-3
[201.575744] Dynamic subtest pipe-B-DP-3: SUCCESS (0.116s)
[201.788411] Starting dynamic subtest: pipe-C-DP-3
[201.903614] Dynamic subtest pipe-C-DP-3: SUCCESS (0.125s)
[202.113209] Starting dynamic subtest: pipe-D-DP-3
[202.218494] Dynamic subtest pipe-D-DP-3: SUCCESS (0.115s)
[202.219352] Subtest test-cursor-atomic: SUCCESS (1.533s)
[202.783333] [036/134] (822s left) kms_big_fb (x-tiled-32bpp-rotate-180)
[202.985014] Starting subtest: x-tiled-32bpp-rotate-180
[204.766303] Subtest x-tiled-32bpp-rotate-180: SUCCESS (1.781s)
[204.979521] [037/134] (820s left) i915_pm_freq_api (freq-reset)
[205.067075] Starting subtest: freq-reset
[205.068070] Starting dynamic subtest: gt0
[205.514515] Dynamic subtest gt0: SUCCESS (0.447s)
[205.515439] Subtest freq-reset: SUCCESS (0.448s)
[205.636471] [038/134] (819s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[205.827565] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[205.829202] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[206.031467] [039/134] (819s left) kms_ccs (random-ccs-data-y-tiled-gen12-rc-ccs-cc)
[206.218177] Starting subtest: random-ccs-data-y-tiled-gen12-rc-ccs-cc
[206.219114] Starting dynamic subtest: pipe-A-DP-3
[206.228220] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[206.238852] Starting dynamic subtest: pipe-B-DP-3
[206.247935] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[206.258389] Starting dynamic subtest: pipe-C-DP-3
[206.267375] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[206.277828] Starting dynamic subtest: pipe-D-DP-3
[206.289201] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[206.290950] Subtest random-ccs-data-y-tiled-gen12-rc-ccs-cc: SKIP (0.005s)
[206.710603] [040/134] (818s left) gem_mmap (short-mmap)
[206.788134] Subtest short-mmap: SKIP (0.000s)
[206.852349] [041/134] (818s left) vgem_basic (dmabuf-export)
[206.909298] Starting subtest: dmabuf-export
[206.946272] Subtest dmabuf-export: SUCCESS (0.038s)
[207.144442] [042/134] (818s left) gem_render_copy (y-tiled)
[207.230465] Starting subtest: y-tiled
[207.232557] Subtest y-tiled: SKIP (0.000s)
[207.509849] [043/134] (818s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-pwrite)
[207.707715] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-pwrite
[207.709388] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[207.908281] [044/134] (818s left) gem_exec_schedule (implicit-write-read)
[207.998262] Starting subtest: implicit-write-read
[207.999218] Starting dynamic subtest: rcs0
[208.299129] Dynamic subtest rcs0: SUCCESS (0.292s)
[208.309659] Starting dynamic subtest: bcs0
[208.491975] Dynamic subtest bcs0: SUCCESS (0.201s)
[208.502682] Starting dynamic subtest: vcs0
[208.699504] Dynamic subtest vcs0: SUCCESS (0.207s)
[208.710168] Starting dynamic subtest: vcs1
[208.907272] Dynamic subtest vcs1: SUCCESS (0.207s)
[208.917942] Starting dynamic subtest: vecs0
[209.118599] Dynamic subtest vecs0: SUCCESS (0.211s)
[209.129060] Starting dynamic subtest: vecs1
[209.331455] Dynamic subtest vecs1: SUCCESS (0.212s)
[209.342097] Starting dynamic subtest: ccs0
[209.547176] Dynamic subtest ccs0: SUCCESS (0.215s)
[209.548216] Subtest implicit-write-read: SUCCESS (1.549s)
[210.077494] [045/134] (815s left) kms_big_fb (4-tiled-64bpp-rotate-90)
[210.275047] Starting subtest: 4-tiled-64bpp-rotate-90
[210.326489] Subtest 4-tiled-64bpp-rotate-90: SKIP (0.051s)
[210.541386] [046/134] (815s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[210.732505] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[210.737015] Starting dynamic subtest: A-DP3
[218.826339] Dynamic subtest A-DP3: SUCCESS (8.089s)
[218.839732] Starting dynamic subtest: B-DP3
[226.859781] Dynamic subtest B-DP3: SUCCESS (8.032s)
[226.871272] Starting dynamic subtest: C-DP3
[234.889803] Dynamic subtest C-DP3: SUCCESS (8.029s)
[234.901242] Starting dynamic subtest: D-DP3
[242.897917] Dynamic subtest D-DP3: SUCCESS (8.006s)
[242.902339] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (32.170s)
[243.267578] [047/134] (782s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[243.459768] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[243.461416] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[243.633403] [048/134] (782s left) kms_color_pipeline (plane-lut1d)
[243.827529] Starting subtest: plane-lut1d
[243.858613] Starting dynamic subtest: pipe-A-DP-3
[244.242063] Dynamic subtest pipe-A-DP-3: SUCCESS (0.383s)
[244.456908] Starting dynamic subtest: pipe-B-DP-3
[244.796048] Dynamic subtest pipe-B-DP-3: SUCCESS (0.349s)
[245.009600] Starting dynamic subtest: pipe-C-DP-3
[245.347542] Dynamic subtest pipe-C-DP-3: SUCCESS (0.348s)
[245.530545] Starting dynamic subtest: pipe-D-DP-3
[245.867621] Dynamic subtest pipe-D-DP-3: SUCCESS (0.348s)
[245.929996] Subtest plane-lut1d: SUCCESS (2.101s)
[246.254835] [049/134] (780s left) i915_pm_rc6_residency (rc6-accuracy)
[246.332212] Starting subtest: rc6-accuracy
[246.333141] Starting dynamic subtest: gt0
[249.556747] Dynamic subtest gt0: FAIL (3.205s)
[249.557796] Subtest rc6-accuracy: FAIL (3.206s)
[249.686582] [050/134] (776s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[249.872125] Starting subtest: basic-flip-before-cursor-atomic
[250.913973] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.041s)
[251.239155] [051/134] (775s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move)
[251.430922] Starting subtest: psr-2p-primscrn-spr-indfb-move
[251.432512] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[251.638575] [052/134] (774s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[251.829025] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu
[251.830797] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[252.017304] [053/134] (774s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-fully-sf)
[252.211122] Subtest fbc-pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[252.262947] [054/134] (774s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-signa
[252.339473] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-signaled
[252.445555] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-signaled: SUCCESS (0.105s)
[252.618348] [055/134] (774s left) gem_mmap_wc (bad-size)
[252.696062] Subtest bad-size: SKIP (0.000s)
[252.763450] [056/134] (774s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[252.968354] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[252.970195] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[253.169693] [057/134] (773s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-msflip-blt)
[253.364663] Starting subtest: psr-2p-scndscrn-shrfb-msflip-blt
[253.366390] Subtest psr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[253.518812] [058/134] (773s left) kms_cursor_legacy (cursora-vs-flipb-atomic)
[253.707174] Starting subtest: cursorA-vs-flipB-atomic
[253.708946] Subtest cursorA-vs-flipB-atomic: SKIP (0.001s)
[253.851324] [059/134] (773s left) sysfs_heartbeat_interval (nopreempt)
[253.937181] Starting subtest: nopreempt
[253.938083] Starting dynamic subtest: vecs0
[258.277223] Dynamic subtest vecs0: SUCCESS (4.340s)
[258.289204] Starting dynamic subtest: ccs0
[262.557268] Dynamic subtest ccs0: SUCCESS (4.279s)
[262.568552] Starting dynamic subtest: vcs0
[266.781313] Dynamic subtest vcs0: SUCCESS (4.223s)
[266.792132] Starting dynamic subtest: bcs0
[270.971012] Dynamic subtest bcs0: SUCCESS (4.189s)
[270.982060] Starting dynamic subtest: vecs1
[275.200132] Dynamic subtest vecs1: SUCCESS (4.228s)
[275.210848] Starting dynamic subtest: rcs0
[279.511272] Dynamic subtest rcs0: SUCCESS (4.310s)
[279.522554] Starting dynamic subtest: vcs1
[283.720403] Dynamic subtest vcs1: SUCCESS (4.208s)
[283.721367] Subtest nopreempt: SUCCESS (29.784s)
[283.916304] [060/134] (743s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[284.113422] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip
[284.115229] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[284.325500] [061/134] (742s left) gem_wait (busy)
[284.417311] Starting subtest: busy
[284.418255] Starting dynamic subtest: all-engines
[284.993257] Dynamic subtest all-engines: SUCCESS (0.575s)
[285.004305] Starting dynamic subtest: rcs0
[285.533042] Dynamic subtest rcs0: SUCCESS (0.539s)
[285.543960] Starting dynamic subtest: bcs0
[286.072207] Dynamic subtest bcs0: SUCCESS (0.538s)
[286.083167] Starting dynamic subtest: vcs0
[286.611036] Dynamic subtest vcs0: SUCCESS (0.538s)
[286.622014] Starting dynamic subtest: vcs1
[287.150163] Dynamic subtest vcs1: SUCCESS (0.538s)
[287.161074] Starting dynamic subtest: vecs0
[287.689027] Dynamic subtest vecs0: SUCCESS (0.538s)
[287.700063] Starting dynamic subtest: vecs1
[288.228175] Dynamic subtest vecs1: SUCCESS (0.538s)
[288.239164] Starting dynamic subtest: ccs0
[288.767059] Dynamic subtest ccs0: SUCCESS (0.538s)
[288.767994] Subtest busy: SUCCESS (4.350s)
[289.088172] [062/134] (738s left) kms_setmode (clone-exclusive-crtc)
[289.168591] Starting subtest: clone-exclusive-crtc
[289.171364] Subtest clone-exclusive-crtc: SKIP (0.000s)
[289.354154] [063/134] (738s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-fully-sf)
[289.547082] Subtest pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[289.597920] [064/134] (737s left) sw_sync (sync_merge_same)
[289.647931] Starting subtest: sync_merge_same
[289.648877] Subtest sync_merge_same: SUCCESS (0.000s)
[289.774344] [065/134] (737s left) kms_hdr (static-toggle)
[289.963201] Starting subtest: static-toggle
[290.525101] Starting dynamic subtest: pipe-A-DP-3
[291.591589] Dynamic subtest pipe-A-DP-3: SUCCESS (1.067s)
[291.596054] Subtest static-toggle: SUCCESS (1.631s)
[291.959376] [066/134] (735s left) gem_exec_await (wide-contexts)
[292.234157] Starting subtest: wide-contexts
[314.193580] Subtest wide-contexts: SUCCESS (21.960s)
[314.482061] [067/134] (713s left) kms_invalid_mode (uint-max-clock)
[314.702618] Starting subtest: uint-max-clock
[314.703493] Starting dynamic subtest: pipe-A-DP-3
[314.785218] Dynamic subtest pipe-A-DP-3: SUCCESS (0.081s)
[314.800531] Starting dynamic subtest: pipe-B-DP-3
[314.801702] Dynamic subtest pipe-B-DP-3: SUCCESS (0.001s)
[314.815185] Starting dynamic subtest: pipe-C-DP-3
[314.816483] Dynamic subtest pipe-C-DP-3: SUCCESS (0.001s)
[314.829520] Starting dynamic subtest: pipe-D-DP-3
[314.830466] Dynamic subtest pipe-D-DP-3: SUCCESS (0.001s)
[314.831444] Subtest uint-max-clock: SUCCESS (0.092s)
[315.084393] [068/134] (712s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[315.280136] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[318.742953] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (3.463s)
[319.008678] [069/134] (708s left) kms_pm_rpm (modeset-non-lpsp)
[321.057994] Starting subtest: modeset-non-lpsp
[321.677435] Subtest modeset-non-lpsp: SUCCESS (0.620s)
[321.955721] [070/134] (706s left) kms_psr (fbc-pr-cursor-mmap-cpu)
[322.144704] Subtest fbc-pr-cursor-mmap-cpu: SKIP (0.000s)
[322.204074] [071/134] (705s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-wc)
[322.395965] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-wc
[322.397708] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[322.606423] [072/134] (705s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[322.801205] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[322.802998] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[322.994911] [073/134] (705s left) i915_pm_rps (thresholds-idle)
[323.081248] Starting subtest: thresholds-idle
[323.083161] Subtest thresholds-idle: SKIP (0.000s)
[323.240314] [074/134] (705s left) kms_vblank (query-idle-hang)
[323.427450] Starting subtest: query-idle-hang
[323.428518] Starting dynamic subtest: pipe-A-DP-3
[349.567927] Dynamic subtest pipe-A-DP-3: SUCCESS (26.139s)
[349.582386] Starting dynamic subtest: pipe-D-DP-3
[374.577431] Dynamic subtest pipe-D-DP-3: SUCCESS (25.005s)
[374.578304] Subtest query-idle-hang: SUCCESS (51.150s)
[374.897876] [075/134] (653s left) perf_pmu (most-busy-idle-check-all)
[374.968888] Starting subtest: most-busy-idle-check-all
[374.969911] Starting dynamic subtest: rcs0
[375.667417] Dynamic subtest rcs0: SUCCESS (0.698s)
[375.684567] Starting dynamic subtest: bcs0
[376.329811] Dynamic subtest bcs0: SUCCESS (0.660s)
[376.342781] Starting dynamic subtest: vcs0
[376.984898] Dynamic subtest vcs0: SUCCESS (0.654s)
[376.997874] Starting dynamic subtest: vcs1
[377.637910] Dynamic subtest vcs1: SUCCESS (0.652s)
[377.649728] Starting dynamic subtest: vecs0
[378.295914] Dynamic subtest vecs0: SUCCESS (0.657s)
[378.309010] Starting dynamic subtest: vecs1
[378.948951] Dynamic subtest vecs1: SUCCESS (0.652s)
[378.960650] Starting dynamic subtest: ccs0
[379.604528] Dynamic subtest ccs0: SUCCESS (0.654s)
[379.610469] Subtest most-busy-idle-check-all: SUCCESS (4.637s)
[379.782793] [076/134] (648s left) kms_atomic_interruptible (universal-setplane-cursor)
[379.974774] Starting subtest: universal-setplane-cursor
[379.977224] Starting dynamic subtest: pipe-A-DP-3
[386.279204] Dynamic subtest pipe-A-DP-3: SUCCESS (6.302s)
[386.280449] Subtest universal-setplane-cursor: SUCCESS (6.305s)
[386.624559] [077/134] (641s left) gem_userptr_blits (process-exit-busy)
[386.720337] Starting subtest: process-exit-busy
[386.736496] Subtest process-exit-busy: SUCCESS (0.016s)
[387.030677] [078/134] (641s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite)
[387.222300] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite
[387.224131] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[387.420397] [079/134] (641s left) gem_lmem_swapping (heavy-verify-multi)
[390.220766] Starting subtest: heavy-verify-multi
[390.224986] Starting dynamic subtest: lmem0
[403.931361] Dynamic subtest lmem0: SUCCESS (13.709s)
[403.932328] Subtest heavy-verify-multi: SUCCESS (13.711s)
[405.729817] [FACT gem_lmem_swapping (heavy-verify-multi)] deleted: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[405.730954] [FACT gem_lmem_swapping (heavy-verify-multi)] deleted: kernel.kmod_is_loaded.i915: true
[405.732926] [080/134] (623s left) gen9_exec_parse (bb-start-cmd)
[407.337991] Subtest bb-start-cmd: SKIP (0.000s)
[407.392824] [FACT gen9_exec_parse (bb-start-cmd)] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[407.395241] [FACT gen9_exec_parse (bb-start-cmd)] new: kernel.kmod_is_loaded.i915: true
[407.398585] [081/134] (621s left) fbdev (unaligned-read)
[407.455192] Starting subtest: unaligned-read
[407.468740] Subtest unaligned-read: SUCCESS (0.014s)
[407.591056] [082/134] (621s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[407.866954] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[408.019169] [083/134] (621s left) gem_ppgtt (blt-vs-render-ctx0)
[408.107345] Starting subtest: blt-vs-render-ctx0
[440.856127] Subtest blt-vs-render-ctx0: SUCCESS (32.748s)
[441.054564] [084/134] (588s left) core_setmaster_vs_auth
[441.376793] [085/134] (587s left) prime_busy (hang)
[441.465563] Starting subtest: hang
[441.467945] Starting dynamic subtest: rcs0
[467.502900] Dynamic subtest rcs0: SUCCESS (26.034s)
[467.513546] Starting dynamic subtest: bcs0
[474.649551] Dynamic subtest bcs0: SUCCESS (7.144s)
[474.659975] Starting dynamic subtest: vcs0
[481.637865] Dynamic subtest vcs0: SUCCESS (6.988s)
[481.648545] Starting dynamic subtest: vcs1
[488.602773] Dynamic subtest vcs1: SUCCESS (6.962s)
[488.613461] Starting dynamic subtest: vecs0
[495.638193] Dynamic subtest vecs0: SUCCESS (7.036s)
[495.650127] Starting dynamic subtest: vecs1
[502.618198] Dynamic subtest vecs1: SUCCESS (6.979s)
[502.628688] Starting dynamic subtest: ccs0
[516.446167] Dynamic subtest ccs0: SUCCESS (13.827s)
[516.448435] Subtest hang: SUCCESS (74.981s)
[516.630868] [086/134] (512s left) gem_eio (unwedge-stress)
[516.875412] Starting subtest: unwedge-stress
[516.876327] Starting dynamic subtest: render
[534.961158] Dynamic subtest render: SUCCESS (18.084s)
[534.973293] Starting dynamic subtest: bsd1
[550.951915] Dynamic subtest bsd1: SUCCESS (15.989s)
[550.963279] Starting dynamic subtest: bsd2
[565.928552] Dynamic subtest bsd2: SUCCESS (14.975s)
[565.945205] Starting dynamic subtest: blt
[581.922496] Dynamic subtest blt: SUCCESS (15.991s)
[581.934143] Starting dynamic subtest: vebox
[599.936817] Dynamic subtest vebox: SUCCESS (18.011s)
[599.938394] Subtest unwedge-stress: SUCCESS (83.060s)
[600.291262] [087/134] (429s left) kms_cursor_crc (cursor-random-256x256)
[600.715107] Starting subtest: cursor-random-256x256
[600.720141] Starting dynamic subtest: pipe-A-DP-3
[605.350936] Dynamic subtest pipe-A-DP-3: SUCCESS (4.629s)
[605.397501] Starting dynamic subtest: pipe-D-DP-3
[609.875960] Dynamic subtest pipe-D-DP-3: SUCCESS (4.489s)
[609.876953] Subtest cursor-random-256x256: SUCCESS (9.160s)
[610.260364] [088/134] (419s left) syncobj_timeline (multi-wait-submitted-signaled)
[610.350280] Starting subtest: multi-wait-submitted-signaled
[610.351186] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s)
[610.472737] [089/134] (419s left) kms_dsc (dsc-with-formats)
[610.667610] Starting subtest: dsc-with-formats
[610.683063] Subtest dsc-with-formats: SKIP (0.002s)
[610.801706] [090/134] (418s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[610.999943] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-wc
[611.001611] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[611.164962] [091/134] (418s left) api_intel_allocator (reopen-fork)
[611.245264] Starting subtest: reopen-fork
[614.606491] Subtest reopen-fork: SUCCESS (3.361s)
[614.749747] [092/134] (414s left) gem_exec_whisper (basic-fds-priority-all)
[614.838450] Starting subtest: basic-fds-priority-all
[637.585147] Subtest basic-fds-priority-all: SUCCESS (22.744s)
[637.844345] [093/134] (391s left) gem_exec_fence (basic-wait-all)
[637.938666] Starting subtest: basic-wait-all
[638.059854] Subtest basic-wait-all: SUCCESS (0.121s)
[638.983067] [094/134] (390s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[639.174277] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[639.175189] Starting dynamic subtest: pipe-A-DP-3
[639.196376] Dynamic subtest pipe-A-DP-3: SKIP (0.002s)
[639.208163] Starting dynamic subtest: pipe-B-DP-3
[639.229453] Dynamic subtest pipe-B-DP-3: SKIP (0.002s)
[639.241316] Starting dynamic subtest: pipe-C-DP-3
[639.266450] Dynamic subtest pipe-C-DP-3: SKIP (0.002s)
[639.278136] Starting dynamic subtest: pipe-D-DP-3
[639.300107] Dynamic subtest pipe-D-DP-3: SKIP (0.002s)
[639.301613] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.015s)
[639.657551] [095/134] (390s left) kms_psr (fbc-psr2-no-drrs)
[639.845406] Subtest fbc-psr2-no-drrs: SKIP (0.000s)
[639.902267] [096/134] (390s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[640.100166] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[640.101895] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[640.295895] [097/134] (389s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-pwrite)
[640.486299] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-pwrite
[640.487999] Subtest fbc-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[640.666735] [098/134] (389s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[640.858123] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[644.058135] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (3.200s)
[644.259780] [099/134] (386s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[644.447602] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[644.448485] Starting dynamic subtest: pipe-A
[644.557077] Dynamic subtest pipe-A: SUCCESS (0.109s)
[644.579184] Starting dynamic subtest: pipe-B
[644.786330] Dynamic subtest pipe-B: SUCCESS (0.228s)
[644.801374] Starting dynamic subtest: pipe-C
[644.981805] Dynamic subtest pipe-C: SUCCESS (0.194s)
[645.003677] Starting dynamic subtest: pipe-D
[645.193822] Dynamic subtest pipe-D: SUCCESS (0.210s)
[645.194871] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SUCCESS (0.747s)
[645.526532] [100/134] (384s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-upscaling)
[645.717821] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-upscaling
[645.719429] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-upscaling: SKIP (0.000s)
[645.871732] [101/134] (384s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[646.058389] Starting subtest: flip-vs-cursor-busy-crc-atomic
[646.847989] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.790s)
[647.186103] [102/134] (383s left) core_getstats (basic)
[647.264358] Starting subtest: basic
[647.265286] Subtest basic: SUCCESS (0.000s)
[647.451802] [103/134] (383s left) gem_mmap_gtt (hang-busy)
[647.531143] Subtest hang-busy: SKIP (0.000s)
[647.645997] [104/134] (383s left) kms_flip (flip-vs-expired-vblank-interruptible)
[647.835756] Starting subtest: flip-vs-expired-vblank-interruptible
[647.840734] Starting dynamic subtest: A-DP3
[655.646388] Dynamic subtest A-DP3: SUCCESS (7.805s)
[655.669300] Starting dynamic subtest: B-DP3
[663.413548] Dynamic subtest B-DP3: SUCCESS (7.765s)
[663.427539] Starting dynamic subtest: C-DP3
[671.163546] Dynamic subtest C-DP3: SUCCESS (7.747s)
[671.179464] Starting dynamic subtest: D-DP3
[678.907532] Dynamic subtest D-DP3: SUCCESS (7.738s)
[678.912924] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (31.076s)
[679.222628] [105/134] (351s left) gem_exec_schedule (preempt-queue-contexts)
[679.317461] Subtest preempt-queue-contexts: SKIP (0.000s)
[679.728838] [106/134] (351s left) perf (oa-formats)
[680.131608] Starting subtest: oa-formats
[680.132452] Starting dynamic subtest: 0-rcs0
[680.155133] Dynamic subtest 0-rcs0: SUCCESS (0.023s)
[680.156047] Subtest oa-formats: SUCCESS (0.025s)
[680.218710] [107/134] (350s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy)
[680.409919] Starting subtest: fbcpsr-modesetfrombusy
[680.411649] Subtest fbcpsr-modesetfrombusy: SKIP (0.000s)
[680.576326] [108/134] (350s left) kms_flip (2x-dpms-vs-vblank-race)
[680.767619] Starting subtest: 2x-dpms-vs-vblank-race
[680.781496] Subtest 2x-dpms-vs-vblank-race: SKIP (0.014s)
[680.936667] [109/134] (350s left) gem_exec_params (readonly)
[681.022158] Starting subtest: readonly
[681.024358] Subtest readonly: SUCCESS (0.002s)
[681.237099] [110/134] (349s left) kms_vblank (invalid)
[681.424429] Starting subtest: invalid
[681.425294] Starting dynamic subtest: pipe-A-DP-3
[681.770406] Dynamic subtest pipe-A-DP-3: SUCCESS (0.343s)
[681.771635] Subtest invalid: SUCCESS (0.346s)
[682.052258] [111/134] (349s left) gem_exec_reloc (basic-cpu-read-noreloc)
[682.137750] Subtest basic-cpu-read-noreloc: SKIP (0.000s)
[682.212791] [112/134] (349s left) gem_ctx_persistence (engines-hostile)
[682.301174] Starting subtest: engines-hostile
[682.302137] Starting dynamic subtest: rcs0
[682.421472] Dynamic subtest rcs0: SUCCESS (0.120s)
[682.444762] Starting dynamic subtest: bcs0
[682.522549] Dynamic subtest bcs0: SUCCESS (0.098s)
[682.568901] Starting dynamic subtest: vcs0
[682.583234] Dynamic subtest vcs0: SUCCESS (0.056s)
[682.602480] Starting dynamic subtest: vcs1
[682.686385] Dynamic subtest vcs1: SUCCESS (0.101s)
[682.735588] Starting dynamic subtest: vecs0
[682.752267] Dynamic subtest vecs0: SUCCESS (0.061s)
[682.770977] Starting dynamic subtest: vecs1
[682.851991] Dynamic subtest vecs1: SUCCESS (0.098s)
[682.901060] Starting dynamic subtest: ccs0
[682.931364] Dynamic subtest ccs0: SUCCESS (0.075s)
[682.932394] Subtest engines-hostile: SUCCESS (0.631s)
[683.135755] [113/134] (348s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-gtt)
[683.326988] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-gtt
[683.328697] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[683.484561] [114/134] (347s left) kms_frontbuffer_tracking (fbcpsr-2p-shrfb-fliptrack-mmap-gtt)
[683.689894] Starting subtest: fbcpsr-2p-shrfb-fliptrack-mmap-gtt
[683.691504] Subtest fbcpsr-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[683.902745] [115/134] (347s left) gem_caching (writes)
[683.987212] Starting subtest: writes
[688.218992] Subtest writes: SUCCESS (4.232s)
[688.413064] [116/134] (343s left) kms_pm_backlight (fade-with-suspend)
[688.599998] Subtest fade-with-suspend: SKIP (0.000s)
[688.663735] [117/134] (343s left) kms_plane_alpha_blend (coverage-vs-premult-vs-constant)
[688.857284] Starting subtest: coverage-vs-premult-vs-constant
[689.117473] Starting dynamic subtest: pipe-A-DP-3
[689.614746] Dynamic subtest pipe-A-DP-3: SUCCESS (0.495s)
[689.640363] Starting dynamic subtest: pipe-D-DP-3
[690.155978] Dynamic subtest pipe-D-DP-3: SUCCESS (0.536s)
[690.156926] Subtest coverage-vs-premult-vs-constant: SUCCESS (1.300s)
[690.522108] [118/134] (341s left) kms_lease (master-vs-lease)
[690.708677] Starting subtest: master-vs-lease
[690.711227] Subtest master-vs-lease: SUCCESS (0.002s)
[690.919321] [119/134] (340s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[691.115157] Starting subtest: psr-rgb565-draw-mmap-wc
[691.116981] Subtest psr-rgb565-draw-mmap-wc: SKIP (0.000s)
[691.304256] [120/134] (340s left) kms_addfb_basic (addfb25-4-tiled)
[691.495408] Starting subtest: addfb25-4-tiled
[691.496275] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[691.644399] [121/134] (340s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling)
[691.840296] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling
[691.842054] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.000s)
[692.005526] [122/134] (340s left) kms_plane_multiple (2x-tiling-x)
[692.194782] Starting subtest: 2x-tiling-x
[692.196453] Subtest 2x-tiling-x: SKIP (0.000s)
[692.319486] [123/134] (339s left) kms_psr (psr2-primary-render)
[692.506425] Subtest psr2-primary-render: SKIP (0.000s)
[692.564602] [124/134] (339s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled)
[692.642765] Starting subtest: multi-wait-all-for-submit-submitted-signaled
[692.755530] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.106s)
[692.956406] [125/134] (339s left) gem_exec_balancer (full)
[693.054763] Starting subtest: full
[695.765510] Subtest full: SUCCESS (2.711s)
[696.200705] [126/134] (336s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-render)
[696.393012] Starting subtest: psr-2p-primscrn-spr-indfb-draw-render
[696.394744] Subtest psr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[696.586021] [127/134] (335s left) gem_mmap_wc (write-read)
[696.663173] Subtest write-read: SKIP (0.000s)
[696.734060] [128/134] (335s left) gem_ctx_persistence (process)
[696.828567] Starting subtest: process
[697.045289] Subtest process: SUCCESS (0.216s)
[697.238542] [129/134] (335s left) gem_ctx_param (non-root-set)
[697.315806] Starting subtest: non-root-set
[697.332260] Subtest non-root-set: SUCCESS (0.016s)
[697.467908] [130/134] (335s left) sw_sync (timeline_closed)
[697.515995] Starting subtest: timeline_closed
[697.516890] Subtest timeline_closed: SUCCESS (0.000s)
[697.623958] [131/134] (335s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[697.814926] Starting subtest: primary-4-tiled-reflect-x-180
[708.185612] Subtest primary-4-tiled-reflect-x-180: SUCCESS (10.370s)
[708.611096] [132/134] (324s left) gem_exec_parallel (basic)
[708.700960] Starting subtest: basic
[708.701965] Starting dynamic subtest: rcs0
[710.389658] Dynamic subtest rcs0: SUCCESS (1.688s)
[710.405372] Starting dynamic subtest: bcs0
[711.865368] Dynamic subtest bcs0: SUCCESS (1.474s)
[711.881568] Starting dynamic subtest: vcs0
[713.359562] Dynamic subtest vcs0: SUCCESS (1.493s)
[713.376063] Starting dynamic subtest: vcs1
[714.844425] Dynamic subtest vcs1: SUCCESS (1.483s)
[714.859874] Starting dynamic subtest: vecs0
[716.319297] Dynamic subtest vecs0: SUCCESS (1.473s)
[716.335843] Starting dynamic subtest: vecs1
[717.810598] Dynamic subtest vecs1: SUCCESS (1.490s)
[717.827654] Starting dynamic subtest: ccs0
[719.280196] Dynamic subtest ccs0: SUCCESS (1.468s)
[719.281206] Subtest basic: SUCCESS (10.580s)
[719.632902] [133/134] (313s left) kms_content_protection (uevent-hdcp14)
[719.886129] Starting subtest: uevent-hdcp14
[719.912409] Starting dynamic subtest: pipe-A-DP-3
[754.175953] Dynamic subtest pipe-A-DP-3: FAIL (34.211s)
[754.224573] Subtest uevent-hdcp14: FAIL (34.337s)
[754.505538] [134/134] (278s left) syncobj_timeline (multi-wait-all-for-submit-available-signaled)
[754.584433] Starting subtest: multi-wait-all-for-submit-available-signaled
[754.585411] Subtest multi-wait-all-for-submit-available-signaled: SUCCESS (0.001s)
[754.735984] Closing watchdogs
results: parsing output: 0/ for test: kms_cursor_crc
results: parsing output: 1/ for test: gem_mmap_gtt
results: parsing output: 2/ for test: gem_ctx_engines
results: parsing output: 3/ for test: perf_pmu
results: parsing output: 4/ for test: kms_big_fb
results: parsing output: 5/ for test: api_intel_bb
results: parsing output: 6/ for test: gem_render_copy
results: parsing output: 7/ for test: kms_plane
results: parsing output: 8/ for test: kms_psr
results: parsing output: 9/ for test: kms_cursor_crc
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: gem_spin_batch
results: parsing output: 12/ for test: gem_exec_reloc
results: parsing output: 13/ for test: kms_plane
results: parsing output: 14/ for test: gem_render_copy
results: parsing output: 15/ for test: kms_big_fb
results: parsing output: 16/ for test: kms_psr
results: parsing output: 17/ for test: gen9_exec_parse
results: parsing output: 18/ for test: perf
results: parsing output: 19/ for test: gem_ctx_param
results: parsing output: 20/ for test: kms_ccs
results: parsing output: 21/ for test: gem_exec_params
results: parsing output: 22/ for test: gem_render_copy
results: parsing output: 23/ for test: syncobj_timeline
results: parsing output: 24/ for test: gem_exec_fence
results: parsing output: 25/ for test: kms_chamelium_edid
results: parsing output: 26/ for test: gem_exec_params
results: parsing output: 27/ for test: kms_big_fb
results: parsing output: 28/ for test: kms_ccs
results: parsing output: 29/ for test: gem_eio
results: parsing output: 30/ for test: kms_cursor_legacy
results: parsing output: 31/ for test: kms_ccs
results: parsing output: 32/ for test: gem_exec_capture
results: parsing output: 33/ for test: syncobj_timeline
results: parsing output: 34/ for test: kms_async_flips
results: parsing output: 35/ for test: kms_big_fb
results: parsing output: 36/ for test: i915_pm_freq_api
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_ccs
results: parsing output: 39/ for test: gem_mmap
results: parsing output: 40/ for test: vgem_basic
results: parsing output: 41/ for test: gem_render_copy
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: gem_exec_schedule
results: parsing output: 44/ for test: kms_big_fb
results: parsing output: 45/ for test: kms_flip
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_color_pipeline
results: parsing output: 48/ for test: i915_pm_rc6_residency
results: parsing output: 49/ for test: kms_cursor_legacy
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_psr2_sf
results: parsing output: 53/ for test: syncobj_timeline
results: parsing output: 54/ for test: gem_mmap_wc
results: parsing output: 55/ for test: kms_big_fb
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_cursor_legacy
results: parsing output: 58/ for test: sysfs_heartbeat_interval
results: parsing output: 59/ for test: kms_big_fb
results: parsing output: 60/ for test: gem_wait
results: parsing output: 61/ for test: kms_setmode
results: parsing output: 62/ for test: kms_psr2_sf
results: parsing output: 63/ for test: sw_sync
results: parsing output: 64/ for test: kms_hdr
results: parsing output: 65/ for test: gem_exec_await
results: parsing output: 66/ for test: kms_invalid_mode
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_pm_rpm
results: parsing output: 69/ for test: kms_psr
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: i915_pm_rps
results: parsing output: 73/ for test: kms_vblank
results: parsing output: 74/ for test: perf_pmu
results: parsing output: 75/ for test: kms_atomic_interruptible
results: parsing output: 76/ for test: gem_userptr_blits
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: gem_lmem_swapping
results: parsing output: 79/ for test: gen9_exec_parse
results: parsing output: 80/ for test: fbdev
results: parsing output: 81/ for test: kms_chamelium_hpd
results: parsing output: 82/ for test: gem_ppgtt
results: parsing output: 83/ for test: core_setmaster_vs_auth
results: parsing output: 84/ for test: prime_busy
results: parsing output: 85/ for test: gem_eio
results: parsing output: 86/ for test: kms_cursor_crc
results: parsing output: 87/ for test: syncobj_timeline
results: parsing output: 88/ for test: kms_dsc
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: api_intel_allocator
results: parsing output: 91/ for test: gem_exec_whisper
results: parsing output: 92/ for test: gem_exec_fence
results: parsing output: 93/ for test: kms_ccs
results: parsing output: 94/ for test: kms_psr
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_plane_scaling
results: parsing output: 99/ for test: kms_flip_scaled_crc
results: parsing output: 100/ for test: kms_cursor_legacy
results: parsing output: 101/ for test: core_getstats
results: parsing output: 102/ for test: gem_mmap_gtt
results: parsing output: 103/ for test: kms_flip
results: parsing output: 104/ for test: gem_exec_schedule
results: parsing output: 105/ for test: perf
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_flip
results: parsing output: 108/ for test: gem_exec_params
results: parsing output: 109/ for test: kms_vblank
results: parsing output: 110/ for test: gem_exec_reloc
results: parsing output: 111/ for test: gem_ctx_persistence
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: gem_caching
results: parsing output: 115/ for test: kms_pm_backlight
results: parsing output: 116/ for test: kms_plane_alpha_blend
results: parsing output: 117/ for test: kms_lease
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_addfb_basic
results: parsing output: 120/ for test: kms_flip_scaled_crc
results: parsing output: 121/ for test: kms_plane_multiple
results: parsing output: 122/ for test: kms_psr
results: parsing output: 123/ for test: syncobj_wait
results: parsing output: 124/ for test: gem_exec_balancer
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: gem_mmap_wc
results: parsing output: 127/ for test: gem_ctx_persistence
results: parsing output: 128/ for test: gem_ctx_param
results: parsing output: 129/ for test: sw_sync
results: parsing output: 130/ for test: kms_rotation_crc
results: parsing output: 131/ for test: gem_exec_parallel
results: parsing output: 132/ for test: kms_content_protection
results: parsing output: 133/ for test: syncobj_timeline
Done.