[57.727158] Initializing watchdogs
[57.727206]   /dev/watchdog0
[57.741629] [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]
[57.746525] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.749061] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.752320] [001/133] (960s left) api_intel_bb (full-batch)
[57.836267] Starting subtest: full-batch
[57.845208] Subtest full-batch: SUCCESS (0.009s)
[57.962459] [002/133] (960s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[58.074300] Starting subtest: x-tiled-64bpp-rotate-90
[58.107122] Subtest x-tiled-64bpp-rotate-90: SKIP (0.034s)
[58.229390] [003/133] (960s left) vgem_basic (second-client)
[58.302155] Starting subtest: second-client
[58.323435] Subtest second-client: SUCCESS (0.022s)
[58.376739] [FACT vgem_basic (second-client)] new: kernel.kmod_is_loaded.vgem: true
[58.379790] [004/133] (959s left) i915_drm_fdinfo (context-close-stress)
[58.425485] Starting subtest: context-close-stress
[69.116133] Subtest context-close-stress: SUCCESS (10.746s)
[69.187275] [005/133] (949s left) kms_rotation_crc (primary-yf-tiled-reflect-x-90)
[69.420278] Starting subtest: primary-yf-tiled-reflect-x-90
[69.425509] Subtest primary-yf-tiled-reflect-x-90: SKIP (0.001s)
[69.491088] [006/133] (948s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[69.583314] Subtest addfb25-framebuffer-vs-set-tiling: SKIP (0.000s)
[69.683116] [007/133] (948s left) gen9_exec_parse (basic-rejected-ctx-param)
[69.744041] Subtest basic-rejected-ctx-param: SKIP (0.000s)
[69.806884] [008/133] (948s left) kms_flip (flip-vs-panning-interruptible)
[69.902689] Starting subtest: flip-vs-panning-interruptible
[69.905037] Starting dynamic subtest: A-eDP1
[78.597577] Dynamic subtest A-eDP1: SUCCESS (8.704s)
[78.605134] Starting dynamic subtest: B-eDP1
[87.318170] Dynamic subtest B-eDP1: SUCCESS (8.720s)
[87.325130] Starting dynamic subtest: C-eDP1
[96.038634] Dynamic subtest C-eDP1: SUCCESS (8.719s)
[96.046465] Starting dynamic subtest: D-eDP1
[104.769073] Dynamic subtest D-eDP1: SUCCESS (8.729s)
[104.771093] Subtest flip-vs-panning-interruptible: SUCCESS (34.879s)
[105.945567] [009/133] (912s left) gem_exec_schedule (manyslice)
[106.026145] Starting subtest: manyslice
[106.028653] Starting dynamic subtest: rcs0
[111.144690] Dynamic subtest rcs0: SUCCESS (5.128s)
[111.156503] Starting dynamic subtest: bcs0
[117.270502] Dynamic subtest bcs0: SUCCESS (6.124s)
[117.281280] Starting dynamic subtest: vcs0
[122.864024] Dynamic subtest vcs0: SUCCESS (5.593s)
[122.872728] Starting dynamic subtest: vcs1
[127.926752] Dynamic subtest vcs1: SUCCESS (5.061s)
[127.938934] Starting dynamic subtest: vecs0
[134.005988] Dynamic subtest vecs0: SUCCESS (6.079s)
[134.017497] Starting dynamic subtest: ccs0
[140.693439] Dynamic subtest ccs0: SUCCESS (6.686s)
[140.695897] Subtest manyslice: SUCCESS (34.678s)
[140.998321] [010/133] (877s left) kms_joiner (basic-max-non-joiner)
[141.104471] Starting subtest: basic-max-non-joiner
[141.108383] Subtest basic-max-non-joiner: SKIP (0.000s)
[141.184880] [011/133] (877s left) syncobj_timeline (host-signal-points)
[141.248502] Starting subtest: host-signal-points
[141.256533] Subtest host-signal-points: SUCCESS (0.008s)
[141.331213] [012/133] (877s left) kms_cursor_legacy (single-move)
[141.456903] Starting subtest: single-move
[141.459112] Starting dynamic subtest: pipe-A
[146.840546] Dynamic subtest pipe-A: SUCCESS (5.380s)
[146.849125] Starting dynamic subtest: pipe-B
[152.222897] Dynamic subtest pipe-B: SUCCESS (5.380s)
[152.230837] Starting dynamic subtest: pipe-C
[157.605779] Dynamic subtest pipe-C: SUCCESS (5.380s)
[157.614168] Starting dynamic subtest: pipe-D
[162.987814] Dynamic subtest pipe-D: SUCCESS (5.380s)
[162.995092] Starting dynamic subtest: all-pipes
[168.377083] Dynamic subtest all-pipes: SUCCESS (5.387s)
[168.379361] Subtest single-move: SUCCESS (26.914s)
[168.522698] [013/133] (850s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[168.630110] Starting subtest: pipe-fbc-rte
[168.636977] Starting dynamic subtest: pipe-B-eDP-1
[174.114830] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.487s)
[174.122988] Subtest pipe-fbc-rte: SUCCESS (5.490s)
[176.435817] [014/133] (842s left) gem_exec_params (no-bsd)
[176.496030] Starting subtest: no-bsd
[176.498415] Subtest no-bsd: SUCCESS (0.002s)
[177.936138] [015/133] (840s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[178.035588] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[181.674598] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (3.642s)
[182.844161] [016/133] (835s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[182.941609] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc
[182.945665] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[183.031239] [017/133] (835s left) kms_cursor_legacy (2x-cursor-vs-flip-atomic)
[183.150230] Starting subtest: 2x-cursor-vs-flip-atomic
[183.154549] Subtest 2x-cursor-vs-flip-atomic: SKIP (0.001s)
[183.296228] [018/133] (835s left) gem_render_copy_redux (flink-interruptible)
[183.363220] Starting subtest: flink-interruptible
[184.166187] Subtest flink-interruptible: SUCCESS (0.803s)
[184.292898] [019/133] (834s left) kms_ccs (crc-primary-suspend-4-tiled-bmg-ccs)
[184.388204] Starting subtest: crc-primary-suspend-4-tiled-bmg-ccs
[184.391823] Subtest crc-primary-suspend-4-tiled-bmg-ccs: SKIP (0.000s)
[185.476026] [020/133] (833s left) vgem_basic (sysfs)
[185.519066] Starting subtest: sysfs
[185.521221] Subtest sysfs: SUCCESS (0.000s)
[185.588218] [021/133] (833s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted)
[185.659204] Starting subtest: multi-wait-all-for-submit-available-submitted
[185.661498] Subtest multi-wait-all-for-submit-available-submitted: SUCCESS (0.001s)
[185.756879] [022/133] (833s left) kms_psr (fbc-pr-cursor-mmap-cpu)
[185.856825] Starting subtest: fbc-pr-cursor-mmap-cpu
[185.860673] Subtest fbc-pr-cursor-mmap-cpu: SKIP (0.000s)
[185.939913] [023/133] (833s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[186.042639] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[186.046624] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[186.122518] [024/133] (833s left) syncobj_basic (test-valid-cycle)
[186.169036] Starting subtest: test-valid-cycle
[186.170963] Subtest test-valid-cycle: SUCCESS (0.000s)
[186.251627] [025/133] (832s left) kms_cursor_crc (cursor-random-128x128)
[186.480071] Starting subtest: cursor-random-128x128
[186.484161] Starting dynamic subtest: pipe-A-eDP-1
[189.797390] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.311s)
[189.825298] Starting dynamic subtest: pipe-D-eDP-1
[193.795807] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.975s)
[193.798077] Subtest cursor-random-128x128: SUCCESS (7.315s)
[194.644513] [026/133] (824s left) vgem_basic (mmap)
[194.708778] Starting subtest: mmap
[194.731958] Subtest mmap: SUCCESS (0.025s)
[194.802313] [027/133] (824s left) kms_content_protection (uevent)
[194.954800] Starting subtest: uevent
[194.959264] Subtest uevent: SKIP (0.000s)
[195.068326] [028/133] (824s left) kms_plane (plane-position-covered)
[195.162821] Starting subtest: plane-position-covered
[195.699526] Starting dynamic subtest: pipe-A-plane-1
[197.073446] Dynamic subtest pipe-A-plane-1: SUCCESS (1.371s)
[197.083428] Starting dynamic subtest: pipe-A-plane-2
[198.373331] Dynamic subtest pipe-A-plane-2: SUCCESS (1.300s)
[198.381851] Starting dynamic subtest: pipe-A-plane-3
[199.691780] Dynamic subtest pipe-A-plane-3: SUCCESS (1.317s)
[199.700499] Starting dynamic subtest: pipe-A-plane-4
[201.051560] Dynamic subtest pipe-A-plane-4: SUCCESS (1.361s)
[201.059671] Starting dynamic subtest: pipe-A-plane-5
[201.079048] Dynamic subtest pipe-A-plane-5: SUCCESS (0.025s)
[202.298410] Starting dynamic subtest: pipe-B-plane-1
[203.635684] Dynamic subtest pipe-B-plane-1: SUCCESS (1.344s)
[203.643863] Starting dynamic subtest: pipe-B-plane-2
[204.944238] Dynamic subtest pipe-B-plane-2: SUCCESS (1.307s)
[204.951734] Starting dynamic subtest: pipe-B-plane-3
[206.310337] Dynamic subtest pipe-B-plane-3: SUCCESS (1.366s)
[206.318569] Starting dynamic subtest: pipe-B-plane-4
[207.596221] Dynamic subtest pipe-B-plane-4: SUCCESS (1.283s)
[207.604496] Starting dynamic subtest: pipe-B-plane-5
[207.620795] Dynamic subtest pipe-B-plane-5: SUCCESS (0.024s)
[207.623226] Subtest plane-position-covered: SUCCESS (12.457s)
[208.482882] [029/133] (810s left) perf_pmu (init-sema)
[208.526551] Starting subtest: init-sema
[208.528992] Starting dynamic subtest: rcs0
[208.531403] Dynamic subtest rcs0: SUCCESS (0.002s)
[208.542062] Starting dynamic subtest: bcs0
[208.544297] Dynamic subtest bcs0: SUCCESS (0.001s)
[208.551753] Starting dynamic subtest: vcs0
[208.553710] Dynamic subtest vcs0: SUCCESS (0.001s)
[208.560627] Starting dynamic subtest: vcs1
[208.562497] Dynamic subtest vcs1: SUCCESS (0.001s)
[208.569206] Starting dynamic subtest: vecs0
[208.571068] Dynamic subtest vecs0: SUCCESS (0.001s)
[208.578170] Starting dynamic subtest: ccs0
[208.580249] Dynamic subtest ccs0: SUCCESS (0.000s)
[208.582363] Subtest init-sema: SUCCESS (0.009s)
[208.631249] [030/133] (810s left) gem_mmap_gtt (basic)
[208.693553] Subtest basic: SKIP (0.000s)
[208.752080] [031/133] (810s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-blt)
[208.846193] Starting subtest: psr-2p-primscrn-spr-indfb-draw-blt
[208.850096] Subtest psr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[208.935249] [032/133] (810s left) kms_plane_cursor (primary)
[209.024785] Starting subtest: primary
[209.028833] Starting dynamic subtest: pipe-A-eDP-1-size-64
[211.611728] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.586s)
[211.639179] Starting dynamic subtest: pipe-A-eDP-1-size-128
[214.179824] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.549s)
[214.202287] Starting dynamic subtest: pipe-A-eDP-1-size-256
[216.791556] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.594s)
[217.107840] Starting dynamic subtest: pipe-B-eDP-1-size-64
[220.393992] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.296s)
[220.416719] Starting dynamic subtest: pipe-B-eDP-1-size-128
[222.973495] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.563s)
[222.995290] Starting dynamic subtest: pipe-B-eDP-1-size-256
[225.607186] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.616s)
[225.926190] Starting dynamic subtest: pipe-C-eDP-1-size-64
[228.835302] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.924s)
[228.860186] Starting dynamic subtest: pipe-C-eDP-1-size-128
[231.035370] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.181s)
[231.060758] Starting dynamic subtest: pipe-C-eDP-1-size-256
[233.303139] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.247s)
[233.629315] Starting dynamic subtest: pipe-D-eDP-1-size-64
[236.555125] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.940s)
[236.580411] Starting dynamic subtest: pipe-D-eDP-1-size-128
[238.767300] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.192s)
[238.792311] Starting dynamic subtest: pipe-D-eDP-1-size-256
[240.989211] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.203s)
[241.298323] Subtest primary: SUCCESS (32.274s)
[242.107150] [033/133] (777s left) gem_exec_params (invalid-ring2)
[242.164414] Starting subtest: invalid-ring2
[242.166654] Subtest invalid-ring2: SUCCESS (0.000s)
[242.269704] [034/133] (777s left) gem_madvise (dontneed-after-mmap)
[242.308927] Starting subtest: dontneed-after-mmap
[242.346190] Subtest dontneed-after-mmap: SUCCESS (0.038s)
[242.473562] [035/133] (777s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[242.536181] Subtest basic-cpu-gtt-noreloc: SKIP (0.000s)
[242.602880] [036/133] (777s left) gem_pxp (create-valid-protected-context)
[242.722591] Starting subtest: create-valid-protected-context
[242.724986] Subtest create-valid-protected-context: SUCCESS (0.002s)
[242.826335] [037/133] (776s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[242.927793] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[242.932043] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[243.032292] [038/133] (776s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-yf-tiled)
[243.094080] Starting subtest: y-tiled-mc-ccs-to-vebox-yf-tiled
[243.098016] Subtest y-tiled-mc-ccs-to-vebox-yf-tiled: SKIP (0.000s)
[243.292761] [039/133] (776s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions-varying-size)
[243.412792] Starting subtest: cursorB-vs-flipA-atomic-transitions-varying-size
[243.417488] Subtest cursorB-vs-flipA-atomic-transitions-varying-size: SKIP (0.001s)
[243.559022] [040/133] (776s left) prime_self_import (basic-with_one_bo)
[243.600800] Starting subtest: basic-with_one_bo
[243.617786] Subtest basic-with_one_bo: SUCCESS (0.021s)
[243.744773] [041/133] (776s left) perf (gen12-unprivileged-single-ctx-counters)
[243.886416] Starting subtest: gen12-unprivileged-single-ctx-counters
[243.888252] Starting dynamic subtest: rcs0
[243.923264] Dynamic subtest rcs0: SUCCESS (0.037s)
[243.925515] Subtest gen12-unprivileged-single-ctx-counters: SUCCESS (0.038s)
[243.972741] [042/133] (775s left) syncobj_wait (single-wait-for-submit-unsubmitted)
[244.050472] Starting subtest: single-wait-for-submit-unsubmitted
[244.150461] Subtest single-wait-for-submit-unsubmitted: SUCCESS (0.100s)
[244.222722] [043/133] (775s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-cpu)
[244.312062] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-cpu
[248.330273] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (4.020s)
[249.522722] [044/133] (770s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling)
[249.623656] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling
[249.628089] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SKIP (0.000s)
[249.696804] [045/133] (770s left) kms_busy (extended-modeset-hang-newfb-with-reset)
[249.800823] Starting subtest: extended-modeset-hang-newfb-with-reset
[249.802776] Starting dynamic subtest: pipe-A
[265.150263] Dynamic subtest pipe-A: SUCCESS (15.348s)
[265.158571] Starting dynamic subtest: pipe-D
[280.740079] Dynamic subtest pipe-D: SUCCESS (15.588s)
[280.742273] Subtest extended-modeset-hang-newfb-with-reset: SUCCESS (30.940s)
[281.535985] [046/133] (738s left) i915_pm_rpm (system-suspend-devices)
[283.068352] Starting subtest: system-suspend-devices
[290.874694] Subtest system-suspend-devices: SUCCESS (7.809s)
[291.263434] [047/133] (728s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[291.392918] Starting subtest: addfb25-y-tiled-legacy
[291.395959] Subtest addfb25-y-tiled-legacy: SUCCESS (0.001s)
[291.496809] [048/133] (728s left) gem_mmap_gtt (flink-race)
[291.559138] Subtest flink-race: SKIP (0.000s)
[291.623516] [049/133] (728s left) kms_cursor_crc (cursor-offscreen-max-size)
[291.946979] Starting subtest: cursor-offscreen-max-size
[291.953089] Subtest cursor-offscreen-max-size: SKIP (0.000s)
[292.067332] [050/133] (728s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[292.121701] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[292.225260] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.103s)
[292.300459] [051/133] (727s left) kms_lease (multimaster-lease)
[292.394301] Starting subtest: multimaster-lease
[292.396135] Subtest multimaster-lease: SUCCESS (0.003s)
[292.512728] [052/133] (727s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-upscaling)
[292.607117] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-upscaling
[292.611130] Subtest flip-64bpp-yftile-to-16bpp-yftile-upscaling: SKIP (0.000s)
[292.678253] [053/133] (727s left) gem_eio (wait-10ms)
[292.887827] Starting subtest: wait-10ms
[293.374158] Subtest wait-10ms: SUCCESS (0.487s)
[293.498396] [054/133] (726s left) syncobj_timeline (multi-wait-for-submit-available-signaled)
[293.564898] Starting subtest: multi-wait-for-submit-available-signaled
[293.567345] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.001s)
[293.676463] [055/133] (726s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[293.779403] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip
[293.783910] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[293.875458] [056/133] (726s left) kms_rotation_crc (cursor-rotation-180)
[293.974783] Starting subtest: cursor-rotation-180
[295.530086] Subtest cursor-rotation-180: SUCCESS (1.556s)
[296.716461] [057/133] (723s left) gem_exec_fence (syncobj-signal)
[297.178814] Starting subtest: syncobj-signal
[297.183760] Subtest syncobj-signal: SUCCESS (0.004s)
[297.958717] [058/133] (722s left) kms_vblank (query-busy-hang)
[298.053640] Starting subtest: query-busy-hang
[298.055596] Starting dynamic subtest: pipe-A-eDP-1
[324.693602] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.638s)
[324.707232] Starting dynamic subtest: pipe-D-eDP-1
[349.653252] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.958s)
[349.655211] Subtest query-busy-hang: SUCCESS (51.600s)
[350.463898] [059/133] (670s left) gem_pxp (verify-pxp-key-change-after-suspend-resume)
[350.571376] Starting subtest: verify-pxp-key-change-after-suspend-resume
[358.460139] Subtest verify-pxp-key-change-after-suspend-resume: SUCCESS (7.890s)
[358.568585] [060/133] (662s left) syncobj_timeline (single-wait-available-signaled)
[358.622401] Starting subtest: single-wait-available-signaled
[358.624370] Subtest single-wait-available-signaled: SUCCESS (0.000s)
[358.704842] [061/133] (661s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier)
[358.795321] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier
[358.799338] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier: SKIP (0.000s)
[358.887447] [062/133] (661s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[358.984505] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[359.609612] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.626s)
[359.771739] [063/133] (660s left) kms_pm_rpm (pm-tiling)
[362.001333] Starting subtest: pm-tiling
[362.006325] Subtest pm-tiling: SKIP (0.000s)
[362.394519] [064/133] (658s left) kms_plane_scaling (plane-upscale-factor-0-25-with-rotation)
[362.482418] Starting subtest: plane-upscale-factor-0-25-with-rotation
[362.484811] Starting dynamic subtest: pipe-A
[362.710477] Dynamic subtest pipe-A: SUCCESS (0.229s)
[362.719659] Starting dynamic subtest: pipe-B
[364.055256] Dynamic subtest pipe-B: SUCCESS (1.341s)
[364.064569] Starting dynamic subtest: pipe-C
[365.366430] Dynamic subtest pipe-C: SUCCESS (1.313s)
[365.379373] Starting dynamic subtest: pipe-D
[366.689093] Dynamic subtest pipe-D: SUCCESS (1.321s)
[366.691319] Subtest plane-upscale-factor-0-25-with-rotation: SUCCESS (4.209s)
[367.890390] [065/133] (652s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted)
[367.952447] Starting subtest: multi-wait-for-submit-unsubmitted-submitted
[368.056781] Subtest multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.104s)
[368.137354] [066/133] (652s left) gem_exec_parallel (contexts)
[368.199317] Starting subtest: contexts
[368.201722] Starting dynamic subtest: rcs0
[370.947970] Dynamic subtest rcs0: SUCCESS (2.746s)
[370.959277] Starting dynamic subtest: bcs0
[374.647086] Dynamic subtest bcs0: SUCCESS (3.696s)
[374.656419] Starting dynamic subtest: vcs0
[378.323250] Dynamic subtest vcs0: SUCCESS (3.673s)
[378.335267] Starting dynamic subtest: vcs1
[382.413189] Dynamic subtest vcs1: SUCCESS (4.087s)
[382.424700] Starting dynamic subtest: vecs0
[386.419919] Dynamic subtest vecs0: SUCCESS (4.003s)
[386.431882] Starting dynamic subtest: ccs0
[390.043335] Dynamic subtest ccs0: SUCCESS (3.622s)
[390.046301] Subtest contexts: SUCCESS (21.843s)
[390.241832] [067/133] (630s left) kms_plane_alpha_blend (alpha-7efc)
[390.356800] Starting subtest: alpha-7efc
[390.654761] Starting dynamic subtest: pipe-A-eDP-1
[391.261116] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.603s)
[391.288235] Starting dynamic subtest: pipe-D-eDP-1
[392.587707] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.307s)
[392.590305] Subtest alpha-7efc: SUCCESS (2.232s)
[393.434385] [068/133] (627s left) i915_suspend (fence-restore-tiled2untiled)
[393.492611] Starting subtest: fence-restore-tiled2untiled
[393.496778] Subtest fence-restore-tiled2untiled: SKIP (0.000s)
[393.597614] [069/133] (627s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-blt)
[393.690186] Starting subtest: psr-1p-primscrn-cur-indfb-draw-blt
[400.451209] Subtest psr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.764s)
[400.589033] [070/133] (620s left) kms_psr (psr2-sprite-mmap-gtt)
[400.679683] Starting subtest: psr2-sprite-mmap-gtt
[400.681930] Starting dynamic subtest: eDP-1
[402.087962] Dynamic subtest eDP-1: SKIP (1.404s)
[402.092917] Subtest psr2-sprite-mmap-gtt: SKIP (1.407s)
[402.232765] [071/133] (618s left) gem_ctx_isolation (nonpriv-switch)
[402.299729] Starting subtest: nonpriv-switch
[402.302060] Starting dynamic subtest: rcs0
[402.622043] Dynamic subtest rcs0: SUCCESS (0.322s)
[402.634789] Starting dynamic subtest: bcs0
[402.925960] Dynamic subtest bcs0: SUCCESS (0.303s)
[402.935443] Starting dynamic subtest: vcs0
[403.245379] Dynamic subtest vcs0: SUCCESS (0.319s)
[403.254173] Starting dynamic subtest: vcs1
[405.187667] Dynamic subtest vcs1: SUCCESS (1.941s)
[405.198936] Starting dynamic subtest: vecs0
[407.229860] Dynamic subtest vecs0: SUCCESS (2.041s)
[407.241070] Starting dynamic subtest: ccs0
[409.264233] Dynamic subtest ccs0: SUCCESS (2.033s)
[409.266569] Subtest nonpriv-switch: SUCCESS (6.965s)
[409.509681] [072/133] (611s left) kms_ccs (missing-ccs-buffer-y-tiled-ccs)
[409.606894] Starting subtest: missing-ccs-buffer-y-tiled-ccs
[409.609042] Starting dynamic subtest: pipe-A-eDP-1
[409.631164] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[409.642799] Starting dynamic subtest: pipe-B-eDP-1
[409.663088] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[409.671802] Starting dynamic subtest: pipe-C-eDP-1
[409.692639] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[409.701543] Starting dynamic subtest: pipe-D-eDP-1
[409.723198] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[409.727176] Subtest missing-ccs-buffer-y-tiled-ccs: SKIP (0.005s)
[411.869597] [073/133] (609s left) gem_softpin (softpin)
[411.927149] Starting subtest: softpin
[444.226733] Subtest softpin: SUCCESS (32.294s)
[444.351923] [074/133] (576s left) gem_blits (basic)
[444.410212] Starting subtest: basic
[446.569658] Subtest basic: SUCCESS (2.158s)
[446.677304] [075/133] (574s left) kms_draw_crc (draw-method-mmap-gtt)
[446.776087] Starting subtest: draw-method-mmap-gtt
[446.780492] Subtest draw-method-mmap-gtt: SKIP (0.000s)
[446.876158] [076/133] (574s left) kms_psr (fbc-psr2-primary-mmap-gtt)
[446.973191] Starting subtest: fbc-psr2-primary-mmap-gtt
[446.975492] Starting dynamic subtest: eDP-1
[446.979781] Dynamic subtest eDP-1: SKIP (0.000s)
[446.984094] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.001s)
[447.047441] [077/133] (574s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-move)
[447.149289] Starting subtest: psr-1p-primscrn-spr-indfb-move
[454.764979] Subtest psr-1p-primscrn-spr-indfb-move: SUCCESS (7.618s)
[454.889744] [078/133] (566s left) gem_exercise_blt (fast-copy-emit)
[454.944079] Starting subtest: fast-copy-emit
[454.946350] Starting dynamic subtest: linear-smem-smem-emit
[454.966037] Dynamic subtest linear-smem-smem-emit: SUCCESS (0.022s)
[454.977246] Starting dynamic subtest: xmajor-smem-smem-emit
[454.983092] Dynamic subtest xmajor-smem-smem-emit: SUCCESS (0.016s)
[454.992143] Starting dynamic subtest: tile4-smem-smem-emit
[454.999948] Dynamic subtest tile4-smem-smem-emit: SUCCESS (0.016s)
[455.008818] Starting dynamic subtest: tile64-smem-smem-emit
[455.016189] Dynamic subtest tile64-smem-smem-emit: SUCCESS (0.016s)
[455.018421] Subtest fast-copy-emit: SUCCESS (0.073s)
[455.142706] [079/133] (566s left) sysfs_heartbeat_interval (nopreempt)
[455.194341] Starting subtest: nopreempt
[455.196562] Starting dynamic subtest: vecs0
[464.918201] Dynamic subtest vecs0: SUCCESS (9.720s)
[464.929311] Starting dynamic subtest: ccs0
[468.949652] Dynamic subtest ccs0: SUCCESS (4.031s)
[468.960817] Starting dynamic subtest: vcs0
[478.931178] Dynamic subtest vcs0: SUCCESS (9.982s)
[478.943304] Starting dynamic subtest: bcs0
[482.914864] Dynamic subtest bcs0: SUCCESS (3.980s)
[482.926618] Starting dynamic subtest: rcs0
[486.942382] Dynamic subtest rcs0: SUCCESS (4.026s)
[486.955322] Starting dynamic subtest: vcs1
[496.912135] Dynamic subtest vcs1: SUCCESS (9.967s)
[496.914586] Subtest nopreempt: SUCCESS (41.718s)
[496.993238] [080/133] (524s left) gem_ctx_shared (q-in-order)
[497.065723] Starting subtest: Q-in-order
[497.068089] Starting dynamic subtest: rcs0
[497.257098] Dynamic subtest rcs0: SUCCESS (0.189s)
[497.270936] Starting dynamic subtest: vcs0
[497.385363] Dynamic subtest vcs0: SUCCESS (0.127s)
[497.407568] Starting dynamic subtest: vcs1
[497.495587] Dynamic subtest vcs1: SUCCESS (0.111s)
[497.513915] Starting dynamic subtest: vecs0
[497.608232] Dynamic subtest vecs0: SUCCESS (0.112s)
[497.625857] Starting dynamic subtest: ccs0
[497.780453] Dynamic subtest ccs0: SUCCESS (0.170s)
[497.783250] Subtest Q-in-order: SUCCESS (0.714s)
[498.992824] [081/133] (522s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[499.114362] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[499.118716] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[499.225484] [082/133] (522s left) gem_readwrite (read-bad-handle)
[499.284750] Subtest read-bad-handle: SKIP (0.000s)
[499.346945] [083/133] (522s left) gem_ctx_persistence (processes)
[499.405184] Starting subtest: processes
[500.892785] Subtest processes: SUCCESS (1.490s)
[500.991602] [084/133] (520s left) kms_hdr (static-swap)
[501.094268] Starting subtest: static-swap
[501.100407] Subtest static-swap: SKIP (0.000s)
[501.171960] [085/133] (520s left) kms_chamelium_hpd (vga-hpd)
[501.555161] Subtest vga-hpd: SKIP (0.000s)
[502.390034] [086/133] (519s left) kms_big_fb (linear-32bpp-rotate-0)
[502.503588] Starting subtest: linear-32bpp-rotate-0
[504.104456] Subtest linear-32bpp-rotate-0: SUCCESS (1.601s)
[504.237970] [087/133] (517s left) kms_psr (psr2-cursor-blt)
[504.341485] Starting subtest: psr2-cursor-blt
[504.343495] Starting dynamic subtest: eDP-1
[506.081829] Dynamic subtest eDP-1: SUCCESS (1.741s)
[506.084249] Subtest psr2-cursor-blt: SUCCESS (1.742s)
[506.870676] [088/133] (514s left) gem_exec_capture (pi)
[506.930143] Starting subtest: pi
[506.932410] Starting dynamic subtest: rcs0
[511.360890] Dynamic subtest rcs0: SUCCESS (4.432s)
[511.373582] Starting dynamic subtest: bcs0
[515.292588] Dynamic subtest bcs0: SUCCESS (3.930s)
[515.304734] Starting dynamic subtest: vcs0
[519.244918] Dynamic subtest vcs0: SUCCESS (3.951s)
[519.254644] Starting dynamic subtest: vcs1
[523.188043] Dynamic subtest vcs1: SUCCESS (3.941s)
[523.199902] Starting dynamic subtest: vecs0
[527.197055] Dynamic subtest vecs0: SUCCESS (4.007s)
[527.209540] Starting dynamic subtest: ccs0
[531.186752] Dynamic subtest ccs0: SUCCESS (3.987s)
[531.189286] Subtest pi: SUCCESS (24.260s)
[531.285761] [089/133] (490s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs-cc)
[531.426992] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs-cc
[531.429311] Starting dynamic subtest: pipe-A-eDP-1
[531.449945] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[531.459893] Starting dynamic subtest: pipe-B-eDP-1
[531.479586] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[531.489200] Starting dynamic subtest: pipe-C-eDP-1
[531.508794] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[531.519835] Starting dynamic subtest: pipe-D-eDP-1
[531.539725] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[531.541877] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.006s)
[533.733426] [090/133] (488s left) gem_eio (in-flight-contexts-1us)
[533.957810] Starting subtest: in-flight-contexts-1us
[545.888862] Subtest in-flight-contexts-1us: SUCCESS (11.931s)
[546.056634] [091/133] (475s left) kms_ccs (random-ccs-data-4-tiled-lnl-ccs)
[546.157978] Starting subtest: random-ccs-data-4-tiled-lnl-ccs
[546.162534] Subtest random-ccs-data-4-tiled-lnl-ccs: SKIP (0.000s)
[547.260420] [092/133] (474s left) kms_pm_backlight (fade)
[547.351566] Starting subtest: fade
[547.435214] Starting dynamic subtest: eDP-1
[549.911689] Dynamic subtest eDP-1: SUCCESS (2.478s)
[549.913949] Subtest fade: SUCCESS (2.560s)
[550.774768] [093/133] (471s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[550.874417] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen
[550.878837] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[550.984104] [094/133] (471s left) gem_partial_pwrite_pread (write-uncached)
[551.042271] Subtest write-uncached: SKIP (0.000s)
[551.114794] [095/133] (471s left) gem_exec_schedule (reorder-wide)
[551.315162] Subtest reorder-wide: SKIP (0.000s)
[551.500677] [096/133] (470s left) gem_exec_params (sol-reset-invalid)
[551.555341] Starting subtest: sol-reset-invalid
[551.560229] Subtest sol-reset-invalid: SUCCESS (0.005s)
[552.987510] [097/133] (469s left) kms_chamelium_edid (hdmi-edid-stress-resolution-non-4k)
[553.371681] Subtest hdmi-edid-stress-resolution-non-4k: SKIP (0.000s)
[554.183904] [098/133] (468s left) i915_pm_freq_api (freq-suspend)
[554.246064] Starting subtest: freq-suspend
[554.248675] Starting dynamic subtest: gt0
[570.333840] Dynamic subtest gt0: SUCCESS (1.230s)
[570.346094] Starting dynamic subtest: gt1
[586.343583] Dynamic subtest gt1: SUCCESS (1.230s)
[586.345736] Subtest freq-suspend: SUCCESS (2.462s)
[586.434240] [099/133] (435s left) syncobj_wait (wait-for-submit-delayed-submit)
[586.495639] Starting subtest: wait-for-submit-delayed-submit
[586.596748] Subtest wait-for-submit-delayed-submit: SUCCESS (0.102s)
[586.678443] [100/133] (435s left) kms_big_fb (4-tiled-8bpp-rotate-270)
[586.788773] Starting subtest: 4-tiled-8bpp-rotate-270
[586.808959] Subtest 4-tiled-8bpp-rotate-270: SKIP (0.020s)
[586.929942] [101/133] (435s left) kms_fbcon_fbt (psr-suspend)
[586.994106] Starting subtest: psr-suspend
[636.326356] Subtest psr-suspend: SUCCESS (20.057s)
[636.405242] [102/133] (386s left) gem_softpin (overlap)
[636.468667] Starting subtest: overlap
[637.345180] Subtest overlap: SUCCESS (0.893s)
[637.458062] [103/133] (385s left) kms_frontbuffer_tracking (fbc-stridechange)
[637.557588] Starting subtest: fbc-stridechange
[640.785763] Subtest fbc-stridechange: SUCCESS (3.284s)
[641.976090] [104/133] (380s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[642.078521] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[642.082779] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[642.164308] [105/133] (380s left) kms_atomic_interruptible (legacy-cursor)
[642.246867] Starting subtest: legacy-cursor
[642.249092] Starting dynamic subtest: pipe-A-eDP-1
[649.665701] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.468s)
[649.667997] Subtest legacy-cursor: SUCCESS (7.469s)
[650.473366] [106/133] (372s left) kms_psr2_sf (fbc-pr-primary-plane-update-sf-dmg-area)
[650.574759] Starting subtest: fbc-pr-primary-plane-update-sf-dmg-area
[650.579751] Subtest fbc-pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[650.695879] [107/133] (371s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs)
[650.797969] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs
[650.799827] Starting dynamic subtest: pipe-A-eDP-1
[651.684936] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.884s)
[651.695298] Starting dynamic subtest: pipe-B-eDP-1
[653.262170] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.577s)
[653.272397] Starting dynamic subtest: pipe-C-eDP-1
[654.819183] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.560s)
[654.829236] Starting dynamic subtest: pipe-D-eDP-1
[656.312290] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.496s)
[656.314389] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (5.524s)
[657.462705] [108/133] (365s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[657.572573] Starting subtest: psr2-cursor-plane-update-sf
[657.574824] Starting dynamic subtest: pipe-A-eDP-1
[658.139768] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.571s)
[658.150184] Starting dynamic subtest: pipe-B-eDP-1
[659.445974] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.307s)
[659.448170] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.881s)
[660.307527] [109/133] (362s left) gem_exec_flush (basic-batch-kernel-default-wb)
[660.395573] Starting subtest: basic-batch-kernel-default-wb
[668.019278] Subtest basic-batch-kernel-default-wb: SUCCESS (7.628s)
[668.213166] [110/133] (354s left) gem_mmap (bad-size)
[668.261891] Subtest bad-size: SKIP (0.000s)
[668.319490] [111/133] (354s left) gem_softpin (allocator-evict)
[668.373838] Starting subtest: allocator-evict
[668.376042] Starting dynamic subtest: rcs0
[692.130659] Dynamic subtest rcs0: SUCCESS (23.749s)
[692.141144] Starting dynamic subtest: bcs0
[716.300900] Dynamic subtest bcs0: SUCCESS (24.174s)
[716.313554] Starting dynamic subtest: vcs0
[739.991971] Dynamic subtest vcs0: SUCCESS (23.689s)
[740.002178] Starting dynamic subtest: vcs1
[763.470047] Dynamic subtest vcs1: SUCCESS (23.473s)
[763.492691] Starting dynamic subtest: vecs0
[787.153817] Dynamic subtest vecs0: SUCCESS (23.683s)
[787.166658] Starting dynamic subtest: ccs0
[810.888044] Dynamic subtest ccs0: SUCCESS (23.736s)
[810.890719] Subtest allocator-evict: SUCCESS (142.511s)
[810.975395] [112/133] (211s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[811.130042] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[811.142611] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.011s)
[811.224008] [113/133] (211s left) gem_exec_schedule (deep)
[811.412340] Starting subtest: deep
[811.414430] Starting dynamic subtest: rcs0
[816.942211] Dynamic subtest rcs0: SKIP (5.528s)
[816.954812] Starting dynamic subtest: vcs0
[827.310156] Dynamic subtest vcs0: SUCCESS (10.371s)
[827.322918] Starting dynamic subtest: vcs1
[838.742657] Dynamic subtest vcs1: SUCCESS (11.434s)
[838.756065] Starting dynamic subtest: vecs0
[850.625544] Dynamic subtest vecs0: SUCCESS (11.883s)
[850.636537] Starting dynamic subtest: ccs0
[863.516259] Dynamic subtest ccs0: SUCCESS (12.890s)
[863.519093] Subtest deep: SUCCESS (52.112s)
[864.989338] [114/133] (157s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[865.115541] Starting subtest: basic-busy-flip-before-cursor-varying-size
[865.120089] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[865.259332] [115/133] (157s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[865.380177] Starting subtest: basic-flip-after-cursor-varying-size
[866.342139] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.959s)
[867.204824] [116/133] (155s left) kms_vblank (ts-continuation-idle-hang)
[867.304503] Starting subtest: ts-continuation-idle-hang
[867.306823] Starting dynamic subtest: pipe-A-eDP-1
[894.076429] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.763s)
[894.092370] Starting dynamic subtest: pipe-D-eDP-1
[909.118878] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.037s)
[909.120913] Subtest ts-continuation-idle-hang: SUCCESS (41.805s)
[909.962465] [117/133] (113s left) gem_userptr_blits (invalid-mmap-offset-unsync)
[910.044121] Subtest invalid-mmap-offset-unsync: SKIP (0.000s)
[910.156368] [118/133] (112s left) kms_frontbuffer_tracking (psr-rgb101010-draw-blt)
[910.259696] Starting subtest: psr-rgb101010-draw-blt
[917.624111] Subtest psr-rgb101010-draw-blt: SUCCESS (7.365s)
[917.759595] [119/133] (105s left) gem_close_race (multigpu-basic-threads)
[917.821842] Starting subtest: multigpu-basic-threads
[917.826285] Subtest multigpu-basic-threads: SKIP (0.000s)
[918.106754] [120/133] (105s left) dmabuf_sync_file (import-basic)
[918.165222] Starting subtest: import-basic
[918.167599] Subtest import-basic: SUCCESS (0.003s)
[918.222152] [121/133] (105s left) prime_busy (before-wait)
[918.288484] Starting subtest: before-wait
[918.293689] Starting dynamic subtest: rcs0
[918.317440] Dynamic subtest rcs0: SUCCESS (0.027s)
[918.329268] Starting dynamic subtest: bcs0
[918.374168] Dynamic subtest bcs0: SUCCESS (0.056s)
[918.385567] Starting dynamic subtest: vcs0
[918.413933] Dynamic subtest vcs0: SUCCESS (0.037s)
[918.425157] Starting dynamic subtest: vcs1
[918.451856] Dynamic subtest vcs1: SUCCESS (0.039s)
[918.461739] Starting dynamic subtest: vecs0
[918.492002] Dynamic subtest vecs0: SUCCESS (0.039s)
[918.501894] Starting dynamic subtest: ccs0
[918.531744] Dynamic subtest ccs0: SUCCESS (0.039s)
[918.640483] Subtest before-wait: SUCCESS (0.351s)
[918.719418] [122/133] (104s left) gem_fence_thrash (bo-write-verify-threaded-none)
[918.768045] Starting subtest: bo-write-verify-threaded-none
[918.793312] Subtest bo-write-verify-threaded-none: SKIP (0.024s)
[918.865000] [123/133] (104s left) kms_psr (psr-suspend)
[918.960325] Starting subtest: psr-suspend
[918.962594] Starting dynamic subtest: eDP-1
[936.786275] Dynamic subtest eDP-1: SUCCESS (2.893s)
[936.788529] Subtest psr-suspend: SUCCESS (2.895s)
[937.641295] [124/133] ( 85s left) gem_readwrite (new-obj)
[937.705188] Subtest new-obj: SKIP (0.000s)
[937.761412] [125/133] ( 85s left) kms_frontbuffer_tracking (fbc-suspend)
[937.856757] Starting subtest: fbc-suspend
[975.813980] Subtest fbc-suspend: SUCCESS (7.452s)
[977.011146] [126/133] ( 46s left) gem_exec_whisper (basic-forked)
[977.079727] Starting subtest: basic-forked
[984.322331] Subtest basic-forked: SUCCESS (7.230s)
[984.524017] [127/133] ( 39s left) kms_atomic_transition (modeset-transition)
[984.625083] Starting subtest: modeset-transition
[984.627237] Starting dynamic subtest: 1x-outputs
[995.555438] Dynamic subtest 1x-outputs: SUCCESS (10.921s)
[995.558310] Subtest modeset-transition: SUCCESS (10.923s)
[996.415615] [128/133] ( 27s left) kms_color_pipeline (plane-lut1d-pre-ctm3x4)
[996.512263] Starting subtest: plane-lut1d-pre-ctm3x4
[996.658050] Starting dynamic subtest: pipe-A-plane-0
[997.063594] Dynamic subtest pipe-A-plane-0: FAIL (0.194s)
[997.076048] Starting dynamic subtest: pipe-A-plane-1
[997.317929] Dynamic subtest pipe-A-plane-1: FAIL (0.191s)
[997.329016] Starting dynamic subtest: pipe-A-plane-2
[997.569573] Dynamic subtest pipe-A-plane-2: FAIL (0.165s)
[998.421348] Starting dynamic subtest: pipe-B-plane-0
[998.887360] Dynamic subtest pipe-B-plane-0: FAIL (0.164s)
[998.897906] Starting dynamic subtest: pipe-B-plane-1
[999.266035] Dynamic subtest pipe-B-plane-1: FAIL (0.165s)
[999.277304] Starting dynamic subtest: pipe-B-plane-2
[999.626190] Dynamic subtest pipe-B-plane-2: FAIL (0.699s)
[1000.640396] Starting dynamic subtest: pipe-C-plane-0
[1001.204085] Dynamic subtest pipe-C-plane-0: FAIL (0.129s)
[1001.215853] Starting dynamic subtest: pipe-C-plane-1
[1001.666345] Dynamic subtest pipe-C-plane-1: FAIL (0.693s)
[1001.675957] Starting dynamic subtest: pipe-C-plane-2
[1002.112865] Dynamic subtest pipe-C-plane-2: FAIL (0.165s)
[1002.779494] Starting dynamic subtest: pipe-D-plane-0
[1003.479912] Dynamic subtest pipe-D-plane-0: FAIL (0.162s)
[1003.491721] Starting dynamic subtest: pipe-D-plane-1
[1004.021621] Dynamic subtest pipe-D-plane-1: FAIL (0.700s)
[1004.033129] Starting dynamic subtest: pipe-D-plane-2
[1004.562594] Dynamic subtest pipe-D-plane-2: FAIL (0.645s)
[1004.583695] Subtest plane-lut1d-pre-ctm3x4: FAIL (8.070s)
[1005.432191] [129/133] ( 18s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[1005.542164] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[1005.547079] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[1005.641663] [130/133] ( 18s left) gem_exec_balancer (bonded-true-hang)
[1005.902642] Starting subtest: bonded-true-hang
[1005.908323] Subtest bonded-true-hang: SKIP (0.000s)
[1006.015136] [131/133] ( 17s left) api_intel_bb (blit-noreloc-keep-cache)
[1006.081332] Starting subtest: blit-noreloc-keep-cache
[1006.093376] Subtest blit-noreloc-keep-cache: SUCCESS (0.013s)
[1006.210082] [132/133] ( 17s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-render)
[1006.316052] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-render
[1006.320957] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[1006.411101] [133/133] ( 17s left) kms_psr (fbc-psr-cursor-plane-move)
[1006.501605] Starting subtest: fbc-psr-cursor-plane-move
[1006.504125] Starting dynamic subtest: eDP-1
[1006.509036] Dynamic subtest eDP-1: SKIP (0.000s)
[1006.513851] Subtest fbc-psr-cursor-plane-move: SKIP (0.001s)
[1006.571617] Closing watchdogs
results: parsing output: 0/ for test: api_intel_bb
results: parsing output: 1/ for test: kms_big_fb
results: parsing output: 2/ for test: vgem_basic
results: parsing output: 3/ for test: i915_drm_fdinfo
results: parsing output: 4/ for test: kms_rotation_crc
results: parsing output: 5/ for test: kms_addfb_basic
results: parsing output: 6/ for test: gen9_exec_parse
results: parsing output: 7/ for test: kms_flip
results: parsing output: 8/ for test: gem_exec_schedule
results: parsing output: 9/ for test: kms_joiner
results: parsing output: 10/ for test: syncobj_timeline
results: parsing output: 11/ for test: kms_cursor_legacy
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: gem_exec_params
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: kms_cursor_legacy
results: parsing output: 17/ for test: gem_render_copy_redux
results: parsing output: 18/ for test: kms_ccs
results: parsing output: 19/ for test: vgem_basic
results: parsing output: 20/ for test: syncobj_timeline
results: parsing output: 21/ for test: kms_psr
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: syncobj_basic
results: parsing output: 24/ for test: kms_cursor_crc
results: parsing output: 25/ for test: vgem_basic
results: parsing output: 26/ for test: kms_content_protection
results: parsing output: 27/ for test: kms_plane
results: parsing output: 28/ for test: perf_pmu
results: parsing output: 29/ for test: gem_mmap_gtt
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_plane_cursor
results: parsing output: 32/ for test: gem_exec_params
results: parsing output: 33/ for test: gem_madvise
results: parsing output: 34/ for test: gem_exec_reloc
results: parsing output: 35/ for test: gem_pxp
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: gem_render_copy
results: parsing output: 38/ for test: kms_cursor_legacy
results: parsing output: 39/ for test: prime_self_import
results: parsing output: 40/ for test: perf
results: parsing output: 41/ for test: syncobj_wait
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: kms_flip_scaled_crc
results: parsing output: 44/ for test: kms_busy
results: parsing output: 45/ for test: i915_pm_rpm
results: parsing output: 46/ for test: kms_addfb_basic
results: parsing output: 47/ for test: gem_mmap_gtt
results: parsing output: 48/ for test: kms_cursor_crc
results: parsing output: 49/ for test: syncobj_timeline
results: parsing output: 50/ for test: kms_lease
results: parsing output: 51/ for test: kms_flip_scaled_crc
results: parsing output: 52/ for test: gem_eio
results: parsing output: 53/ for test: syncobj_timeline
results: parsing output: 54/ for test: kms_big_fb
results: parsing output: 55/ for test: kms_rotation_crc
results: parsing output: 56/ for test: gem_exec_fence
results: parsing output: 57/ for test: kms_vblank
results: parsing output: 58/ for test: gem_pxp
results: parsing output: 59/ for test: syncobj_timeline
results: parsing output: 60/ for test: kms_plane
results: parsing output: 61/ for test: kms_big_fb
results: parsing output: 62/ for test: kms_pm_rpm
results: parsing output: 63/ for test: kms_plane_scaling
results: parsing output: 64/ for test: syncobj_wait
results: parsing output: 65/ for test: gem_exec_parallel
results: parsing output: 66/ for test: kms_plane_alpha_blend
results: parsing output: 67/ for test: i915_suspend
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_psr
results: parsing output: 70/ for test: gem_ctx_isolation
results: parsing output: 71/ for test: kms_ccs
results: parsing output: 72/ for test: gem_softpin
results: parsing output: 73/ for test: gem_blits
results: parsing output: 74/ for test: kms_draw_crc
results: parsing output: 75/ for test: kms_psr
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: gem_exercise_blt
results: parsing output: 78/ for test: sysfs_heartbeat_interval
results: parsing output: 79/ for test: gem_ctx_shared
results: parsing output: 80/ for test: kms_big_fb
results: parsing output: 81/ for test: gem_readwrite
results: parsing output: 82/ for test: gem_ctx_persistence
results: parsing output: 83/ for test: kms_hdr
results: parsing output: 84/ for test: kms_chamelium_hpd
results: parsing output: 85/ for test: kms_big_fb
results: parsing output: 86/ for test: kms_psr
results: parsing output: 87/ for test: gem_exec_capture
results: parsing output: 88/ for test: kms_ccs
results: parsing output: 89/ for test: gem_eio
results: parsing output: 90/ for test: kms_ccs
results: parsing output: 91/ for test: kms_pm_backlight
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: gem_partial_pwrite_pread
results: parsing output: 94/ for test: gem_exec_schedule
results: parsing output: 95/ for test: gem_exec_params
results: parsing output: 96/ for test: kms_chamelium_edid
results: parsing output: 97/ for test: i915_pm_freq_api
results: parsing output: 98/ for test: syncobj_wait
results: parsing output: 99/ for test: kms_big_fb
results: parsing output: 100/ for test: kms_fbcon_fbt
results: parsing output: 101/ for test: gem_softpin
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_atomic_interruptible
results: parsing output: 105/ for test: kms_psr2_sf
results: parsing output: 106/ for test: kms_ccs
results: parsing output: 107/ for test: kms_psr2_sf
results: parsing output: 108/ for test: gem_exec_flush
results: parsing output: 109/ for test: gem_mmap
results: parsing output: 110/ for test: gem_softpin
results: parsing output: 111/ for test: kms_flip
results: parsing output: 112/ for test: gem_exec_schedule
results: parsing output: 113/ for test: kms_cursor_legacy
results: parsing output: 114/ for test: kms_cursor_legacy
results: parsing output: 115/ for test: kms_vblank
results: parsing output: 116/ for test: gem_userptr_blits
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: gem_close_race
results: parsing output: 119/ for test: dmabuf_sync_file
results: parsing output: 120/ for test: prime_busy
results: parsing output: 121/ for test: gem_fence_thrash
results: parsing output: 122/ for test: kms_psr
results: parsing output: 123/ for test: gem_readwrite
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: gem_exec_whisper
results: parsing output: 126/ for test: kms_atomic_transition
results: parsing output: 127/ for test: kms_color_pipeline
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: gem_exec_balancer
results: parsing output: 130/ for test: api_intel_bb
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: kms_psr
Done.