Sep 16 20:13:17 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 16 20:14:40 volumio dhcpcd[727]: eth0: carrier acquired Sep 16 20:14:40 volumio kernel: r8169 0000:01:00.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx Sep 16 20:14:40 volumio dhcpcd[727]: eth0: IAID 0e:5a:a3:02 Sep 16 20:14:40 volumio dhcpcd[727]: eth0: soliciting an IPv6 router Sep 16 20:14:41 volumio dhcpcd[727]: eth0: rebinding lease of 192.168.10.100 Sep 16 20:14:41 volumio ifplugd(eth0)[1141]: Link beat detected. Sep 16 20:14:41 volumio dhcpcd[727]: eth0: NAK: REQUEST for invalid Requested IP Address from 192.168.1.1 Sep 16 20:14:41 volumio dhcpcd[727]: eth0: message: REQUEST for invalid Requested IP Address Sep 16 20:14:41 volumio dhcpcd[727]: eth0: soliciting a DHCP lease Sep 16 20:14:41 volumio ifplugd(eth0)[1141]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Sep 16 20:14:41 volumio dhcpcd[727]: eth0: offered 192.168.1.47 from 192.168.1.1 Sep 16 20:14:41 volumio dhcpcd[727]: control command: dhcpcd eth0 Sep 16 20:14:41 volumio ifplugd(eth0)[1141]: client: sending commands to dhcpcd process Sep 16 20:14:41 volumio dhcpcd[727]: control_free: No such file or directory Sep 16 20:14:41 volumio dhcpcd[727]: eth0: probing address 192.168.1.47/24 Sep 16 20:14:41 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 16 20:14:41 volumio dbus-daemon[670]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.21' (uid=0 pid=2581 comm="timedatectl show --property=NTPSynchronized --valu") Sep 16 20:14:41 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 16 20:14:42 volumio ifplugd(eth0)[1141]: Program executed successfully. Sep 16 20:14:42 volumio dbus-daemon[670]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 16 20:14:42 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 16 20:14:42 volumio setdatetime-helper.sh[2579]: Time is already synchronized. Sep 16 20:14:42 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 16 20:14:42 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 16 20:14:46 volumio dhcpcd[727]: eth0: leased 192.168.1.47 for 86400 seconds Sep 16 20:14:46 volumio dhcpcd[727]: eth0: adding route to 192.168.1.0/24 Sep 16 20:14:46 volumio dhcpcd[727]: eth0: adding default route via 192.168.1.1 Sep 16 20:14:46 volumio avahi-daemon[669]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.47. Sep 16 20:14:46 volumio avahi-daemon[669]: New relevant interface eth0.IPv4 for mDNS. Sep 16 20:14:46 volumio avahi-daemon[669]: Registering new address record for 192.168.1.47 on eth0.IPv4. Sep 16 20:14:46 volumio systemd[1]: welcome.service: Deactivated successfully. Sep 16 20:14:46 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Sep 16 20:14:46 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Sep 16 20:14:46 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Sep 16 20:14:46 volumio welcome[2614]: Resolved ip:[1] 192.168.1.47 Sep 16 20:14:46 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Sep 16 20:14:46 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Sep 16 20:14:46 volumio volumio[868]: info: Volumio Network Manager: Network status updated: 1 Sep 16 20:14:47 volumio volumio[868]: info: Discovery: this is already registered, e96098ba-8029-4c1e-a7c4-1317063a7090 Sep 16 20:14:47 volumio volumio[868]: info: Discovery: Found device Volumio Sep 16 20:14:47 volumio volumio[868]: info: CoreCommandRouter::volumioGetState Sep 16 20:14:47 volumio volumio[868]: info: CorePlayQueue::getTrack 0 Sep 16 20:14:48 volumio ntpd[1128]: IO: Listen normally on 3 eth0 192.168.1.47:123 Sep 16 20:14:48 volumio ntpd[1128]: IO: new interface(s) found: waking up resolver Sep 16 20:14:48 volumio ntpd[1128]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Sep 16 20:14:48 volumio ntpd[1128]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Sep 16 20:14:48 volumio ntpd[1128]: DNS: Pool taking: 46.174.178.150 Sep 16 20:14:48 volumio ntpd[1128]: DNS: Pool taking: 212.127.95.218 Sep 16 20:14:48 volumio ntpd[1128]: DNS: Pool taking: 213.222.217.10 Sep 16 20:14:48 volumio ntpd[1128]: DNS: Pool taking: 193.25.222.136 Sep 16 20:14:48 volumio ntpd[1128]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Sep 16 20:14:49 volumio ntpd[1128]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Sep 16 20:14:49 volumio ntpd[1128]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Sep 16 20:14:49 volumio ntpd[1128]: DNS: Pool taking: 156.17.20.254 Sep 16 20:14:49 volumio ntpd[1128]: DNS: Pool taking: 178.215.228.24 Sep 16 20:14:49 volumio ntpd[1128]: DNS: Pool taking: 77.237.25.197 Sep 16 20:14:49 volumio ntpd[1128]: DNS: Pool taking: 162.159.200.123 Sep 16 20:14:49 volumio ntpd[1128]: DNS: Pool taking: 2a0d:5440::24 Sep 16 20:14:49 volumio ntpd[1128]: DNS: Pool taking: 2606:4700:f1::123 Sep 16 20:14:49 volumio ntpd[1128]: DNS: Pool taking: 2606:4700:f1::1 Sep 16 20:14:49 volumio ntpd[1128]: DNS: Pool taking: 2001:41d0:601:1100::649c Sep 16 20:14:49 volumio ntpd[1128]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 16 20:14:49 volumio volumio[868]: info: Adding plugin bluetooth to MyMusic Plugins Sep 16 20:14:49 volumio volumio[868]: info: Adding plugin multiroom to MyMusic Plugins Sep 16 20:14:49 volumio volumio[868]: info: Adding plugin metavolumio to MyMusic Plugins Sep 16 20:14:49 volumio volumio[868]: info: Adding plugin cd_controller to MyMusic Plugins Sep 16 20:14:49 volumio volumio[868]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 16 20:14:49 volumio volumio[868]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 16 20:14:49 volumio volumio[868]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 16 20:14:49 volumio volumio[868]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 16 20:14:50 volumio ntpd[1128]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Sep 16 20:14:50 volumio ntpd[1128]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Sep 16 20:14:50 volumio ntpd[1128]: DNS: Pool taking: 194.146.251.114 Sep 16 20:14:50 volumio ntpd[1128]: DNS: Pool taking: 192.86.14.67 Sep 16 20:14:50 volumio ntpd[1128]: DNS: Pool taking: 213.222.217.11 Sep 16 20:14:50 volumio ntpd[1128]: DNS: Pool taking: 194.146.251.100 Sep 16 20:14:50 volumio ntpd[1128]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Sep 16 20:14:50 volumio volumio[868]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 16 20:14:50 volumio volumio[868]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 16 20:14:50 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 20:14:50 volumio volumio[868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 20:14:50 volumio volumio[868]: info: Starting MyVolumio Remote Streaming Endpoints Sep 16 20:14:50 volumio volumio[868]: info: MyVolumio login type: Token Sep 16 20:14:50 volumio volumio[868]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 16 20:14:50 volumio volumio[868]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 16 20:14:51 volumio ntpd[1128]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Sep 16 20:14:51 volumio ntpd[1128]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Sep 16 20:14:51 volumio ntpd[1128]: DNS: Pool taking: 194.146.251.101 Sep 16 20:14:51 volumio ntpd[1128]: DNS: Pool taking: 46.175.224.7 Sep 16 20:14:51 volumio ntpd[1128]: DNS: Pool taking: 162.159.200.1 Sep 16 20:14:51 volumio ntpd[1128]: DNS: Pool taking: 212.127.78.21 Sep 16 20:14:51 volumio ntpd[1128]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Sep 16 20:14:51 volumio volumio[868]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 16 20:14:51 volumio volumio[868]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 16 20:14:51 volumio volumio[868]: info: Streaming services startup Sep 16 20:14:51 volumio volumio[868]: info: Starting Streaming Daemon Sep 16 20:14:51 volumio sudo[2653]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 16 20:14:51 volumio sudo[2653]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 20:14:51 volumio volumio[868]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 16 20:14:51 volumio sudo[2653]: pam_unix(sudo:session): session closed for user root Sep 16 20:14:51 volumio volumio[868]: error: Cannot start Volumio Streaming Daemon Sep 16 20:14:51 volumio volumio[868]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 16 20:14:51 volumio volumio[868]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 16 20:14:52 volumio volumio[868]: error: MyVolumio Custom Token format not valid, refreshing it Sep 16 20:14:52 volumio volumio[868]: info: MyVolumio login type: Token Sep 16 20:14:53 volumio volumio[868]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 16 20:14:53 volumio volumio[868]: info: MyVolumio token set successfully Sep 16 20:14:53 volumio volumio[868]: info: MYVOLUMIO: Adding device Sep 16 20:14:53 volumio volumio[868]: info: MYVOLUMIO: Evaluating Server Sep 16 20:14:54 volumio volumio[868]: info: MyVolumio Plan changed: premium Sep 16 20:14:54 volumio volumio[868]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Sep 16 20:14:54 volumio volumio[868]: info: Removing browser output: myVolumio user plan is not superstar Sep 16 20:14:54 volumio volumio[868]: info: Removing audio output: Sep 16 20:14:54 volumio volumio[868]: info: MYVOLUMIO: Adding device Sep 16 20:14:54 volumio volumio[868]: info: MYVOLUMIO: Evaluating Server Sep 16 20:14:54 volumio volumio[868]: info: Remote config written successfully Sep 16 20:14:54 volumio volumio[868]: info: Starting Tunnel 1 Sep 16 20:14:54 volumio volumio[868]: info: Starting Tunnel Connection Checker Sep 16 20:14:53 volumio ntpd[1128]: CLOCK: time stepped by -0.546682 Sep 16 20:14:53 volumio systemd-journald[487]: Time jumped backwards, rotating. Sep 16 20:14:53 volumio ntpd[1128]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Sep 16 20:14:53 volumio volumio[868]: error: Failed to ping endpoint us1.myvolumio.org : unknown error Sep 16 20:14:53 volumio volumio[868]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 20:14:53 volumio volumio[868]: Error: Unable to resolve or reject the same promise twice Sep 16 20:14:53 volumio volumio[868]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Sep 16 20:14:53 volumio volumio[868]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371) Sep 16 20:14:53 volumio volumio[868]: at Socket.emit (node:events:514:28) Sep 16 20:14:53 volumio volumio[868]: at addChunk (node:internal/streams/readable:343:12) Sep 16 20:14:53 volumio volumio[868]: at readableAddChunk (node:internal/streams/readable:316:9) Sep 16 20:14:53 volumio volumio[868]: at Readable.push (node:internal/streams/readable:253:10) Sep 16 20:14:53 volumio volumio[868]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Sep 16 20:14:53 volumio volumio[868]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 20:14:54 volumio sudo[2694]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-16 20:13' Sep 16 20:14:54 volumio sudo[2694]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="477d19cd2b4ebf4b4c802be78e00bbb657e83aea" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Aug 2 17:12:12 UTC 2025" VOLUMIO_VERSION="4.021" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="929ec80ad6e74b38757fdba2e7d66ff0"