Feb 09 01:37:00 volumio sudo[4268]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Feb 09 01:37:00 volumio sudo[4268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 09 01:37:00 volumio sudo[4270]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Feb 09 01:37:00 volumio sudo[4270]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 09 01:37:00 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service...
Feb 09 01:37:00 volumio systemd[1]: mpd_monitor.service: Deactivated successfully.
Feb 09 01:37:00 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service.
Feb 09 01:37:00 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service.
Feb 09 01:37:00 volumio sudo[4270]: pam_unix(sudo:session): session closed for user root
Feb 09 01:37:00 volumio sudo[4268]: pam_unix(sudo:session): session closed for user root
Feb 09 01:37:00 volumio mpd_monitor.sh[4273]: MPD Monitor Service: Starting MPD Monitor Service
Feb 09 01:37:00 volumio volumio[3987]: info: Successfully started MPD Monitor
Feb 09 01:37:00 volumio volumio[3987]: info: Successfully started MPD Monitor
Feb 09 01:37:02 volumio volumio[3987]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Feb 09 01:37:12 volumio volumio[3987]: info: Discovery: adding bb478b4a-e27d-414c-ae91-9a5d4d19c79d
Feb 09 01:37:12 volumio volumio[3987]: info: Discovery: Found device Volumio
Feb 09 01:37:12 volumio volumio[3987]: info: CoreCommandRouter::volumioGetState
Feb 09 01:37:12 volumio volumio[3987]: info: CorePlayQueue::getTrack 0
Feb 09 01:37:13 volumio volumio[3987]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2
Feb 09 01:37:16 volumio go-librespot[4222]: time="2026-02-09T01:37:16-07: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"
Feb 09 01:37:16 volumio volumio[3987]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io
Feb 09 01:37:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 09 01:37:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 09 01:37:16 volumio volumio[3987]: info: [1770626236728] [Pandora] checkPing: Ping www.google.com, attempt 5 failed
Feb 09 01:37:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1.
Feb 09 01:37:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 09 01:37:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 09 01:37:19 volumio go-librespot[4295]: go-librespot daemon starting...
Feb 09 01:37:19 volumio go-librespot[4296]: time="2026-02-09T01:37:19-07:00" level=info msg="running go-librespot 0.4.0"
Feb 09 01:37:19 volumio go-librespot[4296]: time="2026-02-09T01:37:19-07:00" level=debug msg="app state loaded"
Feb 09 01:37:19 volumio go-librespot[4296]: time="2026-02-09T01:37:19-07:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 09 01:37:21 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 09 01:37:21 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 09 01:37:21 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 09 01:37:21 volumio volumio[3987]: error: Plugin music_service pandora failed to complete 'onStart' in a timely fashion
Feb 09 01:37:21 volumio volumio[3987]: info: -------------------------------------------
Feb 09 01:37:21 volumio volumio[3987]: info: ----- MyVolumio plugins startup ----
Feb 09 01:37:21 volumio volumio[3987]: info: -------------------------------------------
Feb 09 01:37:21 volumio volumio[3987]: info: [MyVolumio PluginManager] Fetching plans data....
Feb 09 01:37:21 volumio volumio[3987]: info: [1770626241755] [Pandora] checkPing: Cannot ping www.google.com. Delaying Pandora start until Internet connects.
Feb 09 01:37:26 volumio volumio[3987]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.069&uuid=c753c6db1a42665ae66e246153e1e183" http://updates.volumio.org/downloader-v1/track-device
Feb 09 01:37:26 volumio volumio[3987]: % Total % Received % Xferd Average Speed Time Time Time Current
Feb 09 01:37:26 volumio volumio[3987]: Dload Upload Total Spent Left Speed
Feb 09 01:37:26 volumio volumio[3987]: [1.9K blob data]
Feb 09 01:37:26 volumio volumio[3987]: retrying in 5 seconds, trial 0
Feb 09 01:37:26 volumio volumio[3987]: info: Volumio Calling Home
Feb 09 01:37:29 volumio ntpd[882]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101
Feb 09 01:37:41 volumio volumio[3987]: error: MyVolumio Plugin failed to start in a timely fashion
Feb 09 01:37:41 volumio volumio[3987]: [Metrics] CommandRouter: 65s 688.06ms
Feb 09 01:37:41 volumio volumio[3987]: info: CoreCommandRouter::volumiosetStartupVolume
Feb 09 01:37:41 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 09 01:37:41 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 09 01:37:41 volumio volumio[3987]: info: CoreCommandRouter::Close All Modals sent
Feb 09 01:37:41 volumio volumio[3987]: info: CoreCommandRouter::Close All Modals sent
Feb 09 01:37:41 volumio volumio[3987]: info: [1770626261788] [Pandora] checkPing: Ping www.google.com, attempt 10 failed
Feb 09 01:37:41 volumio kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error!
Feb 09 01:37:42 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Feb 09 01:37:42 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 09 01:37:42 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Feb 09 01:37:43 volumio dhcpcd[782]: eth0: failed to renew DHCP, rebinding
Feb 09 01:37:43 volumio dhcpcd[782]: eth0: NAK: wrong network from 192.168.8.1
Feb 09 01:37:43 volumio dhcpcd[782]: eth0: message: wrong network
Feb 09 01:37:43 volumio avahi-daemon[641]: Withdrawing address record for 192.168.1.205 on eth0.
Feb 09 01:37:43 volumio dhcpcd[712]: eth0: pid 782 deleted IP address 192.168.1.205/24
Feb 09 01:37:43 volumio dhcpcd[712]: eth0: deleting route to 192.168.1.0/24
Feb 09 01:37:43 volumio avahi-daemon[641]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.205.
Feb 09 01:37:43 volumio avahi-daemon[641]: Interface eth0.IPv4 no longer relevant for mDNS.
Feb 09 01:37:43 volumio dhcpcd[782]: eth0: deleting route to 192.168.1.0/24
Feb 09 01:37:43 volumio dhcpcd[712]: eth0: deleting default route via 192.168.1.1
Feb 09 01:37:43 volumio dhcpcd[782]: eth0: deleting default route via 192.168.1.1
Feb 09 01:37:43 volumio volumio[3987]: info: Discovery: A device disappeared from network
Feb 09 01:37:43 volumio volumio[3987]: info: Discovery: Device volumio disappeared from network
Feb 09 01:37:43 volumio dhcpcd[712]: eth0: rebinding lease of 192.168.1.205
Feb 09 01:37:43 volumio dhcpcd[782]: eth0: soliciting a DHCP lease
Feb 09 01:37:43 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Feb 09 01:37:43 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Feb 09 01:37:43 volumio systemd[1]: welcome.service: Deactivated successfully.
Feb 09 01:37:43 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console.
Feb 09 01:37:43 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console...
Feb 09 01:37:44 volumio dhcpcd[712]: eth0: NAK: wrong network from 192.168.8.1
Feb 09 01:37:44 volumio dhcpcd[712]: eth0: message: wrong network
Feb 09 01:37:44 volumio systemd[1]: Starting welcome.service - Show a welcome message on console...
Feb 09 01:37:44 volumio dhcpcd[712]: eth0: soliciting a DHCP lease
Feb 09 01:37:44 volumio go-librespot[4296]: time="2026-02-09T01:37:44-07: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"
Feb 09 01:37:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 09 01:37:44 volumio welcome[4391]: Resolved ip:[0]
Feb 09 01:37:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 09 01:37:44 volumio volumio[3987]: info: Error connecting to go-librespot Websocket: AggregateError
Feb 09 01:37:44 volumio systemd[1]: Finished welcome.service - Show a welcome message on console.
Feb 09 01:37:44 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Feb 09 01:37:44 volumio volumio[3987]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
Feb 09 01:37:44 volumio volumio[3987]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Feb 09 01:37:44 volumio volumio[3987]: info: CoreCommandRouter::volumioGetState
Feb 09 01:37:44 volumio volumio[3987]: info: CorePlayQueue::getTrack 0
Feb 09 01:37:45 volumio ntpd[882]: IO: Deleting interface #3 eth0, 192.168.1.205#123, interface stats: received=8178, sent=9338, dropped=0, active_time=1256361 secs
Feb 09 01:37:45 volumio ntpd[882]: PROTO: 216.66.48.42 unlink local addr 192.168.1.205 ->
Feb 09 01:37:45 volumio ntpd[882]: PROTO: 69.164.213.136 unlink local addr 192.168.1.205 ->
Feb 09 01:37:45 volumio ntpd[882]: PROTO: 162.159.200.123 unlink local addr 192.168.1.205 ->
Feb 09 01:37:45 volumio ntpd[882]: PROTO: 209.148.110.236 unlink local addr 192.168.1.205 ->
Feb 09 01:37:45 volumio ntpd[882]: PROTO: 64.111.99.224 unlink local addr 192.168.1.205 ->
Feb 09 01:37:45 volumio ntpd[882]: PROTO: 208.76.2.12 unlink local addr 192.168.1.205 ->
Feb 09 01:37:45 volumio ntpd[882]: PROTO: 108.61.215.221 unlink local addr 192.168.1.205 ->
Feb 09 01:37:46 volumio ntpd[882]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101
Feb 09 01:37:46 volumio ntpd[882]: DNS: dns_check: DNS error: -11, System error
Feb 09 01:37:46 volumio ntpd[882]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12
Feb 09 01:37:46 volumio volumio[3987]: info: Discovery: adding bb478b4a-e27d-414c-ae91-9a5d4d19c79d
Feb 09 01:37:46 volumio volumio[3987]: info: Discovery: Found device Volumio
Feb 09 01:37:46 volumio volumio[3987]: info: CoreCommandRouter::volumioGetState
Feb 09 01:37:46 volumio volumio[3987]: info: CorePlayQueue::getTrack 0
Feb 09 01:37:47 volumio dhcpcd[782]: eth0: offered 192.168.8.184 from 192.168.8.1
Feb 09 01:37:47 volumio dhcpcd[712]: eth0: offered 192.168.8.184 from 192.168.8.1
Feb 09 01:37:47 volumio dhcpcd[782]: eth0: probing address 192.168.8.184/24
Feb 09 01:37:47 volumio dhcpcd[712]: eth0: probing address 192.168.8.184/24
Feb 09 01:37:47 volumio volumio[3987]: info: Initializing connection to go-librespot Websocket
Feb 09 01:37:47 volumio volumio[3987]: info: Error connecting to go-librespot Websocket: AggregateError
Feb 09 01:37:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2.
Feb 09 01:37:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 09 01:37:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 09 01:37:47 volumio go-librespot[4409]: go-librespot daemon starting...
Feb 09 01:37:47 volumio go-librespot[4410]: time="2026-02-09T01:37:47-07:00" level=info msg="running go-librespot 0.4.0"
Feb 09 01:37:47 volumio go-librespot[4410]: time="2026-02-09T01:37:47-07:00" level=debug msg="app state loaded"
Feb 09 01:37:47 volumio go-librespot[4410]: time="2026-02-09T01:37:47-07:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 09 01:37:47 volumio go-librespot[4410]: time="2026-02-09T01:37:47-07: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"
Feb 09 01:37:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 09 01:37:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 09 01:37:48 volumio volumio[3987]: info: BOOT COMPLETED
Feb 09 01:37:50 volumio volumio[3987]: info: Volumio Network Manager: Network status updated: 0
Feb 09 01:37:50 volumio volumio[3987]: info: Initializing connection to go-librespot Websocket
Feb 09 01:37:50 volumio volumio[3987]: info: Error connecting to go-librespot Websocket: AggregateError
Feb 09 01:37:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3.
Feb 09 01:37:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 09 01:37:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 09 01:37:50 volumio go-librespot[4430]: go-librespot daemon starting...
Feb 09 01:37:50 volumio go-librespot[4431]: time="2026-02-09T01:37:50-07:00" level=info msg="running go-librespot 0.4.0"
Feb 09 01:37:50 volumio go-librespot[4431]: time="2026-02-09T01:37:50-07:00" level=debug msg="app state loaded"
Feb 09 01:37:50 volumio go-librespot[4431]: time="2026-02-09T01:37:50-07:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 09 01:37:50 volumio go-librespot[4431]: time="2026-02-09T01:37:50-07: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"
Feb 09 01:37:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 09 01:37:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 09 01:37:51 volumio dhcpcd[712]: eth0: leased 192.168.8.184 for 43200 seconds
Feb 09 01:37:51 volumio avahi-daemon[641]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.8.184.
Feb 09 01:37:51 volumio avahi-daemon[641]: New relevant interface eth0.IPv4 for mDNS.
Feb 09 01:37:51 volumio dhcpcd[712]: eth0: adding route to 192.168.8.0/24
Feb 09 01:37:51 volumio avahi-daemon[641]: Registering new address record for 192.168.8.184 on eth0.IPv4.
Feb 09 01:37:51 volumio dhcpcd[782]: eth0: leased 192.168.8.184 for 43200 seconds
Feb 09 01:37:51 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Feb 09 01:37:51 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Feb 09 01:37:51 volumio systemd[1]: welcome.service: Deactivated successfully.
Feb 09 01:37:51 volumio dhcpcd[782]: eth0: adding route to 192.168.8.0/24
Feb 09 01:37:51 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console.
Feb 09 01:37:51 volumio dhcpcd[782]: eth0: adding default route via 192.168.8.1
Feb 09 01:37:51 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console...
Feb 09 01:37:51 volumio dhcpcd[712]: eth0: adding default route via 192.168.8.1
Feb 09 01:37:51 volumio systemd[1]: Starting welcome.service - Show a welcome message on console...
Feb 09 01:37:51 volumio welcome[4460]: Resolved ip:[1] 192.168.8.184
Feb 09 01:37:51 volumio systemd[1]: Finished welcome.service - Show a welcome message on console.
Feb 09 01:37:51 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Feb 09 01:37:51 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Feb 09 01:37:51 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Feb 09 01:37:51 volumio systemd[1]: welcome.service: Deactivated successfully.
Feb 09 01:37:51 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console.
Feb 09 01:37:51 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console...
Feb 09 01:37:51 volumio systemd[1]: Starting welcome.service - Show a welcome message on console...
Feb 09 01:37:51 volumio welcome[4485]: Resolved ip:[1] 192.168.8.184
Feb 09 01:37:51 volumio systemd[1]: Finished welcome.service - Show a welcome message on console.
Feb 09 01:37:51 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Feb 09 01:37:51 volumio volumio[3987]: info: [1770626271971] [Pandora] checkPing: ***LOOKS GOOD, ICE DOWN THE BEERS IN THE COOLER***
Feb 09 01:37:51 volumio volumio[3987]: info: [1770626271972] ControllerPandora::checkHTTP
Feb 09 01:37:52 volumio volumio[3987]: info: Discovery: this is already registered, bb478b4a-e27d-414c-ae91-9a5d4d19c79d
Feb 09 01:37:52 volumio volumio[3987]: info: Discovery: Found device Volumio
Feb 09 01:37:52 volumio volumio[3987]: info: CoreCommandRouter::volumioGetState
Feb 09 01:37:52 volumio volumio[3987]: info: CorePlayQueue::getTrack 0
Feb 09 01:37:52 volumio ntpd[882]: IO: Listen normally on 4 eth0 192.168.8.184:123
Feb 09 01:37:52 volumio ntpd[882]: IO: new interface(s) found: waking up resolver
Feb 09 01:37:52 volumio ntpd[882]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101
Feb 09 01:37:53 volumio volumio[3987]: info: Initializing connection to go-librespot Websocket
Feb 09 01:37:53 volumio volumio[3987]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 09 01:37:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4.
Feb 09 01:37:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 09 01:37:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 09 01:37:53 volumio go-librespot[4492]: go-librespot daemon starting...
Feb 09 01:37:53 volumio go-librespot[4493]: time="2026-02-09T01:37:53-07:00" level=info msg="running go-librespot 0.4.0"
Feb 09 01:37:53 volumio go-librespot[4493]: time="2026-02-09T01:37:53-07:00" level=debug msg="app state loaded"
Feb 09 01:37:53 volumio go-librespot[4493]: time="2026-02-09T01:37:53-07:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 09 01:37:54 volumio volumio[3987]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.069&uuid=c753c6db1a42665ae66e246153e1e183" http://updates.volumio.org/downloader-v1/track-device
Feb 09 01:37:54 volumio volumio[3987]: % Total % Received % Xferd Average Speed Time Time Time Current
Feb 09 01:37:54 volumio volumio[3987]: Dload Upload Total Spent Left Speed
Feb 09 01:37:54 volumio volumio[3987]: [1.5K blob data]
Feb 09 01:37:54 volumio volumio[3987]: retrying in 5 seconds, trial 1
Feb 09 01:37:54 volumio volumio[3987]: info: Volumio Calling Home
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Feb 09 01:37:54 volumio volumio[3987]: info: Adding plugin bluetooth to MyMusic Plugins
Feb 09 01:37:54 volumio volumio[3987]: info: Adding plugin multiroom to MyMusic Plugins
Feb 09 01:37:54 volumio volumio[3987]: info: Adding plugin metavolumio to MyMusic Plugins
Feb 09 01:37:54 volumio volumio[3987]: info: Adding plugin cd_controller to MyMusic Plugins
Feb 09 01:37:54 volumio volumio[3987]: info: Adding plugin qobuzconnect to MyMusic Plugins
Feb 09 01:37:54 volumio volumio[3987]: info: Adding plugin smart_inputs to MyMusic Plugins
Feb 09 01:37:54 volumio volumio[3987]: info: Adding plugin tidalconnect to MyMusic Plugins
Feb 09 01:37:54 volumio volumio[3987]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Feb 09 01:37:54 volumio ntpd[882]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101
Feb 09 01:37:54 volumio ntpd[882]: DNS: Pool taking: 86.80.166.233
Feb 09 01:37:54 volumio ntpd[882]: DNS: Pool taking: 31.58.102.164
Feb 09 01:37:54 volumio ntpd[882]: DNS: Pool taking: 162.159.200.1
Feb 09 01:37:54 volumio ntpd[882]: DNS: Pool taking: 81.172.248.188
Feb 09 01:37:54 volumio ntpd[882]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8
Feb 09 01:37:54 volumio go-librespot[4493]: time="2026-02-09T01:37:54-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 09 01:37:54 volumio go-librespot[4493]: time="2026-02-09T01:37:54-07:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 09 01:37:54 volumio go-librespot[4493]: time="2026-02-09T01:37:54-07:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 09 01:37:54 volumio go-librespot[4493]: time="2026-02-09T01:37:54-07:00" level=info msg="zeroconf server listening on port 45273"
Feb 09 01:37:55 volumio go-librespot[4493]: time="2026-02-09T01:37:55-07:00" level=debug msg="obtained new client token: AACeqzFarGcHRugTjjS4j0p8pqxWj9V1tFDoJedlJR0xkM267lwXkWVmpSQNK+oYVu8PXpTxAijMN6RTQQd/7m+k/rgj6KuGl7JL72RAac+1KylDec7X2Yzq668MSPM4gK6hyNM23/qWgRf+QINB38W8vpBQPkuSx77ZravUDpdihE5dwRmMD843KK50siq6c5qGEfBaINplf0KT51fTiLp0BfjRn7xf1MTZBJvkGQe22w+lBiogKZIRmzk="
Feb 09 01:37:55 volumio ntpd[882]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101
Feb 09 01:37:56 volumio ntpd[882]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101
Feb 09 01:37:56 volumio ntpd[882]: DNS: Pool taking: 198.46.254.130
Feb 09 01:37:56 volumio ntpd[882]: DNS: Pool taking: 45.77.126.122
Feb 09 01:37:56 volumio ntpd[882]: DNS: Pool taking: 44.27.134.6
Feb 09 01:37:56 volumio ntpd[882]: DNS: Pool taking: 23.186.168.129
Feb 09 01:37:56 volumio ntpd[882]: DNS: Pool taking: 2a03:94e3:ffff:195:35:113:0:80
Feb 09 01:37:56 volumio ntpd[882]: DNS: Pool taking: 2a10:3781:2d18::32
Feb 09 01:37:56 volumio ntpd[882]: DNS: Pool taking: 2a03:38a0:60:123::1
Feb 09 01:37:56 volumio ntpd[882]: DNS: Pool taking: 2a04:dd01:19:81:195:242:99:71
Feb 09 01:37:56 volumio ntpd[882]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8
Feb 09 01:37:56 volumio volumio[3987]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Feb 09 01:37:56 volumio volumio[3987]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Feb 09 01:37:56 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 09 01:37:56 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 09 01:37:56 volumio volumio[3987]: info: Starting MyVolumio Remote Streaming Endpoints
Feb 09 01:37:56 volumio volumio[3987]: info: MyVolumio login type: Token
Feb 09 01:37:56 volumio volumio[3987]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Feb 09 01:37:56 volumio volumio[3987]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Feb 09 01:37:58 volumio volumio[3987]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Feb 09 01:37:58 volumio volumio[3987]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Feb 09 01:37:58 volumio volumio[3987]: info: Streaming services startup
Feb 09 01:37:58 volumio volumio[3987]: info: Starting Streaming Daemon
Feb 09 01:37:58 volumio sudo[4505]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Feb 09 01:37:58 volumio volumio[3987]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Feb 09 01:37:58 volumio sudo[4505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 09 01:37:58 volumio sudo[4505]: pam_unix(sudo:session): session closed for user root
Feb 09 01:37:58 volumio volumio[3987]: info: Initializing connection to go-librespot Websocket
Feb 09 01:37:58 volumio volumio[3987]: error: Cannot start Volumio Streaming Daemon
Feb 09 01:37:58 volumio volumio[3987]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Feb 09 01:37:58 volumio volumio[3987]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Feb 09 01:37:58 volumio volumio[3987]: info: Volumio called home
Feb 09 01:37:58 volumio go-librespot[4493]: time="2026-02-09T01:37:58-07:00" level=debug msg="new websocket client"
Feb 09 01:37:58 volumio volumio[3987]: info: Connection to go-librespot Websocket established
Feb 09 01:38:00 volumio volumio[3987]: info: Volumio Network Manager: Network status updated: 1
Feb 09 01:38:00 volumio volumio[3987]: error: MyVolumio Custom Token format not valid, refreshing it
Feb 09 01:38:00 volumio volumio[3987]: info: [1770626280438] [Pandora] checkHTTP: ***CRACK THE BEERS WE HAVE INTERNET***
Feb 09 01:38:00 volumio volumio[3987]: info: [1770626280439] ControllerPandora::initializeMQTT
Feb 09 01:38:00 volumio volumio[3987]: info: [1770626280440] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration
Feb 09 01:38:00 volumio volumio[3987]: info: [1770626280441] ControllerPandora::PandoraHandler::init
Feb 09 01:38:00 volumio volumio[3987]: info: [1770626280443] ControllerPandora::PandoraHandler::loadErrorCodes
Feb 09 01:38:00 volumio volumio[3987]: info: [1770626280446] ControllerPandora::validateAndSetAccountOptions
Feb 09 01:38:00 volumio volumio[3987]: info: [1770626280448] ControllerPandora::PandoraHandler::setAccountOptions
Feb 09 01:38:00 volumio volumio[3987]: info: [1770626280451] ControllerPandora::flushPandora
Feb 09 01:38:00 volumio volumio[3987]: info: CorePlayQueue::getQueue
Feb 09 01:38:00 volumio volumio[3987]: info: CorePlayQueue::clearAddPlayQueue
Feb 09 01:38:00 volumio volumio[3987]: info: CorePlayQueue::saveQueue
Feb 09 01:38:00 volumio volumio[3987]: info: CoreCommandRouter::serviceClearAddPlayTracks
Feb 09 01:38:00 volumio volumio[3987]: error: WARNING: No clearAddPlayTracks method for service spop
Feb 09 01:38:00 volumio volumio[3987]: info: CoreCommandRouter::volumioPushQueue
Feb 09 01:38:00 volumio volumio[3987]: info: [1770626280475] [Pandora] PreventAuthTimeout::init: Timer loaded
Feb 09 01:38:00 volumio volumio[3987]: info: [1770626280476] [Pandora] PreventAuthTimeout::init: Interval set to 10800000 ms
Feb 09 01:38:00 volumio volumio[3987]: info: [1770626280477] [Pandora] PreventAuthTimeout::init: Starting delayed timer after next interval
Feb 09 01:38:00 volumio volumio[3987]: info: [1770626280478] ControllerPandora::PandoraHandler::pandoraLoginAndGetStations
Feb 09 01:38:01 volumio volumio[3987]: info: Getting Spotify volume
Feb 09 01:38:01 volumio volumio[3987]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Feb 09 01:38:01 volumio volumio[3987]: info: MyVolumio login type: Token
Feb 09 01:38:01 volumio volumio[3987]: info: CoreCommandRouter::volumioGetState
Feb 09 01:38:01 volumio volumio[3987]: info: CorePlayQueue::getTrack 0
Feb 09 01:38:02 volumio volumio[3987]: info: [1770626282427] [Pandora] PandoraHandler::pandoraLoginAndGetStations::pandoraLogin: [<=- Logged in to Pandora Servers -=>]
Feb 09 01:38:02 volumio volumio[3987]: info: [1770626282436] [Pandora] validateAndSetAccountOptions: ***DEBUG*** self.loggedIn: true
Feb 09 01:38:02 volumio volumio[3987]: info: [1770626282438] [Pandora] ExpireOldTracks::init: Timer loaded
Feb 09 01:38:02 volumio volumio[3987]: info: [1770626282439] [Pandora] ExpireOldTracks::init: Interval set to 300000 ms
Feb 09 01:38:02 volumio volumio[3987]: info: [1770626282439] [Pandora] ExpireOldTracks::init: Starting delayed timer after next interval
Feb 09 01:38:02 volumio volumio[3987]: info: [1770626282441] ControllerPandora::PandoraHandler::fillStationData
Feb 09 01:38:02 volumio volumio[3987]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Feb 09 01:38:03 volumio volumio[3987]: info: MyVolumio token set successfully
Feb 09 01:38:03 volumio volumio[3987]: info: MYVOLUMIO: Adding device
Feb 09 01:38:03 volumio volumio[3987]: info: MYVOLUMIO: Evaluating Server
Feb 09 01:38:03 volumio volumio[3987]: info: MyVolumio status changed
Feb 09 01:38:03 volumio volumio[3987]: info: Streaming services startup
Feb 09 01:38:03 volumio volumio[3987]: info: Starting Streaming Daemon
Feb 09 01:38:03 volumio volumio[3987]: info: Removing browser output: myVolumio user plan is not superstar
Feb 09 01:38:03 volumio volumio[3987]: info: Removing audio output:
Feb 09 01:38:03 volumio volumio[3987]: info: Stoppping Tunnel 1
Feb 09 01:38:03 volumio sudo[4552]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Feb 09 01:38:03 volumio sudo[4552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 09 01:38:03 volumio sudo[4552]: pam_unix(sudo:session): session closed for user root
Feb 09 01:38:03 volumio volumio[3987]: info: [1770626283654] [Pandora] PandoraHandler::setMaxStationTracks: 16
Feb 09 01:38:03 volumio volumio[3987]: info: [1770626283656] [Pandora] PandoraHandler::setBandFilter: bandfilter is empty
Feb 09 01:38:03 volumio volumio[3987]: info: [1770626283658] ControllerPandora::flushPandora
Feb 09 01:38:03 volumio volumio[3987]: info: CorePlayQueue::getQueue
Feb 09 01:38:03 volumio volumio[3987]: info: CorePlayQueue::clearAddPlayQueue
Feb 09 01:38:03 volumio volumio[3987]: info: CorePlayQueue::saveQueue
Feb 09 01:38:03 volumio volumio[3987]: info: CoreCommandRouter::serviceClearAddPlayTracks
Feb 09 01:38:03 volumio volumio[3987]: error: WARNING: No clearAddPlayTracks method for service spop
Feb 09 01:38:03 volumio volumio[3987]: info: CoreCommandRouter::volumioPushQueue
Feb 09 01:38:03 volumio sudo[4555]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Feb 09 01:38:03 volumio sudo[4555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 09 01:38:03 volumio volumio[3987]: error: Cannot start Volumio Streaming Daemon
Feb 09 01:38:03 volumio volumio[3987]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Feb 09 01:38:03 volumio volumio[3987]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Feb 09 01:38:03 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 09 01:38:03 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 09 01:38:03 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 09 01:38:03 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 09 01:38:03 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 09 01:38:03 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 09 01:38:03 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 09 01:38:03 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 09 01:38:03 volumio sudo[4555]: pam_unix(sudo:session): session closed for user root
Feb 09 01:38:03 volumio volumio[3987]: info: Remote SSH Stopped
Feb 09 01:38:09 volumio volumio[3987]: info: Setting Geolocation for MyVolumio to us4
Feb 09 01:38:09 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 09 01:38:09 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 09 01:38:09 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 09 01:38:09 volumio volumio[3987]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Feb 09 01:38:10 volumio volumio[3987]: info: Updating MyVolumio device info
Feb 09 01:38:10 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 09 01:38:10 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 09 01:38:10 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 09 01:38:10 volumio volumio[3987]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Feb 09 01:38:11 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Feb 09 01:38:11 volumio volumio[3987]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
Feb 09 01:38:11 volumio volumio[3987]: info: CoreCommandRouter::volumioGetState
Feb 09 01:38:11 volumio volumio[3987]: info: CorePlayQueue::getTrack 0
Feb 09 01:38:13 volumio volumio[3987]: info: MYVOLUMIO: Adding device
Feb 09 01:38:13 volumio volumio[3987]: info: MYVOLUMIO: Evaluating Server
Feb 09 01:38:16 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 09 01:38:16 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 09 01:38:16 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Feb 09 01:38:16 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Feb 09 01:38:16 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Feb 09 01:38:16 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Feb 09 01:38:16 volumio volumio[3987]: info: CoreCommandRouter::volumioGetBrowseSources
Feb 09 01:38:16 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 09 01:38:19 volumio volumio[3987]: info: Setting Geolocation for MyVolumio to us1
Feb 09 01:38:19 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 09 01:38:19 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 09 01:38:19 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 09 01:38:20 volumio volumio[3987]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Feb 09 01:38:20 volumio volumio[3987]: info: Updating MyVolumio device info
Feb 09 01:38:20 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 09 01:38:20 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 09 01:38:20 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 09 01:38:20 volumio volumio[3987]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Feb 09 01:38:25 volumio go-librespot[4493]: time="2026-02-09T01:38:25-07:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 198.18.0.39:4070: i/o timeout"
Feb 09 01:38:26 volumio go-librespot[4493]: time="2026-02-09T01:38:26-07:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Feb 09 01:38:26 volumio go-librespot[4493]: time="2026-02-09T01:38:26-07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF"
Feb 09 01:38:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 09 01:38:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 09 01:38:26 volumio volumio[3987]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 09 01:38:26 volumio volumio[3987]: Error: socket hang up
Feb 09 01:38:26 volumio volumio[3987]: at connResetException (node:internal/errors:720:14)
Feb 09 01:38:26 volumio volumio[3987]: at Socket.socketOnEnd (node:_http_client:519:23)
Feb 09 01:38:26 volumio volumio[3987]: at Socket.emit (node:events:526:35)
Feb 09 01:38:26 volumio volumio[3987]: at endReadableNT (node:internal/streams/readable:1376:12)
Feb 09 01:38:26 volumio volumio[3987]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) {
Feb 09 01:38:26 volumio volumio[3987]: code: 'ECONNRESET',
Feb 09 01:38:26 volumio volumio[3987]: response: undefined
Feb 09 01:38:26 volumio volumio[3987]: }
Feb 09 01:38:26 volumio volumio[3987]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 09 01:38:27 volumio sudo[4621]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-09 01:37'
Feb 09 01:38:27 volumio sudo[4621]: 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="ac7dd5922ede329d1459d48b226ddc71f5209a2c"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="3441cddd43405c36fe444484553f10f2f5bc830b"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Nov 13 11:04:27 UTC 2025"
VOLUMIO_VERSION="4.069"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="7da8d6d9f8baf8621af4ec2af5a00f00"