[58.901910] Initializing watchdogs
[58.901957]   /dev/watchdog0
[58.916341] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[58.920577] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.922846] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.926615] [001/132] (960s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[59.062150] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[59.064614] Starting dynamic subtest: pipe-A-eDP-1
[59.941219] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.866s)
[59.950386] Starting dynamic subtest: pipe-B-eDP-1
[61.520532] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.569s)
[61.528889] Starting dynamic subtest: pipe-C-eDP-1
[63.060039] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.529s)
[63.067360] Starting dynamic subtest: pipe-D-eDP-1
[64.560450] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.494s)
[64.562943] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.462s)
[65.727931] [002/132] (953s left) gem_pipe_control_store_loop (reused-buffer)
[65.807978] Starting subtest: reused-buffer
[67.967355] Subtest reused-buffer: SUCCESS (2.153s)
[68.140834] [003/132] (951s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[68.239891] Starting subtest: extended-modeset-hang-oldfb-with-reset
[68.242247] Starting dynamic subtest: pipe-A
[80.131513] Dynamic subtest pipe-A: SUCCESS (11.880s)
[80.140926] Starting dynamic subtest: pipe-D
[92.619086] Dynamic subtest pipe-D: SUCCESS (12.476s)
[92.621531] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (24.360s)
[93.411425] [004/132] (926s left) gem_userptr_blits (dmabuf-unsync)
[93.480226] Subtest dmabuf-unsync: SKIP (0.000s)
[93.608339] [005/132] (925s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[93.655636] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[93.657778] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[93.750219] [006/132] (925s left) vgem_basic (busy-fence)
[93.854210] Starting subtest: busy-fence
[93.856514] Subtest busy-fence: SUCCESS (0.001s)
[93.926407] [FACT vgem_basic (busy-fence)] new: kernel.kmod_is_loaded.vgem: true
[93.929793] [007/132] (925s left) gem_exec_balancer (parallel-ordering)
[94.190744] Starting subtest: parallel-ordering
[95.133506] Subtest parallel-ordering: SUCCESS (0.942s)
[95.261214] [008/132] (924s left) gem_pxp (display-protected-crc)
[95.385056] Starting subtest: display-protected-crc
[97.215491] Subtest display-protected-crc: SUCCESS (1.831s)
[97.332155] [009/132] (922s left) gem_exec_params (rel-constants-invalid-rel-gen5)
[97.399627] Starting subtest: rel-constants-invalid-rel-gen5
[97.401977] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s)
[97.514498] [010/132] (922s left) kms_prime (d3hot)
[97.596221] Subtest D3hot: SKIP (0.000s)
[97.714621] [011/132] (922s left) syncobj_timeline (etime-multi-wait-for-submit-submitted)
[97.767454] Starting subtest: etime-multi-wait-for-submit-submitted
[97.874302] Subtest etime-multi-wait-for-submit-submitted: SUCCESS (0.106s)
[97.950951] [012/132] (921s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[98.039060] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[98.057506] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.017s)
[98.129352] [013/132] (921s left) prime_self_import (export-vs-gem_close-race)
[98.161377] Starting subtest: export-vs-gem_close-race
[103.251377] Subtest export-vs-gem_close-race: SUCCESS (5.088s)
[103.336996] [014/132] (916s left) kms_content_protection (dp-mst-lic-type-0-hdcp14)
[103.494844] Starting subtest: dp-mst-lic-type-0-hdcp14
[103.498981] Subtest dp-mst-lic-type-0-hdcp14: SKIP (0.000s)
[103.623571] [015/132] (916s left) gem_busy (busy)
[103.686075] Starting subtest: busy
[103.688387] Starting dynamic subtest: all-engines
[103.762955] Dynamic subtest all-engines: SUCCESS (0.080s)
[103.774935] Starting dynamic subtest: rcs0
[104.861703] Dynamic subtest rcs0: SUCCESS (1.097s)
[104.872254] Starting dynamic subtest: bcs0
[104.907167] Dynamic subtest bcs0: SUCCESS (0.044s)
[104.916053] Starting dynamic subtest: vcs0
[104.944544] Dynamic subtest vcs0: SUCCESS (0.038s)
[104.953256] Starting dynamic subtest: vcs1
[106.907871] Dynamic subtest vcs1: SUCCESS (1.959s)
[106.917961] Starting dynamic subtest: vecs0
[108.893106] Dynamic subtest vecs0: SUCCESS (1.984s)
[108.903401] Starting dynamic subtest: ccs0
[110.938246] Dynamic subtest ccs0: SUCCESS (2.045s)
[110.940497] Subtest busy: SUCCESS (7.251s)
[111.285680] [016/132] (908s left) gem_mmap_wc (write-read)
[111.343287] Subtest write-read: SKIP (0.000s)
[111.397833] [017/132] (908s left) kms_psr (fbc-pr-primary-page-flip)
[111.489913] Starting subtest: fbc-pr-primary-page-flip
[111.494367] Subtest fbc-pr-primary-page-flip: SKIP (0.000s)
[111.573416] [018/132] (908s left) vgem_basic (mmap)
[111.628330] Starting subtest: mmap
[111.632797] Subtest mmap: SUCCESS (0.007s)
[111.685217] [019/132] (908s left) gem_exec_reloc (basic-wc)
[111.735457] Subtest basic-wc: SKIP (0.000s)
[111.818705] [020/132] (908s left) gem_vm_create (execbuf)
[111.872705] Starting subtest: execbuf
[111.879596] Subtest execbuf: SUCCESS (0.006s)
[111.992491] [021/132] (908s left) gem_exec_balancer (nohangcheck)
[112.237758] Starting subtest: nohangcheck
[113.127577] Subtest nohangcheck: SUCCESS (0.889s)
[113.272431] [022/132] (907s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-gtt)
[113.360551] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-gtt
[113.364937] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[113.446114] [023/132] (906s left) gem_exec_fence (submit3)
[113.624812] Starting subtest: submit3
[113.629452] Subtest submit3: SKIP (0.000s)
[114.994239] [024/132] (905s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[115.099689] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[115.103800] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[115.200308] [025/132] (905s left) kms_color_pipeline (plane-lut1d-ctm3x4)
[115.296931] Starting subtest: plane-lut1d-ctm3x4
[115.442078] Starting dynamic subtest: pipe-A-plane-0
[115.631318] Dynamic subtest pipe-A-plane-0: SUCCESS (0.189s)
[115.640342] Starting dynamic subtest: pipe-A-plane-1
[115.798115] Dynamic subtest pipe-A-plane-1: SUCCESS (0.166s)
[115.806534] Starting dynamic subtest: pipe-A-plane-2
[115.953070] Dynamic subtest pipe-A-plane-2: SUCCESS (0.155s)
[117.159066] Starting dynamic subtest: pipe-B-plane-0
[117.352068] Dynamic subtest pipe-B-plane-0: SUCCESS (0.200s)
[117.368981] Starting dynamic subtest: pipe-B-plane-1
[117.518988] Dynamic subtest pipe-B-plane-1: SUCCESS (0.166s)
[117.528623] Starting dynamic subtest: pipe-B-plane-2
[117.652585] Dynamic subtest pipe-B-plane-2: SUCCESS (0.133s)
[118.835073] Starting dynamic subtest: pipe-C-plane-0
[118.972237] Dynamic subtest pipe-C-plane-0: SUCCESS (0.144s)
[118.982832] Starting dynamic subtest: pipe-C-plane-1
[119.138585] Dynamic subtest pipe-C-plane-1: SUCCESS (0.166s)
[119.147031] Starting dynamic subtest: pipe-C-plane-2
[119.305478] Dynamic subtest pipe-C-plane-2: SUCCESS (0.166s)
[120.505727] Starting dynamic subtest: pipe-D-plane-0
[120.666321] Dynamic subtest pipe-D-plane-0: SUCCESS (0.167s)
[120.675137] Starting dynamic subtest: pipe-D-plane-1
[120.820617] Dynamic subtest pipe-D-plane-1: SUCCESS (0.155s)
[120.833943] Starting dynamic subtest: pipe-D-plane-2
[120.986749] Dynamic subtest pipe-D-plane-2: SUCCESS (0.166s)
[121.275234] Subtest plane-lut1d-ctm3x4: SUCCESS (5.978s)
[122.157193] [026/132] (898s left) syncobj_wait (invalid-reset-illegal-handle)
[122.217699] Starting subtest: invalid-reset-illegal-handle
[122.220034] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[122.356335] [027/132] (898s left) i915_suspend (debugfs-reader)
[122.412177] Starting subtest: debugfs-reader
[140.046067] Subtest debugfs-reader: SUCCESS (3.283s)
[140.094712] [028/132] (880s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-pwrite)
[140.205081] Starting subtest: psr-2p-primscrn-pri-indfb-draw-pwrite
[140.209403] Subtest psr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[140.317241] [029/132] (880s left) kms_psr (fbc-pr-cursor-blt)
[140.401161] Starting subtest: fbc-pr-cursor-blt
[140.405480] Subtest fbc-pr-cursor-blt: SKIP (0.000s)
[140.474814] [030/132] (880s left) perf (rc6-disable)
[140.594394] Starting subtest: rc6-disable
[140.774069] Subtest rc6-disable: SUCCESS (0.173s)
[140.828079] [031/132] (879s left) gem_softpin (overlap)
[140.893555] Starting subtest: overlap
[141.805840] Subtest overlap: SUCCESS (0.887s)
[141.944883] [032/132] (878s left) kms_ccs (bad-rotation-90-4-tiled-mtl-mc-ccs)
[142.034668] Starting subtest: bad-rotation-90-4-tiled-mtl-mc-ccs
[142.036849] Starting dynamic subtest: pipe-A-eDP-1
[142.484027] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.438s)
[142.495549] Starting dynamic subtest: pipe-B-eDP-1
[142.516921] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.003s)
[142.532400] Starting dynamic subtest: pipe-C-eDP-1
[142.552580] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.006s)
[142.561370] Starting dynamic subtest: pipe-D-eDP-1
[142.580458] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[142.582641] Subtest bad-rotation-90-4-tiled-mtl-mc-ccs: SUCCESS (0.455s)
[144.851230] [033/132] (875s left) kms_tv_load_detect (load-detect)
[144.907675] Subtest load-detect: SKIP (0.000s)
[144.971782] [034/132] (875s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[145.070339] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[145.074664] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[145.189929] [035/132] (875s left) drm_read (empty-nonblock)
[145.345159] Starting subtest: empty-nonblock
[145.347524] Subtest empty-nonblock: SUCCESS (0.000s)
[145.489675] [036/132] (875s left) kms_psr (psr2-cursor-mmap-gtt)
[145.584678] Starting subtest: psr2-cursor-mmap-gtt
[145.586969] Starting dynamic subtest: eDP-1
[147.341378] Dynamic subtest eDP-1: SUCCESS (1.730s)
[147.343983] Subtest psr2-cursor-mmap-gtt: SUCCESS (1.730s)
[148.130223] [037/132] (872s left) kms_hdr (brightness-with-hdr)
[148.219936] Starting subtest: brightness-with-hdr
[148.226696] Subtest brightness-with-hdr: SKIP (0.000s)
[148.292511] [038/132] (872s left) kms_plane_cursor (viewport)
[148.386043] Starting subtest: viewport
[148.390693] Starting dynamic subtest: pipe-A-eDP-1-size-64
[150.886650] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.477s)
[150.952309] Starting dynamic subtest: pipe-A-eDP-1-size-128
[153.384037] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.427s)
[153.444147] Starting dynamic subtest: pipe-A-eDP-1-size-256
[156.010550] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.561s)
[156.364463] Starting dynamic subtest: pipe-B-eDP-1-size-64
[159.592935] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.228s)
[159.655597] Starting dynamic subtest: pipe-B-eDP-1-size-128
[162.224342] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.571s)
[162.281919] Starting dynamic subtest: pipe-B-eDP-1-size-256
[164.773174] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.493s)
[165.124655] Starting dynamic subtest: pipe-C-eDP-1-size-64
[168.087923] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.971s)
[168.124887] Starting dynamic subtest: pipe-C-eDP-1-size-128
[170.280798] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.159s)
[170.319034] Starting dynamic subtest: pipe-C-eDP-1-size-256
[172.413130] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.102s)
[172.747749] Starting dynamic subtest: pipe-D-eDP-1-size-64
[175.616104] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.880s)
[175.653830] Starting dynamic subtest: pipe-D-eDP-1-size-128
[177.794390] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.148s)
[177.831560] Starting dynamic subtest: pipe-D-eDP-1-size-256
[179.972522] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.147s)
[180.289501] Subtest viewport: SUCCESS (31.811s)
[181.142548] [039/132] (839s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-sf)
[181.264453] Starting subtest: fbc-psr2-cursor-plane-move-continuous-sf
[181.266877] Starting dynamic subtest: pipe-A-eDP-1
[181.338650] Dynamic subtest pipe-A-eDP-1: SKIP (0.079s)
[181.349844] Starting dynamic subtest: pipe-B-eDP-1
[182.426640] Dynamic subtest pipe-B-eDP-1: SKIP (1.086s)
[182.430895] Subtest fbc-psr2-cursor-plane-move-continuous-sf: SKIP (1.168s)
[183.614062] [040/132] (837s left) kms_plane_scaling (plane-downscale-factor-0-75-with-pixel-format)
[183.720401] Starting subtest: plane-downscale-factor-0-75-with-pixel-format
[183.722619] Starting dynamic subtest: pipe-A
[184.881206] Dynamic subtest pipe-A: SUCCESS (1.163s)
[184.892033] Starting dynamic subtest: pipe-B
[187.128839] Dynamic subtest pipe-B: SUCCESS (2.246s)
[187.139372] Starting dynamic subtest: pipe-C
[188.334116] Dynamic subtest pipe-C: SUCCESS (1.204s)
[188.343646] Starting dynamic subtest: pipe-D
[189.545105] Dynamic subtest pipe-D: SUCCESS (1.209s)
[189.547458] Subtest plane-downscale-factor-0-75-with-pixel-format: SUCCESS (5.826s)
[190.748257] [041/132] (830s left) kms_big_fb (yf-tiled-32bpp-rotate-0)
[190.862119] Starting subtest: yf-tiled-32bpp-rotate-0
[190.866772] Subtest yf-tiled-32bpp-rotate-0: SKIP (0.000s)
[190.979133] [042/132] (830s left) kms_atomic (plane-primary-overlay-mutable-zpos)
[191.067027] Starting subtest: plane-primary-overlay-mutable-zpos
[194.484340] Subtest plane-primary-overlay-mutable-zpos: SKIP (3.414s)
[195.675297] [043/132] (825s left) gem_spin_batch (resubmit-new-all)
[195.751221] Starting subtest: resubmit-new-all
[195.753607] Starting dynamic subtest: rcs0
[195.764834] Dynamic subtest rcs0: SUCCESS (0.013s)
[195.775796] Starting dynamic subtest: bcs0
[195.778086] Dynamic subtest bcs0: SUCCESS (0.011s)
[195.787216] Starting dynamic subtest: vcs0
[195.789438] Dynamic subtest vcs0: SUCCESS (0.010s)
[195.798752] Starting dynamic subtest: vcs1
[195.800547] Dynamic subtest vcs1: SUCCESS (0.008s)
[195.809643] Starting dynamic subtest: vecs0
[195.811829] Dynamic subtest vecs0: SUCCESS (0.009s)
[195.820684] Starting dynamic subtest: ccs0
[195.822520] Dynamic subtest ccs0: SUCCESS (0.008s)
[195.824399] Subtest resubmit-new-all: SUCCESS (0.065s)
[197.314161] [044/132] (823s left) gem_exec_await (wide-all)
[197.534763] Starting subtest: wide-all
[221.287062] Subtest wide-all: SUCCESS (23.744s)
[221.496216] [045/132] (799s left) gem_exec_params (dr1-dirt)
[221.599692] Starting subtest: DR1-dirt
[221.602165] Subtest DR1-dirt: SUCCESS (0.000s)
[221.695070] [046/132] (799s left) gem_mmap_gtt (isolation)
[221.748706] Subtest isolation: SKIP (0.000s)
[221.806745] [047/132] (799s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-blt)
[221.913162] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-blt
[221.917216] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[222.021616] [048/132] (799s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt)
[222.109322] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt
[228.811013] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.702s)
[228.938196] [049/132] (792s left) syncobj_timeline (multi-wait-for-submit-signaled)
[229.010646] Starting subtest: multi-wait-for-submit-signaled
[229.013152] Subtest multi-wait-for-submit-signaled: SUCCESS (0.001s)
[229.134040] [050/132] (792s left) kms_cursor_crc (cursor-random-32x10)
[229.306560] Starting subtest: cursor-random-32x10
[229.354514] Subtest cursor-random-32x10: SKIP (0.044s)
[229.613365] [051/132] (791s left) perf_pmu (busy-accuracy-50)
[229.658593] Starting subtest: busy-accuracy-50
[229.660857] Starting dynamic subtest: rcs0
[231.194689] Dynamic subtest rcs0: SUCCESS (1.533s)
[231.208252] Starting dynamic subtest: bcs0
[232.737853] Dynamic subtest bcs0: SUCCESS (1.541s)
[232.750898] Starting dynamic subtest: vcs0
[234.276947] Dynamic subtest vcs0: SUCCESS (1.536s)
[234.289100] Starting dynamic subtest: vcs1
[235.818667] Dynamic subtest vcs1: SUCCESS (1.539s)
[235.831863] Starting dynamic subtest: vecs0
[237.361318] Dynamic subtest vecs0: SUCCESS (1.541s)
[237.373702] Starting dynamic subtest: ccs0
[238.910461] Dynamic subtest ccs0: SUCCESS (1.548s)
[238.913434] Subtest busy-accuracy-50: SUCCESS (9.253s)
[239.028889] [052/132] (782s left) kms_vblank (wait-busy-hang)
[239.140990] Starting subtest: wait-busy-hang
[239.143280] Starting dynamic subtest: pipe-A-eDP-1
[266.015415] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.877s)
[266.031301] Starting dynamic subtest: pipe-D-eDP-1
[291.039069] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.018s)
[291.040965] Subtest wait-busy-hang: SUCCESS (51.900s)
[291.864955] [053/132] (729s left) gem_exec_schedule (manyslice)
[291.933345] Starting subtest: manyslice
[291.935832] Starting dynamic subtest: rcs0
[297.060294] Dynamic subtest rcs0: SUCCESS (5.134s)
[297.075375] Starting dynamic subtest: bcs0
[303.186068] Dynamic subtest bcs0: SUCCESS (6.122s)
[303.201547] Starting dynamic subtest: vcs0
[309.278628] Dynamic subtest vcs0: SUCCESS (6.090s)
[309.294269] Starting dynamic subtest: vcs1
[315.874410] Dynamic subtest vcs1: SUCCESS (6.590s)
[315.887308] Starting dynamic subtest: vecs0
[321.944809] Dynamic subtest vecs0: SUCCESS (6.068s)
[321.957361] Starting dynamic subtest: ccs0
[328.124283] Dynamic subtest ccs0: SUCCESS (6.174s)
[328.127057] Subtest manyslice: SUCCESS (36.189s)
[328.436365] [054/132] (693s left) api_intel_allocator (reloc-allocator)
[328.491030] Starting subtest: reloc-allocator
[328.493070] Starting dynamic subtest: basic
[328.495088] Dynamic subtest basic: SUCCESS (0.005s)
[328.506693] Starting dynamic subtest: parallel-one
[328.508939] Dynamic subtest parallel-one: SUCCESS (0.008s)
[328.518734] Starting dynamic subtest: print
[328.521086] Dynamic subtest print: SUCCESS (0.000s)
[328.530708] Starting dynamic subtest: reuse
[328.532946] Dynamic subtest reuse: SUCCESS (0.000s)
[328.542459] Starting dynamic subtest: fork-reopen-allocator
[328.544810] Dynamic subtest fork-reopen-allocator: SUCCESS (0.012s)
[328.547025] Subtest reloc-allocator: SUCCESS (0.028s)
[328.644985] [055/132] (693s left) gem_exec_schedule (fifo)
[328.721347] Starting subtest: fifo
[328.723399] Starting dynamic subtest: rcs0
[328.862150] Dynamic subtest rcs0: SUCCESS (0.150s)
[328.872754] Starting dynamic subtest: vcs0
[328.958398] Dynamic subtest vcs0: SUCCESS (0.095s)
[328.972102] Starting dynamic subtest: vcs1
[329.056948] Dynamic subtest vcs1: SUCCESS (0.098s)
[329.070240] Starting dynamic subtest: vecs0
[329.183856] Dynamic subtest vecs0: SUCCESS (0.126s)
[329.197530] Starting dynamic subtest: ccs0
[329.316621] Dynamic subtest ccs0: SUCCESS (0.131s)
[329.319596] Subtest fifo: SUCCESS (0.605s)
[330.345388] [056/132] (691s left) kms_cursor_crc (cursor-onscreen-256x256)
[330.584863] Starting subtest: cursor-onscreen-256x256
[330.589872] Starting dynamic subtest: pipe-A-eDP-1
[332.697465] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.108s)
[332.728741] Starting dynamic subtest: pipe-D-eDP-1
[335.449804] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.727s)
[335.451640] Subtest cursor-onscreen-256x256: SUCCESS (4.860s)
[336.339302] [057/132] (685s left) kms_flip (flip-vs-rmfb-interruptible)
[336.439210] Starting subtest: flip-vs-rmfb-interruptible
[336.441517] Starting dynamic subtest: A-eDP1
[345.121420] Dynamic subtest A-eDP1: SUCCESS (8.678s)
[345.138601] Starting dynamic subtest: B-eDP1
[353.760597] Dynamic subtest B-eDP1: SUCCESS (8.635s)
[353.774408] Starting dynamic subtest: C-eDP1
[362.487094] Dynamic subtest C-eDP1: SUCCESS (8.721s)
[362.501041] Starting dynamic subtest: D-eDP1
[371.149494] Dynamic subtest D-eDP1: SUCCESS (8.662s)
[371.153929] Subtest flip-vs-rmfb-interruptible: SUCCESS (34.710s)
[372.297757] [058/132] (649s left) sw_sync (alloc_timeline)
[372.343251] Starting subtest: alloc_timeline
[372.345327] Subtest alloc_timeline: SUCCESS (0.000s)
[372.402721] [059/132] (649s left) kms_ccs (random-ccs-data-y-tiled-gen12-mc-ccs)
[372.486772] Starting subtest: random-ccs-data-y-tiled-gen12-mc-ccs
[372.489019] Starting dynamic subtest: pipe-A-eDP-1
[372.514268] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[372.525381] Starting dynamic subtest: pipe-B-eDP-1
[372.549391] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[372.559190] Starting dynamic subtest: pipe-C-eDP-1
[372.582901] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[372.592768] Starting dynamic subtest: pipe-D-eDP-1
[372.616643] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[372.620933] Subtest random-ccs-data-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[374.767541] [060/132] (647s left) gem_lmem_swapping (verify-random-ccs)
[374.814154] Subtest verify-random-ccs: SKIP (0.000s)
[374.853867] [061/132] (647s left) gem_exec_reloc (basic-write-gtt)
[374.912287] Subtest basic-write-gtt: SKIP (0.000s)
[374.989668] [062/132] (647s left) gem_exec_schedule (u-semaphore-codependency)
[375.056722] Starting subtest: u-semaphore-codependency
[375.061553] Subtest u-semaphore-codependency: SUCCESS (0.011s)
[375.385283] [063/132] (646s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0)
[375.488860] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0
[375.493285] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[375.585711] [064/132] (646s left) dumb_buffer (create-clear)
[375.633371] Starting subtest: create-clear
[410.093116] Subtest create-clear: SUCCESS (34.462s)
[410.189798] [065/132] (611s left) kms_vblank (query-busy)
[410.288823] Starting subtest: query-busy
[410.290773] Starting dynamic subtest: pipe-A-eDP-1
[412.171045] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.881s)
[412.186659] Starting dynamic subtest: pipe-D-eDP-1
[414.717807] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.545s)
[414.719648] Subtest query-busy: SUCCESS (4.429s)
[415.520130] [066/132] (606s left) kms_plane_scaling (planes-upscale-20x20)
[415.613767] Starting subtest: planes-upscale-20x20
[415.616158] Starting dynamic subtest: pipe-A
[415.673402] Dynamic subtest pipe-A: SUCCESS (0.062s)
[415.684529] Starting dynamic subtest: pipe-B
[416.841342] Dynamic subtest pipe-B: SUCCESS (1.167s)
[416.853949] Starting dynamic subtest: pipe-C
[417.952235] Dynamic subtest pipe-C: SUCCESS (1.109s)
[417.967930] Starting dynamic subtest: pipe-D
[419.077669] Dynamic subtest pipe-D: SUCCESS (1.124s)
[419.080109] Subtest planes-upscale-20x20: SUCCESS (3.467s)
[420.242982] [067/132] (602s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions-varying-s
[420.353218] Starting subtest: short-busy-flip-before-cursor-atomic-transitions-varying-size
[420.357857] Subtest short-busy-flip-before-cursor-atomic-transitions-varying-size: SKIP (0.000s)
[420.516833] [068/132] (601s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-upscaling)
[420.602134] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-upscaling
[420.604309] Starting dynamic subtest: pipe-A-default-mode
[423.301462] Dynamic subtest pipe-A-default-mode: SUCCESS (2.700s)
[423.303657] Subtest flip-32bpp-xtile-to-64bpp-xtile-upscaling: SUCCESS (2.702s)
[424.163856] [069/132] (598s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[424.262050] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[425.063953] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.803s)
[425.240629] [070/132] (597s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[425.339685] Starting subtest: read-crc-frame-sequence
[425.341508] Starting dynamic subtest: pipe-A-eDP-1
[426.057549] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.718s)
[426.070686] Starting dynamic subtest: pipe-B-eDP-1
[427.445268] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.386s)
[427.456412] Starting dynamic subtest: pipe-C-eDP-1
[428.762309] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.312s)
[428.773499] Starting dynamic subtest: pipe-D-eDP-1
[430.083391] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.322s)
[430.085867] Subtest read-crc-frame-sequence: SUCCESS (4.744s)
[430.919154] [071/132] (591s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[431.044609] Starting subtest: y-tiled-8bpp-rotate-270
[431.049153] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[431.142828] [072/132] (591s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs)
[431.253415] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs
[431.255782] Starting dynamic subtest: pipe-A-eDP-1
[431.281487] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[431.293743] Starting dynamic subtest: pipe-B-eDP-1
[431.317882] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[431.327196] Starting dynamic subtest: pipe-C-eDP-1
[431.351790] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[431.361036] Starting dynamic subtest: pipe-D-eDP-1
[431.385346] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[431.389798] Subtest random-ccs-data-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[433.485176] [073/132] (589s left) kms_chamelium_frames (hdmi-frame-dump)
[433.867991] Subtest hdmi-frame-dump: SKIP (0.000s)
[434.695203] [074/132] (587s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[434.787081] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[434.789326] Starting dynamic subtest: A-eDP1
[438.380237] Dynamic subtest A-eDP1: SUCCESS (3.592s)
[438.390699] Starting dynamic subtest: B-eDP1
[441.570619] Dynamic subtest B-eDP1: SUCCESS (3.187s)
[441.582325] Starting dynamic subtest: C-eDP1
[444.695352] Dynamic subtest C-eDP1: SUCCESS (3.125s)
[444.707215] Starting dynamic subtest: D-eDP1
[447.803785] Dynamic subtest D-eDP1: SUCCESS (3.105s)
[447.806239] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (13.018s)
[448.602802] [075/132] (573s left) gem_exec_whisper (basic-fds-priority-all)
[448.655763] Starting subtest: basic-fds-priority-all
[470.743363] Subtest basic-fds-priority-all: SUCCESS (22.083s)
[470.932998] [076/132] (551s left) gem_mmap_gtt (basic-read)
[470.989518] Subtest basic-read: SKIP (0.000s)
[471.057612] [077/132] (551s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-downscaling)
[471.156422] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-downscaling
[471.158600] Starting dynamic subtest: pipe-A-default-mode
[472.285884] Dynamic subtest pipe-A-default-mode: SKIP (1.127s)
[472.290190] Subtest flip-64bpp-4tile-to-16bpp-4tile-downscaling: SKIP (1.128s)
[472.423378] [078/132] (550s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area)
[472.524032] Starting subtest: pr-primary-plane-update-sf-dmg-area
[472.528823] Subtest pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[472.594084] [079/132] (550s left) kms_plane_scaling (2x-scaler-multi-pipe)
[472.691969] Starting subtest: 2x-scaler-multi-pipe
[472.696433] Subtest 2x-scaler-multi-pipe: SKIP (0.001s)
[472.790865] [080/132] (550s left) kms_chamelium_hpd (hdmi-hpd-storm-disable)
[473.186294] Subtest hdmi-hpd-storm-disable: SKIP (0.000s)
[473.963151] [081/132] (548s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[474.053362] Starting subtest: primary-4-tiled-reflect-x-180
[481.099368] Subtest primary-4-tiled-reflect-x-180: SUCCESS (7.048s)
[482.261409] [082/132] (540s left) i915_pm_rpm (gem-execbuf-stress-pc8)
[484.392662] Starting subtest: gem-execbuf-stress-pc8
[484.431394] Subtest gem-execbuf-stress-pc8: SKIP (0.039s)
[484.774614] [083/132] (538s left) gem_close_race (multigpu-basic-threads)
[484.838996] Starting subtest: multigpu-basic-threads
[484.843965] Subtest multigpu-basic-threads: SKIP (0.000s)
[485.064435] [084/132] (537s left) gem_render_copy (yf-tiled-ccs-to-linear)
[485.118627] Starting subtest: yf-tiled-ccs-to-linear
[485.123503] Subtest yf-tiled-ccs-to-linear: SKIP (0.000s)
[485.304888] [085/132] (537s left) kms_big_fb (4-tiled-32bpp-rotate-270)
[485.395754] Starting subtest: 4-tiled-32bpp-rotate-270
[485.415204] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.019s)
[485.528990] [086/132] (537s left) kms_draw_crc (draw-method-pwrite)
[485.615393] Starting subtest: draw-method-pwrite
[485.617728] Starting dynamic subtest: xrgb8888-untiled
[486.161064] Dynamic subtest xrgb8888-untiled: SUCCESS (0.547s)
[486.172213] Starting dynamic subtest: rgb565-untiled
[486.605423] Dynamic subtest rgb565-untiled: SUCCESS (0.444s)
[486.616856] Starting dynamic subtest: xrgb2101010-untiled
[487.128234] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.522s)
[487.139633] Starting dynamic subtest: xbgr16161616f-untiled
[487.861381] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.732s)
[487.872677] Starting dynamic subtest: xrgb8888-xtiled
[489.583915] Dynamic subtest xrgb8888-xtiled: SUCCESS (1.722s)
[489.595390] Starting dynamic subtest: rgb565-xtiled
[490.650926] Dynamic subtest rgb565-xtiled: SUCCESS (1.066s)
[490.662202] Starting dynamic subtest: xrgb2101010-xtiled
[492.418037] Dynamic subtest xrgb2101010-xtiled: SUCCESS (1.766s)
[492.431533] Starting dynamic subtest: xbgr16161616f-xtiled
[495.618623] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (3.197s)
[495.629328] Starting dynamic subtest: xrgb8888-4tiled
[496.698443] Dynamic subtest xrgb8888-4tiled: SUCCESS (1.076s)
[496.709520] Starting dynamic subtest: rgb565-4tiled
[497.318874] Dynamic subtest rgb565-4tiled: SUCCESS (0.619s)
[497.330348] Starting dynamic subtest: xrgb2101010-4tiled
[498.408020] Dynamic subtest xrgb2101010-4tiled: SUCCESS (1.088s)
[498.419375] Starting dynamic subtest: xbgr16161616f-4tiled
[501.341780] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (2.933s)
[501.344079] Subtest draw-method-pwrite: SUCCESS (15.726s)
[501.456060] [087/132] (521s left) perf (per-context-mode-unprivileged)
[501.576782] Starting subtest: per-context-mode-unprivileged
[501.581589] Subtest per-context-mode-unprivileged: SKIP (0.000s)
[501.627629] [088/132] (521s left) gem_ctx_persistence (engines-hostile)
[501.696350] Starting subtest: engines-hostile
[501.698467] Starting dynamic subtest: rcs0
[501.821376] Dynamic subtest rcs0: SUCCESS (0.127s)
[501.834617] Starting dynamic subtest: bcs0
[501.925179] Dynamic subtest bcs0: SUCCESS (0.101s)
[501.938825] Starting dynamic subtest: vcs0
[502.015475] Dynamic subtest vcs0: SUCCESS (0.088s)
[502.028366] Starting dynamic subtest: vcs1
[502.112227] Dynamic subtest vcs1: SUCCESS (0.095s)
[502.125705] Starting dynamic subtest: vecs0
[502.221528] Dynamic subtest vecs0: SUCCESS (0.107s)
[502.234995] Starting dynamic subtest: ccs0
[502.317045] Dynamic subtest ccs0: SUCCESS (0.094s)
[502.319350] Subtest engines-hostile: SUCCESS (0.624s)
[502.396266] [089/132] (520s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted-submitted)
[502.455954] Starting subtest: multi-wait-all-for-submit-unsubmitted-submitted
[502.558685] Subtest multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.102s)
[502.637683] [090/132] (520s left) kms_atomic_interruptible (universal-setplane-cursor)
[502.736985] Starting subtest: universal-setplane-cursor
[502.739426] Starting dynamic subtest: pipe-A-eDP-1
[510.184814] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.446s)
[510.187250] Subtest universal-setplane-cursor: SUCCESS (7.448s)
[511.037987] [091/132] (512s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-gtt)
[511.144056] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-gtt
[511.148214] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[511.243672] [092/132] (512s left) syncobj_timeline (single-wait-all-available-submitted)
[511.304963] Starting subtest: single-wait-all-available-submitted
[511.307166] Subtest single-wait-all-available-submitted: SUCCESS (0.000s)
[511.408955] [093/132] (511s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[511.542999] Starting subtest: cursorA-vs-flipA-varying-size
[522.688300] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.143s)
[523.499233] [094/132] (499s left) i915_drm_fdinfo (isolation)
[523.544088] Starting subtest: isolation
[523.546645] Starting dynamic subtest: rcs0
[523.569206] Dynamic subtest rcs0: SKIP (0.023s)
[523.583330] Starting dynamic subtest: bcs0
[523.588282] Dynamic subtest bcs0: SKIP (0.000s)
[523.598344] Starting dynamic subtest: vcs0
[523.602611] Dynamic subtest vcs0: SKIP (0.000s)
[523.612377] Starting dynamic subtest: vcs1
[523.616284] Dynamic subtest vcs1: SKIP (0.000s)
[523.625954] Starting dynamic subtest: vecs0
[523.629909] Dynamic subtest vecs0: SKIP (0.000s)
[523.639799] Starting dynamic subtest: ccs0
[523.644271] Dynamic subtest ccs0: SKIP (0.000s)
[523.648498] Subtest isolation: SKIP (0.028s)
[523.685537] [095/132] (499s left) kms_atomic_interruptible (legacy-pageflip)
[523.805156] Starting subtest: legacy-pageflip
[523.807506] Starting dynamic subtest: pipe-A-eDP-1
[531.242503] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.435s)
[531.244843] Subtest legacy-pageflip: SUCCESS (7.438s)
[532.061920] [096/132] (491s left) kms_cursor_edge_walk (64x64-top-bottom)
[532.157647] Starting subtest: 64x64-top-bottom
[532.159806] Starting dynamic subtest: pipe-A-eDP-1
[535.620813] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.462s)
[535.631896] Starting dynamic subtest: pipe-D-eDP-1
[538.754964] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.133s)
[538.757265] Subtest 64x64-top-bottom: SUCCESS (6.598s)
[539.567810] [097/132] (483s left) kms_psr (fbc-psr-sprite-render)
[539.658865] Starting subtest: fbc-psr-sprite-render
[539.661175] Starting dynamic subtest: eDP-1
[539.665588] Dynamic subtest eDP-1: SKIP (0.000s)
[539.670147] Subtest fbc-psr-sprite-render: SKIP (0.001s)
[539.736023] [098/132] (483s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc)
[539.821217] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc
[539.823536] Starting dynamic subtest: pipe-A-eDP-1
[539.847537] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[539.859319] Starting dynamic subtest: pipe-B-eDP-1
[539.880985] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[539.891140] Starting dynamic subtest: pipe-C-eDP-1
[539.913273] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[539.923426] Starting dynamic subtest: pipe-D-eDP-1
[539.946077] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[539.950235] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc: SKIP (0.005s)
[542.126074] [099/132] (481s left) prime_busy (before-wait)
[542.192109] Starting subtest: before-wait
[542.194440] Starting dynamic subtest: rcs0
[542.218324] Dynamic subtest rcs0: SUCCESS (0.025s)
[542.228744] Starting dynamic subtest: bcs0
[542.263997] Dynamic subtest bcs0: SUCCESS (0.044s)
[542.274991] Starting dynamic subtest: vcs0
[542.303846] Dynamic subtest vcs0: SUCCESS (0.040s)
[542.314210] Starting dynamic subtest: vcs1
[542.344950] Dynamic subtest vcs1: SUCCESS (0.040s)
[542.355536] Starting dynamic subtest: vecs0
[542.386352] Dynamic subtest vecs0: SUCCESS (0.041s)
[542.397026] Starting dynamic subtest: ccs0
[542.427034] Dynamic subtest ccs0: SUCCESS (0.040s)
[542.535677] Subtest before-wait: SUCCESS (0.344s)
[542.613147] [100/132] (481s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-pwrite)
[542.714799] Starting subtest: psr-1p-primscrn-pri-indfb-draw-pwrite
[550.521014] Subtest psr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (7.809s)
[550.634881] [101/132] (473s left) gem_render_copy (yf-tiled-ccs-to-y-tiled)
[550.687886] Starting subtest: yf-tiled-ccs-to-y-tiled
[550.692186] Subtest yf-tiled-ccs-to-y-tiled: SKIP (0.000s)
[550.871585] [102/132] (472s left) kms_joiner (invalid-modeset-ultra-joiner)
[550.959898] Starting subtest: invalid-modeset-ultra-joiner
[550.964479] Subtest invalid-modeset-ultra-joiner: SKIP (0.000s)
[551.042807] [103/132] (472s left) api_intel_bb (render-ccs)
[551.098616] Starting subtest: render-ccs
[551.449790] Subtest render-ccs: SUCCESS (0.350s)
[551.554462] [104/132] (472s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0-hflip)
[551.654704] Starting subtest: linear-max-hw-stride-64bpp-rotate-0-hflip
[551.658886] Subtest linear-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[551.744881] [105/132] (472s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[551.803294] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[551.912170] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.108s)
[551.990427] [106/132] (471s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[552.086936] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[559.005683] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.923s)
[559.120023] [107/132] (464s left) i915_pm_freq_api (freq-reset-multiple)
[559.186756] Starting subtest: freq-reset-multiple
[559.188941] Starting dynamic subtest: gt0
[568.903022] Dynamic subtest gt0: SUCCESS (9.713s)
[568.913851] Starting dynamic subtest: gt1
[577.197610] Dynamic subtest gt1: SUCCESS (8.290s)
[577.200586] Subtest freq-reset-multiple: SUCCESS (18.005s)
[577.277923] [108/132] (446s left) gem_reg_read (timestamp-moving)
[577.340451] Starting subtest: timestamp-moving
[578.341396] Subtest timestamp-moving: SUCCESS (1.001s)
[578.420728] [109/132] (445s left) syncobj_timeline (invalid-query-illegal-handle)
[578.477091] Starting subtest: invalid-query-illegal-handle
[578.479431] Subtest invalid-query-illegal-handle: SUCCESS (0.000s)
[578.573431] [110/132] (445s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[578.680120] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[585.212630] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.533s)
[585.374900] [111/132] (438s left) gem_render_copy_redux (interruptible)
[585.442526] Starting subtest: interruptible
[585.746401] Subtest interruptible: SUCCESS (0.307s)
[585.859764] [112/132] (438s left) gem_mmap_gtt (big-copy)
[585.914653] Subtest big-copy: SKIP (0.000s)
[585.975697] [113/132] (438s left) gem_lmem_swapping (heavy-random)
[586.019651] Subtest heavy-random: SKIP (0.000s)
[586.059174] [114/132] (438s left) gem_ctx_persistence (legacy-engines-persistence)
[586.106817] Starting subtest: legacy-engines-persistence
[586.109009] Starting dynamic subtest: render
[586.902330] Dynamic subtest render: SUCCESS (0.795s)
[586.918742] Starting dynamic subtest: bsd1
[587.662530] Dynamic subtest bsd1: SUCCESS (0.757s)
[587.675442] Starting dynamic subtest: bsd2
[588.431541] Dynamic subtest bsd2: SUCCESS (0.766s)
[588.445274] Starting dynamic subtest: blt
[589.204420] Dynamic subtest blt: SUCCESS (0.770s)
[589.220196] Starting dynamic subtest: vebox
[589.979348] Dynamic subtest vebox: SUCCESS (0.771s)
[589.981894] Subtest legacy-engines-persistence: SUCCESS (3.873s)
[590.067273] [115/132] (434s left) gem_mmap (short-mmap)
[590.123735] Subtest short-mmap: SKIP (0.000s)
[590.190979] [116/132] (434s left) kms_invalid_mode (clock-too-high)
[590.276894] Starting subtest: clock-too-high
[590.279105] Starting dynamic subtest: pipe-A-eDP-1
[590.283149] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[590.293914] Starting dynamic subtest: pipe-B-eDP-1
[590.298306] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[590.309044] Starting dynamic subtest: pipe-C-eDP-1
[590.313133] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[590.325275] Starting dynamic subtest: pipe-D-eDP-1
[590.329159] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[590.333100] Subtest clock-too-high: SKIP (0.004s)
[590.369629] [117/132] (434s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[590.460251] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[590.464535] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[590.556283] [118/132] (433s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[590.649174] Subtest addfb25-x-tiled-legacy: SKIP (0.000s)
[590.753814] [119/132] (433s left) kms_plane (pixel-format-4-tiled-modifier-source-clamping)
[590.862836] Starting subtest: pixel-format-4-tiled-modifier-source-clamping
[590.889678] Starting dynamic subtest: pipe-A-plane-0
[591.511423] Dynamic subtest pipe-A-plane-0: SUCCESS (0.622s)
[591.523364] Starting dynamic subtest: pipe-A-plane-3
[591.956560] Dynamic subtest pipe-A-plane-3: SUCCESS (0.444s)
[593.043840] Starting dynamic subtest: pipe-B-plane-0
[593.701352] Dynamic subtest pipe-B-plane-0: SUCCESS (0.666s)
[593.711904] Starting dynamic subtest: pipe-B-plane-3
[594.123456] Dynamic subtest pipe-B-plane-3: SUCCESS (0.421s)
[594.434085] Subtest pixel-format-4-tiled-modifier-source-clamping: SUCCESS (3.569s)
[595.311821] [120/132] (429s left) gem_sync (basic-store-each)
[595.381293] Starting subtest: basic-store-each
[597.764561] Subtest basic-store-each: SUCCESS (2.393s)
[597.976221] [121/132] (426s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[598.098272] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[606.802977] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.705s)
[606.945223] [122/132] (417s left) gem_ctx_shared (exec-shared-gtt)
[607.013454] Starting subtest: exec-shared-gtt
[607.015799] Starting dynamic subtest: rcs0
[607.020953] Dynamic subtest rcs0: SUCCESS (0.006s)
[607.033789] Starting dynamic subtest: bcs0
[607.035658] Dynamic subtest bcs0: SUCCESS (0.005s)
[607.047765] Starting dynamic subtest: vcs0
[607.049859] Dynamic subtest vcs0: SUCCESS (0.006s)
[607.062605] Starting dynamic subtest: vcs1
[607.064462] Dynamic subtest vcs1: SUCCESS (0.007s)
[607.074093] Starting dynamic subtest: vecs0
[607.075977] Dynamic subtest vecs0: SUCCESS (0.005s)
[607.085757] Starting dynamic subtest: ccs0
[607.087621] Dynamic subtest ccs0: SUCCESS (0.006s)
[607.089483] Subtest exec-shared-gtt: SUCCESS (0.039s)
[607.235545] [123/132] (417s left) perf_pmu (busy-idle-check-all)
[607.280648] Starting subtest: busy-idle-check-all
[607.282608] Starting dynamic subtest: rcs0
[607.944562] Dynamic subtest rcs0: SUCCESS (0.665s)
[607.959925] Starting dynamic subtest: bcs0
[608.597124] Dynamic subtest bcs0: SUCCESS (0.650s)
[608.610625] Starting dynamic subtest: vcs0
[610.259081] Dynamic subtest vcs0: SUCCESS (1.658s)
[610.270977] Starting dynamic subtest: vcs1
[612.243644] Dynamic subtest vcs1: SUCCESS (1.981s)
[612.257355] Starting dynamic subtest: vecs0
[614.227966] Dynamic subtest vecs0: SUCCESS (1.981s)
[614.241546] Starting dynamic subtest: ccs0
[614.885437] Dynamic subtest ccs0: SUCCESS (0.656s)
[614.888251] Subtest busy-idle-check-all: SUCCESS (7.605s)
[614.991721] [124/132] (409s left) kms_flip (2x-flip-vs-fences-interruptible)
[615.090649] Starting subtest: 2x-flip-vs-fences-interruptible
[615.094845] Subtest 2x-flip-vs-fences-interruptible: SKIP (0.000s)
[615.185495] [125/132] (409s left) gen7_exec_parse (chained-batch)
[615.247395] Subtest chained-batch: SKIP (0.000s)
[615.325648] [126/132] (409s left) kms_pm_backlight (fade-with-dpms)
[615.412663] Starting subtest: fade-with-dpms
[617.692634] Starting dynamic subtest: eDP-1
[620.184200] Dynamic subtest eDP-1: SUCCESS (2.493s)
[620.186459] Subtest fade-with-dpms: SUCCESS (4.772s)
[620.982668] [127/132] (403s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)
[621.110233] Starting subtest: short-flip-after-cursor-atomic-transitions
[622.056221] Subtest short-flip-after-cursor-atomic-transitions: SUCCESS (0.947s)
[622.910467] [128/132] (401s left) gem_userptr_blits (sync-unmap)
[622.988352] Starting subtest: sync-unmap
[622.997151] Subtest sync-unmap: SUCCESS (0.012s)
[623.120984] [129/132] (401s left) kms_psr (fbc-psr-cursor-render)
[623.221465] Starting subtest: fbc-psr-cursor-render
[623.223617] Starting dynamic subtest: eDP-1
[623.227580] Dynamic subtest eDP-1: SKIP (0.000s)
[623.231398] Subtest fbc-psr-cursor-render: SKIP (0.001s)
[623.307594] [130/132] (401s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-blt)
[623.398484] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-blt
[623.402732] Subtest fbc-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[623.504839] [131/132] (401s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[623.600662] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc
[623.604778] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[623.711359] [132/132] (401s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite)
[623.804414] Starting subtest: fbc-rgb101010-draw-pwrite
[629.177710] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (5.381s)
[630.321874] Closing watchdogs
results: parsing output: 0/ for test: kms_ccs
results: parsing output: 1/ for test: gem_pipe_control_store_loop
results: parsing output: 2/ for test: kms_busy
results: parsing output: 3/ for test: gem_userptr_blits
results: parsing output: 4/ for test: syncobj_wait
results: parsing output: 5/ for test: vgem_basic
results: parsing output: 6/ for test: gem_exec_balancer
results: parsing output: 7/ for test: gem_pxp
results: parsing output: 8/ for test: gem_exec_params
results: parsing output: 9/ for test: kms_prime
results: parsing output: 10/ for test: syncobj_timeline
results: parsing output: 11/ for test: kms_flip
results: parsing output: 12/ for test: prime_self_import
results: parsing output: 13/ for test: kms_content_protection
results: parsing output: 14/ for test: gem_busy
results: parsing output: 15/ for test: gem_mmap_wc
results: parsing output: 16/ for test: kms_psr
results: parsing output: 17/ for test: vgem_basic
results: parsing output: 18/ for test: gem_exec_reloc
results: parsing output: 19/ for test: gem_vm_create
results: parsing output: 20/ for test: gem_exec_balancer
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: gem_exec_fence
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: kms_color_pipeline
results: parsing output: 25/ for test: syncobj_wait
results: parsing output: 26/ for test: i915_suspend
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_psr
results: parsing output: 29/ for test: perf
results: parsing output: 30/ for test: gem_softpin
results: parsing output: 31/ for test: kms_ccs
results: parsing output: 32/ for test: kms_tv_load_detect
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: drm_read
results: parsing output: 35/ for test: kms_psr
results: parsing output: 36/ for test: kms_hdr
results: parsing output: 37/ for test: kms_plane_cursor
results: parsing output: 38/ for test: kms_psr2_sf
results: parsing output: 39/ for test: kms_plane_scaling
results: parsing output: 40/ for test: kms_big_fb
results: parsing output: 41/ for test: kms_atomic
results: parsing output: 42/ for test: gem_spin_batch
results: parsing output: 43/ for test: gem_exec_await
results: parsing output: 44/ for test: gem_exec_params
results: parsing output: 45/ for test: gem_mmap_gtt
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: syncobj_timeline
results: parsing output: 49/ for test: kms_cursor_crc
results: parsing output: 50/ for test: perf_pmu
results: parsing output: 51/ for test: kms_vblank
results: parsing output: 52/ for test: gem_exec_schedule
results: parsing output: 53/ for test: api_intel_allocator
results: parsing output: 54/ for test: gem_exec_schedule
results: parsing output: 55/ for test: kms_cursor_crc
results: parsing output: 56/ for test: kms_flip
results: parsing output: 57/ for test: sw_sync
results: parsing output: 58/ for test: kms_ccs
results: parsing output: 59/ for test: gem_lmem_swapping
results: parsing output: 60/ for test: gem_exec_reloc
results: parsing output: 61/ for test: gem_exec_schedule
results: parsing output: 62/ for test: kms_big_fb
results: parsing output: 63/ for test: dumb_buffer
results: parsing output: 64/ for test: kms_vblank
results: parsing output: 65/ for test: kms_plane_scaling
results: parsing output: 66/ for test: kms_cursor_legacy
results: parsing output: 67/ for test: kms_flip_scaled_crc
results: parsing output: 68/ for test: kms_big_fb
results: parsing output: 69/ for test: kms_pipe_crc_basic
results: parsing output: 70/ for test: kms_big_fb
results: parsing output: 71/ for test: kms_ccs
results: parsing output: 72/ for test: kms_chamelium_frames
results: parsing output: 73/ for test: kms_flip
results: parsing output: 74/ for test: gem_exec_whisper
results: parsing output: 75/ for test: gem_mmap_gtt
results: parsing output: 76/ for test: kms_flip_scaled_crc
results: parsing output: 77/ for test: kms_psr2_sf
results: parsing output: 78/ for test: kms_plane_scaling
results: parsing output: 79/ for test: kms_chamelium_hpd
results: parsing output: 80/ for test: kms_rotation_crc
results: parsing output: 81/ for test: i915_pm_rpm
results: parsing output: 82/ for test: gem_close_race
results: parsing output: 83/ for test: gem_render_copy
results: parsing output: 84/ for test: kms_big_fb
results: parsing output: 85/ for test: kms_draw_crc
results: parsing output: 86/ for test: perf
results: parsing output: 87/ for test: gem_ctx_persistence
results: parsing output: 88/ for test: syncobj_wait
results: parsing output: 89/ for test: kms_atomic_interruptible
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: syncobj_timeline
results: parsing output: 92/ for test: kms_cursor_legacy
results: parsing output: 93/ for test: i915_drm_fdinfo
results: parsing output: 94/ for test: kms_atomic_interruptible
results: parsing output: 95/ for test: kms_cursor_edge_walk
results: parsing output: 96/ for test: kms_psr
results: parsing output: 97/ for test: kms_ccs
results: parsing output: 98/ for test: prime_busy
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: gem_render_copy
results: parsing output: 101/ for test: kms_joiner
results: parsing output: 102/ for test: api_intel_bb
results: parsing output: 103/ for test: kms_big_fb
results: parsing output: 104/ for test: syncobj_timeline
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: i915_pm_freq_api
results: parsing output: 107/ for test: gem_reg_read
results: parsing output: 108/ for test: syncobj_timeline
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: gem_render_copy_redux
results: parsing output: 111/ for test: gem_mmap_gtt
results: parsing output: 112/ for test: gem_lmem_swapping
results: parsing output: 113/ for test: gem_ctx_persistence
results: parsing output: 114/ for test: gem_mmap
results: parsing output: 115/ for test: kms_invalid_mode
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: kms_addfb_basic
results: parsing output: 118/ for test: kms_plane
results: parsing output: 119/ for test: gem_sync
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: gem_ctx_shared
results: parsing output: 122/ for test: perf_pmu
results: parsing output: 123/ for test: kms_flip
results: parsing output: 124/ for test: gen7_exec_parse
results: parsing output: 125/ for test: kms_pm_backlight
results: parsing output: 126/ for test: kms_cursor_legacy
results: parsing output: 127/ for test: gem_userptr_blits
results: parsing output: 128/ for test: kms_psr
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_frontbuffer_tracking
Done.