-- Logs begin at Fri 2026-10-16 17:37:52 +07, end at Fri 2026-10-16 17:40:10 +07. -- Oct 16 17:39:03 volumio volumio-time-update[676]: volumio-time-update-util: Fetching time from Volumio... Oct 16 17:39:15 volumio ntpd[1806]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 16 17:39:19 volumio volumio[944]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.886&uuid=427939faab3f8053d59c6ff80e77bf88" http://updates.volumio.org/downloader-v1/track-device Oct 16 17:39:19 volumio volumio[944]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 17:39:19 volumio volumio[944]: Dload Upload Total Spent Left Speed Oct 16 17:39:19 volumio volumio[944]: [2.2K blob data] Oct 16 17:39:19 volumio volumio[944]: retrying in 5 seconds, trial 2 Oct 16 17:39:19 volumio volumio[944]: info: Volumio Calling Home Oct 16 17:39:27 volumio go-librespot[1970]: time="2026-10-16T17:39:27+07:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 16 17:39:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 17:39:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 17:39:27 volumio nmbd[914]: [2026/10/16 17:39:27.635218, 0] ../source3/libsmb/nmblib.c:917(send_udp) Oct 16 17:39:27 volumio nmbd[914]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Oct 16 17:39:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 16 17:39:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Oct 16 17:39:30 volumio systemd[1]: Stopped go-librespot Daemon. Oct 16 17:39:30 volumio systemd[1]: Started go-librespot Daemon. Oct 16 17:39:30 volumio go-librespot[2033]: go-librespot daemon starting... Oct 16 17:39:30 volumio go-librespot[2033]: time="2026-10-16T17:39:30+07:00" level=info msg="running go-librespot 0.6.2" Oct 16 17:39:30 volumio go-librespot[2033]: time="2026-10-16T17:39:30+07:00" level=debug msg="app state loaded" Oct 16 17:39:30 volumio go-librespot[2033]: time="2026-10-16T17:39:30+07:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 17:39:31 volumio volumio-time-update[676]: volumio-time-update-util: Date not found in response Oct 16 17:39:31 volumio volumio-time-update[676]: volumio-time-update-util: Retrying in 5 seconds... Oct 16 17:39:36 volumio volumio-time-update[676]: volumio-time-update-util: Fetching time from Volumio... Oct 16 17:39:43 volumio ntpd[1806]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 16 17:39:54 volumio volumio[944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 16 17:39:58 volumio go-librespot[2033]: time="2026-10-16T17:39:58+07:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 16 17:39:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 17:39:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 17:40:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 16 17:40:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Oct 16 17:40:01 volumio systemd[1]: Stopped go-librespot Daemon. Oct 16 17:40:01 volumio systemd[1]: Started go-librespot Daemon. Oct 16 17:40:01 volumio go-librespot[2139]: go-librespot daemon starting... Oct 16 17:40:02 volumio go-librespot[2139]: time="2026-10-16T17:40:02+07:00" level=info msg="running go-librespot 0.6.2" Oct 16 17:40:02 volumio go-librespot[2139]: time="2026-10-16T17:40:02+07:00" level=debug msg="app state loaded" Oct 16 17:40:02 volumio go-librespot[2139]: time="2026-10-16T17:40:02+07:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 17:40:03 volumio go-librespot[2139]: time="2026-10-16T17:40:03+07:00" level=debug msg="new websocket client" Oct 16 17:40:03 volumio volumio[944]: info: Connection to go-librespot Websocket established Oct 16 17:40:03 volumio volumio[944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 16 17:40:03 volumio volumio[944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 16 17:40:03 volumio volumio[944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 16 17:40:03 volumio go-librespot[2139]: time="2026-10-16T17:40:03+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 16 17:40:03 volumio go-librespot[2139]: time="2026-10-16T17:40:03+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 16 17:40:03 volumio go-librespot[2139]: time="2026-10-16T17:40:03+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 16 17:40:03 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Oct 16 17:40:03 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Oct 16 17:40:03 volumio go-librespot[2139]: time="2026-10-16T17:40:03+07:00" level=info msg="zeroconf server listening on port 35097" Oct 16 17:40:03 volumio volumio[944]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 16 17:40:03 volumio ntpd[1806]: Soliciting pool server 115.165.161.155 Oct 16 17:40:03 volumio ntpd[1806]: Soliciting pool server 45.252.250.189 Oct 16 17:40:04 volumio ntpd[1806]: Soliciting pool server 115.165.161.155 Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 16 17:40:04 volumio volumio[944]: info: Adding plugin bluetooth to MyMusic Plugins Oct 16 17:40:04 volumio volumio[944]: info: Adding plugin multiroom to MyMusic Plugins Oct 16 17:40:04 volumio volumio[944]: info: Adding plugin metavolumio to MyMusic Plugins Oct 16 17:40:04 volumio volumio[944]: info: Adding plugin cd_controller to MyMusic Plugins Oct 16 17:40:04 volumio volumio[944]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 16 17:40:04 volumio volumio[944]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 16 17:40:04 volumio ntpd[1806]: Soliciting pool server 45.252.250.189 Oct 16 17:40:04 volumio volumio[944]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 16 17:40:04 volumio volumio[944]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 16 17:40:04 volumio volumio-time-update[676]: volumio-time-update-util: Date not found in response Oct 16 17:40:04 volumio volumio-time-update[676]: volumio-time-update-util: Retrying in 5 seconds... Oct 16 17:40:04 volumio ntpd[1806]: Soliciting pool server 115.165.161.155 Oct 16 17:40:05 volumio volumio[944]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 16 17:40:05 volumio volumio[944]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 16 17:40:05 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 17:40:05 volumio volumio[944]: info: Starting MyVolumio Remote Streaming Endpoints Oct 16 17:40:05 volumio volumio[944]: info: MyVolumio login type: Token Oct 16 17:40:05 volumio volumio[944]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 16 17:40:05 volumio volumio[944]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 16 17:40:06 volumio volumio[944]: info: Starting Streaming Service Transparent Proxy Oct 16 17:40:06 volumio volumio[944]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 16 17:40:06 volumio volumio[944]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 16 17:40:06 volumio volumio[944]: info: Streaming services startup Oct 16 17:40:06 volumio volumio[944]: info: Starting Streaming Daemon Oct 16 17:40:06 volumio sudo[2183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 16 17:40:06 volumio sudo[2183]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:40:06 volumio sudo[2183]: pam_unix(sudo:session): session closed for user root Oct 16 17:40:06 volumio volumio[944]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 16 17:40:06 volumio volumio[944]: info: Getting Spotify volume Oct 16 17:40:06 volumio volumio[944]: error: Cannot start Volumio Streaming Daemon Oct 16 17:40:06 volumio volumio[944]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 16 17:40:06 volumio volumio[944]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 16 17:40:06 volumio volumio[944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 16 17:40:06 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Oct 16 17:40:06 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Oct 16 17:40:06 volumio volumio[944]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 16 17:40:06 volumio volumio[944]: error: MyVolumio Custom Token format not valid, refreshing it Oct 16 17:40:06 volumio volumio[944]: STREAMING PROXY: Starting server on port 3245 Oct 16 17:40:06 volumio volumio[944]: Node JS runtime: 14 Oct 16 17:40:07 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 17:40:07 volumio volumio[944]: info: Received Get System Info Oct 16 17:40:07 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 17:40:07 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 17:40:07 volumio volumio[944]: info: Discovery: Getting this device information Oct 16 17:40:07 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Oct 16 17:40:07 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Oct 16 17:40:07 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 17:40:08 volumio volumio[944]: info: MyVolumio login type: Token Oct 16 17:40:08 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 16 17:40:08 volumio volumio[944]: info: MyVolumio login type: Token Oct 16 17:40:09 volumio volumio[944]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 16 17:40:09 volumio volumio-time-update[676]: volumio-time-update-util: Fetching time from Volumio... Oct 16 17:40:10 volumio volumio[944]: info: MyVolumio token set successfully Oct 16 17:40:10 volumio volumio[944]: info: MYVOLUMIO: Adding device Oct 16 17:40:10 volumio volumio[944]: info: MYVOLUMIO: Evaluating Server Oct 16 17:40:10 volumio volumio-time-update[676]: volumio-time-update-util: Setting system time to: 2026-10-16 17:40:10 Oct 16 17:40:10 volumio sudo[2203]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2026-10-16 17:40:10 Oct 16 17:40:10 volumio sudo[2203]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:40:10 volumio dbus-daemon[663]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.14' (uid=0 pid=2205 comm="timedatectl set-time 2026-10-16 17:40:10 ") Oct 16 17:40:10 volumio systemd[1]: Starting Time & Date Service... Oct 16 17:40:10 volumio volumio[944]: info: MyVolumio status changed Oct 16 17:40:10 volumio volumio[944]: info: Streaming services startup Oct 16 17:40:10 volumio volumio[944]: info: Starting Streaming Daemon Oct 16 17:40:10 volumio dbus-daemon[663]: [system] Successfully activated service 'org.freedesktop.timedate1' Oct 16 17:40:10 volumio systemd[1]: Started Time & Date Service. Oct 16 17:40:10 volumio volumio[944]: info: Removing browser output: myVolumio user plan is not superstar Oct 16 17:40:10 volumio volumio[944]: info: Removing audio output: Oct 16 17:40:10 volumio volumio[944]: info: Stoppping Tunnel 1 Oct 16 17:40:10 volumio systemd-timedated[2209]: Changed local time to Fri Oct 16 17:40:10 2026 Oct 16 17:40:10 volumio sudo[2220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 16 17:40:10 volumio volumio-time-update[676]: volumio-time-update-util: System time updated successfully. Oct 16 17:40:10 volumio sudo[2220]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:40:10 volumio sudo[2203]: pam_unix(sudo:session): session closed for user root Oct 16 17:40:10 volumio systemd[1]: Started Volumio Time Update Utility. Oct 16 17:40:10 volumio systemd[1]: Reached target Multi-User System. Oct 16 17:40:10 volumio systemd[1]: Reached target Graphical Interface. Oct 16 17:40:10 volumio volumio[944]: error: Failed to ping endpoint eu2.myvolumio.org : unknown error Oct 16 17:40:10 volumio volumio[944]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 17:40:10 volumio sudo[2223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 16 17:40:10 volumio sudo[2223]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:40:10 volumio volumio[944]: Error: Unable to resolve or reject the same promise twice Oct 16 17:40:10 volumio volumio[944]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 16 17:40:10 volumio volumio[944]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Oct 16 17:40:10 volumio volumio[944]: at Socket.emit (events.js:412:35) Oct 16 17:40:10 volumio volumio[944]: at endReadableNT (internal/streams/readable.js:1333:12) Oct 16 17:40:10 volumio volumio[944]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Oct 16 17:40:10 volumio volumio[944]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 17:40:10 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 16 17:40:10 volumio sudo[2220]: pam_unix(sudo:session): session closed for user root Oct 16 17:40:10 volumio sudo[2223]: pam_unix(sudo:session): session closed for user root Oct 16 17:40:10 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 16 17:40:10 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 16 17:40:10 volumio systemd[1]: Startup finished in 43.734s (kernel) + 2min 18.795s (userspace) = 3min 2.530s. Oct 16 17:40:10 volumio sudo[2239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-16 17:39 Oct 16 17:40:10 volumio sudo[2239]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:18:30 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="33f9369b544b4488ff4979d2ee3813a5"