-- Logs begin at Sat 2025-05-31 00:24:53 -03, end at Mon 2025-06-02 05:41:16 -03. -- Jun 02 05:40:03 volumio volumiossh-tunnel[5614]: ssh: Could not resolve hostname us1.myvolumio.org: Device or resource busy Jun 02 05:40:03 volumio autossh[5615]: ssh exited prematurely with status 255; autossh exiting Jun 02 05:40:03 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jun 02 05:40:03 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jun 02 05:40:04 volumio systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Jun 02 05:40:04 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 5. Jun 02 05:40:04 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Jun 02 05:40:04 volumio systemd[1]: Started MyVolumio SSH Tunnel. Jun 02 05:40:04 volumio autossh[5724]: port set to 0, monitoring disabled Jun 02 05:40:04 volumio autossh[5724]: starting ssh (count 1) Jun 02 05:40:04 volumio autossh[5724]: ssh child pid is 5727 Jun 02 05:40:16 volumio go-librespot[5651]: time="2025-06-02T05:40:16-03:00" level=fatal msg="failed running with username and spotify token" 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" Jun 02 05:40:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 02 05:40:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 02 05:40:19 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 02 05:40:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 235. Jun 02 05:40:19 volumio systemd[1]: Stopped go-librespot Daemon. Jun 02 05:40:19 volumio systemd[1]: Started go-librespot Daemon. Jun 02 05:40:19 volumio go-librespot[5763]: go-librespot daemon starting... Jun 02 05:40:19 volumio go-librespot[5763]: time="2025-06-02T05:40:19-03:00" level=info msg="running go-librespot 0.2.0" Jun 02 05:40:19 volumio go-librespot[5763]: time="2025-06-02T05:40:19-03:00" level=debug msg="app state loaded" Jun 02 05:40:19 volumio go-librespot[5763]: time="2025-06-02T05:40:19-03:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 02 05:40:20 volumio ntpd[819]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 02 05:40:21 volumio volumio[1027]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 02 05:40:21 volumio volumio[1027]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 02 05:40:32 volumio volumiossh-tunnel[5723]: ssh: Could not resolve hostname us1.myvolumio.org: Device or resource busy Jun 02 05:40:32 volumio autossh[5724]: ssh exited prematurely with status 255; autossh exiting Jun 02 05:40:32 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jun 02 05:40:32 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jun 02 05:40:32 volumio systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Jun 02 05:40:32 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 6. Jun 02 05:40:32 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Jun 02 05:40:32 volumio systemd[1]: Started MyVolumio SSH Tunnel. Jun 02 05:40:32 volumio autossh[5789]: port set to 0, monitoring disabled Jun 02 05:40:32 volumio autossh[5789]: starting ssh (count 1) Jun 02 05:40:32 volumio autossh[5789]: ssh child pid is 5792 Jun 02 05:40:42 volumio volumio[1027]: info: Tunnel connection is inactive, restarting it Jun 02 05:40:42 volumio volumio[1027]: info: Starting Tunnel 1 Jun 02 05:40:42 volumio volumio[1027]: info: Starting Tunnel Connection Checker Jun 02 05:40:42 volumio sudo[5816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jun 02 05:40:42 volumio sudo[5816]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 05:40:42 volumio systemd[1]: Stopping MyVolumio SSH Tunnel... Jun 02 05:40:42 volumio autossh[5789]: received signal to exit (15) Jun 02 05:40:42 volumio systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Jun 02 05:40:42 volumio systemd[1]: sshtunnel.service: Succeeded. Jun 02 05:40:42 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Jun 02 05:40:42 volumio volumio[1027]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 05:40:42 volumio volumio[1027]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 02 05:40:42 volumio systemd[1]: Started MyVolumio SSH Tunnel. Jun 02 05:40:42 volumio sudo[5816]: pam_unix(sudo:session): session closed for user root Jun 02 05:40:42 volumio volumio[1027]: info: Remote SSH Started Jun 02 05:40:42 volumio autossh[5819]: port set to 0, monitoring disabled Jun 02 05:40:42 volumio autossh[5819]: starting ssh (count 1) Jun 02 05:40:42 volumio autossh[5819]: ssh child pid is 5822 Jun 02 05:40:43 volumio sudo[5824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 05:40:43 volumio sudo[5824]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 05:40:43 volumio sudo[5824]: pam_unix(sudo:session): session closed for user root Jun 02 05:40:43 volumio sudo[5827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 05:40:43 volumio sudo[5827]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 05:40:43 volumio sudo[5827]: pam_unix(sudo:session): session closed for user root Jun 02 05:40:43 volumio volumio[1027]: verbose: New Socket.io Connection to 192.168.68.120 from 192.168.68.115 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jun 02 05:40:43 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 02 05:40:43 volumio volumio[1027]: info: CoreCommandRouter::volumioGetVisibleSources Jun 02 05:40:43 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 05:40:43 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jun 02 05:40:43 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 02 05:40:43 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 02 05:40:43 volumio volumio[1027]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 02 05:40:43 volumio volumio[1027]: info: Received Get System Info Jun 02 05:40:43 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 05:40:43 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 05:40:43 volumio volumio[1027]: info: Discovery: Getting this device information Jun 02 05:40:43 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jun 02 05:40:43 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 05:40:43 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jun 02 05:40:43 volumio volumio[1027]: info: Listing playlists Jun 02 05:40:43 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 02 05:40:43 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 02 05:40:44 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jun 02 05:40:45 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 02 05:40:45 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 02 05:40:45 volumio volumio[1027]: info: Received Get System Info Jun 02 05:40:45 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 05:40:45 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 05:40:45 volumio volumio[1027]: info: Discovery: Getting this device information Jun 02 05:40:45 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Jun 02 05:40:45 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 05:40:45 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 02 05:40:46 volumio volumio[1027]: info: Executing endpoint metavolumio Jun 02 05:40:46 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 02 05:40:46 volumio volumio[1027]: info: Executing endpoint metavolumio Jun 02 05:40:46 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 02 05:40:46 volumio volumio[1027]: info: Executing endpoint metavolumio Jun 02 05:40:46 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 02 05:40:47 volumio go-librespot[5763]: time="2025-06-02T05:40:47-03:00" level=fatal msg="failed running with username and spotify token" 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" Jun 02 05:40:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 02 05:40:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 02 05:40:48 volumio ntpd[819]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 02 05:40:49 volumio volumio[1027]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 02 05:40:49 volumio volumio[1027]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 02 05:40:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 02 05:40:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 236. Jun 02 05:40:50 volumio systemd[1]: Stopped go-librespot Daemon. Jun 02 05:40:50 volumio systemd[1]: Started go-librespot Daemon. Jun 02 05:40:50 volumio go-librespot[5846]: go-librespot daemon starting... Jun 02 05:40:50 volumio go-librespot[5846]: time="2025-06-02T05:40:50-03:00" level=info msg="running go-librespot 0.2.0" Jun 02 05:40:50 volumio go-librespot[5846]: time="2025-06-02T05:40:50-03:00" level=debug msg="app state loaded" Jun 02 05:40:50 volumio go-librespot[5846]: time="2025-06-02T05:40:50-03:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 02 05:40:53 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 02 05:40:53 volumio volumio[1027]: error: Failed request for metavolumio API Jun 02 05:40:53 volumio volumio[1027]: error: Failed request for metavolumio API Jun 02 05:40:53 volumio volumio[1027]: error: Failed request for metavolumio API Jun 02 05:41:10 volumio volumiossh-tunnel[5818]: ssh: Could not resolve hostname us1.myvolumio.org: Device or resource busy Jun 02 05:41:10 volumio autossh[5819]: ssh exited prematurely with status 255; autossh exiting Jun 02 05:41:10 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jun 02 05:41:10 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jun 02 05:41:10 volumio systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Jun 02 05:41:10 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 1. Jun 02 05:41:10 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Jun 02 05:41:10 volumio systemd[1]: Started MyVolumio SSH Tunnel. Jun 02 05:41:10 volumio autossh[5937]: port set to 0, monitoring disabled Jun 02 05:41:10 volumio autossh[5937]: starting ssh (count 1) Jun 02 05:41:10 volumio autossh[5937]: ssh child pid is 5940 Jun 02 05:41:15 volumio volumio[1027]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 05:41:15 volumio volumio[1027]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] { Jun 02 05:41:15 volumio volumio[1027]: code: 'auth/network-request-failed', Jun 02 05:41:15 volumio volumio[1027]: a: null Jun 02 05:41:15 volumio volumio[1027]: } Jun 02 05:41:15 volumio volumio[1027]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 05:41:16 volumio sudo[5953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-02 05:40 Jun 02 05:41:16 volumio sudo[5953]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"