Aug 30 10:34:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22109. Aug 30 10:34:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:02 volumio go-librespot[1714]: go-librespot daemon starting... Aug 30 10:34:02 volumio go-librespot[1715]: time="2026-08-30T10:34:02-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:34:02 volumio go-librespot[1715]: time="2026-08-30T10:34:02-04:00" level=debug msg="app state loaded" Aug 30 10:34:02 volumio go-librespot[1715]: time="2026-08-30T10:34:02-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:34:02 volumio go-librespot[1715]: time="2026-08-30T10:34:02-04: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" Aug 30 10:34:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:34:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:34:02 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:34:02 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: AggregateError Aug 30 10:34:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22110. Aug 30 10:34:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:05 volumio go-librespot[1735]: go-librespot daemon starting... Aug 30 10:34:05 volumio go-librespot[1736]: time="2026-08-30T10:34:05-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:34:05 volumio go-librespot[1736]: time="2026-08-30T10:34:05-04:00" level=debug msg="app state loaded" Aug 30 10:34:05 volumio go-librespot[1736]: time="2026-08-30T10:34:05-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:34:05 volumio go-librespot[1736]: time="2026-08-30T10:34:05-04: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" Aug 30 10:34:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:34:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:34:05 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:34:05 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: AggregateError Aug 30 10:34:08 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:34:08 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: AggregateError Aug 30 10:34:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22111. Aug 30 10:34:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:08 volumio go-librespot[1742]: go-librespot daemon starting... Aug 30 10:34:08 volumio go-librespot[1743]: time="2026-08-30T10:34:08-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:34:08 volumio go-librespot[1743]: time="2026-08-30T10:34:08-04:00" level=debug msg="app state loaded" Aug 30 10:34:08 volumio go-librespot[1743]: time="2026-08-30T10:34:08-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:34:08 volumio go-librespot[1743]: time="2026-08-30T10:34:08-04: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" Aug 30 10:34:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:34:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:34:11 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:34:11 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: AggregateError Aug 30 10:34:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22112. Aug 30 10:34:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:11 volumio go-librespot[1749]: go-librespot daemon starting... Aug 30 10:34:11 volumio go-librespot[1750]: time="2026-08-30T10:34:11-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:34:11 volumio go-librespot[1750]: time="2026-08-30T10:34:11-04:00" level=debug msg="app state loaded" Aug 30 10:34:11 volumio go-librespot[1750]: time="2026-08-30T10:34:11-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:34:11 volumio go-librespot[1750]: time="2026-08-30T10:34:11-04: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" Aug 30 10:34:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:34:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:34:14 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:34:14 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: AggregateError Aug 30 10:34:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22113. Aug 30 10:34:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:15 volumio go-librespot[1770]: go-librespot daemon starting... Aug 30 10:34:15 volumio go-librespot[1771]: time="2026-08-30T10:34:15-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:34:15 volumio go-librespot[1771]: time="2026-08-30T10:34:15-04:00" level=debug msg="app state loaded" Aug 30 10:34:15 volumio go-librespot[1771]: time="2026-08-30T10:34:15-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:34:15 volumio go-librespot[1771]: time="2026-08-30T10:34:15-04: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" Aug 30 10:34:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:34:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:34:17 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:34:17 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: AggregateError Aug 30 10:34:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22114. Aug 30 10:34:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:18 volumio go-librespot[1777]: go-librespot daemon starting... Aug 30 10:34:18 volumio go-librespot[1778]: time="2026-08-30T10:34:18-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:34:18 volumio go-librespot[1778]: time="2026-08-30T10:34:18-04:00" level=debug msg="app state loaded" Aug 30 10:34:18 volumio go-librespot[1778]: time="2026-08-30T10:34:18-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:34:18 volumio go-librespot[1778]: time="2026-08-30T10:34:18-04: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" Aug 30 10:34:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:34:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:34:20 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:34:20 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: AggregateError Aug 30 10:34:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22115. Aug 30 10:34:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:21 volumio go-librespot[1785]: go-librespot daemon starting... Aug 30 10:34:21 volumio go-librespot[1786]: time="2026-08-30T10:34:21-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:34:21 volumio go-librespot[1786]: time="2026-08-30T10:34:21-04:00" level=debug msg="app state loaded" Aug 30 10:34:21 volumio go-librespot[1786]: time="2026-08-30T10:34:21-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:34:21 volumio go-librespot[1786]: time="2026-08-30T10:34:21-04: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" Aug 30 10:34:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:34:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:34:23 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:34:23 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: AggregateError Aug 30 10:34:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22116. Aug 30 10:34:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:24 volumio go-librespot[1792]: go-librespot daemon starting... Aug 30 10:34:24 volumio go-librespot[1793]: time="2026-08-30T10:34:24-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:34:24 volumio go-librespot[1793]: time="2026-08-30T10:34:24-04:00" level=debug msg="app state loaded" Aug 30 10:34:24 volumio go-librespot[1793]: time="2026-08-30T10:34:24-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:34:24 volumio go-librespot[1793]: time="2026-08-30T10:34:24-04: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" Aug 30 10:34:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:34:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:34:26 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:34:26 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: AggregateError Aug 30 10:34:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22117. Aug 30 10:34:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:28 volumio go-librespot[1814]: go-librespot daemon starting... Aug 30 10:34:28 volumio go-librespot[1815]: time="2026-08-30T10:34:28-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:34:28 volumio go-librespot[1815]: time="2026-08-30T10:34:28-04:00" level=debug msg="app state loaded" Aug 30 10:34:28 volumio go-librespot[1815]: time="2026-08-30T10:34:28-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:34:28 volumio go-librespot[1815]: time="2026-08-30T10:34:28-04: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" Aug 30 10:34:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:34:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:34:29 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:34:29 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: AggregateError Aug 30 10:34:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22118. Aug 30 10:34:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:31 volumio go-librespot[1821]: go-librespot daemon starting... Aug 30 10:34:31 volumio go-librespot[1823]: time="2026-08-30T10:34:31-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:34:31 volumio go-librespot[1823]: time="2026-08-30T10:34:31-04:00" level=debug msg="app state loaded" Aug 30 10:34:31 volumio go-librespot[1823]: time="2026-08-30T10:34:31-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:34:31 volumio go-librespot[1823]: time="2026-08-30T10:34:31-04: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" Aug 30 10:34:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:34:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:34:32 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:34:32 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: AggregateError Aug 30 10:34:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22119. Aug 30 10:34:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:34 volumio go-librespot[1830]: go-librespot daemon starting... Aug 30 10:34:34 volumio go-librespot[1831]: time="2026-08-30T10:34:34-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:34:34 volumio go-librespot[1831]: time="2026-08-30T10:34:34-04:00" level=debug msg="app state loaded" Aug 30 10:34:34 volumio go-librespot[1831]: time="2026-08-30T10:34:34-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:34:34 volumio go-librespot[1831]: time="2026-08-30T10:34:34-04: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" Aug 30 10:34:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:34:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:34:35 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:34:35 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: AggregateError Aug 30 10:34:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22120. Aug 30 10:34:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:37 volumio go-librespot[1852]: go-librespot daemon starting... Aug 30 10:34:37 volumio go-librespot[1853]: time="2026-08-30T10:34:37-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:34:37 volumio go-librespot[1853]: time="2026-08-30T10:34:37-04:00" level=debug msg="app state loaded" Aug 30 10:34:37 volumio go-librespot[1853]: time="2026-08-30T10:34:37-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:34:37 volumio go-librespot[1853]: time="2026-08-30T10:34:37-04: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" Aug 30 10:34:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:34:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:34:38 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:34:38 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: AggregateError Aug 30 10:34:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22121. Aug 30 10:34:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:40 volumio go-librespot[1859]: go-librespot daemon starting... Aug 30 10:34:40 volumio go-librespot[1860]: time="2026-08-30T10:34:40-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:34:40 volumio go-librespot[1860]: time="2026-08-30T10:34:40-04:00" level=debug msg="app state loaded" Aug 30 10:34:40 volumio go-librespot[1860]: time="2026-08-30T10:34:40-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:34:40 volumio go-librespot[1860]: time="2026-08-30T10:34:40-04: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" Aug 30 10:34:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:34:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:34:41 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:34:41 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: AggregateError Aug 30 10:34:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22122. Aug 30 10:34:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:44 volumio go-librespot[1868]: go-librespot daemon starting... Aug 30 10:34:44 volumio go-librespot[1869]: time="2026-08-30T10:34:44-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:34:44 volumio go-librespot[1869]: time="2026-08-30T10:34:44-04:00" level=debug msg="app state loaded" Aug 30 10:34:44 volumio go-librespot[1869]: time="2026-08-30T10:34:44-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:34:44 volumio go-librespot[1869]: time="2026-08-30T10:34:44-04: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" Aug 30 10:34:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:34:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:34:44 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:34:44 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: AggregateError Aug 30 10:34:46 volumio dhcpcd[1015]: wlan0: offered 192.168.121.213 from 192.168.121.1 Aug 30 10:34:46 volumio dhcpcd[1015]: wlan0: probing address 192.168.121.213/24 Aug 30 10:34:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22123. Aug 30 10:34:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:47 volumio go-librespot[1890]: go-librespot daemon starting... Aug 30 10:34:47 volumio go-librespot[1891]: time="2026-08-30T10:34:47-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:34:47 volumio go-librespot[1891]: time="2026-08-30T10:34:47-04:00" level=debug msg="app state loaded" Aug 30 10:34:47 volumio go-librespot[1891]: time="2026-08-30T10:34:47-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:34:47 volumio go-librespot[1891]: time="2026-08-30T10:34:47-04: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" Aug 30 10:34:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:34:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:34:47 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:34:47 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: AggregateError Aug 30 10:34:50 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:34:50 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: AggregateError Aug 30 10:34:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22124. Aug 30 10:34:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:50 volumio go-librespot[1898]: go-librespot daemon starting... Aug 30 10:34:50 volumio go-librespot[1899]: time="2026-08-30T10:34:50-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:34:50 volumio go-librespot[1899]: time="2026-08-30T10:34:50-04:00" level=debug msg="app state loaded" Aug 30 10:34:50 volumio go-librespot[1899]: time="2026-08-30T10:34:50-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:34:50 volumio go-librespot[1899]: time="2026-08-30T10:34:50-04: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" Aug 30 10:34:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:34:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:34:51 volumio dhcpcd[1015]: wlan0: leased 192.168.121.213 for 7200 seconds Aug 30 10:34:51 volumio avahi-daemon[1470]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.121.213. Aug 30 10:34:51 volumio avahi-daemon[1470]: New relevant interface wlan0.IPv4 for mDNS. Aug 30 10:34:51 volumio avahi-daemon[1470]: Registering new address record for 192.168.121.213 on wlan0.IPv4. Aug 30 10:34:51 volumio dhcpcd[1015]: wlan0: adding route to 192.168.121.0/24 Aug 30 10:34:51 volumio dhcpcd[1015]: wlan0: adding default route via 192.168.121.1 Aug 30 10:34:51 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Aug 30 10:34:51 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Aug 30 10:34:51 volumio systemd[1]: welcome.service: Deactivated successfully. Aug 30 10:34:51 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Aug 30 10:34:51 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Aug 30 10:34:52 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Aug 30 10:34:52 volumio welcome[1919]: Resolved ip:[1] 192.168.121.213 Aug 30 10:34:52 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Aug 30 10:34:52 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Aug 30 10:34:52 volumio volumio[10291]: info: Received Get System Info Aug 30 10:34:52 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:34:52 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:34:52 volumio volumio[10291]: info: Discovery: Getting this device information Aug 30 10:34:52 volumio volumio[10291]: info: CoreCommandRouter::volumioGetState Aug 30 10:34:52 volumio volumio[10291]: info: CorePlayQueue::getTrack 0 Aug 30 10:34:52 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:34:52 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 10:34:52 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 10:34:52 volumio volumio[10291]: info: Discovery: this is already registered, 6c1a0cc9-58e8-43e5-adb0-67b7c5cb1ba3 Aug 30 10:34:52 volumio volumio[10291]: info: Discovery: Found device Volumio Aug 30 10:34:52 volumio volumio[10291]: info: CoreCommandRouter::volumioGetState Aug 30 10:34:52 volumio volumio[10291]: info: CorePlayQueue::getTrack 0 Aug 30 10:34:52 volumio ntpd[1132]: IO: Listen normally on 47 wlan0 192.168.121.213:123 Aug 30 10:34:52 volumio ntpd[1132]: IO: new interface(s) found: waking up resolver Aug 30 10:34:52 volumio ntpd[1132]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 30 10:34:53 volumio ntpd[1132]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Aug 30 10:34:53 volumio ntpd[1132]: DNS: Pool taking: 216.250.115.174 Aug 30 10:34:53 volumio ntpd[1132]: DNS: Pool taking: 5.161.18.115 Aug 30 10:34:53 volumio ntpd[1132]: DNS: Pool taking: 104.236.167.15 Aug 30 10:34:53 volumio ntpd[1132]: DNS: Pool taking: 45.77.126.122 Aug 30 10:34:53 volumio ntpd[1132]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Aug 30 10:34:53 volumio volumio5-onboarding[1637]: time=2026-08-30T10:34:53.333-04:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 30 10:34:53 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:34:53 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:34:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22125. Aug 30 10:34:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:53 volumio go-librespot[1935]: go-librespot daemon starting... Aug 30 10:34:53 volumio go-librespot[1936]: time="2026-08-30T10:34:53-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:34:53 volumio go-librespot[1936]: time="2026-08-30T10:34:53-04:00" level=debug msg="app state loaded" Aug 30 10:34:53 volumio go-librespot[1936]: time="2026-08-30T10:34:53-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:34:53 volumio go-librespot[1936]: time="2026-08-30T10:34:53-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]" Aug 30 10:34:53 volumio go-librespot[1936]: time="2026-08-30T10:34:53-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 30 10:34:53 volumio go-librespot[1936]: time="2026-08-30T10:34:53-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 30 10:34:53 volumio ntpd[1132]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 30 10:34:53 volumio go-librespot[1936]: time="2026-08-30T10:34:53-04:00" level=info msg="zeroconf server listening on port 34027" Aug 30 10:34:53 volumio go-librespot[1936]: time="2026-08-30T10:34:53-04:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:34:54 volumio ntpd[1132]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Aug 30 10:34:54 volumio ntpd[1132]: DNS: Pool taking: 23.186.168.125 Aug 30 10:34:54 volumio ntpd[1132]: DNS: Pool taking: 172.234.25.10 Aug 30 10:34:54 volumio ntpd[1132]: DNS: Pool taking: 66.118.229.14 Aug 30 10:34:54 volumio ntpd[1132]: DNS: Pool taking: 149.28.200.179 Aug 30 10:34:54 volumio ntpd[1132]: DNS: Pool taking: 2a01:4ff:f2:e915::1 Aug 30 10:34:54 volumio ntpd[1132]: DNS: Pool taking: 2600:3c03::f03c:91ff:fedf:1e98 Aug 30 10:34:54 volumio ntpd[1132]: DNS: Pool taking: 2600:1f18:7927:8b00:123:: Aug 30 10:34:54 volumio ntpd[1132]: DNS: Pool taking: 2a01:7e03::2000:93ff:fe22:34e4 Aug 30 10:34:54 volumio ntpd[1132]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Aug 30 10:34:54 volumio go-librespot[1936]: time="2026-08-30T10:34:54-04:00" level=debug msg="obtained new client token: AAF7sUEgoPsdzb6k0u73cGgCPEsxwrIViRqdd4o3D37RAIig+u2Cw8QQQT1hol9Hrvbpzve8iJkMv6blCW9sjaBtapcy+E1/9DvzuUnRAP4p5tvF4onWgUKTB36rNmlJTeF6TM7jVoBVEDMkOGuVj6YPnNruBZiJSaEDBB3ho5sq2uJ/zY8yTgwHoLU6PzAcCHmcblJqpybWF8ZiCeInu40JkrcjbJRrwg4tvHO78FtMGCABylICeBjM" Aug 30 10:34:54 volumio go-librespot[1936]: time="2026-08-30T10:34:54-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 30 10:34:54 volumio go-librespot[1936]: time="2026-08-30T10:34:54-04:00" level=debug msg="completed keyexchange" Aug 30 10:34:54 volumio go-librespot[1936]: time="2026-08-30T10:34:54-04:00" level=debug msg="completed challenge" Aug 30 10:34:54 volumio go-librespot[1936]: time="2026-08-30T10:34:54-04:00" level=info msg="authenticated AP" username="12******25" Aug 30 10:34:54 volumio go-librespot[1936]: time="2026-08-30T10:34:54-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:34:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:34:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:34:54 volumio wpa_supplicant[1279]: RRM: Ignoring radio measurement request: Not RRM network Aug 30 10:34:54 volumio volumio[10291]: info: Volumio Network Manager: Network status updated: 2 Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 30 10:34:56 volumio volumio[10291]: info: Adding plugin bluetooth to MyMusic Plugins Aug 30 10:34:56 volumio volumio[10291]: info: Adding plugin multiroom to MyMusic Plugins Aug 30 10:34:56 volumio volumio[10291]: info: Adding plugin metavolumio to MyMusic Plugins Aug 30 10:34:56 volumio volumio[10291]: info: Adding plugin cd_controller to MyMusic Plugins Aug 30 10:34:56 volumio volumio[10291]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 30 10:34:56 volumio volumio[10291]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 30 10:34:56 volumio volumio[10291]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 30 10:34:56 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 10:34:56 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 10:34:56 volumio volumio[10291]: info: Starting MyVolumio Remote Streaming Endpoints Aug 30 10:34:56 volumio volumio[10291]: info: MyVolumio login type: Token Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 30 10:34:56 volumio volumio[10291]: info: Streaming services startup Aug 30 10:34:56 volumio volumio[10291]: info: Starting Streaming Daemon Aug 30 10:34:56 volumio sudo[1962]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 30 10:34:56 volumio sudo[1962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 10:34:56 volumio volumio[10291]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 30 10:34:56 volumio sudo[1962]: pam_unix(sudo:session): session closed for user root Aug 30 10:34:56 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:34:56 volumio volumio[10291]: error: Cannot start Volumio Streaming Daemon Aug 30 10:34:56 volumio volumio[10291]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 30 10:34:56 volumio volumio[10291]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 30 10:34:56 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:34:56 volumio volumio[10291]: error: MyVolumio Custom Token format not valid, refreshing it Aug 30 10:34:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22126. Aug 30 10:34:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:34:57 volumio go-librespot[1968]: go-librespot daemon starting... Aug 30 10:34:57 volumio go-librespot[1969]: time="2026-08-30T10:34:57-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:34:57 volumio go-librespot[1969]: time="2026-08-30T10:34:57-04:00" level=debug msg="app state loaded" Aug 30 10:34:57 volumio go-librespot[1969]: time="2026-08-30T10:34:57-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:34:57 volumio volumio[10291]: info: MyVolumio login type: Token Aug 30 10:34:58 volumio go-librespot[1969]: time="2026-08-30T10:34:58-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]" Aug 30 10:34:58 volumio go-librespot[1969]: time="2026-08-30T10:34:58-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 30 10:34:58 volumio go-librespot[1969]: time="2026-08-30T10:34:58-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 30 10:34:58 volumio go-librespot[1969]: time="2026-08-30T10:34:58-04:00" level=info msg="zeroconf server listening on port 37381" Aug 30 10:34:58 volumio go-librespot[1969]: time="2026-08-30T10:34:58-04:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:34:58 volumio go-librespot[1969]: time="2026-08-30T10:34:58-04:00" level=debug msg="obtained new client token: AAEoWw6/WnSyA1/RCezHCujXoaOT+PuhY8WZp42QtQGH6TLiEf7hpVT+N60rsGu52qOuJz8F8LoW+EEDQt4pJSx30jKiS4j0UcrdWh38E4yKv8VtjNzeLrw9KvrTNv/e+VjCzpdjjoti398HR9iDWvNb1s+wlhr/WTrarzg2yDwcINyG4O5e3D45Zc7zHTTw7V/54y6fb8m6iLwSlS9aXw96GXlkip4ZO3hf/6cH74QG6gqMM5f1PiFv" Aug 30 10:34:58 volumio go-librespot[1969]: time="2026-08-30T10:34:58-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 30 10:34:58 volumio volumio[10291]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 30 10:34:58 volumio go-librespot[1969]: time="2026-08-30T10:34:58-04:00" level=debug msg="completed keyexchange" Aug 30 10:34:58 volumio go-librespot[1969]: time="2026-08-30T10:34:58-04:00" level=debug msg="completed challenge" Aug 30 10:34:58 volumio go-librespot[1969]: time="2026-08-30T10:34:58-04:00" level=info msg="authenticated AP" username="12******25" Aug 30 10:34:58 volumio volumio[10291]: info: MyVolumio token set successfully Aug 30 10:34:58 volumio volumio[10291]: info: MYVOLUMIO: Adding device Aug 30 10:34:58 volumio volumio[10291]: info: MYVOLUMIO: Evaluating Server Aug 30 10:34:58 volumio volumio[10291]: info: MyVolumio Plan changed: premium Aug 30 10:34:58 volumio volumio[10291]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Aug 30 10:34:58 volumio volumio[10291]: info: Removing browser output: myVolumio user plan is not superstar Aug 30 10:34:58 volumio volumio[10291]: info: Removing audio output: Aug 30 10:34:58 volumio volumio[10291]: info: MYVOLUMIO: Adding device Aug 30 10:34:58 volumio volumio[10291]: info: MYVOLUMIO: Evaluating Server Aug 30 10:34:58 volumio volumio[10291]: info: Remote config written successfully Aug 30 10:34:58 volumio volumio[10291]: info: Starting Tunnel 1 Aug 30 10:34:58 volumio volumio[10291]: info: Starting Tunnel Connection Checker Aug 30 10:34:58 volumio go-librespot[1969]: time="2026-08-30T10:34:58-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:34:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:34:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:34:58 volumio volumio[10291]: info: MYVolumio Device enabled Aug 30 10:34:58 volumio volumio[10291]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Aug 30 10:34:58 volumio volumio[10291]: info: MyVolumio status changed Aug 30 10:34:58 volumio volumio[10291]: info: Streaming services startup Aug 30 10:34:58 volumio volumio[10291]: info: Starting Streaming Daemon Aug 30 10:34:58 volumio sudo[2017]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 30 10:34:58 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Aug 30 10:34:58 volumio sudo[2017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 10:34:58 volumio volumio[10291]: error: [MyVolumio PluginManager] Cache data is invalid! Aug 30 10:34:59 volumio sudo[2017]: pam_unix(sudo:session): session closed for user root Aug 30 10:34:59 volumio volumio[10291]: error: Cannot start Volumio Streaming Daemon Aug 30 10:34:59 volumio volumio[10291]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 30 10:34:59 volumio volumio[10291]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 30 10:34:59 volumio volumio[10291]: info: Setting Geolocation for MyVolumio to us4 Aug 30 10:34:59 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 10:34:59 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 10:34:59 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 10:34:59 volumio volumio[10291]: info: Setting Geolocation for MyVolumio to us1 Aug 30 10:34:59 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 10:34:59 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 10:34:59 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 10:34:59 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:34:59 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:34:59 volumio volumio[10291]: info: Successfully Added MyVolumio device Aug 30 10:34:59 volumio volumio[10291]: info: Successfully Added MyVolumio device Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Aug 30 10:35:00 volumio volumio[10291]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Aug 30 10:35:00 volumio sudo[2021]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Aug 30 10:35:00 volumio sudo[2021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 10:35:00 volumio sudo[2021]: pam_unix(sudo:session): session closed for user root Aug 30 10:35:00 volumio volumio[10291]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Aug 30 10:35:00 volumio volumio[10291]: info: MRS: MultiRoom plugin initialized Aug 30 10:35:00 volumio volumio[10291]: info: MRS: STOPPING SNAPCLIENT Aug 30 10:35:00 volumio volumio[10291]: info: MRS: Snap server stop Aug 30 10:35:00 volumio volumio[10291]: info: MRS: STOPPING volumioStreaming Aug 30 10:35:00 volumio sudo[2040]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Aug 30 10:35:00 volumio sudo[2040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Aug 30 10:35:00 volumio sudo[2047]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 30 10:35:00 volumio sudo[2042]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Aug 30 10:35:00 volumio sudo[2042]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 10:35:00 volumio sudo[2047]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 10:35:00 volumio sudo[2045]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Aug 30 10:35:00 volumio sudo[2047]: pam_unix(sudo:session): session closed for user root Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Aug 30 10:35:00 volumio sudo[2040]: pam_unix(sudo:session): session closed for user root Aug 30 10:35:00 volumio sudo[2045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Aug 30 10:35:00 volumio sudo[2042]: pam_unix(sudo:session): session closed for user root Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Aug 30 10:35:00 volumio sudo[2045]: pam_unix(sudo:session): session closed for user root Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Aug 30 10:35:00 volumio volumio[10291]: info: Preparing to generate the ALSA configuration file Aug 30 10:35:00 volumio volumio[10291]: info: Updating MyVolumio device info Aug 30 10:35:00 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 10:35:00 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 10:35:00 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 10:35:00 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Aug 30 10:35:00 volumio volumio[10291]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 30 10:35:00 volumio volumio[10291]: info: Reading ALSA contributions from plugins. Aug 30 10:35:00 volumio volumio[10291]: info: MRS: Removed streaming files Aug 30 10:35:00 volumio volumio[10291]: info: MRS: volumioStreaming STOPPED Aug 30 10:35:00 volumio volumio[10291]: info: MRS: SNAPSERVER STOPPED Aug 30 10:35:00 volumio volumio[10291]: info: MRS: SNAPCLIENT STOPPED Aug 30 10:35:00 volumio volumio[10291]: info: Asound.conf file unchanged, so no further update is needed Aug 30 10:35:00 volumio volumio[10291]: info: Output device has changed, restarting MPD Aug 30 10:35:00 volumio volumio[10291]: info: Output device has changed, restarting Shairport Sync Aug 30 10:35:00 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 10:35:00 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 10:35:00 volumio sudo[2053]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 10:35:00 volumio sudo[2053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 10:35:00 volumio sudo[2053]: pam_unix(sudo:session): session closed for user root Aug 30 10:35:00 volumio sudo[2056]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 10:35:00 volumio sudo[2056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 10:35:00 volumio volumio[10291]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 10:35:00 volumio volumio[10291]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 30 10:35:00 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Aug 30 10:35:00 volumio volumio[10291]: ------------------------------------ BT MESSAGE: [FUNC] onStart Aug 30 10:35:00 volumio volumio[10291]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Aug 30 10:35:00 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 30 10:35:00 volumio volumio[10291]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Aug 30 10:35:00 volumio volumio[10291]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Plugin multiroom disabled by user. Not starting Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Aug 30 10:35:00 volumio volumio[10291]: info: Adding METAVOLUMIO REST API Endpoints Aug 30 10:35:00 volumio volumio[10291]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Aug 30 10:35:00 volumio volumio[10291]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Aug 30 10:35:00 volumio volumio[10291]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Aug 30 10:35:00 volumio volumio[10291]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Aug 30 10:35:00 volumio volumio[10291]: info: Adding inputs REST Endpoints Aug 30 10:35:00 volumio volumio[10291]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Aug 30 10:35:00 volumio volumio[10291]: info: Scanning Audio Inputs Aug 30 10:35:00 volumio volumio[10291]: info: Checking against Known Cards name Aug 30 10:35:00 volumio volumio[10291]: info: Adding Server instance for streaming Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Aug 30 10:35:00 volumio volumio[10291]: error: Hi Res Audio Failed Login: Missing Login Data Aug 30 10:35:00 volumio volumio[10291]: info: Adding HIGHRESAUDIO REST API Endpoints Aug 30 10:35:00 volumio volumio[10291]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Aug 30 10:35:00 volumio volumio[10291]: info: Refreshing QOBUZ token Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Aug 30 10:35:00 volumio volumio[10291]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Aug 30 10:35:00 volumio volumio[10291]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Aug 30 10:35:00 volumio volumio[10291]: info: QobuzConnect: Starting Qobuz Connect socket and service Aug 30 10:35:00 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 30 10:35:00 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 30 10:35:00 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 30 10:35:00 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 30 10:35:00 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 30 10:35:00 volumio volumio[10291]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Aug 30 10:35:00 volumio volumio[10291]: info: Adding TIDAL REST API Endpoints Aug 30 10:35:00 volumio volumio[10291]: info: Updating MyVolumio device info Aug 30 10:35:00 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 10:35:00 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 10:35:00 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 10:35:00 volumio sudo[2078]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 30 10:35:00 volumio sudo[2078]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 10:35:00 volumio bluetoothd[993]: Path / reserved for Adv Monitor app :1.1027 Aug 30 10:35:00 volumio bluetoothd[993]: Adv Monitor app :1.1027 disconnected from D-Bus Aug 30 10:35:00 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 30 10:35:00 volumio volumio[10291]: info: MPD Permissions set Aug 30 10:35:00 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 30 10:35:00 volumio volumio[10291]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Aug 30 10:35:00 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 10:35:00 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 10:35:00 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 10:35:00 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 10:35:00 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 10:35:00 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 10:35:00 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 10:35:00 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 10:35:00 volumio sudo[2078]: pam_unix(sudo:session): session closed for user root Aug 30 10:35:00 volumio sudo[2082]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Aug 30 10:35:00 volumio sudo[2082]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 10:35:00 volumio sudo[2088]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 30 10:35:00 volumio sudo[2088]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 10:35:00 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 10:35:00 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 10:35:00 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Aug 30 10:35:00 volumio sudo[2082]: pam_unix(sudo:session): session closed for user root Aug 30 10:35:00 volumio qobuz-connect[29514]: 20260830 10:35:00.592 [29514.29514] INFO SampleApp: Stopping Local configuration server Aug 30 10:35:00 volumio volumio[10291]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Aug 30 10:35:00 volumio volumio[10291]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Aug 30 10:35:00 volumio volumio[10291]: info: Starting Shairport Sync Aug 30 10:35:00 volumio sudo[2092]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 10:35:00 volumio sudo[2092]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 10:35:00 volumio sudo[2080]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 10:35:00 volumio sudo[2080]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 30 10:35:00 volumio sudo[2080]: pam_unix(sudo:session): session closed for user root Aug 30 10:35:00 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 30 10:35:00 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 30 10:35:00 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 30 10:35:00 volumio systemd[1]: shairport-sync.service: Consumed 6.592s CPU time. Aug 30 10:35:00 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 30 10:35:00 volumio sudo[2092]: pam_unix(sudo:session): session closed for user root Aug 30 10:35:00 volumio volumio[10291]: info: Shairport-Sync Started Aug 30 10:35:00 volumio volumio[10291]: info: Successfully Updated MyVolumio device Aug 30 10:35:00 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Aug 30 10:35:00 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Aug 30 10:35:00 volumio systemd[1]: qobuz-connect.service: Consumed 2h 43.717s CPU time. Aug 30 10:35:00 volumio volumio[10291]: info: Access Token successfully retrieved Aug 30 10:35:00 volumio volumio[10291]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 10:35:00 volumio volumio[10291]: info: [1788100500914] CoreMusicLibrary::Adding element QOBUZ Aug 30 10:35:00 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 10:35:00 volumio volumio[10291]: Cannot find translation for source QOBUZ Aug 30 10:35:00 volumio volumio[10291]: info: Stopping AccessToken refresher cron for QOBUZ Aug 30 10:35:00 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Aug 30 10:35:00 volumio sudo[2088]: pam_unix(sudo:session): session closed for user root Aug 30 10:35:00 volumio volumio[10291]: info: AccessToken refresher cron started for QOBUZ Aug 30 10:35:00 volumio volumio[10291]: info: Adding QOBUZ REST API Endpoints Aug 30 10:35:00 volumio volumio[10291]: info: Executing endpoint qc_getconfig Aug 30 10:35:00 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Aug 30 10:35:00 volumio qobuz-connect[2112]: 20260830 10:35:00.960 [2112.2112] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Aug 30 10:35:00 volumio volumio[10291]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Aug 30 10:35:00 volumio volumio[10291]: info: QobuzConnect: QOBUZ Connect daemon connected Aug 30 10:35:00 volumio qobuz-connect[2112]: 20260830 10:35:00.961 [2112.2112] INFO VolumeManager: [0x13a1288]: Setting new playback volume: 75 Aug 30 10:35:00 volumio qobuz-connect[2112]: 20260830 10:35:00.961 [2112.2112] INFO VolumeManager: [0x13a1288]: Setting new mute state: 0 Aug 30 10:35:00 volumio qobuz-connect[2112]: 20260830 10:35:00.961 [2112.2112] INFO AudioStreamManager: [0x13a0fe0]: Setting new audio download buffer size: 1048576 Aug 30 10:35:00 volumio qobuz-connect[2112]: 20260830 10:35:00.961 [2112.2112] INFO QobuzConnect: [0x13a1b50]: Client initialized! Aug 30 10:35:00 volumio qobuz-connect[2112]: 20260830 10:35:00.961 [2112.2112] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Aug 30 10:35:00 volumio qobuz-connect[2112]: 20260830 10:35:00.967 [2112.2112] INFO LocalConfigManager: [0x13a0d08]: Starting Local Configuration server Aug 30 10:35:00 volumio qobuz-connect[2112]: 20260830 10:35:00.967 [2112.2112] INFO SampleApp: Starting Local configuration server Aug 30 10:35:00 volumio qobuz-connect[2112]: 20260830 10:35:00.968 [2112.2112] INFO SampleApp: Connected to UNIX socket client 0x138b818 Aug 30 10:35:01 volumio mpd[2096]: 2026-08-30T10:35:01 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 30 10:35:01 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 30 10:35:01 volumio sudo[2056]: pam_unix(sudo:session): session closed for user root Aug 30 10:35:01 volumio volumio[10291]: error: updateQueue error: null Aug 30 10:35:01 volumio qobuz-connect[2112]: 20260830 10:35:01.110 [2112.2112] INFO SampleApp: Playback volume changed: 75 Aug 30 10:35:01 volumio volumio[10291]: info: CoreCommandRouter::volumioGetState Aug 30 10:35:01 volumio volumio[10291]: info: CorePlayQueue::getTrack 0 Aug 30 10:35:01 volumio volumio[10291]: info: Successfully Updated MyVolumio device Aug 30 10:35:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22127. Aug 30 10:35:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:02 volumio go-librespot[2122]: go-librespot daemon starting... Aug 30 10:35:02 volumio go-librespot[2123]: time="2026-08-30T10:35:02-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:35:02 volumio go-librespot[2123]: time="2026-08-30T10:35:02-04:00" level=debug msg="app state loaded" Aug 30 10:35:02 volumio go-librespot[2123]: time="2026-08-30T10:35:02-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:35:02 volumio go-librespot[2123]: time="2026-08-30T10:35:02-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]" Aug 30 10:35:02 volumio go-librespot[2123]: time="2026-08-30T10:35:02-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 30 10:35:02 volumio go-librespot[2123]: time="2026-08-30T10:35:02-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 30 10:35:02 volumio go-librespot[2123]: time="2026-08-30T10:35:02-04:00" level=info msg="zeroconf server listening on port 42559" Aug 30 10:35:02 volumio go-librespot[2123]: time="2026-08-30T10:35:02-04:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:35:02 volumio go-librespot[2123]: time="2026-08-30T10:35:02-04:00" level=debug msg="obtained new client token: AAHKjUOVSBg9C+nk5wWhaG2r6KdsqtykA9q5UMsO7FYQj26r4YASq+mFCkfGjq15kBzan1rkW1JnGjh17+2KFuH6ZA4W5kxf93ARWULFYffodykglKzR2s3HKgyxiMq3uCKXxgfHnFLTKxp6filQnxCSU1MuiLnuj3yHbaqIZJvu5NE1w0jjAtMtj1fxjIIvYr94gzq0P3z/l7n60jyW36x3OSZM49leLP+CUAOjEkrkvmRw1nm9T2v+PXo=" Aug 30 10:35:02 volumio go-librespot[2123]: time="2026-08-30T10:35:02-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 30 10:35:02 volumio go-librespot[2123]: time="2026-08-30T10:35:02-04:00" level=debug msg="completed keyexchange" Aug 30 10:35:02 volumio go-librespot[2123]: time="2026-08-30T10:35:02-04:00" level=debug msg="completed challenge" Aug 30 10:35:02 volumio go-librespot[2123]: time="2026-08-30T10:35:02-04:00" level=info msg="authenticated AP" username="12******25" Aug 30 10:35:02 volumio go-librespot[2123]: time="2026-08-30T10:35:02-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:35:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:35:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:35:02 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:35:02 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:35:03 volumio sudo[2140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Aug 30 10:35:03 volumio sudo[2140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 10:35:03 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Aug 30 10:35:03 volumio sudo[2140]: pam_unix(sudo:session): session closed for user root Aug 30 10:35:03 volumio autossh[2148]: port set to 0, monitoring disabled Aug 30 10:35:03 volumio volumio[10291]: info: Remote SSH Started Aug 30 10:35:03 volumio autossh[2148]: starting ssh (count 1) Aug 30 10:35:03 volumio autossh[2148]: ssh child pid is 2151 Aug 30 10:35:03 volumio volumio[10291]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 30 10:35:03 volumio volumio[10291]: info: CoreCommandRouter::volumioGetState Aug 30 10:35:03 volumio volumio[10291]: info: CorePlayQueue::getTrack 0 Aug 30 10:35:04 volumio volumiossh-tunnel[2151]: Warning: Permanently added '[us1.myvolumio.org]:2222' (RSA) to the list of known hosts. Aug 30 10:35:05 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:35:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22128. Aug 30 10:35:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:05 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:35:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:05 volumio go-librespot[2166]: go-librespot daemon starting... Aug 30 10:35:05 volumio go-librespot[2167]: time="2026-08-30T10:35:05-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:35:05 volumio go-librespot[2167]: time="2026-08-30T10:35:05-04:00" level=debug msg="app state loaded" Aug 30 10:35:05 volumio go-librespot[2167]: time="2026-08-30T10:35:05-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:35:05 volumio go-librespot[2167]: time="2026-08-30T10:35:05-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 30 10:35:05 volumio go-librespot[2167]: time="2026-08-30T10:35:05-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 30 10:35:05 volumio go-librespot[2167]: time="2026-08-30T10:35:05-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 30 10:35:05 volumio go-librespot[2167]: time="2026-08-30T10:35:05-04:00" level=info msg="zeroconf server listening on port 45089" Aug 30 10:35:05 volumio go-librespot[2167]: time="2026-08-30T10:35:05-04:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:35:06 volumio go-librespot[2167]: time="2026-08-30T10:35:06-04:00" level=debug msg="obtained new client token: AAHBavEF74ccmNGvdRww9zOxQVFXkmMQOC1U0NJszT0t+8QcWsvTB8+zkQ50bnHA4aJy09vhezt+TSiPf2MMxTo9U0gaDYbJt+FrPIYC3LPCj/SfFpFX/aRCVMFyOyIhyo49JrGSMbcBt2RWFI1jd0BjRvlmBmY00ZNlaCABOnfXCg/K5gquD+Z6Vda0RRKjK07Q2kxsaeL3UqAOPKA6k5Wrp04HDFvsOISHgpx5OOjpD0/Rrgm4Rhs1" Aug 30 10:35:06 volumio go-librespot[2167]: time="2026-08-30T10:35:06-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 30 10:35:06 volumio go-librespot[2167]: time="2026-08-30T10:35:06-04:00" level=debug msg="completed keyexchange" Aug 30 10:35:06 volumio go-librespot[2167]: time="2026-08-30T10:35:06-04:00" level=debug msg="completed challenge" Aug 30 10:35:06 volumio go-librespot[2167]: time="2026-08-30T10:35:06-04:00" level=info msg="authenticated AP" username="12******25" Aug 30 10:35:06 volumio go-librespot[2167]: time="2026-08-30T10:35:06-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:35:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:35:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:35:08 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:35:08 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:35:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22129. Aug 30 10:35:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:09 volumio go-librespot[2176]: go-librespot daemon starting... Aug 30 10:35:09 volumio go-librespot[2177]: time="2026-08-30T10:35:09-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:35:09 volumio go-librespot[2177]: time="2026-08-30T10:35:09-04:00" level=debug msg="app state loaded" Aug 30 10:35:09 volumio go-librespot[2177]: time="2026-08-30T10:35:09-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:35:09 volumio go-librespot[2177]: time="2026-08-30T10:35:09-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 30 10:35:09 volumio go-librespot[2177]: time="2026-08-30T10:35:09-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 30 10:35:09 volumio go-librespot[2177]: time="2026-08-30T10:35:09-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 30 10:35:09 volumio go-librespot[2177]: time="2026-08-30T10:35:09-04:00" level=info msg="zeroconf server listening on port 33165" Aug 30 10:35:09 volumio go-librespot[2177]: time="2026-08-30T10:35:09-04:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:35:09 volumio go-librespot[2177]: time="2026-08-30T10:35:09-04:00" level=debug msg="obtained new client token: AAHhuoFxKKd4aolWeBsCHtn57+LL+qHa8iu/lmXBTPLcYpJvwYKa2Gls4OTMlY0Y/cwS9QIhf5Wzx3h11uFJnF9LqtjmUMh1SX3FFZlp9SuuHljyvTcA1Yej1Fo5HOReVwABPeoZXx+r6foK/zveh57zHSrK18UChWXwABsUW+GJ7uc29bm7ihkaHDytNsu5YpaI8cS8eDU4u6gD2DU7K8vuBYUeNvA9LZtFN1HDTgqhF/9McDXniDrsxSM=" Aug 30 10:35:10 volumio go-librespot[2177]: time="2026-08-30T10:35:10-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 30 10:35:10 volumio go-librespot[2177]: time="2026-08-30T10:35:10-04:00" level=debug msg="completed keyexchange" Aug 30 10:35:10 volumio go-librespot[2177]: time="2026-08-30T10:35:10-04:00" level=debug msg="completed challenge" Aug 30 10:35:10 volumio go-librespot[2177]: time="2026-08-30T10:35:10-04:00" level=info msg="authenticated AP" username="12******25" Aug 30 10:35:10 volumio go-librespot[2177]: time="2026-08-30T10:35:10-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:35:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:35:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:35:11 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 10:35:11 volumio volumio[10291]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 30 10:35:11 volumio volumio[10291]: info: CoreCommandRouter::volumioGetState Aug 30 10:35:11 volumio volumio[10291]: info: CorePlayQueue::getTrack 0 Aug 30 10:35:11 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:35:11 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:35:12 volumio wpa_supplicant[1279]: RRM: Ignoring radio measurement request: Not RRM network Aug 30 10:35:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22130. Aug 30 10:35:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:13 volumio go-librespot[2187]: go-librespot daemon starting... Aug 30 10:35:13 volumio go-librespot[2188]: time="2026-08-30T10:35:13-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:35:13 volumio go-librespot[2188]: time="2026-08-30T10:35:13-04:00" level=debug msg="app state loaded" Aug 30 10:35:13 volumio go-librespot[2188]: time="2026-08-30T10:35:13-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:35:14 volumio go-librespot[2188]: time="2026-08-30T10:35:14-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 30 10:35:14 volumio go-librespot[2188]: time="2026-08-30T10:35:14-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 30 10:35:14 volumio go-librespot[2188]: time="2026-08-30T10:35:14-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 30 10:35:14 volumio go-librespot[2188]: time="2026-08-30T10:35:14-04:00" level=info msg="zeroconf server listening on port 37453" Aug 30 10:35:14 volumio go-librespot[2188]: time="2026-08-30T10:35:14-04:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:35:14 volumio go-librespot[2188]: time="2026-08-30T10:35:14-04:00" level=debug msg="obtained new client token: AAECVKBXTP63cpxJCb4hP+g6GG6A3lZenO0c9rdKSNR6PjwNz5Kkm1Llk2VLjfGeLJRHjIMUIwbAJxVbd80mAkn3PtwpTFkVKuyzdSHVamwfpiXUxE5f7pws8Ukhddf5rg16GPTALGe4mIpupKjpt9EFz4bxi5tQoI9N02/ybcUG1MCdGulG+mhTByMkPoHZHmXoYgvM5J0C+WNjeKwi3IovdRksj4zLBaRyqFG2cRH5tClD3eD8VhuF" Aug 30 10:35:14 volumio go-librespot[2188]: time="2026-08-30T10:35:14-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 30 10:35:14 volumio go-librespot[2188]: time="2026-08-30T10:35:14-04:00" level=debug msg="completed keyexchange" Aug 30 10:35:14 volumio go-librespot[2188]: time="2026-08-30T10:35:14-04:00" level=debug msg="completed challenge" Aug 30 10:35:14 volumio go-librespot[2188]: time="2026-08-30T10:35:14-04:00" level=info msg="authenticated AP" username="12******25" Aug 30 10:35:14 volumio go-librespot[2188]: time="2026-08-30T10:35:14-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:35:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:35:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:35:14 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:35:14 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:35:14 volumio wpa_supplicant[1279]: wlan0: WNM: Preferred List Available Aug 30 10:35:14 volumio wpa_supplicant[1279]: wlan0: Trying to associate with 34:98:b5:a4:6b:dd (SSID='borgnet2' freq=5200 MHz) Aug 30 10:35:14 volumio dhcpcd[1015]: wlan0: carrier lost - roaming Aug 30 10:35:14 volumio dhcpcd[1015]: wlan0: changing route to 192.168.121.0/24 Aug 30 10:35:14 volumio dhcpcd[1015]: wlan0: changing default route via 192.168.121.1 Aug 30 10:35:14 volumio kernel: ieee80211 phy0: brcmf_p2p_send_action_frame: Unknown Frame: category 0xa, action 0x8 Aug 30 10:35:15 volumio wpa_supplicant[1279]: wlan0: Associated with 34:98:b5:a4:6b:dd Aug 30 10:35:15 volumio wpa_supplicant[1279]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 30 10:35:15 volumio wpa_supplicant[1279]: wlan0: WPA: Key negotiation completed with 34:98:b5:a4:6b:dd [PTK=CCMP GTK=CCMP] Aug 30 10:35:15 volumio wpa_supplicant[1279]: wlan0: CTRL-EVENT-CONNECTED - Connection to 34:98:b5:a4:6b:dd completed [id=0 id_str=] Aug 30 10:35:15 volumio dhcpcd[1015]: wlan0: carrier acquired Aug 30 10:35:15 volumio dhcpcd[1015]: wlan0: IAID 67:93:93:74 Aug 30 10:35:15 volumio dhcpcd[1015]: wlan0: rebinding lease of 192.168.121.213 Aug 30 10:35:15 volumio dhcpcd[1015]: wlan0: leased 192.168.121.213 for 7171 seconds Aug 30 10:35:15 volumio dhcpcd[1015]: wlan0: changing route to 192.168.121.0/24 Aug 30 10:35:15 volumio dhcpcd[1015]: wlan0: changing default route via 192.168.121.1 Aug 30 10:35:15 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Aug 30 10:35:15 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Aug 30 10:35:15 volumio systemd[1]: welcome.service: Deactivated successfully. Aug 30 10:35:15 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Aug 30 10:35:15 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Aug 30 10:35:15 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Aug 30 10:35:15 volumio welcome[2245]: Resolved ip:[1] 192.168.121.213 Aug 30 10:35:15 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Aug 30 10:35:15 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Aug 30 10:35:15 volumio volumio[10291]: info: Received Get System Info Aug 30 10:35:15 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:35:15 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:35:15 volumio volumio[10291]: info: Discovery: Getting this device information Aug 30 10:35:15 volumio volumio[10291]: info: CoreCommandRouter::volumioGetState Aug 30 10:35:15 volumio volumio[10291]: info: CorePlayQueue::getTrack 0 Aug 30 10:35:15 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:35:15 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 10:35:15 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 10:35:16 volumio dhcpcd[1015]: wlan0: soliciting an IPv6 router Aug 30 10:35:16 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 10:35:16 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 10:35:16 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 30 10:35:16 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 30 10:35:16 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 30 10:35:16 volumio volumio[10291]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 10:35:16 volumio volumio[10291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 10:35:16 volumio volumio5-onboarding[1637]: time=2026-08-30T10:35:16.958-04:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 30 10:35:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22131. Aug 30 10:35:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:17 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:35:17 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:35:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:17 volumio go-librespot[2257]: go-librespot daemon starting... Aug 30 10:35:17 volumio go-librespot[2258]: time="2026-08-30T10:35:17-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:35:17 volumio go-librespot[2258]: time="2026-08-30T10:35:17-04:00" level=debug msg="app state loaded" Aug 30 10:35:17 volumio go-librespot[2258]: time="2026-08-30T10:35:17-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:35:17 volumio go-librespot[2258]: time="2026-08-30T10:35:17-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 30 10:35:17 volumio go-librespot[2258]: time="2026-08-30T10:35:17-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 30 10:35:17 volumio go-librespot[2258]: time="2026-08-30T10:35:17-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 30 10:35:17 volumio go-librespot[2258]: time="2026-08-30T10:35:17-04:00" level=info msg="zeroconf server listening on port 46031" Aug 30 10:35:17 volumio go-librespot[2258]: time="2026-08-30T10:35:17-04:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:35:17 volumio go-librespot[2258]: time="2026-08-30T10:35:17-04:00" level=debug msg="obtained new client token: AAGwNJYNkj67kHmOtfxzSA7/TXB4E7lFoym+4o6+hkNTM6k7mFA1ULpelCG5jrf4/6MkbrSVaZu7Oq3c+L6YU5Yzr83ci9hjeSNCQGZxIU2AkDAFpnfV0qWRjDMffyOEgA16+cnyW5XnIDLOUvdqzxLdeb/suP4Dq9Ixdv457w69eytaIuRMEhc5oRB4uGRWEbyhaOD6nNYwxxoAFXMxshueYbrlZ+VJyE18wE5gC5CN65fMZVhA20/HJSk=" Aug 30 10:35:18 volumio go-librespot[2258]: time="2026-08-30T10:35:18-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 30 10:35:18 volumio go-librespot[2258]: time="2026-08-30T10:35:18-04:00" level=debug msg="completed keyexchange" Aug 30 10:35:18 volumio go-librespot[2258]: time="2026-08-30T10:35:18-04:00" level=debug msg="completed challenge" Aug 30 10:35:18 volumio go-librespot[2258]: time="2026-08-30T10:35:18-04:00" level=info msg="authenticated AP" username="12******25" Aug 30 10:35:18 volumio go-librespot[2258]: time="2026-08-30T10:35:18-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:35:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:35:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:35:20 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:35:20 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:35:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22132. Aug 30 10:35:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:21 volumio go-librespot[2268]: go-librespot daemon starting... Aug 30 10:35:21 volumio go-librespot[2269]: time="2026-08-30T10:35:21-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:35:21 volumio go-librespot[2269]: time="2026-08-30T10:35:21-04:00" level=debug msg="app state loaded" Aug 30 10:35:21 volumio go-librespot[2269]: time="2026-08-30T10:35:21-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:35:21 volumio go-librespot[2269]: time="2026-08-30T10:35:21-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 30 10:35:21 volumio go-librespot[2269]: time="2026-08-30T10:35:21-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 30 10:35:21 volumio go-librespot[2269]: time="2026-08-30T10:35:21-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 30 10:35:21 volumio go-librespot[2269]: time="2026-08-30T10:35:21-04:00" level=info msg="zeroconf server listening on port 43229" Aug 30 10:35:21 volumio go-librespot[2269]: time="2026-08-30T10:35:21-04:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:35:21 volumio go-librespot[2269]: time="2026-08-30T10:35:21-04:00" level=debug msg="obtained new client token: AAHDisGIMaMUOqh4hsvjJCmyYoZ0zeUU+SMwiUhVf2FsBJCsC1i/oGkkwy/7GIfoOh/9TD9AjAk8wLnk21SSrGRizswYfePNlpCg6VpZc10pa/RurX2bzNQHMg4qqSj04Fvj6dYcjSXD+HCw2eB1gXHIttg+kBYXAlJfkVzDbDE4nS38hkNXU7RXNmyi+ecWo7sE8SS6jdtsFwEcT19jrY+jpCTF1SAk0xDPJhbEVcvJ4T+pJX9lfE1tBQc=" Aug 30 10:35:21 volumio go-librespot[2269]: time="2026-08-30T10:35:21-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 30 10:35:21 volumio go-librespot[2269]: time="2026-08-30T10:35:21-04:00" level=debug msg="completed keyexchange" Aug 30 10:35:21 volumio go-librespot[2269]: time="2026-08-30T10:35:21-04:00" level=debug msg="completed challenge" Aug 30 10:35:21 volumio go-librespot[2269]: time="2026-08-30T10:35:21-04:00" level=info msg="authenticated AP" username="12******25" Aug 30 10:35:22 volumio go-librespot[2269]: time="2026-08-30T10:35:22-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:35:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:35:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:35:23 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:35:23 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:35:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22133. Aug 30 10:35:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:25 volumio go-librespot[2293]: go-librespot daemon starting... Aug 30 10:35:25 volumio go-librespot[2294]: time="2026-08-30T10:35:25-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:35:25 volumio go-librespot[2294]: time="2026-08-30T10:35:25-04:00" level=debug msg="app state loaded" Aug 30 10:35:25 volumio go-librespot[2294]: time="2026-08-30T10:35:25-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:35:25 volumio go-librespot[2294]: time="2026-08-30T10:35:25-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 30 10:35:25 volumio go-librespot[2294]: time="2026-08-30T10:35:25-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 30 10:35:25 volumio go-librespot[2294]: time="2026-08-30T10:35:25-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 30 10:35:25 volumio go-librespot[2294]: time="2026-08-30T10:35:25-04:00" level=info msg="zeroconf server listening on port 41501" Aug 30 10:35:25 volumio go-librespot[2294]: time="2026-08-30T10:35:25-04:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:35:25 volumio go-librespot[2294]: time="2026-08-30T10:35:25-04:00" level=debug msg="obtained new client token: AAHogRPwB4dzxtmff9CoLFGXVOVH5qjGQCkxqAuZnJxWwIlzvuu9mJN00ozLuT8K4B0E0ua6t3FMyNxCiaxp55iVE1YvEqAtm997+z6r7gUjqvpaOC0pnG6irKamMgR2c4WEnP7OKP/tcNlTDNIAo4XpaUwjAxtsMz9SEJOO884BntX33yZP4X6/2gTPQfgyeSX2TyTQIqcBiRQVk5Re+ZX4z2Qw5nnh1t38zfGUvddfw6JTWEyEC1Z8pys=" Aug 30 10:35:25 volumio go-librespot[2294]: time="2026-08-30T10:35:25-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 30 10:35:25 volumio go-librespot[2294]: time="2026-08-30T10:35:25-04:00" level=debug msg="completed keyexchange" Aug 30 10:35:25 volumio go-librespot[2294]: time="2026-08-30T10:35:25-04:00" level=debug msg="completed challenge" Aug 30 10:35:25 volumio go-librespot[2294]: time="2026-08-30T10:35:25-04:00" level=info msg="authenticated AP" username="12******25" Aug 30 10:35:25 volumio go-librespot[2294]: time="2026-08-30T10:35:25-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:35:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:35:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:35:26 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:35:26 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:35:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22134. Aug 30 10:35:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:29 volumio go-librespot[2303]: go-librespot daemon starting... Aug 30 10:35:29 volumio go-librespot[2304]: time="2026-08-30T10:35:29-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:35:29 volumio go-librespot[2304]: time="2026-08-30T10:35:29-04:00" level=debug msg="app state loaded" Aug 30 10:35:29 volumio go-librespot[2304]: time="2026-08-30T10:35:29-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:35:29 volumio go-librespot[2304]: time="2026-08-30T10:35:29-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 30 10:35:29 volumio go-librespot[2304]: time="2026-08-30T10:35:29-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 30 10:35:29 volumio go-librespot[2304]: time="2026-08-30T10:35:29-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 30 10:35:29 volumio go-librespot[2304]: time="2026-08-30T10:35:29-04:00" level=info msg="zeroconf server listening on port 45939" Aug 30 10:35:29 volumio go-librespot[2304]: time="2026-08-30T10:35:29-04:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:35:29 volumio go-librespot[2304]: time="2026-08-30T10:35:29-04:00" level=debug msg="obtained new client token: AAHCj1NiI6+rT3yOH9Y2cy0+YXFLFAOC7HWGLYYyexMkJvuqG2MkSN1awlxeqQy2G6CkJrmNmdApcc5/rHVmIV56ZcLFw1IuRozK7YhI3xVmlwB2A9HCvagA5/tAXQ9xI47/5ABGSU+s/DWh8RiaJjIp32mS2oxa5HB/x1aUtwL1VHGqRUhrErL49DnX+Hyb+qKQsjT2Ys67nr6nrAQKB1lmDNkP676U+ktMrh3cj+/nDScnS2bxp2R/" Aug 30 10:35:29 volumio go-librespot[2304]: time="2026-08-30T10:35:29-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.255.62:4070: connect: connection refused" Aug 30 10:35:29 volumio go-librespot[2304]: time="2026-08-30T10:35:29-04:00" level=debug msg="connected to ap-gue1.spotify.com:443" Aug 30 10:35:29 volumio go-librespot[2304]: time="2026-08-30T10:35:29-04:00" level=debug msg="completed keyexchange" Aug 30 10:35:29 volumio go-librespot[2304]: time="2026-08-30T10:35:29-04:00" level=debug msg="completed challenge" Aug 30 10:35:29 volumio go-librespot[2304]: time="2026-08-30T10:35:29-04:00" level=info msg="authenticated AP" username="12******25" Aug 30 10:35:29 volumio go-librespot[2304]: time="2026-08-30T10:35:29-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:35:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:35:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:35:29 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:35:29 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:35:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22135. Aug 30 10:35:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:32 volumio go-librespot[2314]: go-librespot daemon starting... Aug 30 10:35:32 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:35:32 volumio go-librespot[2315]: time="2026-08-30T10:35:32-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:35:32 volumio go-librespot[2315]: time="2026-08-30T10:35:32-04:00" level=debug msg="app state loaded" Aug 30 10:35:32 volumio go-librespot[2315]: time="2026-08-30T10:35:32-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:35:32 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:35:32 volumio go-librespot[2315]: time="2026-08-30T10:35:32-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]" Aug 30 10:35:32 volumio go-librespot[2315]: time="2026-08-30T10:35:32-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 30 10:35:32 volumio go-librespot[2315]: time="2026-08-30T10:35:32-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 30 10:35:32 volumio go-librespot[2315]: time="2026-08-30T10:35:32-04:00" level=info msg="zeroconf server listening on port 39499" Aug 30 10:35:32 volumio go-librespot[2315]: time="2026-08-30T10:35:32-04:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:35:33 volumio go-librespot[2315]: time="2026-08-30T10:35:33-04:00" level=debug msg="obtained new client token: AAGEmXfVxP2CKgHVkrN34Vh5sKcuxcs7Yhxe4MqGd8G2Osq0nzVgWxohhCnuwPs80JqjgGqLlR5tdqsUFRrdrA4yXXNt2+FFf0WMhCI3LC0A14kZHObYwLH43428ClY+48MMO58pfALRR2VxtFWB5E5P7z7vxjlD3X30/FL5VivJ7QlM4fWIOh7Evcc3jZlTz2xQC7A8l/lAd2SrP1IjKwwWZEbfY/h0ljB2HjiTbPgZdhpf3FY4UGyOxng=" Aug 30 10:35:33 volumio go-librespot[2315]: time="2026-08-30T10:35:33-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 30 10:35:33 volumio go-librespot[2315]: time="2026-08-30T10:35:33-04:00" level=debug msg="completed keyexchange" Aug 30 10:35:33 volumio go-librespot[2315]: time="2026-08-30T10:35:33-04:00" level=debug msg="completed challenge" Aug 30 10:35:33 volumio go-librespot[2315]: time="2026-08-30T10:35:33-04:00" level=info msg="authenticated AP" username="12******25" Aug 30 10:35:33 volumio go-librespot[2315]: time="2026-08-30T10:35:33-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:35:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:35:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:35:35 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:35:35 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:35:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22136. Aug 30 10:35:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:36 volumio go-librespot[2338]: go-librespot daemon starting... Aug 30 10:35:36 volumio go-librespot[2339]: time="2026-08-30T10:35:36-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:35:36 volumio go-librespot[2339]: time="2026-08-30T10:35:36-04:00" level=debug msg="app state loaded" Aug 30 10:35:36 volumio go-librespot[2339]: time="2026-08-30T10:35:36-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:35:36 volumio go-librespot[2339]: time="2026-08-30T10:35:36-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]" Aug 30 10:35:36 volumio go-librespot[2339]: time="2026-08-30T10:35:36-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 30 10:35:36 volumio go-librespot[2339]: time="2026-08-30T10:35:36-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 30 10:35:36 volumio go-librespot[2339]: time="2026-08-30T10:35:36-04:00" level=info msg="zeroconf server listening on port 46149" Aug 30 10:35:36 volumio go-librespot[2339]: time="2026-08-30T10:35:36-04:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:35:36 volumio go-librespot[2339]: time="2026-08-30T10:35:36-04:00" level=debug msg="obtained new client token: AAEA/BifaWZo6uSar1LetZXr3jM9U0s/u1yPG2Jfad6lOLHJy8oCP6GEJiVBGpJFvAGfx/xUuK2Zi69EOivAdhjl0u0+AFzHfW29WSREVy9DyRTfyLEnEFon7kwBmkkPOvuzEaIwrxXVdoGIPp+t5x9wBWH66GRNfM7AaxcgsTcaySaVAHADOxYqys34gRLM78WxSY65OICQ2ESaZuV7PQLpZ5nPr4gFXPVXlQL6x8qCbz5oVn0l6IwZc/Q=" Aug 30 10:35:36 volumio go-librespot[2339]: time="2026-08-30T10:35:36-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 30 10:35:36 volumio go-librespot[2339]: time="2026-08-30T10:35:36-04:00" level=debug msg="completed keyexchange" Aug 30 10:35:36 volumio go-librespot[2339]: time="2026-08-30T10:35:36-04:00" level=debug msg="completed challenge" Aug 30 10:35:36 volumio go-librespot[2339]: time="2026-08-30T10:35:36-04:00" level=info msg="authenticated AP" username="12******25" Aug 30 10:35:37 volumio go-librespot[2339]: time="2026-08-30T10:35:37-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:35:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:35:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:35:38 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:35:38 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:35:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22137. Aug 30 10:35:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:40 volumio go-librespot[2349]: go-librespot daemon starting... Aug 30 10:35:40 volumio go-librespot[2350]: time="2026-08-30T10:35:40-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:35:40 volumio go-librespot[2350]: time="2026-08-30T10:35:40-04:00" level=debug msg="app state loaded" Aug 30 10:35:40 volumio go-librespot[2350]: time="2026-08-30T10:35:40-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:35:40 volumio go-librespot[2350]: time="2026-08-30T10:35:40-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]" Aug 30 10:35:40 volumio go-librespot[2350]: time="2026-08-30T10:35:40-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 30 10:35:40 volumio go-librespot[2350]: time="2026-08-30T10:35:40-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 30 10:35:40 volumio go-librespot[2350]: time="2026-08-30T10:35:40-04:00" level=info msg="zeroconf server listening on port 34201" Aug 30 10:35:40 volumio go-librespot[2350]: time="2026-08-30T10:35:40-04:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:35:40 volumio go-librespot[2350]: time="2026-08-30T10:35:40-04:00" level=debug msg="obtained new client token: AAEXyzPhpR210D5qOMq2FX8VCN8lXeUQzCCExKG4b1YaW5ovp+6SbE1Xz3neu3Z9LH7e1iu/f5Ak0el1yF7P+aHIpFzItSE/Mu4b9PxDeSs69IYeSg595e1vC51UFMFaTKCrgzVtvzvnRLW8OvhqWOGOENwRXRzzInf6En5VydbG0DfcN8q0jojihZWgPlqXAmFZP1uDPhTI9TUmJpO4YSuUiB42X4hLEXzrpxB3P2VHjKxG9qH5+26IP6E=" Aug 30 10:35:40 volumio go-librespot[2350]: time="2026-08-30T10:35:40-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 30 10:35:40 volumio go-librespot[2350]: time="2026-08-30T10:35:40-04:00" level=debug msg="completed keyexchange" Aug 30 10:35:40 volumio go-librespot[2350]: time="2026-08-30T10:35:40-04:00" level=debug msg="completed challenge" Aug 30 10:35:40 volumio go-librespot[2350]: time="2026-08-30T10:35:40-04:00" level=info msg="authenticated AP" username="12******25" Aug 30 10:35:40 volumio go-librespot[2350]: time="2026-08-30T10:35:40-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:35:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:35:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:35:41 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:35:41 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:35:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22138. Aug 30 10:35:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:44 volumio go-librespot[2360]: go-librespot daemon starting... Aug 30 10:35:44 volumio go-librespot[2361]: time="2026-08-30T10:35:44-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:35:44 volumio go-librespot[2361]: time="2026-08-30T10:35:44-04:00" level=debug msg="app state loaded" Aug 30 10:35:44 volumio go-librespot[2361]: time="2026-08-30T10:35:44-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:35:44 volumio go-librespot[2361]: time="2026-08-30T10:35:44-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 30 10:35:44 volumio go-librespot[2361]: time="2026-08-30T10:35:44-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 30 10:35:44 volumio go-librespot[2361]: time="2026-08-30T10:35:44-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 30 10:35:44 volumio go-librespot[2361]: time="2026-08-30T10:35:44-04:00" level=info msg="zeroconf server listening on port 37791" Aug 30 10:35:44 volumio go-librespot[2361]: time="2026-08-30T10:35:44-04:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:35:44 volumio go-librespot[2361]: time="2026-08-30T10:35:44-04:00" level=debug msg="obtained new client token: AAEP6OL1Yc8BLAM+uLPPZS2gnJoqzySCIhlgZ/9YIbOqVMEr4iUJnTC+hwXCRwQRKZQQCL4NB4c4WTJHvfLhpYCY9OhqWCQydResHNkn4OpflkhAt3klS3/cmhIgTKgxDpsuqpWxjHCbXyQ41iI8ZXvhfTtTBGN/e19oNSRIBftXvdmSBDbPRbmMoHZCR2pj1xbIbEkmi1xIhzPx9dqwyyu5wu/D8dfiuv6kAaGdg/hDaf7KNxvMOT3d" Aug 30 10:35:44 volumio go-librespot[2361]: time="2026-08-30T10:35:44-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 30 10:35:44 volumio go-librespot[2361]: time="2026-08-30T10:35:44-04:00" level=debug msg="completed keyexchange" Aug 30 10:35:44 volumio go-librespot[2361]: time="2026-08-30T10:35:44-04:00" level=debug msg="completed challenge" Aug 30 10:35:44 volumio go-librespot[2361]: time="2026-08-30T10:35:44-04:00" level=info msg="authenticated AP" username="12******25" Aug 30 10:35:44 volumio go-librespot[2361]: time="2026-08-30T10:35:44-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:35:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:35:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:35:44 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:35:44 volumio volumio[10291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:35:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22139. Aug 30 10:35:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:35:47 volumio go-librespot[2384]: go-librespot daemon starting... Aug 30 10:35:47 volumio go-librespot[2385]: time="2026-08-30T10:35:47-04:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:35:47 volumio go-librespot[2385]: time="2026-08-30T10:35:47-04:00" level=debug msg="app state loaded" Aug 30 10:35:47 volumio go-librespot[2385]: time="2026-08-30T10:35:47-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:35:47 volumio volumio[10291]: info: Initializing connection to go-librespot Websocket Aug 30 10:35:47 volumio volumio[10291]: info: Connection to go-librespot Websocket established Aug 30 10:35:47 volumio go-librespot[2385]: time="2026-08-30T10:35:47-04:00" level=debug msg="new websocket client" Aug 30 10:35:47 volumio go-librespot[2385]: time="2026-08-30T10:35:47-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 30 10:35:47 volumio go-librespot[2385]: time="2026-08-30T10:35:47-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 30 10:35:47 volumio go-librespot[2385]: time="2026-08-30T10:35:47-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 30 10:35:47 volumio go-librespot[2385]: time="2026-08-30T10:35:47-04:00" level=info msg="zeroconf server listening on port 40369" Aug 30 10:35:48 volumio go-librespot[2385]: time="2026-08-30T10:35:48-04:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:35:48 volumio go-librespot[2385]: time="2026-08-30T10:35:48-04:00" level=debug msg="obtained new client token: AAFeF/OonumFSoU6S3knyi0G231U2fOOFE49plsyy4xHJd13BLePkWV9EmwyAbFFnx4QgdOQw7ylcnyxpJ5x4FE3tDb3NP1PPbr39B7mhwEW5sdCommyCd/mJuRK9G3Ztux/CE76KDWXchGX9L5DPTizbfk3N3kEDnXXW730au9QEDC1qIDbGjrkr0i9LOCcGJFmi8cNBxBqQbzV5/rKvnGyokWr7NpDrrDND/QK7mymSJaJwnW0DzkB" Aug 30 10:35:48 volumio go-librespot[2385]: time="2026-08-30T10:35:48-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 30 10:35:48 volumio go-librespot[2385]: time="2026-08-30T10:35:48-04:00" level=debug msg="completed keyexchange" Aug 30 10:35:48 volumio go-librespot[2385]: time="2026-08-30T10:35:48-04:00" level=debug msg="completed challenge" Aug 30 10:35:48 volumio go-librespot[2385]: time="2026-08-30T10:35:48-04:00" level=info msg="authenticated AP" username="12******25" Aug 30 10:35:48 volumio go-librespot[2385]: time="2026-08-30T10:35:48-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:35:48 volumio volumio[10291]: info: Connection to go-librespot Websocket closed Aug 30 10:35:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:35:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:35:50 volumio volumio[10291]: info: Getting Spotify volume Aug 30 10:35:50 volumio volumio[10291]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 10:35:50 volumio volumio[10291]: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:35:50 volumio volumio[10291]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Aug 30 10:35:50 volumio volumio[10291]: errno: -111, Aug 30 10:35:50 volumio volumio[10291]: code: 'ECONNREFUSED', Aug 30 10:35:50 volumio volumio[10291]: syscall: 'connect', Aug 30 10:35:50 volumio volumio[10291]: address: '127.0.0.1', Aug 30 10:35:50 volumio volumio[10291]: port: 9879, Aug 30 10:35:50 volumio volumio[10291]: response: undefined Aug 30 10:35:50 volumio volumio[10291]: } Aug 30 10:35:50 volumio volumio[10291]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 10:35:50 volumio sudo[2408]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-30 10:34' Aug 30 10:35:50 volumio sudo[2408]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"