-- Logs begin at Sat 2025-06-07 11:17:41 CDT, end at Sat 2025-06-07 11:20:34 CDT. -- Jun 07 11:19:05 volumio volumio[1195]: info: Discovery: adding 9598635b-5a8e-49f7-8450-e661ff446931 Jun 07 11:19:05 volumio volumio[1195]: info: Discovery: Found device Volumio Jun 07 11:19:05 volumio volumio[1195]: info: CoreCommandRouter::volumioGetState Jun 07 11:19:05 volumio volumio[1195]: info: CorePlayQueue::getTrack 0 Jun 07 11:19:09 volumio ntpd[949]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 07 11:19:13 volumio volumio-time-update[656]: volumio-time-update-util: Date not found in response Jun 07 11:19:13 volumio volumio-time-update[656]: volumio-time-update-util: Retrying in 5 seconds... Jun 07 11:19:18 volumio volumio-time-update[656]: volumio-time-update-util: Fetching time from Volumio... Jun 07 11:19:29 volumio ntpd[949]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 07 11:19:38 volumio volumio-time-update[656]: volumio-time-update-util: Date not found in response Jun 07 11:19:38 volumio volumio-time-update[656]: volumio-time-update-util: Retrying in 5 seconds... Jun 07 11:19:43 volumio volumio-time-update[656]: volumio-time-update-util: Fetching time from Volumio... Jun 07 11:19:49 volumio ntpd[949]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 07 11:20:03 volumio volumio-time-update[656]: volumio-time-update-util: Date not found in response Jun 07 11:20:03 volumio volumio-time-update[656]: volumio-time-update-util: Retrying in 5 seconds... Jun 07 11:20:08 volumio volumio-time-update[656]: volumio-time-update-util: Fetching time from Volumio... Jun 07 11:20:09 volumio ntpd[949]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 07 11:20:22 volumio dhcpcd[754]: eth0: offered 192.168.5.101 from 192.168.5.1 Jun 07 11:20:22 volumio dhcpcd[754]: eth0: probing address 192.168.5.101/21 Jun 07 11:20:27 volumio dhcpcd[754]: eth0: leased 192.168.5.101 for 802710091 seconds Jun 07 11:20:27 volumio dhcpcd[754]: eth0: adding route to 192.168.0.0/21 Jun 07 11:20:27 volumio dhcpcd[754]: eth0: changing default route via 192.168.5.1 Jun 07 11:20:27 volumio avahi-daemon[666]: Registering new address record for 192.168.5.101 on eth0.IPv4. Jun 07 11:20:27 volumio ntpd[949]: ntpd exiting on signal 15 (Terminated) Jun 07 11:20:27 volumio systemd[1]: Stopping Network Time Service... Jun 07 11:20:27 volumio systemd[1]: ntp.service: Succeeded. Jun 07 11:20:27 volumio systemd[1]: Stopped Network Time Service. Jun 07 11:20:27 volumio systemd[1]: Starting Network Time Service... Jun 07 11:20:27 volumio ntpd[2285]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 07 11:20:27 volumio ntpd[2285]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 104:103 Jun 07 11:20:27 volumio systemd[1]: Started Network Time Service. Jun 07 11:20:27 volumio ntpd[2291]: proto: precision = 0.111 usec (-23) Jun 07 11:20:27 volumio ntpd[2291]: Listen and drop on 0 v6wildcard [::]:123 Jun 07 11:20:27 volumio ntpd[2291]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 07 11:20:27 volumio ntpd[2291]: Listen normally on 2 lo 127.0.0.1:123 Jun 07 11:20:27 volumio ntpd[2291]: Listen normally on 3 eth0 169.254.73.25:123 Jun 07 11:20:27 volumio ntpd[2291]: Listen normally on 4 eth0 192.168.5.101:123 Jun 07 11:20:27 volumio ntpd[2291]: Listening on routing socket on fd #21 for interface updates Jun 07 11:20:27 volumio ntpd[2291]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 07 11:20:27 volumio ntpd[2291]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 07 11:20:27 volumio dhcpcd[754]: eth0: deleting route to 169.254.0.0/16 Jun 07 11:20:27 volumio avahi-daemon[666]: Withdrawing address record for 169.254.73.25 on eth0. Jun 07 11:20:27 volumio avahi-daemon[666]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.73.25. Jun 07 11:20:27 volumio avahi-daemon[666]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.5.101. Jun 07 11:20:28 volumio volumio-time-update[656]: volumio-time-update-util: Date not found in response Jun 07 11:20:28 volumio volumio-time-update[656]: volumio-time-update-util: Retrying in 5 seconds... Jun 07 11:20:29 volumio ntpd[2291]: Deleting interface #3 eth0, 169.254.73.25#123, interface stats: received=0, sent=0, dropped=0, active_time=2 secs Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 07 11:20:30 volumio volumio[1195]: info: Adding plugin bluetooth to MyMusic Plugins Jun 07 11:20:30 volumio volumio[1195]: info: Adding plugin multiroom to MyMusic Plugins Jun 07 11:20:30 volumio volumio[1195]: info: Adding plugin metavolumio to MyMusic Plugins Jun 07 11:20:30 volumio volumio[1195]: info: Adding plugin cd_controller to MyMusic Plugins Jun 07 11:20:30 volumio volumio[1195]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 07 11:20:30 volumio volumio[1195]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 07 11:20:30 volumio volumio[1195]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 07 11:20:30 volumio volumio[1195]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 07 11:20:31 volumio volumio[1195]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 07 11:20:31 volumio volumio[1195]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 07 11:20:31 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 11:20:31 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 11:20:31 volumio volumio[1195]: info: Starting MyVolumio Remote Streaming Endpoints Jun 07 11:20:31 volumio volumio[1195]: info: MyVolumio login type: Token Jun 07 11:20:31 volumio volumio[1195]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 07 11:20:31 volumio volumio[1195]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 07 11:20:31 volumio volumio[1195]: info: Starting Streaming Service Transparent Proxy Jun 07 11:20:31 volumio volumio[1195]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 07 11:20:31 volumio volumio[1195]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 07 11:20:31 volumio volumio[1195]: info: Streaming services startup Jun 07 11:20:31 volumio volumio[1195]: info: Starting Streaming Daemon Jun 07 11:20:31 volumio sudo[2322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 07 11:20:31 volumio sudo[2322]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 11:20:31 volumio sudo[2322]: pam_unix(sudo:session): session closed for user root Jun 07 11:20:31 volumio volumio[1195]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 07 11:20:31 volumio volumio[1195]: error: Cannot start Volumio Streaming Daemon Jun 07 11:20:31 volumio volumio[1195]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 07 11:20:31 volumio volumio[1195]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 07 11:20:32 volumio volumio[1195]: error: MyVolumio Custom Token format not valid, refreshing it Jun 07 11:20:32 volumio volumio[1195]: STREAMING PROXY: Starting server on port 3245 Jun 07 11:20:32 volumio volumio[1195]: Node JS runtime: 14 Jun 07 11:20:32 volumio volumio[1195]: info: MyVolumio login type: Token Jun 07 11:20:32 volumio volumio[1195]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 07 11:20:33 volumio volumio-time-update[656]: volumio-time-update-util: Fetching time from Volumio... Jun 07 11:20:34 volumio volumio[1195]: info: MyVolumio token set successfully Jun 07 11:20:34 volumio volumio[1195]: info: MYVOLUMIO: Adding device Jun 07 11:20:34 volumio volumio[1195]: info: MYVOLUMIO: Evaluating Server Jun 07 11:20:34 volumio volumio[1195]: info: MyVolumio status changed Jun 07 11:20:34 volumio volumio[1195]: info: Streaming services startup Jun 07 11:20:34 volumio volumio[1195]: info: Starting Streaming Daemon Jun 07 11:20:34 volumio volumio[1195]: info: Removing browser output: myVolumio user plan is not superstar Jun 07 11:20:34 volumio volumio[1195]: info: Removing audio output: Jun 07 11:20:34 volumio volumio[1195]: info: Stoppping Tunnel 1 Jun 07 11:20:34 volumio sudo[2356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 07 11:20:34 volumio sudo[2356]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 11:20:34 volumio sudo[2359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 07 11:20:34 volumio sudo[2359]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 11:20:34 volumio sudo[2356]: pam_unix(sudo:session): session closed for user root Jun 07 11:20:34 volumio volumio[1195]: error: Cannot start Volumio Streaming Daemon Jun 07 11:20:34 volumio volumio[1195]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 07 11:20:34 volumio volumio[1195]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 07 11:20:34 volumio sudo[2359]: pam_unix(sudo:session): session closed for user root Jun 07 11:20:34 volumio volumio[1195]: info: Remote SSH Stopped Jun 07 11:20:34 volumio volumio-time-update[656]: volumio-time-update-util: Setting system time to: 2025-06-07 11:20:34 Jun 07 11:20:34 volumio sudo[2362]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-06-07 11:20:34 Jun 07 11:20:34 volumio sudo[2362]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 11:20:34 volumio dbus-daemon[653]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.11' (uid=0 pid=2363 comm="timedatectl set-time 2025-06-07 11:20:34 ") Jun 07 11:20:34 volumio systemd[1]: Starting Time & Date Service... Jun 07 11:20:34 volumio dbus-daemon[653]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 07 11:20:34 volumio systemd[1]: Started Time & Date Service. Jun 07 11:20:34 volumio systemd-timedated[2364]: Changed local time to Sat Jun 7 11:20:34 2025 Jun 07 11:20:34 volumio sudo[2362]: pam_unix(sudo:session): session closed for user root Jun 07 11:20:34 volumio volumio-time-update[656]: volumio-time-update-util: System time updated successfully. Jun 07 11:20:34 volumio systemd[1]: Started Volumio Time Update Utility. Jun 07 11:20:34 volumio systemd[1]: Reached target Multi-User System. Jun 07 11:20:34 volumio systemd[1]: Reached target Graphical Interface. Jun 07 11:20:34 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 07 11:20:34 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 07 11:20:34 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 07 11:20:34 volumio systemd[1]: Startup finished in 31.744s (firmware) + 868ms (loader) + 11.730s (kernel) + 2min 54.171s (userspace) = 3min 38.514s. Jun 07 11:20:34 volumio volumio[1195]: error: Failed to ping endpoint as2.myvolumio.org : unknown error Jun 07 11:20:34 volumio volumio[1195]: info: Setting Geolocation for MyVolumio to us1 Jun 07 11:20:34 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 11:20:34 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 11:20:34 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 11:20:34 volumio volumio[1195]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 07 11:20:34 volumio volumio[1195]: Error: Unable to resolve or reject the same promise twice Jun 07 11:20:34 volumio volumio[1195]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jun 07 11:20:34 volumio volumio[1195]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Jun 07 11:20:34 volumio volumio[1195]: at Socket.emit (events.js:412:35) Jun 07 11:20:34 volumio volumio[1195]: at endReadableNT (internal/streams/readable.js:1333:12) Jun 07 11:20:34 volumio volumio[1195]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jun 07 11:20:34 volumio volumio[1195]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 07 11:20:34 volumio sudo[2376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-07 11:19 Jun 07 11:20:34 volumio sudo[2376]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"