Feb 26 02:07:00 volumio go-librespot[1644619]: go-librespot daemon starting... Feb 26 02:07:00 volumio go-librespot[1644620]: time="2026-02-26T02:07:00+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:07:00 volumio go-librespot[1644620]: time="2026-02-26T02:07:00+07:00" level=debug msg="app state loaded" Feb 26 02:07:00 volumio go-librespot[1644620]: time="2026-02-26T02:07:00+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:07:02 volumio go-librespot[1644620]: time="2026-02-26T02:07:01+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 26 02:07:02 volumio go-librespot[1644620]: time="2026-02-26T02:07:01+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 26 02:07:02 volumio go-librespot[1644620]: time="2026-02-26T02:07:01+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 26 02:07:02 volumio go-librespot[1644620]: time="2026-02-26T02:07:01+07:00" level=info msg="zeroconf server listening on port 42213" Feb 26 02:07:02 volumio go-librespot[1644620]: time="2026-02-26T02:07:02+07:00" level=debug msg="obtained new client token: AAB9WHEMk9WvP0aW4xVyMM62Yalviyy/RdtjB7NVNibI2JH2a6WVDEJWsCWKYHoad/hWXUKvQM3A3C15CJEMaGauMKRll+iLE3SXmJ11PPfKr6jlQfdhYzv2YSTE4l1ChEgtX0C5VqDv+1m5za1C+vTRQKJhAaiGFt9cR3BpFgM63PAIld17uv/YpQt/Sp35B3Zj+33Q7xq69hvnozSIo55g1qL1eSIvSho/KxpyBF6ufSQZmMM8qis0" Feb 26 02:07:02 volumio go-librespot[1644620]: time="2026-02-26T02:07:02+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:07:02 volumio go-librespot[1644620]: time="2026-02-26T02:07:02+07:00" level=debug msg="completed keyexchange" Feb 26 02:07:02 volumio go-librespot[1644620]: time="2026-02-26T02:07:02+07:00" level=debug msg="completed challenge" Feb 26 02:07:03 volumio go-librespot[1644620]: time="2026-02-26T02:07:03+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:07:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:07:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:07:04 volumio volumio[1643994]: info: Initializing connection to go-librespot Websocket Feb 26 02:07:04 volumio volumio[1643994]: info: CoreCommandRouter::volumioGetState Feb 26 02:07:04 volumio volumio[1643994]: info: CorePlayQueue::getTrack 0 Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 26 02:07:05 volumio volumio[1643994]: info: Adding plugin bluetooth to MyMusic Plugins Feb 26 02:07:05 volumio volumio[1643994]: info: Adding plugin multiroom to MyMusic Plugins Feb 26 02:07:05 volumio volumio[1643994]: info: Adding plugin metavolumio to MyMusic Plugins Feb 26 02:07:05 volumio volumio[1643994]: info: Adding plugin cd_controller to MyMusic Plugins Feb 26 02:07:05 volumio volumio[1643994]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 26 02:07:05 volumio volumio[1643994]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 26 02:07:05 volumio volumio[1643994]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 26 02:07:05 volumio volumio[1643994]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 26 02:07:05 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:07:05 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:07:05 volumio volumio[1643994]: info: Starting MyVolumio Remote Streaming Endpoints Feb 26 02:07:05 volumio volumio[1643994]: error: Cannot start Volumio Streaming Daemon Feb 26 02:07:05 volumio volumio[1643994]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 26 02:07:05 volumio volumio[1643994]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 26 02:07:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Feb 26 02:07:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:07:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:07:07 volumio go-librespot[1644647]: go-librespot daemon starting... Feb 26 02:07:07 volumio go-librespot[1644649]: time="2026-02-26T02:07:07+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:07:07 volumio go-librespot[1644649]: time="2026-02-26T02:07:07+07:00" level=debug msg="app state loaded" Feb 26 02:07:07 volumio go-librespot[1644649]: time="2026-02-26T02:07:07+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:07:08 volumio go-librespot[1644649]: time="2026-02-26T02:07:08+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:07:08 volumio go-librespot[1644649]: time="2026-02-26T02:07:08+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:07:08 volumio go-librespot[1644649]: time="2026-02-26T02:07:08+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:07:08 volumio go-librespot[1644649]: time="2026-02-26T02:07:08+07:00" level=info msg="zeroconf server listening on port 35341" Feb 26 02:07:08 volumio go-librespot[1644649]: time="2026-02-26T02:07:08+07:00" level=debug msg="obtained new client token: AAAQhdYAQSm49hLDLrEVNsZfEtaGmx28A8gO1dYlmnYgcnNkBkS8fTbvLAttZGfobqlTX2+YvQTn+nK06YU35HuyIbTyvKAbNUQVj3Or3VFQAhj2mYhxPHhwg03cQnY+qIq508s68Qccr2gC07XezrsEdMIkZV/RmNELs+wEaXzFP18Z39CHrUaHeA8+aEEJJZGUDeXDnb3gpG/GwZw9JLKAxIs1Dg78zWDD/IyKwCRBn2hORqh8T7YvQ3w=" Feb 26 02:07:08 volumio go-librespot[1644649]: time="2026-02-26T02:07:08+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:07:08 volumio go-librespot[1644649]: time="2026-02-26T02:07:08+07:00" level=debug msg="completed keyexchange" Feb 26 02:07:08 volumio go-librespot[1644649]: time="2026-02-26T02:07:08+07:00" level=debug msg="completed challenge" Feb 26 02:07:09 volumio go-librespot[1644649]: time="2026-02-26T02:07:09+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:07:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:07:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:07:10 volumio volumio[1643994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 02:07:12 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 26 02:07:12 volumio volumio[1643994]: info: CoreCommandRouter::volumioGetState Feb 26 02:07:12 volumio volumio[1643994]: info: CorePlayQueue::getTrack 0 Feb 26 02:07:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Feb 26 02:07:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:07:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:07:13 volumio go-librespot[1644675]: go-librespot daemon starting... Feb 26 02:07:13 volumio go-librespot[1644676]: time="2026-02-26T02:07:13+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:07:13 volumio go-librespot[1644676]: time="2026-02-26T02:07:13+07:00" level=debug msg="app state loaded" Feb 26 02:07:13 volumio go-librespot[1644676]: time="2026-02-26T02:07:13+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:07:14 volumio go-librespot[1644676]: time="2026-02-26T02:07:14+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 26 02:07:14 volumio go-librespot[1644676]: time="2026-02-26T02:07:14+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 26 02:07:14 volumio go-librespot[1644676]: time="2026-02-26T02:07:14+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 26 02:07:14 volumio go-librespot[1644676]: time="2026-02-26T02:07:14+07:00" level=info msg="zeroconf server listening on port 37383" Feb 26 02:07:14 volumio go-librespot[1644676]: time="2026-02-26T02:07:14+07:00" level=debug msg="obtained new client token: AADdwMnAUfMsaVgY71yCn0Jtu66ds22u0/BlGDbnYfe7i7x4pxI+on14As7G9OjBP+AFWWx0+D4Zk7l7LobA30DP6355wsylr9PfmsUHsE1wOgcinEL18ar2UcqllKOW6U78j2lOiaOXBsbX70hM39PoaJFNsMp5e7Q041QkI9BojbLOVeHEwxjlPkc3Ac7GnnVhO5evsh9vauWWqgoXtrZiAtZq7N8jqvBrM9kakHlhROipYt3oUpBXqrM=" Feb 26 02:07:14 volumio go-librespot[1644676]: time="2026-02-26T02:07:14+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:07:15 volumio go-librespot[1644676]: time="2026-02-26T02:07:15+07:00" level=debug msg="completed keyexchange" Feb 26 02:07:15 volumio go-librespot[1644676]: time="2026-02-26T02:07:15+07:00" level=debug msg="completed challenge" Feb 26 02:07:15 volumio go-librespot[1644676]: time="2026-02-26T02:07:15+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:07:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:07:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:07:16 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:07:16 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:07:16 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 26 02:07:16 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 26 02:07:16 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 26 02:07:16 volumio volumio[1643994]: info: CoreCommandRouter::volumioGetBrowseSources Feb 26 02:07:16 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 02:07:16 volumio volumio[1643994]: info: Initializing connection to go-librespot Websocket Feb 26 02:07:17 volumio volumio[1643994]: info: CoreCommandRouter::volumioGetState Feb 26 02:07:17 volumio volumio[1643994]: info: CorePlayQueue::getTrack 0 Feb 26 02:07:17 volumio volumio[1643994]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 26 02:07:17 volumio volumio[1643994]: info: Completed starting MyVolumio Plugin Feb 26 02:07:17 volumio volumio[1643994]: [Metrics] CommandRouter: 124s 358.26ms Feb 26 02:07:17 volumio volumio[1643994]: info: CoreCommandRouter::volumiosetStartupVolume Feb 26 02:07:17 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:07:17 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:07:18 volumio volumio[1643994]: info: CoreCommandRouter::Close All Modals sent Feb 26 02:07:18 volumio volumio[1643994]: info: CoreCommandRouter::Close All Modals sent Feb 26 02:07:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Feb 26 02:07:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:07:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:07:19 volumio go-librespot[1644694]: go-librespot daemon starting... Feb 26 02:07:19 volumio go-librespot[1644695]: time="2026-02-26T02:07:19+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:07:19 volumio go-librespot[1644695]: time="2026-02-26T02:07:19+07:00" level=debug msg="app state loaded" Feb 26 02:07:19 volumio go-librespot[1644695]: time="2026-02-26T02:07:19+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:07:19 volumio volumio[1643994]: info: Listing playlists Feb 26 02:07:19 volumio volumio[1643994]: info: Listing playlists Feb 26 02:07:20 volumio go-librespot[1644695]: time="2026-02-26T02:07:20+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:07:20 volumio go-librespot[1644695]: time="2026-02-26T02:07:20+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:07:20 volumio go-librespot[1644695]: time="2026-02-26T02:07:20+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:07:20 volumio go-librespot[1644695]: time="2026-02-26T02:07:20+07:00" level=info msg="zeroconf server listening on port 33823" Feb 26 02:07:20 volumio go-librespot[1644695]: time="2026-02-26T02:07:20+07:00" level=debug msg="obtained new client token: AAC5KE32Bv0B23EAPCMZV5eG+cvow3SKlFzQ8xfQEIrJjE4PIW9WOOeq7R1kF8G6wzFwf4da2WeTVnHfeZ2bJ7BSpNbGK1wyC8ZFl16Lb8P6dZA+EwwWGkNmPaeHqdNcUHHrEJbKKQxm1aqvk6UEVH2JUZ6QpAXH41XIHNN5xvuniXrqwyoWmzfI7Skm2jiq5k3ybwsIWqEC9E+1fJN6M/ZhTjsN9yYm7s5ahiFz4/thWnJcfatBTiRX8S4=" Feb 26 02:07:20 volumio go-librespot[1644695]: time="2026-02-26T02:07:20+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:07:21 volumio go-librespot[1644695]: time="2026-02-26T02:07:21+07:00" level=debug msg="completed keyexchange" Feb 26 02:07:21 volumio go-librespot[1644695]: time="2026-02-26T02:07:21+07:00" level=debug msg="completed challenge" Feb 26 02:07:21 volumio go-librespot[1644695]: time="2026-02-26T02:07:21+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:07:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:07:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:07:23 volumio volumio[1643994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 02:07:24 volumio volumio[1643994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 26 02:07:24 volumio volumio[1643994]: info: camilladsp respawn in 100 ms (attempt 1/10) Feb 26 02:07:24 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 26 02:07:24 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 26 02:07:24 volumio volumio[1643994]: aplay: device_list:274: no soundcards found... Feb 26 02:07:24 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Feb 26 02:07:24 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 26 02:07:24 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 26 02:07:24 volumio volumio[1643994]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 26 02:07:24 volumio volumio[1643994]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 26 02:07:24 volumio volumio[1643994]: info: camilladsp service terminated, instance 1 Feb 26 02:07:24 volumio volumio[1643994]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 26 02:07:24 volumio volumio[1643994]: info: camilladsp service started and running in background, instance 1 Feb 26 02:07:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Feb 26 02:07:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:07:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:07:25 volumio go-librespot[1644724]: go-librespot daemon starting... Feb 26 02:07:25 volumio go-librespot[1644726]: time="2026-02-26T02:07:25+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:07:25 volumio go-librespot[1644726]: time="2026-02-26T02:07:25+07:00" level=debug msg="app state loaded" Feb 26 02:07:25 volumio go-librespot[1644726]: time="2026-02-26T02:07:25+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:07:26 volumio go-librespot[1644726]: time="2026-02-26T02:07:26+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:07:26 volumio go-librespot[1644726]: time="2026-02-26T02:07:26+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:07:26 volumio go-librespot[1644726]: time="2026-02-26T02:07:26+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:07:26 volumio go-librespot[1644726]: time="2026-02-26T02:07:26+07:00" level=info msg="zeroconf server listening on port 38627" Feb 26 02:07:26 volumio go-librespot[1644726]: time="2026-02-26T02:07:26+07:00" level=debug msg="obtained new client token: AABiXlJRMhAnmG+Mr3e6GkDQQl2aeQp7kjU9N+MCmzvf6mY2lInP31zzZd+GeeB1fs0qpsrsuaRaml6IjEbMgfCFTegODrQrSMtsEX16VTBbFAkPTYaq3paCQIpRbAYiBNpGqkBwQq+QwvWZ4uQf49/0jFeIbnOyenxQm9oSxgAlCHnQCtV+i/yykR3xLV38auNXws8wUHb2xroyA41goFYLqO4EjapvAQKONDvfWkNR3vrtwsspyoF19AQ=" Feb 26 02:07:26 volumio go-librespot[1644726]: time="2026-02-26T02:07:26+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:07:27 volumio go-librespot[1644726]: time="2026-02-26T02:07:27+07:00" level=debug msg="completed keyexchange" Feb 26 02:07:27 volumio go-librespot[1644726]: time="2026-02-26T02:07:27+07:00" level=debug msg="completed challenge" Feb 26 02:07:27 volumio go-librespot[1644726]: time="2026-02-26T02:07:27+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:07:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:07:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:07:28 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:07:28 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:07:28 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 26 02:07:28 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 26 02:07:28 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 26 02:07:28 volumio volumio[1643994]: info: CoreCommandRouter::volumioGetBrowseSources Feb 26 02:07:28 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 02:07:28 volumio volumio[1643994]: info: BOOT COMPLETED Feb 26 02:07:28 volumio volumio[1643994]: info: Initializing connection to go-librespot Websocket Feb 26 02:07:28 volumio volumio[1643994]: info: CoreCommandRouter::volumioGetState Feb 26 02:07:28 volumio volumio[1643994]: info: CorePlayQueue::getTrack 0 Feb 26 02:07:28 volumio volumio[1643994]: info: camilladsp respawn in 100 ms (attempt 1/10) Feb 26 02:07:28 volumio volumio[1643994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 02:07:28 volumio volumio[1643994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 26 02:07:29 volumio volumio[1643994]: info: CoreCommandRouter::volumioGetState Feb 26 02:07:29 volumio volumio[1643994]: info: CorePlayQueue::getTrack 0 Feb 26 02:07:29 volumio volumio[1643994]: info: CoreCommandRouter::volumioGetState Feb 26 02:07:29 volumio volumio[1643994]: info: CorePlayQueue::getTrack 0 Feb 26 02:07:29 volumio volumio[1643994]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 26 02:07:29 volumio volumio[1643994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 26 02:07:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Feb 26 02:07:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:07:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:07:31 volumio go-librespot[1644750]: go-librespot daemon starting... Feb 26 02:07:31 volumio go-librespot[1644752]: time="2026-02-26T02:07:31+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:07:31 volumio go-librespot[1644752]: time="2026-02-26T02:07:31+07:00" level=debug msg="app state loaded" Feb 26 02:07:31 volumio go-librespot[1644752]: time="2026-02-26T02:07:31+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:07:31 volumio go-librespot[1644752]: time="2026-02-26T02:07:31+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:07:31 volumio go-librespot[1644752]: time="2026-02-26T02:07:31+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:07:32 volumio go-librespot[1644752]: time="2026-02-26T02:07:31+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:07:32 volumio go-librespot[1644752]: time="2026-02-26T02:07:31+07:00" level=info msg="zeroconf server listening on port 35367" Feb 26 02:07:32 volumio go-librespot[1644752]: time="2026-02-26T02:07:32+07:00" level=debug msg="obtained new client token: AAAUR3Dvsvmk3130ihycs9bs8HtmPWOt+fCr7JvwuQRgzbJBCwMXGtVGeB1WiBDh3SWOPVGHvrYcoc7Kh5FMU92q7wQegTOXIW7YHMFCHyELtWIvUqZEynCa75cCnCDIYLvv1gOHspFvMGhvCDvKJmblcjUny+5ESJjt6ROvJyydBvdh77D53e0vznY00HbkJMty3idpaUwqeD8tc7+VA8Yj0nebpqo7WFMkVdgX0X3tMWB6t61o4wol" Feb 26 02:07:32 volumio go-librespot[1644752]: time="2026-02-26T02:07:32+07:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Feb 26 02:07:32 volumio go-librespot[1644752]: time="2026-02-26T02:07:32+07:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 26 02:07:32 volumio go-librespot[1644752]: time="2026-02-26T02:07:32+07:00" level=debug msg="completed keyexchange" Feb 26 02:07:32 volumio go-librespot[1644752]: time="2026-02-26T02:07:32+07:00" level=debug msg="completed challenge" Feb 26 02:07:32 volumio go-librespot[1644752]: time="2026-02-26T02:07:32+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:07:33 volumio volumio[1643994]: info: Initializing connection to go-librespot Websocket Feb 26 02:07:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:07:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:07:35 volumio volumio[1643994]: info: camilladsp respawn in 200 ms (attempt 2/10) Feb 26 02:07:36 volumio volumio[1643994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 02:07:36 volumio volumio[1643994]: info: MyVolumio token set successfully Feb 26 02:07:36 volumio volumio[1643994]: info: MYVOLUMIO: Adding device Feb 26 02:07:36 volumio volumio[1643994]: info: MYVOLUMIO: Evaluating Server Feb 26 02:07:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Feb 26 02:07:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:07:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:07:37 volumio go-librespot[1644778]: go-librespot daemon starting... Feb 26 02:07:37 volumio go-librespot[1644780]: time="2026-02-26T02:07:37+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:07:37 volumio go-librespot[1644780]: time="2026-02-26T02:07:37+07:00" level=debug msg="app state loaded" Feb 26 02:07:37 volumio go-librespot[1644780]: time="2026-02-26T02:07:37+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:07:38 volumio go-librespot[1644780]: time="2026-02-26T02:07:38+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:07:38 volumio go-librespot[1644780]: time="2026-02-26T02:07:38+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:07:38 volumio go-librespot[1644780]: time="2026-02-26T02:07:38+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:07:38 volumio go-librespot[1644780]: time="2026-02-26T02:07:38+07:00" level=info msg="zeroconf server listening on port 36581" Feb 26 02:07:38 volumio go-librespot[1644780]: time="2026-02-26T02:07:38+07:00" level=debug msg="obtained new client token: AADSbXaSZ4fi+wMKvdhdYYxRKQBcC5thLtMLeLK/5rUM7s7zUxAUaGSXNfogbr0naVT62rnjb2f3LP/FW7L8bo7hvETHWJDS4nJyBoiLMhBGmXfrV0XoLP66n4SYme4Ga6qlm/A23OyyHRGzPjePNcNdgnGeLRt24Kgqlfb4auV9DxA+tgYgG07r+ggK6QGeAYFnreijo4GvD6a6LrLFNVdbZFr78KBxq8+uYUQuH6cLmwSTebv23IS5JVQ=" Feb 26 02:07:38 volumio go-librespot[1644780]: time="2026-02-26T02:07:38+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:07:39 volumio go-librespot[1644780]: time="2026-02-26T02:07:39+07:00" level=debug msg="completed keyexchange" Feb 26 02:07:39 volumio go-librespot[1644780]: time="2026-02-26T02:07:39+07:00" level=debug msg="completed challenge" Feb 26 02:07:39 volumio go-librespot[1644780]: time="2026-02-26T02:07:39+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:07:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:07:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:07:40 volumio volumio[1643994]: info: CoreCommandRouter::volumioGetState Feb 26 02:07:40 volumio volumio[1643994]: info: CorePlayQueue::getTrack 0 Feb 26 02:07:41 volumio volumio[1643994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 26 02:07:41 volumio volumio[1643994]: info: CoreCommandRouter::volumioGetState Feb 26 02:07:41 volumio volumio[1643994]: info: CorePlayQueue::getTrack 0 Feb 26 02:07:41 volumio volumio[1643994]: info: Initializing connection to go-librespot Websocket Feb 26 02:07:41 volumio volumio[1643994]: info: camilladsp respawn in 400 ms (attempt 3/10) Feb 26 02:07:41 volumio volumio[1643994]: info: Listing playlists Feb 26 02:07:41 volumio volumio[1643994]: info: Listing playlists Feb 26 02:07:41 volumio volumio[1643994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 02:07:41 volumio volumio[1643994]: info: MyVolumio status changed Feb 26 02:07:41 volumio volumio[1643994]: info: Streaming services startup Feb 26 02:07:41 volumio volumio[1643994]: info: Starting Streaming Daemon Feb 26 02:07:41 volumio volumio[1643994]: info: Removing browser output: myVolumio user plan is not superstar Feb 26 02:07:41 volumio volumio[1643994]: info: Removing audio output: Feb 26 02:07:41 volumio volumio[1643994]: info: Stoppping Tunnel 1 Feb 26 02:07:42 volumio sudo[1644816]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 26 02:07:42 volumio sudo[1644818]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 26 02:07:42 volumio sudo[1644816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:07:42 volumio sudo[1644818]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:07:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Feb 26 02:07:43 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 02:07:43 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 02:07:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:07:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:07:43 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 02:07:43 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 02:07:43 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 02:07:43 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 02:07:43 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 02:07:43 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 02:07:43 volumio sudo[1644818]: pam_unix(sudo:session): session closed for user root Feb 26 02:07:43 volumio sudo[1644816]: pam_unix(sudo:session): session closed for user root Feb 26 02:07:43 volumio go-librespot[1644839]: go-librespot daemon starting... Feb 26 02:07:43 volumio go-librespot[1644847]: time="2026-02-26T02:07:43+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:07:43 volumio go-librespot[1644847]: time="2026-02-26T02:07:43+07:00" level=debug msg="app state loaded" Feb 26 02:07:43 volumio go-librespot[1644847]: time="2026-02-26T02:07:43+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:07:44 volumio go-librespot[1644847]: time="2026-02-26T02:07:44+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:07:44 volumio go-librespot[1644847]: time="2026-02-26T02:07:44+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:07:44 volumio go-librespot[1644847]: time="2026-02-26T02:07:44+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:07:44 volumio go-librespot[1644847]: time="2026-02-26T02:07:44+07:00" level=info msg="zeroconf server listening on port 43467" Feb 26 02:07:44 volumio volumio[1643994]: info: Setting Geolocation for MyVolumio to as1 Feb 26 02:07:44 volumio go-librespot[1644847]: time="2026-02-26T02:07:44+07:00" level=debug msg="obtained new client token: AAAbZMi1vbfSniiinAViMLlueG8RteSaWL19Mc1P3ADN7NByz8iM0bMPm/BY+ZoYNtlFOYKRQVotN8y4Y/2lxehxZwoVu69LAgTchWiQS2ZiiTLPSWn+HjoEaTMSZ1hiJdQeMmEJ67u74nD96ADn9K26gJxYmHw5j52fMFio82WUeQzome8fdcW50DqdAsO4WLAfABbq/nvv1Pn1oOWGkTz5biRdi8zQffH9WOW+JoaEUoqZy1Ps8PVG" Feb 26 02:07:44 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:07:44 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:07:44 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:07:44 volumio go-librespot[1644847]: time="2026-02-26T02:07:44+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:07:44 volumio go-librespot[1644847]: time="2026-02-26T02:07:44+07:00" level=debug msg="completed keyexchange" Feb 26 02:07:44 volumio go-librespot[1644847]: time="2026-02-26T02:07:44+07:00" level=debug msg="completed challenge" Feb 26 02:07:44 volumio volumio[1643994]: info: Initializing connection to go-librespot Websocket Feb 26 02:07:44 volumio volumio[1643994]: info: camilladsp respawn in 800 ms (attempt 4/10) Feb 26 02:07:44 volumio volumio[1643994]: info: Remote SSH Stopped Feb 26 02:07:44 volumio go-librespot[1644847]: time="2026-02-26T02:07:44+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:07:44 volumio volumio[1643994]: error: Cannot start Volumio Streaming Daemon Feb 26 02:07:44 volumio volumio[1643994]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 26 02:07:44 volumio volumio[1643994]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 26 02:07:44 volumio volumio[1643994]: info: CoreCommandRouter::volumioGetState Feb 26 02:07:44 volumio volumio[1643994]: info: CorePlayQueue::getTrack 0 Feb 26 02:07:45 volumio volumio[1643994]: info: Error connecting to go-librespot Websocket: Error: socket hang up Feb 26 02:07:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:07:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:07:45 volumio volumio[1643994]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 26 02:07:45 volumio systemd[1]: mpd.service: start operation timed out. Terminating. Feb 26 02:07:45 volumio systemd[1]: mpd.service: Failed with result 'timeout'. Feb 26 02:07:45 volumio systemd[1]: Failed to start mpd.service - Music Player Daemon. Feb 26 02:07:45 volumio systemd[1]: mpd.service: Consumed 15.410s CPU time. Feb 26 02:07:45 volumio volumio[1643994]: info: Updating MyVolumio device info Feb 26 02:07:45 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:07:45 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:07:45 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:07:45 volumio sudo[1644425]: pam_unix(sudo:session): session closed for user root Feb 26 02:07:45 volumio sudo[1644169]: pam_unix(sudo:session): session closed for user root Feb 26 02:07:45 volumio sudo[1644193]: pam_unix(sudo:session): session closed for user root Feb 26 02:07:45 volumio volumio[1643994]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Feb 26 02:07:45 volumio volumio[1643994]: Job for mpd.service failed because a timeout was exceeded. Feb 26 02:07:45 volumio volumio[1643994]: See "systemctl status mpd.service" and "journalctl -xeu mpd.service" for details. Feb 26 02:07:45 volumio volumio[1643994]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Feb 26 02:07:45 volumio volumio[1643994]: Job for mpd.service failed because a timeout was exceeded. Feb 26 02:07:45 volumio volumio[1643994]: See "systemctl status mpd.service" and "journalctl -xeu mpd.service" for details. Feb 26 02:07:45 volumio volumio[1643994]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Feb 26 02:07:45 volumio volumio[1643994]: Job for mpd.service failed because a timeout was exceeded. Feb 26 02:07:45 volumio volumio[1643994]: See "systemctl status mpd.service" and "journalctl -xeu mpd.service" for details. Feb 26 02:07:45 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 26 02:07:46 volumio sudo[1644855]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 26 02:07:46 volumio sudo[1644855]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 26 02:07:46 volumio sudo[1644855]: pam_unix(sudo:session): session closed for user root Feb 26 02:07:47 volumio volumio[1643994]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 26 02:07:47 volumio volumio[1643994]: info: camilladsp respawn in 1600 ms (attempt 5/10) Feb 26 02:07:48 volumio volumio[1643994]: info: Initializing connection to go-librespot Websocket Feb 26 02:07:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Feb 26 02:07:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:07:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:07:48 volumio go-librespot[1644868]: go-librespot daemon starting... Feb 26 02:07:49 volumio go-librespot[1644869]: time="2026-02-26T02:07:49+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:07:49 volumio go-librespot[1644869]: time="2026-02-26T02:07:49+07:00" level=debug msg="app state loaded" Feb 26 02:07:49 volumio go-librespot[1644869]: time="2026-02-26T02:07:49+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:07:50 volumio go-librespot[1644869]: time="2026-02-26T02:07:50+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:07:50 volumio go-librespot[1644869]: time="2026-02-26T02:07:50+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:07:50 volumio go-librespot[1644869]: time="2026-02-26T02:07:50+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:07:50 volumio go-librespot[1644869]: time="2026-02-26T02:07:50+07:00" level=info msg="zeroconf server listening on port 37039" Feb 26 02:07:50 volumio go-librespot[1644869]: time="2026-02-26T02:07:50+07:00" level=debug msg="obtained new client token: AACMotFTqmkRzg+v/jULrkH6N5q269wWDqtrFpB+4t/k6kkWM/MEgEGUCJ2wGoeB2QFo1sU4jCayW7udh4KAXRmMNsIAI4LWQBEstp6F3gEdo8Lb11WZQ8u08tjhtkdnYLjn8T4sMkcnUNefMa6TG0C0b8q2SJNYEB3XlWLBtTYlhfMRhb98Cem6HlbePGy3gyiK/ankVG7wGqSW96V1JP3Cs8AqMqJ8mEDhh2vQWXpfc/M8OPMNRlzX+bY=" Feb 26 02:07:50 volumio go-librespot[1644869]: time="2026-02-26T02:07:50+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:07:50 volumio go-librespot[1644869]: time="2026-02-26T02:07:50+07:00" level=debug msg="completed keyexchange" Feb 26 02:07:50 volumio go-librespot[1644869]: time="2026-02-26T02:07:50+07:00" level=debug msg="completed challenge" Feb 26 02:07:51 volumio volumio[1643994]: info: MYVOLUMIO: Adding device Feb 26 02:07:51 volumio volumio[1643994]: info: MYVOLUMIO: Evaluating Server Feb 26 02:07:51 volumio go-librespot[1644869]: time="2026-02-26T02:07:51+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:07:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:07:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:07:52 volumio volumio[1643994]: info: camilladsp respawn in 3200 ms (attempt 6/10) Feb 26 02:07:52 volumio volumio[1643994]: info: CoreCommandRouter::volumioGetState Feb 26 02:07:52 volumio volumio[1643994]: info: CorePlayQueue::getTrack 0 Feb 26 02:07:52 volumio volumio[1643994]: info: Error connecting to go-librespot Websocket: Error: socket hang up Feb 26 02:07:53 volumio volumio[1643994]: info: Setting Geolocation for MyVolumio to as1 Feb 26 02:07:53 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:07:53 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:07:53 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:07:53 volumio volumio[1643994]: info: CoreCommandRouter::volumioGetState Feb 26 02:07:53 volumio volumio[1643994]: info: CorePlayQueue::getTrack 0 Feb 26 02:07:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Feb 26 02:07:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:07:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:07:54 volumio go-librespot[1644921]: go-librespot daemon starting... Feb 26 02:07:55 volumio volumio[1643994]: info: Updating MyVolumio device info Feb 26 02:07:55 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:07:55 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:07:55 volumio volumio[1643994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:07:55 volumio go-librespot[1644922]: time="2026-02-26T02:07:55+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:07:55 volumio go-librespot[1644922]: time="2026-02-26T02:07:55+07:00" level=debug msg="app state loaded" Feb 26 02:07:55 volumio go-librespot[1644922]: time="2026-02-26T02:07:55+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:07:55 volumio go-librespot[1644922]: time="2026-02-26T02:07:55+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:07:55 volumio go-librespot[1644922]: time="2026-02-26T02:07:55+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:07:55 volumio go-librespot[1644922]: time="2026-02-26T02:07:55+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:07:55 volumio go-librespot[1644922]: time="2026-02-26T02:07:55+07:00" level=info msg="zeroconf server listening on port 39599" Feb 26 02:07:55 volumio go-librespot[1644922]: time="2026-02-26T02:07:55+07:00" level=debug msg="obtained new client token: AADnNdY9XXrgPiO3Vl3o/zZLtpWcB4URUuC5Ervo/U03QsDzqlevkwNJ6yd5mIKzwlPoj6VuneXsOK8tH29i6eLPjAPyOlueD+K7p5VWdHuBKXoCj6NSDy+Kba2McKI/8gy6Gu9UOA4pgFUXlCSR/x2KJO1Atb072QYah+1Ovwdu07S9j97XiTDpnyyNNWBEOgTk34zCBqqR2P+xJjuuU4rF9htc63u91ONPCnoy1UKcWAXzZs8AIo5TtII=" Feb 26 02:07:56 volumio go-librespot[1644922]: time="2026-02-26T02:07:56+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:07:56 volumio go-librespot[1644922]: time="2026-02-26T02:07:56+07:00" level=debug msg="completed keyexchange" Feb 26 02:07:56 volumio go-librespot[1644922]: time="2026-02-26T02:07:56+07:00" level=debug msg="completed challenge" Feb 26 02:07:56 volumio go-librespot[1644922]: time="2026-02-26T02:07:56+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:07:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:07:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:07:58 volumio volumio[1643994]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 26 02:07:58 volumio volumio[1643994]: info: Initializing connection to go-librespot Websocket Feb 26 02:08:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Feb 26 02:08:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:08:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:08:00 volumio go-librespot[1644933]: go-librespot daemon starting... Feb 26 02:08:00 volumio volumio[1643994]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 26 02:08:00 volumio go-librespot[1644934]: time="2026-02-26T02:08:00+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:08:00 volumio go-librespot[1644934]: time="2026-02-26T02:08:00+07:00" level=debug msg="app state loaded" Feb 26 02:08:00 volumio go-librespot[1644934]: time="2026-02-26T02:08:00+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:08:00 volumio volumio[1643994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 02:08:01 volumio volumio[1643994]: info: camilladsp respawn in 6400 ms (attempt 7/10) Feb 26 02:08:01 volumio go-librespot[1644934]: time="2026-02-26T02:08:01+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:08:02 volumio go-librespot[1644934]: time="2026-02-26T02:08:01+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:08:02 volumio go-librespot[1644934]: time="2026-02-26T02:08:01+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:08:02 volumio go-librespot[1644934]: time="2026-02-26T02:08:01+07:00" level=info msg="zeroconf server listening on port 38625" Feb 26 02:08:02 volumio go-librespot[1644934]: time="2026-02-26T02:08:02+07:00" level=debug msg="obtained new client token: AABVJVkkT67dRbxmHiYq5L7X1btJPbfYHPyrbe0X5fuwSRNycZyaYx7v7YQAbpAnvcQyf9drS0tXEtTTfyoddGRyyuImZLuKa+iAfCb1oidh/L1PKdM8bvQ9in5ptaDloQfCva3t+zQxXpwLHEFizvJ8wkliaRIllhVSdFc+ihD0oe0bzT+mgF4EGJP3WciiSNZEKhBOdn1Udeqy2vli81Cp+9arh6CIiRQshKP28CGQibGK+h4dfqtP" Feb 26 02:08:02 volumio go-librespot[1644934]: time="2026-02-26T02:08:02+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:08:02 volumio go-librespot[1644934]: time="2026-02-26T02:08:02+07:00" level=debug msg="completed keyexchange" Feb 26 02:08:02 volumio go-librespot[1644934]: time="2026-02-26T02:08:02+07:00" level=debug msg="completed challenge" Feb 26 02:08:02 volumio go-librespot[1644934]: time="2026-02-26T02:08:02+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:08:03 volumio volumio[1643994]: info: CoreCommandRouter::volumioGetState Feb 26 02:08:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:08:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:08:03 volumio volumio[1643994]: info: CorePlayQueue::getTrack 0 Feb 26 02:08:03 volumio volumio[1643994]: info: Initializing connection to go-librespot Websocket Feb 26 02:08:04 volumio volumio[1643994]: info: CoreCommandRouter::volumioGetState Feb 26 02:08:04 volumio volumio[1643994]: info: CorePlayQueue::getTrack 0 Feb 26 02:08:04 volumio volumio[1643994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 02:08:04 volumio volumio[1643994]: info: Listing playlists Feb 26 02:08:04 volumio volumio[1643994]: info: Listing playlists Feb 26 02:08:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Feb 26 02:08:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:08:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:08:06 volumio go-librespot[1644966]: go-librespot daemon starting... Feb 26 02:08:06 volumio go-librespot[1644967]: time="2026-02-26T02:08:06+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:08:06 volumio go-librespot[1644967]: time="2026-02-26T02:08:06+07:00" level=debug msg="app state loaded" Feb 26 02:08:06 volumio go-librespot[1644967]: time="2026-02-26T02:08:06+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:08:07 volumio go-librespot[1644967]: time="2026-02-26T02:08:07+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 26 02:08:07 volumio go-librespot[1644967]: time="2026-02-26T02:08:07+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 26 02:08:07 volumio go-librespot[1644967]: time="2026-02-26T02:08:07+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 26 02:08:07 volumio go-librespot[1644967]: time="2026-02-26T02:08:07+07:00" level=info msg="zeroconf server listening on port 43127" Feb 26 02:08:07 volumio go-librespot[1644967]: time="2026-02-26T02:08:07+07:00" level=debug msg="obtained new client token: AAD1jrleJL7kpByzJXX0NQLmfQXknMj0yBDp9LbzyzIek18w3h4fauDWFHwZKoEPVlBycaM1pAYune3RmUT9u88Cf8mgbmvDKyFGgCl7Tj0BeuwmtVeo0kH0A4RuQF1wzlaUEvDZLcBXqkXszVIHvlvPJDMvyWx0qncxGC3CxKcEXCdx4OLlkKaNF/U6JRQh+N5f2+qqod3r1IivPPofRRHtZrI1P9Op4oMN6KwTLi/T3Rm8/DEwpT/e15k=" Feb 26 02:08:07 volumio go-librespot[1644967]: time="2026-02-26T02:08:07+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:08:07 volumio go-librespot[1644967]: time="2026-02-26T02:08:07+07:00" level=debug msg="completed keyexchange" Feb 26 02:08:08 volumio go-librespot[1644967]: time="2026-02-26T02:08:07+07:00" level=debug msg="completed challenge" Feb 26 02:08:08 volumio go-librespot[1644967]: time="2026-02-26T02:08:08+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:08:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:08:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:08:08 volumio volumio[1643994]: info: Initializing connection to go-librespot Websocket Feb 26 02:08:09 volumio volumio[1643994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 02:08:09 volumio volumio[1643994]: info: camilladsp respawn in 10000 ms (attempt 8/10) Feb 26 02:08:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Feb 26 02:08:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:08:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:08:12 volumio go-librespot[1644985]: go-librespot daemon starting... Feb 26 02:08:12 volumio volumio[1643994]: info: Initializing connection to go-librespot Websocket Feb 26 02:08:12 volumio go-librespot[1644986]: time="2026-02-26T02:08:12+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:08:12 volumio go-librespot[1644986]: time="2026-02-26T02:08:12+07:00" level=debug msg="app state loaded" Feb 26 02:08:12 volumio go-librespot[1644986]: time="2026-02-26T02:08:12+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:08:12 volumio volumio[1643994]: info: CoreCommandRouter::volumioGetState Feb 26 02:08:12 volumio volumio[1643994]: info: CorePlayQueue::getTrack 0 Feb 26 02:08:13 volumio go-librespot[1644986]: time="2026-02-26T02:08:13+07:00" level=debug msg="new websocket client" Feb 26 02:08:13 volumio go-librespot[1644986]: time="2026-02-26T02:08:13+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:08:13 volumio go-librespot[1644986]: time="2026-02-26T02:08:13+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:08:13 volumio go-librespot[1644986]: time="2026-02-26T02:08:13+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:08:13 volumio go-librespot[1644986]: time="2026-02-26T02:08:13+07:00" level=info msg="zeroconf server listening on port 39981" Feb 26 02:08:13 volumio go-librespot[1644986]: time="2026-02-26T02:08:13+07:00" level=debug msg="obtained new client token: AAB1U9jibpD167ejEMrzdxrfuPgTZNfFqt6RuQaNSeta9z97owEP6dkX9318+C6o9u62XivbOHKL/TfWggjhKZcVeBKlazw6XjeDUaRx9jimmrqjNaKXBE4lXZ6rh/YWNSvHk1m556Zo+Up016Gx/wZEJXmUqIqgeOZuvUidCLwlgy2bqfcAfaG4xA91ifQ7ubIBY9SI8S0TOmKdVwH3yu+Sbp9pMEG51CzDBUjZZ7JumRi75X6Twa4e" Feb 26 02:08:13 volumio go-librespot[1644986]: time="2026-02-26T02:08:13+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:08:13 volumio go-librespot[1644986]: time="2026-02-26T02:08:13+07:00" level=debug msg="completed keyexchange" Feb 26 02:08:13 volumio go-librespot[1644986]: time="2026-02-26T02:08:13+07:00" level=debug msg="completed challenge" Feb 26 02:08:13 volumio go-librespot[1644986]: time="2026-02-26T02:08:13+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:08:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:08:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:08:14 volumio volumio[1643994]: info: Connection to go-librespot Websocket established Feb 26 02:08:15 volumio volumio[1643994]: info: CoreCommandRouter::volumioGetState Feb 26 02:08:15 volumio volumio[1643994]: info: CorePlayQueue::getTrack 0 Feb 26 02:08:16 volumio volumio[1643994]: info: Connection to go-librespot Websocket closed Feb 26 02:08:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Feb 26 02:08:17 volumio volumio[1643994]: info: Getting Spotify volume Feb 26 02:08:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:08:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:08:17 volumio volumio[1643994]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 02:08:18 volumio go-librespot[1645010]: go-librespot daemon starting... Feb 26 02:08:18 volumio go-librespot[1645012]: time="2026-02-26T02:08:18+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:08:18 volumio go-librespot[1645012]: time="2026-02-26T02:08:18+07:00" level=debug msg="app state loaded" Feb 26 02:08:18 volumio go-librespot[1645012]: time="2026-02-26T02:08:18+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:08:18 volumio volumio[1643994]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 02:08:18 volumio volumio[1643994]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 26 02:08:18 volumio volumio[1643994]: errno: -111, Feb 26 02:08:18 volumio volumio[1643994]: code: 'ECONNREFUSED', Feb 26 02:08:18 volumio volumio[1643994]: syscall: 'connect', Feb 26 02:08:18 volumio volumio[1643994]: address: '127.0.0.1', Feb 26 02:08:18 volumio volumio[1643994]: port: 9879, Feb 26 02:08:18 volumio volumio[1643994]: response: undefined Feb 26 02:08:18 volumio volumio[1643994]: } Feb 26 02:08:18 volumio go-librespot[1645012]: time="2026-02-26T02:08:18+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:08:18 volumio go-librespot[1645012]: time="2026-02-26T02:08:18+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:08:18 volumio go-librespot[1645012]: time="2026-02-26T02:08:18+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:08:18 volumio go-librespot[1645012]: time="2026-02-26T02:08:18+07:00" level=info msg="zeroconf server listening on port 42611" Feb 26 02:08:18 volumio volumio[1643994]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 02:08:19 volumio go-librespot[1645012]: time="2026-02-26T02:08:19+07:00" level=debug msg="obtained new client token: AADl6Lp/zL7SzJ1wYhbqfiUmHLs7fZrqfXEPqtBLDsWsJYFT8ICTHDZUDmcfi98XhTNMkpETfXcspbej6eWQZAfMGhpe+cuRNLZpoJ5YVvZddKmXX7EY20ADJNMLeuOGFAc1BgwG5AtGRH9YeRt9up3srqeTl2wGbVQuT2JwZf4ytem0WagFCqMYmlfT4pl4zCgpNMOHFfVRw3J4LUEgGft3IUsk+zd4CuiLm+GYgbZsqPIHtQT4jOCO" Feb 26 02:08:19 volumio go-librespot[1645012]: time="2026-02-26T02:08:19+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:08:19 volumio go-librespot[1645012]: time="2026-02-26T02:08:19+07:00" level=debug msg="completed keyexchange" Feb 26 02:08:19 volumio go-librespot[1645012]: time="2026-02-26T02:08:19+07:00" level=debug msg="completed challenge" Feb 26 02:08:19 volumio go-librespot[1645012]: time="2026-02-26T02:08:19+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:08:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:08:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:08:20 volumio mpd[1644864]: 2026-02-26T02:08:20 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 26 02:08:22 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 26 02:08:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Feb 26 02:08:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:08:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:08:23 volumio go-librespot[1645037]: go-librespot daemon starting... Feb 26 02:08:23 volumio nmbd[979]: [2026/02/26 02:08:23.495591, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 26 02:08:23 volumio nmbd[979]: query_name_response: Multiple (2) responses received for a query on subnet 10.10.9.65 for name WORKGROUP<1d>. Feb 26 02:08:23 volumio nmbd[979]: This response was from IP 10.10.9.5, reporting an IP address of 10.10.9.5. Feb 26 02:08:23 volumio go-librespot[1645038]: time="2026-02-26T02:08:23+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:08:23 volumio go-librespot[1645038]: time="2026-02-26T02:08:23+07:00" level=debug msg="app state loaded" Feb 26 02:08:23 volumio go-librespot[1645038]: time="2026-02-26T02:08:23+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:08:24 volumio go-librespot[1645038]: time="2026-02-26T02:08:24+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:08:24 volumio go-librespot[1645038]: time="2026-02-26T02:08:24+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:08:24 volumio go-librespot[1645038]: time="2026-02-26T02:08:24+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:08:24 volumio go-librespot[1645038]: time="2026-02-26T02:08:24+07:00" level=info msg="zeroconf server listening on port 41755" Feb 26 02:08:24 volumio go-librespot[1645038]: time="2026-02-26T02:08:24+07:00" level=debug msg="obtained new client token: AAC5k8MmuXGr0aCkkO0OhQcqgwJYc1N8gHWIkJwAqTOpMXzLhKwguMOhtODpqrbUbbx+Nkv6jo0nRcXJfKH0cOhRuSbcCYuZTiqVnuWctsqUVcAMt8BiH6NyKnUYJg84LyDPc+JqtYIoYaag+IV4Lr0aUrWifBHX+D2eSaQ1O/ETiPDABzdhXznW2vdd4Z0SDSq7mbkrhvwzrkzq1Opoo2mOvYlxLAPnImAGBiMo2NhYoGCYFNEtDSsJgEQ=" Feb 26 02:08:24 volumio go-librespot[1645038]: time="2026-02-26T02:08:24+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:08:24 volumio go-librespot[1645038]: time="2026-02-26T02:08:24+07:00" level=debug msg="completed keyexchange" Feb 26 02:08:24 volumio go-librespot[1645038]: time="2026-02-26T02:08:24+07:00" level=debug msg="completed challenge" Feb 26 02:08:25 volumio go-librespot[1645038]: time="2026-02-26T02:08:25+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:08:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:08:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:08:25 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 26 02:08:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Feb 26 02:08:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:08:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:08:29 volumio go-librespot[1645077]: go-librespot daemon starting... Feb 26 02:08:30 volumio go-librespot[1645079]: time="2026-02-26T02:08:30+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:08:30 volumio go-librespot[1645079]: time="2026-02-26T02:08:30+07:00" level=debug msg="app state loaded" Feb 26 02:08:30 volumio go-librespot[1645079]: time="2026-02-26T02:08:30+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:08:32 volumio go-librespot[1645079]: time="2026-02-26T02:08:32+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:08:32 volumio go-librespot[1645079]: time="2026-02-26T02:08:32+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:08:32 volumio go-librespot[1645079]: time="2026-02-26T02:08:32+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:08:32 volumio go-librespot[1645079]: time="2026-02-26T02:08:32+07:00" level=info msg="zeroconf server listening on port 34513" Feb 26 02:08:32 volumio go-librespot[1645079]: time="2026-02-26T02:08:32+07:00" level=debug msg="obtained new client token: AABB8gE1XsVf/w6hZhKDCt+NjHogwogNixhJMfMkeLCdqyd4Aa6lR2QVOk0aSprE7a/o62tubxVxLcSbTmiaG4T2R7ZpzwpjeEzfCnnEfepAf44V2guMH2nZhEQAZFhZ/ynaAPHi9tKTNng+cs55DrQCv/PQbgnFMAtR4iQ79G22j6QtnW2He5g4Ca8qGpaTf+Pc0TXJxY2Y1CWhNOuphDM82a9QCvPeIpBFYqSGElhQSsJE/wm5l06nTIk=" Feb 26 02:08:32 volumio go-librespot[1645079]: time="2026-02-26T02:08:32+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:08:33 volumio go-librespot[1645079]: time="2026-02-26T02:08:33+07:00" level=debug msg="completed keyexchange" Feb 26 02:08:33 volumio go-librespot[1645079]: time="2026-02-26T02:08:33+07:00" level=debug msg="completed challenge" Feb 26 02:08:33 volumio go-librespot[1645079]: time="2026-02-26T02:08:33+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:08:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:08:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:08:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Feb 26 02:08:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:08:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:08:38 volumio go-librespot[1645106]: go-librespot daemon starting... Feb 26 02:08:39 volumio go-librespot[1645108]: time="2026-02-26T02:08:39+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:08:39 volumio go-librespot[1645108]: time="2026-02-26T02:08:39+07:00" level=debug msg="app state loaded" Feb 26 02:08:39 volumio go-librespot[1645108]: time="2026-02-26T02:08:39+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:08:40 volumio go-librespot[1645108]: time="2026-02-26T02:08:40+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:08:40 volumio go-librespot[1645108]: time="2026-02-26T02:08:40+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:08:40 volumio go-librespot[1645108]: time="2026-02-26T02:08:40+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:08:40 volumio go-librespot[1645108]: time="2026-02-26T02:08:40+07:00" level=info msg="zeroconf server listening on port 39781" Feb 26 02:08:41 volumio go-librespot[1645108]: time="2026-02-26T02:08:41+07:00" level=debug msg="obtained new client token: AAAK+jq+ZyPD4xL/RSAVByC8KTRoXxyym2VfVg0b0uE9Aig+vcmMoGtE+tCyzLE/l+stRkFbOUuR2aa603e4Yg0J5i+ENqFR9q9WBC67tC1zI7r65yI5M/zgAx47z/V1tSVGjduzVzAPNPpnryA5gcsIw05hDxrCHGgTCLNr4/9m7q2Gf5FN33vuNTcPbKjBRFMbUNqmmxalHxy1ZNe1mALxR2mlt+Go2qQ+yddFBwgsxlVFJ0lJMKZPy0Y=" Feb 26 02:08:41 volumio go-librespot[1645108]: time="2026-02-26T02:08:41+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:08:42 volumio go-librespot[1645108]: time="2026-02-26T02:08:42+07:00" level=debug msg="completed keyexchange" Feb 26 02:08:42 volumio go-librespot[1645108]: time="2026-02-26T02:08:42+07:00" level=debug msg="completed challenge" Feb 26 02:08:42 volumio go-librespot[1645108]: time="2026-02-26T02:08:42+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:08:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:08:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:08:45 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 26 02:08:45 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 26 02:08:45 volumio systemd[1]: setdatetime-helper.service: Consumed 2.630s CPU time. Feb 26 02:08:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Feb 26 02:08:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:08:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:08:46 volumio go-librespot[1645142]: go-librespot daemon starting... Feb 26 02:08:46 volumio go-librespot[1645143]: time="2026-02-26T02:08:46+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:08:46 volumio go-librespot[1645143]: time="2026-02-26T02:08:46+07:00" level=debug msg="app state loaded" Feb 26 02:08:46 volumio go-librespot[1645143]: time="2026-02-26T02:08:46+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:08:47 volumio go-librespot[1645143]: time="2026-02-26T02:08:47+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:08:47 volumio go-librespot[1645143]: time="2026-02-26T02:08:47+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 26 02:08:47 volumio go-librespot[1645143]: time="2026-02-26T02:08:47+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 26 02:08:47 volumio go-librespot[1645143]: time="2026-02-26T02:08:47+07:00" level=info msg="zeroconf server listening on port 45525" Feb 26 02:08:47 volumio go-librespot[1645143]: time="2026-02-26T02:08:47+07:00" level=debug msg="obtained new client token: AACnm1+kc8lhlbLoaOZrth2YBdDhhR3aSmGlI90B1DZI71Zg2m/wYAQypxbNyr5KWsm882W2fXwo4xe5AcxCenDxsZrdq973QPg5F5Jbl4qugyAUjAVAz/yVYFxiPIjaJssWMH4dRidVWBHePp1tkc0wpJe2L4IdMYtZzE+cZ7cWpkOtG2whb9PTckRD70MLhG4AiX3Arnp61+xlW6uiiphjBsz08J3vPVxybATstzHgS+t+6a6DM6eP+q4=" Feb 26 02:08:47 volumio go-librespot[1645143]: time="2026-02-26T02:08:47+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:08:47 volumio go-librespot[1645143]: time="2026-02-26T02:08:47+07:00" level=debug msg="completed keyexchange" Feb 26 02:08:47 volumio go-librespot[1645143]: time="2026-02-26T02:08:47+07:00" level=debug msg="completed challenge" Feb 26 02:08:48 volumio go-librespot[1645143]: time="2026-02-26T02:08:48+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:08:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:08:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:08:49 volumio sudo[1645153]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-26 02:07' Feb 26 02:08:49 volumio sudo[1645153]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:31:57 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6759e875c98b942866984e844891d6c1"