Jun 07 12:43:33 volumio ntpd[929]: CLOCK: time stepped by 657790.072012 Jun 07 12:43:33 volumio ntpd[929]: CLOCK: time changed from 2025-05-30 to 2025-06-07 Jun 07 12:43:33 volumio ntpd[929]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jun 07 12:43:33 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Jun 07 12:43:33 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Jun 07 12:43:33 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Jun 07 12:43:33 volumio systemd[1]: e2scrub_all.service: Deactivated successfully. Jun 07 12:43:33 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Jun 07 12:43:33 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Jun 07 12:43:34 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Jun 07 12:43:34 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Jun 07 12:43:35 volumio nmbd[1003]: [2025/06/07 12:43:35.285781, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Jun 07 12:43:35 volumio nmbd[1003]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Jun 07 12:43:35 volumio nmbd[1003]: [2025/06/07 12:43:35.290377, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Jun 07 12:43:35 volumio nmbd[1003]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Jun 07 12:43:35 volumio nmbd[1003]: [2025/06/07 12:43:35.290829, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Jun 07 12:43:35 volumio nmbd[1003]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Jun 07 12:43:35 volumio nmbd[1003]: [2025/06/07 12:43:35.291110, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Jun 07 12:43:35 volumio nmbd[1003]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Jun 07 12:43:35 volumio nmbd[1003]: [2025/06/07 12:43:35.291185, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Jun 07 12:43:35 volumio nmbd[1003]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Jun 07 12:43:35 volumio nmbd[1003]: [2025/06/07 12:43:35.291240, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Jun 07 12:43:35 volumio nmbd[1003]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Jun 07 12:43:35 volumio nmbd[1003]: [2025/06/07 12:43:35.291300, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Jun 07 12:43:35 volumio nmbd[1003]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Jun 07 12:43:35 volumio nmbd[1003]: [2025/06/07 12:43:35.291350, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Jun 07 12:43:35 volumio nmbd[1003]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Jun 07 12:43:35 volumio nmbd[1003]: [2025/06/07 12:43:35.291402, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Jun 07 12:43:35 volumio nmbd[1003]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Jun 07 12:43:35 volumio nmbd[1003]: [2025/06/07 12:43:35.291450, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Jun 07 12:43:35 volumio nmbd[1003]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Jun 07 12:43:35 volumio nmbd[1003]: [2025/06/07 12:43:35.291501, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Jun 07 12:43:35 volumio nmbd[1003]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Jun 07 12:43:35 volumio nmbd[1003]: [2025/06/07 12:43:35.291549, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Jun 07 12:43:35 volumio nmbd[1003]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Jun 07 12:43:35 volumio volumio[1217]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 07 12:43:35 volumio volumio[1217]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 07 12:43:35 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 12:43:35 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 12:43:35 volumio volumio[1217]: info: Starting MyVolumio Remote Streaming Endpoints Jun 07 12:43:35 volumio volumio[1217]: info: MyVolumio login type: Token Jun 07 12:43:35 volumio volumio[1217]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 07 12:43:35 volumio volumio[1217]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 07 12:43:36 volumio volumio[1217]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 07 12:43:36 volumio volumio[1217]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 07 12:43:36 volumio volumio[1217]: info: Streaming services startup Jun 07 12:43:36 volumio volumio[1217]: info: Starting Streaming Daemon Jun 07 12:43:36 volumio volumio[1217]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 07 12:43:36 volumio sudo[2263]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 07 12:43:36 volumio sudo[2263]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 12:43:36 volumio sudo[2263]: pam_unix(sudo:session): session closed for user root Jun 07 12:43:36 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jun 07 12:43:36 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jun 07 12:43:36 volumio volumio[1217]: info: Refreshing Cached IP Addresses Jun 07 12:43:36 volumio sudo[2269]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jun 07 12:43:36 volumio sudo[2269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 12:43:36 volumio volumio[1217]: info: Volumio Network Manager: Network status updated: 2 Jun 07 12:43:36 volumio sudo[2269]: pam_unix(sudo:session): session closed for user root Jun 07 12:43:36 volumio sudo[2273]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 07 12:43:36 volumio sudo[2273]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 12:43:36 volumio volumio[1217]: error: Cannot start Volumio Streaming Daemon Jun 07 12:43:36 volumio volumio[1217]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 07 12:43:36 volumio volumio[1217]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 07 12:43:36 volumio sudo[2272]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 07 12:43:36 volumio sudo[2272]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 12:43:36 volumio sudo[2273]: pam_unix(sudo:session): session closed for user root Jun 07 12:43:37 volumio sudo[2272]: pam_unix(sudo:session): session closed for user root Jun 07 12:43:37 volumio systemd[1]: upmpdcli.service: Deactivated successfully. Jun 07 12:43:37 volumio systemd[1]: upmpdcli.service: Consumed 25.197s CPU time. Jun 07 12:43:37 volumio volumio[1217]: error: MyVolumio Custom Token format not valid, refreshing it Jun 07 12:43:39 volumio volumio[1217]: info: MyVolumio login type: Token Jun 07 12:43:39 volumio nmbd[1003]: [2025/06/07 12:43:39.309175, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Jun 07 12:43:39 volumio nmbd[1003]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Jun 07 12:43:45 volumio volumio[1217]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 07 12:43:46 volumio volumio[1217]: info: MyVolumio token set successfully Jun 07 12:43:46 volumio volumio[1217]: info: MYVOLUMIO: Adding device Jun 07 12:43:46 volumio volumio[1217]: info: MYVOLUMIO: Evaluating Server Jun 07 12:43:47 volumio sudo[2294]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 07 12:43:47 volumio sudo[2296]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 07 12:43:47 volumio sudo[2296]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 12:43:47 volumio sudo[2294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 12:43:47 volumio sudo[2296]: pam_unix(sudo:session): session closed for user root Jun 07 12:43:47 volumio sudo[2294]: pam_unix(sudo:session): session closed for user root Jun 07 12:43:47 volumio sudo[2300]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 07 12:43:47 volumio sudo[2300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 12:43:47 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 07 12:43:47 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 07 12:43:47 volumio sudo[2300]: pam_unix(sudo:session): session closed for user root Jun 07 12:43:47 volumio dbus-daemon[666]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.31' (uid=0 pid=2311 comm="timedatectl show --property=NTPSynchronized --valu") Jun 07 12:43:47 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 07 12:43:47 volumio dbus-daemon[666]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 07 12:43:47 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 07 12:43:47 volumio setdatetime-helper.sh[2304]: Time is not synchronized. Attempting to sync... Jun 07 12:43:47 volumio volumio[1217]: info: MyVolumio Plan changed: premium Jun 07 12:43:47 volumio volumio[1217]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jun 07 12:43:47 volumio volumio[1217]: info: Removing browser output: myVolumio user plan is not superstar Jun 07 12:43:47 volumio volumio[1217]: info: Removing audio output: Jun 07 12:43:47 volumio volumio[1217]: info: MYVOLUMIO: Adding device Jun 07 12:43:47 volumio volumio[1217]: info: MYVOLUMIO: Evaluating Server Jun 07 12:43:47 volumio volumio[1217]: info: Upmpdcli Daemon Started Jun 07 12:43:47 volumio volumio[1217]: info: Remote config written successfully Jun 07 12:43:47 volumio volumio[1217]: info: Starting Tunnel 1 Jun 07 12:43:47 volumio volumio[1217]: info: Starting Tunnel Connection Checker Jun 07 12:43:47 volumio upmpdcli[2341]: writing RSA key Jun 07 12:43:48 volumio sudo[2347]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sat, 07 Jun 2025 09:43:47 GMT#015' Jun 07 12:43:48 volumio sudo[2347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 07 12:43:47 volumio setdatetime-helper.sh[2348]: Sat Jun 7 12:43:47 MSK 2025 Jun 07 12:43:47 volumio setdatetime-helper.sh[2304]: Time synchronized successfully. Jun 07 12:43:47 volumio sudo[2347]: pam_unix(sudo:session): session closed for user root Jun 07 12:43:47 volumio systemd-journald[352]: Time jumped backwards, rotating. Jun 07 12:43:47 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 07 12:43:47 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 07 12:43:47 volumio volumio[1217]: info: MYVolumio Device enabled Jun 07 12:43:47 volumio volumio[1217]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jun 07 12:43:47 volumio volumio[1217]: info: MyVolumio status changed Jun 07 12:43:47 volumio volumio[1217]: info: Streaming services startup Jun 07 12:43:47 volumio volumio[1217]: info: Starting Streaming Daemon Jun 07 12:43:47 volumio sudo[2366]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 07 12:43:47 volumio sudo[2366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 12:43:47 volumio volumio[1217]: error: Failed to ping endpoint as2.myvolumio.org : unknown error Jun 07 12:43:47 volumio sudo[2366]: pam_unix(sudo:session): session closed for user root Jun 07 12:43:47 volumio volumio[1217]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 07 12:43:47 volumio volumio[1217]: Error: Unable to resolve or reject the same promise twice Jun 07 12:43:47 volumio volumio[1217]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jun 07 12:43:47 volumio volumio[1217]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371) Jun 07 12:43:47 volumio volumio[1217]: at Socket.emit (node:events:514:28) Jun 07 12:43:47 volumio volumio[1217]: at addChunk (node:internal/streams/readable:343:12) Jun 07 12:43:47 volumio volumio[1217]: at readableAddChunk (node:internal/streams/readable:316:9) Jun 07 12:43:47 volumio volumio[1217]: at Readable.push (node:internal/streams/readable:253:10) Jun 07 12:43:47 volumio volumio[1217]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Jun 07 12:43:47 volumio volumio[1217]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 07 12:43:49 volumio sudo[2382]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-07 12:42' Jun 07 12:43:49 volumio sudo[2382]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="fd567ba9bc0dc34c0403e2d5b72a0f46467d1983" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri May 23 08:02:06 UTC 2025" VOLUMIO_VERSION="0.069" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="76fa67e30b28261e1dc7c3a89e610b9e"