[58.456832] Initializing watchdogs
[58.456881]   /dev/watchdog0
[58.471921] [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.476725] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.479146] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.482421] [001/133] (960s left) kms_ccs (bad-pixel-format-y-tiled-gen12-mc-ccs)
[58.592776] Starting subtest: bad-pixel-format-y-tiled-gen12-mc-ccs
[58.593616] Starting dynamic subtest: pipe-A-eDP-1
[58.601623] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[58.607734] Starting dynamic subtest: pipe-B-eDP-1
[58.615948] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[58.621576] Starting dynamic subtest: pipe-C-eDP-1
[58.629849] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[58.635533] Starting dynamic subtest: pipe-D-eDP-1
[58.643759] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[58.645403] Subtest bad-pixel-format-y-tiled-gen12-mc-ccs: SKIP (0.003s)
[60.861428] [002/133] (958s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[60.970329] Starting subtest: x-tiled-16bpp-rotate-90
[60.990894] Subtest x-tiled-16bpp-rotate-90: SKIP (0.020s)
[61.095358] [003/133] (957s left) gem_userptr_blits (set-cache-level)
[61.162247] Starting subtest: set-cache-level
[61.164779] Subtest set-cache-level: SKIP (0.000s)
[61.274288] [004/133] (957s left) kms_big_fb (4-tiled-64bpp-rotate-180)
[61.370738] Starting subtest: 4-tiled-64bpp-rotate-180
[63.032729] Subtest 4-tiled-64bpp-rotate-180: SUCCESS (1.668s)
[63.193028] [005/133] (955s left) gem_madvise (dontneed-before-mmap)
[63.228249] Starting subtest: dontneed-before-mmap
[63.259975] Subtest dontneed-before-mmap: SUCCESS (0.032s)
[63.347631] [006/133] (955s left) kms_big_fb (yf-tiled-32bpp-rotate-90)
[63.452973] Starting subtest: yf-tiled-32bpp-rotate-90
[63.454928] Subtest yf-tiled-32bpp-rotate-90: SKIP (0.000s)
[63.550071] [007/133] (955s left) syncobj_wait (single-wait-all-submitted)
[63.611423] Starting subtest: single-wait-all-submitted
[63.720805] Subtest single-wait-all-submitted: SUCCESS (0.109s)
[63.792832] [008/133] (955s left) drm_mm (drm_mm)
[63.821240] Starting subtest: drm_mm
[63.841658] Starting dynamic subtest: drm_test_mm_init
[63.925851] Dynamic subtest drm_test_mm_init: SUCCESS (0.084s)
[63.933752] Starting dynamic subtest: drm_test_mm_debug
[64.017861] Dynamic subtest drm_test_mm_debug: SUCCESS (0.092s)
[64.025572] Starting dynamic subtest: drm_test_mm_align32
[64.100062] Dynamic subtest drm_test_mm_align32: SUCCESS (0.082s)
[64.107600] Starting dynamic subtest: drm_test_mm_align64
[64.213789] Dynamic subtest drm_test_mm_align64: SUCCESS (0.115s)
[64.220429] Starting dynamic subtest: drm_test_mm_lowest
[64.305701] Dynamic subtest drm_test_mm_lowest: SUCCESS (0.092s)
[64.313392] Starting dynamic subtest: drm_test_mm_highest
[64.395407] Dynamic subtest drm_test_mm_highest: SUCCESS (0.089s)
[64.396333] Subtest drm_mm: SUCCESS (0.576s)
[64.555471] [009/133] (954s left) gem_exec_balancer (waits)
[64.618709] Starting subtest: waits
[71.205381] Subtest waits: SUCCESS (6.553s)
[71.461848] [010/133] (947s left) kms_addfb_basic (bad-pitch-128)
[71.511133] Starting subtest: bad-pitch-128
[71.512026] Subtest bad-pitch-128: SUCCESS (0.000s)
[71.632617] [011/133] (947s left) gem_close_race (multigpu-basic-threads)
[71.688591] Starting subtest: multigpu-basic-threads
[71.690358] Subtest multigpu-basic-threads: SKIP (0.000s)
[71.910343] [012/133] (947s left) gem_softpin (allocator-evict)
[71.955217] Starting subtest: allocator-evict
[71.956277] Starting dynamic subtest: rcs0
[95.386703] Dynamic subtest rcs0: SUCCESS (23.433s)
[95.393277] Starting dynamic subtest: bcs0
[119.112404] Dynamic subtest bcs0: SUCCESS (23.724s)
[119.119034] Starting dynamic subtest: vcs0
[143.070717] Dynamic subtest vcs0: SUCCESS (23.957s)
[143.079668] Starting dynamic subtest: vcs1
[167.116772] Dynamic subtest vcs1: SUCCESS (24.044s)
[167.123301] Starting dynamic subtest: vecs0
[190.963617] Dynamic subtest vecs0: SUCCESS (23.845s)
[190.971384] Starting dynamic subtest: ccs0
[214.976493] Dynamic subtest ccs0: SUCCESS (24.011s)
[214.977587] Subtest allocator-evict: SUCCESS (143.017s)
[215.049489] [013/133] (804s left) gem_mmap_gtt (basic-read)
[215.141111] Subtest basic-read: SKIP (0.000s)
[215.192667] [014/133] (804s left) kms_big_fb (linear-16bpp-rotate-0)
[215.301712] Starting subtest: linear-16bpp-rotate-0
[216.726964] Subtest linear-16bpp-rotate-0: SUCCESS (1.425s)
[216.841557] [015/133] (802s left) syncobj_timeline (multi-wait-available-unsubmitted-signaled)
[216.894051] Starting subtest: multi-wait-available-unsubmitted-signaled
[216.894957] Subtest multi-wait-available-unsubmitted-signaled: SUCCESS (0.001s)
[216.977133] [016/133] (802s left) prime_vgem (coherency-blt)
[217.133873] Starting subtest: coherency-blt
[218.295261] Subtest coherency-blt: SUCCESS (1.151s)
[218.416604] [FACT prime_vgem (coherency-blt)] new: kernel.kmod_is_loaded.vgem: true
[218.418434] [017/133] (801s left) gem_exec_schedule (u-submit-early-slice)
[218.487434] Starting subtest: u-submit-early-slice
[218.488457] Starting dynamic subtest: rcs0
[218.532402] Dynamic subtest rcs0: SUCCESS (0.045s)
[218.544509] Starting dynamic subtest: bcs0
[218.568550] Dynamic subtest bcs0: SUCCESS (0.036s)
[218.575129] Starting dynamic subtest: vcs0
[218.605390] Dynamic subtest vcs0: SUCCESS (0.036s)
[218.614558] Starting dynamic subtest: vcs1
[218.638033] Dynamic subtest vcs1: SUCCESS (0.032s)
[218.645577] Starting dynamic subtest: vecs0
[218.672130] Dynamic subtest vecs0: SUCCESS (0.034s)
[218.679127] Starting dynamic subtest: ccs0
[218.704291] Dynamic subtest ccs0: SUCCESS (0.032s)
[218.705281] Subtest u-submit-early-slice: SUCCESS (0.217s)
[220.007536] [018/133] (799s left) kms_lease (master-vs-lease)
[220.094291] Starting subtest: master-vs-lease
[220.096180] Subtest master-vs-lease: SUCCESS (0.002s)
[220.194265] [019/133] (799s left) kms_fbcon_fbt (psr-suspend)
[220.247684] Starting subtest: psr-suspend
[270.843238] Subtest psr-suspend: SUCCESS (20.682s)
[271.890662] [020/133] (748s left) kms_cursor_legacy (2x-long-cursor-vs-flip-legacy)
[272.000720] Starting subtest: 2x-long-cursor-vs-flip-legacy
[272.003167] Subtest 2x-long-cursor-vs-flip-legacy: SKIP (0.001s)
[272.139698] [021/133] (748s left) kms_pipe_b_c_ivb (pipe-b-dpms-off-modeset-pipe-c)
[272.179709] Subtest pipe-B-dpms-off-modeset-pipe-C: SKIP (0.000s)
[272.235337] [022/133] (748s left) syncobj_timeline (etime-single-wait-all-submitted)
[272.280390] Starting subtest: etime-single-wait-all-submitted
[272.389643] Subtest etime-single-wait-all-submitted: SUCCESS (0.109s)
[272.457277] [023/133] (748s left) gem_exec_reloc (basic-cpu-wc)
[272.509630] Subtest basic-cpu-wc: SKIP (0.000s)
[272.566469] [024/133] (748s left) kms_debugfs (display-on-read-all)
[272.648549] Starting subtest: display-on-read-all
[272.813969] Subtest display-on-read-all: SUCCESS (0.169s)
[272.917216] [025/133] (747s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling)
[273.006414] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling
[273.007390] Starting dynamic subtest: pipe-A-default-mode
[275.743670] Dynamic subtest pipe-A-default-mode: SUCCESS (2.767s)
[275.744577] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (2.768s)
[276.592303] [026/133] (744s left) syncobj_timeline (wait-all-interrupted)
[276.631312] Starting subtest: wait-all-interrupted
[276.734782] Subtest wait-all-interrupted: SUCCESS (0.103s)
[276.804157] [027/133] (744s left) gem_exec_whisper (basic-sync)
[276.867706] Starting subtest: basic-sync
[283.923899] Subtest basic-sync: SUCCESS (7.083s)
[284.093101] [028/133] (736s left) gem_pxp (reject-modify-context-protection-off-2)
[284.206784] Starting subtest: reject-modify-context-protection-off-2
[284.208562] Subtest reject-modify-context-protection-off-2: SUCCESS (0.002s)
[284.320999] [029/133] (736s left) kms_flip (2x-dpms-vs-vblank-race)
[284.420574] Starting subtest: 2x-dpms-vs-vblank-race
[284.444627] Subtest 2x-dpms-vs-vblank-race: SKIP (0.023s)
[284.545528] [030/133] (736s left) gem_ctx_persistence (engines-hang)
[284.595757] Starting subtest: engines-hang
[284.596903] Starting dynamic subtest: rcs0
[284.658043] Dynamic subtest rcs0: SUCCESS (0.062s)
[284.669042] Starting dynamic subtest: bcs0
[284.749988] Dynamic subtest bcs0: SUCCESS (0.091s)
[284.761180] Starting dynamic subtest: vcs0
[284.845008] Dynamic subtest vcs0: SUCCESS (0.094s)
[284.856456] Starting dynamic subtest: vcs1
[284.940994] Dynamic subtest vcs1: SUCCESS (0.095s)
[284.953431] Starting dynamic subtest: vecs0
[285.027290] Dynamic subtest vecs0: SUCCESS (0.085s)
[285.041198] Starting dynamic subtest: ccs0
[285.115249] Dynamic subtest ccs0: SUCCESS (0.088s)
[285.116597] Subtest engines-hang: SUCCESS (0.521s)
[285.188747] [031/133] (735s left) kms_flip (2x-flip-vs-fences)
[285.280984] Starting subtest: 2x-flip-vs-fences
[285.282884] Subtest 2x-flip-vs-fences: SKIP (0.000s)
[285.389604] [032/133] (735s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[285.476941] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[285.478712] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[285.557218] [033/133] (735s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-wc)
[285.648759] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-wc
[289.775799] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (4.137s)
[290.931951] [034/133] (730s left) syncobj_wait (wait-all-for-submit-snapshot)
[290.990252] Starting subtest: wait-all-for-submit-snapshot
[291.072250] Subtest wait-all-for-submit-snapshot: SUCCESS (0.082s)
[291.144863] [035/133] (729s left) gem_exec_capture (userptr)
[291.199121] Starting subtest: userptr
[292.036278] Subtest userptr: SUCCESS (0.838s)
[292.138889] [036/133] (729s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-sf)
[292.234970] Starting subtest: psr2-cursor-plane-move-continuous-exceed-sf
[292.235904] Starting dynamic subtest: pipe-A-eDP-1
[302.918608] Dynamic subtest pipe-A-eDP-1: SUCCESS (10.694s)
[302.927474] Starting dynamic subtest: pipe-B-eDP-1
[314.334921] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.407s)
[314.335772] Subtest psr2-cursor-plane-move-continuous-exceed-sf: SUCCESS (22.104s)
[315.176992] [037/133] (706s left) kms_flip (plain-flip-ts-check)
[315.266884] Starting subtest: plain-flip-ts-check
[315.267898] Starting dynamic subtest: A-eDP1
[324.201587] Dynamic subtest A-eDP1: SUCCESS (8.906s)
[324.209877] Starting dynamic subtest: B-eDP1
[333.030188] Dynamic subtest B-eDP1: SUCCESS (8.828s)
[333.039505] Starting dynamic subtest: C-eDP1
[341.878834] Dynamic subtest C-eDP1: SUCCESS (8.837s)
[341.889706] Starting dynamic subtest: D-eDP1
[350.668494] Dynamic subtest D-eDP1: SUCCESS (8.826s)
[350.672527] Subtest plain-flip-ts-check: SUCCESS (35.407s)
[351.829835] [038/133] (669s left) kms_cursor_legacy (2x-cursor-vs-flip-legacy)
[351.945283] Starting subtest: 2x-cursor-vs-flip-legacy
[351.947265] Subtest 2x-cursor-vs-flip-legacy: SKIP (0.001s)
[352.084654] [039/133] (669s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-wc)
[352.181513] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-wc
[352.183141] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[352.283337] [040/133] (669s left) kms_psr (fbc-psr2-sprite-plane-onoff)
[352.376563] Starting subtest: fbc-psr2-sprite-plane-onoff
[352.377488] Starting dynamic subtest: eDP-1
[352.379270] Dynamic subtest eDP-1: SKIP (0.000s)
[352.380855] Subtest fbc-psr2-sprite-plane-onoff: SKIP (0.001s)
[352.447429] [041/133] (668s left) gem_exec_nop (basic-sequential)
[352.502338] Starting subtest: basic-sequential
[367.453882] Subtest basic-sequential: SUCCESS (14.957s)
[367.672927] [042/133] (653s left) kms_properties (colorop-properties-atomic)
[367.761098] Starting subtest: colorop-properties-atomic
[367.761992] Starting dynamic subtest: pipe-A-eDP-1
[368.122919] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.359s)
[368.131123] Starting dynamic subtest: pipe-B-eDP-1
[369.196094] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.072s)
[369.209597] Starting dynamic subtest: pipe-C-eDP-1
[370.232954] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.037s)
[370.244040] Starting dynamic subtest: pipe-D-eDP-1
[371.274468] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.039s)
[371.275310] Subtest colorop-properties-atomic: SUCCESS (3.512s)
[372.074087] [043/133] (649s left) kms_content_protection (legacy)
[372.211815] Starting subtest: legacy
[372.213804] Subtest legacy: SKIP (0.000s)
[372.333749] [044/133] (649s left) kms_psr (fbc-pr-primary-render)
[372.421053] Starting subtest: fbc-pr-primary-render
[372.422964] Subtest fbc-pr-primary-render: SKIP (0.000s)
[372.491954] [045/133] (648s left) gem_exec_schedule (lateslice)
[372.559071] Starting subtest: lateslice
[372.560182] Starting dynamic subtest: rcs0
[372.578982] Dynamic subtest rcs0: SUCCESS (0.021s)
[372.589246] Starting dynamic subtest: bcs0
[372.596817] Dynamic subtest bcs0: SUCCESS (0.017s)
[372.604732] Starting dynamic subtest: vcs0
[372.614606] Dynamic subtest vcs0: SUCCESS (0.017s)
[372.622469] Starting dynamic subtest: vcs1
[372.634377] Dynamic subtest vcs1: SUCCESS (0.019s)
[372.642036] Starting dynamic subtest: vecs0
[372.653547] Dynamic subtest vecs0: SUCCESS (0.019s)
[372.661333] Starting dynamic subtest: ccs0
[372.674053] Dynamic subtest ccs0: SUCCESS (0.020s)
[372.674894] Subtest lateslice: SUCCESS (0.116s)
[374.006533] [046/133] (647s left) api_intel_allocator (fork-simple-stress)
[374.061510] Starting subtest: fork-simple-stress
[379.509556] Subtest fork-simple-stress: SUCCESS (5.401s)
[379.592950] [047/133] (641s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[379.696346] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[379.697204] Starting dynamic subtest: pipe-A-eDP-1
[380.234243] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.525s)
[380.247489] Starting dynamic subtest: pipe-B-eDP-1
[381.734104] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.499s)
[381.745656] Starting dynamic subtest: pipe-C-eDP-1
[383.093418] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.358s)
[383.102961] Starting dynamic subtest: pipe-D-eDP-1
[384.516558] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.422s)
[384.517403] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.808s)
[385.691136] [048/133] (635s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[385.780021] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[385.781756] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[385.881820] [049/133] (635s left) gem_exec_parallel (contexts)
[385.937300] Starting subtest: contexts
[385.938245] Starting dynamic subtest: rcs0
[388.730516] Dynamic subtest rcs0: SUCCESS (2.782s)
[388.740230] Starting dynamic subtest: bcs0
[392.242407] Dynamic subtest bcs0: SUCCESS (3.508s)
[392.257852] Starting dynamic subtest: vcs0
[396.391537] Dynamic subtest vcs0: SUCCESS (4.146s)
[396.400053] Starting dynamic subtest: vcs1
[400.510770] Dynamic subtest vcs1: SUCCESS (4.118s)
[400.519639] Starting dynamic subtest: vecs0
[404.576955] Dynamic subtest vecs0: SUCCESS (4.063s)
[404.588018] Starting dynamic subtest: ccs0
[408.284063] Dynamic subtest ccs0: SUCCESS (3.707s)
[408.285107] Subtest contexts: SUCCESS (22.335s)
[408.476424] [050/133] (613s left) kms_chamelium_color (ctm-0-25)
[408.576052] Subtest ctm-0-25: SKIP (0.000s)
[408.620743] [051/133] (613s left) kms_pm_lpsp (screens-disabled)
[409.724249] Starting subtest: screens-disabled
[409.726191] Subtest screens-disabled: SKIP (0.000s)
[409.803087] [052/133] (611s left) kms_psr2_su (frontbuffer-xrgb8888)
[409.854446] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[409.915729] [053/133] (611s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-render)
[410.005740] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-render
[410.007563] Subtest fbc-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[410.094712] [054/133] (611s left) kms_cursor_crc (cursor-random-512x512)
[410.364791] Starting subtest: cursor-random-512x512
[410.366728] Subtest cursor-random-512x512: SKIP (0.001s)
[410.514627] [055/133] (611s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[410.899066] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[411.704492] [056/133] (610s left) kms_chamelium_frames (dp-crc-multiple)
[412.097145] Subtest dp-crc-multiple: SKIP (0.000s)
[412.940880] [057/133] (608s left) i915_drm_fdinfo (busy-hang)
[412.976447] Starting subtest: busy-hang
[412.977385] Starting dynamic subtest: rcs0
[413.012108] Dynamic subtest rcs0: SKIP (0.034s)
[413.022594] Starting dynamic subtest: bcs0
[413.024418] Dynamic subtest bcs0: SKIP (0.001s)
[413.032300] Starting dynamic subtest: vcs0
[413.034230] Dynamic subtest vcs0: SKIP (0.000s)
[413.041979] Starting dynamic subtest: vcs1
[413.043616] Dynamic subtest vcs1: SKIP (0.001s)
[413.051333] Starting dynamic subtest: vecs0
[413.052984] Dynamic subtest vecs0: SKIP (0.000s)
[413.060876] Starting dynamic subtest: ccs0
[413.062774] Dynamic subtest ccs0: SKIP (0.000s)
[413.064461] Subtest busy-hang: SKIP (0.039s)
[413.094834] [058/133] (608s left) kms_pipe_stress (stress-xrgb8888-ytiled)
[413.183677] Starting subtest: stress-xrgb8888-ytiled
[413.185622] Subtest stress-xrgb8888-ytiled: SKIP (0.000s)
[413.247410] [059/133] (608s left) gen9_exec_parse (batch-without-end)
[413.300248] Subtest batch-without-end: SKIP (0.000s)
[413.356591] [060/133] (608s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-pwrite)
[413.460908] Starting subtest: psr-2p-primscrn-cur-indfb-draw-pwrite
[413.462890] Subtest psr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[413.551423] [061/133] (608s left) i915_query (query-regions-unallocated)
[413.594215] Starting subtest: query-regions-unallocated
[413.595116] Subtest query-regions-unallocated: SUCCESS (0.000s)
[413.687558] [062/133] (608s left) gem_mmap_offset (partial-mmap)
[413.747906] Starting subtest: partial-mmap
[413.748941] Starting dynamic subtest: smem0
[413.753176] Dynamic subtest smem0: SUCCESS (0.006s)
[413.754142] Subtest partial-mmap: SUCCESS (0.007s)
[413.889143] [063/133] (608s left) kms_invalid_mode (zero-vdisplay)
[413.973377] Starting subtest: zero-vdisplay
[413.974241] Starting dynamic subtest: pipe-A-eDP-1
[414.256601] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.284s)
[414.266432] Starting dynamic subtest: pipe-B-eDP-1
[414.267477] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[414.275389] Starting dynamic subtest: pipe-C-eDP-1
[414.276386] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[414.284198] Starting dynamic subtest: pipe-D-eDP-1
[414.285226] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[414.286267] Subtest zero-vdisplay: SUCCESS (0.289s)
[415.095423] [064/133] (607s left) kms_cursor_crc (cursor-dpms)
[415.220048] Starting subtest: cursor-dpms
[415.220942] Starting dynamic subtest: pipe-A-eDP-1
[418.272766] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.051s)
[418.280736] Starting dynamic subtest: pipe-D-eDP-1
[421.870457] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.597s)
[421.871398] Subtest cursor-dpms: SUCCESS (6.651s)
[422.708027] [065/133] (599s left) i915_pm_rpm (gem-execbuf)
[424.233241] Starting subtest: gem-execbuf
[424.234651] Starting dynamic subtest: smem0
[425.914080] Dynamic subtest smem0: SUCCESS (1.683s)
[425.914992] Subtest gem-execbuf: SUCCESS (1.684s)
[425.996672] [066/133] (596s left) vgem_basic (dmabuf-mmap)
[426.041364] Starting subtest: dmabuf-mmap
[426.078733] Subtest dmabuf-mmap: SUCCESS (0.038s)
[426.129447] [067/133] (596s left) gem_exec_params (invalid-bsd1-flag-on-blt)
[426.183568] Starting subtest: invalid-bsd1-flag-on-blt
[426.184655] Subtest invalid-bsd1-flag-on-blt: SUCCESS (0.000s)
[426.275766] [068/133] (596s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted-submitted)
[426.331323] Starting subtest: multi-wait-all-for-submit-unsubmitted-submitted
[426.437003] Subtest multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.105s)
[426.534630] [069/133] (595s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[426.619682] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[426.620585] Starting dynamic subtest: pipe-A-eDP-1
[428.871726] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.252s)
[428.881835] Starting dynamic subtest: pipe-B-eDP-1
[431.278605] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.406s)
[431.288366] Starting dynamic subtest: pipe-C-eDP-1
[433.358114] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.078s)
[433.369940] Starting dynamic subtest: pipe-D-eDP-1
[435.602166] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.243s)
[435.603090] Subtest crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SUCCESS (8.985s)
[436.779024] [070/133] (585s left) kms_atomic_interruptible (universal-setplane-primary)
[436.870699] Starting subtest: universal-setplane-primary
[436.871594] Starting dynamic subtest: pipe-A-eDP-1
[444.289608] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.417s)
[444.290423] Subtest universal-setplane-primary: SUCCESS (7.419s)
[445.133945] [071/133] (577s left) gem_userptr_blits (create-destroy-unsync)
[445.203047] Subtest create-destroy-unsync: SKIP (0.000s)
[445.334474] [072/133] (577s left) kms_psr (psr2-cursor-mmap-cpu)
[445.423639] Starting subtest: psr2-cursor-mmap-cpu
[445.424558] Starting dynamic subtest: eDP-1
[447.123278] Dynamic subtest eDP-1: SUCCESS (1.698s)
[447.124266] Subtest psr2-cursor-mmap-cpu: SUCCESS (1.699s)
[447.915908] [073/133] (574s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-msflip-blt)
[448.016027] Starting subtest: fbc-1p-primscrn-indfb-msflip-blt
[452.577001] Subtest fbc-1p-primscrn-indfb-msflip-blt: SUCCESS (4.562s)
[453.729155] [074/133] (568s left) kms_psr (psr2-sprite-mmap-gtt)
[453.811840] Starting subtest: psr2-sprite-mmap-gtt
[453.812664] Starting dynamic subtest: eDP-1
[455.222825] Dynamic subtest eDP-1: SKIP (1.399s)
[455.224827] Subtest psr2-sprite-mmap-gtt: SKIP (1.401s)
[455.344271] [075/133] (567s left) kms_plane (pixel-format-4-tiled-lnl-ccs-modifier-source-clamping)
[455.439667] Starting subtest: pixel-format-4-tiled-lnl-ccs-modifier-source-clamping
[455.441377] Subtest pixel-format-4-tiled-lnl-ccs-modifier-source-clamping: SKIP (0.000s)
[455.508011] [076/133] (567s left) gem_exec_balancer (nohangcheck)
[455.780086] Starting subtest: nohangcheck
[456.799024] Subtest nohangcheck: SUCCESS (1.017s)
[456.899042] [077/133] (565s left) kms_cursor_crc (cursor-sliding-32x10)
[457.070761] Starting subtest: cursor-sliding-32x10
[457.116883] Subtest cursor-sliding-32x10: SKIP (0.044s)
[457.442167] [078/133] (565s left) gem_ctx_persistence (legacy-engines-cleanup)
[457.495807] Starting subtest: legacy-engines-cleanup
[457.496734] Starting dynamic subtest: render
[457.556647] Dynamic subtest render: SUCCESS (0.060s)
[457.568537] Starting dynamic subtest: bsd1
[457.590352] Dynamic subtest bsd1: SUCCESS (0.031s)
[457.599515] Starting dynamic subtest: bsd2
[457.642563] Dynamic subtest bsd2: SUCCESS (0.050s)
[457.653356] Starting dynamic subtest: blt
[457.693730] Dynamic subtest blt: SUCCESS (0.048s)
[457.704525] Starting dynamic subtest: vebox
[457.717245] Dynamic subtest vebox: SUCCESS (0.021s)
[457.718015] Subtest legacy-engines-cleanup: SUCCESS (0.222s)
[457.840650] [079/133] (564s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[457.886199] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[457.989089] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.103s)
[458.062174] [080/133] (564s left) syncobj_wait (invalid-reset-illegal-handle)
[458.119333] Starting subtest: invalid-reset-illegal-handle
[458.120183] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[458.226352] [081/133] (564s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180)
[458.320085] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180
[458.321754] Subtest y-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[458.401388] [082/133] (564s left) gem_exec_fence (syncobj-invalid-wait)
[458.843676] Starting subtest: syncobj-invalid-wait
[458.844777] Subtest syncobj-invalid-wait: SUCCESS (0.000s)
[460.051108] [083/133] (562s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-pgflip-blt)
[460.147925] Starting subtest: fbcpsr-2p-primscrn-shrfb-pgflip-blt
[460.149777] Subtest fbcpsr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[460.251697] [084/133] (562s left) gem_ppgtt (shrink-vs-evict-any)
[460.309406] Starting subtest: shrink-vs-evict-any
[465.971807] Subtest shrink-vs-evict-any: SUCCESS (5.618s)
[466.016028] [085/133] (556s left) kms_sequence (queue-busy)
[466.114360] Starting subtest: queue-busy
[466.115296] Starting dynamic subtest: pipe-A-eDP-1
[468.059173] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.934s)
[468.069510] Starting dynamic subtest: pipe-B-eDP-1
[470.638234] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.588s)
[470.647035] Starting dynamic subtest: pipe-C-eDP-1
[473.245965] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.608s)
[473.255995] Starting dynamic subtest: pipe-D-eDP-1
[475.786901] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.539s)
[475.787873] Subtest queue-busy: SUCCESS (9.675s)
[476.592867] [086/133] (546s left) perf_pmu (idle-no-semaphores)
[476.640447] Starting subtest: idle-no-semaphores
[476.641238] Starting dynamic subtest: rcs0
[477.197731] Dynamic subtest rcs0: SUCCESS (0.547s)
[477.208846] Starting dynamic subtest: bcs0
[477.718129] Dynamic subtest bcs0: SUCCESS (0.517s)
[477.729112] Starting dynamic subtest: vcs0
[478.240734] Dynamic subtest vcs0: SUCCESS (0.519s)
[478.251892] Starting dynamic subtest: vcs1
[478.761975] Dynamic subtest vcs1: SUCCESS (0.518s)
[478.772960] Starting dynamic subtest: vecs0
[479.283710] Dynamic subtest vecs0: SUCCESS (0.519s)
[479.295007] Starting dynamic subtest: ccs0
[479.805246] Dynamic subtest ccs0: SUCCESS (0.519s)
[479.806682] Subtest idle-no-semaphores: SUCCESS (3.158s)
[479.916571] [087/133] (543s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[480.005374] Starting subtest: psr-1p-primscrn-cur-indfb-move
[487.389772] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (7.386s)
[487.514112] [088/133] (535s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[487.613444] Starting subtest: y-tiled-32bpp-rotate-0
[487.615565] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[487.703787] [089/133] (535s left) syncobj_wait (multi-wait-all-submitted-signaled)
[487.753915] Starting subtest: multi-wait-all-submitted-signaled
[487.866343] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.106s)
[487.932128] [090/133] (535s left) kms_atomic (crtc-invalid-params-fence)
[488.023363] Starting subtest: crtc-invalid-params-fence
[488.024203] Starting dynamic subtest: pipe-A-eDP-1
[488.479008] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.455s)
[488.479929] Subtest crtc-invalid-params-fence: SUCCESS (0.456s)
[489.351512] [091/133] (533s left) kms_ccs (crc-primary-basic-y-tiled-gen12-rc-ccs-cc)
[489.445333] Starting subtest: crc-primary-basic-y-tiled-gen12-rc-ccs-cc
[489.447648] Starting dynamic subtest: pipe-A-eDP-1
[489.456054] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[489.466183] Starting dynamic subtest: pipe-B-eDP-1
[489.474715] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[489.484263] Starting dynamic subtest: pipe-C-eDP-1
[489.492842] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[489.501273] Starting dynamic subtest: pipe-D-eDP-1
[489.509824] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[489.511481] Subtest crc-primary-basic-y-tiled-gen12-rc-ccs-cc: SKIP (0.007s)
[491.711503] [092/133] (531s left) kms_color_pipeline (plane-lut1d-ctm3x4-lut1d)
[491.800478] Starting subtest: plane-lut1d-ctm3x4-lut1d
[491.925732] Starting dynamic subtest: pipe-A-plane-0
[492.185674] Dynamic subtest pipe-A-plane-0: FAIL (0.187s)
[492.194857] Starting dynamic subtest: pipe-A-plane-1
[492.373969] Dynamic subtest pipe-A-plane-1: FAIL (0.187s)
[492.382855] Starting dynamic subtest: pipe-A-plane-2
[492.576071] Dynamic subtest pipe-A-plane-2: FAIL (0.188s)
[493.732343] Starting dynamic subtest: pipe-B-plane-0
[494.002335] Dynamic subtest pipe-B-plane-0: FAIL (0.175s)
[494.014476] Starting dynamic subtest: pipe-B-plane-1
[494.187086] Dynamic subtest pipe-B-plane-1: FAIL (0.177s)
[494.195740] Starting dynamic subtest: pipe-B-plane-2
[494.375325] Dynamic subtest pipe-B-plane-2: FAIL (0.178s)
[495.436692] Starting dynamic subtest: pipe-C-plane-0
[495.727820] Dynamic subtest pipe-C-plane-0: FAIL (0.140s)
[495.737318] Starting dynamic subtest: pipe-C-plane-1
[495.890244] Dynamic subtest pipe-C-plane-1: FAIL (0.155s)
[495.899642] Starting dynamic subtest: pipe-C-plane-2
[496.055306] Dynamic subtest pipe-C-plane-2: FAIL (0.169s)
[497.092428] Starting dynamic subtest: pipe-D-plane-0
[497.426104] Dynamic subtest pipe-D-plane-0: FAIL (0.185s)
[497.434708] Starting dynamic subtest: pipe-D-plane-1
[497.621988] Dynamic subtest pipe-D-plane-1: FAIL (0.166s)
[497.630838] Starting dynamic subtest: pipe-D-plane-2
[497.818951] Dynamic subtest pipe-D-plane-2: FAIL (0.167s)
[497.908887] Subtest plane-lut1d-ctm3x4-lut1d: FAIL (6.109s)
[498.725958] [093/133] (524s left) vgem_basic (setversion)
[498.759894] Starting subtest: setversion
[498.761643] Subtest setversion: SUCCESS (0.000s)
[498.813439] [094/133] (524s left) gem_ctx_param (set-priority-range)
[498.866782] Starting subtest: set-priority-range
[498.882461] Subtest set-priority-range: SUCCESS (0.015s)
[499.006964] [095/133] (524s left) gem_pipe_control_store_loop (reused-buffer)
[499.062697] Starting subtest: reused-buffer
[501.216991] Subtest reused-buffer: SUCCESS (2.153s)
[501.328465] [096/133] (521s left) perf_pmu (busy-check-all)
[501.364769] Starting subtest: busy-check-all
[501.365783] Starting dynamic subtest: rcs0
[501.945979] Dynamic subtest rcs0: SUCCESS (0.580s)
[501.959247] Starting dynamic subtest: bcs0
[502.501073] Dynamic subtest bcs0: SUCCESS (0.553s)
[502.514640] Starting dynamic subtest: vcs0
[503.930516] Dynamic subtest vcs0: SUCCESS (1.416s)
[503.941548] Starting dynamic subtest: vcs1
[505.978648] Dynamic subtest vcs1: SUCCESS (2.045s)
[505.989797] Starting dynamic subtest: vecs0
[507.962941] Dynamic subtest vecs0: SUCCESS (1.980s)
[507.975170] Starting dynamic subtest: ccs0
[508.507946] Dynamic subtest ccs0: SUCCESS (0.553s)
[508.511047] Subtest busy-check-all: SUCCESS (7.143s)
[508.605814] [097/133] (514s left) syncobj_timeline (invalid-signal-illegal-handle)
[508.663435] Starting subtest: invalid-signal-illegal-handle
[508.664273] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[508.756620] [098/133] (514s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-render)
[508.847722] Starting subtest: psr-1p-offscreen-pri-indfb-draw-render
[515.919200] Subtest psr-1p-offscreen-pri-indfb-draw-render: SUCCESS (7.059s)
[516.052417] [099/133] (507s left) gem_exec_schedule (deep)
[516.244564] Starting subtest: deep
[516.245602] Starting dynamic subtest: rcs0
[521.605339] Dynamic subtest rcs0: SKIP (5.357s)
[521.615570] Starting dynamic subtest: vcs0
[531.911869] Dynamic subtest vcs0: SUCCESS (10.306s)
[531.928492] Starting dynamic subtest: vcs1
[542.589907] Dynamic subtest vcs1: SUCCESS (10.674s)
[542.604237] Starting dynamic subtest: vecs0
[553.644910] Dynamic subtest vecs0: SUCCESS (11.053s)
[553.659546] Starting dynamic subtest: ccs0
[565.537063] Dynamic subtest ccs0: SUCCESS (11.890s)
[565.539375] Subtest deep: SUCCESS (49.286s)
[566.909150] [100/133] (456s left) sysfs_timeslice_duration (idempotent)
[566.979550] Starting subtest: idempotent
[566.980581] Starting dynamic subtest: vecs0
[566.981531] Dynamic subtest vecs0: SUCCESS (0.000s)
[566.991315] Starting dynamic subtest: ccs0
[566.992057] Dynamic subtest ccs0: SUCCESS (0.000s)
[567.000247] Starting dynamic subtest: vcs0
[567.000984] Dynamic subtest vcs0: SUCCESS (0.000s)
[567.009391] Starting dynamic subtest: bcs0
[567.010137] Dynamic subtest bcs0: SUCCESS (0.000s)
[567.018424] Starting dynamic subtest: rcs0
[567.019152] Dynamic subtest rcs0: SUCCESS (0.000s)
[567.027692] Starting dynamic subtest: vcs1
[567.028537] Dynamic subtest vcs1: SUCCESS (0.000s)
[567.029463] Subtest idempotent: SUCCESS (0.006s)
[567.080294] [101/133] (456s left) gem_eio (in-flight-contexts-10ms)
[567.321451] Starting subtest: in-flight-contexts-10ms
[580.997224] Subtest in-flight-contexts-10ms: SUCCESS (13.675s)
[581.142549] [102/133] (442s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[581.244267] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[581.269355] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.024s)
[581.351799] [103/133] (442s left) gem_render_copy (y-tiled-ccs-to-y-tiled-ccs)
[581.407361] Starting subtest: y-tiled-ccs-to-y-tiled-ccs
[581.409049] Subtest y-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[581.593872] [104/133] (441s left) tools_test (tools_test)
[581.630886] Starting subtest: tools_test
[581.697963] Subtest tools_test: SUCCESS (0.074s)
[581.758685] [105/133] (441s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[581.846376] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[581.848043] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[581.940827] [106/133] (441s left) kms_plane (pixel-format-y-tiled-modifier)
[582.040034] Starting subtest: pixel-format-y-tiled-modifier
[582.041912] Subtest pixel-format-y-tiled-modifier: SKIP (0.000s)
[582.115916] [107/133] (441s left) kms_frontbuffer_tracking (fbcpsr-tiling-linear)
[582.194058] Starting subtest: fbcpsr-tiling-linear
[588.788087] Subtest fbcpsr-tiling-linear: SUCCESS (6.595s)
[588.913370] [108/133] (434s left) kms_flip (2x-flip-vs-wf_vblank)
[589.008577] Starting subtest: 2x-flip-vs-wf_vblank
[589.019113] Subtest 2x-flip-vs-wf_vblank: SKIP (0.011s)
[589.113899] [109/133] (434s left) gem_ctx_param (basic-default)
[589.162976] Starting subtest: basic-default
[589.163993] Subtest basic-default: SUCCESS (0.000s)
[589.237355] [110/133] (434s left) i915_drm_fdinfo (virtual-idle)
[589.273759] Starting subtest: virtual-idle
[592.341781] Subtest virtual-idle: SUCCESS (3.058s)
[592.410595] [111/133] (431s left) gem_exec_reloc (basic-write-read)
[592.459244] Subtest basic-write-read: SKIP (0.000s)
[592.524139] [112/133] (431s left) gem_exec_whisper (basic-fds-forked)
[592.578367] Starting subtest: basic-fds-forked
[616.719223] Subtest basic-fds-forked: SUCCESS (24.116s)
[616.877919] [113/133] (407s left) kms_pm_rpm (pc8-residency)
[619.103023] Starting subtest: pc8-residency
[619.105586] Subtest pc8-residency: SKIP (0.000s)
[619.464282] [114/133] (404s left) kms_plane_scaling (planes-downscale-factor-0-75-unity-scaling)
[619.550403] Starting subtest: planes-downscale-factor-0-75-unity-scaling
[619.551258] Starting dynamic subtest: pipe-A
[619.616964] Dynamic subtest pipe-A: SUCCESS (0.067s)
[619.627474] Starting dynamic subtest: pipe-B
[620.783822] Dynamic subtest pipe-B: SUCCESS (1.165s)
[620.796862] Starting dynamic subtest: pipe-C
[621.901193] Dynamic subtest pipe-C: SUCCESS (1.115s)
[621.910332] Starting dynamic subtest: pipe-D
[623.043179] Dynamic subtest pipe-D: SUCCESS (1.141s)
[623.044117] Subtest planes-downscale-factor-0-75-unity-scaling: SUCCESS (3.495s)
[624.216728] [115/133] (399s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-blt)
[624.316794] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-blt
[628.061461] Subtest fbc-1p-primscrn-spr-indfb-draw-blt: SUCCESS (3.745s)
[629.209347] [116/133] (394s left) kms_plane_lowres (tiling-y)
[629.291101] Starting subtest: tiling-y
[629.293076] Subtest tiling-y: SKIP (0.000s)
[629.358033] [117/133] (394s left) kms_psr (psr-no-drrs)
[629.437733] Starting subtest: psr-no-drrs
[629.438661] Starting dynamic subtest: eDP-1
[631.090400] Dynamic subtest eDP-1: SUCCESS (1.652s)
[631.091561] Subtest psr-no-drrs: SUCCESS (1.653s)
[631.970974] [118/133] (392s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-move)
[632.067344] Starting subtest: fbc-1p-primscrn-cur-indfb-move
[636.185034] Subtest fbc-1p-primscrn-cur-indfb-move: SUCCESS (4.118s)
[637.363137] [119/133] (386s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[637.465010] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[638.168655] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (0.704s)
[638.329163] [120/133] (385s left) kms_addfb_basic (legacy-format)
[638.375807] Starting subtest: legacy-format
[638.397512] Subtest legacy-format: SUCCESS (0.021s)
[638.532623] [121/133] (385s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling)
[638.623344] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling
[638.624822] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.000s)
[638.694271] [122/133] (385s left) fbdev (read)
[638.736739] Starting subtest: read
[639.389133] Subtest read: SUCCESS (0.642s)
[639.442963] [123/133] (384s left) gem_ctx_shared (exec-single-timeline)
[639.503280] Starting subtest: exec-single-timeline
[639.504148] Starting dynamic subtest: rcs0
[640.598626] Dynamic subtest rcs0: SUCCESS (1.084s)
[640.610949] Starting dynamic subtest: bcs0
[641.687397] Dynamic subtest bcs0: SUCCESS (1.085s)
[641.699905] Starting dynamic subtest: vcs0
[642.775996] Dynamic subtest vcs0: SUCCESS (1.086s)
[642.795471] Starting dynamic subtest: vcs1
[643.863334] Dynamic subtest vcs1: SUCCESS (1.085s)
[643.880932] Starting dynamic subtest: vecs0
[644.950610] Dynamic subtest vecs0: SUCCESS (1.084s)
[644.962659] Starting dynamic subtest: ccs0
[646.039961] Dynamic subtest ccs0: SUCCESS (1.087s)
[646.041466] Subtest exec-single-timeline: SUCCESS (6.528s)
[647.023333] [124/133] (377s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-cpu)
[647.116170] Starting subtest: psr-rgb101010-draw-mmap-cpu
[654.331566] Subtest psr-rgb101010-draw-mmap-cpu: SUCCESS (7.217s)
[654.431994] [125/133] (369s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite)
[654.516571] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite
[659.002053] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (4.486s)
[660.155346] [126/133] (364s left) kms_vblank (query-forked-busy)
[660.257465] Starting subtest: query-forked-busy
[660.258317] Starting dynamic subtest: pipe-A-eDP-1
[662.145812] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.888s)
[662.160991] Starting dynamic subtest: pipe-D-eDP-1
[664.712058] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.564s)
[664.712848] Subtest query-forked-busy: SUCCESS (4.456s)
[665.513373] [127/133] (358s left) kms_cursor_legacy (flip-vs-cursor-legacy)
[665.625394] Starting subtest: flip-vs-cursor-legacy
[673.056269] Subtest flip-vs-cursor-legacy: SUCCESS (7.432s)
[673.942824] [128/133] (350s left) api_intel_bb (purge-bb)
[673.996112] Starting subtest: purge-bb
[673.998552] Subtest purge-bb: SUCCESS (0.003s)
[674.120827] [129/133] (350s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[674.224564] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[674.225998] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[674.313316] [130/133] (350s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-render)
[674.399805] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-render
[679.082443] Subtest fbc-1p-primscrn-pri-indfb-draw-render: SUCCESS (4.684s)
[680.242335] [131/133] (344s left) kms_feature_discovery (psr1)
[680.328813] Starting subtest: psr1
[680.329708] Subtest psr1: SUCCESS (0.000s)
[680.404372] [132/133] (344s left) gem_lmem_swapping (verify-random-ccs)
[680.445892] Subtest verify-random-ccs: SKIP (0.000s)
[680.485299] [133/133] (344s left) gem_exec_reloc (basic-write-cpu-active)
[680.541320] Subtest basic-write-cpu-active: SKIP (0.000s)
[680.607581] Closing watchdogs
results: parsing output: 0/ for test: kms_ccs
results: parsing output: 1/ for test: kms_big_fb
results: parsing output: 2/ for test: gem_userptr_blits
results: parsing output: 3/ for test: kms_big_fb
results: parsing output: 4/ for test: gem_madvise
results: parsing output: 5/ for test: kms_big_fb
results: parsing output: 6/ for test: syncobj_wait
results: parsing output: 7/ for test: drm_mm
results: parsing output: 8/ for test: gem_exec_balancer
results: parsing output: 9/ for test: kms_addfb_basic
results: parsing output: 10/ for test: gem_close_race
results: parsing output: 11/ for test: gem_softpin
results: parsing output: 12/ for test: gem_mmap_gtt
results: parsing output: 13/ for test: kms_big_fb
results: parsing output: 14/ for test: syncobj_timeline
results: parsing output: 15/ for test: prime_vgem
results: parsing output: 16/ for test: gem_exec_schedule
results: parsing output: 17/ for test: kms_lease
results: parsing output: 18/ for test: kms_fbcon_fbt
results: parsing output: 19/ for test: kms_cursor_legacy
results: parsing output: 20/ for test: kms_pipe_b_c_ivb
results: parsing output: 21/ for test: syncobj_timeline
results: parsing output: 22/ for test: gem_exec_reloc
results: parsing output: 23/ for test: kms_debugfs
results: parsing output: 24/ for test: kms_flip_scaled_crc
results: parsing output: 25/ for test: syncobj_timeline
results: parsing output: 26/ for test: gem_exec_whisper
results: parsing output: 27/ for test: gem_pxp
results: parsing output: 28/ for test: kms_flip
results: parsing output: 29/ for test: gem_ctx_persistence
results: parsing output: 30/ for test: kms_flip
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: syncobj_wait
results: parsing output: 34/ for test: gem_exec_capture
results: parsing output: 35/ for test: kms_psr2_sf
results: parsing output: 36/ for test: kms_flip
results: parsing output: 37/ for test: kms_cursor_legacy
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: kms_psr
results: parsing output: 40/ for test: gem_exec_nop
results: parsing output: 41/ for test: kms_properties
results: parsing output: 42/ for test: kms_content_protection
results: parsing output: 43/ for test: kms_psr
results: parsing output: 44/ for test: gem_exec_schedule
results: parsing output: 45/ for test: api_intel_allocator
results: parsing output: 46/ for test: kms_ccs
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: gem_exec_parallel
results: parsing output: 49/ for test: kms_chamelium_color
results: parsing output: 50/ for test: kms_pm_lpsp
results: parsing output: 51/ for test: kms_psr2_su
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_cursor_crc
results: parsing output: 54/ for test: kms_chamelium_hpd
results: parsing output: 55/ for test: kms_chamelium_frames
results: parsing output: 56/ for test: i915_drm_fdinfo
results: parsing output: 57/ for test: kms_pipe_stress
results: parsing output: 58/ for test: gen9_exec_parse
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: i915_query
results: parsing output: 61/ for test: gem_mmap_offset
results: parsing output: 62/ for test: kms_invalid_mode
results: parsing output: 63/ for test: kms_cursor_crc
results: parsing output: 64/ for test: i915_pm_rpm
results: parsing output: 65/ for test: vgem_basic
results: parsing output: 66/ for test: gem_exec_params
results: parsing output: 67/ for test: syncobj_wait
results: parsing output: 68/ for test: kms_ccs
results: parsing output: 69/ for test: kms_atomic_interruptible
results: parsing output: 70/ for test: gem_userptr_blits
results: parsing output: 71/ for test: kms_psr
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: kms_psr
results: parsing output: 74/ for test: kms_plane
results: parsing output: 75/ for test: gem_exec_balancer
results: parsing output: 76/ for test: kms_cursor_crc
results: parsing output: 77/ for test: gem_ctx_persistence
results: parsing output: 78/ for test: syncobj_timeline
results: parsing output: 79/ for test: syncobj_wait
results: parsing output: 80/ for test: kms_big_fb
results: parsing output: 81/ for test: gem_exec_fence
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: gem_ppgtt
results: parsing output: 84/ for test: kms_sequence
results: parsing output: 85/ for test: perf_pmu
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_big_fb
results: parsing output: 88/ for test: syncobj_wait
results: parsing output: 89/ for test: kms_atomic
results: parsing output: 90/ for test: kms_ccs
results: parsing output: 91/ for test: kms_color_pipeline
results: parsing output: 92/ for test: vgem_basic
results: parsing output: 93/ for test: gem_ctx_param
results: parsing output: 94/ for test: gem_pipe_control_store_loop
results: parsing output: 95/ for test: perf_pmu
results: parsing output: 96/ for test: syncobj_timeline
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: gem_exec_schedule
results: parsing output: 99/ for test: sysfs_timeslice_duration
results: parsing output: 100/ for test: gem_eio
results: parsing output: 101/ for test: kms_flip
results: parsing output: 102/ for test: gem_render_copy
results: parsing output: 103/ for test: tools_test
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: kms_plane
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_flip
results: parsing output: 108/ for test: gem_ctx_param
results: parsing output: 109/ for test: i915_drm_fdinfo
results: parsing output: 110/ for test: gem_exec_reloc
results: parsing output: 111/ for test: gem_exec_whisper
results: parsing output: 112/ for test: kms_pm_rpm
results: parsing output: 113/ for test: kms_plane_scaling
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: kms_plane_lowres
results: parsing output: 116/ for test: kms_psr
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_big_fb
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: fbdev
results: parsing output: 122/ for test: gem_ctx_shared
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_vblank
results: parsing output: 126/ for test: kms_cursor_legacy
results: parsing output: 127/ for test: api_intel_bb
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: kms_feature_discovery
results: parsing output: 131/ for test: gem_lmem_swapping
results: parsing output: 132/ for test: gem_exec_reloc
Done.