[44.416119] Initializing watchdogs [44.416169] /dev/watchdog0 [44.421802] [001/139] (960s left) gem_exec_params (secure-non-root) [44.500433] Starting subtest: secure-non-root [44.505050] Subtest secure-non-root: SKIP (0.000s) [44.589183] [002/139] (960s left) kms_big_fb (4-tiled-32bpp-rotate-180) [44.662579] Starting subtest: 4-tiled-32bpp-rotate-180 [46.155508] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.497s) [46.268918] [003/139] (958s left) gem_exec_flush (basic-wb-prw-default) [46.332723] Starting subtest: basic-wb-prw-default [51.824042] Subtest basic-wb-prw-default: SUCCESS (5.453s) [51.960238] [004/139] (953s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25) [52.025275] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25 [52.027735] Starting dynamic subtest: pipe-A-eDP-1 [52.090650] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.066s) [52.101145] Starting dynamic subtest: pipe-B-eDP-1 [53.311313] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.219s) [53.324904] Starting dynamic subtest: pipe-C-eDP-1 [54.518144] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.204s) [54.529073] Starting dynamic subtest: pipe-D-eDP-1 [55.732636] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.213s) [55.735048] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (3.710s) [56.953067] [005/139] (948s left) i915_pm_rpm (debugfs-read) [68.572783] Starting subtest: debugfs-read [72.405516] Subtest debugfs-read: SUCCESS (3.829s) [73.190111] [006/139] (931s left) kms_content_protection (dp-mst-lic-type-1) [73.308762] Starting subtest: dp-mst-lic-type-1 [73.313450] Subtest dp-mst-lic-type-1: SKIP (0.000s) [73.409200] [007/139] (931s left) vc4/vc4_purgeable_bo (mark-willneed) [73.512426] Subtest mark-willneed: SKIP (0.000s) [73.557377] [008/139] (931s left) v3d/v3d_submit_cl (bad-multisync-pad) [73.658749] Subtest bad-multisync-pad: SKIP (0.000s) [73.704700] [009/139] (931s left) kms_cursor_crc (cursor-sliding-512x170) [73.924611] Starting subtest: cursor-sliding-512x170 [73.929086] Subtest cursor-sliding-512x170: SKIP (0.001s) [74.031908] [010/139] (931s left) kms_ccs (pipe-d-ccs-on-another-bo-4-tiled-mtl-mc-ccs) [74.471610] Starting subtest: pipe-D-ccs-on-another-bo-4-tiled-mtl-mc-ccs [74.496137] Subtest pipe-D-ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.023s) [75.292602] [011/139] (929s left) kms_pm_rpm (cursor-dpms) [86.897946] Starting subtest: cursor-dpms [118.889375] Subtest cursor-dpms: SUCCESS (31.992s) [119.940376] [012/139] (885s left) vgem_basic (setversion) [120.007419] Starting subtest: setversion [120.011676] Subtest setversion: SUCCESS (0.000s) [120.050696] [013/139] (885s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc) [120.130261] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc [123.198059] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (3.072s) [123.324174] [014/139] (881s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted) [123.389624] Starting subtest: invalid-multi-wait-unsubmitted-submitted [123.391721] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.001s) [123.454326] [015/139] (881s left) gem_barrier_race (remote-request) [123.535310] Starting subtest: remote-request [123.537628] Starting dynamic subtest: rcs0 [128.639440] Dynamic subtest rcs0: SUCCESS (5.107s) [128.641348] Subtest remote-request: SUCCESS (5.108s) [128.736990] [016/139] (876s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-pwrite) [128.818413] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-pwrite [128.821947] Subtest psr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [128.893472] [017/139] (876s left) kms_atomic_transition (plane-use-after-nonblocking-unbind) [128.959614] Starting subtest: plane-use-after-nonblocking-unbind [128.961720] Starting dynamic subtest: pipe-A-eDP-1 [129.027922] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.068s) [129.338419] Starting dynamic subtest: pipe-B-eDP-1 [130.228616] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.902s) [130.526734] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.567s) [131.446197] [018/139] (873s left) vgem_basic (dmabuf-fence) [131.502957] Starting subtest: dmabuf-fence [131.505567] Subtest dmabuf-fence: SUCCESS (0.000s) [131.547998] [019/139] (873s left) sysfs_preempt_timeout (timeout) [131.609300] Starting subtest: timeout [131.611777] Starting dynamic subtest: vecs0 [141.498866] Dynamic subtest vecs0: SUCCESS (9.888s) [141.511148] Starting dynamic subtest: ccs0 [142.776034] Dynamic subtest ccs0: SUCCESS (1.280s) [142.783533] Starting dynamic subtest: vcs0 [152.450821] Dynamic subtest vcs0: SUCCESS (9.672s) [152.466568] Starting dynamic subtest: bcs0 [153.749249] Dynamic subtest bcs0: SUCCESS (1.298s) [153.761724] Starting dynamic subtest: rcs0 [154.967732] Dynamic subtest rcs0: SUCCESS (1.219s) [154.977430] Starting dynamic subtest: vcs1 [164.471763] Dynamic subtest vcs1: SUCCESS (9.506s) [164.474341] Subtest timeout: SUCCESS (32.866s) [164.539678] [020/139] (840s left) kms_selftest (drm_plane_helper) [164.589298] Starting subtest: drm_plane_helper [164.717633] Starting dynamic subtest: drm_test_check_plane_state [164.729798] Dynamic subtest drm_test_check_plane_state: SUCCESS (0.013s) [164.742349] Starting dynamic subtest: drm_test_check_invalid_plane_state [164.744459] Dynamic subtest drm_test_check_invalid_plane_state: SUCCESS (0.001s) [164.746519] Subtest drm_plane_helper: SUCCESS (0.143s) [164.915435] [021/139] (840s left) kms_ccs (pipe-d-bad-rotation-90-4-tiled-dg2-mc-ccs) [165.317955] Starting subtest: pipe-D-bad-rotation-90-4-tiled-dg2-mc-ccs [165.323203] Subtest pipe-D-bad-rotation-90-4-tiled-dg2-mc-ccs: SKIP (0.000s) [166.138939] [022/139] (839s left) kms_ccs (pipe-a-crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc) [166.524211] Starting subtest: pipe-A-crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc [166.530254] Subtest pipe-A-crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.001s) [167.420682] [023/139] (838s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu) [167.515057] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu [167.519325] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s) [167.596341] [024/139] (837s left) kms_color (ctm-max) [167.664434] Starting subtest: ctm-max [167.666789] Starting dynamic subtest: pipe-A [168.408620] Dynamic subtest pipe-A: SUCCESS (0.744s) [168.422047] Starting dynamic subtest: pipe-B [169.932748] Dynamic subtest pipe-B: SUCCESS (1.524s) [169.946678] Starting dynamic subtest: pipe-C [171.352246] Dynamic subtest pipe-C: SUCCESS (1.419s) [171.365469] Starting dynamic subtest: pipe-D [172.822492] Dynamic subtest pipe-D: SUCCESS (1.472s) [172.824568] Subtest ctm-max: SUCCESS (5.160s) [173.757349] [025/139] (831s left) kms_chamelium_hpd (hdmi-hpd) [174.108466] Subtest hdmi-hpd: SKIP (0.000s) [175.008917] [026/139] (830s left) gem_exec_whisper (basic-queues-all) [175.068751] Starting subtest: basic-queues-all [192.474535] Subtest basic-queues-all: SUCCESS (17.403s) [192.649366] [027/139] (812s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu) [192.723200] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu [192.727883] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s) [192.813968] [028/139] (812s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-cpu) [192.883725] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-cpu [192.888332] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [192.952633] [029/139] (812s left) syncobj_wait (multi-wait-for-submit-submitted-signaled) [193.010251] Starting subtest: multi-wait-for-submit-submitted-signaled [193.013300] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.001s) [193.073586] [030/139] (812s left) prime_self_import (basic-llseek-size) [193.123369] Starting subtest: basic-llseek-size [193.128562] Subtest basic-llseek-size: SUCCESS (0.004s) [193.208451] [031/139] (812s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-downscaling) [193.277112] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-downscaling [193.279993] Starting dynamic subtest: pipe-A-default-mode [193.581843] Dynamic subtest pipe-A-default-mode: SKIP (0.302s) [193.586898] Subtest flip-32bpp-ytile-to-64bpp-ytile-downscaling: SKIP (0.304s) [194.492919] [032/139] (811s left) gem_ctx_exec (basic-norecovery) [194.551270] Starting subtest: basic-norecovery [194.962564] Subtest basic-norecovery: SUCCESS (0.413s) [195.055410] [033/139] (810s left) kms_psr (psr2_cursor_mmap_cpu) [195.108250] Starting subtest: psr2_cursor_mmap_cpu [196.897455] Subtest psr2_cursor_mmap_cpu: SUCCESS (1.789s) [197.823780] [034/139] (807s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-pwrite) [197.894052] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-pwrite [197.898163] Subtest fbc-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [197.962288] [035/139] (807s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling) [198.028083] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling [198.030462] Starting dynamic subtest: pipe-A-default-mode [198.316272] Dynamic subtest pipe-A-default-mode: SKIP (0.286s) [198.321222] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.287s) [199.230748] [036/139] (806s left) gem_render_copy (y-tiled-to-vebox-x-tiled) [199.282824] Starting subtest: y-tiled-to-vebox-x-tiled [199.288147] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s) [199.454538] [037/139] (806s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip) [199.538876] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip [200.462791] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.925s) [200.626301] [038/139] (805s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-pwrite) [200.700673] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-pwrite [205.947722] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (5.255s) [206.075329] [039/139] (799s left) kms_ccs (pipe-d-crc-primary-rotation-180-4-tiled-mtl-rc-ccs) [206.490137] Starting subtest: pipe-D-crc-primary-rotation-180-4-tiled-mtl-rc-ccs [207.487623] Subtest pipe-D-crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (0.997s) [208.706429] [040/139] (797s left) gem_sync (basic-store-all) [208.783135] Starting subtest: basic-store-all [213.365297] Subtest basic-store-all: SUCCESS (4.601s) [213.543465] [041/139] (792s left) kms_busy (extended-modeset-hang-oldfb-with-reset) [213.630116] Starting subtest: extended-modeset-hang-oldfb-with-reset [213.632973] Starting dynamic subtest: pipe-A [216.745608] Dynamic subtest pipe-A: SUCCESS (3.112s) [216.760269] Starting dynamic subtest: pipe-D [220.479479] Dynamic subtest pipe-D: SUCCESS (3.730s) [220.482848] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (6.850s) [221.359319] [042/139] (784s left) dmabuf_sync_file (export-multiwait) [221.407561] Starting subtest: export-multiwait [221.409747] Subtest export-multiwait: SUCCESS (0.000s) [221.453488] [043/139] (784s left) perf (whitelisted-registers-userspace-config) [221.584052] Starting subtest: whitelisted-registers-userspace-config [221.586627] Subtest whitelisted-registers-userspace-config: SUCCESS (0.000s) [221.612413] [044/139] (784s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset-interruptible) [221.667820] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset-interruptible [221.682547] Subtest 2x-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.014s) [221.749254] [045/139] (784s left) kms_ccs (pipe-c-bad-aux-stride-4-tiled-mtl-rc-ccs) [222.130719] Starting subtest: pipe-C-bad-aux-stride-4-tiled-mtl-rc-ccs [222.133598] Subtest pipe-C-bad-aux-stride-4-tiled-mtl-rc-ccs: SUCCESS (0.000s) [222.975200] [046/139] (783s left) i915_pm_freq_api (freq-reset) [223.037821] Starting subtest: freq-reset [223.040414] Starting dynamic subtest: gt0 [223.229212] Dynamic subtest gt0: SUCCESS (0.190s) [223.243667] Starting dynamic subtest: gt1 [223.421570] Dynamic subtest gt1: SUCCESS (0.191s) [223.424387] Subtest freq-reset: SUCCESS (0.383s) [223.478471] [047/139] (782s left) kms_rotation_crc (sprite-rotation-270) [223.535539] Starting subtest: sprite-rotation-270 [223.540633] Subtest sprite-rotation-270: SKIP (0.002s) [223.600199] [048/139] (782s left) kms_lease (lessee-list) [223.676459] Starting subtest: lessee-list [223.679069] Starting dynamic subtest: pipe-A-eDP-1 [223.681405] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s) [223.687399] Starting dynamic subtest: pipe-B-eDP-1 [223.689280] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [223.694728] Starting dynamic subtest: pipe-C-eDP-1 [223.696652] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [223.702117] Starting dynamic subtest: pipe-D-eDP-1 [223.704425] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [223.706642] Subtest lessee-list: SUCCESS (0.004s) [223.729352] [049/139] (782s left) kms_ccs (pipe-b-bad-pixel-format-y-tiled-gen12-rc-ccs) [224.078435] Starting subtest: pipe-B-bad-pixel-format-y-tiled-gen12-rc-ccs [224.084684] Subtest pipe-B-bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.000s) [224.960270] [050/139] (781s left) kms_plane_cursor (overlay) [225.035930] Starting subtest: overlay [225.040816] Starting dynamic subtest: pipe-A-eDP-1-size-64 [228.245681] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (3.206s) [228.309389] Starting dynamic subtest: pipe-A-eDP-1-size-128 [231.479583] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (3.182s) [231.542058] Starting dynamic subtest: pipe-A-eDP-1-size-256 [234.803645] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (3.271s) [235.157756] Starting dynamic subtest: pipe-B-eDP-1-size-64 [239.191564] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (4.048s) [239.256263] Starting dynamic subtest: pipe-B-eDP-1-size-128 [242.495579] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (3.249s) [242.557400] Starting dynamic subtest: pipe-B-eDP-1-size-256 [245.785635] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (3.237s) [246.151939] Starting dynamic subtest: pipe-C-eDP-1-size-64 [249.710633] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (3.572s) [249.746844] Starting dynamic subtest: pipe-C-eDP-1-size-128 [252.532142] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.790s) [252.574235] Starting dynamic subtest: pipe-C-eDP-1-size-256 [255.343088] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.779s) [255.678490] Starting dynamic subtest: pipe-D-eDP-1-size-64 [259.246071] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (3.581s) [259.286595] Starting dynamic subtest: pipe-D-eDP-1-size-128 [262.045908] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.768s) [262.087780] Starting dynamic subtest: pipe-D-eDP-1-size-256 [264.824772] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.746s) [265.148521] Subtest overlay: SUCCESS (40.110s) [266.046057] [051/139] (740s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc) [266.128930] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc [266.133716] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s) [266.217187] [052/139] (739s left) syncobj_timeline (invalid-transfer-illegal-handle) [266.281164] Starting subtest: invalid-transfer-illegal-handle [266.283448] Subtest invalid-transfer-illegal-handle: SUCCESS (0.000s) [266.348859] [053/139] (739s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-blt) [266.409843] Starting subtest: psr-1p-offscren-pri-indfb-draw-blt [271.182964] Subtest psr-1p-offscren-pri-indfb-draw-blt: SUCCESS (4.777s) [271.303392] [054/139] (734s left) perf (gen12-group-exclusive-stream-sample-oa) [271.399750] Starting subtest: gen12-group-exclusive-stream-sample-oa [271.418252] Subtest gen12-group-exclusive-stream-sample-oa: SUCCESS (0.017s) [271.439152] [055/139] (734s left) syncobj_timeline (single-wait-all-signaled) [271.484337] Starting subtest: single-wait-all-signaled [271.486895] Subtest single-wait-all-signaled: SUCCESS (0.000s) [271.539399] [056/139] (734s left) perf_pmu (semaphore-wait-idle) [271.590658] Starting subtest: semaphore-wait-idle [271.593224] Starting dynamic subtest: rcs0 [272.104774] Dynamic subtest rcs0: SUCCESS (0.514s) [272.119882] Starting dynamic subtest: bcs0 [272.618457] Dynamic subtest bcs0: SUCCESS (0.512s) [272.633431] Starting dynamic subtest: vcs0 [273.131425] Dynamic subtest vcs0: SUCCESS (0.511s) [273.146216] Starting dynamic subtest: vcs1 [273.645505] Dynamic subtest vcs1: SUCCESS (0.512s) [273.660565] Starting dynamic subtest: vecs0 [274.158634] Dynamic subtest vecs0: SUCCESS (0.512s) [274.173688] Starting dynamic subtest: ccs0 [274.671553] Dynamic subtest ccs0: SUCCESS (0.512s) [274.674451] Subtest semaphore-wait-idle: SUCCESS (3.082s) [274.781371] [057/139] (731s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-blt) [274.842038] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-blt [274.846630] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s) [274.930536] [058/139] (731s left) gem_partial_pwrite_pread (reads-display) [274.981284] Subtest reads-display: SKIP (0.000s) [275.021345] [059/139] (731s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip) [275.085122] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip [275.089725] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s) [275.160310] [060/139] (731s left) gem_mmap (bad-object) [275.210345] Subtest bad-object: SKIP (0.000s) [275.237091] [061/139] (731s left) kms_big_fb (x-tiled-16bpp-rotate-90) [275.323355] Starting subtest: x-tiled-16bpp-rotate-90 [275.342065] Subtest x-tiled-16bpp-rotate-90: SKIP (0.019s) [275.437750] [062/139] (731s left) prime_mmap (test_userptr) [275.479738] Starting subtest: test_userptr [275.481952] Starting dynamic subtest: test_userptr-smem [275.484314] Dynamic subtest test_userptr-smem: SUCCESS (0.000s) [275.486666] Subtest test_userptr: SUCCESS (0.001s) [275.568230] [063/139] (730s left) gem_render_copy (linear-to-vebox-yf-tiled) [275.628923] Starting subtest: linear-to-vebox-yf-tiled [275.633679] Subtest linear-to-vebox-yf-tiled: SKIP (0.000s) [275.809670] [064/139] (730s left) gem_mmap_offset (bad-flags) [275.868787] Starting subtest: bad-flags [275.871155] Subtest bad-flags: SUCCESS (0.000s) [275.941133] [065/139] (730s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-render) [276.000452] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-render [276.005247] Subtest psr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s) [276.086461] [066/139] (730s left) kms_flip (dpms-off-confusion) [276.158624] Starting subtest: dpms-off-confusion [276.161050] Starting dynamic subtest: A-eDP1 [284.953451] Dynamic subtest A-eDP1: SUCCESS (8.760s) [284.968814] Starting dynamic subtest: B-eDP1 [293.719608] Dynamic subtest B-eDP1: SUCCESS (8.764s) [293.727119] Starting dynamic subtest: C-eDP1 [302.443829] Dynamic subtest C-eDP1: SUCCESS (8.756s) [302.459089] Starting dynamic subtest: D-eDP1 [311.268511] Dynamic subtest D-eDP1: SUCCESS (8.778s) [311.270914] Subtest dpms-off-confusion: SUCCESS (35.078s) [312.513803] [067/139] (694s left) gem_ctx_persistence (legacy-engines-hostile) [312.564041] Starting subtest: legacy-engines-hostile [312.566822] Starting dynamic subtest: render [312.636332] Dynamic subtest render: SUCCESS (0.073s) [312.651036] Starting dynamic subtest: bsd1 [312.744584] Dynamic subtest bsd1: SUCCESS (0.106s) [312.759587] Starting dynamic subtest: bsd2 [312.830933] Dynamic subtest bsd2: SUCCESS (0.084s) [312.847040] Starting dynamic subtest: blt [312.916584] Dynamic subtest blt: SUCCESS (0.084s) [312.931574] Starting dynamic subtest: vebox [313.002398] Dynamic subtest vebox: SUCCESS (0.083s) [313.005319] Subtest legacy-engines-hostile: SUCCESS (0.439s) [313.074793] [068/139] (693s left) perf (non-zero-reason) [313.177524] Starting subtest: non-zero-reason [313.180294] Starting dynamic subtest: 0-rcs0 [319.585042] Dynamic subtest 0-rcs0: SUCCESS (6.405s) [319.596621] Starting dynamic subtest: 1-vecs0 [323.958408] Dynamic subtest 1-vecs0: SUCCESS (4.373s) [323.961279] Subtest non-zero-reason: SUCCESS (10.780s) [323.990308] [069/139] (682s left) core_hotunplug (unbind-rebind) [324.064984] Starting subtest: unbind-rebind [328.201354] Subtest unbind-rebind: SUCCESS (4.135s) [328.228522] [070/139] (678s left) kms_cursor_edge_walk (256x256-top-edge) [329.480697] Starting subtest: 256x256-top-edge [329.485193] Starting dynamic subtest: pipe-A-eDP-1 [332.994283] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.512s) [333.014173] Starting dynamic subtest: pipe-D-eDP-1 [336.230379] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.233s) [336.233124] Subtest 256x256-top-edge: SUCCESS (6.751s) [337.153564] [071/139] (669s left) gem_mmap_gtt (basic-short) [337.217765] Subtest basic-short: SKIP (0.000s) [337.242346] [072/139] (669s left) drm_read (invalid-buffer) [337.385230] Starting subtest: invalid-buffer [337.387720] Subtest invalid-buffer: SUCCESS (0.000s) [337.488575] [073/139] (669s left) kms_psr (cursor_mmap_gtt) [337.534518] Starting subtest: cursor_mmap_gtt [339.050580] Subtest cursor_mmap_gtt: SKIP (1.513s) [339.215223] [074/139] (667s left) gem_ctx_persistence (heartbeat-many) [339.266505] Starting subtest: heartbeat-many [339.271603] Subtest heartbeat-many: SKIP (0.000s) [339.332205] [075/139] (667s left) gem_exec_balancer (bonded-dual) [339.384713] Starting subtest: bonded-dual [339.389397] Subtest bonded-dual: SKIP (0.000s) [339.618439] [076/139] (667s left) gem_pxp (fail-invalid-protected-context) [342.234042] Subtest fail-invalid-protected-context: SKIP (0.000s) [342.267637] [077/139] (664s left) sw_sync (timeline_closed) [342.320725] Starting subtest: timeline_closed [342.323149] Subtest timeline_closed: SUCCESS (0.000s) [342.372275] [078/139] (664s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu) [342.444513] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu [347.112095] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (4.670s) [347.211442] [079/139] (659s left) kms_ccs (pipe-c-crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc) [347.621209] Starting subtest: pipe-C-crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc [347.626926] Subtest pipe-C-crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.000s) [348.480803] [080/139] (658s left) gem_pread (snoop) [348.540132] Subtest snoop: SKIP (0.000s) [348.563742] [081/139] (658s left) kms_ccs (pipe-a-crc-primary-basic-4-tiled-dg2-mc-ccs) [348.914720] Starting subtest: pipe-A-crc-primary-basic-4-tiled-dg2-mc-ccs [348.920045] Subtest pipe-A-crc-primary-basic-4-tiled-dg2-mc-ccs: SKIP (0.000s) [349.794991] [082/139] (657s left) gem_partial_pwrite_pread (write-uncached) [349.868153] Subtest write-uncached: SKIP (0.000s) [349.934796] [083/139] (657s left) gem_exec_reloc (basic-active) [349.984976] Subtest basic-active: SKIP (0.000s) [350.049372] [084/139] (656s left) gem_tiled_blits (basic) [350.097578] Subtest basic: SKIP (0.000s) [350.136774] [085/139] (656s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-render) [350.201498] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-render [354.980990] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-render: SUCCESS (4.785s) [355.113369] [086/139] (651s left) gem_exec_schedule (u-semaphore-codependency) [355.193174] Starting subtest: u-semaphore-codependency [355.214504] Subtest u-semaphore-codependency: SUCCESS (0.029s) [355.541935] [087/139] (651s left) perf_pmu (all-busy-check-all) [355.598443] Starting subtest: all-busy-check-all [357.446334] Subtest all-busy-check-all: SUCCESS (1.847s) [357.518888] [088/139] (649s left) core_getclient [357.615075] [089/139] (649s left) kms_psr2_sf (cursor-plane-move-continuous-sf) [362.864515] Starting subtest: cursor-plane-move-continuous-sf [362.867221] Starting dynamic subtest: pipe-A-eDP-1 [374.055442] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.188s) [374.068723] Starting dynamic subtest: pipe-B-eDP-1 [385.215626] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.157s) [385.218147] Subtest cursor-plane-move-continuous-sf: SUCCESS (22.352s) [386.114400] [090/139] (621s left) kms_plane_scaling (plane-upscale-factor-0-25-with-pixel-format) [386.192724] Starting subtest: plane-upscale-factor-0-25-with-pixel-format [386.194961] Starting dynamic subtest: pipe-A-eDP-1 [388.028459] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.837s) [388.039133] Starting dynamic subtest: pipe-B-eDP-1 [391.023967] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.993s) [391.044446] Starting dynamic subtest: pipe-C-eDP-1 [392.355433] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.326s) [392.374907] Starting dynamic subtest: pipe-D-eDP-1 [393.744069] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.386s) [393.746624] Subtest plane-upscale-factor-0-25-with-pixel-format: SUCCESS (7.554s) [395.010343] [091/139] (612s left) kms_hdr (static-toggle-suspend) [395.066580] Starting subtest: static-toggle-suspend [395.070499] Subtest static-toggle-suspend: SKIP (0.000s) [395.113935] [092/139] (612s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt) [395.180648] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt [395.184674] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [395.275716] [093/139] (611s left) kms_frontbuffer_tracking (plane-fbc-rte) [395.347420] Starting subtest: plane-fbc-rte [395.352103] Subtest plane-fbc-rte: SKIP (0.000s) [395.423723] [094/139] (611s left) kms_plane (plane-position-hole-dpms) [395.515590] Starting subtest: plane-position-hole-dpms [395.517598] Starting dynamic subtest: pipe-A [406.273478] Dynamic subtest pipe-A: SUCCESS (10.760s) [406.289897] Starting dynamic subtest: pipe-B [417.790068] Dynamic subtest pipe-B: SUCCESS (11.515s) [417.792693] Subtest plane-position-hole-dpms: SUCCESS (22.278s) [418.642972] [095/139] (588s left) gen9_exec_parse (shadow-peek) [418.686546] Subtest shadow-peek: SKIP (0.000s) [418.712644] [096/139] (588s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-move) [418.780887] Starting subtest: psr-1p-primscrn-spr-indfb-move [424.450449] Subtest psr-1p-primscrn-spr-indfb-move: SUCCESS (5.673s) [424.568809] [097/139] (582s left) kms_ccs (pipe-c-bad-pixel-format-y-tiled-gen12-rc-ccs-cc) [424.971237] Starting subtest: pipe-C-bad-pixel-format-y-tiled-gen12-rc-ccs-cc [424.976802] Subtest pipe-C-bad-pixel-format-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s) [425.852805] [098/139] (581s left) kms_rotation_crc (primary-yf-tiled-reflect-x-0) [425.918051] Starting subtest: primary-yf-tiled-reflect-x-0 [425.977257] Subtest primary-yf-tiled-reflect-x-0: SKIP (0.059s) [426.066194] [099/139] (581s left) gem_reg_read (bad-register) [426.128782] Starting subtest: bad-register [426.130757] Subtest bad-register: SUCCESS (0.000s) [426.183047] [100/139] (581s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180) [426.258517] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180 [426.262471] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s) [426.347549] [101/139] (581s left) v3d/v3d_submit_csd (single-in-sync) [426.434845] Subtest single-in-sync: SKIP (0.000s) [426.477459] [102/139] (581s left) gem_exec_schedule (pi-common) [426.639183] Starting subtest: pi-common [426.641845] Starting dynamic subtest: rcs0 [426.721523] Dynamic subtest rcs0: SUCCESS (0.085s) [426.748990] Starting dynamic subtest: bcs0 [426.802485] Dynamic subtest bcs0: SUCCESS (0.079s) [426.828761] Starting dynamic subtest: vcs0 [426.885903] Dynamic subtest vcs0: SUCCESS (0.082s) [426.913163] Starting dynamic subtest: vcs1 [426.969569] Dynamic subtest vcs1: SUCCESS (0.082s) [426.993514] Starting dynamic subtest: vecs0 [427.053750] Dynamic subtest vecs0: SUCCESS (0.083s) [427.078352] Starting dynamic subtest: ccs0 [427.137264] Dynamic subtest ccs0: SUCCESS (0.081s) [427.140189] Subtest pi-common: SUCCESS (0.501s) [427.359708] [103/139] (580s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-async-flip) [427.470736] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-async-flip [428.173399] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.704s) [428.360434] [104/139] (579s left) kms_flip (plain-flip-fb-recreate) [428.428793] Starting subtest: plain-flip-fb-recreate [428.434943] Starting dynamic subtest: A-eDP1 [437.361511] Dynamic subtest A-eDP1: SUCCESS (8.929s) [437.376872] Starting dynamic subtest: B-eDP1 [446.322959] Dynamic subtest B-eDP1: SUCCESS (8.960s) [446.340082] Starting dynamic subtest: C-eDP1 [455.282121] Dynamic subtest C-eDP1: SUCCESS (8.958s) [455.297078] Starting dynamic subtest: D-eDP1 [464.232540] Dynamic subtest D-eDP1: SUCCESS (8.949s) [464.239659] Subtest plain-flip-fb-recreate: SUCCESS (35.813s) [465.537938] [105/139] (542s left) kms_ccs (pipe-a-random-ccs-data-4-tiled-dg2-rc-ccs) [465.894147] Starting subtest: pipe-A-random-ccs-data-4-tiled-dg2-rc-ccs [465.902144] Subtest pipe-A-random-ccs-data-4-tiled-dg2-rc-ccs: SKIP (0.000s) [466.814222] [106/139] (540s left) api_intel_allocator (reopen) [466.868338] Starting subtest: reopen [466.870971] Subtest reopen: SUCCESS (0.004s) [466.962533] [107/139] (540s left) syncobj_timeline (etime-single-wait-submitted) [467.022172] Starting subtest: etime-single-wait-submitted [467.122324] Subtest etime-single-wait-submitted: SUCCESS (0.102s) [467.190529] [108/139] (540s left) v3d/v3d_perfmon (get-values-invalid-pointer) [467.265250] Subtest get-values-invalid-pointer: SKIP (0.000s) [467.312314] [109/139] (540s left) gem_exec_capture (capture) [467.371379] Starting subtest: capture [467.373454] Starting dynamic subtest: rcs0-smem [468.223075] Dynamic subtest rcs0-smem: SUCCESS (0.849s) [468.229961] Starting dynamic subtest: bcs0-smem [469.558880] Dynamic subtest bcs0-smem: SUCCESS (1.337s) [469.567778] Starting dynamic subtest: vcs0-smem [470.392579] Dynamic subtest vcs0-smem: SUCCESS (0.836s) [470.399177] Starting dynamic subtest: vcs1-smem [471.243372] Dynamic subtest vcs1-smem: SUCCESS (0.847s) [471.249503] Starting dynamic subtest: vecs0-smem [472.567707] Dynamic subtest vecs0-smem: SUCCESS (1.327s) [472.574320] Starting dynamic subtest: ccs0-smem [473.399434] Dynamic subtest ccs0-smem: SUCCESS (0.828s) [473.401754] Subtest capture: SUCCESS (6.027s) [473.500794] [110/139] (534s left) kms_ccs (pipe-b-missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc) [473.896086] Starting subtest: pipe-B-missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc [473.902051] Subtest pipe-B-missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s) [474.747336] [111/139] (533s left) fbdev (unaligned-write) [474.812772] Starting subtest: unaligned-write [474.918978] Subtest unaligned-write: SUCCESS (0.105s) [474.966979] [112/139] (532s left) kms_atomic (plane-cursor-legacy) [475.043233] Starting subtest: plane-cursor-legacy [475.046250] Starting dynamic subtest: pipe-A-eDP-1 [475.440854] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.396s) [475.443560] Subtest plane-cursor-legacy: SUCCESS (0.398s) [476.353165] [113/139] (531s left) gem_spin_batch (legacy-resubmit-new) [476.416330] Starting subtest: legacy-resubmit-new [476.421503] Starting dynamic subtest: default [476.423847] Dynamic subtest default: SUCCESS (0.007s) [476.441342] Starting dynamic subtest: render [476.444269] Dynamic subtest render: SUCCESS (0.002s) [476.458353] Starting dynamic subtest: bsd [476.460898] Dynamic subtest bsd: SUCCESS (0.004s) [476.474763] Starting dynamic subtest: bsd1 [476.477180] Dynamic subtest bsd1: SUCCESS (0.004s) [476.488554] Starting dynamic subtest: bsd2 [476.490854] Dynamic subtest bsd2: SUCCESS (0.003s) [476.499821] Starting dynamic subtest: blt [476.502139] Dynamic subtest blt: SUCCESS (0.006s) [476.509986] Starting dynamic subtest: vebox [476.512315] Dynamic subtest vebox: SUCCESS (0.003s) [476.514601] Subtest legacy-resubmit-new: SUCCESS (0.034s) [478.503261] [114/139] (529s left) gem_shrink (reclaim) [478.560967] Starting subtest: reclaim [480.833161] Subtest reclaim: SUCCESS (2.274s) [480.935955] [115/139] (526s left) kms_ccs (pipe-c-random-ccs-data-4-tiled-dg2-mc-ccs) [481.313908] Starting subtest: pipe-C-random-ccs-data-4-tiled-dg2-mc-ccs [481.321721] Subtest pipe-C-random-ccs-data-4-tiled-dg2-mc-ccs: SKIP (0.000s) [482.178153] [116/139] (525s left) gem_exec_balancer (invalid-balancer) [482.231480] Starting subtest: invalid-balancer [482.833093] Subtest invalid-balancer: SUCCESS (0.601s) [483.081907] [117/139] (524s left) gem_exec_schedule (u-semaphore-user) [483.153934] Starting subtest: u-semaphore-user [483.160390] Subtest u-semaphore-user: SUCCESS (0.020s) [484.522247] [118/139] (523s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc) [484.588773] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc [484.592731] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [484.677082] [119/139] (523s left) gem_ctx_create (basic-files) [484.745766] Starting subtest: basic-files [486.967333] Subtest basic-files: SUCCESS (2.221s) [488.489747] [120/139] (519s left) gem_wait (write-busy) [488.549305] Starting subtest: write-busy [488.552166] Starting dynamic subtest: all-engines [489.138482] Dynamic subtest all-engines: SUCCESS (0.590s) [489.162639] Starting dynamic subtest: rcs0 [489.694737] Dynamic subtest rcs0: SUCCESS (0.554s) [489.721565] Starting dynamic subtest: bcs0 [490.248816] Dynamic subtest bcs0: SUCCESS (0.553s) [490.273352] Starting dynamic subtest: vcs0 [491.462477] Dynamic subtest vcs0: SUCCESS (1.211s) [491.488161] Starting dynamic subtest: vcs1 [493.445761] Dynamic subtest vcs1: SUCCESS (1.983s) [493.471524] Starting dynamic subtest: vecs0 [495.506778] Dynamic subtest vecs0: SUCCESS (2.047s) [495.519264] Starting dynamic subtest: ccs0 [496.047414] Dynamic subtest ccs0: SUCCESS (0.553s) [496.050050] Subtest write-busy: SUCCESS (7.499s) [496.254107] [121/139] (511s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size) [496.321239] Starting subtest: cursorB-vs-flipB-varying-size [496.326089] Subtest cursorB-vs-flipB-varying-size: SKIP (0.002s) [496.469172] [122/139] (511s left) gem_softpin (invalid) [496.530893] Starting subtest: invalid [496.538765] Subtest invalid: SUCCESS (0.005s) [496.649341] [123/139] (511s left) perf (oa-formats) [496.764504] Starting subtest: oa-formats [496.766859] Starting dynamic subtest: 0-rcs0 [496.774703] Dynamic subtest 0-rcs0: SUCCESS (0.013s) [496.791030] Starting dynamic subtest: 1-vecs0 [496.808724] Dynamic subtest 1-vecs0: SUCCESS (0.033s) [496.811342] Subtest oa-formats: SUCCESS (0.048s) [496.850427] [124/139] (511s left) kms_vblank (ts-continuation-modeset) [496.902342] Starting subtest: ts-continuation-modeset [496.904723] Starting dynamic subtest: pipe-A-eDP-1 [498.518895] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.617s) [498.536785] Starting dynamic subtest: pipe-D-eDP-1 [500.916446] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.390s) [500.919067] Subtest ts-continuation-modeset: SUCCESS (4.015s) [501.826578] [125/139] (506s left) kms_chamelium_edid (dp-edid-stress-resolution-non-4k) [502.206034] Subtest dp-edid-stress-resolution-non-4k: SKIP (0.000s) [503.078203] [126/139] (505s left) gem_tiled_blits (normal) [503.146774] Subtest normal: SKIP (0.000s) [503.183293] [127/139] (505s left) gem_lmem_swapping (random-engines) [503.251695] Subtest random-engines: SKIP (0.000s) [503.280935] [128/139] (504s left) kms_flip (2x-busy-flip) [503.349163] Starting subtest: 2x-busy-flip [503.368141] Subtest 2x-busy-flip: SKIP (0.021s) [503.440398] [129/139] (504s left) kms_ccs (pipe-b-crc-primary-basic-y-tiled-ccs) [503.811722] Starting subtest: pipe-B-crc-primary-basic-y-tiled-ccs [503.819668] Subtest pipe-B-crc-primary-basic-y-tiled-ccs: SKIP (0.000s) [504.708035] [130/139] (503s left) kms_ccs (pipe-d-missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc) [505.141760] Starting subtest: pipe-D-missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc [505.147546] Subtest pipe-D-missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s) [505.986273] [131/139] (502s left) kms_cursor_crc (cursor-onscreen-32x10) [506.131522] Starting subtest: cursor-onscreen-32x10 [506.175579] Subtest cursor-onscreen-32x10: SKIP (0.039s) [506.406076] [132/139] (501s left) gem_exec_reloc (basic-wc-cpu) [506.455908] Subtest basic-wc-cpu: SKIP (0.000s) [506.507629] [133/139] (501s left) intel_hwmon (hwmon-write) [506.581173] Subtest hwmon-write: SKIP (0.000s) [506.608206] [134/139] (501s left) kms_big_fb (y-tiled-16bpp-rotate-180) [506.663957] Starting subtest: y-tiled-16bpp-rotate-180 [506.668688] Subtest y-tiled-16bpp-rotate-180: SKIP (0.000s) [506.745907] [135/139] (501s left) syncobj_eventfd (binary-wait-before-signal) [506.791221] Starting subtest: binary-wait-before-signal [506.821563] Subtest binary-wait-before-signal: SUCCESS (0.031s) [506.892263] [136/139] (501s left) vc4/vc4_perfmon (create-two-perfmon) [506.964610] Subtest create-two-perfmon: SKIP (0.000s) [507.005628] [137/139] (501s left) gem_exec_fence (invalid-fence-array) [507.441495] Starting subtest: invalid-fence-array [507.444453] Subtest invalid-fence-array: SUCCESS (0.002s) [508.535705] [138/139] (499s left) gem_exec_reloc (basic-cpu-active) [508.610710] Subtest basic-cpu-active: SKIP (0.000s) [508.672431] [139/139] (499s left) kms_flip_event_leak (basic) [508.721026] Starting subtest: basic [508.723373] Starting dynamic subtest: pipe-A-eDP-1 [509.173773] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.451s) [509.188041] Starting dynamic subtest: pipe-B-eDP-1 [510.402444] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.225s) [510.421453] Starting dynamic subtest: pipe-C-eDP-1 [511.607288] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.201s) [511.624373] Starting dynamic subtest: pipe-D-eDP-1 [512.811360] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.204s) [512.813884] Subtest basic: SUCCESS (4.091s) [513.727100] Closing watchdogs Done.