[58.337641] Initializing watchdogs
[58.337687]   /dev/watchdog0
[58.351784] [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.356199] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.358231] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.360477] [001/134] (960s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[58.467893] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[58.469614] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[58.559472] [002/134] (960s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf)
[63.677082] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf
[63.678059] Starting dynamic subtest: pipe-A-eDP-1
[64.460905] Dynamic subtest pipe-A-eDP-1: SKIP (0.782s)
[64.467582] Starting dynamic subtest: pipe-B-eDP-1
[65.596160] Dynamic subtest pipe-B-eDP-1: SKIP (1.134s)
[65.597957] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf: SKIP (1.920s)
[66.794795] [003/134] (952s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-gtt)
[66.904238] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-gtt
[66.905962] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[67.140413] [004/134] (951s left) kms_psr (psr-sprite-render)
[67.345448] Starting subtest: psr-sprite-render
[67.346509] Starting dynamic subtest: eDP-1
[69.097726] Dynamic subtest eDP-1: SUCCESS (1.752s)
[69.098584] Subtest psr-sprite-render: SUCCESS (1.753s)
[69.948463] [005/134] (949s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[70.053058] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[70.878143] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.826s)
[71.066021] [006/134] (947s left) kms_flip (flip-vs-rmfb-interruptible)
[71.150724] Starting subtest: flip-vs-rmfb-interruptible
[71.152760] Starting dynamic subtest: A-eDP1
[79.829403] Dynamic subtest A-eDP1: SUCCESS (8.676s)
[79.837227] Starting dynamic subtest: B-eDP1
[88.469811] Dynamic subtest B-eDP1: SUCCESS (8.639s)
[88.477835] Starting dynamic subtest: C-eDP1
[97.092177] Dynamic subtest C-eDP1: SUCCESS (8.621s)
[97.100191] Starting dynamic subtest: D-eDP1
[105.724606] Dynamic subtest D-eDP1: SUCCESS (8.631s)
[105.727786] Subtest flip-vs-rmfb-interruptible: SUCCESS (34.576s)
[106.905732] [007/134] (912s left) kms_cursor_legacy (cursora-vs-flipa-atomic)
[107.029502] Starting subtest: cursorA-vs-flipA-atomic
[118.177407] Subtest cursorA-vs-flipA-atomic: SUCCESS (11.147s)
[119.033298] [008/134] (900s left) gem_tiled_blits (basic)
[119.095905] Subtest basic: SKIP (0.000s)
[119.144457] [009/134] (899s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs)
[119.241319] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs
[119.242212] Starting dynamic subtest: pipe-A-eDP-1
[119.245585] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[119.251462] Starting dynamic subtest: pipe-B-eDP-1
[119.254838] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[119.260334] Starting dynamic subtest: pipe-C-eDP-1
[119.263699] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[119.269115] Starting dynamic subtest: pipe-D-eDP-1
[119.272383] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[119.273936] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs: SKIP (0.006s)
[121.412831] [010/134] (897s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping)
[121.500090] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping
[121.528069] Starting dynamic subtest: pipe-A-plane-0
[121.972168] Dynamic subtest pipe-A-plane-0: SUCCESS (0.444s)
[121.977983] Starting dynamic subtest: pipe-A-plane-3
[122.205704] Dynamic subtest pipe-A-plane-3: SUCCESS (0.233s)
[122.211513] Starting dynamic subtest: pipe-A-plane-5
[122.213228] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[123.306766] Starting dynamic subtest: pipe-B-plane-0
[123.712784] Dynamic subtest pipe-B-plane-0: SUCCESS (0.411s)
[123.718565] Starting dynamic subtest: pipe-B-plane-3
[123.968374] Dynamic subtest pipe-B-plane-3: SUCCESS (0.255s)
[123.973962] Starting dynamic subtest: pipe-B-plane-5
[123.975733] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[124.283548] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping: SUCCESS (2.784s)
[125.089574] [011/134] (894s left) gem_pxp (hw-rejects-pxp-buffer)
[125.217422] Starting subtest: hw-rejects-pxp-buffer
[125.219744] Subtest hw-rejects-pxp-buffer: SKIP (0.000s)
[125.324844] [012/134] (893s left) syncobj_timeline (multi-wait-signaled)
[125.375579] Starting subtest: multi-wait-signaled
[125.376517] Subtest multi-wait-signaled: SUCCESS (0.001s)
[125.499495] [013/134] (893s left) kms_joiner (basic-force-big-joiner)
[125.591796] Starting subtest: basic-force-big-joiner
[125.593473] Subtest basic-force-big-joiner: SKIP (0.000s)
[125.664497] [014/134] (893s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[125.755129] Starting subtest: 4-tiled-16bpp-rotate-270
[125.775179] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.019s)
[125.892536] [015/134] (893s left) kms_plane_multiple (2x-tiling-none)
[125.990538] Starting subtest: 2x-tiling-none
[125.992325] Subtest 2x-tiling-none: SKIP (0.000s)
[126.065458] [016/134] (893s left) kms_big_fb (linear-64bpp-rotate-180)
[126.161746] Starting subtest: linear-64bpp-rotate-180
[127.772956] Subtest linear-64bpp-rotate-180: SUCCESS (1.611s)
[127.956137] [017/134] (891s left) gem_ctx_bad_destroy (invalid-pad)
[128.012612] Starting subtest: invalid-pad
[128.013685] Subtest invalid-pad: SUCCESS (0.000s)
[128.092521] [018/134] (891s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[133.068989] Starting subtest: pr-cursor-plane-move-continuous-exceed-fully-sf
[133.072046] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.003s)
[133.925271] [019/134] (885s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-blt)
[134.025318] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-blt
[134.027062] Subtest psr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[134.145830] [020/134] (885s left) kms_addfb_basic (master-rmfb)
[134.203045] Starting subtest: master-rmfb
[134.204032] Subtest master-rmfb: SUCCESS (0.001s)
[134.351885] [021/134] (885s left) kms_big_fb (x-tiled-64bpp-rotate-180)
[134.459436] Starting subtest: x-tiled-64bpp-rotate-180
[135.904855] Subtest x-tiled-64bpp-rotate-180: SUCCESS (1.446s)
[136.074888] [022/134] (883s left) kms_psr (fbc-pr-primary-blt)
[136.168551] Starting subtest: fbc-pr-primary-blt
[136.170377] Subtest fbc-pr-primary-blt: SKIP (0.000s)
[136.245874] [023/134] (883s left) fbdev (nullptr)
[136.282281] Starting subtest: nullptr
[136.283209] Subtest nullptr: SUCCESS (0.000s)
[136.341871] [024/134] (883s left) gen3_render_mixed_blits
[136.455503] [025/134] (883s left) kms_vblank (query-forked)
[136.550539] Starting subtest: query-forked
[136.551526] Starting dynamic subtest: pipe-A-eDP-1
[138.381174] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.830s)
[138.388999] Starting dynamic subtest: pipe-D-eDP-1
[140.878698] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.495s)
[140.879666] Subtest query-forked: SUCCESS (4.329s)
[141.714219] [026/134] (877s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-downscaling)
[141.807711] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-downscaling
[141.808799] Starting dynamic subtest: pipe-A-default-mode
[142.096943] Dynamic subtest pipe-A-default-mode: SKIP (0.289s)
[142.098767] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-downscaling: SKIP (0.291s)
[142.953479] [027/134] (876s left) kms_cursor_crc (cursor-rapid-movement-32x10)
[143.096385] Starting subtest: cursor-rapid-movement-32x10
[143.136504] Subtest cursor-rapid-movement-32x10: SKIP (0.038s)
[143.402057] [028/134] (876s left) kms_frontbuffer_tracking (fbcpsr-1p-pri-indfb-multidraw)
[143.490799] Starting subtest: fbcpsr-1p-pri-indfb-multidraw
[166.062442] Subtest fbcpsr-1p-pri-indfb-multidraw: SUCCESS (22.572s)
[166.202042] [029/134] (853s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs-cc)
[166.297532] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs-cc
[166.298510] Starting dynamic subtest: pipe-A-eDP-1
[166.446457] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.145s)
[166.454058] Starting dynamic subtest: pipe-B-eDP-1
[166.461965] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.009s)
[166.467943] Starting dynamic subtest: pipe-C-eDP-1
[166.475674] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.010s)
[166.481670] Starting dynamic subtest: pipe-D-eDP-1
[166.489644] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.011s)
[166.490517] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.179s)
[168.762425] [030/134] (850s left) kms_feature_discovery (display)
[168.863860] Starting subtest: display
[168.864974] Subtest display: SUCCESS (0.000s)
[168.941303] [031/134] (850s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc)
[169.033828] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc
[169.035605] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[169.174100] [032/134] (850s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-blt)
[169.282107] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-blt
[169.284090] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[169.398829] [033/134] (850s left) template (a)
[169.449982] Starting subtest: A
[169.451081] Subtest A: SUCCESS (0.000s)
[169.571268] [034/134] (850s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-wc)
[169.667014] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-wc
[173.698052] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (4.032s)
[174.857075] [035/134] (845s left) gem_render_copy (yf-tiled-ccs-to-y-tiled)
[174.915957] Starting subtest: yf-tiled-ccs-to-y-tiled
[174.917991] Subtest yf-tiled-ccs-to-y-tiled: SKIP (0.000s)
[175.107345] [036/134] (844s left) gem_exec_flush (basic-uc-pro-default)
[175.162699] Starting subtest: basic-uc-pro-default
[180.623192] Subtest basic-uc-pro-default: SUCCESS (5.460s)
[180.811436] [037/134] (839s left) gem_ctx_persistence (engines-hostile-preempt)
[180.873358] Starting subtest: engines-hostile-preempt
[180.874512] Starting dynamic subtest: rcs0
[180.958769] Dynamic subtest rcs0: SUCCESS (0.085s)
[180.969448] Starting dynamic subtest: bcs0
[181.046053] Dynamic subtest bcs0: SUCCESS (0.085s)
[181.056143] Starting dynamic subtest: vcs0
[182.967011] Dynamic subtest vcs0: SUCCESS (1.917s)
[182.978374] Starting dynamic subtest: vcs1
[184.945740] Dynamic subtest vcs1: SUCCESS (1.976s)
[184.954736] Starting dynamic subtest: vecs0
[186.931970] Dynamic subtest vecs0: SUCCESS (1.984s)
[186.941293] Starting dynamic subtest: ccs0
[187.012839] Dynamic subtest ccs0: SUCCESS (0.081s)
[187.014060] Subtest engines-hostile-preempt: SUCCESS (6.141s)
[187.104358] [038/134] (832s left) gem_exec_balancer (bonded-semaphore)
[187.160383] Starting subtest: bonded-semaphore
[187.162366] Subtest bonded-semaphore: SKIP (0.000s)
[187.468209] [039/134] (832s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[187.577075] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt
[187.578740] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[187.714547] [040/134] (832s left) syncobj_timeline (single-wait-all-signaled)
[187.766095] Starting subtest: single-wait-all-signaled
[187.767073] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[187.893531] [041/134] (832s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-wc)
[187.984470] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-wc
[187.986303] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[188.104568] [042/134] (831s left) gem_userptr_blits (sd-probe)
[188.164129] Starting subtest: sd-probe
[188.165175] Subtest sd-probe: SUCCESS (0.000s)
[188.324285] [043/134] (831s left) gem_linear_blits (basic)
[188.771827] Starting subtest: basic
[188.782401] Subtest basic: SUCCESS (0.011s)
[188.889587] [044/134] (831s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-msflip-blt)
[189.017473] Starting subtest: fbc-2p-scndscrn-shrfb-msflip-blt
[189.019220] Subtest fbc-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[189.141928] [045/134] (831s left) gem_ctx_persistence (legacy-engines-hang)
[189.198899] Starting subtest: legacy-engines-hang
[189.201665] Starting dynamic subtest: render
[189.362925] Dynamic subtest render: SUCCESS (0.162s)
[189.373322] Starting dynamic subtest: bsd1
[189.474644] Dynamic subtest bsd1: SUCCESS (0.109s)
[189.488383] Starting dynamic subtest: bsd2
[189.592686] Dynamic subtest bsd2: SUCCESS (0.115s)
[189.603138] Starting dynamic subtest: blt
[189.730437] Dynamic subtest blt: SUCCESS (0.134s)
[189.741192] Starting dynamic subtest: vebox
[189.856657] Dynamic subtest vebox: SUCCESS (0.124s)
[189.858029] Subtest legacy-engines-hang: SUCCESS (0.658s)
[189.940889] [046/134] (830s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[190.031200] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc
[190.032898] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[190.146085] [047/134] (830s left) kms_pm_rpm (universal-planes)
[192.080101] Starting subtest: universal-planes
[192.081880] Starting dynamic subtest: plane-34
[200.347217] Dynamic subtest plane-34: SUCCESS (8.266s)
[200.358125] Starting dynamic subtest: plane-64
[208.618616] Dynamic subtest plane-64: SUCCESS (8.268s)
[208.629508] Starting dynamic subtest: plane-94
[216.890936] Dynamic subtest plane-94: SUCCESS (8.269s)
[216.902473] Starting dynamic subtest: plane-124
[225.183680] Dynamic subtest plane-124: SUCCESS (8.291s)
[225.193825] Starting dynamic subtest: plane-134
[233.472569] Dynamic subtest plane-134: SUCCESS (8.285s)
[233.483521] Starting dynamic subtest: plane-144
[241.739860] Dynamic subtest plane-144: SUCCESS (8.265s)
[241.742938] Subtest universal-planes: SUCCESS (49.662s)
[242.127160] [048/134] (778s left) kms_color_pipeline (plane-lut1d-ctm3x4)
[242.233366] Starting subtest: plane-lut1d-ctm3x4
[242.247330] Starting dynamic subtest: pipe-A-eDP-1
[242.697999] Dynamic subtest pipe-A-eDP-1: FAIL (0.301s)
[243.589960] Starting dynamic subtest: pipe-B-eDP-1
[244.071540] Dynamic subtest pipe-B-eDP-1: FAIL (0.291s)
[244.913035] Starting dynamic subtest: pipe-C-eDP-1
[245.355702] Dynamic subtest pipe-C-eDP-1: FAIL (0.235s)
[246.252827] Starting dynamic subtest: pipe-D-eDP-1
[246.734329] Dynamic subtest pipe-D-eDP-1: FAIL (0.268s)
[246.813604] Subtest plane-lut1d-ctm3x4: FAIL (4.580s)
[247.651190] [049/134] (772s left) gem_exec_params (rel-constants-invalid)
[247.716489] Starting subtest: rel-constants-invalid
[247.717511] Subtest rel-constants-invalid: SUCCESS (0.000s)
[247.826262] [050/134] (772s left) gem_exec_params (invalid-fence-in-submit)
[247.877299] Starting subtest: invalid-fence-in-submit
[247.881944] Subtest invalid-fence-in-submit: SUCCESS (0.004s)
[247.977566] [051/134] (772s left) kms_lease (lease-again)
[248.063390] Starting subtest: lease-again
[248.064283] Starting dynamic subtest: pipe-A-eDP-1
[248.066367] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.003s)
[248.073041] Starting dynamic subtest: pipe-B-eDP-1
[248.073939] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[248.080320] Starting dynamic subtest: pipe-C-eDP-1
[248.081313] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[248.087767] Starting dynamic subtest: pipe-D-eDP-1
[248.088655] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[248.089569] Subtest lease-again: SUCCESS (0.013s)
[248.201124] [052/134] (772s left) kms_cursor_crc (cursor-offscreen-128x128)
[248.402364] Starting subtest: cursor-offscreen-128x128
[248.406234] Starting dynamic subtest: pipe-A-eDP-1
[250.346551] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.940s)
[250.371956] Starting dynamic subtest: pipe-D-eDP-1
[252.931727] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.565s)
[252.932679] Subtest cursor-offscreen-128x128: SUCCESS (4.529s)
[253.788331] [053/134] (766s left) kms_ccs (crc-primary-basic-4-tiled-lnl-ccs)
[253.889586] Starting subtest: crc-primary-basic-4-tiled-lnl-ccs
[253.891421] Subtest crc-primary-basic-4-tiled-lnl-ccs: SKIP (0.000s)
[255.012072] [054/134] (765s left) gem_softpin (evict-prime)
[255.067436] Starting subtest: evict-prime
[255.068404] Starting dynamic subtest: rcs0
[256.245806] Dynamic subtest rcs0: SUCCESS (1.178s)
[256.256839] Starting dynamic subtest: bcs0
[257.410941] Dynamic subtest bcs0: SUCCESS (1.162s)
[257.422411] Starting dynamic subtest: vcs0
[258.938321] Dynamic subtest vcs0: SUCCESS (1.523s)
[258.948041] Starting dynamic subtest: vcs1
[260.920036] Dynamic subtest vcs1: SUCCESS (1.980s)
[260.929672] Starting dynamic subtest: vecs0
[262.973182] Dynamic subtest vecs0: SUCCESS (2.051s)
[262.982844] Starting dynamic subtest: ccs0
[264.127940] Dynamic subtest ccs0: SUCCESS (1.154s)
[264.138549] Starting dynamic subtest: all
[265.905322] Dynamic subtest all: SUCCESS (1.773s)
[265.906593] Subtest evict-prime: SUCCESS (10.837s)
[265.986739] [055/134] (754s left) kms_cursor_edge_walk (128x128-top-edge)
[266.082913] Starting subtest: 128x128-top-edge
[266.083773] Starting dynamic subtest: pipe-A-eDP-1
[269.560896] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.476s)
[269.567538] Starting dynamic subtest: pipe-D-eDP-1
[272.692873] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.131s)
[272.693853] Subtest 128x128-top-edge: SUCCESS (6.610s)
[273.513249] [056/134] (746s left) kms_async_flips (test-cursor)
[273.611568] Starting subtest: test-cursor
[273.613300] Subtest test-cursor: SKIP (0.000s)
[274.843360] [057/134] (745s left) gem_exec_async (concurrent-writes)
[274.905305] Starting subtest: concurrent-writes
[274.906333] Starting dynamic subtest: rcs0
[274.921025] Dynamic subtest rcs0: SUCCESS (0.015s)
[274.929897] Starting dynamic subtest: bcs0
[274.930682] Dynamic subtest bcs0: SUCCESS (0.006s)
[274.937140] Starting dynamic subtest: vcs0
[274.937939] Dynamic subtest vcs0: SUCCESS (0.004s)
[274.944269] Starting dynamic subtest: vcs1
[274.945094] Dynamic subtest vcs1: SUCCESS (0.006s)
[274.951553] Starting dynamic subtest: vecs0
[274.952334] Dynamic subtest vecs0: SUCCESS (0.005s)
[274.958680] Starting dynamic subtest: ccs0
[274.959510] Dynamic subtest ccs0: SUCCESS (0.005s)
[274.960378] Subtest concurrent-writes: SUCCESS (0.045s)
[275.207741] [058/134] (745s left) kms_rotation_crc (primary-x-tiled-reflect-x-0)
[275.304109] Starting subtest: primary-x-tiled-reflect-x-0
[282.216583] Subtest primary-x-tiled-reflect-x-0: SUCCESS (6.912s)
[283.397781] [059/134] (737s left) i915_pm_rps (reset)
[283.463789] Starting subtest: reset
[291.911290] Subtest reset: SUCCESS (8.448s)
[292.062460] [060/134] (728s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-75)
[292.174086] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-75
[292.174938] Starting dynamic subtest: pipe-A
[292.200903] Dynamic subtest pipe-A: SKIP (0.023s)
[292.207684] Starting dynamic subtest: pipe-B
[292.233662] Dynamic subtest pipe-B: SKIP (0.032s)
[292.240511] Starting dynamic subtest: pipe-C
[292.265477] Dynamic subtest pipe-C: SKIP (0.032s)
[292.272208] Starting dynamic subtest: pipe-D
[292.298896] Dynamic subtest pipe-D: SKIP (0.031s)
[292.300600] Subtest planes-upscale-factor-0-25-downscale-factor-0-75: SKIP (0.123s)
[292.410565] [061/134] (728s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-render)
[292.514734] Starting subtest: psr-1p-primscrn-cur-indfb-draw-render
[299.235898] Subtest psr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.722s)
[299.369903] [062/134] (721s left) kms_cursor_legacy (2x-cursor-vs-flip-legacy)
[299.486864] Starting subtest: 2x-cursor-vs-flip-legacy
[299.489791] Subtest 2x-cursor-vs-flip-legacy: SKIP (0.001s)
[299.637384] [063/134] (721s left) sysfs_heartbeat_interval (invalid)
[299.683270] Starting subtest: invalid
[299.684322] Starting dynamic subtest: vecs0
[299.685301] Dynamic subtest vecs0: SUCCESS (0.000s)
[299.693792] Starting dynamic subtest: ccs0
[299.694704] Dynamic subtest ccs0: SUCCESS (0.000s)
[299.701766] Starting dynamic subtest: vcs0
[299.702665] Dynamic subtest vcs0: SUCCESS (0.000s)
[299.709195] Starting dynamic subtest: bcs0
[299.710037] Dynamic subtest bcs0: SUCCESS (0.000s)
[299.716564] Starting dynamic subtest: rcs0
[299.717477] Dynamic subtest rcs0: SUCCESS (0.000s)
[299.724228] Starting dynamic subtest: vcs1
[299.725099] Dynamic subtest vcs1: SUCCESS (0.000s)
[299.725943] Subtest invalid: SUCCESS (0.006s)
[299.806554] [064/134] (720s left) syncobj_wait (multi-wait-for-submit-unsubmitted)
[299.857586] Starting subtest: multi-wait-for-submit-unsubmitted
[299.964402] Subtest multi-wait-for-submit-unsubmitted: SUCCESS (0.104s)
[300.043283] [065/134] (720s left) gem_exec_schedule (timeslicing)
[300.110169] Starting subtest: timeslicing
[300.111117] Starting dynamic subtest: rcs0
[300.131821] Dynamic subtest rcs0: SUCCESS (0.022s)
[300.141395] Starting dynamic subtest: bcs0
[300.153326] Dynamic subtest bcs0: SUCCESS (0.020s)
[300.160572] Starting dynamic subtest: vcs0
[300.173834] Dynamic subtest vcs0: SUCCESS (0.020s)
[300.183640] Starting dynamic subtest: vcs1
[300.194193] Dynamic subtest vcs1: SUCCESS (0.020s)
[300.201200] Starting dynamic subtest: vecs0
[300.213889] Dynamic subtest vecs0: SUCCESS (0.019s)
[300.223723] Starting dynamic subtest: ccs0
[300.234842] Dynamic subtest ccs0: SUCCESS (0.020s)
[300.235934] Subtest timeslicing: SUCCESS (0.126s)
[301.950909] [066/134] (718s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[302.047308] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[302.048880] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[302.140905] [067/134] (718s left) kms_frontbuffer_tracking (fbc-abgr161616f-draw-pwrite)
[302.228852] Starting subtest: fbc-abgr161616f-draw-pwrite
[302.230535] Subtest fbc-abgr161616f-draw-pwrite: SKIP (0.000s)
[302.324518] [068/134] (718s left) gem_exec_reloc (basic-cpu-read)
[302.381844] Subtest basic-cpu-read: SKIP (0.000s)
[302.462384] [069/134] (718s left) kms_atomic_transition (plane-all-transition-fencing)
[302.566727] Starting subtest: plane-all-transition-fencing
[302.567776] Starting dynamic subtest: pipe-A-eDP-1
[305.060799] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.495s)
[305.361638] Starting dynamic subtest: pipe-B-eDP-1
[308.568515] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.213s)
[308.879414] Subtest plane-all-transition-fencing: SUCCESS (6.313s)
[309.705804] [070/134] (711s left) kms_getfb (getfb2-handle-closed)
[309.793294] Starting subtest: getfb2-handle-closed
[309.794087] Subtest getfb2-handle-closed: SUCCESS (0.000s)
[309.898981] [071/134] (711s left) gem_exec_reloc (basic-cpu-active)
[309.955305] Subtest basic-cpu-active: SKIP (0.000s)
[310.022422] [072/134] (710s left) kms_flip (modeset-vs-vblank-race)
[310.112139] Starting subtest: modeset-vs-vblank-race
[310.114103] Starting dynamic subtest: A-eDP1
[314.844967] Dynamic subtest A-eDP1: SUCCESS (4.731s)
[314.855511] Starting dynamic subtest: B-eDP1
[319.577992] Dynamic subtest B-eDP1: SUCCESS (4.731s)
[319.587083] Starting dynamic subtest: C-eDP1
[324.216688] Dynamic subtest C-eDP1: SUCCESS (4.636s)
[324.225992] Starting dynamic subtest: D-eDP1
[328.840991] Dynamic subtest D-eDP1: SUCCESS (4.621s)
[328.843481] Subtest modeset-vs-vblank-race: SUCCESS (18.731s)
[330.106742] [073/134] (690s left) kms_pm_dc (dc6-dpms)
[331.216408] Starting subtest: dc6-dpms
[333.288547] Subtest dc6-dpms: SUCCESS (2.069s)
[333.367018] [074/134] (687s left) kms_chamelium_color (degamma)
[333.457283] Subtest degamma: SKIP (0.000s)
[333.496258] [075/134] (687s left) gem_exec_capture (pi)
[333.551456] Starting subtest: pi
[333.552394] Starting dynamic subtest: rcs0
[337.925692] Dynamic subtest rcs0: SUCCESS (4.375s)
[337.937304] Starting dynamic subtest: bcs0
[342.185882] Dynamic subtest bcs0: SUCCESS (4.257s)
[342.197249] Starting dynamic subtest: vcs0
[346.372167] Dynamic subtest vcs0: SUCCESS (4.184s)
[346.383761] Starting dynamic subtest: vcs1
[350.599975] Dynamic subtest vcs1: SUCCESS (4.225s)
[350.609784] Starting dynamic subtest: vecs0
[354.847655] Dynamic subtest vecs0: SUCCESS (4.246s)
[354.859575] Starting dynamic subtest: ccs0
[359.010504] Dynamic subtest ccs0: SUCCESS (4.160s)
[359.013855] Subtest pi: SUCCESS (25.461s)
[359.098701] [076/134] (662s left) perf_pmu (init-sema)
[359.166705] Starting subtest: init-sema
[359.167669] Starting dynamic subtest: rcs0
[359.170351] Dynamic subtest rcs0: SUCCESS (0.002s)
[359.178356] Starting dynamic subtest: bcs0
[359.179215] Dynamic subtest bcs0: SUCCESS (0.000s)
[359.186264] Starting dynamic subtest: vcs0
[359.187218] Dynamic subtest vcs0: SUCCESS (0.001s)
[359.194188] Starting dynamic subtest: vcs1
[359.195077] Dynamic subtest vcs1: SUCCESS (0.000s)
[359.201581] Starting dynamic subtest: vecs0
[359.202510] Dynamic subtest vecs0: SUCCESS (0.000s)
[359.209134] Starting dynamic subtest: ccs0
[359.210065] Dynamic subtest ccs0: SUCCESS (0.000s)
[359.210994] Subtest init-sema: SUCCESS (0.009s)
[359.288049] [077/134] (661s left) kms_plane_cursor (primary)
[359.385013] Starting subtest: primary
[359.386745] Starting dynamic subtest: pipe-A-eDP-1-size-64
[361.942455] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.557s)
[361.967303] Starting dynamic subtest: pipe-A-eDP-1-size-128
[364.531860] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.571s)
[364.558913] Starting dynamic subtest: pipe-A-eDP-1-size-256
[367.099561] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.549s)
[367.409788] Starting dynamic subtest: pipe-B-eDP-1-size-64
[370.730675] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.328s)
[370.754290] Starting dynamic subtest: pipe-B-eDP-1-size-128
[373.307753] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.560s)
[373.332448] Starting dynamic subtest: pipe-B-eDP-1-size-256
[375.864293] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.539s)
[376.174826] Starting dynamic subtest: pipe-C-eDP-1-size-64
[379.074407] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.908s)
[379.100946] Starting dynamic subtest: pipe-C-eDP-1-size-128
[381.274605] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.180s)
[381.301377] Starting dynamic subtest: pipe-C-eDP-1-size-256
[383.463488] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.169s)
[383.785173] Starting dynamic subtest: pipe-D-eDP-1-size-64
[386.732926] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.955s)
[386.759094] Starting dynamic subtest: pipe-D-eDP-1-size-128
[388.921506] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.169s)
[388.948369] Starting dynamic subtest: pipe-D-eDP-1-size-256
[391.110771] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.169s)
[391.422918] Subtest primary: SUCCESS (32.037s)
[392.263557] [078/134] (628s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[392.373066] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip
[392.374738] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[392.491417] [079/134] (628s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[392.585130] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[401.064591] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.480s)
[401.193704] [080/134] (620s left) gem_mmap_gtt (zero-extend)
[401.253237] Subtest zero-extend: SKIP (0.000s)
[401.313229] [081/134] (619s left) perf (oa-formats)
[401.448996] Starting subtest: oa-formats
[401.449893] Starting dynamic subtest: 0-rcs0
[401.463827] Dynamic subtest 0-rcs0: SUCCESS (0.015s)
[401.471047] Starting dynamic subtest: 1-vcs0
[401.486946] Dynamic subtest 1-vcs0: SUCCESS (0.022s)
[401.487886] Subtest oa-formats: SUCCESS (0.039s)
[401.527741] [082/134] (619s left) gem_exec_whisper (basic-queues-all)
[401.593991] Starting subtest: basic-queues-all
[412.068305] Subtest basic-queues-all: SUCCESS (10.471s)
[412.256604] [083/134] (609s left) gem_close_race (multigpu-basic-threads)
[412.323358] Starting subtest: multigpu-basic-threads
[412.325125] Subtest multigpu-basic-threads: SKIP (0.000s)
[412.539845] [084/134] (608s left) syncobj_wait (wait-delayed-signal)
[412.591043] Starting subtest: wait-delayed-signal
[412.695982] Subtest wait-delayed-signal: SUCCESS (0.102s)
[412.782873] [085/134] (608s left) syncobj_timeline (invalid-query-one-illegal-handle)
[412.838741] Starting subtest: invalid-query-one-illegal-handle
[412.839743] Subtest invalid-query-one-illegal-handle: SUCCESS (0.000s)
[412.935757] [086/134] (608s left) gem_exec_whisper (basic-contexts-forked-all)
[412.996437] Starting subtest: basic-contexts-forked-all
[436.174148] Subtest basic-contexts-forked-all: SUCCESS (23.174s)
[436.359529] [087/134] (585s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted-signal
[436.411918] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted-signaled
[436.516802] Subtest etime-multi-wait-all-available-unsubmitted-submitted-signaled: SUCCESS (0.102s)
[436.602911] [088/134] (584s left) gem_mmap_gtt (medium-copy)
[436.659692] Subtest medium-copy: SKIP (0.000s)
[436.725543] [089/134] (584s left) gem_readwrite (beyond-eob)
[436.780502] Subtest beyond-EOB: SKIP (0.000s)
[436.836308] [090/134] (584s left) gem_lmem_swapping (verify-ccs)
[436.870062] Subtest verify-ccs: SKIP (0.000s)
[436.904760] [091/134] (584s left) api_intel_bb (lot-of-buffers)
[436.955426] Starting subtest: lot-of-buffers
[438.008628] Subtest lot-of-buffers: SUCCESS (1.051s)
[439.003964] [092/134] (582s left) gem_exec_params (invalid-bsd1-flag-on-render)
[439.055289] Starting subtest: invalid-bsd1-flag-on-render
[439.056214] Subtest invalid-bsd1-flag-on-render: SUCCESS (0.000s)
[439.182668] [093/134] (582s left) dumb_buffer (invalid-bpp)
[439.233710] Starting subtest: invalid-bpp
[439.234961] Subtest invalid-bpp: SUCCESS (0.000s)
[439.362225] [094/134] (582s left) gen9_exec_parse (bb-start-cmd)
[439.409637] Subtest bb-start-cmd: SKIP (0.000s)
[439.492928] [095/134] (582s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-upscaling)
[439.589156] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-upscaling
[439.590120] Starting dynamic subtest: pipe-A-default-mode
[442.334776] Dynamic subtest pipe-A-default-mode: SUCCESS (2.744s)
[442.335675] Subtest flip-64bpp-xtile-to-16bpp-xtile-upscaling: SUCCESS (2.746s)
[443.206462] [096/134] (578s left) gem_exec_fence (syncobj-channel)
[443.717556] Starting subtest: syncobj-channel
[444.734885] Subtest syncobj-channel: SUCCESS (1.015s)
[445.074035] [FACT gem_exec_fence (syncobj-channel)] new: kernel.kmod_is_loaded.vgem: true
[445.076395] [097/134] (576s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-wc)
[445.170811] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-wc
[445.172678] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[445.307237] [098/134] (576s left) kms_vblank (crtc-id)
[445.393849] Starting subtest: crtc-id
[445.394970] Starting dynamic subtest: pipe-A-eDP-1
[445.844380] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.449s)
[445.855098] Starting dynamic subtest: pipe-D-eDP-1
[446.948356] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.100s)
[446.949243] Subtest crtc-id: SUCCESS (1.554s)
[447.788607] [099/134] (574s left) kms_big_fb (y-tiled-8bpp-rotate-0)
[447.884467] Starting subtest: y-tiled-8bpp-rotate-0
[447.886396] Subtest y-tiled-8bpp-rotate-0: SKIP (0.000s)
[448.007465] [100/134] (573s left) perf (gen12-mi-rpc)
[448.101681] Starting subtest: gen12-mi-rpc
[448.102824] Starting dynamic subtest: rcs0
[448.121815] Dynamic subtest rcs0: SUCCESS (0.019s)
[448.122777] Subtest gen12-mi-rpc: SUCCESS (0.020s)
[448.157527] [101/134] (573s left) kms_psr (fbc-psr2-sprite-plane-move)
[448.289080] Starting subtest: fbc-psr2-sprite-plane-move
[448.289932] Starting dynamic subtest: eDP-1
[448.291626] Dynamic subtest eDP-1: SKIP (0.000s)
[448.293347] Subtest fbc-psr2-sprite-plane-move: SKIP (0.001s)
[448.373625] [102/134] (573s left) syncobj_wait (wait-any-snapshot)
[448.431793] Starting subtest: wait-any-snapshot
[448.513347] Subtest wait-any-snapshot: SUCCESS (0.081s)
[448.595610] [103/134] (573s left) kms_content_protection (atomic-dpms-hdcp14)
[448.736520] Starting subtest: atomic-dpms-hdcp14
[448.738485] Subtest atomic-dpms-hdcp14: SKIP (0.000s)
[448.873165] [104/134] (573s left) api_intel_allocator (two-level-inception-interruptible)
[448.921173] Starting subtest: two-level-inception-interruptible
[454.326945] Subtest two-level-inception-interruptible: SUCCESS (5.405s)
[454.466788] [105/134] (567s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[454.578053] Starting subtest: extended-modeset-hang-oldfb-with-reset
[454.578960] Starting dynamic subtest: pipe-A
[466.347248] Dynamic subtest pipe-A: SUCCESS (11.768s)
[466.356588] Starting dynamic subtest: pipe-D
[478.629429] Dynamic subtest pipe-D: SUCCESS (12.279s)
[478.630366] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (24.052s)
[479.451242] [106/134] (542s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-20x20)
[479.549659] Starting subtest: planes-downscale-factor-0-5-upscale-20x20
[479.550634] Starting dynamic subtest: pipe-A
[479.567165] Dynamic subtest pipe-A: SKIP (0.016s)
[479.575784] Starting dynamic subtest: pipe-B
[479.596848] Dynamic subtest pipe-B: SKIP (0.029s)
[479.605192] Starting dynamic subtest: pipe-C
[479.626153] Dynamic subtest pipe-C: SKIP (0.028s)
[479.633962] Starting dynamic subtest: pipe-D
[479.654274] Dynamic subtest pipe-D: SKIP (0.028s)
[479.656786] Subtest planes-downscale-factor-0-5-upscale-20x20: SKIP (0.104s)
[479.770964] [107/134] (542s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-wc)
[479.870695] Starting subtest: psr-rgb101010-draw-mmap-wc
[486.802283] Subtest psr-rgb101010-draw-mmap-wc: SUCCESS (6.932s)
[486.932339] [108/134] (535s left) kms_flip (2x-plain-flip-ts-check-interruptible)
[487.035732] Starting subtest: 2x-plain-flip-ts-check-interruptible
[487.047858] Subtest 2x-plain-flip-ts-check-interruptible: SKIP (0.011s)
[487.131731] [109/134] (535s left) prime_vgem (shrink)
[487.213815] Starting subtest: shrink
[487.228555] Subtest shrink: SUCCESS (0.013s)
[487.332883] [110/134] (534s left) kms_dp_link_training (uhbr-mst)
[487.429857] Starting subtest: uhbr-mst
[487.433553] Subtest uhbr-mst: SKIP (0.000s)
[487.510393] [111/134] (534s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-cpu)
[487.605727] Starting subtest: fbc-rgb565-draw-mmap-cpu
[491.698860] Subtest fbc-rgb565-draw-mmap-cpu: SUCCESS (4.094s)
[492.854789] [112/134] (529s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[492.974544] Starting subtest: 2x-long-flip-vs-cursor-atomic
[492.977363] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.001s)
[493.138093] [113/134] (529s left) syncobj_timeline (etime-single-wait-all-submitted)
[493.197883] Starting subtest: etime-single-wait-all-submitted
[493.308358] Subtest etime-single-wait-all-submitted: SUCCESS (0.108s)
[493.389981] [114/134] (528s left) gem_exec_schedule (noreorder-corked)
[493.563714] Starting subtest: noreorder-corked
[493.564890] Starting dynamic subtest: rcs0
[494.710028] Dynamic subtest rcs0: SUCCESS (1.146s)
[494.723308] Starting dynamic subtest: vcs0
[495.880358] Dynamic subtest vcs0: SUCCESS (1.168s)
[495.893059] Starting dynamic subtest: vcs1
[497.918022] Dynamic subtest vcs1: SUCCESS (2.033s)
[497.928844] Starting dynamic subtest: vecs0
[499.946096] Dynamic subtest vecs0: SUCCESS (2.026s)
[499.957911] Starting dynamic subtest: ccs0
[501.116181] Dynamic subtest ccs0: SUCCESS (1.169s)
[501.117394] Subtest noreorder-corked: SUCCESS (7.554s)
[501.303758] [115/134] (521s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-blt)
[501.414732] Starting subtest: psr-1p-offscreen-pri-indfb-draw-blt
[508.393809] Subtest psr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (6.977s)
[508.552699] [116/134] (513s left) kms_psr (fbc-psr-basic)
[508.666679] Starting subtest: fbc-psr-basic
[508.667913] Starting dynamic subtest: eDP-1
[508.670104] Dynamic subtest eDP-1: SKIP (0.000s)
[508.671935] Subtest fbc-psr-basic: SKIP (0.002s)
[508.765619] [117/134] (513s left) gem_pxp (create-regular-context-1)
[508.859256] Starting subtest: create-regular-context-1
[508.860134] Subtest create-regular-context-1: SUCCESS (0.001s)
[508.977050] [118/134] (513s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[509.069996] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt
[509.071707] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[509.183914] [119/134] (513s left) kms_frontbuffer_tracking (fbc-stridechange)
[509.267161] Starting subtest: fbc-stridechange
[512.530894] Subtest fbc-stridechange: SUCCESS (3.264s)
[513.658520] [120/134] (508s left) kms_psr (psr2-cursor-render)
[513.753101] Starting subtest: psr2-cursor-render
[513.753870] Starting dynamic subtest: eDP-1
[515.408271] Dynamic subtest eDP-1: SUCCESS (1.653s)
[515.409241] Subtest psr2-cursor-render: SUCCESS (1.654s)
[516.210134] [121/134] (506s left) gem_fence_thrash (bo-copy)
[516.236552] Starting subtest: bo-copy
[516.265308] Subtest bo-copy: SKIP (0.029s)
[516.331518] [122/134] (506s left) i915_drm_fdinfo (virtual-busy-hang)
[516.370677] Starting subtest: virtual-busy-hang
[516.372547] Subtest virtual-busy-hang: SKIP (0.000s)
[516.444417] [123/134] (506s left) kms_vrr (lobf)
[516.545452] Starting subtest: lobf
[516.547203] Subtest lobf: SKIP (0.000s)
[516.622395] [124/134] (506s left) syncobj_basic (bad-create-flags)
[516.682847] Starting subtest: bad-create-flags
[516.683803] Subtest bad-create-flags: SUCCESS (0.000s)
[516.807366] [125/134] (505s left) kms_chamelium_color (ctm-0-75)
[516.903354] Subtest ctm-0-75: SKIP (0.000s)
[516.942930] [126/134] (505s left) kms_psr (pr-primary-mmap-gtt)
[517.042705] Starting subtest: pr-primary-mmap-gtt
[517.044632] Subtest pr-primary-mmap-gtt: SKIP (0.000s)
[517.123114] [127/134] (505s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[517.235594] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[517.237945] Starting dynamic subtest: pipe-A-eDP-1
[517.260103] Dynamic subtest pipe-A-eDP-1: SKIP (0.003s)
[517.267806] Starting dynamic subtest: pipe-B-eDP-1
[517.290178] Dynamic subtest pipe-B-eDP-1: SKIP (0.002s)
[517.297765] Starting dynamic subtest: pipe-C-eDP-1
[517.319551] Dynamic subtest pipe-C-eDP-1: SKIP (0.002s)
[517.326761] Starting dynamic subtest: pipe-D-eDP-1
[517.348128] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[517.349781] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.018s)
[519.482480] [128/134] (503s left) i915_suspend (basic-s3-without-i915)
[519.511940] Starting subtest: basic-s3-without-i915
[523.051286] Subtest basic-s3-without-i915: SKIP (3.535s)
[524.562527] [129/134] (498s left) syncobj_wait (wait-all-snapshot)
[524.616725] Starting subtest: wait-all-snapshot
[524.698514] Subtest wait-all-snapshot: SUCCESS (0.081s)
[524.778533] [130/134] (498s left) kms_flip (2x-blocking-absolute-wf_vblank)
[524.874867] Starting subtest: 2x-blocking-absolute-wf_vblank
[524.886402] Subtest 2x-blocking-absolute-wf_vblank: SKIP (0.011s)
[524.993396] [131/134] (497s left) syncobj_wait (invalid-wait-illegal-handle)
[525.044770] Starting subtest: invalid-wait-illegal-handle
[525.045845] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[525.179265] [132/134] (497s left) gem_exec_flush (basic-wb-ro-before-default)
[525.237599] Starting subtest: basic-wb-ro-before-default
[530.706607] Subtest basic-wb-ro-before-default: SUCCESS (5.469s)
[530.885058] [133/134] (492s left) kms_chamelium_hpd (dp-hpd-storm)
[531.272501] Subtest dp-hpd-storm: SKIP (0.000s)
[532.047059] [134/134] (490s left) i915_pm_freq_api (freq-basic-api)
[532.101854] Starting subtest: freq-basic-api
[532.103031] Starting dynamic subtest: gt0
[532.105451] Dynamic subtest gt0: SUCCESS (0.003s)
[532.114379] Starting dynamic subtest: gt1
[532.115267] Dynamic subtest gt1: SUCCESS (0.002s)
[532.116099] Subtest freq-basic-api: SUCCESS (0.006s)
[532.187232] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_psr2_sf
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_psr
results: parsing output: 4/ for test: kms_big_fb
results: parsing output: 5/ for test: kms_flip
results: parsing output: 6/ for test: kms_cursor_legacy
results: parsing output: 7/ for test: gem_tiled_blits
results: parsing output: 8/ for test: kms_ccs
results: parsing output: 9/ for test: kms_plane
results: parsing output: 10/ for test: gem_pxp
results: parsing output: 11/ for test: syncobj_timeline
results: parsing output: 12/ for test: kms_joiner
results: parsing output: 13/ for test: kms_big_fb
results: parsing output: 14/ for test: kms_plane_multiple
results: parsing output: 15/ for test: kms_big_fb
results: parsing output: 16/ for test: gem_ctx_bad_destroy
results: parsing output: 17/ for test: kms_psr2_sf
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_addfb_basic
results: parsing output: 20/ for test: kms_big_fb
results: parsing output: 21/ for test: kms_psr
results: parsing output: 22/ for test: fbdev
results: parsing output: 23/ for test: gen3_render_mixed_blits
results: parsing output: 24/ for test: kms_vblank
results: parsing output: 25/ for test: kms_flip_scaled_crc
results: parsing output: 26/ for test: kms_cursor_crc
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_ccs
results: parsing output: 29/ for test: kms_feature_discovery
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: template
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: gem_render_copy
results: parsing output: 35/ for test: gem_exec_flush
results: parsing output: 36/ for test: gem_ctx_persistence
results: parsing output: 37/ for test: gem_exec_balancer
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: syncobj_timeline
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: gem_userptr_blits
results: parsing output: 42/ for test: gem_linear_blits
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: gem_ctx_persistence
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_pm_rpm
results: parsing output: 47/ for test: kms_color_pipeline
results: parsing output: 48/ for test: gem_exec_params
results: parsing output: 49/ for test: gem_exec_params
results: parsing output: 50/ for test: kms_lease
results: parsing output: 51/ for test: kms_cursor_crc
results: parsing output: 52/ for test: kms_ccs
results: parsing output: 53/ for test: gem_softpin
results: parsing output: 54/ for test: kms_cursor_edge_walk
results: parsing output: 55/ for test: kms_async_flips
results: parsing output: 56/ for test: gem_exec_async
results: parsing output: 57/ for test: kms_rotation_crc
results: parsing output: 58/ for test: i915_pm_rps
results: parsing output: 59/ for test: kms_plane_scaling
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_cursor_legacy
results: parsing output: 62/ for test: sysfs_heartbeat_interval
results: parsing output: 63/ for test: syncobj_wait
results: parsing output: 64/ for test: gem_exec_schedule
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: gem_exec_reloc
results: parsing output: 68/ for test: kms_atomic_transition
results: parsing output: 69/ for test: kms_getfb
results: parsing output: 70/ for test: gem_exec_reloc
results: parsing output: 71/ for test: kms_flip
results: parsing output: 72/ for test: kms_pm_dc
results: parsing output: 73/ for test: kms_chamelium_color
results: parsing output: 74/ for test: gem_exec_capture
results: parsing output: 75/ for test: perf_pmu
results: parsing output: 76/ for test: kms_plane_cursor
results: parsing output: 77/ for test: kms_big_fb
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: gem_mmap_gtt
results: parsing output: 80/ for test: perf
results: parsing output: 81/ for test: gem_exec_whisper
results: parsing output: 82/ for test: gem_close_race
results: parsing output: 83/ for test: syncobj_wait
results: parsing output: 84/ for test: syncobj_timeline
results: parsing output: 85/ for test: gem_exec_whisper
results: parsing output: 86/ for test: syncobj_timeline
results: parsing output: 87/ for test: gem_mmap_gtt
results: parsing output: 88/ for test: gem_readwrite
results: parsing output: 89/ for test: gem_lmem_swapping
results: parsing output: 90/ for test: api_intel_bb
results: parsing output: 91/ for test: gem_exec_params
results: parsing output: 92/ for test: dumb_buffer
results: parsing output: 93/ for test: gen9_exec_parse
results: parsing output: 94/ for test: kms_flip_scaled_crc
results: parsing output: 95/ for test: gem_exec_fence
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_vblank
results: parsing output: 98/ for test: kms_big_fb
results: parsing output: 99/ for test: perf
results: parsing output: 100/ for test: kms_psr
results: parsing output: 101/ for test: syncobj_wait
results: parsing output: 102/ for test: kms_content_protection
results: parsing output: 103/ for test: api_intel_allocator
results: parsing output: 104/ for test: kms_busy
results: parsing output: 105/ for test: kms_plane_scaling
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_flip
results: parsing output: 108/ for test: prime_vgem
results: parsing output: 109/ for test: kms_dp_link_training
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_cursor_legacy
results: parsing output: 112/ for test: syncobj_timeline
results: parsing output: 113/ for test: gem_exec_schedule
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: kms_psr
results: parsing output: 116/ for test: gem_pxp
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_psr
results: parsing output: 120/ for test: gem_fence_thrash
results: parsing output: 121/ for test: i915_drm_fdinfo
results: parsing output: 122/ for test: kms_vrr
results: parsing output: 123/ for test: syncobj_basic
results: parsing output: 124/ for test: kms_chamelium_color
results: parsing output: 125/ for test: kms_psr
results: parsing output: 126/ for test: kms_ccs
results: parsing output: 127/ for test: i915_suspend
results: parsing output: 128/ for test: syncobj_wait
results: parsing output: 129/ for test: kms_flip
results: parsing output: 130/ for test: syncobj_wait
results: parsing output: 131/ for test: gem_exec_flush
results: parsing output: 132/ for test: kms_chamelium_hpd
results: parsing output: 133/ for test: i915_pm_freq_api
Done.