[38.993669] Initializing watchdogs [38.993725] /dev/watchdog0 [38.999408] [001/144] (960s left) kms_chamelium_color (ctm-0-25) [39.119853] Subtest ctm-0-25: SKIP (0.000s) [39.150147] [002/144] (960s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-blt) [39.268362] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-blt [39.270078] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s) [39.344311] [003/144] (960s left) kms_plane (plane-position-hole-dpms) [39.473717] Starting subtest: plane-position-hole-dpms [39.474666] Starting dynamic subtest: pipe-A [42.587025] Dynamic subtest pipe-A: SUCCESS (3.112s) [42.593165] Starting dynamic subtest: pipe-B [45.467983] Dynamic subtest pipe-B: SUCCESS (2.880s) [45.468856] Subtest plane-position-hole-dpms: SUCCESS (5.994s) [45.653427] [004/144] (953s left) kms_psr2_sf (overlay-plane-update-continuous-sf) [45.735494] Subtest overlay-plane-update-continuous-sf: SKIP (0.000s) [45.765302] [005/144] (953s left) gem_exec_flush (basic-uc-set-default) [45.818035] Subtest basic-uc-set-default: SKIP (0.000s) [45.848251] [006/144] (953s left) kms_psr (fbc-psr-cursor-plane-onoff) [45.963385] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.000s) [45.981412] [007/144] (953s left) gem_partial_pwrite_pread (write-snoop) [46.030602] Subtest write-snoop: SKIP (0.000s) [46.065623] [008/144] (953s left) gem_exec_reloc (basic-gtt-read) [46.115228] Subtest basic-gtt-read: SKIP (0.000s) [46.149050] [009/144] (953s left) gem_exec_balancer (bonded-semaphore) [46.207781] Starting subtest: bonded-semaphore [46.209662] Subtest bonded-semaphore: SKIP (0.000s) [46.508931] [010/144] (953s left) syncobj_timeline (multi-wait-for-submit-available-signaled) [46.556273] Starting subtest: multi-wait-for-submit-available-signaled [46.557119] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.000s) [46.610287] [011/144] (953s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-downscaling) [46.729484] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-downscaling [46.730432] Starting dynamic subtest: pipe-A-valid-mode [46.801842] Dynamic subtest pipe-A-valid-mode: SKIP (0.071s) [46.803393] Subtest flip-32bpp-yftile-to-64bpp-yftile-downscaling: SKIP (0.072s) [46.969173] [012/144] (952s left) gen9_exec_parse (allowed-single) [47.073973] Subtest allowed-single: SKIP (0.000s) [47.104473] [013/144] (952s left) gem_sync (basic-many-each) [47.177171] Starting subtest: basic-many-each [49.559553] Subtest basic-many-each: SUCCESS (2.383s) [49.768837] [014/144] (949s left) gem_exec_fence (submit3) [49.998428] Starting subtest: submit3 [50.002275] Subtest submit3: SKIP (0.000s) [50.916130] [015/144] (948s left) kms_plane_scaling (planes-downscale-factor-0-75-unity-scaling) [51.065877] Starting subtest: planes-downscale-factor-0-75-unity-scaling [51.076359] Starting dynamic subtest: pipe-A-DP-4 [51.162419] Dynamic subtest pipe-A-DP-4: SUCCESS (0.086s) [51.182371] Starting dynamic subtest: pipe-B-DP-4 [51.385776] Dynamic subtest pipe-B-DP-4: SUCCESS (0.210s) [51.406620] Starting dynamic subtest: pipe-C-DP-4 [51.610442] Dynamic subtest pipe-C-DP-4: SUCCESS (0.211s) [51.633019] Starting dynamic subtest: pipe-D-DP-4 [51.833621] Dynamic subtest pipe-D-DP-4: SUCCESS (0.210s) [51.834520] Subtest planes-downscale-factor-0-75-unity-scaling: SUCCESS (0.769s) [52.108243] [016/144] (947s left) kms_ccs (pipe-b-ccs-on-another-bo-y-tiled-gen12-mc-ccs) [52.382797] Starting subtest: pipe-B-ccs-on-another-bo-y-tiled-gen12-mc-ccs [52.384477] Subtest pipe-B-ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.000s) [52.580606] [017/144] (947s left) kms_cursor_crc (cursor-sliding-64x21) [52.822277] Starting subtest: cursor-sliding-64x21 [52.827449] Starting dynamic subtest: pipe-A-DP-4 [57.050516] Dynamic subtest pipe-A-DP-4: SUCCESS (4.223s) [57.092813] Starting dynamic subtest: pipe-D-DP-4 [61.213065] Dynamic subtest pipe-D-DP-4: SUCCESS (4.125s) [61.213982] Subtest cursor-sliding-64x21: SUCCESS (8.391s) [61.562245] [018/144] (938s left) perf_pmu (frequency) [61.630186] Starting subtest: frequency [61.631206] Starting dynamic subtest: gt0 [63.879693] Dynamic subtest gt0: FAIL (2.150s) [63.898225] Starting dynamic subtest: idle-gt0 [64.288043] Dynamic subtest idle-gt0: SUCCESS (0.504s) [64.290056] Subtest frequency: FAIL (2.655s) [64.354783] [019/144] (935s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers) [64.471433] Starting subtest: plane-upscale-factor-0-25-with-modifiers [64.482693] Starting dynamic subtest: pipe-A-DP-4 [65.131215] Dynamic subtest pipe-A-DP-4: SUCCESS (0.648s) [65.154750] Starting dynamic subtest: pipe-B-DP-4 [65.971355] Dynamic subtest pipe-B-DP-4: SUCCESS (0.826s) [65.995651] Starting dynamic subtest: pipe-C-DP-4 [66.778635] Dynamic subtest pipe-C-DP-4: SUCCESS (0.793s) [66.802857] Starting dynamic subtest: pipe-D-DP-4 [67.653494] Dynamic subtest pipe-D-DP-4: SUCCESS (0.860s) [67.654808] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (3.182s) [67.964960] [020/144] (931s left) kms_cursor_legacy (flip-vs-cursor-atomic) [68.129861] Starting subtest: flip-vs-cursor-atomic [75.885616] Subtest flip-vs-cursor-atomic: SUCCESS (7.756s) [76.097966] [021/144] (923s left) kms_psr (fbc-pr-sprite-mmap-gtt) [76.255284] Subtest fbc-pr-sprite-mmap-gtt: SKIP (0.000s) [76.272610] [022/144] (923s left) kms_chamelium_hpd (hdmi-hpd-fast) [76.459505] Subtest hdmi-hpd-fast: SKIP (0.000s) [76.569341] [023/144] (923s left) vc4/vc4_wait_bo (used-bo-1ns) [76.657099] Subtest used-bo-1ns: SKIP (0.000s) [76.707843] [024/144] (923s left) syncobj_timeline (invalid-multi-wait-unsubmitted-signaled) [76.769954] Starting subtest: invalid-multi-wait-unsubmitted-signaled [76.770972] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s) [76.839496] [025/144] (923s left) kms_flip (2x-dpms-vs-vblank-race) [76.997422] Starting subtest: 2x-dpms-vs-vblank-race [77.012988] Subtest 2x-dpms-vs-vblank-race: SKIP (0.016s) [77.071613] [026/144] (922s left) kms_frontbuffer_tracking (psr-rgb565-draw-render) [77.207671] Starting subtest: psr-rgb565-draw-render [77.209542] Subtest psr-rgb565-draw-render: SKIP (0.000s) [77.273548] [027/144] (922s left) syncobj_wait (wait-all-for-submit-snapshot) [77.333127] Starting subtest: wait-all-for-submit-snapshot [77.414880] Subtest wait-all-for-submit-snapshot: SUCCESS (0.082s) [77.507251] [028/144] (922s left) gem_reg_read (bad-register) [77.564536] Starting subtest: bad-register [77.565438] Subtest bad-register: SUCCESS (0.000s) [77.624584] [029/144] (922s left) syncobj_timeline (etime-multi-wait-available-unsubmitted) [77.673631] Starting subtest: etime-multi-wait-available-unsubmitted [77.777627] Subtest etime-multi-wait-available-unsubmitted: SUCCESS (0.104s) [77.880981] [030/144] (922s left) kms_pipe_crc_basic (disable-crc-after-crtc) [78.008766] Starting subtest: disable-crc-after-crtc [78.010830] Starting dynamic subtest: pipe-A-DP-4 [78.702591] Dynamic subtest pipe-A-DP-4: SUCCESS (0.692s) [78.713324] Starting dynamic subtest: pipe-B-DP-4 [79.337730] Dynamic subtest pipe-B-DP-4: SUCCESS (0.633s) [79.344075] Starting dynamic subtest: pipe-C-DP-4 [79.937164] Dynamic subtest pipe-C-DP-4: SUCCESS (0.599s) [79.943929] Starting dynamic subtest: pipe-D-DP-4 [80.568906] Dynamic subtest pipe-D-DP-4: SUCCESS (0.630s) [80.569943] Subtest disable-crc-after-crtc: SUCCESS (2.560s) [80.784740] [031/144] (919s left) kms_frontbuffer_tracking (fbc-2p-rte) [80.952689] Starting subtest: fbc-2p-rte [80.954372] Subtest fbc-2p-rte: SKIP (0.000s) [81.023808] [032/144] (919s left) prime_vgem (fence-read-hang) [81.096533] Subtest fence-read-hang: SKIP (0.000s) [81.186469] [033/144] (919s left) kms_frontbuffer_tracking (psr-2p-pri-indfb-multidraw) [81.342881] Starting subtest: psr-2p-pri-indfb-multidraw [81.344449] Subtest psr-2p-pri-indfb-multidraw: SKIP (0.000s) [81.427936] [034/144] (918s left) kms_color (ctm-0-50) [81.566444] Starting subtest: ctm-0-50 [81.567379] Starting dynamic subtest: pipe-A [83.257313] Dynamic subtest pipe-A: SUCCESS (1.690s) [83.266114] Starting dynamic subtest: pipe-B [84.851543] Dynamic subtest pipe-B: SUCCESS (1.593s) [84.861169] Starting dynamic subtest: pipe-C [86.460920] Dynamic subtest pipe-C: SUCCESS (1.609s) [86.468561] Starting dynamic subtest: pipe-D [88.088517] Dynamic subtest pipe-D: SUCCESS (1.627s) [88.089519] Subtest ctm-0-50: SUCCESS (6.522s) [88.322615] [035/144] (911s left) kms_color (legacy-gamma-reset) [88.481490] Starting subtest: legacy-gamma-reset [88.482416] Starting dynamic subtest: pipe-A [88.664118] Dynamic subtest pipe-A: SUCCESS (0.182s) [88.671909] Starting dynamic subtest: pipe-B [88.682175] Dynamic subtest pipe-B: SUCCESS (0.018s) [88.687743] Starting dynamic subtest: pipe-C [88.696069] Dynamic subtest pipe-C: SUCCESS (0.014s) [88.701439] Starting dynamic subtest: pipe-D [88.709754] Dynamic subtest pipe-D: SUCCESS (0.013s) [88.710710] Subtest legacy-gamma-reset: SUCCESS (0.228s) [88.863064] [036/144] (911s left) syncobj_wait (wait-all-delayed-signal) [88.915827] Starting subtest: wait-all-delayed-signal [89.015861] Subtest wait-all-delayed-signal: SUCCESS (0.101s) [89.068021] [037/144] (911s left) kms_flip (2x-nonexisting-fb) [89.181656] Starting subtest: 2x-nonexisting-fb [89.197487] Subtest 2x-nonexisting-fb: SKIP (0.015s) [89.270723] [038/144] (911s left) api_intel_bb (intel-bb-blit-x) [89.329789] Starting subtest: intel-bb-blit-x [90.051289] Subtest intel-bb-blit-x: SUCCESS (0.722s) [90.141755] [039/144] (910s left) kms_atomic (plane-primary-overlay-mutable-zpos) [90.258552] Starting subtest: plane-primary-overlay-mutable-zpos [92.183986] Subtest plane-primary-overlay-mutable-zpos: SKIP (1.924s) [92.488243] [040/144] (907s left) perf_pmu (multi-client) [92.585891] Starting subtest: multi-client [92.587143] Starting dynamic subtest: rcs0 [93.650525] Dynamic subtest rcs0: SUCCESS (1.063s) [93.658564] Starting dynamic subtest: bcs0 [94.701917] Dynamic subtest bcs0: SUCCESS (1.049s) [94.709634] Starting dynamic subtest: vcs0 [95.753639] Dynamic subtest vcs0: SUCCESS (1.049s) [95.761473] Starting dynamic subtest: vcs1 [96.803530] Dynamic subtest vcs1: SUCCESS (1.048s) [96.811492] Starting dynamic subtest: vecs0 [97.855064] Dynamic subtest vecs0: SUCCESS (1.049s) [97.862864] Starting dynamic subtest: vecs1 [98.906352] Dynamic subtest vecs1: SUCCESS (1.049s) [98.913889] Starting dynamic subtest: ccs0 [99.956474] Dynamic subtest ccs0: SUCCESS (1.048s) [99.964557] Starting dynamic subtest: ccs1 [101.007863] Dynamic subtest ccs1: SUCCESS (1.049s) [101.018662] Starting dynamic subtest: ccs2 [102.057938] Dynamic subtest ccs2: SUCCESS (1.048s) [102.065920] Starting dynamic subtest: ccs3 [103.109357] Dynamic subtest ccs3: SUCCESS (1.049s) [103.111431] Subtest multi-client: SUCCESS (10.524s) [103.225795] [041/144] (897s left) syncobj_wait (multi-wait-for-submit-signaled) [103.269646] Starting subtest: multi-wait-for-submit-signaled [103.270609] Subtest multi-wait-for-submit-signaled: SUCCESS (0.000s) [103.336356] [042/144] (897s left) kms_ccs (pipe-a-bad-pixel-format-4-tiled-dg2-rc-ccs-cc) [103.460030] Starting subtest: pipe-A-bad-pixel-format-4-tiled-dg2-rc-ccs-cc [103.460854] Subtest pipe-A-bad-pixel-format-4-tiled-dg2-rc-ccs-cc: SUCCESS (0.000s) [103.780792] [043/144] (896s left) kms_psr (fbc-psr-cursor-mmap-cpu) [103.937471] Subtest fbc-psr-cursor-mmap-cpu: SKIP (0.000s) [103.956633] [044/144] (896s left) i915_pm_rps (engine-order) [104.006487] Starting subtest: engine-order [114.676224] Subtest engine-order: SUCCESS (10.668s) [114.830778] [045/144] (885s left) gem_tiled_partial_pwrite_pread (reads) [114.879171] Subtest reads: SKIP (0.000s) [114.906933] [046/144] (885s left) gem_exec_balancer (busy) [114.958397] Starting subtest: busy [115.458493] Subtest busy: SUCCESS (0.500s) [115.748825] [047/144] (884s left) kms_flip (flip-vs-panning-vs-hang) [115.865441] Starting subtest: flip-vs-panning-vs-hang [115.868201] Starting dynamic subtest: A-DP4 [154.991536] Dynamic subtest A-DP4: SUCCESS (39.123s) [154.997531] Starting dynamic subtest: D-DP4 [204.954545] Dynamic subtest D-DP4: SUCCESS (49.962s) [204.955392] Subtest flip-vs-panning-vs-hang: SUCCESS (89.090s) [205.228145] [048/144] (795s left) gem_pxp (verify-pxp-key-change-after-suspend-resume) [207.824237] Subtest verify-pxp-key-change-after-suspend-resume: SKIP (0.000s) [207.883148] [049/144] (792s left) kms_ccs (pipe-c-random-ccs-data-4-tiled-mtl-mc-ccs) [208.139980] Starting subtest: pipe-C-random-ccs-data-4-tiled-mtl-mc-ccs [208.142403] Subtest pipe-C-random-ccs-data-4-tiled-mtl-mc-ccs: SKIP (0.000s) [208.327411] [050/144] (792s left) gem_exec_schedule (preempt) [208.419065] Starting subtest: preempt [208.420014] Starting dynamic subtest: rcs0 [208.464334] Dynamic subtest rcs0: SUCCESS (0.045s) [208.470280] Starting dynamic subtest: bcs0 [208.490689] Dynamic subtest bcs0: SUCCESS (0.026s) [208.496547] Starting dynamic subtest: vcs0 [208.518869] Dynamic subtest vcs0: SUCCESS (0.028s) [208.524689] Starting dynamic subtest: vcs1 [208.545152] Dynamic subtest vcs1: SUCCESS (0.026s) [208.551088] Starting dynamic subtest: vecs0 [208.572635] Dynamic subtest vecs0: SUCCESS (0.027s) [208.578440] Starting dynamic subtest: vecs1 [208.599505] Dynamic subtest vecs1: SUCCESS (0.026s) [208.605309] Starting dynamic subtest: ccs0 [208.627646] Dynamic subtest ccs0: SUCCESS (0.028s) [208.633427] Starting dynamic subtest: ccs1 [208.655158] Dynamic subtest ccs1: SUCCESS (0.027s) [208.661035] Starting dynamic subtest: ccs2 [208.682954] Dynamic subtest ccs2: SUCCESS (0.027s) [208.688746] Starting dynamic subtest: ccs3 [208.710249] Dynamic subtest ccs3: SUCCESS (0.027s) [208.711093] Subtest preempt: SUCCESS (0.291s) [209.045569] [051/144] (791s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu) [209.165860] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu [209.167448] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [209.231153] [052/144] (791s left) i915_suspend (sysfs-reader) [209.292108] Starting subtest: sysfs-reader [230.795849] Subtest sysfs-reader: SUCCESS (5.535s) [230.816190] [053/144] (785s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size) [230.933422] Starting subtest: cursorB-vs-flipB-varying-size [230.935258] Subtest cursorB-vs-flipB-varying-size: SKIP (0.001s) [230.990126] [054/144] (785s left) gem_render_linear_blits (basic) [231.038443] Starting subtest: basic [231.103217] Subtest basic: SUCCESS (0.065s) [231.165796] [055/144] (785s left) kms_getfb (getfb-handle-protection) [231.218627] Starting subtest: getfb-handle-protection [231.238198] Subtest getfb-handle-protection: SUCCESS (0.020s) [231.361010] [056/144] (785s left) gem_exec_whisper (basic-queues-priority-all) [231.443443] Starting subtest: basic-queues-priority-all [255.352311] Subtest basic-queues-priority-all: SUCCESS (23.907s) [255.527921] [057/144] (761s left) kms_selftest (drm_dp_mst_helper) [255.572424] Starting subtest: drm_dp_mst_helper [255.708346] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_mode [255.728684] Dynamic subtest drm_test_dp_mst_calc_pbn_mode: SUCCESS (0.018s) [255.749994] Starting dynamic subtest: drm_test_dp_mst_sideband_msg_req_decode [255.751015] Dynamic subtest drm_test_dp_mst_sideband_msg_req_decode: SUCCESS (0.018s) [255.752037] Subtest drm_dp_mst_helper: SUCCESS (0.174s) [255.978907] [058/144] (760s left) kms_psr (psr-primary-mmap-gtt) [256.105210] Subtest psr-primary-mmap-gtt: SKIP (0.000s) [256.133698] [059/144] (760s left) kms_ccs (pipe-a-crc-primary-basic-4-tiled-mtl-rc-ccs-cc) [256.338112] Starting subtest: pipe-A-crc-primary-basic-4-tiled-mtl-rc-ccs-cc [256.340007] Subtest pipe-A-crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SKIP (0.000s) [256.580682] [060/144] (760s left) kms_addfb_basic (framebuffer-vs-set-tiling) [256.664985] Starting subtest: framebuffer-vs-set-tiling [256.666608] Subtest framebuffer-vs-set-tiling: SKIP (0.000s) [256.812038] [061/144] (759s left) gem_mmap_wc (write-read) [256.910417] Subtest write-read: SKIP (0.000s) [256.934344] [062/144] (759s left) kms_ccs (pipe-b-missing-ccs-buffer-y-tiled-gen12-rc-ccs) [257.163150] Starting subtest: pipe-B-missing-ccs-buffer-y-tiled-gen12-rc-ccs [257.164900] Subtest pipe-B-missing-ccs-buffer-y-tiled-gen12-rc-ccs: SKIP (0.000s) [257.366537] [063/144] (759s left) perf (global-sseu-config-invalid) [257.731068] Subtest global-sseu-config-invalid: SKIP (0.000s) [257.750439] [064/144] (759s left) kms_big_fb (y-tiled-8bpp-rotate-180) [257.872456] Starting subtest: y-tiled-8bpp-rotate-180 [257.874147] Subtest y-tiled-8bpp-rotate-180: SKIP (0.000s) [257.938423] [065/144] (758s left) kms_rotation_crc (primary-rotation-90) [258.059965] Starting subtest: primary-rotation-90 [258.061862] Subtest primary-rotation-90: SKIP (0.001s) [258.120643] [066/144] (758s left) gem_softpin (zero) [258.169689] Starting subtest: zero [258.173937] Subtest zero: SUCCESS (0.004s) [258.296065] [067/144] (758s left) v3d/v3d_perfmon (get-values-valid-perfmon) [258.404791] Subtest get-values-valid-perfmon: SKIP (0.000s) [258.463810] [068/144] (758s left) kms_vrr (flipline) [258.612162] Starting subtest: flipline [258.613858] Subtest flipline: SKIP (0.000s) [258.670563] [069/144] (758s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled) [258.718747] Starting subtest: multi-wait-for-submit-unsubmitted-signaled [258.719630] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.001s) [258.790857] [070/144] (758s left) kms_flip (2x-flip-vs-fences-interruptible) [258.924825] Starting subtest: 2x-flip-vs-fences-interruptible [258.926557] Subtest 2x-flip-vs-fences-interruptible: SKIP (0.000s) [258.989155] [071/144] (758s left) gem_exec_capture (capture-recoverable) [259.050182] Starting subtest: capture-recoverable [259.053318] Subtest capture-recoverable: SUCCESS (0.003s) [259.163322] [072/144] (757s left) kms_cursor_edge_walk (128x128-top-edge) [259.289370] Starting subtest: 128x128-top-edge [259.290297] Starting dynamic subtest: pipe-A-DP-4 [262.735769] Dynamic subtest pipe-A-DP-4: SUCCESS (3.445s) [262.745060] Starting dynamic subtest: pipe-D-DP-4 [266.047732] Dynamic subtest pipe-D-DP-4: SUCCESS (3.310s) [266.048738] Subtest 128x128-top-edge: SUCCESS (6.759s) [266.290866] [073/144] (750s left) syncobj_wait (invalid-signal-one-illegal-handle) [266.374730] Starting subtest: invalid-signal-one-illegal-handle [266.375642] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s) [266.436097] [074/144] (750s left) kms_ccs (pipe-c-ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc) [266.675491] Starting subtest: pipe-C-ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc [266.677063] Subtest pipe-C-ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SKIP (0.000s) [266.864774] [075/144] (750s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt) [267.021019] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt [267.022636] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s) [267.099666] [076/144] (750s left) syncobj_eventfd (timeline-wait-signaled) [267.158691] Starting subtest: timeline-wait-signaled [267.159649] Subtest timeline-wait-signaled: SUCCESS (0.000s) [267.233209] [077/144] (749s left) kms_big_fb (yf-tiled-addfb-size-overflow) [267.384982] Starting subtest: yf-tiled-addfb-size-overflow [267.386733] Subtest yf-tiled-addfb-size-overflow: SKIP (0.000s) [267.464243] [078/144] (749s left) gem_ctx_param (root-set) [267.519990] Starting subtest: root-set [267.521059] Subtest root-set: SUCCESS (0.000s) [267.577948] [079/144] (749s left) gem_ctx_isolation (preservation) [267.635983] Starting subtest: preservation [267.636932] Starting dynamic subtest: rcs0 [267.964290] Dynamic subtest rcs0: SUCCESS (0.326s) [267.971773] Starting dynamic subtest: bcs0 [268.364278] Dynamic subtest bcs0: SUCCESS (0.400s) [268.371838] Starting dynamic subtest: vcs0 [268.762815] Dynamic subtest vcs0: SUCCESS (0.398s) [268.770323] Starting dynamic subtest: vcs1 [269.164271] Dynamic subtest vcs1: SUCCESS (0.394s) [269.176931] Starting dynamic subtest: vecs0 [269.557023] Dynamic subtest vecs0: SUCCESS (0.392s) [269.566008] Starting dynamic subtest: vecs1 [269.952756] Dynamic subtest vecs1: SUCCESS (0.396s) [269.960336] Starting dynamic subtest: ccs0 [270.332287] Dynamic subtest ccs0: SUCCESS (0.378s) [270.339889] Starting dynamic subtest: ccs1 [270.712931] Dynamic subtest ccs1: SUCCESS (0.386s) [270.720375] Starting dynamic subtest: ccs2 [271.086148] Dynamic subtest ccs2: SUCCESS (0.373s) [271.093657] Starting dynamic subtest: ccs3 [271.480375] Dynamic subtest ccs3: SUCCESS (0.394s) [271.481214] Subtest preservation: SUCCESS (3.844s) [271.712351] [080/144] (745s left) kms_ccs (pipe-d-bad-rotation-90-4-tiled-mtl-rc-ccs-cc) [271.961914] Starting subtest: pipe-D-bad-rotation-90-4-tiled-mtl-rc-ccs-cc [271.963834] Subtest pipe-D-bad-rotation-90-4-tiled-mtl-rc-ccs-cc: SKIP (0.000s) [272.138870] [081/144] (745s left) gem_exec_params (rsvd2-dirt) [272.223452] Starting subtest: rsvd2-dirt [272.225552] Subtest rsvd2-dirt: SKIP (0.000s) [272.309558] [082/144] (744s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-downscaling) [272.454593] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-downscaling [272.455558] Starting dynamic subtest: pipe-A-valid-mode [274.633296] Dynamic subtest pipe-A-valid-mode: SUCCESS (2.174s) [274.634793] Subtest flip-64bpp-xtile-to-16bpp-xtile-downscaling: SUCCESS (2.177s) [274.859057] [083/144] (742s left) syncobj_basic (bad-fd-to-handle) [274.921931] Starting subtest: bad-fd-to-handle [274.922757] Subtest bad-fd-to-handle: SUCCESS (0.000s) [274.982703] [084/144] (742s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-cpu) [275.101039] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-cpu [275.102608] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [275.186813] [085/144] (742s left) gem_exec_schedule (reorder-wide) [275.394370] Subtest reorder-wide: SKIP (0.000s) [275.588619] [086/144] (741s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt) [275.711077] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt [275.712807] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [275.786130] [087/144] (741s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt) [275.923173] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt [275.924872] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SKIP (0.000s) [276.006119] [088/144] (741s left) vc4/vc4_perfmon (create-single-perfmon) [276.099801] Subtest create-single-perfmon: SKIP (0.000s) [276.152024] [089/144] (741s left) drm_fdinfo (busy) [276.209328] Starting subtest: busy [276.210196] Starting dynamic subtest: rcs0 [276.211915] Dynamic subtest rcs0: SKIP (0.000s) [276.219458] Starting dynamic subtest: bcs0 [276.221026] Dynamic subtest bcs0: SKIP (0.000s) [276.228663] Starting dynamic subtest: vcs0 [276.230907] Dynamic subtest vcs0: SKIP (0.000s) [276.238168] Starting dynamic subtest: vcs1 [276.239716] Dynamic subtest vcs1: SKIP (0.000s) [276.247243] Starting dynamic subtest: vecs0 [276.249564] Dynamic subtest vecs0: SKIP (0.000s) [276.256788] Starting dynamic subtest: vecs1 [276.259293] Dynamic subtest vecs1: SKIP (0.000s) [276.269483] Starting dynamic subtest: ccs0 [276.271225] Dynamic subtest ccs0: SKIP (0.000s) [276.278721] Starting dynamic subtest: ccs1 [276.281282] Dynamic subtest ccs1: SKIP (0.000s) [276.288440] Starting dynamic subtest: ccs2 [276.289980] Dynamic subtest ccs2: SKIP (0.000s) [276.297289] Starting dynamic subtest: ccs3 [276.299602] Dynamic subtest ccs3: SKIP (0.000s) [276.302196] Subtest busy: SKIP (0.007s) [276.314828] [090/144] (741s left) kms_big_fb (x-tiled-8bpp-rotate-0) [276.436204] Starting subtest: x-tiled-8bpp-rotate-0 [277.833182] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.397s) [277.973055] [091/144] (739s left) kms_cursor_crc (cursor-onscreen-max-size) [278.284693] Starting subtest: cursor-onscreen-max-size [278.287359] Subtest cursor-onscreen-max-size: SKIP (0.000s) [278.424945] [092/144] (739s left) kms_ccs (pipe-a-crc-primary-basic-4-tiled-dg2-mc-ccs) [278.661299] Starting subtest: pipe-A-crc-primary-basic-4-tiled-dg2-mc-ccs [283.079204] Subtest pipe-A-crc-primary-basic-4-tiled-dg2-mc-ccs: SUCCESS (4.418s) [283.442868] [093/144] (734s left) kms_pm_rpm (modeset-lpsp-stress) [285.489222] Starting subtest: modeset-lpsp-stress [285.855860] Subtest modeset-lpsp-stress: SKIP (0.365s) [286.166219] [094/144] (731s left) gem_exec_store (cachelines) [286.248217] Starting subtest: cachelines [286.249231] Starting dynamic subtest: rcs0 [286.337566] Dynamic subtest rcs0: SUCCESS (0.089s) [286.347618] Starting dynamic subtest: bcs0 [286.393482] Dynamic subtest bcs0: SUCCESS (0.054s) [286.430116] Starting dynamic subtest: vcs0 [286.462909] Dynamic subtest vcs0: SUCCESS (0.067s) [286.493991] Starting dynamic subtest: vcs1 [286.528709] Dynamic subtest vcs1: SUCCESS (0.064s) [286.556125] Starting dynamic subtest: vecs0 [286.582563] Dynamic subtest vecs0: SUCCESS (0.052s) [286.608127] Starting dynamic subtest: vecs1 [286.635780] Dynamic subtest vecs1: SUCCESS (0.052s) [286.659799] Starting dynamic subtest: ccs0 [286.692854] Dynamic subtest ccs0: SUCCESS (0.055s) [286.727260] Starting dynamic subtest: ccs1 [286.760487] Dynamic subtest ccs1: SUCCESS (0.066s) [286.780458] Starting dynamic subtest: ccs2 [286.819482] Dynamic subtest ccs2: SUCCESS (0.057s) [286.856291] Starting dynamic subtest: ccs3 [286.880181] Dynamic subtest ccs3: SUCCESS (0.058s) [286.881235] Subtest cachelines: SUCCESS (0.632s) [287.089069] [095/144] (730s left) gem_ctx_persistence (legacy-engines-hang) [287.147277] Starting subtest: legacy-engines-hang [287.148291] Starting dynamic subtest: render [287.211403] Dynamic subtest render: SUCCESS (0.064s) [287.221686] Starting dynamic subtest: bsd1 [287.288631] Dynamic subtest bsd1: SUCCESS (0.076s) [287.300295] Starting dynamic subtest: bsd2 [287.356676] Dynamic subtest bsd2: SUCCESS (0.066s) [287.391519] Starting dynamic subtest: blt [287.400515] Dynamic subtest blt: SUCCESS (0.042s) [287.419661] Starting dynamic subtest: vebox [287.483687] Dynamic subtest vebox: SUCCESS (0.081s) [287.485203] Subtest legacy-engines-hang: SUCCESS (0.337s) [287.626705] [096/144] (729s left) gem_flink_race (flink_close) [287.698457] Starting subtest: flink_close [292.724576] Subtest flink_close: SUCCESS (5.026s) [292.788121] [097/144] (724s left) gem_workarounds (basic-read-fd) [292.903597] Starting subtest: basic-read-fd [292.943467] Subtest basic-read-fd: SUCCESS (0.040s) [293.047964] [098/144] (724s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-fullscreen) [293.208571] Starting subtest: psr-2p-primscrn-spr-indfb-fullscreen [293.210354] Subtest psr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s) [293.292261] [099/144] (724s left) fbdev (read) [293.368606] Starting subtest: read [295.994502] Subtest read: SUCCESS (2.626s) [296.051629] [100/144] (721s left) gem_partial_pwrite_pread (writes-after-reads-display) [296.099714] Subtest writes-after-reads-display: SKIP (0.000s) [296.144891] [101/144] (721s left) kms_ccs (pipe-d-bad-aux-stride-y-tiled-ccs) [296.418819] Starting subtest: pipe-D-bad-aux-stride-y-tiled-ccs [296.420418] Subtest pipe-D-bad-aux-stride-y-tiled-ccs: SKIP (0.000s) [296.586576] [102/144] (721s left) gem_mmap_offset (bad-extensions) [296.647337] Starting subtest: bad-extensions [296.648311] Subtest bad-extensions: SUCCESS (0.000s) [296.728989] [103/144] (721s left) gem_readwrite (write-bad-handle) [296.782950] Subtest write-bad-handle: SKIP (0.000s) [296.828249] [104/144] (720s left) syncobj_wait (single-wait-for-submit-submitted) [296.881520] Starting subtest: single-wait-for-submit-submitted [296.988221] Subtest single-wait-for-submit-submitted: SUCCESS (0.107s) [297.054183] [105/144] (720s left) gem_close (many-handles-one-vma) [297.103961] Starting subtest: many-handles-one-vma [297.130364] Subtest many-handles-one-vma: SUCCESS (0.026s) [297.202212] [106/144] (720s left) kms_ccs (pipe-b-crc-primary-rotation-180-y-tiled-gen12-mc-ccs) [297.439415] Starting subtest: pipe-B-crc-primary-rotation-180-y-tiled-gen12-mc-ccs [297.441094] Subtest pipe-B-crc-primary-rotation-180-y-tiled-gen12-mc-ccs: SKIP (0.000s) [297.652663] [107/144] (720s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0) [297.812096] Starting subtest: linear-max-hw-stride-32bpp-rotate-0 [299.033743] Subtest linear-max-hw-stride-32bpp-rotate-0: SUCCESS (1.221s) [299.205770] [108/144] (718s left) gem_lmem_swapping (parallel-random-verify-ccs) [301.755662] Starting subtest: parallel-random-verify-ccs [301.756716] Starting dynamic subtest: lmem0 [366.271655] Dynamic subtest lmem0: SUCCESS (64.515s) [366.272663] Subtest parallel-random-verify-ccs: SUCCESS (64.516s) [367.594417] [109/144] (650s left) syncobj_basic (create-signaled) [368.986659] Starting subtest: create-signaled [368.987682] Subtest create-signaled: SUCCESS (0.000s) [369.017851] [110/144] (648s left) kms_ccs (pipe-b-bad-aux-stride-y-tiled-gen12-rc-ccs) [369.260392] Starting subtest: pipe-B-bad-aux-stride-y-tiled-gen12-rc-ccs [369.262193] Subtest pipe-B-bad-aux-stride-y-tiled-gen12-rc-ccs: SKIP (0.000s) [369.465949] [111/144] (648s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-plflip-blt) [369.622924] Starting subtest: fbcpsr-2p-primscrn-indfb-plflip-blt [369.624725] Subtest fbcpsr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s) [369.697519] [112/144] (648s left) sysfs_preempt_timeout (invalid) [369.752471] Starting subtest: invalid [369.753324] Starting dynamic subtest: ccs2 [369.754170] Dynamic subtest ccs2: SUCCESS (0.000s) [369.761167] Starting dynamic subtest: vecs0 [369.762223] Dynamic subtest vecs0: SUCCESS (0.000s) [369.769153] Starting dynamic subtest: ccs0 [369.770304] Dynamic subtest ccs0: SUCCESS (0.000s) [369.776945] Starting dynamic subtest: vcs0 [369.777863] Dynamic subtest vcs0: SUCCESS (0.000s) [369.784574] Starting dynamic subtest: bcs0 [369.785400] Dynamic subtest bcs0: SUCCESS (0.000s) [369.792166] Starting dynamic subtest: ccs3 [369.793295] Dynamic subtest ccs3: SUCCESS (0.000s) [369.800340] Starting dynamic subtest: vecs1 [369.801766] Dynamic subtest vecs1: SUCCESS (0.000s) [369.808835] Starting dynamic subtest: ccs1 [369.810108] Dynamic subtest ccs1: SUCCESS (0.000s) [369.819763] Starting dynamic subtest: rcs0 [369.820601] Dynamic subtest rcs0: SUCCESS (0.000s) [369.827034] Starting dynamic subtest: vcs1 [369.827867] Dynamic subtest vcs1: SUCCESS (0.000s) [369.829037] Subtest invalid: SUCCESS (0.008s) [369.840464] [113/144] (648s left) kms_frontbuffer_tracking (fbcpsr-1p-indfb-fliptrack-mmap-gtt) [369.960501] Starting subtest: fbcpsr-1p-indfb-fliptrack-mmap-gtt [369.962291] Subtest fbcpsr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s) [370.053174] [114/144] (647s left) i915_pciid [370.180007] [115/144] (647s left) gem_userptr_blits (vma-merge) [370.269673] Starting subtest: vma-merge [390.364855] Subtest vma-merge: FAIL (20.080s) [390.447854] [116/144] (627s left) gem_render_copy (y-tiled-mc-ccs-to-yf-tiled-ccs) [390.501963] Starting subtest: y-tiled-mc-ccs-to-yf-tiled-ccs [390.503885] Subtest y-tiled-mc-ccs-to-yf-tiled-ccs: SKIP (0.000s) [390.679982] [117/144] (627s left) kms_ccs (pipe-d-crc-primary-rotation-180-y-tiled-ccs) [390.963912] Starting subtest: pipe-D-crc-primary-rotation-180-y-tiled-ccs [390.965785] Subtest pipe-D-crc-primary-rotation-180-y-tiled-ccs: SKIP (0.000s) [391.130553] [118/144] (626s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip) [391.288117] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip [391.289959] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s) [391.355711] [119/144] (626s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-mmap-wc) [391.493232] Starting subtest: fbc-1p-offscren-pri-indfb-draw-mmap-wc [391.495015] Subtest fbc-1p-offscren-pri-indfb-draw-mmap-wc: SKIP (0.000s) [391.573478] [120/144] (626s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-wc) [391.714499] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-wc [391.716249] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s) [391.789784] [121/144] (626s left) api_intel_bb (blit-noreloc-keep-cache) [391.849734] Starting subtest: blit-noreloc-keep-cache [391.875007] Subtest blit-noreloc-keep-cache: SUCCESS (0.026s) [391.984663] [122/144] (626s left) drm_fdinfo (virtual-busy-idle) [392.068538] Starting subtest: virtual-busy-idle [392.070612] Subtest virtual-busy-idle: SKIP (0.000s) [392.139422] [123/144] (626s left) kms_psr (psr2-cursor-plane-onoff) [392.264963] Subtest psr2-cursor-plane-onoff: SKIP (0.000s) [392.296059] [124/144] (625s left) vgem_basic (bad-handle) [392.353480] Starting subtest: bad-handle [392.354473] Subtest bad-handle: SUCCESS (0.000s) [392.414013] [125/144] (625s left) kms_sequence (get-forked-busy) [392.560674] Starting subtest: get-forked-busy [392.561649] Starting dynamic subtest: pipe-A-DP-4 [395.160185] Dynamic subtest pipe-A-DP-4: SUCCESS (2.597s) [395.178163] Starting dynamic subtest: pipe-B-DP-4 [397.735520] Dynamic subtest pipe-B-DP-4: SUCCESS (2.574s) [397.750124] Starting dynamic subtest: pipe-C-DP-4 [400.296407] Dynamic subtest pipe-C-DP-4: SUCCESS (2.558s) [400.327741] Starting dynamic subtest: pipe-D-DP-4 [402.891326] Dynamic subtest pipe-D-DP-4: SUCCESS (2.590s) [402.892596] Subtest get-forked-busy: SUCCESS (10.332s) [403.100487] [126/144] (615s left) i915_suspend (basic-s3-without-i915) [403.181097] Starting subtest: basic-s3-without-i915 [425.428690] Subtest basic-s3-without-i915: SUCCESS (5.519s) [425.482768] [127/144] (609s left) gem_wait (invalid-flags) [425.539338] Starting subtest: invalid-flags [425.540148] Subtest invalid-flags: SUCCESS (0.000s) [425.787472] [128/144] (609s left) v3d/v3d_submit_csd (bad-multisync-extension) [425.894730] Subtest bad-multisync-extension: SKIP (0.000s) [425.945480] [129/144] (609s left) kms_pipe_crc_basic (bad-source) [426.070347] Starting subtest: bad-source [426.071552] Subtest bad-source: SUCCESS (0.000s) [426.127105] [130/144] (608s left) kms_dsc (dsc-with-bpc) [426.248649] Subtest dsc-with-bpc: SKIP (0.000s) [426.269311] [131/144] (608s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip) [426.394767] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip [427.578634] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (1.183s) [427.718457] [132/144] (607s left) gem_mmap_offset (ptrace) [427.763534] Starting subtest: ptrace [427.765219] Starting dynamic subtest: lmem0-fixed [427.867671] Dynamic subtest lmem0-fixed: SUCCESS (0.102s) [427.876971] Starting dynamic subtest: smem0-fixed [427.888388] Dynamic subtest smem0-fixed: SUCCESS (0.018s) [427.889306] Subtest ptrace: SUCCESS (0.126s) [427.994173] [133/144] (607s left) kms_ccs (pipe-d-random-ccs-data-4-tiled-mtl-rc-ccs) [428.273468] Starting subtest: pipe-D-random-ccs-data-4-tiled-mtl-rc-ccs [428.276208] Subtest pipe-D-random-ccs-data-4-tiled-mtl-rc-ccs: SKIP (0.000s) [428.475201] [134/144] (606s left) kms_ccs (pipe-a-missing-ccs-buffer-y-tiled-ccs) [428.676570] Starting subtest: pipe-A-missing-ccs-buffer-y-tiled-ccs [428.678214] Subtest pipe-A-missing-ccs-buffer-y-tiled-ccs: SKIP (0.000s) [428.896209] [135/144] (606s left) gem_create (hog-create) [428.986171] Starting subtest: hog-create [428.987126] Starting dynamic subtest: lmem0 [461.258522] Dynamic subtest lmem0: SUCCESS (32.272s) [461.266854] Starting dynamic subtest: smem0 [493.520442] Dynamic subtest smem0: SUCCESS (32.261s) [493.521248] Subtest hog-create: SUCCESS (64.535s) [493.757922] [136/144] (541s left) kms_prop_blob (blob-multiple) [493.812868] Starting subtest: blob-multiple [493.813843] Subtest blob-multiple: SUCCESS (0.001s) [493.868544] [137/144] (541s left) gem_exec_parallel (userptr) [493.922537] Starting subtest: userptr [493.923588] Starting dynamic subtest: rcs0 [495.388907] Dynamic subtest rcs0: SUCCESS (1.466s) [495.398435] Starting dynamic subtest: bcs0 [496.793824] Dynamic subtest bcs0: SUCCESS (1.404s) [496.802575] Starting dynamic subtest: vcs0 [498.193948] Dynamic subtest vcs0: SUCCESS (1.399s) [498.202960] Starting dynamic subtest: vcs1 [499.604995] Dynamic subtest vcs1: SUCCESS (1.410s) [499.614021] Starting dynamic subtest: vecs0 [501.015961] Dynamic subtest vecs0: SUCCESS (1.410s) [501.024767] Starting dynamic subtest: vecs1 [502.416996] Dynamic subtest vecs1: SUCCESS (1.400s) [502.426073] Starting dynamic subtest: ccs0 [503.839780] Dynamic subtest ccs0: SUCCESS (1.422s) [503.848008] Starting dynamic subtest: ccs1 [505.229789] Dynamic subtest ccs1: SUCCESS (1.389s) [505.237862] Starting dynamic subtest: ccs2 [506.649850] Dynamic subtest ccs2: SUCCESS (1.419s) [506.658793] Starting dynamic subtest: ccs3 [508.051932] Dynamic subtest ccs3: SUCCESS (1.401s) [508.053214] Subtest userptr: SUCCESS (14.130s) [508.299117] [138/144] (527s left) kms_ccs (pipe-c-ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc) [508.598909] Starting subtest: pipe-C-ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc [508.600598] Subtest pipe-C-ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s) [508.811809] [139/144] (526s left) gem_exec_reloc (basic-gtt-cpu-active) [508.866816] Subtest basic-gtt-cpu-active: SKIP (0.000s) [508.910253] [140/144] (526s left) kms_frontbuffer_tracking (fbcpsr-stridechange) [509.031884] Starting subtest: fbcpsr-stridechange [509.033503] Subtest fbcpsr-stridechange: SKIP (0.000s) [509.122576] [141/144] (526s left) kms_lease (master-vs-lease) [509.251701] Starting subtest: master-vs-lease [509.253988] Subtest master-vs-lease: SUCCESS (0.001s) [509.318252] [142/144] (526s left) prime_vgem (basic-fence-flip) [509.373797] Starting subtest: basic-fence-flip [509.454016] Subtest basic-fence-flip: SKIP (0.080s) [509.541005] [143/144] (525s left) sysfs_heartbeat_interval (idempotent) [509.609707] Starting subtest: idempotent [509.610617] Starting dynamic subtest: ccs2 [509.612736] Dynamic subtest ccs2: SUCCESS (0.001s) [509.621029] Starting dynamic subtest: vecs0 [509.621981] Dynamic subtest vecs0: SUCCESS (0.001s) [509.630910] Starting dynamic subtest: ccs0 [509.631946] Dynamic subtest ccs0: SUCCESS (0.001s) [509.639679] Starting dynamic subtest: vcs0 [509.640913] Dynamic subtest vcs0: SUCCESS (0.001s) [509.648651] Starting dynamic subtest: bcs0 [509.649435] Dynamic subtest bcs0: SUCCESS (0.001s) [509.657210] Starting dynamic subtest: ccs3 [509.658394] Dynamic subtest ccs3: SUCCESS (0.001s) [509.666061] Starting dynamic subtest: vecs1 [509.667274] Dynamic subtest vecs1: SUCCESS (0.001s) [509.674823] Starting dynamic subtest: ccs1 [509.676071] Dynamic subtest ccs1: SUCCESS (0.001s) [509.683706] Starting dynamic subtest: rcs0 [509.684513] Dynamic subtest rcs0: SUCCESS (0.001s) [509.691992] Starting dynamic subtest: vcs1 [509.693437] Dynamic subtest vcs1: SUCCESS (0.001s) [509.694816] Subtest idempotent: SUCCESS (0.016s) [509.718058] [144/144] (525s left) kms_flip (plain-flip-fb-recreate-interruptible) [509.834997] Starting subtest: plain-flip-fb-recreate-interruptible [509.841331] Starting dynamic subtest: A-DP4 [517.936840] Dynamic subtest A-DP4: SUCCESS (8.094s) [517.969794] Starting dynamic subtest: B-DP4 [525.988408] Dynamic subtest B-DP4: SUCCESS (8.047s) [526.022944] Starting dynamic subtest: C-DP4 [534.037530] Dynamic subtest C-DP4: SUCCESS (8.045s) [534.066190] Starting dynamic subtest: D-DP4 [542.068732] Dynamic subtest D-DP4: SUCCESS (8.028s) [542.072603] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (32.235s) [542.365861] Closing watchdogs Done.