[57.077649] Initializing watchdogs [57.077696] /dev/watchdog0 [57.090909] [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.094690] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [57.096867] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [57.100346] [001/130] (960s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area-big-fb) [62.249545] Starting subtest: pr-primary-plane-update-sf-dmg-area-big-fb [62.254226] Subtest pr-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.002s) [63.102379] [002/130] (954s left) gem_exec_schedule (implicit-boths) [63.174059] Starting subtest: implicit-boths [63.175993] Starting dynamic subtest: rcs0 [63.280783] Dynamic subtest rcs0: SUCCESS (0.114s) [63.290429] Starting dynamic subtest: vcs0 [63.360378] Dynamic subtest vcs0: SUCCESS (0.078s) [63.370051] Starting dynamic subtest: vcs1 [63.435403] Dynamic subtest vcs1: SUCCESS (0.075s) [63.446360] Starting dynamic subtest: vecs0 [63.506255] Dynamic subtest vecs0: SUCCESS (0.071s) [63.515087] Starting dynamic subtest: ccs0 [63.580844] Dynamic subtest ccs0: SUCCESS (0.074s) [63.583172] Subtest implicit-boths: SUCCESS (0.412s) [65.045171] [003/130] (952s left) sysfs_timeslice_duration (timeout) [65.115370] Starting subtest: timeout [65.117710] Starting dynamic subtest: vecs0 [74.943119] Dynamic subtest vecs0: SUCCESS (9.808s) [74.950978] Starting dynamic subtest: ccs0 [76.293454] Dynamic subtest ccs0: SUCCESS (1.350s) [76.305634] Starting dynamic subtest: vcs0 [85.935787] Dynamic subtest vcs0: SUCCESS (9.636s) [85.944420] Starting dynamic subtest: bcs0 [86.543443] Dynamic subtest bcs0: SUCCESS (0.609s) [86.551833] Starting dynamic subtest: rcs0 [87.819085] Dynamic subtest rcs0: SUCCESS (1.274s) [87.825350] Starting dynamic subtest: vcs1 [96.973192] Dynamic subtest vcs1: SUCCESS (9.149s) [96.975697] Subtest timeout: SUCCESS (31.830s) [97.035310] [004/130] (920s left) vgem_basic (dmabuf-export) [97.110385] Starting subtest: dmabuf-export [97.134487] Subtest dmabuf-export: SUCCESS (0.026s) [97.226776] [FACT vgem_basic (dmabuf-export)] new: kernel.kmod_is_loaded.vgem: true [97.229923] [005/130] (920s left) kms_rotation_crc (cursor-rotation-180) [97.330842] Starting subtest: cursor-rotation-180 [98.769581] Subtest cursor-rotation-180: SUCCESS (1.437s) [99.975503] [006/130] (917s left) perf_pmu (busy-double-start) [100.016762] Starting subtest: busy-double-start [100.018922] Starting dynamic subtest: rcs0 [103.645057] Dynamic subtest rcs0: FAIL (3.573s) [103.653084] Starting dynamic subtest: bcs0 [107.160912] Dynamic subtest bcs0: SUCCESS (3.572s) [107.170258] Starting dynamic subtest: vcs0 [111.475543] Dynamic subtest vcs0: SUCCESS (4.314s) [111.482543] Starting dynamic subtest: vcs1 [115.443610] Dynamic subtest vcs1: SUCCESS (3.966s) [115.453372] Starting dynamic subtest: vecs0 [119.480701] Dynamic subtest vecs0: SUCCESS (4.036s) [119.490623] Starting dynamic subtest: ccs0 [123.117638] Dynamic subtest ccs0: FAIL (3.568s) [123.119590] Subtest busy-double-start: FAIL (23.031s) [123.177399] [007/130] (894s left) drm_fdinfo (memory-info-active) [123.216043] Starting subtest: memory-info-active [123.218408] Starting dynamic subtest: smem0 [126.308563] Dynamic subtest smem0: SUCCESS (3.093s) [126.311220] Subtest memory-info-active: SUCCESS (3.094s) [126.373319] [008/130] (891s left) gem_eio (execbuf) [126.600550] Starting subtest: execbuf [126.906787] Subtest execbuf: SUCCESS (0.305s) [127.019211] [009/130] (890s left) kms_vrr (lobf) [127.111118] Starting subtest: lobf [127.116329] Subtest lobf: SKIP (0.000s) [127.180325] [010/130] (890s left) gem_userptr_blits (unsync-unmap-cycles) [127.251367] Subtest unsync-unmap-cycles: SKIP (0.000s) [127.392607] [011/130] (890s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-pgflip-blt) [127.495201] Starting subtest: psr-2p-scndscrn-indfb-pgflip-blt [127.498619] Subtest psr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s) [127.601283] [012/130] (890s left) gem_exec_flush (basic-wb-pro-default) [127.657333] Starting subtest: basic-wb-pro-default [133.136197] Subtest basic-wb-pro-default: SUCCESS (5.480s) [133.314888] [013/130] (884s left) gem_eio (banned) [133.533870] Starting subtest: banned [134.187494] Subtest banned: SUCCESS (0.654s) [134.373211] [014/130] (883s left) syncobj_timeline (multi-wait-available-submitted) [134.434257] Starting subtest: multi-wait-available-submitted [134.436266] Subtest multi-wait-available-submitted: SUCCESS (0.001s) [134.545234] [015/130] (883s left) kms_pm_lpsp (screens-disabled) [135.656433] Starting subtest: screens-disabled [135.660727] Subtest screens-disabled: SKIP (0.000s) [135.724074] [016/130] (882s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted) [135.785041] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted [135.787360] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s) [135.882732] [017/130] (882s left) kms_pipe_crc_basic (bad-source) [135.983146] Starting subtest: bad-source [135.985477] Subtest bad-source: SUCCESS (0.000s) [136.048910] [018/130] (882s left) kms_psr (fbc-psr-cursor-mmap-gtt) [136.145063] Starting subtest: fbc-psr-cursor-mmap-gtt [136.147362] Starting dynamic subtest: eDP-1 [136.152129] Dynamic subtest eDP-1: SKIP (0.000s) [136.156597] Subtest fbc-psr-cursor-mmap-gtt: SKIP (0.000s) [136.210678] [019/130] (882s left) sw_sync (sync_busy) [136.245894] Starting subtest: sync_busy [136.248322] Subtest sync_busy: SUCCESS (0.002s) [136.300804] [020/130] (881s left) kms_cursor_crc (cursor-offscreen-64x21) [136.471752] Starting subtest: cursor-offscreen-64x21 [136.510580] Subtest cursor-offscreen-64x21: SKIP (0.034s) [136.749801] [021/130] (881s left) gem_userptr_blits (map-fixed-invalidate) [136.815551] Starting subtest: map-fixed-invalidate [136.819126] Subtest map-fixed-invalidate: SKIP (0.000s) [136.941921] [022/130] (881s left) kms_dsc (dsc-basic) [137.025726] Starting subtest: dsc-basic [137.036816] Subtest dsc-basic: SKIP (0.000s) [137.092343] [023/130] (881s left) gem_exec_flush (basic-batch-kernel-default-cmd) [137.148811] Starting subtest: basic-batch-kernel-default-cmd [137.152862] Subtest basic-batch-kernel-default-cmd: SKIP (0.000s) [137.324093] [024/130] (881s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-blt) [137.416480] Starting subtest: fbcpsr-rgb101010-draw-blt [142.088023] Subtest fbcpsr-rgb101010-draw-blt: SUCCESS (4.675s) [142.218702] [025/130] (876s left) kms_psr (fbc-psr-primary-page-flip) [142.321187] Starting subtest: fbc-psr-primary-page-flip [142.323597] Starting dynamic subtest: eDP-1 [142.328112] Dynamic subtest eDP-1: SKIP (0.000s) [142.332620] Subtest fbc-psr-primary-page-flip: SKIP (0.000s) [142.389170] [026/130] (876s left) gem_mmap_gtt (medium-copy-xy) [142.448080] Subtest medium-copy-XY: SKIP (0.000s) [142.500219] [027/130] (876s left) gem_lmem_evict (dontneed-evict-race) [142.546975] Subtest dontneed-evict-race: SKIP (0.000s) [142.581856] [028/130] (875s left) gem_mmap_gtt (basic-read-write-distinct) [142.634699] Subtest basic-read-write-distinct: SKIP (0.000s) [142.696552] [029/130] (875s left) kms_cursor_legacy (cursorb-vs-flipb-atomic) [142.821974] Starting subtest: cursorB-vs-flipB-atomic [142.826170] Subtest cursorB-vs-flipB-atomic: SKIP (0.001s) [142.916190] [030/130] (875s left) perf_pmu (most-busy-idle-check-all) [142.954748] Starting subtest: most-busy-idle-check-all [142.956872] Starting dynamic subtest: rcs0 [144.945876] Dynamic subtest rcs0: SUCCESS (1.989s) [144.955809] Starting dynamic subtest: bcs0 [146.929031] Dynamic subtest bcs0: SUCCESS (1.982s) [146.939846] Starting dynamic subtest: vcs0 [148.912708] Dynamic subtest vcs0: SUCCESS (1.983s) [148.924043] Starting dynamic subtest: vcs1 [150.895473] Dynamic subtest vcs1: SUCCESS (1.983s) [150.905089] Starting dynamic subtest: vecs0 [152.943851] Dynamic subtest vecs0: SUCCESS (2.047s) [152.954548] Starting dynamic subtest: ccs0 [154.929090] Dynamic subtest ccs0: SUCCESS (1.984s) [154.932263] Subtest most-busy-idle-check-all: SUCCESS (11.972s) [155.030583] [031/130] (863s left) kms_panel_fitting (atomic-fastset) [155.140893] Starting subtest: atomic-fastset [155.143318] Starting dynamic subtest: pipe-A-eDP-1 [155.289752] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.147s) [155.298238] Starting dynamic subtest: pipe-B-eDP-1 [156.453231] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.163s) [156.462219] Starting dynamic subtest: pipe-C-eDP-1 [157.652109] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.200s) [157.660035] Starting dynamic subtest: pipe-D-eDP-1 [158.799251] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.145s) [158.801725] Subtest atomic-fastset: SUCCESS (3.656s) [159.928677] [032/130] (858s left) kms_plane_scaling (intel-max-src-size) [160.026053] Starting subtest: intel-max-src-size [160.032785] Subtest intel-max-src-size: SKIP (0.000s) [160.119741] [033/130] (858s left) kms_psr (psr-basic) [160.216071] Starting subtest: psr-basic [160.218421] Starting dynamic subtest: eDP-1 [161.808415] Dynamic subtest eDP-1: SUCCESS (1.589s) [161.810839] Subtest psr-basic: SUCCESS (1.589s) [162.678468] [034/130] (856s left) kms_big_fb (x-tiled-16bpp-rotate-90) [162.787913] Starting subtest: x-tiled-16bpp-rotate-90 [162.807918] Subtest x-tiled-16bpp-rotate-90: SKIP (0.018s) [162.935236] [035/130] (855s left) gem_ctx_bad_destroy (invalid-pad) [162.990649] Starting subtest: invalid-pad [162.993113] Subtest invalid-pad: SUCCESS (0.000s) [163.060108] [036/130] (855s left) gem_exec_gttfill (engines) [163.124014] Starting subtest: engines [163.126326] Starting dynamic subtest: rcs0 [187.978866] Dynamic subtest rcs0: SUCCESS (24.851s) [187.990041] Starting dynamic subtest: bcs0 [213.610929] Dynamic subtest bcs0: SUCCESS (25.631s) [213.621413] Starting dynamic subtest: vcs0 [239.196550] Dynamic subtest vcs0: SUCCESS (25.584s) [239.204180] Starting dynamic subtest: vcs1 [264.876375] Dynamic subtest vcs1: SUCCESS (25.678s) [264.886781] Starting dynamic subtest: vecs0 [290.426644] Dynamic subtest vecs0: SUCCESS (25.548s) [290.434870] Starting dynamic subtest: ccs0 [316.098593] Dynamic subtest ccs0: SUCCESS (25.670s) [316.100701] Subtest engines: SUCCESS (152.967s) [316.279820] [037/130] (702s left) kms_big_fb (y-tiled-8bpp-rotate-0) [316.436959] Starting subtest: y-tiled-8bpp-rotate-0 [316.441091] Subtest y-tiled-8bpp-rotate-0: SKIP (0.000s) [316.541912] [038/130] (702s left) kms_lease (setcrtc-implicit-plane) [316.636506] Starting subtest: setcrtc-implicit-plane [316.638981] Starting dynamic subtest: pipe-A-eDP-1 [317.099812] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.461s) [317.108478] Starting dynamic subtest: pipe-B-eDP-1 [318.182431] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.081s) [318.192723] Starting dynamic subtest: pipe-C-eDP-1 [319.300619] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.116s) [319.309487] Starting dynamic subtest: pipe-D-eDP-1 [320.431747] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.131s) [320.433763] Subtest setcrtc-implicit-plane: SUCCESS (3.794s) [321.264864] [039/130] (697s left) gem_mmap_wc (write-read-distinct) [321.319662] Subtest write-read-distinct: SKIP (0.000s) [321.369187] [040/130] (697s left) kms_pipe_crc_basic (suspend-read-crc) [321.470043] Starting subtest: suspend-read-crc [321.472410] Starting dynamic subtest: pipe-A-eDP-1 [339.501171] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.565s) [339.509227] Starting dynamic subtest: pipe-B-eDP-1 [357.196444] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.772s) [357.206775] Starting dynamic subtest: pipe-C-eDP-1 [375.159518] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.692s) [375.168140] Starting dynamic subtest: pipe-D-eDP-1 [393.179404] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.693s) [393.182083] Subtest suspend-read-crc: SUCCESS (10.727s) [394.018265] [041/130] (685s left) gem_mmap_gtt (big-bo) [394.086122] Subtest big-bo: SKIP (0.000s) [394.147985] [042/130] (685s left) gen9_exec_parse (basic-rejected-ctx-param) [394.213890] Subtest basic-rejected-ctx-param: SKIP (0.000s) [394.280190] [043/130] (685s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-upscaling) [394.382571] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-upscaling [394.385120] Starting dynamic subtest: pipe-A-default-mode [394.678638] Dynamic subtest pipe-A-default-mode: SKIP (0.286s) [394.683428] Subtest flip-32bpp-ytile-to-64bpp-ytile-upscaling: SKIP (0.287s) [395.465189] [044/130] (684s left) gem_exec_balancer (parallel-keep-submit-fence) [395.717788] Starting subtest: parallel-keep-submit-fence [396.912075] Subtest parallel-keep-submit-fence: SUCCESS (1.177s) [396.998328] [045/130] (683s left) sriov_basic (enable-vfs-autoprobe-off) [397.065235] Starting subtest: enable-vfs-autoprobe-off [397.068254] Starting dynamic subtest: numvfs-1 [397.156959] Dynamic subtest numvfs-1: FAIL (0.028s) [397.166633] Starting dynamic subtest: numvfs-2 [397.257900] Dynamic subtest numvfs-2: FAIL (0.003s) [397.268524] Starting dynamic subtest: numvfs-3 [397.355874] Dynamic subtest numvfs-3: FAIL (0.003s) [397.364987] Starting dynamic subtest: numvfs-4 [397.453927] Dynamic subtest numvfs-4: FAIL (0.003s) [397.463850] Starting dynamic subtest: numvfs-5 [397.552877] Dynamic subtest numvfs-5: FAIL (0.003s) [397.561992] Starting dynamic subtest: numvfs-6 [397.650877] Dynamic subtest numvfs-6: FAIL (0.003s) [397.659887] Starting dynamic subtest: numvfs-7 [397.748329] Dynamic subtest numvfs-7: FAIL (0.003s) [397.756591] Starting dynamic subtest: numvfs-random [397.842512] Dynamic subtest numvfs-random: FAIL (0.003s) [397.851932] Starting dynamic subtest: numvfs-all [397.938011] Dynamic subtest numvfs-all: FAIL (0.599s) [397.940917] Subtest enable-vfs-autoprobe-off: FAIL (0.652s) [397.976183] [046/130] (682s left) gem_exec_schedule (pi-userfault) [398.164341] Starting subtest: pi-userfault [398.166886] Starting dynamic subtest: rcs0 [398.180096] Dynamic subtest rcs0: SUCCESS (0.018s) [398.189531] Starting dynamic subtest: bcs0 [398.191595] Dynamic subtest bcs0: SUCCESS (0.012s) [398.201512] Starting dynamic subtest: vcs0 [398.203844] Dynamic subtest vcs0: SUCCESS (0.011s) [398.211878] Starting dynamic subtest: vcs1 [398.217064] Dynamic subtest vcs1: SUCCESS (0.011s) [398.225560] Starting dynamic subtest: vecs0 [398.227815] Dynamic subtest vecs0: SUCCESS (0.012s) [398.236601] Starting dynamic subtest: ccs0 [398.242006] Dynamic subtest ccs0: SUCCESS (0.012s) [398.244822] Subtest pi-userfault: SUCCESS (0.078s) [398.424289] [047/130] (681s left) kms_force_connector_basic (force-connector-state) [398.512731] Starting subtest: force-connector-state [399.119889] Subtest force-connector-state: SUCCESS (0.600s) [400.057621] [048/130] (680s left) kms_psr2_sf (pr-cursor-plane-move-continuous-sf) [405.162935] Starting subtest: pr-cursor-plane-move-continuous-sf [405.168358] Subtest pr-cursor-plane-move-continuous-sf: SKIP (0.002s) [405.978329] [049/130] (674s left) gem_caching (reads) [406.050011] Subtest reads: SKIP (0.000s) [406.126447] [050/130] (674s left) kms_frontbuffer_tracking (fbcpsr-2p-shrfb-fliptrack-mmap-gtt) [406.234959] Starting subtest: fbcpsr-2p-shrfb-fliptrack-mmap-gtt [406.239937] Subtest fbcpsr-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s) [406.318428] [051/130] (674s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip) [406.433234] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip [406.437914] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s) [406.532205] [052/130] (673s left) gem_ctx_isolation (preservation-s3) [406.608369] Starting subtest: preservation-S3 [406.611271] Starting dynamic subtest: rcs0 [422.693253] Dynamic subtest rcs0: SUCCESS (1.472s) [422.705476] Starting dynamic subtest: bcs0 [438.625586] Dynamic subtest bcs0: SUCCESS (1.404s) [438.634335] Starting dynamic subtest: vcs0 [454.627684] Dynamic subtest vcs0: SUCCESS (2.323s) [454.646653] Starting dynamic subtest: vcs1 [472.708562] Dynamic subtest vcs1: SUCCESS (4.986s) [472.718044] Starting dynamic subtest: vecs0 [490.741404] Dynamic subtest vecs0: SUCCESS (4.932s) [490.752758] Starting dynamic subtest: ccs0 [508.621217] Dynamic subtest ccs0: SUCCESS (3.152s) [508.623795] Subtest preservation-S3: SUCCESS (18.272s) [508.871209] [053/130] (655s left) kms_flip (plain-flip-ts-check-interruptible) [508.974661] Starting subtest: plain-flip-ts-check-interruptible [508.977180] Starting dynamic subtest: A-eDP1 [516.788909] Dynamic subtest A-eDP1: FAIL (7.145s) [516.799851] Starting dynamic subtest: B-eDP1 [528.836703] Dynamic subtest B-eDP1: FAIL (11.985s) [528.847859] Starting dynamic subtest: C-eDP1 [537.163585] Dynamic subtest C-eDP1: SUCCESS (8.878s) [537.173199] Starting dynamic subtest: D-eDP1 [546.071887] Dynamic subtest D-eDP1: SUCCESS (8.900s) [546.077650] Subtest plain-flip-ts-check-interruptible: FAIL (36.924s) [547.318093] [054/130] (617s left) kms_psr (fbc-pr-sprite-render) [547.427615] Starting subtest: fbc-pr-sprite-render [547.432743] Subtest fbc-pr-sprite-render: SKIP (0.000s) [547.494907] [055/130] (617s left) syncobj_wait (single-wait-all-for-submit-signaled) [547.561468] Starting subtest: single-wait-all-for-submit-signaled [547.564361] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s) [547.650651] [056/130] (616s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs-cc) [547.739245] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs-cc [547.741699] Starting dynamic subtest: pipe-A-eDP-1 [547.763762] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s) [547.772702] Starting dynamic subtest: pipe-B-eDP-1 [547.793526] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [547.801632] Starting dynamic subtest: pipe-C-eDP-1 [547.822078] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [547.830108] Starting dynamic subtest: pipe-D-eDP-1 [547.850649] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [547.852883] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.004s) [550.011337] [057/130] (614s left) kms_vblank (query-busy) [550.112619] Starting subtest: query-busy [550.115615] Starting dynamic subtest: pipe-A-eDP-1 [552.008573] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.893s) [552.022779] Starting dynamic subtest: pipe-D-eDP-1 [554.557379] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.544s) [554.559846] Subtest query-busy: SUCCESS (4.442s) [555.423850] [058/130] (609s left) kms_chamelium_edid (dp-edid-resolution-list) [555.803610] Subtest dp-edid-resolution-list: SKIP (0.000s) [556.601002] [059/130] (608s left) kms_big_fb (x-tiled-8bpp-rotate-90) [556.707837] Starting subtest: x-tiled-8bpp-rotate-90 [556.729134] Subtest x-tiled-8bpp-rotate-90: SKIP (0.021s) [556.879963] [060/130] (607s left) gem_exec_whisper (basic-contexts-forked) [556.951165] Starting subtest: basic-contexts-forked [581.600702] Subtest basic-contexts-forked: SUCCESS (24.633s) [581.779515] [061/130] (582s left) kms_content_protection (legacy) [581.965974] Starting subtest: legacy [585.759585] Subtest legacy: SKIP (3.788s) [586.945618] [062/130] (577s left) api_intel_bb (add-remove-objects) [587.012892] Starting subtest: add-remove-objects [587.019292] Subtest add-remove-objects: SUCCESS (0.010s) [587.157180] [063/130] (577s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-mmap-gtt) [587.264179] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-mmap-gtt [587.268751] Subtest fbc-1p-offscren-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [587.354076] [064/130] (577s left) gem_render_copy (y-tiled-ccs-to-x-tiled) [587.421300] Starting subtest: y-tiled-ccs-to-x-tiled [587.426501] Subtest y-tiled-ccs-to-x-tiled: SKIP (0.000s) [587.600682] [065/130] (577s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs) [587.703287] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs [587.705501] Starting dynamic subtest: pipe-A-eDP-1 [588.146418] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.410s) [588.156714] Starting dynamic subtest: pipe-B-eDP-1 [589.499684] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.351s) [589.509388] Starting dynamic subtest: pipe-C-eDP-1 [590.877390] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.376s) [590.886011] Starting dynamic subtest: pipe-D-eDP-1 [592.180605] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.298s) [592.183407] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.438s) [593.329672] [066/130] (571s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation) [593.424682] Starting subtest: plane-scaler-unity-scaling-with-rotation [593.427760] Starting dynamic subtest: pipe-A [593.774587] Dynamic subtest pipe-A: SUCCESS (0.350s) [593.784429] Starting dynamic subtest: pipe-B [595.210335] Dynamic subtest pipe-B: SUCCESS (1.434s) [595.219748] Starting dynamic subtest: pipe-C [596.598614] Dynamic subtest pipe-C: SUCCESS (1.387s) [596.608353] Starting dynamic subtest: pipe-D [597.989671] Dynamic subtest pipe-D: SUCCESS (1.390s) [597.992139] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (4.564s) [599.136061] [067/130] (565s left) syncobj_timeline (wait-any-interrupted) [599.196666] Starting subtest: wait-any-interrupted [599.298836] Subtest wait-any-interrupted: SUCCESS (0.103s) [599.373149] [068/130] (565s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite) [599.482783] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite [604.078440] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (4.600s) [604.196355] [069/130] (560s left) gem_softpin (evict-prime-sanity-check) [604.257856] Starting subtest: evict-prime-sanity-check [604.260725] Starting dynamic subtest: rcs0 [605.432243] Dynamic subtest rcs0: SUCCESS (1.178s) [605.444080] Starting dynamic subtest: bcs0 [606.579364] Dynamic subtest bcs0: SUCCESS (1.148s) [606.592514] Starting dynamic subtest: vcs0 [608.234030] Dynamic subtest vcs0: SUCCESS (1.657s) [608.246977] Starting dynamic subtest: vcs1 [610.266135] Dynamic subtest vcs1: SUCCESS (2.036s) [610.276512] Starting dynamic subtest: vecs0 [612.267839] Dynamic subtest vecs0: SUCCESS (2.004s) [612.278020] Starting dynamic subtest: ccs0 [613.435189] Dynamic subtest ccs0: SUCCESS (1.169s) [613.446887] Starting dynamic subtest: all [614.567392] Dynamic subtest all: SUCCESS (1.133s) [614.570368] Subtest evict-prime-sanity-check: SUCCESS (10.340s) [614.644305] [070/130] (550s left) kms_frontbuffer_tracking (fbc-modesetfrombusy) [614.767414] Starting subtest: fbc-modesetfrombusy [617.571724] Subtest fbc-modesetfrombusy: SUCCESS (2.815s) [617.717193] [071/130] (547s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0) [617.815181] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0 [618.770891] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (0.955s) [618.920318] [072/130] (546s left) debugfs_test (read_all_entries_display_on) [619.014620] Starting subtest: read_all_entries_display_on [619.403372] Subtest read_all_entries_display_on: SUCCESS (0.390s) [619.517939] [073/130] (545s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move) [619.622179] Starting subtest: fbc-2p-primscrn-cur-indfb-move [619.626137] Subtest fbc-2p-primscrn-cur-indfb-move: SKIP (0.000s) [619.716030] [074/130] (545s left) kms_atomic (crtc-invalid-params) [619.812759] Starting subtest: crtc-invalid-params [619.815158] Starting dynamic subtest: pipe-A-eDP-1 [620.176760] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.362s) [620.179236] Subtest crtc-invalid-params: SUCCESS (0.363s) [620.989171] [075/130] (544s left) kms_rotation_crc (primary-rotation-180) [621.098481] Starting subtest: primary-rotation-180 [628.229561] Subtest primary-rotation-180: SUCCESS (7.137s) [629.379868] [076/130] (535s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu) [629.484652] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu [629.489642] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s) [629.564122] [077/130] (535s left) kms_color (invalid-gamma-lut-sizes) [629.668799] Starting subtest: invalid-gamma-lut-sizes [629.671603] Starting dynamic subtest: pipe-A [629.948439] Dynamic subtest pipe-A: SUCCESS (0.282s) [629.958947] Starting dynamic subtest: pipe-B [629.961736] Dynamic subtest pipe-B: SUCCESS (0.001s) [629.970605] Starting dynamic subtest: pipe-C [629.973190] Dynamic subtest pipe-C: SUCCESS (0.000s) [629.981886] Starting dynamic subtest: pipe-D [629.984706] Dynamic subtest pipe-D: SUCCESS (0.000s) [629.987472] Subtest invalid-gamma-lut-sizes: SUCCESS (0.286s) [630.774639] [078/130] (534s left) i915_pm_sseu (full-enable) [630.837846] Subtest full-enable: SKIP (0.000s) [630.935113] [079/130] (534s left) kms_getfb (getfb2-handle-zero) [630.993617] Starting subtest: getfb2-handle-zero [630.996518] Subtest getfb2-handle-zero: SUCCESS (0.000s) [631.105384] [080/130] (534s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt) [631.211563] Starting subtest: psr-2p-primscrn-indfb-msflip-blt [631.216693] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s) [631.316215] [081/130] (534s left) i915_pm_rpm (debugfs-read) [633.455474] Starting subtest: debugfs-read [634.624454] Subtest debugfs-read: SUCCESS (1.167s) [634.972526] [082/130] (530s left) drm_read (empty-block) [635.149873] Starting subtest: empty-block [636.151847] Subtest empty-block: SUCCESS (1.002s) [636.290174] [083/130] (529s left) gem_ctx_shared (q-promotion) [636.354461] Starting subtest: Q-promotion [636.357309] Starting dynamic subtest: rcs0 [636.468349] Dynamic subtest rcs0: SUCCESS (0.113s) [636.481321] Starting dynamic subtest: vcs0 [636.558674] Dynamic subtest vcs0: SUCCESS (0.090s) [636.574879] Starting dynamic subtest: vcs1 [636.634717] Dynamic subtest vcs1: SUCCESS (0.075s) [636.650674] Starting dynamic subtest: vecs0 [636.698844] Dynamic subtest vecs0: SUCCESS (0.064s) [636.715464] Starting dynamic subtest: ccs0 [636.772707] Dynamic subtest ccs0: SUCCESS (0.072s) [636.775887] Subtest Q-promotion: SUCCESS (0.418s) [638.375755] [084/130] (527s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc) [638.482106] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc [642.785907] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (4.310s) [642.906108] [085/130] (522s left) tools_test (sysfs_l3_parity) [642.958734] Starting subtest: sysfs_l3_parity [643.028577] Subtest sysfs_l3_parity: SKIP (0.081s) [643.078266] [086/130] (522s left) prime_vgem (fence-flip-hang) [643.158905] Subtest fence-flip-hang: SKIP (0.000s) [643.244240] [087/130] (522s left) gem_exec_reloc (basic-softpin) [643.306476] Subtest basic-softpin: SKIP (0.000s) [643.380216] [088/130] (522s left) kms_cursor_legacy (cursorb-vs-flipa-toggle) [643.500692] Starting subtest: cursorB-vs-flipA-toggle [643.504845] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s) [643.593988] [089/130] (522s left) kms_cursor_legacy (torture-move) [643.718092] Starting subtest: torture-move [643.720427] Starting dynamic subtest: pipe-A [649.671869] Dynamic subtest pipe-A: SUCCESS (5.952s) [649.684451] Starting dynamic subtest: pipe-B [655.901784] Dynamic subtest pipe-B: SUCCESS (6.229s) [655.914592] Starting dynamic subtest: pipe-C [662.587508] Dynamic subtest pipe-C: SUCCESS (6.684s) [662.599210] Starting dynamic subtest: pipe-D [669.075732] Dynamic subtest pipe-D: SUCCESS (6.485s) [669.086417] Starting dynamic subtest: all-pipes [675.560519] Dynamic subtest all-pipes: SUCCESS (6.484s) [675.563630] Subtest torture-move: SUCCESS (31.844s) [675.714450] [090/130] (490s left) fbdev (read) [675.765012] Starting subtest: read [676.292855] Subtest read: SUCCESS (0.526s) [676.351744] [091/130] (489s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff) [676.450167] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff [676.454784] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s) [676.559078] [092/130] (489s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-mmap-wc) [676.669719] Starting subtest: psr-1p-offscren-pri-indfb-draw-mmap-wc [681.189882] Subtest psr-1p-offscren-pri-indfb-draw-mmap-wc: SUCCESS (4.524s) [681.302841] [093/130] (484s left) kms_ccs (bad-rotation-90-4-tiled-lnl-ccs) [681.395319] Starting subtest: bad-rotation-90-4-tiled-lnl-ccs [681.400371] Subtest bad-rotation-90-4-tiled-lnl-ccs: SKIP (0.000s) [682.463955] [094/130] (483s left) gem_close (many-handles-one-vma) [682.535896] Starting subtest: many-handles-one-vma [682.562461] Subtest many-handles-one-vma: SUCCESS (0.026s) [682.660121] [095/130] (483s left) kms_rotation_crc (multiplane-rotation-cropping-top) [682.764705] Starting subtest: multiplane-rotation-cropping-top [690.046568] Subtest multiplane-rotation-cropping-top: SUCCESS (7.282s) [690.180079] [096/130] (475s left) syncobj_timeline (multi-wait-for-submit-available-submitted-signaled) [690.246550] Starting subtest: multi-wait-for-submit-available-submitted-signaled [690.248997] Subtest multi-wait-for-submit-available-submitted-signaled: SUCCESS (0.000s) [690.339186] [097/130] (475s left) kms_prop_blob (blob-prop-validate) [690.395152] Starting subtest: blob-prop-validate [690.397717] Subtest blob-prop-validate: SUCCESS (0.000s) [690.490175] [098/130] (475s left) gem_pxp (verify-pxp-stale-buf-execution) [690.627656] Starting subtest: verify-pxp-stale-buf-execution [691.686683] Subtest verify-pxp-stale-buf-execution: SUCCESS (1.060s) [691.815155] [099/130] (474s left) kms_flip_event_leak (basic) [691.910842] Starting subtest: basic [691.913573] Starting dynamic subtest: pipe-A-eDP-1 [692.366968] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.453s) [692.377730] Starting dynamic subtest: pipe-B-eDP-1 [693.455104] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.089s) [693.464860] Starting dynamic subtest: pipe-C-eDP-1 [694.545678] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.088s) [694.556366] Starting dynamic subtest: pipe-D-eDP-1 [695.646519] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.102s) [695.649387] Subtest basic: SUCCESS (3.736s) [696.458045] [100/130] (469s left) gem_pxp (protected-raw-src-copy-not-readible) [696.520126] Starting subtest: protected-raw-src-copy-not-readible [696.577512] Subtest protected-raw-src-copy-not-readible: SUCCESS (0.059s) [696.694210] [101/130] (469s left) kms_chamelium_hpd (hdmi-hpd-fast) [697.089021] Subtest hdmi-hpd-fast: SKIP (0.000s) [697.849970] [102/130] (468s left) kms_pm_dc (dc5-dpms-negative) [698.949643] Starting subtest: dc5-dpms-negative [698.954836] Subtest dc5-dpms-negative: SKIP (0.000s) [699.020195] [103/130] (467s left) gem_create (create-massive) [699.084621] Starting subtest: create-massive [699.086764] Subtest create-massive: SUCCESS (0.000s) [699.264014] [104/130] (466s left) kms_ccs (crc-primary-suspend-4-tiled-lnl-ccs) [699.365689] Starting subtest: crc-primary-suspend-4-tiled-lnl-ccs [699.370991] Subtest crc-primary-suspend-4-tiled-lnl-ccs: SKIP (0.000s) [700.459876] [105/130] (465s left) gem_spin_batch (resubmit-all) [700.533380] Starting subtest: resubmit-all [700.535866] Starting dynamic subtest: rcs0 [700.546003] Dynamic subtest rcs0: SUCCESS (0.012s) [700.559116] Starting dynamic subtest: bcs0 [700.561732] Dynamic subtest bcs0: SUCCESS (0.002s) [700.571448] Starting dynamic subtest: vcs0 [700.574050] Dynamic subtest vcs0: SUCCESS (0.002s) [700.582947] Starting dynamic subtest: vcs1 [700.585846] Dynamic subtest vcs1: SUCCESS (0.002s) [700.594879] Starting dynamic subtest: vecs0 [700.597634] Dynamic subtest vecs0: SUCCESS (0.001s) [700.606693] Starting dynamic subtest: ccs0 [700.609554] Dynamic subtest ccs0: SUCCESS (0.002s) [700.612127] Subtest resubmit-all: SUCCESS (0.024s) [700.794085] [106/130] (465s left) kms_invalid_mode (overflow-vrefresh) [700.894358] Starting subtest: overflow-vrefresh [700.896694] Starting dynamic subtest: pipe-A-eDP-1 [701.174839] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.280s) [701.184782] Starting dynamic subtest: pipe-B-eDP-1 [701.187302] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s) [701.195762] Starting dynamic subtest: pipe-C-eDP-1 [701.198155] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s) [701.207473] Starting dynamic subtest: pipe-D-eDP-1 [701.215670] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [701.224066] Subtest overflow-vrefresh: SUCCESS (0.289s) [701.957234] [107/130] (464s left) gem_vm_create (invalid-create) [702.024069] Starting subtest: invalid-create [702.026869] Subtest invalid-create: SUCCESS (0.002s) [702.134784] [108/130] (464s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite) [702.242249] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite [702.247482] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s) [702.353186] [109/130] (464s left) gem_tiling_max_stride [702.481760] [110/130] (463s left) i915_query (query-regions-unallocated) [702.540522] Starting subtest: query-regions-unallocated [702.542764] Subtest query-regions-unallocated: SUCCESS (0.000s) [702.653324] [111/130] (463s left) gen3_render_tiledy_blits [702.771832] [112/130] (463s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip) [702.870833] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip [702.875719] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s) [702.972631] [113/130] (463s left) gem_exec_fence (submit) [703.161302] Starting subtest: submit [703.166508] Subtest submit: SKIP (0.000s) [705.363413] [114/130] (461s left) gem_userptr_blits (readonly-unsync) [705.429885] Subtest readonly-unsync: SKIP (0.000s) [705.560719] [115/130] (461s left) gem_workarounds (suspend-resume-context) [705.626701] Starting subtest: suspend-resume-context [721.596220] Subtest suspend-resume-context: SUCCESS (1.204s) [721.720096] [116/130] (459s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc) [721.807119] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc [721.809462] Starting dynamic subtest: pipe-A-eDP-1 [721.834549] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [721.844741] Starting dynamic subtest: pipe-B-eDP-1 [721.868322] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [721.877739] Starting dynamic subtest: pipe-C-eDP-1 [721.901986] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [721.910805] Starting dynamic subtest: pipe-D-eDP-1 [721.936655] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [721.941041] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s) [724.044340] [117/130] (457s left) kms_flip (busy-flip) [724.149110] Starting subtest: busy-flip [724.151460] Starting dynamic subtest: A-eDP1 [725.851426] Dynamic subtest A-eDP1: SUCCESS (1.677s) [725.862124] Starting dynamic subtest: B-eDP1 [727.575212] Dynamic subtest B-eDP1: SUCCESS (1.703s) [727.586697] Starting dynamic subtest: C-eDP1 [729.279520] Dynamic subtest C-eDP1: SUCCESS (1.687s) [729.290501] Starting dynamic subtest: D-eDP1 [730.989756] Dynamic subtest D-eDP1: SUCCESS (1.697s) [730.992300] Subtest busy-flip: SUCCESS (6.771s) [732.117817] [118/130] (449s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted-signaled) [732.170159] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled [732.172430] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s) [732.276155] [119/130] (449s left) kms_atomic (test-only) [732.383137] Starting subtest: test-only [732.439634] Starting dynamic subtest: pipe-A-eDP-1 [734.870836] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.421s) [734.873234] Subtest test-only: SUCCESS (2.474s) [735.735018] [120/130] (445s left) gem_mmap_wc (bad-object) [735.800026] Subtest bad-object: SKIP (0.000s) [735.852566] [121/130] (445s left) kms_psr (psr2-primary-blt) [735.949365] Starting subtest: psr2-primary-blt [735.951698] Starting dynamic subtest: eDP-1 [737.700041] Dynamic subtest eDP-1: SUCCESS (1.742s) [737.702416] Subtest psr2-primary-blt: SUCCESS (1.743s) [738.545067] [122/130] (443s left) prime_mmap (test_reprime) [738.612462] Starting subtest: test_reprime [738.614398] Starting dynamic subtest: test_reprime-smem [738.643440] Dynamic subtest test_reprime-smem: SUCCESS (0.031s) [738.645914] Subtest test_reprime: SUCCESS (0.032s) [738.765938] [123/130] (443s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted) [738.827619] Starting subtest: etime-multi-wait-all-for-submit-submitted [738.933176] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.105s) [739.003315] [124/130] (442s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-onoff) [739.111539] Starting subtest: psr-1p-primscrn-cur-indfb-onoff [743.852215] Subtest psr-1p-primscrn-cur-indfb-onoff: SUCCESS (4.732s) [743.958672] [125/130] (437s left) kms_cursor_edge_walk (128x128-left-edge) [744.062697] Starting subtest: 128x128-left-edge [744.065427] Starting dynamic subtest: pipe-A-eDP-1 [747.491457] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.422s) [747.501383] Starting dynamic subtest: pipe-D-eDP-1 [750.660484] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.166s) [750.662807] Subtest 128x128-left-edge: SUCCESS (6.590s) [751.466266] [126/130] (430s left) gem_exec_params (invalid-bsd1-flag-on-render) [751.537553] Starting subtest: invalid-bsd1-flag-on-render [751.540065] Subtest invalid-bsd1-flag-on-render: SUCCESS (0.000s) [751.645518] [127/130] (430s left) gem_exec_schedule (u-lateslice) [751.720602] Starting subtest: u-lateslice [751.722720] Starting dynamic subtest: rcs0 [751.731024] Dynamic subtest rcs0: SUCCESS (0.020s) [751.743043] Starting dynamic subtest: bcs0 [751.747838] Dynamic subtest bcs0: SUCCESS (0.016s) [751.757484] Starting dynamic subtest: vcs0 [751.764508] Dynamic subtest vcs0: SUCCESS (0.017s) [751.774460] Starting dynamic subtest: vcs1 [751.780932] Dynamic subtest vcs1: SUCCESS (0.016s) [751.790585] Starting dynamic subtest: vecs0 [751.797087] Dynamic subtest vecs0: SUCCESS (0.016s) [751.806651] Starting dynamic subtest: ccs0 [751.813225] Dynamic subtest ccs0: SUCCESS (0.016s) [751.815429] Subtest u-lateslice: SUCCESS (0.103s) [753.144172] [128/130] (428s left) gem_busy (semaphore) [753.336783] Subtest semaphore: SKIP (0.000s) [753.564522] [129/130] (428s left) gem_mmap_gtt (cpuset-big-copy-odd) [753.631146] Subtest cpuset-big-copy-odd: SKIP (0.000s) [753.695461] [130/130] (428s left) syncobj_wait (single-wait-for-submit-unsubmitted) [753.754333] Starting subtest: single-wait-for-submit-unsubmitted [753.860571] Subtest single-wait-for-submit-unsubmitted: SUCCESS (0.104s) [753.929332] Closing watchdogs Done.