Feb 13 10:53:00 volumio volumio[4242]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 13 10:53:00 volumio volumio[4242]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 13 10:53:00 volumio go-librespot[4583]: time="2026-02-13T10:53:00+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:53:00 volumio go-librespot[4583]: time="2026-02-13T10:53:00+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:53:00 volumio go-librespot[4583]: time="2026-02-13T10:53:00+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:53:00 volumio go-librespot[4583]: time="2026-02-13T10:53:00+07:00" level=info msg="zeroconf server listening on port 43673" Feb 13 10:53:00 volumio go-librespot[4583]: time="2026-02-13T10:53:00+07:00" level=debug msg="obtained new client token: AAAolmq5EMka4oRWXj1Kpaq3wkJ1GrbIHv/HKjVBl+orzmADrYbFM1qfnFJW/fGPN4wNYKnuwuZGk6JyB16oIH2D7Z4Z0u2rgIEQDXlc6TbIFCEkqgfh3SX0gWBqPEzHLf8UOjx2R6a7n3EYglfLeIZ/IJOZe3ENcuMRD4G3nTEIqgjxQPdduOXYvUPSnnAuYNeSA4u/JgHo6axUw+9z+ggs0PeM8q/Caj1AgSkFi1UAnpB4HuzWAZFoiA==" Feb 13 10:53:00 volumio go-librespot[4583]: time="2026-02-13T10:53:00+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 13 10:53:00 volumio go-librespot[4583]: time="2026-02-13T10:53:00+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 13 10:53:00 volumio go-librespot[4583]: time="2026-02-13T10:53:00+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:00 volumio go-librespot[4583]: time="2026-02-13T10:53:00+07:00" level=debug msg="completed challenge" Feb 13 10:53:00 volumio go-librespot[4583]: time="2026-02-13T10:53:00+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:53:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:01 volumio volumio[4242]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 13 10:53:01 volumio volumio[4242]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 13 10:53:01 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:53:01 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:53:01 volumio volumio[4242]: info: Starting MyVolumio Remote Streaming Endpoints Feb 13 10:53:01 volumio volumio[4242]: info: MyVolumio login type: Token Feb 13 10:53:01 volumio volumio[4242]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 13 10:53:01 volumio volumio[4242]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 13 10:53:03 volumio volumio[4242]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 13 10:53:03 volumio volumio[4242]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 13 10:53:03 volumio volumio[4242]: info: Streaming services startup Feb 13 10:53:03 volumio volumio[4242]: info: Starting Streaming Daemon Feb 13 10:53:03 volumio sudo[4606]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 13 10:53:03 volumio sudo[4606]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:03 volumio volumio[4242]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 13 10:53:03 volumio sudo[4606]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:03 volumio volumio[4242]: info: Getting Spotify volume Feb 13 10:53:03 volumio volumio[4242]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 13 10:53:03 volumio volumio[4242]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 10:53:03 volumio volumio[4242]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 13 10:53:03 volumio volumio[4242]: errno: -111, Feb 13 10:53:03 volumio volumio[4242]: code: 'ECONNREFUSED', Feb 13 10:53:03 volumio volumio[4242]: syscall: 'connect', Feb 13 10:53:03 volumio volumio[4242]: address: '127.0.0.1', Feb 13 10:53:03 volumio volumio[4242]: port: 9879, Feb 13 10:53:03 volumio volumio[4242]: response: undefined Feb 13 10:53:03 volumio volumio[4242]: } Feb 13 10:53:03 volumio volumio[4242]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 13 10:53:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 13 10:53:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:04 volumio go-librespot[4623]: go-librespot daemon starting... Feb 13 10:53:04 volumio go-librespot[4625]: time="2026-02-13T10:53:04+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:04 volumio go-librespot[4625]: time="2026-02-13T10:53:04+07:00" level=debug msg="app state loaded" Feb 13 10:53:04 volumio go-librespot[4625]: time="2026-02-13T10:53:04+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:53:04 volumio go-librespot[4625]: time="2026-02-13T10:53:04+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:53:04 volumio go-librespot[4625]: time="2026-02-13T10:53:04+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:53:04 volumio go-librespot[4625]: time="2026-02-13T10:53:04+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:53:04 volumio go-librespot[4625]: time="2026-02-13T10:53:04+07:00" level=info msg="zeroconf server listening on port 33151" Feb 13 10:53:04 volumio go-librespot[4625]: time="2026-02-13T10:53:04+07:00" level=debug msg="obtained new client token: AAAf+6V2UvHT6dmFE0DEhXzyJ720g7mlBkQuJ2tklRIFUTie8z1jAD99nCwiu/EHVuVk817qiEvvVARYH6Z8yNmxFTx9/DjwQRIm6KEkAza0eBvRtz6d89Dx1UyLXaK20QZacWC8zXdGErQpYvDi348tmqgDF6p9wHIqa/SaZ/6Wec9213tLfCn/I0qCjeU5jYVYfK+C3oXGYZ0oi3Ic2pXLfDuSrJX2CQt4mgdgLc+sHg6/SAW1LCsoVQ==" Feb 13 10:53:05 volumio go-librespot[4625]: time="2026-02-13T10:53:05+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:53:05 volumio go-librespot[4625]: time="2026-02-13T10:53:05+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:05 volumio go-librespot[4625]: time="2026-02-13T10:53:05+07:00" level=debug msg="completed challenge" Feb 13 10:53:05 volumio go-librespot[4625]: time="2026-02-13T10:53:05+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:53:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:05 volumio sudo[4634]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-13 10:52' Feb 13 10:53:05 volumio sudo[4634]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:05 volumio sudo[4634]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:07 volumio volumio-remote-updater[643]: [2026-02-13 10:53:07] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 13 10:53:07 volumio volumio-remote-updater[643]: [2026-02-13 10:53:07] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 13 10:53:07 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:07 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 13 10:53:07 volumio systemd[1]: volumio.service: Consumed 55.058s CPU time. Feb 13 10:53:07 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 13 10:53:07 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 13 10:53:07 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 14594. Feb 13 10:53:07 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 13 10:53:07 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 13 10:53:07 volumio systemd[1]: volumio.service: Consumed 55.058s CPU time. Feb 13 10:53:07 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 13 10:53:07 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 13 10:53:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 13 10:53:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:08 volumio go-librespot[4666]: go-librespot daemon starting... Feb 13 10:53:08 volumio go-librespot[4667]: time="2026-02-13T10:53:08+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:08 volumio go-librespot[4667]: time="2026-02-13T10:53:08+07:00" level=debug msg="app state loaded" Feb 13 10:53:08 volumio go-librespot[4667]: time="2026-02-13T10:53:08+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:53:09 volumio go-librespot[4667]: time="2026-02-13T10:53:09+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:53:09 volumio go-librespot[4667]: time="2026-02-13T10:53:09+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:53:09 volumio go-librespot[4667]: time="2026-02-13T10:53:09+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:53:09 volumio go-librespot[4667]: time="2026-02-13T10:53:09+07:00" level=info msg="zeroconf server listening on port 34221" Feb 13 10:53:09 volumio go-librespot[4667]: time="2026-02-13T10:53:09+07:00" level=debug msg="obtained new client token: AAA3X6yiRH2bqx7dAqbLIuiVj8rv+i0n2u+0nY5V0byVk7j8CTCmHKextRBQVxjndnPMfNMwlvxaqPISw9Hb8Q4OG7OGNkWDtaXYzdeXvVJUeQqlV32mwJf4oUc6qweoyK3J1LWCjkRU1kfiMMa87IzzvmIx6ILsaLCapeWrKT3yigv1U/MxCGt6HYSNQ0DVYNsr9nnt8RNYNpmBsjV5Fx2VV1MqNfNjAtEe0F80UFPkWVCXNvPw+VIJrQ==" Feb 13 10:53:09 volumio go-librespot[4667]: time="2026-02-13T10:53:09+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:53:09 volumio go-librespot[4667]: time="2026-02-13T10:53:09+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:09 volumio go-librespot[4667]: time="2026-02-13T10:53:09+07:00" level=debug msg="completed challenge" Feb 13 10:53:09 volumio go-librespot[4667]: time="2026-02-13T10:53: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: BadCredentials " Feb 13 10:53:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:11 volumio volumio[4651]: info: ------------------------------------------- Feb 13 10:53:11 volumio volumio[4651]: info: ----- Volumio3 ---- Feb 13 10:53:11 volumio volumio[4651]: info: ------------------------------------------- Feb 13 10:53:11 volumio volumio[4651]: info: ----- System startup ---- Feb 13 10:53:11 volumio volumio[4651]: info: ------------------------------------------- Feb 13 10:53:12 volumio volumio-remote-updater[643]: [2026-02-13 10:53:12] [connect] Successful connection Feb 13 10:53:12 volumio volumio[4651]: info: MYVOLUMIO Environment detected Feb 13 10:53:12 volumio volumio[4651]: info: Plugin folders cleanup Feb 13 10:53:12 volumio volumio[4651]: info: Scanning into folder /volumio/app/plugins/ Feb 13 10:53:12 volumio volumio[4651]: info: Scanning category audio_interface Feb 13 10:53:12 volumio volumio[4651]: info: Scanning category miscellanea Feb 13 10:53:12 volumio volumio[4651]: info: Scanning category music_service Feb 13 10:53:12 volumio volumio[4651]: info: Scanning category plugins.json Feb 13 10:53:12 volumio volumio[4651]: info: Scanning category system_controller Feb 13 10:53:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 13 10:53:12 volumio volumio[4651]: info: Scanning category user_interface Feb 13 10:53:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:12 volumio volumio[4651]: info: Scanning into folder /data/plugins/ Feb 13 10:53:12 volumio volumio[4651]: info: Scanning category music_service Feb 13 10:53:12 volumio volumio[4651]: info: Plugin folders cleanup completed Feb 13 10:53:12 volumio volumio[4651]: info: ------------------------------------------- Feb 13 10:53:12 volumio volumio[4651]: info: ----- Core plugins startup ---- Feb 13 10:53:12 volumio volumio[4651]: info: ------------------------------------------- Feb 13 10:53:12 volumio volumio[4651]: info: Loading plugins from folder /volumio/app/plugins/ Feb 13 10:53:12 volumio volumio[4651]: info: Adding plugin upnp to MyMusic Plugins Feb 13 10:53:12 volumio volumio[4651]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 13 10:53:12 volumio volumio[4651]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 13 10:53:12 volumio volumio[4651]: info: Loading plugins from folder /data/plugins/ Feb 13 10:53:12 volumio volumio[4651]: info: Loading plugin "system"... Feb 13 10:53:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:12 volumio go-librespot[4684]: go-librespot daemon starting... Feb 13 10:53:12 volumio volumio[4651]: info: Loading plugin "appearance"... Feb 13 10:53:12 volumio go-librespot[4685]: time="2026-02-13T10:53:12+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:12 volumio go-librespot[4685]: time="2026-02-13T10:53:12+07:00" level=debug msg="app state loaded" Feb 13 10:53:12 volumio go-librespot[4685]: time="2026-02-13T10:53:12+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:53:13 volumio go-librespot[4685]: time="2026-02-13T10:53:13+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:53:13 volumio go-librespot[4685]: time="2026-02-13T10:53:13+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:53:13 volumio go-librespot[4685]: time="2026-02-13T10:53:13+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:53:13 volumio go-librespot[4685]: time="2026-02-13T10:53:13+07:00" level=info msg="zeroconf server listening on port 38621" Feb 13 10:53:13 volumio go-librespot[4685]: time="2026-02-13T10:53:13+07:00" level=debug msg="obtained new client token: AABR/CvxkI/CKcf/lYViD4BBlxqJVG9FNOrx9y8GFBqNhUXyM6P9AR3IKD8A5PxJQPOvSCKAaoLMtQcHj+qpHCTS8AOzVCnph/8z0kl4zSQrA937KrsTapwIUQEtzA68hprp8RX2DJn52Ef4DNuNxnVytsZNo7rRvLro+k/bqPN+ULZDuxwd4FzjJoIGMTYKtGl0xyq5KwPD0aiYfaVBztPrWhFLPL8G/gI2D0mqh8OSsnWvpg1E40SQiQ==" Feb 13 10:53:13 volumio go-librespot[4685]: time="2026-02-13T10:53:13+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:53:13 volumio go-librespot[4685]: time="2026-02-13T10:53:13+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:13 volumio go-librespot[4685]: time="2026-02-13T10:53:13+07:00" level=debug msg="completed challenge" Feb 13 10:53:14 volumio go-librespot[4685]: time="2026-02-13T10:53:14+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:53:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:14 volumio volumio[4651]: info: Loading plugin "network"... Feb 13 10:53:14 volumio volumio[4651]: info: Refreshing Cached IP Addresses Feb 13 10:53:14 volumio sudo[4696]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 13 10:53:14 volumio sudo[4696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:14 volumio sudo[4696]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:14 volumio sudo[4698]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 13 10:53:14 volumio volumio[4651]: info: Loading plugin "services"... Feb 13 10:53:14 volumio volumio[4651]: info: Loading plugin "alsa_controller"... Feb 13 10:53:14 volumio sudo[4698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:15 volumio sudo[4698]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:15 volumio sudo[4706]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 13 10:53:15 volumio sudo[4706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:15 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 10:53:15 volumio volumio[4651]: info: Loading plugin "wizard"... Feb 13 10:53:15 volumio volumio[4651]: info: Loading plugin "networkfs"... Feb 13 10:53:15 volumio volumio[4651]: info: Starting Udev Watcher for removable devices Feb 13 10:53:15 volumio volumio[4651]: info: Ignoring mount for partition: boot Feb 13 10:53:15 volumio volumio[4651]: info: Ignoring mount for partition: volumio Feb 13 10:53:15 volumio volumio[4651]: info: Ignoring mount for partition: volumio_data Feb 13 10:53:15 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 13 10:53:15 volumio volumio[4651]: info: Loading plugin "volumio_command_line_client"... Feb 13 10:53:15 volumio volumio[4651]: info: Loading plugin "upnp"... Feb 13 10:53:15 volumio volumio[4651]: info: [1770954795281] Starting Upmpd Daemon Feb 13 10:53:15 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 13 10:53:15 volumio volumio[4651]: info: Loading plugin "my_music"... Feb 13 10:53:15 volumio volumio[4651]: info: Loading plugin "mpd"... Feb 13 10:53:15 volumio volumio[4651]: info: Loading plugin "upnp_browser"... Feb 13 10:53:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 13 10:53:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:17 volumio go-librespot[4730]: go-librespot daemon starting... Feb 13 10:53:17 volumio go-librespot[4731]: time="2026-02-13T10:53:17+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:17 volumio go-librespot[4731]: time="2026-02-13T10:53:17+07:00" level=debug msg="app state loaded" Feb 13 10:53:17 volumio go-librespot[4731]: time="2026-02-13T10:53:17+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:53:17 volumio go-librespot[4731]: time="2026-02-13T10:53:17+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:53:17 volumio go-librespot[4731]: time="2026-02-13T10:53:17+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:53:17 volumio go-librespot[4731]: time="2026-02-13T10:53:17+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:53:17 volumio go-librespot[4731]: time="2026-02-13T10:53:17+07:00" level=info msg="zeroconf server listening on port 45839" Feb 13 10:53:17 volumio sudo[4706]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:17 volumio go-librespot[4731]: time="2026-02-13T10:53:17+07:00" level=debug msg="obtained new client token: AADCjYCFj0Jvz63XkbfpDjPC6wf8uNPF12ESkbgw5tK4/ll+sEodKMrEwBZaheB7Uk53ov8l80X1w5AbltbDVS3FJx8yIuP3rUqfTXA97A+2MzIPVjIhaSePUI3N/l16KM1HCZm3eIRHJ+Nabyc/9TEG4E7nK2CQyclBKylamH49IA+t/GgKVHgzvjqubK0kFMq0rB0VQ56iBC4RScOLWlqC7exHttb6H/diL6q6uKsNytAOD5oFXtLagg==" Feb 13 10:53:17 volumio go-librespot[4731]: time="2026-02-13T10:53:17+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:53:18 volumio go-librespot[4731]: time="2026-02-13T10:53:18+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:18 volumio go-librespot[4731]: time="2026-02-13T10:53:18+07:00" level=debug msg="completed challenge" Feb 13 10:53:18 volumio go-librespot[4731]: time="2026-02-13T10:53:18+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:53:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:18 volumio volumio[4651]: info: Starting UPNP Browser Feb 13 10:53:18 volumio volumio[4651]: info: Loading plugin "alarm-clock"... Feb 13 10:53:19 volumio volumio[4651]: info: Loading plugin "airplay_emulation"... Feb 13 10:53:19 volumio volumio[4651]: info: Starting Shairport Sync Feb 13 10:53:19 volumio volumio[4651]: info: Loading plugin "last_100"... Feb 13 10:53:19 volumio volumio[4651]: info: Loading plugin "webradio"... Feb 13 10:53:19 volumio volumio[4651]: info: Loading plugin "i2s_dacs"... Feb 13 10:53:19 volumio volumio[4651]: info: Loading plugin "volumiodiscovery"... Feb 13 10:53:19 volumio volumio[4651]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 13 10:53:19 volumio volumio[4651]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 10:53:19 volumio node[4651]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 13 10:53:19 volumio volumio[4651]: *** WARNING *** For more information see Feb 13 10:53:19 volumio volumio[4651]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 13 10:53:19 volumio volumio[4651]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 10:53:19 volumio volumio[4651]: *** WARNING *** For more information see Feb 13 10:53:19 volumio node[4651]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 10:53:19 volumio node[4651]: *** WARNING *** For more information see Feb 13 10:53:19 volumio node[4651]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 13 10:53:19 volumio node[4651]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 10:53:19 volumio node[4651]: *** WARNING *** For more information see Feb 13 10:53:19 volumio volumio[4651]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 13 10:53:19 volumio volumio[4651]: info: Discovery: Started advertising with name: Volumio Feb 13 10:53:19 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 13 10:53:19 volumio volumio[4651]: info: Loading plugin "spop"... Feb 13 10:53:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 13 10:53:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:21 volumio volumio[4651]: info: Loading plugin "ytcr"... Feb 13 10:53:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:21 volumio go-librespot[4741]: go-librespot daemon starting... Feb 13 10:53:21 volumio go-librespot[4742]: time="2026-02-13T10:53:21+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:21 volumio go-librespot[4742]: time="2026-02-13T10:53:21+07:00" level=debug msg="app state loaded" Feb 13 10:53:21 volumio go-librespot[4742]: time="2026-02-13T10:53:21+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:53:21 volumio go-librespot[4742]: time="2026-02-13T10:53:21+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:53:21 volumio go-librespot[4742]: time="2026-02-13T10:53:21+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:53:21 volumio go-librespot[4742]: time="2026-02-13T10:53:21+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:53:21 volumio go-librespot[4742]: time="2026-02-13T10:53:21+07:00" level=info msg="zeroconf server listening on port 45121" Feb 13 10:53:22 volumio go-librespot[4742]: time="2026-02-13T10:53:22+07:00" level=debug msg="obtained new client token: AABzOT/Buzxu5VWkeduy5Jjj4UqAj5tlfhDsZqvtMvJ1TBCEWWFjEW16EZsW1D/9OnTBLEPJDwRJqIKqIbPgYAs2QjW2dwXPe2ZOgGYPeVEhHZ1fk2T9GNS+H3mq0Lq5nbiihyaR1btIs6krk+mRAckQPAtABmsyPkhfJa9vxWf6blJ/F6iVS5+Yjn/6AXwQLLFfIN4+2rL1OilrszHZ6XeZS00rxvi4o2NKCSjMhspN6+FhqutboiI=" Feb 13 10:53:22 volumio go-librespot[4742]: time="2026-02-13T10:53:22+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:53:22 volumio go-librespot[4742]: time="2026-02-13T10:53:22+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:22 volumio go-librespot[4742]: time="2026-02-13T10:53:22+07:00" level=debug msg="completed challenge" Feb 13 10:53:22 volumio go-librespot[4742]: time="2026-02-13T10:53:22+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:53:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:24 volumio volumio[4651]: info: Loading plugin "ytmusic"... Feb 13 10:53:24 volumio volumio-remote-updater[643]: [2026-02-13 10:53:24] [connect] Successful connection Feb 13 10:53:25 volumio volumio[4651]: info: Loading plugin "outputs"... Feb 13 10:53:25 volumio volumio[4651]: info: Loading plugin "albumart"... Feb 13 10:53:25 volumio volumio[4651]: info: Plugin example_plugin is not enabled Feb 13 10:53:25 volumio volumio[4651]: info: Loading plugin "inputs"... Feb 13 10:53:25 volumio volumio[4651]: info: Loading plugin "updater_comm"... Feb 13 10:53:25 volumio volumio[4651]: info: Plugin mpdemulation is not enabled Feb 13 10:53:25 volumio volumio[4651]: info: Loading plugin "rest_api"... Feb 13 10:53:25 volumio volumio[4651]: info: Loading plugin "websocket"... Feb 13 10:53:25 volumio volumio[4651]: info: Starting Socket.io Server version 1.7.4 Feb 13 10:53:25 volumio volumio[4651]: info: Loading plugin "RoonBridge"... Feb 13 10:53:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 13 10:53:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:25 volumio go-librespot[4773]: go-librespot daemon starting... Feb 13 10:53:25 volumio go-librespot[4774]: time="2026-02-13T10:53:25+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:25 volumio go-librespot[4774]: time="2026-02-13T10:53:25+07:00" level=debug msg="app state loaded" Feb 13 10:53:25 volumio go-librespot[4774]: time="2026-02-13T10:53:25+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:53:26 volumio volumio[4651]: info: Applying required configuration parameters for plugin RoonBridge Feb 13 10:53:26 volumio volumio[4651]: info: Loading i18n strings for locale en Feb 13 10:53:26 volumio volumio[4651]: Updating browse sources language Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:53:26 volumio go-librespot[4774]: time="2026-02-13T10:53:26+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:53:26 volumio go-librespot[4774]: time="2026-02-13T10:53:26+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:53:26 volumio go-librespot[4774]: time="2026-02-13T10:53:26+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:53:26 volumio go-librespot[4774]: time="2026-02-13T10:53:26+07:00" level=info msg="zeroconf server listening on port 38943" Feb 13 10:53:26 volumio volumio[4763]: Forking 3 albumart workers Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::initPlayerControls Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 10:53:26 volumio volumio[4651]: Express server listening on port 3000 Feb 13 10:53:26 volumio volumio[4651]: [Metrics] WebUI: 16s 109.36ms Feb 13 10:53:26 volumio go-librespot[4774]: time="2026-02-13T10:53:26+07:00" level=debug msg="obtained new client token: AAAPkwA0o696vASmNTTEOattIN+JFZyMldXYSabiQS5KYRE0rUGzeyjpZKWXUj5GqioxzvZd4ZzCKlsIQpTGb9HRy/joabMLHM30YOaImrRTqmKowK+T2Z4UIt3pLFwWDQ8/s/jYIfdNSncRdkdZKFyZk4bjB/6HGFV7/8ZaFS2CaXkrrqQ7/wjuO32+jBR2huen9kbmyzh73A3HjdT2DmAHvy5wcWcnofYzPIeWq6nFCqF7MOS2/PMLoA==" Feb 13 10:53:26 volumio volumio[4651]: info: CoreStateMachine::resetVolumioState Feb 13 10:53:26 volumio volumio[4651]: info: CoreStateMachine::getcurrentVolume Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 10:53:26 volumio go-librespot[4774]: time="2026-02-13T10:53:26+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:53:26 volumio sudo[4815]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 13 10:53:26 volumio sudo[4815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:26 volumio go-librespot[4774]: time="2026-02-13T10:53:26+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:26 volumio go-librespot[4774]: time="2026-02-13T10:53:26+07:00" level=debug msg="completed challenge" Feb 13 10:53:26 volumio sudo[4815]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:26 volumio sudo[4817]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 13 10:53:26 volumio sudo[4817]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:26 volumio sudo[4817]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:26 volumio go-librespot[4774]: time="2026-02-13T10:53:26+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:53:26 volumio volumio[4651]: info: Volumio Network Manager: Network status updated: 1 Feb 13 10:53:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:27 volumio volumio[4651]: info: VolumeController:: Volume=100 Mute =false Feb 13 10:53:27 volumio volumio[4651]: info: CoreStateMachine::pushState Feb 13 10:53:27 volumio volumio[4651]: info: CorePlayQueue::getTrack 0 Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::volumioPushState Feb 13 10:53:27 volumio volumio[4651]: info: CoreStateMachine::updateTrackBlock Feb 13 10:53:27 volumio volumio[4651]: info: CorePlayQueue::getTrackBlock Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 10:53:27 volumio volumio-remote-updater[643]: [2026-02-13 10:53:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770954804 101 Feb 13 10:53:27 volumio volumio[4651]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:53:27 volumio volumio[4651]: info: Reloading queue from file Feb 13 10:53:27 volumio volumio[4651]: info: CoreStateMachine::setRepeat null single undefined Feb 13 10:53:27 volumio volumio[4651]: info: CoreStateMachine::pushState Feb 13 10:53:27 volumio volumio[4651]: info: CorePlayQueue::getTrack 0 Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::volumioPushState Feb 13 10:53:27 volumio volumio[4651]: info: CoreStateMachine::setRandom null Feb 13 10:53:27 volumio volumio[4651]: info: CoreStateMachine::pushState Feb 13 10:53:27 volumio volumio[4651]: info: CorePlayQueue::getTrack 0 Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::volumioPushState Feb 13 10:53:27 volumio volumio[4651]: info: Setting Device type: Raspberry PI Feb 13 10:53:27 volumio volumio[4651]: info: Completed loading Core Plugins Feb 13 10:53:27 volumio volumio[4651]: info: Preparing to generate the ALSA configuration file Feb 13 10:53:27 volumio volumio[4651]: info: VolumeController:: Volume=100 Mute =false Feb 13 10:53:27 volumio volumio[4651]: info: CoreStateMachine::pushState Feb 13 10:53:27 volumio volumio[4651]: info: CorePlayQueue::getTrack 0 Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::volumioPushState Feb 13 10:53:27 volumio volumio[4651]: info: Asound.conf file unchanged, so no further update is needed Feb 13 10:53:27 volumio volumio[4651]: info: Output device has changed, restarting MPD Feb 13 10:53:27 volumio volumio[4651]: info: Output device has changed, restarting Shairport Sync Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:53:27 volumio sudo[4832]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 13 10:53:27 volumio sudo[4832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:27 volumio sudo[4834]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 13 10:53:27 volumio sudo[4834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:27 volumio sudo[4836]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 13 10:53:27 volumio sudo[4836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:27 volumio sudo[4834]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:27 volumio volumio[4651]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 13 10:53:27 volumio volumio[4651]: info: ___________ START PLUGINS ___________ Feb 13 10:53:27 volumio volumio[4651]: info: ControllerMpd::onStart: Initializing MPD Feb 13 10:53:27 volumio volumio[4651]: info: Creating MPD Configuration file Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 10:53:27 volumio volumio[4651]: info: [1770954807886] CoreMusicLibrary::Adding element Media Servers Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:53:27 volumio sudo[4845]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 13 10:53:27 volumio sudo[4845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:27 volumio sudo[4845]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:27 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 13 10:53:27 volumio sudo[4832]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:27 volumio volumio[4651]: info: UPNP Browser: Client initialized successfully Feb 13 10:53:28 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:28 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:53:28 volumio sudo[4847]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 13 10:53:28 volumio sudo[4847]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:28 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 13 10:53:28 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 13 10:53:28 volumio systemd[1]: mpd.service: Consumed 7.039s CPU time. Feb 13 10:53:28 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 13 10:53:28 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 13 10:53:28 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 13 10:53:28 volumio volumio[4651]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 13 10:53:28 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:28 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:53:28 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 13 10:53:28 volumio volumio[4651]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 13 10:53:28 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 13 10:53:28 volumio volumio[4651]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 10:53:28 volumio volumio[4651]: info: [1770954808180] CoreMusicLibrary::Adding element Last_100 Feb 13 10:53:28 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:53:28 volumio volumio[4651]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 10:53:28 volumio volumio[4651]: info: [1770954808195] CoreMusicLibrary::Adding element Webradio Feb 13 10:53:28 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:53:28 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 10:53:28 volumio volumio[4651]: info: Initializing BBC Radios Feb 13 10:53:28 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 13 10:53:28 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 13 10:53:28 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 13 10:53:28 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 13 10:53:28 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 13 10:53:28 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 10:53:28 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:53:28 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 13 10:53:28 volumio volumio[4651]: info: Creating Spotify config file Feb 13 10:53:28 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:28 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 13 10:53:28 volumio sudo[4874]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 13 10:53:28 volumio sudo[4874]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 13 10:53:28 volumio sudo[4874]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:29 volumio volumio[4651]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 10:53:29 volumio volumio[4651]: info: [1770954809477] CoreMusicLibrary::Adding element YouTube Music Feb 13 10:53:29 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:53:29 volumio volumio[4651]: Cannot find translation for source YouTube Music Feb 13 10:53:29 volumio volumio[4651]: info: Volumio Calling Home Feb 13 10:53:29 volumio volumio[4782]: Starting albumart workers Feb 13 10:53:29 volumio sudo[4878]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 13 10:53:29 volumio sudo[4878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:29 volumio sudo[4878]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:29 volumio volumio[4781]: Starting albumart workers Feb 13 10:53:29 volumio volumio[4783]: Starting albumart workers Feb 13 10:53:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 13 10:53:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:30 volumio go-librespot[4883]: go-librespot daemon starting... Feb 13 10:53:30 volumio go-librespot[4884]: time="2026-02-13T10:53:30+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:30 volumio volumio[4651]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 13 10:53:30 volumio volumio[4651]: info: Discovery: Found device Volumio Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::volumioGetState Feb 13 10:53:30 volumio volumio[4651]: info: CorePlayQueue::getTrack 0 Feb 13 10:53:30 volumio volumio[4651]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 13 10:53:30 volumio volumio[4651]: info: Discovery: Found device Volumio Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::volumioGetState Feb 13 10:53:30 volumio volumio[4651]: info: CorePlayQueue::getTrack 0 Feb 13 10:53:30 volumio volumio[4651]: info: MPD Permissions set Feb 13 10:53:30 volumio volumio[4651]: info: MPD Permissions set Feb 13 10:53:30 volumio volumio[4651]: info: Upmpdcli Daemon Started Feb 13 10:53:30 volumio volumio[4651]: info: Volumio called home Feb 13 10:53:30 volumio volumio[4651]: info: Spotify config file written Feb 13 10:53:30 volumio sudo[4896]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 13 10:53:30 volumio sudo[4896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:30 volumio volumio[4651]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: No need to fix Spotify hosts Feb 13 10:53:31 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 13 10:53:31 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 13 10:53:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:31 volumio go-librespot[4911]: go-librespot daemon starting... Feb 13 10:53:31 volumio sudo[4896]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:31 volumio go-librespot[4912]: time="2026-02-13T10:53:31+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:31 volumio go-librespot[4912]: time="2026-02-13T10:53:31+07:00" level=debug msg="app state loaded" Feb 13 10:53:31 volumio go-librespot[4912]: time="2026-02-13T10:53:31+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:53:32 volumio go-librespot[4912]: time="2026-02-13T10:53:32+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:53:32 volumio go-librespot[4912]: time="2026-02-13T10:53:32+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:53:32 volumio go-librespot[4912]: time="2026-02-13T10:53:32+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:53:32 volumio go-librespot[4912]: time="2026-02-13T10:53:32+07:00" level=info msg="zeroconf server listening on port 42301" Feb 13 10:53:32 volumio go-librespot[4912]: time="2026-02-13T10:53:32+07:00" level=debug msg="obtained new client token: AABGD+BLkbCAp1PvBk06LaDA0QlDYhuIEHkCoZFGvEta/ChyBpcbWqu6SxgrZwCE3osTDi22lTXGcSmepHBvy+DzCFjempxtU1KBGikp0j4AUbKMK5/yet3x/OmK1ZJVgxwzt3z3H/Py+nwGSzDg3jaBBKWoadNmW1qXDQ5MW+Mn9QHMYRWwNHeRba8eAeahnCpIqcNJL3jRkX+5gsY94D9PaEl14t3vMcDmyOcmDmgL0Cc9v354Utr+PQ==" Feb 13 10:53:32 volumio volumio[4651]: info: Starting Shairport Sync Feb 13 10:53:32 volumio volumio[4651]: info: Starting Shairport Sync Feb 13 10:53:32 volumio volumio[4651]: info: Starting Shairport Sync Feb 13 10:53:32 volumio sudo[4936]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 10:53:32 volumio sudo[4936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:32 volumio go-librespot[4912]: time="2026-02-13T10:53:32+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:53:32 volumio sudo[4941]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 10:53:32 volumio sudo[4941]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:32 volumio sudo[4943]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 10:53:32 volumio sudo[4943]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:32 volumio go-librespot[4912]: time="2026-02-13T10:53:32+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:32 volumio go-librespot[4912]: time="2026-02-13T10:53:32+07:00" level=debug msg="completed challenge" Feb 13 10:53:32 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 13 10:53:32 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 13 10:53:32 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 13 10:53:32 volumio systemd[1]: shairport-sync.service: Consumed 2.346s CPU time. Feb 13 10:53:32 volumio go-librespot[4912]: time="2026-02-13T10:53: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: BadCredentials " Feb 13 10:53:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:32 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 13 10:53:32 volumio sudo[4941]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:32 volumio sudo[4936]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:33 volumio sudo[4943]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:33 volumio volumio[4651]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 13 10:53:33 volumio volumio[4651]: SPOTIFY: BQBxCv4gOLzClJvMQHfirkrwUh1pYa-XsSgm-o04sf-ZxBUsl0v_tafQc3Its6UpqWsE6C9asrtFAArBh8iIus7VazYon_A4oksinjOukasqZ6_IRSz35K3b1RZx2BvWX3eyBkEnSzc5OD76cyQqVHjS-ngWjAsx5WzlbxDSmq8inNfPLyh0M2N7SLyuuQyLffgZ6NsejrLPeRiqbYj97QFIORESDUUHbwl7kVDAleGdVHt4mGOCSKaqUQxh7sNUQ-n_fl1hO1eCEuDM4rbaxkt-5XoYnGufqLkZ_-9zy2lmH1D-Mtg5CYWb Feb 13 10:53:33 volumio volumio[4651]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 13 10:53:33 volumio volumio[4651]: info: New Spotify access token = BQBxCv4gOLzClJvMQHfirkrwUh1pYa-XsSgm-o04sf-ZxBUsl0v_tafQc3Its6UpqWsE6C9asrtFAArBh8iIus7VazYon_A4oksinjOukasqZ6_IRSz35K3b1RZx2BvWX3eyBkEnSzc5OD76cyQqVHjS-ngWjAsx5WzlbxDSmq8inNfPLyh0M2N7SLyuuQyLffgZ6NsejrLPeRiqbYj97QFIORESDUUHbwl7kVDAleGdVHt4mGOCSKaqUQxh7sNUQ-n_fl1hO1eCEuDM4rbaxkt-5XoYnGufqLkZ_-9zy2lmH1D-Mtg5CYWb Feb 13 10:53:33 volumio volumio[4651]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 13 10:53:33 volumio volumio[4651]: info: Shairport-Sync Started Feb 13 10:53:33 volumio volumio[4651]: Error adding Membership: Error: addMembership EINVAL Feb 13 10:53:33 volumio volumio[4651]: info: Shairport-Sync Started Feb 13 10:53:33 volumio volumio[4651]: info: Shairport-Sync Started Feb 13 10:53:33 volumio volumio[4651]: info: CoreCommandRouter::volumioGetState Feb 13 10:53:33 volumio volumio[4651]: info: CorePlayQueue::getTrack 0 Feb 13 10:53:33 volumio volumio[4651]: SPOTIFY: User informations: {"country":"PK","display_name":"215mmgea6kss3jxeqsqdquvji","email":"linh.ngotran@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/215mmgea6kss3jxeqsqdquvji"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/215mmgea6kss3jxeqsqdquvji","id":"215mmgea6kss3jxeqsqdquvji","images":[],"product":"premium","type":"user","uri":"spotify:user:215mmgea6kss3jxeqsqdquvji"} Feb 13 10:53:33 volumio volumio[4651]: info: Spotify Successfully logged in Feb 13 10:53:33 volumio volumio[4651]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 10:53:33 volumio volumio[4651]: info: [1770954813568] CoreMusicLibrary::Adding element Spotify Feb 13 10:53:33 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:53:33 volumio volumio[4651]: Cannot find translation for source YouTube Music Feb 13 10:53:33 volumio volumio[4651]: Cannot find translation for source Spotify Feb 13 10:53:34 volumio volumio[4651]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 13 10:53:34 volumio volumio[4651]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 10:53:34 volumio volumio[4651]: info: VolumeController:: Volume=100 Mute =false Feb 13 10:53:34 volumio volumio[4651]: info: CoreCommandRouter::volumioGetState Feb 13 10:53:34 volumio volumio[4651]: info: CorePlayQueue::getTrack 0 Feb 13 10:53:34 volumio volumio[4651]: info: CoreStateMachine::pushState Feb 13 10:53:34 volumio volumio[4651]: info: CorePlayQueue::getTrack 0 Feb 13 10:53:34 volumio volumio[4651]: info: CoreCommandRouter::volumioPushState Feb 13 10:53:35 volumio volumio[4651]: info: go-librespot daemon successfully initialized Feb 13 10:53:35 volumio mpd[4876]: 2026-02-13T10:53:35 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 13 10:53:35 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 13 10:53:35 volumio sudo[4836]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:35 volumio sudo[4847]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 13 10:53:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:35 volumio go-librespot[4985]: go-librespot daemon starting... Feb 13 10:53:35 volumio go-librespot[4986]: time="2026-02-13T10:53:35+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:35 volumio go-librespot[4986]: time="2026-02-13T10:53:35+07:00" level=debug msg="app state loaded" Feb 13 10:53:35 volumio go-librespot[4986]: time="2026-02-13T10:53:35+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:53:36 volumio volumio[4651]: error: MPD error: The expression evaluated to a falsy value: Feb 13 10:53:36 volumio volumio[4651]: assert.ok(self.idling) Feb 13 10:53:36 volumio volumio[4651]: error: The expression evaluated to a falsy value: Feb 13 10:53:36 volumio volumio[4651]: assert.ok(self.idling) Feb 13 10:53:36 volumio volumio[4651]: error: updateQueue error: null Feb 13 10:53:36 volumio volumio[4651]: info: MPD running with PID4876 Feb 13 10:53:36 volumio volumio[4651]: ,establishing connection Feb 13 10:53:36 volumio volumio[4651]: info: Completed starting Core Plugins Feb 13 10:53:36 volumio volumio[4651]: info: ------------------------------------------- Feb 13 10:53:36 volumio volumio[4651]: info: ----- MyVolumio plugins startup ---- Feb 13 10:53:36 volumio volumio[4651]: info: ------------------------------------------- Feb 13 10:53:36 volumio volumio[4651]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 13 10:53:36 volumio volumio[4651]: error: updateQueue error: null Feb 13 10:53:36 volumio go-librespot[4986]: time="2026-02-13T10:53:36+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:53:36 volumio go-librespot[4986]: time="2026-02-13T10:53:36+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:53:36 volumio go-librespot[4986]: time="2026-02-13T10:53:36+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:53:36 volumio go-librespot[4986]: time="2026-02-13T10:53:36+07:00" level=info msg="zeroconf server listening on port 39087" Feb 13 10:53:36 volumio go-librespot[4986]: time="2026-02-13T10:53:36+07:00" level=debug msg="obtained new client token: AACaQRdnYmvM9Sj2zq1LZNf4vH5rdiQQda9sho3+XZR/VAzAwTVCkYrMwKyz2Q7aRzGWj9SfpUlf2vtGXyYQsT0TmxlhzGM1hRBQoJcA6vqrODjufxd/85IQPu+q0wOVoLdOqWf8iX6sAt2syxw8YUfgi6dk6N7SVqWQC5uWQijsDNR6Vwgz1P5w+bvmoYDBzkJLnSA33Gum8l+qm4rGj9jXK9buqKVFUKuyvL3mvaXy19IsnxB36XLI7A==" Feb 13 10:53:36 volumio go-librespot[4986]: time="2026-02-13T10:53:36+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:53:36 volumio go-librespot[4986]: time="2026-02-13T10:53:36+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:36 volumio go-librespot[4986]: time="2026-02-13T10:53:36+07:00" level=debug msg="completed challenge" Feb 13 10:53:37 volumio go-librespot[4986]: time="2026-02-13T10:53:37+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:53:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:38 volumio volumio[4651]: info: Initializing connection to go-librespot Websocket Feb 13 10:53:38 volumio volumio[4651]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 10:53:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 13 10:53:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:40 volumio go-librespot[4994]: go-librespot daemon starting... Feb 13 10:53:40 volumio go-librespot[4995]: time="2026-02-13T10:53:40+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:40 volumio go-librespot[4995]: time="2026-02-13T10:53:40+07:00" level=debug msg="app state loaded" Feb 13 10:53:40 volumio go-librespot[4995]: time="2026-02-13T10:53:40+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:53:40 volumio go-librespot[4995]: time="2026-02-13T10:53:40+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:53:40 volumio go-librespot[4995]: time="2026-02-13T10:53:40+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:53:40 volumio go-librespot[4995]: time="2026-02-13T10:53:40+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:53:40 volumio go-librespot[4995]: time="2026-02-13T10:53:40+07:00" level=info msg="zeroconf server listening on port 42049" Feb 13 10:53:40 volumio go-librespot[4995]: time="2026-02-13T10:53:40+07:00" level=debug msg="obtained new client token: AABLkoubnx8zSMTyLtW+iNUVtPg9T4EPRzIP4Ar0hORtn3CkLLioYf5DwJCzjRF57TCF45DoWTtsvThftoXh0qDfab+dgBD5oNumBjXscfBjMOwkref5NbwcwEXZIH9VZEs72UdGFyzyw2deMcbzkhCl5i6ekYV+1eHdo8m3zHwEJlszErEOUJlF9Ixdz8wyg3KGT3Q7iQb9uTyIfndQUxfjz5QV/RJYMZDEr68Wogc5vsO/weTIqaNrag==" Feb 13 10:53:40 volumio volumio[4651]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 13 10:53:40 volumio go-librespot[4995]: time="2026-02-13T10:53:40+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:53:41 volumio go-librespot[4995]: time="2026-02-13T10:53:41+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:41 volumio go-librespot[4995]: time="2026-02-13T10:53:41+07:00" level=debug msg="completed challenge" Feb 13 10:53:41 volumio go-librespot[4995]: time="2026-02-13T10:53:41+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:53:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:41 volumio volumio[4651]: info: Initializing connection to go-librespot Websocket Feb 13 10:53:41 volumio volumio[4651]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 10:53:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 13 10:53:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:44 volumio go-librespot[5002]: go-librespot daemon starting... Feb 13 10:53:44 volumio go-librespot[5003]: time="2026-02-13T10:53:44+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:44 volumio go-librespot[5003]: time="2026-02-13T10:53:44+07:00" level=debug msg="app state loaded" Feb 13 10:53:44 volumio volumio[4651]: info: Initializing connection to go-librespot Websocket Feb 13 10:53:44 volumio go-librespot[5003]: time="2026-02-13T10:53:44+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:53:44 volumio go-librespot[5003]: time="2026-02-13T10:53:44+07:00" level=debug msg="new websocket client" Feb 13 10:53:44 volumio volumio[4651]: info: Connection to go-librespot Websocket established Feb 13 10:53:44 volumio go-librespot[5003]: time="2026-02-13T10:53:44+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:53:44 volumio go-librespot[5003]: time="2026-02-13T10:53:44+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:53:44 volumio go-librespot[5003]: time="2026-02-13T10:53:44+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:53:44 volumio go-librespot[5003]: time="2026-02-13T10:53:44+07:00" level=info msg="zeroconf server listening on port 33773" Feb 13 10:53:45 volumio go-librespot[5003]: time="2026-02-13T10:53:45+07:00" level=debug msg="obtained new client token: AAAzUC2DSDnQg4V5VmooR+lcGWL8A/s1i9AkRG+speFcBasew++q311rmHdH90xGxudmM390nIxtcHfPIGz07Cgox21TvnspFVbzJIGGqkFCjhvURmxJKltOcvoOvMUcr8rfIHYxQyehWQCpdk25tfYd5o5YjATRqCJf+WQBhX2wRf9cxwW4h1fE3bB5a86z8/dn4QS6KXUksxuvCDdENt0rI04c+GxLmgXLB6gPMJO0G2eWLbfhS0Y=" Feb 13 10:53:45 volumio go-librespot[5003]: time="2026-02-13T10:53:45+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 13 10:53:45 volumio volumio[4651]: info: Adding plugin bluetooth to MyMusic Plugins Feb 13 10:53:45 volumio volumio[4651]: info: Adding plugin multiroom to MyMusic Plugins Feb 13 10:53:45 volumio volumio[4651]: info: Adding plugin metavolumio to MyMusic Plugins Feb 13 10:53:45 volumio volumio[4651]: info: Adding plugin cd_controller to MyMusic Plugins Feb 13 10:53:45 volumio volumio[4651]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 13 10:53:45 volumio volumio[4651]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 13 10:53:45 volumio volumio[4651]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 13 10:53:45 volumio go-librespot[5003]: time="2026-02-13T10:53:45+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 13 10:53:45 volumio go-librespot[5003]: time="2026-02-13T10:53:45+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:45 volumio go-librespot[5003]: time="2026-02-13T10:53:45+07:00" level=debug msg="completed challenge" Feb 13 10:53:45 volumio go-librespot[5003]: time="2026-02-13T10:53:45+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:53:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:47 volumio volumio[4651]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 13 10:53:47 volumio volumio[4651]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 13 10:53:47 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:53:47 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:53:47 volumio volumio[4651]: info: Starting MyVolumio Remote Streaming Endpoints Feb 13 10:53:47 volumio volumio[4651]: info: MyVolumio login type: Token Feb 13 10:53:47 volumio volumio[4651]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 13 10:53:47 volumio volumio[4651]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 13 10:53:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 13 10:53:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:48 volumio go-librespot[5024]: go-librespot daemon starting... Feb 13 10:53:48 volumio volumio[4651]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 13 10:53:48 volumio volumio[4651]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 13 10:53:48 volumio volumio[4651]: info: Streaming services startup Feb 13 10:53:48 volumio volumio[4651]: info: Starting Streaming Daemon Feb 13 10:53:48 volumio go-librespot[5025]: time="2026-02-13T10:53:48+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:48 volumio go-librespot[5025]: time="2026-02-13T10:53:48+07:00" level=debug msg="app state loaded" Feb 13 10:53:48 volumio go-librespot[5025]: time="2026-02-13T10:53:48+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:53:48 volumio sudo[5033]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 13 10:53:48 volumio sudo[5033]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:48 volumio volumio[4651]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 13 10:53:49 volumio sudo[5033]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:49 volumio volumio[4651]: info: Getting Spotify volume Feb 13 10:53:49 volumio volumio[4651]: info: Connection to go-librespot Websocket closed Feb 13 10:53:49 volumio volumio[4651]: error: Cannot start Volumio Streaming Daemon Feb 13 10:53:49 volumio volumio[4651]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 13 10:53:49 volumio volumio[4651]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 13 10:53:49 volumio volumio[4651]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 13 10:53:49 volumio volumio[4651]: info: CoreCommandRouter::volumioGetState Feb 13 10:53:49 volumio volumio[4651]: info: CorePlayQueue::getTrack 0 Feb 13 10:53:49 volumio volumio[4651]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 13 10:53:49 volumio volumio[4651]: SPOTIFY: SPOTIFY VOLUME undefined Feb 13 10:53:49 volumio volumio[4651]: SPOTIFY: VOLUMIO VOLUME 100 Feb 13 10:53:49 volumio volumio[4651]: info: Aligning Spotify Volume to Volumio Volume Feb 13 10:53:49 volumio volumio[4651]: info: CoreCommandRouter::volumioGetState Feb 13 10:53:49 volumio volumio[4651]: info: CorePlayQueue::getTrack 0 Feb 13 10:53:49 volumio volumio[4651]: info: Setting Spotify Volume from Volumio: 100 Feb 13 10:53:49 volumio go-librespot[5025]: time="2026-02-13T10:53:49+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 13 10:53:49 volumio go-librespot[5025]: time="2026-02-13T10:53:49+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 13 10:53:49 volumio go-librespot[5025]: time="2026-02-13T10:53:49+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 13 10:53:49 volumio go-librespot[5025]: time="2026-02-13T10:53:49+07:00" level=info msg="zeroconf server listening on port 33017" Feb 13 10:53:49 volumio go-librespot[5025]: time="2026-02-13T10:53:49+07:00" level=debug msg="obtained new client token: AACVOtgNfKmUXpxUwImYu/8D9bQGeWVkKQ3tFbPcWfwp/bs3GlBTmAjW6YwOZTjpSukGawb8Mf5ZRCl0isWXPYBhcPakJaY12lNwntfi/OFoDhXNc231ED8EJF1Tjo6bSlCbFYeQuOxt770NJShC2Q5ZMk/aX55r/dIa7+LmGlqubXAYRlwGGvG90CLU42SW3hgw5hO3JM8zcNyIrMneUFP+4TNwWOV4FtHZ0b6YHHR25nI7rknOCcqXCA==" Feb 13 10:53:49 volumio go-librespot[5025]: time="2026-02-13T10:53:49+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:53:49 volumio go-librespot[5025]: time="2026-02-13T10:53:49+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:49 volumio go-librespot[5025]: time="2026-02-13T10:53:49+07:00" level=debug msg="completed challenge" Feb 13 10:53:49 volumio go-librespot[5025]: time="2026-02-13T10:53:49+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:53:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:49 volumio volumio[4651]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 13 10:53:50 volumio volumio[4651]: Error: socket hang up Feb 13 10:53:50 volumio volumio[4651]: at connResetException (node:internal/errors:720:14) Feb 13 10:53:50 volumio volumio[4651]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 13 10:53:50 volumio volumio[4651]: at Socket.emit (node:events:526:35) Feb 13 10:53:50 volumio volumio[4651]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 13 10:53:50 volumio volumio[4651]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 13 10:53:50 volumio volumio[4651]: code: 'ECONNRESET', Feb 13 10:53:50 volumio volumio[4651]: response: undefined Feb 13 10:53:50 volumio volumio[4651]: } Feb 13 10:53:50 volumio volumio[4651]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 13 10:53:51 volumio sudo[5056]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-13 10:52' Feb 13 10:53:51 volumio sudo[5056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:51 volumio sudo[5056]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 13 10:53:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:53 volumio go-librespot[5064]: go-librespot daemon starting... Feb 13 10:53:53 volumio go-librespot[5065]: time="2026-02-13T10:53:53+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:53 volumio go-librespot[5065]: time="2026-02-13T10:53:53+07:00" level=debug msg="app state loaded" Feb 13 10:53:53 volumio go-librespot[5065]: time="2026-02-13T10:53:53+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:53:53 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:53 volumio volumio-remote-updater[643]: [2026-02-13 10:53:53] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Feb 13 10:53:53 volumio volumio-remote-updater[643]: [2026-02-13 10:53:53] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Feb 13 10:53:53 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 13 10:53:53 volumio systemd[1]: volumio.service: Consumed 55.886s CPU time. Feb 13 10:53:53 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 13 10:53:53 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 13 10:53:53 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 14595. Feb 13 10:53:53 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 13 10:53:53 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 13 10:53:53 volumio systemd[1]: volumio.service: Consumed 55.886s CPU time. Feb 13 10:53:53 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 13 10:53:53 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 13 10:53:53 volumio go-librespot[5065]: time="2026-02-13T10:53:53+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:53:53 volumio go-librespot[5065]: time="2026-02-13T10:53:53+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:53:53 volumio go-librespot[5065]: time="2026-02-13T10:53:53+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:53:53 volumio go-librespot[5065]: time="2026-02-13T10:53:53+07:00" level=info msg="zeroconf server listening on port 39807" Feb 13 10:53:53 volumio go-librespot[5065]: time="2026-02-13T10:53:53+07:00" level=debug msg="obtained new client token: AAB5fOt/mWyyWgkKktM3xDmPL7Lwz7AAdBh4R1WsVk/0jU9dE9t1olM8+NL6Waw6u3s6fDPjV89iXTYG/GS0Rw2haM0yhWKr956F7P4TWWMSjBOM/pluImTlvlVRFKON9b0bpietJs1mdCK8sumj1jsE/bdF8UDNOuCTwIwW12F1BxLLXeLvFvq5OR9bgEHeO5QidNpjRXI16FPy1X05Qk/YAhAuiRRCYKvfhygidXGOkE9VlrzLUtQ0og==" Feb 13 10:53:54 volumio go-librespot[5065]: time="2026-02-13T10:53:54+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 13 10:53:54 volumio go-librespot[5065]: time="2026-02-13T10:53:54+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.241.202:443: connect: connection refused" Feb 13 10:53:54 volumio go-librespot[5065]: time="2026-02-13T10:53:54+07:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 13 10:53:54 volumio go-librespot[5065]: time="2026-02-13T10:53:54+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:54 volumio go-librespot[5065]: time="2026-02-13T10:53:54+07:00" level=debug msg="completed challenge" Feb 13 10:53:54 volumio go-librespot[5065]: time="2026-02-13T10:53:54+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:53:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:57 volumio volumio[5084]: info: ------------------------------------------- Feb 13 10:53:57 volumio volumio[5084]: info: ----- Volumio3 ---- Feb 13 10:53:57 volumio volumio[5084]: info: ------------------------------------------- Feb 13 10:53:57 volumio volumio[5084]: info: ----- System startup ---- Feb 13 10:53:57 volumio volumio[5084]: info: ------------------------------------------- Feb 13 10:53:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 13 10:53:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:57 volumio go-librespot[5105]: go-librespot daemon starting... Feb 13 10:53:57 volumio go-librespot[5106]: time="2026-02-13T10:53:57+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:57 volumio go-librespot[5106]: time="2026-02-13T10:53:57+07:00" level=debug msg="app state loaded" Feb 13 10:53:57 volumio go-librespot[5106]: time="2026-02-13T10:53:57+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:53:58 volumio go-librespot[5106]: time="2026-02-13T10:53:58+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:53:58 volumio go-librespot[5106]: time="2026-02-13T10:53:58+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:53:58 volumio go-librespot[5106]: time="2026-02-13T10:53:58+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:53:58 volumio go-librespot[5106]: time="2026-02-13T10:53:58+07:00" level=info msg="zeroconf server listening on port 41695" Feb 13 10:53:58 volumio volumio-remote-updater[643]: [2026-02-13 10:53:58] [connect] Successful connection Feb 13 10:53:58 volumio go-librespot[5106]: time="2026-02-13T10:53:58+07:00" level=debug msg="obtained new client token: AACqzwC3lpxbLX/SwY/7R+Z5E7SE/3gCRUm82WGLElha77FCLEMSFD0vX6PRdtYFZkAqAAk5bSEyIBHYGUhf00MZEQih0Qbgf2B/UH9PeYSeP3kKqs9w3+/g0x1aJhkX1ET8yWDfwkX2E5OUH+r06XShbtrv223R4VNQddValbRaHG7ql8JXFLcX23j11LggY3UaiIOlrxSYpuyixn2PnVe6SVcnxWDWEqk9ArZJw+Ru957/RWULCBkXZg==" Feb 13 10:53:58 volumio go-librespot[5106]: time="2026-02-13T10:53:58+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 13 10:53:58 volumio volumio[5084]: info: MYVOLUMIO Environment detected Feb 13 10:53:58 volumio go-librespot[5106]: time="2026-02-13T10:53:58+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.241.202:443: connect: connection refused" Feb 13 10:53:58 volumio go-librespot[5106]: time="2026-02-13T10:53:58+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:80, retrying with a different AP" error="dial tcp 104.199.241.202:80: connect: connection refused" Feb 13 10:53:58 volumio volumio[5084]: info: Plugin folders cleanup Feb 13 10:53:58 volumio volumio[5084]: info: Scanning into folder /volumio/app/plugins/ Feb 13 10:53:58 volumio volumio[5084]: info: Scanning category audio_interface Feb 13 10:53:58 volumio volumio[5084]: info: Scanning category miscellanea Feb 13 10:53:58 volumio volumio[5084]: info: Scanning category music_service Feb 13 10:53:58 volumio volumio[5084]: info: Scanning category plugins.json Feb 13 10:53:58 volumio volumio[5084]: info: Scanning category system_controller Feb 13 10:53:58 volumio volumio[5084]: info: Scanning category user_interface Feb 13 10:53:58 volumio volumio[5084]: info: Scanning into folder /data/plugins/ Feb 13 10:53:58 volumio volumio[5084]: info: Scanning category music_service Feb 13 10:53:58 volumio volumio[5084]: info: Plugin folders cleanup completed Feb 13 10:53:58 volumio volumio[5084]: info: ------------------------------------------- Feb 13 10:53:58 volumio volumio[5084]: info: ----- Core plugins startup ---- Feb 13 10:53:58 volumio volumio[5084]: info: ------------------------------------------- Feb 13 10:53:58 volumio volumio[5084]: info: Loading plugins from folder /volumio/app/plugins/ Feb 13 10:53:58 volumio volumio[5084]: info: Adding plugin upnp to MyMusic Plugins Feb 13 10:53:58 volumio volumio[5084]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 13 10:53:58 volumio volumio[5084]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 13 10:53:58 volumio volumio[5084]: info: Loading plugins from folder /data/plugins/ Feb 13 10:53:58 volumio volumio[5084]: info: Loading plugin "system"... Feb 13 10:53:58 volumio volumio[5084]: info: Loading plugin "appearance"... Feb 13 10:53:59 volumio go-librespot[5106]: time="2026-02-13T10:53:59+07:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 13 10:53:59 volumio go-librespot[5106]: time="2026-02-13T10:53:59+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:59 volumio go-librespot[5106]: time="2026-02-13T10:53:59+07:00" level=debug msg="completed challenge" Feb 13 10:53:59 volumio go-librespot[5106]: time="2026-02-13T10:53:59+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:53:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:54:00 volumio volumio[5084]: info: Loading plugin "network"... Feb 13 10:54:00 volumio volumio[5084]: info: Refreshing Cached IP Addresses Feb 13 10:54:00 volumio sudo[5121]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 13 10:54:00 volumio sudo[5121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:54:00 volumio sudo[5123]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 13 10:54:00 volumio sudo[5123]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:54:00 volumio volumio[5084]: info: Loading plugin "services"... Feb 13 10:54:00 volumio sudo[5121]: pam_unix(sudo:session): session closed for user root Feb 13 10:54:00 volumio volumio[5084]: info: Loading plugin "alsa_controller"... Feb 13 10:54:00 volumio sudo[5123]: pam_unix(sudo:session): session closed for user root Feb 13 10:54:00 volumio sudo[5130]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 13 10:54:00 volumio sudo[5130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:54:01 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 10:54:01 volumio volumio[5084]: info: Loading plugin "wizard"... Feb 13 10:54:01 volumio volumio[5084]: info: Loading plugin "networkfs"... Feb 13 10:54:01 volumio volumio[5084]: info: Starting Udev Watcher for removable devices Feb 13 10:54:01 volumio volumio[5084]: info: Ignoring mount for partition: boot Feb 13 10:54:01 volumio volumio[5084]: info: Ignoring mount for partition: volumio Feb 13 10:54:01 volumio volumio[5084]: info: Ignoring mount for partition: volumio_data Feb 13 10:54:01 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 13 10:54:01 volumio volumio[5084]: info: Loading plugin "volumio_command_line_client"... Feb 13 10:54:01 volumio volumio[5084]: info: Loading plugin "upnp"... Feb 13 10:54:01 volumio volumio[5084]: info: [1770954841219] Starting Upmpd Daemon Feb 13 10:54:01 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 13 10:54:01 volumio volumio[5084]: info: Loading plugin "my_music"... Feb 13 10:54:01 volumio volumio[5084]: info: Loading plugin "mpd"... Feb 13 10:54:01 volumio volumio[5084]: info: Loading plugin "upnp_browser"... Feb 13 10:54:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 13 10:54:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:54:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:54:02 volumio go-librespot[5154]: go-librespot daemon starting... Feb 13 10:54:02 volumio go-librespot[5155]: time="2026-02-13T10:54:02+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:54:02 volumio go-librespot[5155]: time="2026-02-13T10:54:02+07:00" level=debug msg="app state loaded" Feb 13 10:54:02 volumio go-librespot[5155]: time="2026-02-13T10:54:02+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:54:03 volumio go-librespot[5155]: time="2026-02-13T10:54:03+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:54:03 volumio go-librespot[5155]: time="2026-02-13T10:54:03+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:54:03 volumio go-librespot[5155]: time="2026-02-13T10:54:03+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:54:03 volumio go-librespot[5155]: time="2026-02-13T10:54:03+07:00" level=info msg="zeroconf server listening on port 45163" Feb 13 10:54:03 volumio sudo[5130]: pam_unix(sudo:session): session closed for user root Feb 13 10:54:03 volumio go-librespot[5155]: time="2026-02-13T10:54:03+07:00" level=debug msg="obtained new client token: AADL2cR+hZworgeuW2quM6esn7vzCK3VOrljaA5HBJUe9LSwqFGuHn21M1Qxb/k7RqKQQR19jR36Y7g4W4Gfxpii+ZWcHs8Un6SGtOGiYV28lu0XdT9GZ7s2NeUy47LHiP6eeBl0I4RT5MjLYHLpW4ONdl3ZzLqEFZaQ3HjCfkbQHtbOS7sbLSWbC5exWfoKMnX8f5TSg9inp0EojNMZHGkcArXqKoqikdvAi8NFif5j50sbQLY95TU3Gg==" Feb 13 10:54:03 volumio go-librespot[5155]: time="2026-02-13T10:54:03+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:54:03 volumio go-librespot[5155]: time="2026-02-13T10:54:03+07:00" level=debug msg="completed keyexchange" Feb 13 10:54:03 volumio go-librespot[5155]: time="2026-02-13T10:54:03+07:00" level=debug msg="completed challenge" Feb 13 10:54:03 volumio go-librespot[5155]: time="2026-02-13T10:54: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: BadCredentials " Feb 13 10:54:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:54:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:54:04 volumio volumio[5084]: info: Starting UPNP Browser Feb 13 10:54:04 volumio volumio[5084]: info: Loading plugin "alarm-clock"... Feb 13 10:54:05 volumio volumio[5084]: info: Loading plugin "airplay_emulation"... Feb 13 10:54:05 volumio volumio[5084]: info: Starting Shairport Sync Feb 13 10:54:05 volumio volumio[5084]: info: Loading plugin "last_100"... Feb 13 10:54:05 volumio volumio[5084]: info: Loading plugin "webradio"... Feb 13 10:54:05 volumio volumio[5084]: info: Loading plugin "i2s_dacs"... Feb 13 10:54:05 volumio volumio[5084]: info: Loading plugin "volumiodiscovery"... Feb 13 10:54:05 volumio volumio[5084]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 13 10:54:05 volumio node[5084]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 13 10:54:05 volumio volumio[5084]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 10:54:05 volumio volumio[5084]: *** WARNING *** For more information see Feb 13 10:54:05 volumio volumio[5084]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 13 10:54:05 volumio volumio[5084]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 10:54:05 volumio volumio[5084]: *** WARNING *** For more information see Feb 13 10:54:05 volumio node[5084]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 10:54:05 volumio node[5084]: *** WARNING *** For more information see Feb 13 10:54:05 volumio node[5084]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 13 10:54:05 volumio node[5084]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 10:54:05 volumio node[5084]: *** WARNING *** For more information see Feb 13 10:54:05 volumio volumio[5084]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 13 10:54:05 volumio volumio[5084]: info: Discovery: Started advertising with name: Volumio Feb 13 10:54:05 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 13 10:54:05 volumio volumio[5084]: info: Loading plugin "spop"... Feb 13 10:54:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 13 10:54:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:54:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:54:07 volumio go-librespot[5166]: go-librespot daemon starting... Feb 13 10:54:07 volumio go-librespot[5167]: time="2026-02-13T10:54:07+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:54:07 volumio go-librespot[5167]: time="2026-02-13T10:54:07+07:00" level=debug msg="app state loaded" Feb 13 10:54:07 volumio go-librespot[5167]: time="2026-02-13T10:54:07+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:54:07 volumio volumio[5084]: info: Loading plugin "ytcr"... Feb 13 10:54:07 volumio go-librespot[5167]: time="2026-02-13T10:54:07+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:54:07 volumio go-librespot[5167]: time="2026-02-13T10:54:07+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:54:07 volumio go-librespot[5167]: time="2026-02-13T10:54:07+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:54:07 volumio go-librespot[5167]: time="2026-02-13T10:54:07+07:00" level=info msg="zeroconf server listening on port 43947" Feb 13 10:54:08 volumio go-librespot[5167]: time="2026-02-13T10:54:08+07:00" level=debug msg="obtained new client token: AABtYiJtVI51Y35p3e3ljGQr+G6jkkNPRhL3pqmPMnspbF18CMREswHOFTZ2/ED9oTeVyNdWJNOLQZNHKi5+jIRBe49NtnmmDv1xDDTamR/iQP/ePZGYb2vQR1Eua4Msexz6wOBAzsM0rKNYtnqC7jBjp1dVjlfZaVgEdsimkbq6K+JfCLaYNmLCPaRd5ymEInZAsJ/0h11wxiVhVtte+bBausHCS0EPBFjpn21B9lPYwkzm2ofoLig=" Feb 13 10:54:08 volumio go-librespot[5167]: time="2026-02-13T10:54:08+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:54:08 volumio go-librespot[5167]: time="2026-02-13T10:54:08+07:00" level=debug msg="completed keyexchange" Feb 13 10:54:08 volumio go-librespot[5167]: time="2026-02-13T10:54:08+07:00" level=debug msg="completed challenge" Feb 13 10:54:08 volumio go-librespot[5167]: time="2026-02-13T10:54: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: BadCredentials " Feb 13 10:54:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:54:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:54:10 volumio volumio[5084]: info: Loading plugin "ytmusic"... Feb 13 10:54:10 volumio volumio-remote-updater[643]: [2026-02-13 10:54:10] [connect] Successful connection Feb 13 10:54:11 volumio volumio[5084]: info: Loading plugin "outputs"... Feb 13 10:54:11 volumio volumio[5084]: info: Loading plugin "albumart"... Feb 13 10:54:11 volumio volumio[5084]: info: Plugin example_plugin is not enabled Feb 13 10:54:11 volumio volumio[5084]: info: Loading plugin "inputs"... Feb 13 10:54:11 volumio volumio[5084]: info: Loading plugin "updater_comm"... Feb 13 10:54:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 13 10:54:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:54:11 volumio volumio[5084]: info: Plugin mpdemulation is not enabled Feb 13 10:54:11 volumio volumio[5084]: info: Loading plugin "rest_api"... Feb 13 10:54:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:54:11 volumio go-librespot[5197]: go-librespot daemon starting... Feb 13 10:54:11 volumio volumio[5084]: info: Loading plugin "websocket"... Feb 13 10:54:11 volumio volumio[5084]: info: Starting Socket.io Server version 1.7.4 Feb 13 10:54:11 volumio volumio[5084]: info: Loading plugin "RoonBridge"... Feb 13 10:54:11 volumio go-librespot[5199]: time="2026-02-13T10:54:11+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:54:11 volumio go-librespot[5199]: time="2026-02-13T10:54:11+07:00" level=debug msg="app state loaded" Feb 13 10:54:11 volumio go-librespot[5199]: time="2026-02-13T10:54:11+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:54:12 volumio volumio[5084]: info: Applying required configuration parameters for plugin RoonBridge Feb 13 10:54:12 volumio volumio[5084]: info: Loading i18n strings for locale en Feb 13 10:54:12 volumio volumio[5084]: Updating browse sources language Feb 13 10:54:12 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:54:12 volumio go-librespot[5199]: time="2026-02-13T10:54:12+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 13 10:54:12 volumio go-librespot[5199]: time="2026-02-13T10:54:12+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 13 10:54:12 volumio go-librespot[5199]: time="2026-02-13T10:54:12+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 13 10:54:12 volumio go-librespot[5199]: time="2026-02-13T10:54:12+07:00" level=info msg="zeroconf server listening on port 44777" Feb 13 10:54:12 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 10:54:12 volumio volumio[5084]: info: CoreCommandRouter::initPlayerControls Feb 13 10:54:12 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:12 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:12 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:12 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:12 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:12 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:12 volumio volumio[5188]: Forking 3 albumart workers Feb 13 10:54:12 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:12 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:12 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 10:54:12 volumio volumio[5084]: Express server listening on port 3000 Feb 13 10:54:12 volumio volumio[5084]: [Metrics] WebUI: 16s 208.27ms Feb 13 10:54:12 volumio go-librespot[5199]: time="2026-02-13T10:54:12+07:00" level=debug msg="obtained new client token: AADtl6XaG+g1+mn5mpnyO9mMR0P9PUviAB4UpZeNJXVezneR4Iyuxk+cvGNNyP7obx1R8IfDNFSyrOQeNRzxgXYjVCDqgXPBvtp4pEL6Dw/Fn85S4IqQhDwQxb96AF4l/pysynk85RKXJes39HhtfCrltfgaz/Lhvd1zXcE7KS0UOoqXoN3qJQS2NCpVHYUyNWTSx4ESrli6vmdJlglzEBrKdszdL8Ws9XlOyIAqfE7KHVeBh5LPjHH4fg==" Feb 13 10:54:12 volumio volumio[5084]: info: CoreStateMachine::resetVolumioState Feb 13 10:54:12 volumio volumio[5084]: info: CoreStateMachine::getcurrentVolume Feb 13 10:54:12 volumio volumio[5084]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 10:54:12 volumio go-librespot[5199]: time="2026-02-13T10:54:12+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:54:12 volumio sudo[5239]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 13 10:54:12 volumio sudo[5239]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:54:12 volumio go-librespot[5199]: time="2026-02-13T10:54:12+07:00" level=debug msg="completed keyexchange" Feb 13 10:54:12 volumio go-librespot[5199]: time="2026-02-13T10:54:12+07:00" level=debug msg="completed challenge" Feb 13 10:54:12 volumio sudo[5239]: pam_unix(sudo:session): session closed for user root Feb 13 10:54:12 volumio sudo[5241]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 13 10:54:12 volumio sudo[5241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:54:12 volumio volumio[5084]: info: Volumio Network Manager: Network status updated: 1 Feb 13 10:54:12 volumio sudo[5241]: pam_unix(sudo:session): session closed for user root Feb 13 10:54:12 volumio go-librespot[5199]: time="2026-02-13T10:54:12+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:54:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:54:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:54:13 volumio volumio[5084]: info: VolumeController:: Volume=100 Mute =false Feb 13 10:54:13 volumio volumio[5084]: info: CoreStateMachine::pushState Feb 13 10:54:13 volumio volumio[5084]: info: CorePlayQueue::getTrack 0 Feb 13 10:54:13 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 10:54:13 volumio volumio[5084]: info: CoreCommandRouter::volumioPushState Feb 13 10:54:13 volumio volumio[5084]: info: CoreStateMachine::updateTrackBlock Feb 13 10:54:13 volumio volumio[5084]: info: CorePlayQueue::getTrackBlock Feb 13 10:54:13 volumio volumio[5084]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 10:54:13 volumio volumio-remote-updater[643]: [2026-02-13 10:54:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770954850 101 Feb 13 10:54:13 volumio volumio[5084]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Feb 13 10:54:13 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:54:13 volumio volumio[5084]: info: Reloading queue from file Feb 13 10:54:13 volumio volumio[5084]: info: CoreStateMachine::setRepeat null single undefined Feb 13 10:54:13 volumio volumio[5084]: info: CoreStateMachine::pushState Feb 13 10:54:13 volumio volumio[5084]: info: CorePlayQueue::getTrack 0 Feb 13 10:54:13 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 10:54:13 volumio volumio[5084]: info: CoreCommandRouter::volumioPushState Feb 13 10:54:13 volumio volumio[5084]: info: CoreStateMachine::setRandom null Feb 13 10:54:13 volumio volumio[5084]: info: CoreStateMachine::pushState Feb 13 10:54:13 volumio volumio[5084]: info: CorePlayQueue::getTrack 0 Feb 13 10:54:13 volumio volumio[5084]: info: CoreCommandRouter::volumioPushState Feb 13 10:54:13 volumio volumio[5084]: info: Setting Device type: Raspberry PI Feb 13 10:54:13 volumio volumio[5084]: info: Completed loading Core Plugins Feb 13 10:54:13 volumio volumio[5084]: info: Preparing to generate the ALSA configuration file Feb 13 10:54:13 volumio volumio[5084]: info: VolumeController:: Volume=100 Mute =false Feb 13 10:54:13 volumio volumio[5084]: info: CoreStateMachine::pushState Feb 13 10:54:13 volumio volumio[5084]: info: CorePlayQueue::getTrack 0 Feb 13 10:54:13 volumio volumio[5084]: info: CoreCommandRouter::volumioPushState Feb 13 10:54:13 volumio volumio[5084]: info: Asound.conf file unchanged, so no further update is needed Feb 13 10:54:13 volumio volumio[5084]: info: Output device has changed, restarting MPD Feb 13 10:54:13 volumio volumio[5084]: info: Output device has changed, restarting Shairport Sync Feb 13 10:54:13 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:13 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:54:13 volumio sudo[5259]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 13 10:54:13 volumio sudo[5259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:54:13 volumio sudo[5262]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 13 10:54:13 volumio sudo[5263]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 13 10:54:13 volumio sudo[5263]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:54:13 volumio sudo[5262]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:54:13 volumio volumio[5084]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 13 10:54:13 volumio volumio[5084]: info: ___________ START PLUGINS ___________ Feb 13 10:54:13 volumio sudo[5262]: pam_unix(sudo:session): session closed for user root Feb 13 10:54:13 volumio volumio[5084]: info: ControllerMpd::onStart: Initializing MPD Feb 13 10:54:13 volumio volumio[5084]: info: Creating MPD Configuration file Feb 13 10:54:13 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 13 10:54:13 volumio sudo[5259]: pam_unix(sudo:session): session closed for user root Feb 13 10:54:13 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 10:54:13 volumio volumio[5084]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 10:54:13 volumio volumio[5084]: info: [1770954853816] CoreMusicLibrary::Adding element Media Servers Feb 13 10:54:13 volumio sudo[5272]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 13 10:54:13 volumio sudo[5274]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 13 10:54:13 volumio sudo[5274]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:54:13 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:54:13 volumio sudo[5272]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:54:13 volumio sudo[5272]: pam_unix(sudo:session): session closed for user root Feb 13 10:54:13 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 13 10:54:13 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 13 10:54:13 volumio systemd[1]: mpd.service: Consumed 7.121s CPU time. Feb 13 10:54:13 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 13 10:54:13 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 13 10:54:13 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 13 10:54:13 volumio volumio[5084]: info: UPNP Browser: Client initialized successfully Feb 13 10:54:13 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:13 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:54:13 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 13 10:54:14 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 13 10:54:14 volumio volumio[5084]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 13 10:54:14 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:14 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:54:14 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 13 10:54:14 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 13 10:54:14 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 13 10:54:14 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 13 10:54:14 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 13 10:54:14 volumio volumio[5084]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 13 10:54:14 volumio volumio[5084]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 10:54:14 volumio volumio[5084]: info: [1770954854180] CoreMusicLibrary::Adding element Last_100 Feb 13 10:54:14 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:54:14 volumio volumio[5084]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 10:54:14 volumio volumio[5084]: info: [1770954854189] CoreMusicLibrary::Adding element Webradio Feb 13 10:54:14 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:54:14 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 10:54:14 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 13 10:54:14 volumio volumio[5084]: info: Initializing BBC Radios Feb 13 10:54:14 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 13 10:54:14 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 10:54:14 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:54:14 volumio volumio[5084]: info: Creating Spotify config file Feb 13 10:54:14 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:14 volumio sudo[5288]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 13 10:54:14 volumio sudo[5288]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 13 10:54:14 volumio sudo[5288]: pam_unix(sudo:session): session closed for user root Feb 13 10:54:15 volumio volumio[5208]: Starting albumart workers Feb 13 10:54:15 volumio volumio[5084]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 10:54:15 volumio volumio[5084]: info: [1770954855748] CoreMusicLibrary::Adding element YouTube Music Feb 13 10:54:15 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:54:15 volumio volumio[5084]: Cannot find translation for source YouTube Music Feb 13 10:54:15 volumio volumio[5084]: info: Volumio Calling Home Feb 13 10:54:15 volumio volumio[5207]: Starting albumart workers Feb 13 10:54:15 volumio sudo[5305]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 13 10:54:15 volumio sudo[5305]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:54:15 volumio volumio[5210]: Starting albumart workers Feb 13 10:54:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 13 10:54:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:54:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:54:16 volumio go-librespot[5310]: go-librespot daemon starting... Feb 13 10:54:16 volumio sudo[5305]: pam_unix(sudo:session): session closed for user root Feb 13 10:54:16 volumio go-librespot[5311]: time="2026-02-13T10:54:16+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:54:16 volumio go-librespot[5311]: time="2026-02-13T10:54:16+07:00" level=info msg="zeroconf server listening on port 34401" Feb 13 10:54:17 volumio volumio[5084]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 13 10:54:17 volumio volumio[5084]: info: Discovery: Found device Volumio Feb 13 10:54:17 volumio volumio[5084]: info: CoreCommandRouter::volumioGetState Feb 13 10:54:17 volumio volumio[5084]: info: CorePlayQueue::getTrack 0 Feb 13 10:54:17 volumio volumio[5084]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 13 10:54:17 volumio volumio[5084]: info: Discovery: Found device Volumio Feb 13 10:54:17 volumio volumio[5084]: info: CoreCommandRouter::volumioGetState Feb 13 10:54:17 volumio volumio[5084]: info: CorePlayQueue::getTrack 0 Feb 13 10:54:17 volumio volumio[5084]: info: MPD Permissions set Feb 13 10:54:17 volumio volumio[5084]: info: MPD Permissions set Feb 13 10:54:17 volumio volumio[5084]: info: Upmpdcli Daemon Started Feb 13 10:54:17 volumio volumio[5084]: info: Volumio called home Feb 13 10:54:17 volumio volumio[5084]: info: Spotify config file written Feb 13 10:54:17 volumio volumio[5084]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 13 10:54:17 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:17 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:17 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:17 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:17 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:17 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:17 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:17 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:17 volumio sudo[5319]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 13 10:54:17 volumio sudo[5319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:54:17 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:54:17 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:17 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:17 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:17 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:17 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:17 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:17 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:17 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:17 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:17 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 13 10:54:17 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:54:17 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:54:17 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 13 10:54:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:54:17 volumio volumio[5084]: info: No need to fix Spotify hosts Feb 13 10:54:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:54:17 volumio go-librespot[5331]: go-librespot daemon starting... Feb 13 10:54:17 volumio sudo[5319]: pam_unix(sudo:session): session closed for user root Feb 13 10:54:17 volumio go-librespot[5338]: time="2026-02-13T10:54:17+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:54:17 volumio go-librespot[5338]: time="2026-02-13T10:54:17+07:00" level=debug msg="app state loaded" Feb 13 10:54:17 volumio go-librespot[5338]: time="2026-02-13T10:54:17+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:54:18 volumio go-librespot[5338]: time="2026-02-13T10:54:18+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:54:18 volumio go-librespot[5338]: time="2026-02-13T10:54:18+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:54:18 volumio go-librespot[5338]: time="2026-02-13T10:54:18+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:54:18 volumio go-librespot[5338]: time="2026-02-13T10:54:18+07:00" level=info msg="zeroconf server listening on port 46415" Feb 13 10:54:18 volumio volumio[5084]: info: Starting Shairport Sync Feb 13 10:54:18 volumio volumio[5084]: info: Starting Shairport Sync Feb 13 10:54:18 volumio volumio[5084]: info: Starting Shairport Sync Feb 13 10:54:18 volumio sudo[5359]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 10:54:18 volumio sudo[5359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:54:18 volumio sudo[5361]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 10:54:18 volumio sudo[5361]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:54:18 volumio sudo[5363]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 10:54:18 volumio sudo[5363]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:54:18 volumio go-librespot[5338]: time="2026-02-13T10:54:18+07:00" level=debug msg="obtained new client token: AAAvcskSTey6fDav1aL//FV21AKshXr7Y5muyuRR0RtkTFHsI0IVSJ7uJsv1thOtUL60xvvrFUJGUD9H4JYHf74uqJ91hBVrxXOBYGamMUHG9WqlculwxZBVrelrFXW4t4y25t6v1EpGKkHK5Q346h5Ab97K4NMe5aR5D6GG8glNHQG36D5nIQHshNSIDoIZnSmjtEvkNKSynlZ+FSNagc1uaRf2oGOgF0QO4e4Vsrerl8sTdJV1xRgFKw==" Feb 13 10:54:18 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 13 10:54:18 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 13 10:54:18 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 13 10:54:18 volumio systemd[1]: shairport-sync.service: Consumed 2.331s CPU time. Feb 13 10:54:19 volumio go-librespot[5338]: time="2026-02-13T10:54:19+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:54:19 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 13 10:54:19 volumio sudo[5359]: pam_unix(sudo:session): session closed for user root Feb 13 10:54:19 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 13 10:54:19 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 13 10:54:19 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 13 10:54:19 volumio go-librespot[5338]: time="2026-02-13T10:54:19+07:00" level=debug msg="completed keyexchange" Feb 13 10:54:19 volumio go-librespot[5338]: time="2026-02-13T10:54:19+07:00" level=debug msg="completed challenge" Feb 13 10:54:19 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 13 10:54:19 volumio sudo[5363]: pam_unix(sudo:session): session closed for user root Feb 13 10:54:19 volumio volumio[5084]: info: Shairport-Sync Started Feb 13 10:54:19 volumio volumio[5084]: Error adding Membership: Error: addMembership EINVAL Feb 13 10:54:19 volumio sudo[5361]: pam_unix(sudo:session): session closed for user root Feb 13 10:54:19 volumio go-librespot[5338]: time="2026-02-13T10:54: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: BadCredentials " Feb 13 10:54:19 volumio volumio[5084]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 13 10:54:19 volumio volumio[5084]: SPOTIFY: BQA-6gHPUzSpjR4d1tIBnA3KIquUOZJB8R9m7sRcOB6-O0cGCQjmNe8U5Lt89bZ_dtVMUvaORVSmD85D6Les0iXMOJ9xqqegoJLftS6NMUdicTaHLutoNnZhxU3rnlpr4uGGYr4qUQMQKiNKro8ImnGJVsxfnCJGuhMbU0hI-2Mm2MgD8-RO_t01AWPlGFOUeghzK_2Kwems4CvpqE0Cl44z0wfj2rojn-d4-5y14IdOyC_wc7W1mcsNFs4COZRqGLyr9DR4aN6q9FPC4ewZ9lf0H9sJpFw1es-1O6oe4gxI_J-wq6QsQX3F Feb 13 10:54:19 volumio volumio[5084]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 13 10:54:19 volumio volumio[5084]: info: New Spotify access token = BQA-6gHPUzSpjR4d1tIBnA3KIquUOZJB8R9m7sRcOB6-O0cGCQjmNe8U5Lt89bZ_dtVMUvaORVSmD85D6Les0iXMOJ9xqqegoJLftS6NMUdicTaHLutoNnZhxU3rnlpr4uGGYr4qUQMQKiNKro8ImnGJVsxfnCJGuhMbU0hI-2Mm2MgD8-RO_t01AWPlGFOUeghzK_2Kwems4CvpqE0Cl44z0wfj2rojn-d4-5y14IdOyC_wc7W1mcsNFs4COZRqGLyr9DR4aN6q9FPC4ewZ9lf0H9sJpFw1es-1O6oe4gxI_J-wq6QsQX3F Feb 13 10:54:19 volumio volumio[5084]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 13 10:54:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:54:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:54:19 volumio volumio[5084]: info: CoreCommandRouter::volumioGetState Feb 13 10:54:19 volumio volumio[5084]: info: CorePlayQueue::getTrack 0 Feb 13 10:54:19 volumio volumio[5084]: info: Shairport-Sync Started Feb 13 10:54:19 volumio volumio[5084]: info: Shairport-Sync Started Feb 13 10:54:19 volumio volumio[5084]: SPOTIFY: User informations: {"country":"PK","display_name":"215mmgea6kss3jxeqsqdquvji","email":"linh.ngotran@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/215mmgea6kss3jxeqsqdquvji"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/215mmgea6kss3jxeqsqdquvji","id":"215mmgea6kss3jxeqsqdquvji","images":[],"product":"premium","type":"user","uri":"spotify:user:215mmgea6kss3jxeqsqdquvji"} Feb 13 10:54:19 volumio volumio[5084]: info: Spotify Successfully logged in Feb 13 10:54:19 volumio volumio[5084]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 10:54:19 volumio volumio[5084]: info: [1770954859828] CoreMusicLibrary::Adding element Spotify Feb 13 10:54:19 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:54:19 volumio volumio[5084]: Cannot find translation for source YouTube Music Feb 13 10:54:19 volumio volumio[5084]: Cannot find translation for source Spotify Feb 13 10:54:20 volumio volumio[5084]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 13 10:54:20 volumio volumio[5084]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 10:54:20 volumio volumio[5084]: info: VolumeController:: Volume=100 Mute =false Feb 13 10:54:20 volumio volumio[5084]: info: CoreCommandRouter::volumioGetState Feb 13 10:54:20 volumio volumio[5084]: info: CorePlayQueue::getTrack 0 Feb 13 10:54:20 volumio volumio[5084]: info: CoreStateMachine::pushState Feb 13 10:54:20 volumio volumio[5084]: info: CorePlayQueue::getTrack 0 Feb 13 10:54:20 volumio volumio[5084]: info: CoreCommandRouter::volumioPushState Feb 13 10:54:21 volumio volumio[5084]: info: go-librespot daemon successfully initialized Feb 13 10:54:21 volumio mpd[5303]: 2026-02-13T10:54:21 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 13 10:54:21 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 13 10:54:21 volumio sudo[5263]: pam_unix(sudo:session): session closed for user root Feb 13 10:54:21 volumio sudo[5274]: pam_unix(sudo:session): session closed for user root Feb 13 10:54:21 volumio volumio[5084]: error: MPD error: The expression evaluated to a falsy value: Feb 13 10:54:21 volumio volumio[5084]: assert.ok(self.idling) Feb 13 10:54:21 volumio volumio[5084]: error: The expression evaluated to a falsy value: Feb 13 10:54:21 volumio volumio[5084]: assert.ok(self.idling) Feb 13 10:54:21 volumio volumio[5084]: error: updateQueue error: null Feb 13 10:54:21 volumio volumio[5084]: info: MPD running with PID5303 Feb 13 10:54:21 volumio volumio[5084]: ,establishing connection Feb 13 10:54:22 volumio volumio[5084]: info: Completed starting Core Plugins Feb 13 10:54:22 volumio volumio[5084]: info: ------------------------------------------- Feb 13 10:54:22 volumio volumio[5084]: info: ----- MyVolumio plugins startup ---- Feb 13 10:54:22 volumio volumio[5084]: info: ------------------------------------------- Feb 13 10:54:22 volumio volumio[5084]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 13 10:54:22 volumio volumio[5084]: error: updateQueue error: null Feb 13 10:54:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 13 10:54:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:54:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:54:22 volumio go-librespot[5404]: go-librespot daemon starting... Feb 13 10:54:22 volumio go-librespot[5405]: time="2026-02-13T10:54:22+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:54:22 volumio go-librespot[5405]: time="2026-02-13T10:54:22+07:00" level=debug msg="app state loaded" Feb 13 10:54:22 volumio go-librespot[5405]: time="2026-02-13T10:54:22+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:54:22 volumio go-librespot[5405]: time="2026-02-13T10:54:22+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 13 10:54:22 volumio go-librespot[5405]: time="2026-02-13T10:54:22+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 13 10:54:22 volumio go-librespot[5405]: time="2026-02-13T10:54:22+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 13 10:54:22 volumio go-librespot[5405]: time="2026-02-13T10:54:22+07:00" level=info msg="zeroconf server listening on port 42947" Feb 13 10:54:23 volumio go-librespot[5405]: time="2026-02-13T10:54:23+07:00" level=debug msg="obtained new client token: AABRlZ15DnXiGOePY5Q6VBSYJcrm1NZFgJpLhpYvZ3nADcymbnnDdvpBaO2Nni8pay5AoaLiS4DfnEEyZU0PHXUh4nVRvTnf0a/Mod9Gc40PAJGau+3SPTs96rDBCF5OOyahOfNk9Nq9M+NimfhqiBaJcBtL8XRMMRkApk/b4GlidQo2RKAK/kzxS8KKuO+wrZSsD303NcCM3WPyr34DWvZ1QQLAsI6Y6ehixbybTw1C74FYKZYD5B0=" Feb 13 10:54:23 volumio go-librespot[5405]: time="2026-02-13T10:54:23+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:54:23 volumio go-librespot[5405]: time="2026-02-13T10:54:23+07:00" level=debug msg="completed keyexchange" Feb 13 10:54:23 volumio go-librespot[5405]: time="2026-02-13T10:54:23+07:00" level=debug msg="completed challenge" Feb 13 10:54:23 volumio go-librespot[5405]: time="2026-02-13T10:54:23+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:54:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:54:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:54:24 volumio volumio[5084]: info: Initializing connection to go-librespot Websocket Feb 13 10:54:24 volumio volumio[5084]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 10:54:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 13 10:54:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:54:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:54:26 volumio go-librespot[5412]: go-librespot daemon starting... Feb 13 10:54:26 volumio go-librespot[5413]: time="2026-02-13T10:54:26+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:54:26 volumio go-librespot[5413]: time="2026-02-13T10:54:26+07:00" level=debug msg="app state loaded" Feb 13 10:54:26 volumio go-librespot[5413]: time="2026-02-13T10:54:26+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:54:26 volumio volumio[5084]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 13 10:54:27 volumio go-librespot[5413]: time="2026-02-13T10:54:27+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:54:27 volumio go-librespot[5413]: time="2026-02-13T10:54:27+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:54:27 volumio go-librespot[5413]: time="2026-02-13T10:54:27+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:54:27 volumio go-librespot[5413]: time="2026-02-13T10:54:27+07:00" level=info msg="zeroconf server listening on port 35853" Feb 13 10:54:27 volumio go-librespot[5413]: time="2026-02-13T10:54:27+07:00" level=debug msg="obtained new client token: AADO+f/rgsa//kjtskFlAD90kSwuqFn6qUSSB5iFBm+zsmePa9C6GoDzn+LLKj+IUII1ecAuCrmSUkR1xR/kMV7yDMQAVr8sH6kL/0Yn6s9FsZRBgbjgt3ErXMV2myekaq39SPUMqZf2+5WaUi93Fj9gCsJ/qz8hcZTo++Wd+1FmBpfWOl29MA567LJKfZnJtqpozdjMxse9eWqw9PbsceQgq28awkGbQK6F9WqdnduTGcaTTV3i8fWigg==" Feb 13 10:54:27 volumio go-librespot[5413]: time="2026-02-13T10:54:27+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:54:27 volumio go-librespot[5413]: time="2026-02-13T10:54:27+07:00" level=debug msg="completed keyexchange" Feb 13 10:54:27 volumio go-librespot[5413]: time="2026-02-13T10:54:27+07:00" level=debug msg="completed challenge" Feb 13 10:54:27 volumio go-librespot[5413]: time="2026-02-13T10:54: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: BadCredentials " Feb 13 10:54:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:54:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:54:27 volumio volumio[5084]: info: Initializing connection to go-librespot Websocket Feb 13 10:54:27 volumio volumio[5084]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 13 10:54:30 volumio volumio[5084]: info: Adding plugin bluetooth to MyMusic Plugins Feb 13 10:54:30 volumio volumio[5084]: info: Adding plugin multiroom to MyMusic Plugins Feb 13 10:54:30 volumio volumio[5084]: info: Adding plugin metavolumio to MyMusic Plugins Feb 13 10:54:30 volumio volumio[5084]: info: Adding plugin cd_controller to MyMusic Plugins Feb 13 10:54:30 volumio volumio[5084]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 13 10:54:30 volumio volumio[5084]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 13 10:54:30 volumio volumio[5084]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 13 10:54:30 volumio volumio[5084]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 13 10:54:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 13 10:54:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:54:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:54:30 volumio go-librespot[5420]: go-librespot daemon starting... Feb 13 10:54:30 volumio go-librespot[5421]: time="2026-02-13T10:54:30+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:54:30 volumio go-librespot[5421]: time="2026-02-13T10:54:30+07:00" level=debug msg="app state loaded" Feb 13 10:54:30 volumio go-librespot[5421]: time="2026-02-13T10:54:30+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:54:31 volumio go-librespot[5421]: time="2026-02-13T10:54:31+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 13 10:54:31 volumio go-librespot[5421]: time="2026-02-13T10:54:31+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 13 10:54:31 volumio go-librespot[5421]: time="2026-02-13T10:54:31+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 13 10:54:31 volumio go-librespot[5421]: time="2026-02-13T10:54:31+07:00" level=info msg="zeroconf server listening on port 45007" Feb 13 10:54:31 volumio go-librespot[5421]: time="2026-02-13T10:54:31+07:00" level=debug msg="obtained new client token: AABq63NdBqmJUHzAKtThJDHa9A+xjHt42d1GoKJg3urIAhK03NeDVCwW4lU2oSppoRBAiAZKXBOUjnY4CyRkujFtPqZUWXAl0ZEh3q2xOthvTY351FydW//9mJjvXwYqtXCIrddUDWtguBQXLC7ePytJCa4x/CmYMtNp7BYu/A63oWeuGISypIm1AUNzXv4miFH+Rgh83A4zQctNSRqH/vLxGD7fX6RGrzklschxRA6wV/DvWx+9LUByzw==" Feb 13 10:54:31 volumio go-librespot[5421]: time="2026-02-13T10:54:31+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:54:31 volumio go-librespot[5421]: time="2026-02-13T10:54:31+07:00" level=debug msg="completed keyexchange" Feb 13 10:54:31 volumio go-librespot[5421]: time="2026-02-13T10:54:31+07:00" level=debug msg="completed challenge" Feb 13 10:54:32 volumio go-librespot[5421]: time="2026-02-13T10:54: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: BadCredentials " Feb 13 10:54:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:54:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:54:32 volumio volumio[5084]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 13 10:54:32 volumio volumio[5084]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 13 10:54:32 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:54:32 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:54:32 volumio volumio[5084]: info: Starting MyVolumio Remote Streaming Endpoints Feb 13 10:54:32 volumio volumio[5084]: info: MyVolumio login type: Token Feb 13 10:54:32 volumio volumio[5084]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 13 10:54:32 volumio volumio[5084]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 13 10:54:34 volumio volumio[5084]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 13 10:54:34 volumio volumio[5084]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 13 10:54:34 volumio volumio[5084]: info: Streaming services startup Feb 13 10:54:34 volumio volumio[5084]: info: Starting Streaming Daemon Feb 13 10:54:34 volumio sudo[5445]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 13 10:54:34 volumio sudo[5445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:54:34 volumio volumio[5084]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 13 10:54:34 volumio sudo[5445]: pam_unix(sudo:session): session closed for user root Feb 13 10:54:34 volumio volumio[5084]: info: Initializing connection to go-librespot Websocket Feb 13 10:54:34 volumio volumio[5084]: error: Cannot start Volumio Streaming Daemon Feb 13 10:54:34 volumio volumio[5084]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 13 10:54:34 volumio volumio[5084]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 13 10:54:34 volumio volumio[5084]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 10:54:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 13 10:54:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:54:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:54:35 volumio go-librespot[5452]: go-librespot daemon starting... Feb 13 10:54:35 volumio go-librespot[5453]: time="2026-02-13T10:54:35+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:54:35 volumio go-librespot[5453]: time="2026-02-13T10:54:35+07:00" level=debug msg="app state loaded" Feb 13 10:54:35 volumio go-librespot[5453]: time="2026-02-13T10:54:35+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:54:35 volumio volumio[5084]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 13 10:54:35 volumio go-librespot[5453]: time="2026-02-13T10:54:35+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:54:35 volumio go-librespot[5453]: time="2026-02-13T10:54:35+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:54:35 volumio go-librespot[5453]: time="2026-02-13T10:54:35+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:54:35 volumio go-librespot[5453]: time="2026-02-13T10:54:35+07:00" level=info msg="zeroconf server listening on port 42581" Feb 13 10:54:36 volumio go-librespot[5453]: time="2026-02-13T10:54:36+07:00" level=debug msg="obtained new client token: AADOfGtETXTC6B59pkOy3RSonuAi9PskxUpj7PBeK3XjP+7qCj1x0SNcq/YABm4WXf8hosG2Ycl5OTe2eQxWFwziZkx6+ILtCnBlDRvamoWWkqajkQFdK4eMdcbte73Xatb6//NHDK066CqUbYUOndpqwSN/sJjy+9QxqsjqBJVwRmWcFwkueGl1SF87O7ycdzdRTvyV1iF6KXm6tV3J2F/Lqr9iBHhnQ0rWxHaqiFzVXV8M5VuJoJY=" Feb 13 10:54:36 volumio go-librespot[5453]: time="2026-02-13T10:54:36+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:54:36 volumio volumio[5084]: info: MyVolumio token set successfully Feb 13 10:54:36 volumio go-librespot[5453]: time="2026-02-13T10:54:36+07:00" level=debug msg="completed keyexchange" Feb 13 10:54:36 volumio volumio[5084]: info: MYVOLUMIO: Adding device Feb 13 10:54:36 volumio volumio[5084]: info: MYVOLUMIO: Evaluating Server Feb 13 10:54:36 volumio go-librespot[5453]: time="2026-02-13T10:54:36+07:00" level=debug msg="completed challenge" Feb 13 10:54:36 volumio go-librespot[5453]: time="2026-02-13T10:54:36+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:54:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:54:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:54:37 volumio volumio[5084]: info: MyVolumio status changed Feb 13 10:54:37 volumio volumio[5084]: info: Streaming services startup Feb 13 10:54:37 volumio volumio[5084]: info: Starting Streaming Daemon Feb 13 10:54:37 volumio volumio[5084]: info: Removing browser output: myVolumio user plan is not superstar Feb 13 10:54:37 volumio volumio[5084]: info: Removing audio output: Feb 13 10:54:37 volumio volumio[5084]: info: Stoppping Tunnel 1 Feb 13 10:54:37 volumio sudo[5481]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 13 10:54:37 volumio sudo[5481]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:54:37 volumio sudo[5481]: pam_unix(sudo:session): session closed for user root Feb 13 10:54:37 volumio sudo[5485]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 13 10:54:37 volumio sudo[5485]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:54:37 volumio volumio[5084]: error: Cannot start Volumio Streaming Daemon Feb 13 10:54:37 volumio volumio[5084]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 13 10:54:37 volumio volumio[5084]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 13 10:54:37 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 13 10:54:37 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 13 10:54:37 volumio volumio[5084]: info: Setting Geolocation for MyVolumio to as1 Feb 13 10:54:37 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:54:37 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:54:37 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:54:37 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 13 10:54:37 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 13 10:54:37 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 13 10:54:37 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 13 10:54:37 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 13 10:54:37 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 13 10:54:37 volumio sudo[5485]: pam_unix(sudo:session): session closed for user root Feb 13 10:54:37 volumio volumio[5084]: info: Remote SSH Stopped Feb 13 10:54:37 volumio volumio[5084]: info: Initializing connection to go-librespot Websocket Feb 13 10:54:37 volumio volumio[5084]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 10:54:38 volumio volumio[5084]: info: Updating MyVolumio device info Feb 13 10:54:38 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:54:38 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:54:38 volumio volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:54:38 volumio volumio[5084]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 13 10:54:39 volumio volumio[5084]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 13 10:54:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 13 10:54:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:54:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:54:39 volumio go-librespot[5487]: go-librespot daemon starting... Feb 13 10:54:39 volumio go-librespot[5488]: time="2026-02-13T10:54:39+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:54:39 volumio go-librespot[5488]: time="2026-02-13T10:54:39+07:00" level=debug msg="app state loaded" Feb 13 10:54:39 volumio go-librespot[5488]: time="2026-02-13T10:54:39+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:54:40 volumio go-librespot[5488]: time="2026-02-13T10:54:40+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:54:40 volumio go-librespot[5488]: time="2026-02-13T10:54:40+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:54:40 volumio go-librespot[5488]: time="2026-02-13T10:54:40+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:54:40 volumio go-librespot[5488]: time="2026-02-13T10:54:40+07:00" level=info msg="zeroconf server listening on port 38135" Feb 13 10:54:40 volumio go-librespot[5488]: time="2026-02-13T10:54:40+07:00" level=debug msg="obtained new client token: AAAT2G7B/NgRcxI/PTtb3FBGxFe4thbg0mHO7xXq4IzJFTAICf+zRyRlH3lhTKLh09vmoorF2ZvdM1VZd7lOvXrfwUQt5RZ+oxq02N+2DLMrb/f9vn+pKRIqmEFqk68MSHzhxblcM4Uz2PHsbZiOYrY3/1sOdk59GIDxL+fnJVIXiU7pfq74WlDVo+ajOKQn2VaQuWb3o6hWiExqkDobxJDtCiIL93+QHoZU+hjRvpjxj2OXRZMRT5d/rA==" Feb 13 10:54:40 volumio volumio[5084]: info: Initializing connection to go-librespot Websocket Feb 13 10:54:40 volumio go-librespot[5488]: time="2026-02-13T10:54:40+07:00" level=debug msg="new websocket client" Feb 13 10:54:40 volumio volumio[5084]: info: Connection to go-librespot Websocket established Feb 13 10:54:40 volumio go-librespot[5488]: time="2026-02-13T10:54:40+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:54:41 volumio go-librespot[5488]: time="2026-02-13T10:54:41+07:00" level=debug msg="completed keyexchange" Feb 13 10:54:41 volumio go-librespot[5488]: time="2026-02-13T10:54:41+07:00" level=debug msg="completed challenge" Feb 13 10:54:41 volumio go-librespot[5488]: time="2026-02-13T10:54:41+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:54:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:54:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:54:41 volumio volumio[5084]: info: Connection to go-librespot Websocket closed Feb 13 10:54:43 volumio volumio[5084]: info: MYVOLUMIO: Adding device Feb 13 10:54:43 volumio volumio[5084]: info: MYVOLUMIO: Evaluating Server Feb 13 10:54:44 volumio volumio[5084]: info: Getting Spotify volume Feb 13 10:54:44 volumio volumio[5084]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 13 10:54:44 volumio volumio[5084]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 10:54:44 volumio volumio[5084]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 13 10:54:44 volumio volumio[5084]: errno: -111, Feb 13 10:54:44 volumio volumio[5084]: code: 'ECONNREFUSED', Feb 13 10:54:44 volumio volumio[5084]: syscall: 'connect', Feb 13 10:54:44 volumio volumio[5084]: address: '127.0.0.1', Feb 13 10:54:44 volumio volumio[5084]: port: 9879, Feb 13 10:54:44 volumio volumio[5084]: response: undefined Feb 13 10:54:44 volumio volumio[5084]: } Feb 13 10:54:44 volumio volumio[5084]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 13 10:54:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 13 10:54:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:54:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:54:45 volumio go-librespot[5540]: go-librespot daemon starting... Feb 13 10:54:45 volumio go-librespot[5541]: time="2026-02-13T10:54:45+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:54:45 volumio go-librespot[5541]: time="2026-02-13T10:54:45+07:00" level=debug msg="app state loaded" Feb 13 10:54:45 volumio go-librespot[5541]: time="2026-02-13T10:54:45+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:54:45 volumio sudo[5549]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-13 10:53' Feb 13 10:54:45 volumio sudo[5549]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:54:45 volumio go-librespot[5541]: time="2026-02-13T10:54:45+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:54:45 volumio go-librespot[5541]: time="2026-02-13T10:54:45+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:54:45 volumio go-librespot[5541]: time="2026-02-13T10:54:45+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:54:45 volumio go-librespot[5541]: time="2026-02-13T10:54:45+07:00" level=info msg="zeroconf server listening on port 32931" 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"