[57.360207] Initializing watchdogs
[57.360253]   /dev/watchdog0
[57.373239] [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.377319] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.379455] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.382899] [001/133] (960s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-fullscreen)
[57.515425] Starting subtest: psr-2p-scndscrn-spr-indfb-fullscreen
[57.519340] Subtest psr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[57.616193] [002/133] (960s left) kms_ccs (ccs-on-another-bo-yf-tiled-ccs)
[57.702701] Starting subtest: ccs-on-another-bo-yf-tiled-ccs
[57.704925] Starting dynamic subtest: pipe-A-eDP-1
[57.727297] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[57.737734] Starting dynamic subtest: pipe-B-eDP-1
[57.759127] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[57.765199] Starting dynamic subtest: pipe-C-eDP-1
[57.787026] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[57.793207] Starting dynamic subtest: pipe-D-eDP-1
[57.815910] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[57.820580] Subtest ccs-on-another-bo-yf-tiled-ccs: SKIP (0.003s)
[60.021278] [003/133] (957s left) syncobj_timeline (wait-all-interrupted)
[60.076364] Starting subtest: wait-all-interrupted
[60.179658] Subtest wait-all-interrupted: SUCCESS (0.103s)
[60.265591] [004/133] (957s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[60.372772] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[63.913421] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (3.552s)
[65.114565] [005/133] (952s left) kms_pm_lpsp (kms-lpsp)
[66.231278] Starting subtest: kms-lpsp
[66.233547] Starting dynamic subtest: pipe-A-eDP-1
[66.402575] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.175s)
[66.696395] Subtest kms-lpsp: SUCCESS (0.469s)
[67.542939] [006/133] (950s left) kms_big_fb (yf-tiled-8bpp-rotate-180)
[67.702052] Starting subtest: yf-tiled-8bpp-rotate-180
[67.706361] Subtest yf-tiled-8bpp-rotate-180: SKIP (0.000s)
[67.967144] [007/133] (950s left) kms_cursor_crc (cursor-random-512x170)
[68.288245] Starting subtest: cursor-random-512x170
[68.292952] Subtest cursor-random-512x170: SKIP (0.001s)
[68.419164] [008/133] (949s left) kms_psr (fbc-pr-suspend)
[68.508847] Starting subtest: fbc-pr-suspend
[68.513247] Subtest fbc-pr-suspend: SKIP (0.000s)
[68.573848] [009/133] (949s left) kms_chamelium_edid (dp-edid-resolution-list)
[68.964247] Subtest dp-edid-resolution-list: SKIP (0.000s)
[69.736635] [010/133] (948s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[69.837962] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[69.840398] Starting dynamic subtest: pipe-A-default-mode
[71.022438] Dynamic subtest pipe-A-default-mode: SKIP (1.183s)
[71.026718] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.184s)
[71.180640] [011/133] (947s left) kms_plane_alpha_blend (alpha-transparent-fb)
[71.279457] Starting subtest: alpha-transparent-fb
[71.555591] Starting dynamic subtest: pipe-A-eDP-1
[72.189336] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.633s)
[72.197579] Starting dynamic subtest: pipe-D-eDP-1
[73.512671] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.321s)
[73.514674] Subtest alpha-transparent-fb: SUCCESS (2.234s)
[74.355193] [012/133] (943s left) syncobj_eventfd (invalid-bad-flags)
[74.408037] Starting subtest: invalid-bad-flags
[74.410073] Subtest invalid-bad-flags: SUCCESS (0.000s)
[74.501930] [013/133] (943s left) gem_ctx_sseu (invalid-args)
[74.569617] Subtest invalid-args: SKIP (0.000s)
[74.624506] [014/133] (943s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs-cc)
[74.720993] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs-cc
[74.723312] Starting dynamic subtest: pipe-A-eDP-1
[74.746133] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[74.753214] Starting dynamic subtest: pipe-B-eDP-1
[74.774755] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[74.782049] Starting dynamic subtest: pipe-C-eDP-1
[74.803226] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[74.809625] Starting dynamic subtest: pipe-D-eDP-1
[74.830648] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[74.834589] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs-cc: SKIP (0.003s)
[76.926328] [015/133] (941s left) kms_cursor_edge_walk (128x128-left-edge)
[77.009042] Starting subtest: 128x128-left-edge
[77.011403] Starting dynamic subtest: pipe-A-eDP-1
[80.487178] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.478s)
[80.495575] Starting dynamic subtest: pipe-D-eDP-1
[83.616080] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.127s)
[83.618419] Subtest 128x128-left-edge: SUCCESS (6.607s)
[84.417210] [016/133] (934s left) fbdev (eof)
[84.467675] Starting subtest: eof
[84.470045] Subtest eof: SUCCESS (0.000s)
[84.541777] [017/133] (933s left) kms_psr (fbc-pr-cursor-mmap-gtt)
[84.631443] Starting subtest: fbc-pr-cursor-mmap-gtt
[84.635603] Subtest fbc-pr-cursor-mmap-gtt: SKIP (0.000s)
[84.713267] [018/133] (933s left) gem_flink_basic (double-flink)
[84.760073] Starting subtest: double-flink
[84.762058] Subtest double-flink: SUCCESS (0.000s)
[84.861245] [019/133] (933s left) kms_psr (psr-primary-mmap-cpu)
[84.951809] Starting subtest: psr-primary-mmap-cpu
[84.954220] Starting dynamic subtest: eDP-1
[86.713522] Dynamic subtest eDP-1: SUCCESS (1.760s)
[86.715845] Subtest psr-primary-mmap-cpu: SUCCESS (1.761s)
[87.576510] [020/133] (931s left) syncobj_timeline (multi-wait-all-available-signaled)
[87.635753] Starting subtest: multi-wait-all-available-signaled
[87.638064] Subtest multi-wait-all-available-signaled: SUCCESS (0.000s)
[87.718516] [021/133] (930s left) gem_exec_reloc (basic-scanout)
[87.781022] Subtest basic-scanout: SKIP (0.000s)
[87.844506] [022/133] (930s left) gem_ctx_persistence (saturated-hostile-nopreempt)
[87.909951] Starting subtest: saturated-hostile-nopreempt
[87.912549] Starting dynamic subtest: rcs0
[87.978294] Dynamic subtest rcs0: SKIP (0.066s)
[87.988786] Starting dynamic subtest: bcs0
[88.000585] Dynamic subtest bcs0: SKIP (0.022s)
[88.007259] Starting dynamic subtest: vcs0
[88.018494] Dynamic subtest vcs0: SKIP (0.017s)
[88.024884] Starting dynamic subtest: vcs1
[88.039380] Dynamic subtest vcs1: SKIP (0.021s)
[88.045814] Starting dynamic subtest: vecs0
[88.057299] Dynamic subtest vecs0: SKIP (0.017s)
[88.063789] Starting dynamic subtest: ccs0
[88.081653] Dynamic subtest ccs0: SKIP (0.023s)
[88.085935] Subtest saturated-hostile-nopreempt: SKIP (0.171s)
[88.156769] [023/133] (930s left) sw_sync (alloc_fence_invalid_timeline)
[88.186917] Starting subtest: alloc_fence_invalid_timeline
[88.189846] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[88.237941] [024/133] (930s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[88.330113] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[88.334040] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[88.409378] [025/133] (930s left) kms_feature_discovery (display-2x)
[88.514632] Starting subtest: display-2x
[88.518131] Subtest display-2x: SKIP (0.000s)
[88.596332] [026/133] (930s left) kms_ccs (crc-primary-rotation-180-y-tiled-ccs)
[88.699223] Starting subtest: crc-primary-rotation-180-y-tiled-ccs
[88.701220] Starting dynamic subtest: pipe-A-eDP-1
[88.721087] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[88.735672] Starting dynamic subtest: pipe-B-eDP-1
[88.754478] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[88.768719] Starting dynamic subtest: pipe-C-eDP-1
[88.786132] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[88.793542] Starting dynamic subtest: pipe-D-eDP-1
[88.811665] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[88.815162] Subtest crc-primary-rotation-180-y-tiled-ccs: SKIP (0.005s)
[90.943427] [027/133] (927s left) kms_invalid_mode (zero-clock)
[91.044503] Starting subtest: zero-clock
[91.046883] Starting dynamic subtest: pipe-A-eDP-1
[91.329581] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.288s)
[91.338244] Starting dynamic subtest: pipe-B-eDP-1
[91.340173] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[91.346823] Starting dynamic subtest: pipe-C-eDP-1
[91.348822] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[91.356020] Starting dynamic subtest: pipe-D-eDP-1
[91.358292] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[91.360564] Subtest zero-clock: SUCCESS (0.292s)
[92.173856] [028/133] (926s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[92.287927] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[92.292386] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[92.368454] [029/133] (926s left) gem_render_copy (y-tiled-ccs-to-y-tiled-mc-ccs)
[92.422688] Starting subtest: y-tiled-ccs-to-y-tiled-mc-ccs
[92.427268] Subtest y-tiled-ccs-to-y-tiled-mc-ccs: SKIP (0.000s)
[92.614716] [030/133] (926s left) i915_pm_rps (thresholds-idle-park)
[92.670761] Starting subtest: thresholds-idle-park
[92.675520] Subtest thresholds-idle-park: SKIP (0.001s)
[92.770831] [031/133] (926s left) gen7_exec_parse (cmd-crossing-page)
[92.838884] Subtest cmd-crossing-page: SKIP (0.000s)
[92.909691] [032/133] (926s left) syncobj_wait (single-wait-for-submit-submitted)
[92.964065] Starting subtest: single-wait-for-submit-submitted
[93.065454] Subtest single-wait-for-submit-submitted: SUCCESS (0.102s)
[93.136593] [033/133] (925s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-pwrite)
[93.239945] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-pwrite
[98.151037] Subtest fbc-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (4.915s)
[99.281303] [034/133] (919s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[99.375824] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[106.780314] Subtest psr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.409s)
[106.917167] [035/133] (912s left) gem_ppgtt (blt-vs-render-ctx0)
[106.975479] Starting subtest: blt-vs-render-ctx0
[139.821092] Subtest blt-vs-render-ctx0: SUCCESS (32.843s)
[139.948574] [036/133] (879s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[140.094761] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[144.645040] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (4.554s)
[145.806704] [037/133] (873s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-plflip-blt)
[145.901186] Starting subtest: fbc-2p-scndscrn-shrfb-plflip-blt
[145.905668] Subtest fbc-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[145.986611] [038/133] (873s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-blt)
[146.091607] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-blt
[153.434439] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.346s)
[153.566967] [039/133] (865s left) kms_frontbuffer_tracking (psr-suspend)
[153.669650] Starting subtest: psr-suspend
[191.772624] Subtest psr-suspend: SUCCESS (8.836s)
[191.873279] [040/133] (827s left) api_intel_bb (misplaced-blitter)
[191.932941] Starting subtest: misplaced-blitter
[191.938967] Subtest misplaced-blitter: SUCCESS (0.009s)
[192.058513] [041/133] (827s left) kms_setmode (basic-clone-single-crtc)
[192.113655] Starting subtest: basic-clone-single-crtc
[192.121699] Subtest basic-clone-single-crtc: SKIP (0.001s)
[192.219723] [042/133] (827s left) kms_pm_rpm (modeset-lpsp-stress)
[194.280091] Starting subtest: modeset-lpsp-stress
[210.502979] Subtest modeset-lpsp-stress: SUCCESS (16.296s)
[210.629096] [043/133] (808s left) gem_exec_whisper (basic-fds-forked-all)
[210.704793] Starting subtest: basic-fds-forked-all
[234.167214] Subtest basic-fds-forked-all: SUCCESS (23.478s)
[234.350943] [044/133] (785s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[234.453741] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[234.458298] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[234.539503] [045/133] (785s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[234.632385] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[234.637400] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[234.725108] [046/133] (784s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-msflip-blt)
[234.819489] Starting subtest: psr-1p-primscrn-shrfb-msflip-blt
[242.188581] Subtest psr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.368s)
[242.302288] [047/133] (777s left) kms_ccs (crc-sprite-planes-basic-y-tiled-ccs)
[242.405838] Starting subtest: crc-sprite-planes-basic-y-tiled-ccs
[242.408401] Starting dynamic subtest: pipe-A-eDP-1
[242.472974] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[242.481923] Starting dynamic subtest: pipe-B-eDP-1
[242.550749] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[242.561006] Starting dynamic subtest: pipe-C-eDP-1
[242.628804] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[242.637949] Starting dynamic subtest: pipe-D-eDP-1
[242.704773] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[242.709466] Subtest crc-sprite-planes-basic-y-tiled-ccs: SKIP (0.009s)
[244.701476] [048/133] (774s left) kms_plane (pixel-format-y-tiled-gen12-mc-ccs-modifier-source-clamping)
[244.801864] Starting subtest: pixel-format-y-tiled-gen12-mc-ccs-modifier-source-clamping
[244.806779] Subtest pixel-format-y-tiled-gen12-mc-ccs-modifier-source-clamping: SKIP (0.000s)
[244.871486] [049/133] (774s left) kms_content_protection (atomic-dpms-hdcp14)
[245.019941] Starting subtest: atomic-dpms-hdcp14
[245.025778] Subtest atomic-dpms-hdcp14: SKIP (0.000s)
[245.126745] [050/133] (774s left) kms_color_pipeline (plane-ctm3x4-lut1d)
[245.223584] Starting subtest: plane-ctm3x4-lut1d
[245.381629] Starting dynamic subtest: pipe-A-plane-0
[245.759175] Dynamic subtest pipe-A-plane-0: FAIL (0.193s)
[245.769147] Starting dynamic subtest: pipe-A-plane-1
[245.967700] Dynamic subtest pipe-A-plane-1: FAIL (0.169s)
[245.976527] Starting dynamic subtest: pipe-A-plane-2
[246.219597] Dynamic subtest pipe-A-plane-2: FAIL (0.166s)
[247.104405] Starting dynamic subtest: pipe-B-plane-0
[247.589753] Dynamic subtest pipe-B-plane-0: FAIL (0.174s)
[247.602713] Starting dynamic subtest: pipe-B-plane-1
[247.978241] Dynamic subtest pipe-B-plane-1: FAIL (0.166s)
[247.988716] Starting dynamic subtest: pipe-B-plane-2
[248.331558] Dynamic subtest pipe-B-plane-2: FAIL (0.712s)
[249.346702] Starting dynamic subtest: pipe-C-plane-0
[249.937988] Dynamic subtest pipe-C-plane-0: FAIL (0.151s)
[249.948959] Starting dynamic subtest: pipe-C-plane-1
[250.384545] Dynamic subtest pipe-C-plane-1: FAIL (0.734s)
[250.396846] Starting dynamic subtest: pipe-C-plane-2
[250.838922] Dynamic subtest pipe-C-plane-2: FAIL (0.149s)
[251.595747] Starting dynamic subtest: pipe-D-plane-0
[252.259579] Dynamic subtest pipe-D-plane-0: FAIL (0.141s)
[252.269932] Starting dynamic subtest: pipe-D-plane-1
[252.810843] Dynamic subtest pipe-D-plane-1: FAIL (0.703s)
[252.821179] Starting dynamic subtest: pipe-D-plane-2
[253.357231] Dynamic subtest pipe-D-plane-2: FAIL (0.634s)
[253.367114] Subtest plane-ctm3x4-lut1d: FAIL (8.145s)
[254.226458] [051/133] (765s left) gem_lmem_swapping (heavy-random)
[254.289705] Subtest heavy-random: SKIP (0.000s)
[254.331198] [052/133] (765s left) kms_psr2_sf (pr-overlay-plane-update-continuous-sf)
[254.446262] Starting subtest: pr-overlay-plane-update-continuous-sf
[254.450610] Subtest pr-overlay-plane-update-continuous-sf: SKIP (0.000s)
[254.521120] [053/133] (765s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions-varying-size)
[254.638437] Starting subtest: cursorB-vs-flipA-atomic-transitions-varying-size
[254.643311] Subtest cursorB-vs-flipA-atomic-transitions-varying-size: SKIP (0.001s)
[254.792037] [054/133] (765s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[254.883504] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[254.886228] Starting dynamic subtest: pipe-A
[255.218333] Dynamic subtest pipe-A: SUCCESS (0.338s)
[255.226436] Starting dynamic subtest: pipe-B
[256.627413] Dynamic subtest pipe-B: SUCCESS (1.408s)
[256.636461] Starting dynamic subtest: pipe-C
[258.051694] Dynamic subtest pipe-C: SUCCESS (1.424s)
[258.061091] Starting dynamic subtest: pipe-D
[259.485721] Dynamic subtest pipe-D: SUCCESS (1.433s)
[259.488425] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (4.606s)
[260.654309] [055/133] (759s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[260.752621] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[260.757727] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[260.852378] [056/133] (759s left) kms_psr (fbc-psr2-sprite-mmap-gtt)
[260.949483] Starting subtest: fbc-psr2-sprite-mmap-gtt
[260.952025] Starting dynamic subtest: eDP-1
[260.957104] Dynamic subtest eDP-1: SKIP (0.000s)
[260.961938] Subtest fbc-psr2-sprite-mmap-gtt: SKIP (0.001s)
[261.025053] [057/133] (759s left) gem_pxp (protected-raw-src-copy-not-readible)
[261.157549] Starting subtest: protected-raw-src-copy-not-readible
[261.215955] Subtest protected-raw-src-copy-not-readible: SUCCESS (0.060s)
[261.349238] [058/133] (758s left) gem_softpin (allocator-evict)
[261.408866] Starting subtest: allocator-evict
[261.411395] Starting dynamic subtest: rcs0
[284.542991] Dynamic subtest rcs0: SUCCESS (23.139s)
[284.553847] Starting dynamic subtest: bcs0
[308.230743] Dynamic subtest bcs0: SUCCESS (23.687s)
[308.241224] Starting dynamic subtest: vcs0
[331.601978] Dynamic subtest vcs0: SUCCESS (23.367s)
[331.611922] Starting dynamic subtest: vcs1
[355.421423] Dynamic subtest vcs1: SUCCESS (23.819s)
[355.432259] Starting dynamic subtest: vecs0
[379.301554] Dynamic subtest vecs0: SUCCESS (23.883s)
[379.312888] Starting dynamic subtest: ccs0
[403.288489] Dynamic subtest ccs0: SUCCESS (23.991s)
[403.291350] Subtest allocator-evict: SUCCESS (141.893s)
[403.372012] [059/133] (616s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[403.516949] Starting subtest: plane-scaler-unity-scaling-with-rotation
[403.519799] Starting dynamic subtest: pipe-A
[403.753297] Dynamic subtest pipe-A: SUCCESS (0.239s)
[403.762879] Starting dynamic subtest: pipe-B
[405.049599] Dynamic subtest pipe-B: SUCCESS (1.295s)
[405.058698] Starting dynamic subtest: pipe-C
[406.340780] Dynamic subtest pipe-C: SUCCESS (1.291s)
[406.350243] Starting dynamic subtest: pipe-D
[407.671502] Dynamic subtest pipe-D: SUCCESS (1.330s)
[407.673852] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (4.159s)
[408.850037] [060/133] (611s left) gem_ctx_exec (basic)
[408.907004] Starting subtest: basic
[408.912504] Subtest basic: SUCCESS (0.006s)
[409.020322] [061/133] (611s left) gem_exec_schedule (semaphore-noskip)
[409.103063] Starting subtest: semaphore-noskip
[409.532246] Subtest semaphore-noskip: SUCCESS (0.439s)
[410.220942] [062/133] (610s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[410.330141] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[410.987107] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.659s)
[411.137853] [063/133] (609s left) gem_exec_capture (capture-invisible)
[411.187645] Starting subtest: capture-invisible
[411.190365] Starting dynamic subtest: smem0
[411.194819] Dynamic subtest smem0: SKIP (0.000s)
[411.199753] Subtest capture-invisible: SKIP (0.001s)
[411.273169] [064/133] (609s left) kms_async_flips (async-flip-hang)
[411.363756] Starting subtest: async-flip-hang
[411.737570] Starting dynamic subtest: pipe-A-eDP-1
[426.655253] Dynamic subtest pipe-A-eDP-1: SUCCESS (14.918s)
[427.030690] Starting dynamic subtest: pipe-B-eDP-1
[442.660101] Dynamic subtest pipe-B-eDP-1: SUCCESS (15.628s)
[443.048507] Starting dynamic subtest: pipe-C-eDP-1
[458.656501] Dynamic subtest pipe-C-eDP-1: SUCCESS (15.608s)
[459.033766] Starting dynamic subtest: pipe-D-eDP-1
[474.653978] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.625s)
[474.656827] Subtest async-flip-hang: SUCCESS (63.281s)
[475.807372] [065/133] (544s left) kms_dp_aux_dev
[476.162189] [066/133] (544s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move)
[476.257827] Starting subtest: fbc-2p-scndscrn-cur-indfb-move
[476.262933] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[476.345114] [067/133] (544s left) kms_cursor_crc (cursor-offscreen-128x128)
[476.547964] Starting subtest: cursor-offscreen-128x128
[476.553289] Starting dynamic subtest: pipe-A-eDP-1
[478.483968] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.930s)
[478.511894] Starting dynamic subtest: pipe-D-eDP-1
[481.113844] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.608s)
[481.116436] Subtest cursor-offscreen-128x128: SUCCESS (4.563s)
[481.957156] [068/133] (538s left) gem_userptr_blits (sd-probe)
[482.029660] Starting subtest: sd-probe
[482.032098] Subtest sd-probe: SUCCESS (0.000s)
[482.151211] [069/133] (538s left) kms_flip (2x-flip-vs-rmfb-interruptible)
[482.247842] Starting subtest: 2x-flip-vs-rmfb-interruptible
[482.259301] Subtest 2x-flip-vs-rmfb-interruptible: SKIP (0.010s)
[482.359253] [070/133] (538s left) gem_exec_fence (keep-in-fence)
[482.542738] Starting subtest: keep-in-fence
[482.545960] Starting dynamic subtest: rcs0
[482.597548] Dynamic subtest rcs0: SUCCESS (0.054s)
[482.610131] Starting dynamic subtest: bcs0
[482.653297] Dynamic subtest bcs0: SUCCESS (0.053s)
[482.665814] Starting dynamic subtest: vcs0
[484.231224] Dynamic subtest vcs0: SUCCESS (1.575s)
[484.244578] Starting dynamic subtest: vcs1
[486.215506] Dynamic subtest vcs1: SUCCESS (1.980s)
[486.229254] Starting dynamic subtest: vecs0
[488.199355] Dynamic subtest vecs0: SUCCESS (1.981s)
[488.211927] Starting dynamic subtest: ccs0
[488.430949] Dynamic subtest ccs0: SUCCESS (0.230s)
[488.433816] Subtest keep-in-fence: SUCCESS (5.889s)
[490.259670] [FACT gem_exec_fence (keep-in-fence)] new: kernel.kmod_is_loaded.vgem: true
[490.265895] [071/133] (530s left) kms_big_fb (x-tiled-addfb)
[490.370805] Starting subtest: x-tiled-addfb
[490.373549] Subtest x-tiled-addfb: SUCCESS (0.000s)
[490.464631] [072/133] (530s left) kms_cursor_legacy (cursora-vs-flipb-legacy)
[490.578302] Starting subtest: cursorA-vs-flipB-legacy
[490.582927] Subtest cursorA-vs-flipB-legacy: SKIP (0.001s)
[490.722698] [073/133] (529s left) gem_mmap_gtt (pf-nonblock)
[490.780423] Subtest pf-nonblock: SKIP (0.000s)
[490.843480] [074/133] (529s left) kms_psr (pr-primary-mmap-cpu)
[490.937189] Starting subtest: pr-primary-mmap-cpu
[490.942677] Subtest pr-primary-mmap-cpu: SKIP (0.000s)
[491.021173] [075/133] (529s left) kms_selftest (drm_damage_helper)
[491.064546] Starting subtest: drm_damage_helper
[491.075676] Starting dynamic subtest: drm_test_damage_iter_no_damage
[491.165147] Dynamic subtest drm_test_damage_iter_no_damage: SUCCESS (0.091s)
[491.176602] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src
[491.250242] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src: SUCCESS (0.084s)
[491.260852] Starting dynamic subtest: drm_test_damage_iter_no_damage_src_moved
[491.345522] Dynamic subtest drm_test_damage_iter_no_damage_src_moved: SUCCESS (0.095s)
[491.354753] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src_moved
[491.425325] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src_moved: SUCCESS (0.078s)
[491.435359] Starting dynamic subtest: drm_test_damage_iter_no_damage_not_visible
[491.518003] Dynamic subtest drm_test_damage_iter_no_damage_not_visible: SUCCESS (0.091s)
[491.526995] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_crtc
[491.603286] Dynamic subtest drm_test_damage_iter_no_damage_no_crtc: SUCCESS (0.084s)
[491.612585] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_fb
[491.685785] Dynamic subtest drm_test_damage_iter_no_damage_no_fb: SUCCESS (0.082s)
[491.696567] Starting dynamic subtest: drm_test_damage_iter_simple_damage
[491.766691] Dynamic subtest drm_test_damage_iter_simple_damage: SUCCESS (0.078s)
[491.775931] Starting dynamic subtest: drm_test_damage_iter_single_damage
[491.845924] Dynamic subtest drm_test_damage_iter_single_damage: SUCCESS (0.080s)
[491.856199] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_src
[491.932517] Dynamic subtest drm_test_damage_iter_single_damage_intersect_src: SUCCESS (0.084s)
[491.942231] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_src
[492.015166] Dynamic subtest drm_test_damage_iter_single_damage_outside_src: SUCCESS (0.082s)
[492.025544] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src
[492.106729] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src: SUCCESS (0.091s)
[492.117237] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_fractional_src
[492.205763] Dynamic subtest drm_test_damage_iter_single_damage_intersect_fractional_src: SUCCESS (0.097s)
[492.216831] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_fractional_src
[492.308713] Dynamic subtest drm_test_damage_iter_single_damage_outside_fractional_src: SUCCESS (0.103s)
[492.319673] Starting dynamic subtest: drm_test_damage_iter_single_damage_src_moved
[492.396653] Dynamic subtest drm_test_damage_iter_single_damage_src_moved: SUCCESS (0.087s)
[492.407972] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src_moved
[492.484332] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src_moved: SUCCESS (0.087s)
[492.493987] Starting dynamic subtest: drm_test_damage_iter_damage
[492.563716] Dynamic subtest drm_test_damage_iter_damage: SUCCESS (0.078s)
[492.574343] Starting dynamic subtest: drm_test_damage_iter_damage_one_intersect
[492.646773] Dynamic subtest drm_test_damage_iter_damage_one_intersect: SUCCESS (0.082s)
[492.657866] Starting dynamic subtest: drm_test_damage_iter_damage_one_outside
[492.733980] Dynamic subtest drm_test_damage_iter_damage_one_outside: SUCCESS (0.086s)
[492.745133] Starting dynamic subtest: drm_test_damage_iter_damage_src_moved
[492.817802] Dynamic subtest drm_test_damage_iter_damage_src_moved: SUCCESS (0.081s)
[492.833139] Starting dynamic subtest: drm_test_damage_iter_damage_not_visible
[492.898624] Dynamic subtest drm_test_damage_iter_damage_not_visible: SUCCESS (0.078s)
[492.900616] Subtest drm_damage_helper: SUCCESS (1.835s)
[493.055626] [076/133] (527s left) syncobj_timeline (wait-zero-handles)
[493.118640] Starting subtest: wait-zero-handles
[493.121133] Subtest wait-zero-handles: SUCCESS (0.000s)
[493.212216] [077/133] (527s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-cpu)
[493.307808] Starting subtest: fbcpsr-rgb101010-draw-mmap-cpu
[499.985429] Subtest fbcpsr-rgb101010-draw-mmap-cpu: SUCCESS (6.681s)
[500.094278] [078/133] (520s left) kms_lease (lease-invalid-connector)
[500.176215] Starting subtest: lease-invalid-connector
[500.178543] Starting dynamic subtest: pipe-A-eDP-1
[500.180849] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[500.190272] Starting dynamic subtest: pipe-B-eDP-1
[500.192845] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[500.201700] Starting dynamic subtest: pipe-C-eDP-1
[500.204213] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[500.213082] Starting dynamic subtest: pipe-D-eDP-1
[500.215844] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[500.218609] Subtest lease-invalid-connector: SUCCESS (0.004s)
[500.256377] [079/133] (520s left) gem_exec_params (no-blt)
[500.309882] Starting subtest: no-blt
[500.315703] Subtest no-blt: SUCCESS (0.003s)
[500.437166] [080/133] (520s left) i915_pm_rpm (debugfs-forcewake-user)
[502.374449] Starting subtest: debugfs-forcewake-user
[513.756619] Subtest debugfs-forcewake-user: SUCCESS (11.383s)
[514.140915] [081/133] (506s left) kms_big_fb (linear-32bpp-rotate-180)
[514.247939] Starting subtest: linear-32bpp-rotate-180
[515.772784] Subtest linear-32bpp-rotate-180: SUCCESS (1.524s)
[515.891819] [082/133] (505s left) gem_workarounds (reset)
[515.974846] Starting subtest: reset
[516.148351] Subtest reset: SUCCESS (0.174s)
[516.259401] [083/133] (504s left) syncobj_timeline (reset-unsignaled)
[516.312105] Starting subtest: reset-unsignaled
[516.314762] Subtest reset-unsignaled: SUCCESS (0.000s)
[516.408273] [084/133] (504s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-plflip-blt)
[516.501712] Starting subtest: fbc-2p-primscrn-shrfb-plflip-blt
[516.505757] Subtest fbc-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[516.604284] [085/133] (504s left) prime_self_import (basic-llseek-size)
[516.644440] Starting subtest: basic-llseek-size
[516.662364] Subtest basic-llseek-size: SUCCESS (0.021s)
[516.782184] [086/133] (504s left) gem_exec_schedule (preempt-other)
[516.846938] Starting subtest: preempt-other
[516.848946] Starting dynamic subtest: rcs0
[516.915614] Dynamic subtest rcs0: SUCCESS (0.072s)
[516.930558] Starting dynamic subtest: bcs0
[516.984616] Dynamic subtest bcs0: SUCCESS (0.068s)
[516.995655] Starting dynamic subtest: vcs0
[517.052957] Dynamic subtest vcs0: SUCCESS (0.067s)
[517.065291] Starting dynamic subtest: vcs1
[517.121793] Dynamic subtest vcs1: SUCCESS (0.068s)
[517.134424] Starting dynamic subtest: vecs0
[517.195699] Dynamic subtest vecs0: SUCCESS (0.074s)
[517.206908] Starting dynamic subtest: ccs0
[517.267435] Dynamic subtest ccs0: SUCCESS (0.071s)
[517.269415] Subtest preempt-other: SUCCESS (0.426s)
[518.286040] [087/133] (503s left) gem_pxp (create-protected-buffer)
[518.394358] Starting subtest: create-protected-buffer
[518.396940] Subtest create-protected-buffer: SUCCESS (0.000s)
[518.498385] [088/133] (502s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[518.595032] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[518.599576] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[518.675067] [089/133] (502s left) gem_mmap_wc (set-cache-level)
[518.737774] Subtest set-cache-level: SKIP (0.000s)
[518.797741] [090/133] (502s left) kms_busy (extended-pageflip-hang-oldfb)
[518.897250] Starting subtest: extended-pageflip-hang-oldfb
[518.899603] Starting dynamic subtest: pipe-A
[519.290809] Dynamic subtest pipe-A: SUCCESS (0.394s)
[519.306087] Starting dynamic subtest: pipe-D
[520.434637] Dynamic subtest pipe-D: SUCCESS (1.139s)
[520.437107] Subtest extended-pageflip-hang-oldfb: SUCCESS (1.538s)
[521.272014] [091/133] (500s left) kms_psr (psr-cursor-mmap-gtt)
[521.367095] Starting subtest: psr-cursor-mmap-gtt
[521.369718] Starting dynamic subtest: eDP-1
[523.060671] Dynamic subtest eDP-1: SUCCESS (1.694s)
[523.063186] Subtest psr-cursor-mmap-gtt: SUCCESS (1.695s)
[523.914291] [092/133] (497s left) kms_async_flips (test-time-stamp)
[524.013442] Starting subtest: test-time-stamp
[524.398577] Starting dynamic subtest: pipe-A-eDP-1
[525.232857] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.833s)
[525.608578] Starting dynamic subtest: pipe-B-eDP-1
[526.364035] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.769s)
[526.740161] Starting dynamic subtest: pipe-C-eDP-1
[527.494437] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.760s)
[527.862254] Starting dynamic subtest: pipe-D-eDP-1
[528.624178] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.773s)
[528.626858] Subtest test-time-stamp: SUCCESS (4.611s)
[529.777311] [093/133] (491s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[530.184168] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[530.985615] [094/133] (490s left) gem_close_race (multigpu-basic-threads)
[531.049990] Starting subtest: multigpu-basic-threads
[531.055767] Subtest multigpu-basic-threads: SKIP (0.000s)
[531.267448] [095/133] (490s left) prime_mmap (test_correct_cpu_write)
[531.320616] Starting subtest: test_correct_cpu_write
[531.323580] Starting dynamic subtest: test_correct_cpu_write-smem
[531.325887] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.000s)
[531.328584] Subtest test_correct_cpu_write: SUCCESS (0.001s)
[531.425094] [096/133] (490s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-sf)
[531.523638] Starting subtest: fbc-psr2-cursor-plane-move-continuous-sf
[531.526268] Starting dynamic subtest: pipe-A-eDP-1
[531.598817] Dynamic subtest pipe-A-eDP-1: SKIP (0.077s)
[531.608881] Starting dynamic subtest: pipe-B-eDP-1
[532.690897] Dynamic subtest pipe-B-eDP-1: SKIP (1.090s)
[532.696175] Subtest fbc-psr2-cursor-plane-move-continuous-sf: SKIP (1.170s)
[533.901312] [097/133] (487s left) syncobj_timeline (etime-single-wait-for-submit-submitted)
[533.953846] Starting subtest: etime-single-wait-for-submit-submitted
[534.057848] Subtest etime-single-wait-for-submit-submitted: SUCCESS (0.104s)
[534.139307] [098/133] (487s left) perf (non-zero-reason)
[534.283830] Starting subtest: non-zero-reason
[534.286529] Starting dynamic subtest: 0-rcs0
[539.647299] Dynamic subtest 0-rcs0: SUCCESS (5.362s)
[539.660792] Starting dynamic subtest: 1-vcs0
[543.558208] Dynamic subtest 1-vcs0: SUCCESS (3.910s)
[543.561153] Subtest non-zero-reason: SUCCESS (9.275s)
[543.607226] [099/133] (478s left) kms_flip (2x-flip-vs-absolute-wf_vblank)
[543.715718] Starting subtest: 2x-flip-vs-absolute-wf_vblank
[543.726641] Subtest 2x-flip-vs-absolute-wf_vblank: SKIP (0.010s)
[543.831191] [100/133] (477s left) gem_fence_thrash (bo-write-verify-y)
[543.867641] Starting subtest: bo-write-verify-y
[543.883741] Subtest bo-write-verify-y: SKIP (0.015s)
[543.950556] [101/133] (477s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[544.047254] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[544.051963] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[544.155320] [102/133] (477s left) prime_vgem (busy)
[544.231438] Starting subtest: busy
[544.233987] Starting dynamic subtest: rcs0
[544.302213] Dynamic subtest rcs0: SUCCESS (0.070s)
[544.312674] Starting dynamic subtest: bcs0
[544.363784] Dynamic subtest bcs0: SUCCESS (0.061s)
[544.373695] Starting dynamic subtest: vcs0
[544.413014] Dynamic subtest vcs0: SUCCESS (0.048s)
[544.423731] Starting dynamic subtest: vcs1
[544.460237] Dynamic subtest vcs1: SUCCESS (0.046s)
[544.470621] Starting dynamic subtest: vecs0
[544.510089] Dynamic subtest vecs0: SUCCESS (0.049s)
[544.520262] Starting dynamic subtest: ccs0
[544.556461] Dynamic subtest ccs0: SUCCESS (0.045s)
[544.558786] Subtest busy: SUCCESS (0.326s)
[544.685859] [103/133] (477s left) gem_eio (in-flight-suspend)
[544.907165] Starting subtest: in-flight-suspend
[587.512805] Subtest in-flight-suspend: SUCCESS (13.477s)
[587.650791] [104/133] (434s left) kms_vblank (wait-forked-busy)
[587.745620] Starting subtest: wait-forked-busy
[587.747685] Starting dynamic subtest: pipe-A-eDP-1
[589.614266] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.897s)
[589.630075] Starting dynamic subtest: pipe-D-eDP-1
[592.137731] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.547s)
[592.140071] Subtest wait-forked-busy: SUCCESS (4.450s)
[592.935074] [105/133] (429s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[593.024320] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[593.028739] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[593.095396] [106/133] (428s left) gem_exec_reloc (basic-wc-active)
[593.171726] Subtest basic-wc-active: SKIP (0.000s)
[593.244012] [107/133] (428s left) kms_big_fb (y-tiled-64bpp-rotate-180)
[593.340823] Starting subtest: y-tiled-64bpp-rotate-180
[593.344974] Subtest y-tiled-64bpp-rotate-180: SKIP (0.000s)
[593.448559] [108/133] (428s left) i915_query (query-topology-known-pci-ids)
[593.511160] Starting subtest: query-topology-known-pci-ids
[593.515532] Subtest query-topology-known-pci-ids: SKIP (0.000s)
[593.622348] [109/133] (428s left) kms_cdclk (plane-scaling)
[593.707606] Starting subtest: plane-scaling
[593.709684] Starting dynamic subtest: pipe-A-eDP-1
[593.993830] Dynamic subtest pipe-A-eDP-1: SKIP (0.288s)
[594.008370] Starting dynamic subtest: pipe-B-eDP-1
[594.012948] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[594.022791] Starting dynamic subtest: pipe-C-eDP-1
[594.027249] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[594.037399] Starting dynamic subtest: pipe-D-eDP-1
[594.041702] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[594.045875] Subtest plane-scaling: SKIP (0.295s)
[594.849873] [110/133] (427s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[594.957999] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt
[594.962124] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[595.042687] [111/133] (427s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-downscaling)
[595.125696] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-downscaling
[595.128115] Starting dynamic subtest: pipe-A-default-mode
[596.309940] Dynamic subtest pipe-A-default-mode: SKIP (1.188s)
[596.314458] Subtest flip-32bpp-xtile-to-64bpp-xtile-downscaling: SKIP (1.190s)
[596.455465] [112/133] (425s left) gem_userptr_blits (access-control)
[596.529490] Starting subtest: access-control
[596.533706] Subtest access-control: SKIP (0.000s)
[596.648294] [113/133] (425s left) perf_pmu (busy-idle-no-semaphores)
[596.688792] Starting subtest: busy-idle-no-semaphores
[596.691221] Starting dynamic subtest: rcs0
[597.332777] Dynamic subtest rcs0: SUCCESS (0.648s)
[597.343828] Starting dynamic subtest: bcs0
[597.982741] Dynamic subtest bcs0: SUCCESS (0.650s)
[597.996339] Starting dynamic subtest: vcs0
[598.634846] Dynamic subtest vcs0: SUCCESS (0.652s)
[598.648400] Starting dynamic subtest: vcs1
[599.927606] Dynamic subtest vcs1: SUCCESS (1.295s)
[599.940646] Starting dynamic subtest: vecs0
[601.974646] Dynamic subtest vecs0: SUCCESS (2.052s)
[601.988234] Starting dynamic subtest: ccs0
[603.958859] Dynamic subtest ccs0: SUCCESS (1.987s)
[603.961667] Subtest busy-idle-no-semaphores: SUCCESS (7.299s)
[604.078693] [114/133] (418s left) gem_ctx_freq (sysfs)
[604.155682] Starting subtest: sysfs
[604.158080] Starting dynamic subtest: gt0
[609.651975] Dynamic subtest gt0: SUCCESS (5.505s)
[609.665000] Starting dynamic subtest: gt1
[651.342929] Dynamic subtest gt1: SUCCESS (41.693s)
[651.345604] Subtest sysfs: SUCCESS (47.202s)
[651.478724] [115/133] (370s left) dumb_buffer (create-valid-dumb)
[651.530691] Starting subtest: create-valid-dumb
[651.532880] Subtest create-valid-dumb: SUCCESS (0.000s)
[651.634852] [116/133] (370s left) gem_media_fill (media-fill)
[651.698350] Subtest media-fill: SKIP (0.000s)
[651.826210] [117/133] (370s left) gem_exec_nop (basic-parallel)
[651.901482] Starting subtest: basic-parallel
[668.304315] Subtest basic-parallel: SUCCESS (16.417s)
[668.539151] [118/133] (354s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[668.588891] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[668.591237] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[668.692397] [119/133] (353s left) gem_partial_pwrite_pread (write-snoop)
[668.750310] Subtest write-snoop: SKIP (0.000s)
[668.819111] [120/133] (353s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-move)
[668.912621] Starting subtest: fbc-2p-scndscrn-spr-indfb-move
[668.916794] Subtest fbc-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[669.007694] [121/133] (353s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[669.106952] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[669.931192] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.826s)
[670.099038] [122/133] (352s left) gem_render_copy (x-tiled)
[670.170841] Starting subtest: x-tiled
[670.173178] Starting dynamic subtest: smem
[670.210987] Dynamic subtest smem: SUCCESS (0.040s)
[670.213567] Subtest x-tiled: SUCCESS (0.041s)
[670.440321] [123/133] (352s left) gem_exec_reloc (basic-wc-read-active)
[670.506804] Subtest basic-wc-read-active: SKIP (0.000s)
[670.576239] [124/133] (352s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[670.699244] Starting subtest: basic-flip-before-cursor-varying-size
[671.627103] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.926s)
[672.451389] [125/133] (350s left) gem_tiled_wb
[672.589148] [126/133] (350s left) gem_exec_balancer (invalid-bonds)
[672.643208] Starting subtest: invalid-bonds
[672.647846] Subtest invalid-bonds: SKIP (0.000s)
[672.938108] [127/133] (350s left) api_intel_allocator (execbuf-with-allocator)
[672.988745] Starting subtest: execbuf-with-allocator
[672.994960] Subtest execbuf-with-allocator: SUCCESS (0.009s)
[673.108498] [128/133] (349s left) gem_exec_fence (basic-busy-all)
[673.173564] Starting subtest: basic-busy-all
[673.254116] Subtest basic-busy-all: SUCCESS (0.081s)
[675.415851] [129/133] (347s left) gem_exec_reloc (basic-cpu-read-noreloc)
[675.482618] Subtest basic-cpu-read-noreloc: SKIP (0.000s)
[675.546167] [130/133] (347s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-wc)
[675.650163] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-wc
[682.277849] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.631s)
[682.391131] [131/133] (340s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[682.493919] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu
[682.498333] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[682.600307] [132/133] (340s left) i915_pciid
[682.761519] [133/133] (340s left) i915_drm_fdinfo (memory-info-resident)
[682.801847] Starting subtest: memory-info-resident
[682.803928] Starting dynamic subtest: smem0
[685.755421] Dynamic subtest smem0: SUCCESS (2.955s)
[685.757597] Subtest memory-info-resident: SUCCESS (2.956s)
[685.848358] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_ccs
results: parsing output: 2/ for test: syncobj_timeline
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_pm_lpsp
results: parsing output: 5/ for test: kms_big_fb
results: parsing output: 6/ for test: kms_cursor_crc
results: parsing output: 7/ for test: kms_psr
results: parsing output: 8/ for test: kms_chamelium_edid
results: parsing output: 9/ for test: kms_flip_scaled_crc
results: parsing output: 10/ for test: kms_plane_alpha_blend
results: parsing output: 11/ for test: syncobj_eventfd
results: parsing output: 12/ for test: gem_ctx_sseu
results: parsing output: 13/ for test: kms_ccs
results: parsing output: 14/ for test: kms_cursor_edge_walk
results: parsing output: 15/ for test: fbdev
results: parsing output: 16/ for test: kms_psr
results: parsing output: 17/ for test: gem_flink_basic
results: parsing output: 18/ for test: kms_psr
results: parsing output: 19/ for test: syncobj_timeline
results: parsing output: 20/ for test: gem_exec_reloc
results: parsing output: 21/ for test: gem_ctx_persistence
results: parsing output: 22/ for test: sw_sync
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: kms_feature_discovery
results: parsing output: 25/ for test: kms_ccs
results: parsing output: 26/ for test: kms_invalid_mode
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: gem_render_copy
results: parsing output: 29/ for test: i915_pm_rps
results: parsing output: 30/ for test: gen7_exec_parse
results: parsing output: 31/ for test: syncobj_wait
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: gem_ppgtt
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: api_intel_bb
results: parsing output: 40/ for test: kms_setmode
results: parsing output: 41/ for test: kms_pm_rpm
results: parsing output: 42/ for test: gem_exec_whisper
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_ccs
results: parsing output: 47/ for test: kms_plane
results: parsing output: 48/ for test: kms_content_protection
results: parsing output: 49/ for test: kms_color_pipeline
results: parsing output: 50/ for test: gem_lmem_swapping
results: parsing output: 51/ for test: kms_psr2_sf
results: parsing output: 52/ for test: kms_cursor_legacy
results: parsing output: 53/ for test: kms_plane_scaling
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: kms_psr
results: parsing output: 56/ for test: gem_pxp
results: parsing output: 57/ for test: gem_softpin
results: parsing output: 58/ for test: kms_plane_scaling
results: parsing output: 59/ for test: gem_ctx_exec
results: parsing output: 60/ for test: gem_exec_schedule
results: parsing output: 61/ for test: kms_big_fb
results: parsing output: 62/ for test: gem_exec_capture
results: parsing output: 63/ for test: kms_async_flips
results: parsing output: 64/ for test: kms_dp_aux_dev
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: kms_cursor_crc
results: parsing output: 67/ for test: gem_userptr_blits
results: parsing output: 68/ for test: kms_flip
results: parsing output: 69/ for test: gem_exec_fence
results: parsing output: 70/ for test: kms_big_fb
results: parsing output: 71/ for test: kms_cursor_legacy
results: parsing output: 72/ for test: gem_mmap_gtt
results: parsing output: 73/ for test: kms_psr
results: parsing output: 74/ for test: kms_selftest
results: parsing output: 75/ for test: syncobj_timeline
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_lease
results: parsing output: 78/ for test: gem_exec_params
results: parsing output: 79/ for test: i915_pm_rpm
results: parsing output: 80/ for test: kms_big_fb
results: parsing output: 81/ for test: gem_workarounds
results: parsing output: 82/ for test: syncobj_timeline
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: prime_self_import
results: parsing output: 85/ for test: gem_exec_schedule
results: parsing output: 86/ for test: gem_pxp
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: gem_mmap_wc
results: parsing output: 89/ for test: kms_busy
results: parsing output: 90/ for test: kms_psr
results: parsing output: 91/ for test: kms_async_flips
results: parsing output: 92/ for test: kms_chamelium_hpd
results: parsing output: 93/ for test: gem_close_race
results: parsing output: 94/ for test: prime_mmap
results: parsing output: 95/ for test: kms_psr2_sf
results: parsing output: 96/ for test: syncobj_timeline
results: parsing output: 97/ for test: perf
results: parsing output: 98/ for test: kms_flip
results: parsing output: 99/ for test: gem_fence_thrash
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: prime_vgem
results: parsing output: 102/ for test: gem_eio
results: parsing output: 103/ for test: kms_vblank
results: parsing output: 104/ for test: kms_plane
results: parsing output: 105/ for test: gem_exec_reloc
results: parsing output: 106/ for test: kms_big_fb
results: parsing output: 107/ for test: i915_query
results: parsing output: 108/ for test: kms_cdclk
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_flip_scaled_crc
results: parsing output: 111/ for test: gem_userptr_blits
results: parsing output: 112/ for test: perf_pmu
results: parsing output: 113/ for test: gem_ctx_freq
results: parsing output: 114/ for test: dumb_buffer
results: parsing output: 115/ for test: gem_media_fill
results: parsing output: 116/ for test: gem_exec_nop
results: parsing output: 117/ for test: syncobj_wait
results: parsing output: 118/ for test: gem_partial_pwrite_pread
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: kms_big_fb
results: parsing output: 121/ for test: gem_render_copy
results: parsing output: 122/ for test: gem_exec_reloc
results: parsing output: 123/ for test: kms_cursor_legacy
results: parsing output: 124/ for test: gem_tiled_wb
results: parsing output: 125/ for test: gem_exec_balancer
results: parsing output: 126/ for test: api_intel_allocator
results: parsing output: 127/ for test: gem_exec_fence
results: parsing output: 128/ for test: gem_exec_reloc
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: i915_pciid
results: parsing output: 132/ for test: i915_drm_fdinfo
Done.