func-arm-aspeed_ast2500 test occasionally times out
I very occasionally see this timeout when running "make check-functional" locally:
qemu:func-thorough+func-arm-thorough+thorough / func-arm-aspeed_ast2500 time out (After 720.0 seconds)
53/63 qemu:func-thorough+func-arm-thorough+thorough / func-arm-aspeed_ast2500 TIMEOUT 720.01s killed by signal 15 SIGTERM
>>> QEMU_BUILD_ROOT=/mnt/nvmedisk/linaro/qemu-from-laptop/qemu/build/arm-clang MSAN_OPTIONS=halt_on_error=1:abort_on_error=1:print_summary=1:print_stacktrace=1 RUST_BACKTRACE=1 LD_LIBRARY_PATH=/mnt/nvmedisk/linaro/qemu-from-laptop/qemu/build/arm-clang/tests/tcg/plugins:/mnt/nvmedisk/linaro/qemu-from-laptop/qemu/build/arm-clang/contrib/plugins QEMU_TEST_QEMU_BINARY=/mnt/nvmedisk/linaro/qemu-from-laptop/qemu/build/arm-clang/qemu-system-arm MESON_TEST_ITERATION=1 ASAN_OPTIONS=halt_on_error=1:abort_on_error=1:print_summary=1 PYTHONPATH=/mnt/nvmedisk/linaro/qemu-from-laptop/qemu/python:/mnt/nvmedisk/linaro/qemu-from-laptop/qemu/tests/functional MALLOC_PERTURB_=67 UBSAN_OPTIONS=halt_on_error=1:abort_on_error=1:print_summary=1:print_stacktrace=1 G_TEST_SLOW=1 QEMU_TEST_QEMU_IMG=/mnt/nvmedisk/linaro/qemu-from-laptop/qemu/build/arm-clang/qemu-img /mnt/nvmedisk/linaro/qemu-from-laptop/qemu/build/arm-clang/pyvenv/bin/python3 /mnt/nvmedisk/linaro/qemu-from-laptop/qemu/tests/functional/arm/test_aspeed_ast2500.py
―――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――― ✀ ―――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――
stderr:
/mnt/nvmedisk/linaro/qemu-from-laptop/qemu/python/qemu/qmp/legacy.py:89: DeprecationWarning: There is no current event loop
self._aloop = asyncio.get_event_loop()
―――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――
The test log says it's waiting for the string "ast2500-default login:", but the console output has that mixed in with other startup messages so it didn't match:
2025-09-16 13:39:42,455: [ OK ] Started Adc Sensor.
2025-09-16 13:39:42,976: [ OK ] Started Fan Sensor.
2025-09-16 13:39:43,635: [ OK ] Started Phosphor-Pid-Control Margin-based Fan Control Daemon.
2025-09-16 13:39:44,155: [ OK ] Started Hwmon Temp Sensor.
2025-09-16 13:39:44,725: [ OK ] Started Intrusion Sensor.
2025-09-16 13:39:48,741: [ OK ] Finished Wait for /xyz/openbmc_project/control/host0/boot/one_time.
2025-09-16 13:39:48,906: [ OK ] Finished Wait for /xyz/openbmc_project/control/host0/boot.
2025-09-16 13:39:49,141:
2025-09-16 13:39:49,142: Phosphor OpenBMC (Phosphor OpenBMC Project Reference Distro) nodistro.0 ast2500-default ttyS4
2025-09-16 13:39:49,142:
2025-09-16 13:39:49,151: ast2500-default[ OK ] Finished Wait for /xyz/openbmc_pro…control/host0/power_restore_policy.
2025-09-16 13:39:49,376: login: [ OK ] Finished Wait for /xyz/openbmc_project/control/host0/restriction_mode.
2025-09-16 13:39:49,579: [ OK ] Finished Wait for /xyz/openbmc_project/led/groups/bmc_booted.
2025-09-16 13:39:49,751: [ OK ] Finished Wait for /xyz/openbmc_project/led/groups.
2025-09-16 13:39:49,948: [ OK ] Finished Wait for /xyz/openbmc_project/time/sync_method.
2025-09-16 13:39:50,251: [FAILED] Failed to start Phosphor IPMI BT DBus Bridge.
2025-09-16 13:39:50,252: See 'systemctl status org.openbmc.HostIpmi.service' for details.
2025-09-16 13:39:50,557: [FAILED] Failed to start Intel Power Control for the Host 0.
2025-09-16 13:39:50,559: See 'systemctl status xyz.openbmc_proje…s.Control.Power@0.service' for details.