Feb 17 10:54:01 volumio volumio[22792]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 17 10:54:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 17 10:54:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:02 volumio go-librespot[23118]: go-librespot daemon starting... Feb 17 10:54:02 volumio go-librespot[23119]: time="2026-02-17T10:54:02+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:54:02 volumio go-librespot[23119]: time="2026-02-17T10:54:02+07:00" level=debug msg="app state loaded" Feb 17 10:54:02 volumio go-librespot[23119]: time="2026-02-17T10:54:02+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:54:02 volumio volumio[22792]: info: Initializing connection to go-librespot Websocket Feb 17 10:54:02 volumio go-librespot[23119]: time="2026-02-17T10:54:02+07:00" level=debug msg="new websocket client" Feb 17 10:54:02 volumio volumio[22792]: info: Connection to go-librespot Websocket established Feb 17 10:54:03 volumio go-librespot[23119]: time="2026-02-17T10: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 17 10:54:03 volumio go-librespot[23119]: time="2026-02-17T10: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 17 10:54:03 volumio go-librespot[23119]: time="2026-02-17T10: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 17 10:54:03 volumio go-librespot[23119]: time="2026-02-17T10:54:03+07:00" level=info msg="zeroconf server listening on port 44597" Feb 17 10:54:03 volumio go-librespot[23119]: time="2026-02-17T10:54:03+07:00" level=debug msg="obtained new client token: AAC4/ChzdYkrJmjSi+YEA5VHahZgB6xKOo28uqjtBfFQO6dFG2luVSfwBczy8j1VOTPB/qtPDr0U5OszSrOYYmF49CJ9fxEb4fA7O2B6gH9CyCako+O3FXPzM5tAgH6HYUtohIEeRCIF48CwRJ5davj5MKth7WaUtqjIkUcUVjbmXfd+wRLYiTzSYJXpx+GLrhdUyMCZjtbLvB1ev9Lj8Iz3iR9WaPR03olJxMMXj0bFOKuCL/2YM9msLQ==" Feb 17 10:54:03 volumio go-librespot[23119]: time="2026-02-17T10:54:03+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:54:03 volumio go-librespot[23119]: time="2026-02-17T10:54:03+07:00" level=debug msg="completed keyexchange" Feb 17 10:54:03 volumio go-librespot[23119]: time="2026-02-17T10:54:03+07:00" level=debug msg="completed challenge" Feb 17 10:54:03 volumio go-librespot[23119]: time="2026-02-17T10: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 17 10:54:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:54:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:54:03 volumio volumio[22792]: info: Connection to go-librespot Websocket closed Feb 17 10:54:05 volumio volumio[22792]: info: Getting Spotify volume Feb 17 10:54:05 volumio volumio[22792]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 10:54:05 volumio volumio[22792]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 10:54:05 volumio volumio[22792]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 17 10:54:05 volumio volumio[22792]: errno: -111, Feb 17 10:54:05 volumio volumio[22792]: code: 'ECONNREFUSED', Feb 17 10:54:05 volumio volumio[22792]: syscall: 'connect', Feb 17 10:54:05 volumio volumio[22792]: address: '127.0.0.1', Feb 17 10:54:05 volumio volumio[22792]: port: 9879, Feb 17 10:54:05 volumio volumio[22792]: response: undefined Feb 17 10:54:05 volumio volumio[22792]: } Feb 17 10:54:05 volumio volumio[22792]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 10:54:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 17 10:54:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:06 volumio go-librespot[23153]: go-librespot daemon starting... Feb 17 10:54:06 volumio go-librespot[23154]: time="2026-02-17T10:54:06+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:54:06 volumio go-librespot[23154]: time="2026-02-17T10:54:06+07:00" level=debug msg="app state loaded" Feb 17 10:54:06 volumio go-librespot[23154]: time="2026-02-17T10:54:06+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:54:07 volumio sudo[23163]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-17 10:53' Feb 17 10:54:07 volumio sudo[23163]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:54:07 volumio go-librespot[23154]: time="2026-02-17T10: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:54:07 volumio go-librespot[23154]: time="2026-02-17T10:54:07+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 17 10:54:07 volumio go-librespot[23154]: time="2026-02-17T10:54:07+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 17 10:54:07 volumio go-librespot[23154]: time="2026-02-17T10:54:07+07:00" level=info msg="zeroconf server listening on port 36659" Feb 17 10:54:07 volumio sudo[23163]: pam_unix(sudo:session): session closed for user root Feb 17 10:54:07 volumio go-librespot[23154]: time="2026-02-17T10:54:07+07:00" level=debug msg="obtained new client token: AADz4Q7TUx/9jHu8sgS9aaNxwPrtWZLjnrrbaEtpEeO+5JhOBpT4lflLHCZCbITnCwUczHEuiMtagJY79HnLexGWb65m7Q+pVGEGNYOKpqkqCWpA6LQ0+YexXnBH+MJF3dIKpGewdreBdoXGsFPT5W3lKYs0TfR/DAvRNcXVDyT4ndbISXOac2ToIcokOJSmFJoklVKUiXtXe1FvJKzA8GQYpPC9fDQrv789pd6LwmIOtcbPk7BiPC834A==" Feb 17 10:54:07 volumio volumio-remote-updater[25144]: [2026-02-17 10:54:07] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 17 10:54:07 volumio volumio-remote-updater[25144]: [2026-02-17 10:54:07] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 17 10:54:07 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:54:07 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 17 10:54:07 volumio systemd[1]: volumio.service: Consumed 49.306s CPU time. Feb 17 10:54:07 volumio go-librespot[23154]: time="2026-02-17T10:54:07+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 17 10:54:07 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 17 10:54:07 volumio go-librespot[23154]: time="2026-02-17T10:54:07+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 17 10:54:07 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 17 10:54:07 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 20282. Feb 17 10:54:07 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 17 10:54:07 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 17 10:54:07 volumio systemd[1]: volumio.service: Consumed 49.306s CPU time. Feb 17 10:54:07 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 17 10:54:07 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 17 10:54:08 volumio go-librespot[23154]: time="2026-02-17T10:54:08+07:00" level=debug msg="completed keyexchange" Feb 17 10:54:08 volumio go-librespot[23154]: time="2026-02-17T10:54:08+07:00" level=debug msg="completed challenge" Feb 17 10:54:08 volumio go-librespot[23154]: time="2026-02-17T10: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 17 10:54:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:54:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:54:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 17 10:54:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:11 volumio go-librespot[23194]: go-librespot daemon starting... Feb 17 10:54:11 volumio go-librespot[23201]: time="2026-02-17T10:54:11+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:54:11 volumio go-librespot[23201]: time="2026-02-17T10:54:11+07:00" level=debug msg="app state loaded" Feb 17 10:54:11 volumio go-librespot[23201]: time="2026-02-17T10:54:11+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:54:11 volumio volumio[23178]: info: ------------------------------------------- Feb 17 10:54:11 volumio volumio[23178]: info: ----- Volumio3 ---- Feb 17 10:54:11 volumio volumio[23178]: info: ------------------------------------------- Feb 17 10:54:11 volumio volumio[23178]: info: ----- System startup ---- Feb 17 10:54:11 volumio volumio[23178]: info: ------------------------------------------- Feb 17 10:54:11 volumio go-librespot[23201]: time="2026-02-17T10:54:11+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 17 10:54:11 volumio go-librespot[23201]: time="2026-02-17T10:54:11+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 17 10:54:11 volumio go-librespot[23201]: time="2026-02-17T10:54:11+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 17 10:54:11 volumio go-librespot[23201]: time="2026-02-17T10:54:11+07:00" level=info msg="zeroconf server listening on port 41473" Feb 17 10:54:12 volumio go-librespot[23201]: time="2026-02-17T10:54:12+07:00" level=debug msg="obtained new client token: AACeKZXaRKhTXL5d7LtgDccWC+ZncgN+cQGZPcYb02XA2zEjT/cLs00x4dTzJoEDAbRr8Q/jdim5/5gBVv09EvnynWH1psC4JHTTMtCtwYvuPv2dM05RuEOpXDZqvzVKCQY+kXybA8HyKUDagiwuswFYjZkjH0UjbBTlEGv9CvEtMplEPeA2dUHsAojtAc/y9MRJoef3AsDmhAu1FdGc4x4+OU6ROL5f6oGmfmXatDtyw1fhwhrDAT4=" Feb 17 10:54:12 volumio go-librespot[23201]: time="2026-02-17T10:54:12+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:54:12 volumio go-librespot[23201]: time="2026-02-17T10:54:12+07:00" level=debug msg="completed keyexchange" Feb 17 10:54:12 volumio go-librespot[23201]: time="2026-02-17T10:54:12+07:00" level=debug msg="completed challenge" Feb 17 10:54:12 volumio go-librespot[23201]: time="2026-02-17T10: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 17 10:54:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:54:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:54:12 volumio volumio-remote-updater[25144]: [2026-02-17 10:54:12] [connect] Successful connection Feb 17 10:54:13 volumio volumio[23178]: info: MYVOLUMIO Environment detected Feb 17 10:54:13 volumio volumio[23178]: info: Plugin folders cleanup Feb 17 10:54:13 volumio volumio[23178]: info: Scanning into folder /volumio/app/plugins/ Feb 17 10:54:13 volumio volumio[23178]: info: Scanning category audio_interface Feb 17 10:54:13 volumio volumio[23178]: info: Scanning category miscellanea Feb 17 10:54:13 volumio volumio[23178]: info: Scanning category music_service Feb 17 10:54:13 volumio volumio[23178]: info: Scanning category plugins.json Feb 17 10:54:13 volumio volumio[23178]: info: Scanning category system_controller Feb 17 10:54:13 volumio volumio[23178]: info: Scanning category user_interface Feb 17 10:54:13 volumio volumio[23178]: info: Scanning into folder /data/plugins/ Feb 17 10:54:13 volumio volumio[23178]: info: Scanning category music_service Feb 17 10:54:13 volumio volumio[23178]: info: Plugin folders cleanup completed Feb 17 10:54:13 volumio volumio[23178]: info: ------------------------------------------- Feb 17 10:54:13 volumio volumio[23178]: info: ----- Core plugins startup ---- Feb 17 10:54:13 volumio volumio[23178]: info: ------------------------------------------- Feb 17 10:54:13 volumio volumio[23178]: info: Loading plugins from folder /volumio/app/plugins/ Feb 17 10:54:13 volumio volumio[23178]: info: Adding plugin upnp to MyMusic Plugins Feb 17 10:54:13 volumio volumio[23178]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 17 10:54:13 volumio volumio[23178]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 17 10:54:13 volumio volumio[23178]: info: Loading plugins from folder /data/plugins/ Feb 17 10:54:13 volumio volumio[23178]: info: Loading plugin "system"... Feb 17 10:54:13 volumio volumio[23178]: info: Loading plugin "appearance"... Feb 17 10:54:15 volumio volumio[23178]: info: Loading plugin "network"... Feb 17 10:54:15 volumio volumio[23178]: info: Refreshing Cached IP Addresses Feb 17 10:54:15 volumio sudo[23218]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 10:54:15 volumio sudo[23218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:54:15 volumio volumio[23178]: info: Loading plugin "services"... Feb 17 10:54:15 volumio sudo[23218]: pam_unix(sudo:session): session closed for user root Feb 17 10:54:15 volumio sudo[23220]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 10:54:15 volumio sudo[23220]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:54:15 volumio volumio[23178]: info: Loading plugin "alsa_controller"... Feb 17 10:54:15 volumio sudo[23220]: pam_unix(sudo:session): session closed for user root Feb 17 10:54:15 volumio sudo[23228]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 17 10:54:15 volumio sudo[23228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:54:15 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 10:54:15 volumio volumio[23178]: info: Loading plugin "wizard"... Feb 17 10:54:15 volumio volumio[23178]: info: Loading plugin "networkfs"... Feb 17 10:54:15 volumio volumio[23178]: info: Starting Udev Watcher for removable devices Feb 17 10:54:15 volumio volumio[23178]: info: Ignoring mount for partition: boot Feb 17 10:54:15 volumio volumio[23178]: info: Ignoring mount for partition: volumio Feb 17 10:54:15 volumio volumio[23178]: info: Ignoring mount for partition: volumio_data Feb 17 10:54:15 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 10:54:15 volumio volumio[23178]: info: Loading plugin "volumio_command_line_client"... Feb 17 10:54:15 volumio volumio[23178]: info: Loading plugin "upnp"... Feb 17 10:54:15 volumio volumio[23178]: info: [1771300455568] Starting Upmpd Daemon Feb 17 10:54:15 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 10:54:15 volumio volumio[23178]: info: Loading plugin "my_music"... Feb 17 10:54:15 volumio volumio[23178]: info: Loading plugin "mpd"... Feb 17 10:54:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 17 10:54:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:15 volumio go-librespot[23251]: go-librespot daemon starting... Feb 17 10:54:15 volumio go-librespot[23252]: time="2026-02-17T10:54:15+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:54:15 volumio go-librespot[23252]: time="2026-02-17T10:54:15+07:00" level=debug msg="app state loaded" Feb 17 10:54:15 volumio go-librespot[23252]: time="2026-02-17T10:54:15+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:54:16 volumio volumio[23178]: info: Loading plugin "upnp_browser"... Feb 17 10:54:16 volumio go-librespot[23252]: time="2026-02-17T10:54:16+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 17 10:54:16 volumio go-librespot[23252]: time="2026-02-17T10:54:16+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 17 10:54:16 volumio go-librespot[23252]: time="2026-02-17T10:54:16+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 17 10:54:16 volumio go-librespot[23252]: time="2026-02-17T10:54:16+07:00" level=info msg="zeroconf server listening on port 45145" Feb 17 10:54:16 volumio go-librespot[23252]: time="2026-02-17T10:54:16+07:00" level=debug msg="obtained new client token: AABvkRu8D6dtssEBnGDSZkm+GhN5CN45BI+XyRLU+cOktU/OQ/h/NX7XB7KSczuGl+rZ3536QvV3n3iy/cXVdrKvSoVxZ8vgmERMJ+DMl3O82eRLJFLVSs/zZBS33cAEztzm4CGzS9FguCbqpp+C3Vcwqrslf1CFPmJL/qF3QXEhQAtYFUVGxipcZNJzU8UayFpiIRgCmAtjTzhfiBCjJle0xVGkntDG2FidP4299XzAM58fJ/RYgcYv0A==" Feb 17 10:54:16 volumio go-librespot[23252]: time="2026-02-17T10:54:16+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:54:16 volumio go-librespot[23252]: time="2026-02-17T10:54:16+07:00" level=debug msg="completed keyexchange" Feb 17 10:54:16 volumio go-librespot[23252]: time="2026-02-17T10:54:16+07:00" level=debug msg="completed challenge" Feb 17 10:54:17 volumio go-librespot[23252]: time="2026-02-17T10:54:17+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 17 10:54:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:54:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:54:18 volumio sudo[23228]: pam_unix(sudo:session): session closed for user root Feb 17 10:54:19 volumio volumio[23178]: info: Starting UPNP Browser Feb 17 10:54:19 volumio volumio[23178]: info: Loading plugin "alarm-clock"... Feb 17 10:54:19 volumio volumio[23178]: info: Loading plugin "airplay_emulation"... Feb 17 10:54:19 volumio volumio[23178]: info: Starting Shairport Sync Feb 17 10:54:19 volumio volumio[23178]: info: Loading plugin "last_100"... Feb 17 10:54:19 volumio volumio[23178]: info: Loading plugin "webradio"... Feb 17 10:54:19 volumio volumio[23178]: info: Loading plugin "i2s_dacs"... Feb 17 10:54:19 volumio volumio[23178]: info: Loading plugin "volumiodiscovery"... Feb 17 10:54:19 volumio volumio[23178]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 17 10:54:19 volumio volumio[23178]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 10:54:19 volumio volumio[23178]: *** WARNING *** For more information see Feb 17 10:54:19 volumio volumio[23178]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 17 10:54:19 volumio volumio[23178]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 10:54:19 volumio volumio[23178]: *** WARNING *** For more information see Feb 17 10:54:19 volumio node[23178]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 17 10:54:19 volumio node[23178]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 10:54:19 volumio node[23178]: *** WARNING *** For more information see Feb 17 10:54:19 volumio node[23178]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 17 10:54:19 volumio node[23178]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 10:54:19 volumio node[23178]: *** WARNING *** For more information see Feb 17 10:54:19 volumio volumio[23178]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 17 10:54:19 volumio volumio[23178]: info: Discovery: Started advertising with name: Volumio Feb 17 10:54:19 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 10:54:19 volumio volumio[23178]: info: Loading plugin "spop"... Feb 17 10:54:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 17 10:54:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:20 volumio go-librespot[23261]: go-librespot daemon starting... Feb 17 10:54:20 volumio go-librespot[23262]: time="2026-02-17T10:54:20+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:54:20 volumio go-librespot[23262]: time="2026-02-17T10:54:20+07:00" level=debug msg="app state loaded" Feb 17 10:54:20 volumio go-librespot[23262]: time="2026-02-17T10:54:20+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:54:20 volumio go-librespot[23262]: time="2026-02-17T10:54:20+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 17 10:54:20 volumio go-librespot[23262]: time="2026-02-17T10:54:20+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 17 10:54:20 volumio go-librespot[23262]: time="2026-02-17T10:54:20+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 17 10:54:20 volumio go-librespot[23262]: time="2026-02-17T10:54:20+07:00" level=info msg="zeroconf server listening on port 38843" Feb 17 10:54:21 volumio go-librespot[23262]: time="2026-02-17T10:54:21+07:00" level=debug msg="obtained new client token: AACiaeA49VOpnHYaYJ2CdIthVxX59g37S6+VzgEh2aAKQ1ry8QLBDpLMnzrzLzSaPwqfikFO2FEOg3CT7T++Zqew9u37ODxkWY5YvVF83WflQZk1pZRSksS4E6RFY1joxc6hWwTvieVzM6R5pr/EdxNBED/g9a87V4qvPPLXxeP4DxN17V63mnQgJvviJFICSqrkfw5U0Jo/mZJY00OK96aHYWldskp6CkHAUaClq09cLbd+9jPh4VI=" Feb 17 10:54:21 volumio go-librespot[23262]: time="2026-02-17T10:54:21+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:54:21 volumio go-librespot[23262]: time="2026-02-17T10:54:21+07:00" level=debug msg="completed keyexchange" Feb 17 10:54:21 volumio go-librespot[23262]: time="2026-02-17T10:54:21+07:00" level=debug msg="completed challenge" Feb 17 10:54:21 volumio go-librespot[23262]: time="2026-02-17T10:54:21+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:54:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:54:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:54:21 volumio volumio[23178]: info: Loading plugin "ytcr"... Feb 17 10:54:24 volumio volumio[23178]: info: Loading plugin "ytmusic"... Feb 17 10:54:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 17 10:54:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:24 volumio go-librespot[23272]: go-librespot daemon starting... Feb 17 10:54:24 volumio go-librespot[23273]: time="2026-02-17T10:54:24+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:54:24 volumio go-librespot[23273]: time="2026-02-17T10:54:24+07:00" level=debug msg="app state loaded" Feb 17 10:54:24 volumio go-librespot[23273]: time="2026-02-17T10:54:24+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:54:25 volumio volumio-remote-updater[25144]: [2026-02-17 10:54:25] [connect] Successful connection Feb 17 10:54:25 volumio go-librespot[23273]: time="2026-02-17T10:54:25+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 17 10:54:25 volumio go-librespot[23273]: time="2026-02-17T10:54:25+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 17 10:54:25 volumio go-librespot[23273]: time="2026-02-17T10:54:25+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 17 10:54:25 volumio go-librespot[23273]: time="2026-02-17T10:54:25+07:00" level=info msg="zeroconf server listening on port 35087" Feb 17 10:54:25 volumio volumio[23178]: info: Loading plugin "outputs"... Feb 17 10:54:25 volumio volumio[23178]: info: Loading plugin "albumart"... Feb 17 10:54:25 volumio volumio[23178]: info: Plugin example_plugin is not enabled Feb 17 10:54:25 volumio volumio[23178]: info: Loading plugin "inputs"... Feb 17 10:54:25 volumio volumio[23178]: info: Loading plugin "updater_comm"... Feb 17 10:54:25 volumio go-librespot[23273]: time="2026-02-17T10:54:25+07:00" level=debug msg="obtained new client token: AAAlgCVBECtdvjuuKOP1yve8qx4zfPi6YLa1IbVRdlknIsTkD/zkkONtec4bxpcTTN18EGZdURzaqJ1bsaY4lMbUChP2wSG6aCUUxTvTF7+tPeC12pevI5FOA5YnH80YZLshLB3KMarUst8zT4smgjMg3yPpFv1E4PGihJdA1M/6AcUcFXYnvq38Y1i2Lpu2dk4pxFAOXFhZl76Un0CPenmnvfCFfFnxaHDRqXC4nMQ0QQlrZufj4fys9w==" Feb 17 10:54:25 volumio go-librespot[23273]: time="2026-02-17T10:54:25+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:54:25 volumio volumio[23178]: info: Plugin mpdemulation is not enabled Feb 17 10:54:25 volumio volumio[23178]: info: Loading plugin "rest_api"... Feb 17 10:54:25 volumio go-librespot[23273]: time="2026-02-17T10:54:25+07:00" level=debug msg="completed keyexchange" Feb 17 10:54:25 volumio go-librespot[23273]: time="2026-02-17T10:54:25+07:00" level=debug msg="completed challenge" Feb 17 10:54:25 volumio volumio[23178]: info: Loading plugin "websocket"... Feb 17 10:54:25 volumio volumio[23178]: info: Starting Socket.io Server version 1.7.4 Feb 17 10:54:25 volumio volumio[23178]: info: Loading plugin "RoonBridge"... Feb 17 10:54:25 volumio go-librespot[23273]: time="2026-02-17T10:54:25+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:54:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:54:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:54:26 volumio volumio[23178]: info: Applying required configuration parameters for plugin RoonBridge Feb 17 10:54:26 volumio volumio[23178]: info: Loading i18n strings for locale en Feb 17 10:54:26 volumio volumio[23178]: Updating browse sources language Feb 17 10:54:26 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 10:54:26 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 10:54:26 volumio volumio[23178]: info: CoreCommandRouter::initPlayerControls Feb 17 10:54:26 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:26 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:26 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:26 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:26 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:26 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:26 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:26 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:26 volumio volumio[23294]: Forking 3 albumart workers Feb 17 10:54:26 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 10:54:26 volumio volumio[23178]: Express server listening on port 3000 Feb 17 10:54:26 volumio volumio[23178]: [Metrics] WebUI: 16s 72.14ms Feb 17 10:54:26 volumio volumio[23178]: info: CoreStateMachine::resetVolumioState Feb 17 10:54:26 volumio volumio[23178]: info: CoreStateMachine::getcurrentVolume Feb 17 10:54:26 volumio volumio[23178]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 10:54:26 volumio sudo[23328]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 10:54:26 volumio sudo[23328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:54:26 volumio sudo[23328]: pam_unix(sudo:session): session closed for user root Feb 17 10:54:26 volumio sudo[23338]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 10:54:26 volumio sudo[23338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:54:26 volumio sudo[23338]: pam_unix(sudo:session): session closed for user root Feb 17 10:54:27 volumio volumio[23178]: info: Volumio Network Manager: Network status updated: 1 Feb 17 10:54:27 volumio volumio[23178]: info: VolumeController:: Volume=100 Mute =false Feb 17 10:54:27 volumio volumio[23178]: info: CoreStateMachine::pushState Feb 17 10:54:27 volumio volumio[23178]: info: CorePlayQueue::getTrack 0 Feb 17 10:54:27 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 10:54:27 volumio volumio[23178]: info: CoreCommandRouter::volumioPushState Feb 17 10:54:27 volumio volumio[23178]: info: CoreStateMachine::updateTrackBlock Feb 17 10:54:27 volumio volumio[23178]: info: CorePlayQueue::getTrackBlock Feb 17 10:54:27 volumio volumio[23178]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 10:54:27 volumio volumio-remote-updater[25144]: [2026-02-17 10:54:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771300465 101 Feb 17 10:54:27 volumio volumio[23178]: 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 17 10:54:27 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:54:27 volumio volumio[23178]: info: Reloading queue from file Feb 17 10:54:27 volumio volumio[23178]: info: CoreStateMachine::setRepeat null single undefined Feb 17 10:54:27 volumio volumio[23178]: info: CoreStateMachine::pushState Feb 17 10:54:27 volumio volumio[23178]: info: CorePlayQueue::getTrack 0 Feb 17 10:54:27 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 10:54:27 volumio volumio[23178]: info: CoreCommandRouter::volumioPushState Feb 17 10:54:27 volumio volumio[23178]: info: CoreStateMachine::setRandom null Feb 17 10:54:27 volumio volumio[23178]: info: CoreStateMachine::pushState Feb 17 10:54:27 volumio volumio[23178]: info: CorePlayQueue::getTrack 0 Feb 17 10:54:27 volumio volumio[23178]: info: CoreCommandRouter::volumioPushState Feb 17 10:54:27 volumio volumio[23178]: info: Setting Device type: Raspberry PI Feb 17 10:54:27 volumio volumio[23178]: info: Completed loading Core Plugins Feb 17 10:54:27 volumio volumio[23178]: info: Preparing to generate the ALSA configuration file Feb 17 10:54:27 volumio volumio[23178]: info: VolumeController:: Volume=100 Mute =false Feb 17 10:54:27 volumio volumio[23178]: info: CoreStateMachine::pushState Feb 17 10:54:27 volumio volumio[23178]: info: CorePlayQueue::getTrack 0 Feb 17 10:54:27 volumio volumio[23178]: info: CoreCommandRouter::volumioPushState Feb 17 10:54:27 volumio volumio[23178]: info: Asound.conf file unchanged, so no further update is needed Feb 17 10:54:27 volumio volumio[23178]: info: Output device has changed, restarting MPD Feb 17 10:54:27 volumio volumio[23178]: info: Output device has changed, restarting Shairport Sync Feb 17 10:54:27 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:27 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:54:27 volumio sudo[23355]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 17 10:54:27 volumio sudo[23355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:54:27 volumio sudo[23357]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 10:54:28 volumio sudo[23357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:54:28 volumio sudo[23359]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 10:54:28 volumio sudo[23357]: pam_unix(sudo:session): session closed for user root Feb 17 10:54:28 volumio sudo[23359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:54:28 volumio volumio[23178]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 10:54:28 volumio volumio[23178]: info: ___________ START PLUGINS ___________ Feb 17 10:54:28 volumio volumio[23178]: info: ControllerMpd::onStart: Initializing MPD Feb 17 10:54:28 volumio volumio[23178]: info: Creating MPD Configuration file Feb 17 10:54:28 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 17 10:54:28 volumio sudo[23355]: pam_unix(sudo:session): session closed for user root Feb 17 10:54:28 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 10:54:28 volumio volumio[23178]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 10:54:28 volumio volumio[23178]: info: [1771300468275] CoreMusicLibrary::Adding element Media Servers Feb 17 10:54:28 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 10:54:28 volumio sudo[23368]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 10:54:28 volumio sudo[23368]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:54:28 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 17 10:54:28 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 17 10:54:28 volumio systemd[1]: mpd.service: Consumed 7.175s CPU time. Feb 17 10:54:28 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 17 10:54:28 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 17 10:54:28 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 17 10:54:28 volumio sudo[23368]: pam_unix(sudo:session): session closed for user root Feb 17 10:54:28 volumio sudo[23370]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 10:54:28 volumio sudo[23370]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:54:28 volumio volumio[23178]: info: UPNP Browser: Client initialized successfully Feb 17 10:54:28 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 17 10:54:28 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:28 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:54:28 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 17 10:54:28 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 17 10:54:28 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 17 10:54:28 volumio volumio[23178]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 10:54:28 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:28 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:54:28 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 17 10:54:28 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 17 10:54:28 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 17 10:54:28 volumio volumio[23178]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 10:54:28 volumio volumio[23178]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 10:54:28 volumio volumio[23178]: info: [1771300468630] CoreMusicLibrary::Adding element Last_100 Feb 17 10:54:28 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 10:54:28 volumio volumio[23178]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 10:54:28 volumio volumio[23178]: info: [1771300468640] CoreMusicLibrary::Adding element Webradio Feb 17 10:54:28 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 10:54:28 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 10:54:28 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 17 10:54:28 volumio volumio[23178]: info: Initializing BBC Radios Feb 17 10:54:28 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 17 10:54:28 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 10:54:28 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:54:28 volumio volumio[23178]: info: Creating Spotify config file Feb 17 10:54:28 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 17 10:54:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:29 volumio sudo[23384]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 17 10:54:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:29 volumio sudo[23384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 17 10:54:29 volumio go-librespot[23399]: go-librespot daemon starting... Feb 17 10:54:29 volumio sudo[23384]: pam_unix(sudo:session): session closed for user root Feb 17 10:54:29 volumio go-librespot[23401]: time="2026-02-17T10:54:29+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:54:29 volumio volumio[23304]: Starting albumart workers Feb 17 10:54:30 volumio go-librespot[23401]: time="2026-02-17T10:54:30+07:00" level=info msg="zeroconf server listening on port 42793" Feb 17 10:54:30 volumio volumio[23309]: Starting albumart workers Feb 17 10:54:30 volumio volumio[23178]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 10:54:30 volumio volumio[23178]: info: [1771300470282] CoreMusicLibrary::Adding element YouTube Music Feb 17 10:54:30 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 10:54:30 volumio volumio[23178]: Cannot find translation for source YouTube Music Feb 17 10:54:30 volumio volumio[23306]: Starting albumart workers Feb 17 10:54:30 volumio volumio[23178]: info: Volumio Calling Home Feb 17 10:54:30 volumio sudo[23410]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 17 10:54:30 volumio sudo[23410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:54:30 volumio sudo[23410]: pam_unix(sudo:session): session closed for user root Feb 17 10:54:31 volumio volumio[23178]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 17 10:54:31 volumio volumio[23178]: info: Discovery: Found device Volumio Feb 17 10:54:31 volumio volumio[23178]: info: CoreCommandRouter::volumioGetState Feb 17 10:54:31 volumio volumio[23178]: info: CorePlayQueue::getTrack 0 Feb 17 10:54:31 volumio volumio[23178]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 17 10:54:31 volumio volumio[23178]: info: Discovery: Found device Volumio Feb 17 10:54:31 volumio volumio[23178]: info: CoreCommandRouter::volumioGetState Feb 17 10:54:31 volumio volumio[23178]: info: CorePlayQueue::getTrack 0 Feb 17 10:54:31 volumio volumio[23178]: info: MPD Permissions set Feb 17 10:54:31 volumio volumio[23178]: info: MPD Permissions set Feb 17 10:54:31 volumio volumio[23178]: info: Upmpdcli Daemon Started Feb 17 10:54:31 volumio volumio[23178]: info: Spotify config file written Feb 17 10:54:31 volumio sudo[23416]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 17 10:54:31 volumio sudo[23416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:54:31 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 17 10:54:31 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 17 10:54:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:31 volumio go-librespot[23418]: go-librespot daemon starting... Feb 17 10:54:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:31 volumio sudo[23416]: pam_unix(sudo:session): session closed for user root Feb 17 10:54:31 volumio go-librespot[23424]: time="2026-02-17T10:54:31+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:54:31 volumio go-librespot[23424]: time="2026-02-17T10:54:31+07:00" level=debug msg="app state loaded" Feb 17 10:54:31 volumio go-librespot[23424]: time="2026-02-17T10:54:31+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:54:32 volumio volumio[23178]: 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 17 10:54:32 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:32 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:32 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:32 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:32 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:32 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:32 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:32 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:32 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:54:32 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:32 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:32 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:32 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:32 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:32 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:32 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:32 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:32 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:32 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:54:32 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:54:32 volumio volumio[23178]: info: No need to fix Spotify hosts Feb 17 10:54:32 volumio go-librespot[23424]: time="2026-02-17T10:54: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:54:32 volumio go-librespot[23424]: time="2026-02-17T10:54:32+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 17 10:54:32 volumio go-librespot[23424]: time="2026-02-17T10:54:32+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 17 10:54:32 volumio go-librespot[23424]: time="2026-02-17T10:54:32+07:00" level=info msg="zeroconf server listening on port 34013" Feb 17 10:54:32 volumio go-librespot[23424]: time="2026-02-17T10:54:32+07:00" level=debug msg="obtained new client token: AAASz/CN4scofMJEaP0Wism3TeJcZcO9QEAG03PQOjk64CZnAl6kP94z3N9P+59f3oz5VNoL6q1KN9vWWyZHzcfBtLhWQcL53Ahp2vbB4Q+4KUkjSK0rdwrmx0vz1i6U0GHZVVc8fHAfS30j1lr10Rc4S4EKUGh1VAjcV0P49UoJPQmrh8YxxATea529GsiKuwedbKxcu7Uhss6bTgNegSM959heHiw3UwxMIKmenkzhqYfL5g7GBxJCMQ==" Feb 17 10:54:32 volumio go-librespot[23424]: time="2026-02-17T10:54:32+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:54:32 volumio volumio[23178]: info: Volumio called home Feb 17 10:54:33 volumio go-librespot[23424]: time="2026-02-17T10:54:33+07:00" level=debug msg="completed keyexchange" Feb 17 10:54:33 volumio go-librespot[23424]: time="2026-02-17T10:54:33+07:00" level=debug msg="completed challenge" Feb 17 10:54:33 volumio volumio[23178]: info: Starting Shairport Sync Feb 17 10:54:33 volumio volumio[23178]: info: Starting Shairport Sync Feb 17 10:54:33 volumio volumio[23178]: info: Starting Shairport Sync Feb 17 10:54:33 volumio go-librespot[23424]: time="2026-02-17T10:54:33+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:54:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:54:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:54:33 volumio sudo[23455]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 10:54:33 volumio sudo[23455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:54:33 volumio sudo[23459]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 10:54:33 volumio sudo[23459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:54:33 volumio sudo[23457]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 10:54:33 volumio sudo[23457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:54:33 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 17 10:54:33 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 17 10:54:33 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 10:54:33 volumio systemd[1]: shairport-sync.service: Consumed 2.180s CPU time. Feb 17 10:54:33 volumio volumio[23178]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 17 10:54:33 volumio volumio[23178]: SPOTIFY: BQC-ePkcoYM7RMfOQ75uKjbQz6Ondfz4BDApaHEAl5N6EQWRyVt4nqMD1GZ8ImCH35WJbvChARtncrJoPfmFCyQDRvOI4lzjFsK49QVwfeZdxA1G__FdOYU-s-76eLWqVH_7AU2ryKt-Y2u0oLKYFTBCn1InqHwSiO-XsMAzJMlnmq3m5EizIBe-Eop4qBR8xZtg43HTYqnJHKuZ1eTuVJthb6WzaLXxmknX6oRoNldrft4d7vtpS4zzuDk-mn3Anpcuku8OgOVlUu7debjiwav55iWBtnQAFn_kOcf-SR9iGjfq__Js5kyw Feb 17 10:54:33 volumio volumio[23178]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 17 10:54:33 volumio volumio[23178]: info: New Spotify access token = BQC-ePkcoYM7RMfOQ75uKjbQz6Ondfz4BDApaHEAl5N6EQWRyVt4nqMD1GZ8ImCH35WJbvChARtncrJoPfmFCyQDRvOI4lzjFsK49QVwfeZdxA1G__FdOYU-s-76eLWqVH_7AU2ryKt-Y2u0oLKYFTBCn1InqHwSiO-XsMAzJMlnmq3m5EizIBe-Eop4qBR8xZtg43HTYqnJHKuZ1eTuVJthb6WzaLXxmknX6oRoNldrft4d7vtpS4zzuDk-mn3Anpcuku8OgOVlUu7debjiwav55iWBtnQAFn_kOcf-SR9iGjfq__Js5kyw Feb 17 10:54:33 volumio volumio[23178]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 17 10:54:33 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 10:54:33 volumio sudo[23455]: pam_unix(sudo:session): session closed for user root Feb 17 10:54:33 volumio sudo[23459]: pam_unix(sudo:session): session closed for user root Feb 17 10:54:33 volumio volumio[23178]: info: Shairport-Sync Started Feb 17 10:54:33 volumio volumio[23178]: Error adding Membership: Error: addMembership EINVAL Feb 17 10:54:33 volumio volumio[23178]: info: CoreCommandRouter::volumioGetState Feb 17 10:54:33 volumio volumio[23178]: info: CorePlayQueue::getTrack 0 Feb 17 10:54:33 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 17 10:54:33 volumio volumio[23178]: info: Shairport-Sync Started Feb 17 10:54:33 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 17 10:54:33 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 10:54:33 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 10:54:33 volumio sudo[23457]: pam_unix(sudo:session): session closed for user root Feb 17 10:54:33 volumio volumio[23178]: info: Shairport-Sync Started Feb 17 10:54:33 volumio volumio[23178]: 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 17 10:54:33 volumio volumio[23178]: info: Spotify Successfully logged in Feb 17 10:54:33 volumio volumio[23178]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 10:54:33 volumio volumio[23178]: info: [1771300473998] CoreMusicLibrary::Adding element Spotify Feb 17 10:54:34 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 10:54:34 volumio volumio[23178]: Cannot find translation for source YouTube Music Feb 17 10:54:34 volumio volumio[23178]: Cannot find translation for source Spotify Feb 17 10:54:34 volumio volumio[23178]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 17 10:54:34 volumio volumio[23178]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 10:54:34 volumio volumio[23178]: info: VolumeController:: Volume=100 Mute =false Feb 17 10:54:34 volumio volumio[23178]: info: CoreCommandRouter::volumioGetState Feb 17 10:54:34 volumio volumio[23178]: info: CorePlayQueue::getTrack 0 Feb 17 10:54:34 volumio volumio[23178]: info: CoreStateMachine::pushState Feb 17 10:54:34 volumio volumio[23178]: info: CorePlayQueue::getTrack 0 Feb 17 10:54:34 volumio volumio[23178]: info: CoreCommandRouter::volumioPushState Feb 17 10:54:35 volumio volumio[23178]: info: go-librespot daemon successfully initialized Feb 17 10:54:36 volumio mpd[23407]: 2026-02-17T10:54:36 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 17 10:54:36 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 17 10:54:36 volumio sudo[23359]: pam_unix(sudo:session): session closed for user root Feb 17 10:54:36 volumio sudo[23370]: pam_unix(sudo:session): session closed for user root Feb 17 10:54:36 volumio volumio[23178]: error: MPD error: The expression evaluated to a falsy value: Feb 17 10:54:36 volumio volumio[23178]: assert.ok(self.idling) Feb 17 10:54:36 volumio volumio[23178]: error: The expression evaluated to a falsy value: Feb 17 10:54:36 volumio volumio[23178]: assert.ok(self.idling) Feb 17 10:54:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 17 10:54:36 volumio volumio[23178]: info: MPD running with PID23407 Feb 17 10:54:36 volumio volumio[23178]: ,establishing connection Feb 17 10:54:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:36 volumio volumio[23178]: error: updateQueue error: null Feb 17 10:54:36 volumio volumio[23178]: info: Completed starting Core Plugins Feb 17 10:54:36 volumio volumio[23178]: info: ------------------------------------------- Feb 17 10:54:36 volumio volumio[23178]: info: ----- MyVolumio plugins startup ---- Feb 17 10:54:36 volumio volumio[23178]: info: ------------------------------------------- Feb 17 10:54:36 volumio volumio[23178]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 17 10:54:36 volumio volumio[23178]: error: updateQueue error: null Feb 17 10:54:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:36 volumio go-librespot[23500]: go-librespot daemon starting... Feb 17 10:54:36 volumio go-librespot[23501]: time="2026-02-17T10:54:36+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:54:36 volumio go-librespot[23501]: time="2026-02-17T10:54:36+07:00" level=debug msg="app state loaded" Feb 17 10:54:36 volumio go-librespot[23501]: time="2026-02-17T10:54:36+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:54:36 volumio go-librespot[23501]: time="2026-02-17T10:54: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-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:54:36 volumio go-librespot[23501]: time="2026-02-17T10:54:36+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 17 10:54:36 volumio go-librespot[23501]: time="2026-02-17T10:54:36+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 17 10:54:36 volumio go-librespot[23501]: time="2026-02-17T10:54:36+07:00" level=info msg="zeroconf server listening on port 36105" Feb 17 10:54:37 volumio go-librespot[23501]: time="2026-02-17T10:54:37+07:00" level=debug msg="obtained new client token: AAB9SFZgWJyK0f2uqrlpVuw3WvrgMjwU/1xxW6vCS9stSz6OBTyz5ikcX1PDhdakNJ7zKG7ZpNeyleLr17GzoI0TC22tH3rwYCIexsmRKMwz6TWMwF+dcIP137oTVy0NFu7SGE+00DMr6I30ZCyMb2DjHbiRA4nGCEgPVy7AfGPGmiKbPTTvNGiYgdvk4U+RRz2UC/ze0/PEC9S+t1un/xeCYq61+tWNk+v4i0xQ09/k5n1oEPL1iyw=" Feb 17 10:54:37 volumio go-librespot[23501]: time="2026-02-17T10:54:37+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:54:37 volumio go-librespot[23501]: time="2026-02-17T10:54:37+07:00" level=debug msg="completed keyexchange" Feb 17 10:54:37 volumio go-librespot[23501]: time="2026-02-17T10:54:37+07:00" level=debug msg="completed challenge" Feb 17 10:54:37 volumio go-librespot[23501]: time="2026-02-17T10:54: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 17 10:54:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:54:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:54:38 volumio volumio[23178]: info: Initializing connection to go-librespot Websocket Feb 17 10:54:38 volumio volumio[23178]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 10:54:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 17 10:54:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:40 volumio go-librespot[23508]: go-librespot daemon starting... Feb 17 10:54:40 volumio go-librespot[23509]: time="2026-02-17T10:54:40+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:54:40 volumio go-librespot[23509]: time="2026-02-17T10:54:40+07:00" level=debug msg="app state loaded" Feb 17 10:54:40 volumio go-librespot[23509]: time="2026-02-17T10:54:40+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:54:41 volumio volumio[23178]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 17 10:54:41 volumio go-librespot[23509]: time="2026-02-17T10:54:41+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 17 10:54:41 volumio go-librespot[23509]: time="2026-02-17T10:54:41+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 17 10:54:41 volumio go-librespot[23509]: time="2026-02-17T10:54:41+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 17 10:54:41 volumio go-librespot[23509]: time="2026-02-17T10:54:41+07:00" level=info msg="zeroconf server listening on port 38591" Feb 17 10:54:41 volumio go-librespot[23509]: time="2026-02-17T10:54:41+07:00" level=debug msg="obtained new client token: AADy+ZDC6vtTlotRp4y7tl6j3qDabrzxJt9XsCOcHX6BuYzZKxDcg7EEMoh5UGn48bqNvNC8PAg6Jd+P6d+0JBqpo+PLC9ml/Z2ZzbVKZPCCcJQrY6gli1Lh76sKjc9KuirxTRF7oi99sOCqYaqhTnUHCCh/jZttJ5kCtAzCp/Sd07DckYmQUl0kpQoaUEdxXuEjAjoCKFNC/CtxLmpzk8E5g4Dho/asT5pA7nCPX2ZjJgkHjX2cRI4BXw==" Feb 17 10:54:41 volumio go-librespot[23509]: time="2026-02-17T10:54:41+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:54:41 volumio go-librespot[23509]: time="2026-02-17T10:54:41+07:00" level=debug msg="completed keyexchange" Feb 17 10:54:41 volumio go-librespot[23509]: time="2026-02-17T10:54:41+07:00" level=debug msg="completed challenge" Feb 17 10:54:41 volumio go-librespot[23509]: time="2026-02-17T10: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 17 10:54:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:54:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:54:41 volumio volumio[23178]: info: Initializing connection to go-librespot Websocket Feb 17 10:54:41 volumio volumio[23178]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 10:54:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 17 10:54:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:44 volumio go-librespot[23517]: go-librespot daemon starting... Feb 17 10:54:44 volumio go-librespot[23518]: time="2026-02-17T10:54:44+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:54:44 volumio go-librespot[23518]: time="2026-02-17T10:54:44+07:00" level=debug msg="app state loaded" Feb 17 10:54:44 volumio go-librespot[23518]: time="2026-02-17T10:54:44+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:54:44 volumio volumio[23178]: info: Initializing connection to go-librespot Websocket Feb 17 10:54:44 volumio go-librespot[23518]: time="2026-02-17T10:54:44+07:00" level=debug msg="new websocket client" Feb 17 10:54:45 volumio volumio[23178]: info: Connection to go-librespot Websocket established Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 17 10:54:45 volumio volumio[23178]: info: Adding plugin bluetooth to MyMusic Plugins Feb 17 10:54:45 volumio volumio[23178]: info: Adding plugin multiroom to MyMusic Plugins Feb 17 10:54:45 volumio volumio[23178]: info: Adding plugin metavolumio to MyMusic Plugins Feb 17 10:54:45 volumio volumio[23178]: info: Adding plugin cd_controller to MyMusic Plugins Feb 17 10:54:45 volumio volumio[23178]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 17 10:54:45 volumio volumio[23178]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 17 10:54:45 volumio volumio[23178]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 17 10:54:45 volumio volumio[23178]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 17 10:54:45 volumio go-librespot[23518]: time="2026-02-17T10: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 17 10:54:45 volumio go-librespot[23518]: time="2026-02-17T10: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 17 10:54:45 volumio go-librespot[23518]: time="2026-02-17T10: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 17 10:54:45 volumio go-librespot[23518]: time="2026-02-17T10:54:45+07:00" level=info msg="zeroconf server listening on port 35125" Feb 17 10:54:45 volumio go-librespot[23518]: time="2026-02-17T10:54:45+07:00" level=debug msg="obtained new client token: AAAJqNPug0lyuU5UF6FprtDgZkfG02USFmyJ9MAULfdzL92DIyrzj2hqXcbXMvJTHF24FhagQ9HXdIk+AnsH56FDsSRKcNm1F/+TT2E9bmS9A9eGDItprHKgoS8xT4+CS2D8eqAV+7CzvZMRauhmrmUdWpFZ0E93rSVqZSEoDk5ClGoRo3ys+DoUPaQfNgJweiw+CB7MHUhxEh+bAlKw7Rye9i7lLfSKMNXVNcU2sAnTQYhoABvr8eff7w==" Feb 17 10:54:45 volumio go-librespot[23518]: time="2026-02-17T10:54: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 17 10:54:45 volumio go-librespot[23518]: time="2026-02-17T10:54:45+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 17 10:54:45 volumio go-librespot[23518]: time="2026-02-17T10:54:45+07:00" level=debug msg="completed keyexchange" Feb 17 10:54:45 volumio go-librespot[23518]: time="2026-02-17T10:54:45+07:00" level=debug msg="completed challenge" Feb 17 10:54:46 volumio go-librespot[23518]: time="2026-02-17T10:54:46+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 17 10:54:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:54:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:54:47 volumio volumio[23178]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 17 10:54:47 volumio volumio[23178]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 17 10:54:47 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:54:47 volumio volumio[23178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:54:47 volumio volumio[23178]: info: Starting MyVolumio Remote Streaming Endpoints Feb 17 10:54:47 volumio volumio[23178]: info: MyVolumio login type: Token Feb 17 10:54:47 volumio volumio[23178]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 17 10:54:47 volumio volumio[23178]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 17 10:54:48 volumio volumio[23178]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 17 10:54:48 volumio volumio[23178]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 17 10:54:48 volumio volumio[23178]: info: Streaming services startup Feb 17 10:54:48 volumio volumio[23178]: info: Starting Streaming Daemon Feb 17 10:54:48 volumio sudo[23540]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 17 10:54:48 volumio sudo[23540]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:54:48 volumio volumio[23178]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 17 10:54:48 volumio nmbd[966]: [2026/02/17 10:54:48.933665, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 17 10:54:48 volumio nmbd[966]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.23 for name WORKGROUP<1d>. Feb 17 10:54:48 volumio nmbd[966]: This response was from IP 192.168.1.237, reporting an IP address of 192.168.1.237. Feb 17 10:54:48 volumio sudo[23540]: pam_unix(sudo:session): session closed for user root Feb 17 10:54:49 volumio volumio[23178]: info: Getting Spotify volume Feb 17 10:54:49 volumio volumio[23178]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 10:54:49 volumio volumio[23178]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 10:54:49 volumio volumio[23178]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 17 10:54:49 volumio volumio[23178]: errno: -111, Feb 17 10:54:49 volumio volumio[23178]: code: 'ECONNREFUSED', Feb 17 10:54:49 volumio volumio[23178]: syscall: 'connect', Feb 17 10:54:49 volumio volumio[23178]: address: '127.0.0.1', Feb 17 10:54:49 volumio volumio[23178]: port: 9879, Feb 17 10:54:49 volumio volumio[23178]: response: undefined Feb 17 10:54:49 volumio volumio[23178]: } Feb 17 10:54:49 volumio volumio[23178]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 10:54:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 17 10:54:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:49 volumio go-librespot[23559]: go-librespot daemon starting... Feb 17 10:54:49 volumio go-librespot[23560]: time="2026-02-17T10:54:49+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:54:49 volumio go-librespot[23560]: time="2026-02-17T10:54:49+07:00" level=debug msg="app state loaded" Feb 17 10:54:49 volumio go-librespot[23560]: time="2026-02-17T10:54:49+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:54:49 volumio go-librespot[23560]: time="2026-02-17T10:54: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 10:54:49 volumio go-librespot[23560]: time="2026-02-17T10:54:49+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 17 10:54:49 volumio go-librespot[23560]: time="2026-02-17T10:54:49+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 17 10:54:49 volumio go-librespot[23560]: time="2026-02-17T10:54:49+07:00" level=info msg="zeroconf server listening on port 40911" Feb 17 10:54:50 volumio go-librespot[23560]: time="2026-02-17T10:54:50+07:00" level=debug msg="obtained new client token: AACp5WIRWb7r+Z1YQzvsv1dFPtbuyDkRD0yKGhuRFIKprG8amAMfPjSI78KRGVYJrFrnZj4LxTp4/k5WdXfInS6wwhcPctMGgL9MyqVNwp2D+Ou3CYScuFuaApp6ZeM966KHAesu2JqjZFiAcHBYQxug6UpRtFiPN2H3nolQ7BrVDFFv57F1Te8BZm3YOeDVdAxVOO24fVh0AcFtQMNldum3gotj6FTDZGPaQgdEa9s0IdRDDAOIhG4=" Feb 17 10:54:50 volumio go-librespot[23560]: time="2026-02-17T10:54:50+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:54:50 volumio go-librespot[23560]: time="2026-02-17T10:54:50+07:00" level=debug msg="completed keyexchange" Feb 17 10:54:50 volumio go-librespot[23560]: time="2026-02-17T10:54:50+07:00" level=debug msg="completed challenge" Feb 17 10:54:50 volumio go-librespot[23560]: time="2026-02-17T10:54:50+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 17 10:54:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:54:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:54:50 volumio sudo[23570]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-17 10:53' Feb 17 10:54:50 volumio sudo[23570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:54:50 volumio sudo[23570]: pam_unix(sudo:session): session closed for user root Feb 17 10:54:51 volumio volumio-remote-updater[25144]: [2026-02-17 10:54:51] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 17 10:54:51 volumio volumio-remote-updater[25144]: [2026-02-17 10:54:51] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 17 10:54:51 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:54:51 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 17 10:54:51 volumio systemd[1]: volumio.service: Consumed 54.834s CPU time. Feb 17 10:54:51 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 17 10:54:51 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 17 10:54:51 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 20283. Feb 17 10:54:51 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 17 10:54:51 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 17 10:54:51 volumio systemd[1]: volumio.service: Consumed 54.834s CPU time. Feb 17 10:54:51 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 17 10:54:51 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 17 10:54:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 17 10:54:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:53 volumio go-librespot[23597]: go-librespot daemon starting... Feb 17 10:54:53 volumio go-librespot[23598]: time="2026-02-17T10:54:53+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:54:53 volumio go-librespot[23598]: time="2026-02-17T10:54:53+07:00" level=debug msg="app state loaded" Feb 17 10:54:53 volumio go-librespot[23598]: time="2026-02-17T10:54:53+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:54:54 volumio go-librespot[23598]: time="2026-02-17T10:54:54+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 17 10:54:54 volumio go-librespot[23598]: time="2026-02-17T10:54:54+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 17 10:54:54 volumio go-librespot[23598]: time="2026-02-17T10:54:54+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 17 10:54:54 volumio go-librespot[23598]: time="2026-02-17T10:54:54+07:00" level=info msg="zeroconf server listening on port 37465" Feb 17 10:54:54 volumio go-librespot[23598]: time="2026-02-17T10:54:54+07:00" level=debug msg="obtained new client token: AAA0n/4VIY5OoOU0JcFy/ceW3EgBH5KB3AeD2tYGZz+OgYD+afLu/K7YtTVJfJ1c4zMSyRsegjUnCj6OzAv05yF4of6bLBbNZr5U2kjjb2V1cc9i2ePs60hE32FBLsUojAhu20q/7jWk+ZbEHpfkOnPOSVNnXmZw4s5nVaf8FD0clE4tVT0vNsesDX5u48yjKzVMNDQA3DiKpzn1KmIshfIAlF9bfLUXFp3tbT425a3NHvIMLxtPbj8caA==" Feb 17 10:54:54 volumio go-librespot[23598]: time="2026-02-17T10:54:54+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:54:54 volumio volumio[23582]: info: ------------------------------------------- Feb 17 10:54:54 volumio volumio[23582]: info: ----- Volumio3 ---- Feb 17 10:54:54 volumio volumio[23582]: info: ------------------------------------------- Feb 17 10:54:54 volumio volumio[23582]: info: ----- System startup ---- Feb 17 10:54:54 volumio volumio[23582]: info: ------------------------------------------- Feb 17 10:54:55 volumio go-librespot[23598]: time="2026-02-17T10:54:55+07:00" level=debug msg="completed keyexchange" Feb 17 10:54:55 volumio go-librespot[23598]: time="2026-02-17T10:54:55+07:00" level=debug msg="completed challenge" Feb 17 10:54:55 volumio go-librespot[23598]: time="2026-02-17T10:54:55+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 17 10:54:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:54:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:54:56 volumio volumio-remote-updater[25144]: [2026-02-17 10:54:56] [connect] Successful connection Feb 17 10:54:56 volumio volumio[23582]: info: MYVOLUMIO Environment detected Feb 17 10:54:56 volumio volumio[23582]: info: Plugin folders cleanup Feb 17 10:54:56 volumio volumio[23582]: info: Scanning into folder /volumio/app/plugins/ Feb 17 10:54:56 volumio volumio[23582]: info: Scanning category audio_interface Feb 17 10:54:56 volumio volumio[23582]: info: Scanning category miscellanea Feb 17 10:54:56 volumio volumio[23582]: info: Scanning category music_service Feb 17 10:54:56 volumio volumio[23582]: info: Scanning category plugins.json Feb 17 10:54:56 volumio volumio[23582]: info: Scanning category system_controller Feb 17 10:54:56 volumio volumio[23582]: info: Scanning category user_interface Feb 17 10:54:56 volumio volumio[23582]: info: Scanning into folder /data/plugins/ Feb 17 10:54:56 volumio volumio[23582]: info: Scanning category music_service Feb 17 10:54:56 volumio volumio[23582]: info: Plugin folders cleanup completed Feb 17 10:54:56 volumio volumio[23582]: info: ------------------------------------------- Feb 17 10:54:56 volumio volumio[23582]: info: ----- Core plugins startup ---- Feb 17 10:54:56 volumio volumio[23582]: info: ------------------------------------------- Feb 17 10:54:56 volumio volumio[23582]: info: Loading plugins from folder /volumio/app/plugins/ Feb 17 10:54:56 volumio volumio[23582]: info: Adding plugin upnp to MyMusic Plugins Feb 17 10:54:56 volumio volumio[23582]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 17 10:54:56 volumio volumio[23582]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 17 10:54:56 volumio volumio[23582]: info: Loading plugins from folder /data/plugins/ Feb 17 10:54:56 volumio volumio[23582]: info: Loading plugin "system"... Feb 17 10:54:56 volumio volumio[23582]: info: Loading plugin "appearance"... Feb 17 10:54:58 volumio volumio[23582]: info: Loading plugin "network"... Feb 17 10:54:58 volumio volumio[23582]: info: Refreshing Cached IP Addresses Feb 17 10:54:58 volumio sudo[23620]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 10:54:58 volumio sudo[23620]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:54:58 volumio sudo[23622]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 10:54:58 volumio sudo[23622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:54:58 volumio volumio[23582]: info: Loading plugin "services"... Feb 17 10:54:58 volumio sudo[23620]: pam_unix(sudo:session): session closed for user root Feb 17 10:54:58 volumio sudo[23622]: pam_unix(sudo:session): session closed for user root Feb 17 10:54:58 volumio volumio[23582]: info: Loading plugin "alsa_controller"... Feb 17 10:54:58 volumio sudo[23630]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 17 10:54:58 volumio sudo[23630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:54:58 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 10:54:58 volumio volumio[23582]: info: Loading plugin "wizard"... Feb 17 10:54:58 volumio volumio[23582]: info: Loading plugin "networkfs"... Feb 17 10:54:58 volumio volumio[23582]: info: Starting Udev Watcher for removable devices Feb 17 10:54:58 volumio volumio[23582]: info: Ignoring mount for partition: boot Feb 17 10:54:58 volumio volumio[23582]: info: Ignoring mount for partition: volumio Feb 17 10:54:58 volumio volumio[23582]: info: Ignoring mount for partition: volumio_data Feb 17 10:54:58 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 10:54:58 volumio volumio[23582]: info: Loading plugin "volumio_command_line_client"... Feb 17 10:54:58 volumio volumio[23582]: info: Loading plugin "upnp"... Feb 17 10:54:58 volumio volumio[23582]: info: [1771300498710] Starting Upmpd Daemon Feb 17 10:54:58 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 10:54:58 volumio volumio[23582]: info: Loading plugin "my_music"... Feb 17 10:54:58 volumio volumio[23582]: info: Loading plugin "mpd"... Feb 17 10:54:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 17 10:54:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:54:59 volumio go-librespot[23653]: go-librespot daemon starting... Feb 17 10:54:59 volumio go-librespot[23654]: time="2026-02-17T10:54:59+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:54:59 volumio go-librespot[23654]: time="2026-02-17T10:54:59+07:00" level=debug msg="app state loaded" Feb 17 10:54:59 volumio go-librespot[23654]: time="2026-02-17T10:54:59+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:54:59 volumio volumio[23582]: info: Loading plugin "upnp_browser"... Feb 17 10:54:59 volumio go-librespot[23654]: time="2026-02-17T10:54:59+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 17 10:54:59 volumio go-librespot[23654]: time="2026-02-17T10:54:59+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 17 10:54:59 volumio go-librespot[23654]: time="2026-02-17T10:54:59+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 17 10:54:59 volumio go-librespot[23654]: time="2026-02-17T10:54:59+07:00" level=info msg="zeroconf server listening on port 33137" Feb 17 10:55:00 volumio go-librespot[23654]: time="2026-02-17T10:55:00+07:00" level=debug msg="obtained new client token: AADRvlt/OORJnNBLCJzHwk84H1hwSIkhpa7q4HWPcH57MX04do/w+luxXggjCeO6Fj4LmY+KMlGeZClgkQFl7vL7PZ4x88O0EKZinyE8e2bin+6e/Q11Lx0yd6zIPLS17Id83KBh6+gTYg9tcevxtp2W+kHg2X/URvpCN280KYEmdEXizHZkaumfB/vrsfBCuIRrvmYXL8UApN+sHSUmw2HIrLhE8zNJ2HcwLroMhkjV3sfTGeImYwp23A==" Feb 17 10:55:00 volumio go-librespot[23654]: time="2026-02-17T10:55:00+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:55:00 volumio go-librespot[23654]: time="2026-02-17T10:55:00+07:00" level=debug msg="completed keyexchange" Feb 17 10:55:00 volumio go-librespot[23654]: time="2026-02-17T10:55:00+07:00" level=debug msg="completed challenge" Feb 17 10:55:00 volumio go-librespot[23654]: time="2026-02-17T10:55: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 17 10:55:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:55:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:55:01 volumio sudo[23630]: pam_unix(sudo:session): session closed for user root Feb 17 10:55:02 volumio volumio[23582]: info: Starting UPNP Browser Feb 17 10:55:02 volumio volumio[23582]: info: Loading plugin "alarm-clock"... Feb 17 10:55:02 volumio volumio[23582]: info: Loading plugin "airplay_emulation"... Feb 17 10:55:02 volumio volumio[23582]: info: Starting Shairport Sync Feb 17 10:55:02 volumio volumio[23582]: info: Loading plugin "last_100"... Feb 17 10:55:02 volumio volumio[23582]: info: Loading plugin "webradio"... Feb 17 10:55:02 volumio volumio[23582]: info: Loading plugin "i2s_dacs"... Feb 17 10:55:02 volumio volumio[23582]: info: Loading plugin "volumiodiscovery"... Feb 17 10:55:02 volumio volumio[23582]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 17 10:55:02 volumio volumio[23582]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 10:55:02 volumio node[23582]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 17 10:55:02 volumio volumio[23582]: *** WARNING *** For more information see Feb 17 10:55:02 volumio volumio[23582]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 17 10:55:02 volumio volumio[23582]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 10:55:02 volumio volumio[23582]: *** WARNING *** For more information see Feb 17 10:55:02 volumio node[23582]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 10:55:02 volumio node[23582]: *** WARNING *** For more information see Feb 17 10:55:02 volumio node[23582]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 17 10:55:02 volumio node[23582]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 10:55:02 volumio node[23582]: *** WARNING *** For more information see Feb 17 10:55:02 volumio volumio[23582]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 17 10:55:02 volumio volumio[23582]: info: Discovery: Started advertising with name: Volumio Feb 17 10:55:03 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 10:55:03 volumio volumio[23582]: info: Loading plugin "spop"... Feb 17 10:55:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 17 10:55:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:55:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:55:03 volumio go-librespot[23664]: go-librespot daemon starting... Feb 17 10:55:03 volumio go-librespot[23665]: time="2026-02-17T10:55:03+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:55:03 volumio go-librespot[23665]: time="2026-02-17T10:55:03+07:00" level=debug msg="app state loaded" Feb 17 10:55:03 volumio go-librespot[23665]: time="2026-02-17T10:55:03+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:55:04 volumio go-librespot[23665]: time="2026-02-17T10:55: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 17 10:55:04 volumio go-librespot[23665]: time="2026-02-17T10:55: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 17 10:55:04 volumio go-librespot[23665]: time="2026-02-17T10:55: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 17 10:55:04 volumio go-librespot[23665]: time="2026-02-17T10:55:04+07:00" level=info msg="zeroconf server listening on port 43459" Feb 17 10:55:04 volumio go-librespot[23665]: time="2026-02-17T10:55:04+07:00" level=debug msg="obtained new client token: AADzD+f/10BhEjrcKOM2au2pEgbkrvusL3QXYTxj6LD9PxzFmFz+l57xh+qSpfVKDLDdlYJYbNPIuXTf20AAYGoHL1mTAfBMyjODD7tHagLX7NhfkIXUmSExnlDdegqUrddJKievwjV7DKZtTJo6TTNmHE0rIjlAwPqjr6CDbjzNiZIbbySzu6aXcsEptryDQFE0zG1MvlhmErhG+CniU7h1k7FUJmVA14Q0E5vJB/omje4JPtiA7Xs0Tg==" Feb 17 10:55:04 volumio go-librespot[23665]: time="2026-02-17T10:55:04+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:55:04 volumio go-librespot[23665]: time="2026-02-17T10:55:04+07:00" level=debug msg="completed keyexchange" Feb 17 10:55:04 volumio go-librespot[23665]: time="2026-02-17T10:55:04+07:00" level=debug msg="completed challenge" Feb 17 10:55:04 volumio go-librespot[23665]: time="2026-02-17T10:55:04+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 17 10:55:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:55:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:55:04 volumio volumio[23582]: info: Loading plugin "ytcr"... Feb 17 10:55:07 volumio volumio[23582]: info: Loading plugin "ytmusic"... Feb 17 10:55:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 17 10:55:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:55:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:55:07 volumio go-librespot[23672]: go-librespot daemon starting... Feb 17 10:55:07 volumio go-librespot[23673]: time="2026-02-17T10:55:07+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:55:07 volumio go-librespot[23673]: time="2026-02-17T10:55:07+07:00" level=debug msg="app state loaded" Feb 17 10:55:07 volumio go-librespot[23673]: time="2026-02-17T10:55:07+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:55:08 volumio go-librespot[23673]: time="2026-02-17T10:55:08+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 17 10:55:08 volumio go-librespot[23673]: time="2026-02-17T10:55:08+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 17 10:55:08 volumio go-librespot[23673]: time="2026-02-17T10:55:08+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 17 10:55:08 volumio go-librespot[23673]: time="2026-02-17T10:55:08+07:00" level=info msg="zeroconf server listening on port 39531" Feb 17 10:55:08 volumio volumio-remote-updater[25144]: [2026-02-17 10:55:08] [connect] Successful connection Feb 17 10:55:08 volumio volumio[23582]: info: Loading plugin "outputs"... Feb 17 10:55:08 volumio volumio[23582]: info: Loading plugin "albumart"... Feb 17 10:55:08 volumio volumio[23582]: info: Plugin example_plugin is not enabled Feb 17 10:55:08 volumio volumio[23582]: info: Loading plugin "inputs"... Feb 17 10:55:08 volumio volumio[23582]: info: Loading plugin "updater_comm"... Feb 17 10:55:08 volumio go-librespot[23673]: time="2026-02-17T10:55:08+07:00" level=debug msg="obtained new client token: AAA3KwsXlZ8P7KFQZuXm2DAuWv5P6j4jpCr9yUEkcMc4Dbni7Bcn3WuYWIe62tnvg0jhEUnnzngN3+JYXJQT7oxl0djtdfO9k5EthfiiD6CKTjf+9Yw0WP17pyxVK2DirwCxr0i32vkyskcuCcXn7DpKpz9MQfSGgPcNXOSyH8+YxN6XUfAToLUlV+Y/zS0O34kDvt55ft+fw8FCI0hib6Q+OuCWpIiDfNAjj1p/eWADCkTc0NZGjjH7Dw==" Feb 17 10:55:08 volumio go-librespot[23673]: time="2026-02-17T10:55:08+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:55:08 volumio volumio[23582]: info: Plugin mpdemulation is not enabled Feb 17 10:55:08 volumio volumio[23582]: info: Loading plugin "rest_api"... Feb 17 10:55:08 volumio go-librespot[23673]: time="2026-02-17T10:55:08+07:00" level=debug msg="completed keyexchange" Feb 17 10:55:08 volumio go-librespot[23673]: time="2026-02-17T10:55:08+07:00" level=debug msg="completed challenge" Feb 17 10:55:08 volumio volumio[23582]: info: Loading plugin "websocket"... Feb 17 10:55:08 volumio volumio[23582]: info: Starting Socket.io Server version 1.7.4 Feb 17 10:55:08 volumio volumio[23582]: info: Loading plugin "RoonBridge"... Feb 17 10:55:08 volumio go-librespot[23673]: time="2026-02-17T10:55: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 17 10:55:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:55:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:55:09 volumio volumio[23582]: info: Applying required configuration parameters for plugin RoonBridge Feb 17 10:55:09 volumio volumio[23582]: info: Loading i18n strings for locale en Feb 17 10:55:09 volumio volumio[23582]: Updating browse sources language Feb 17 10:55:09 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 10:55:09 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 10:55:09 volumio volumio[23582]: info: CoreCommandRouter::initPlayerControls Feb 17 10:55:09 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:09 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:09 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:09 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:09 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:09 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:09 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:09 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:09 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 10:55:09 volumio volumio[23582]: Express server listening on port 3000 Feb 17 10:55:09 volumio volumio[23582]: [Metrics] WebUI: 15s 924.98ms Feb 17 10:55:09 volumio volumio[23582]: info: CoreStateMachine::resetVolumioState Feb 17 10:55:09 volumio volumio[23582]: info: CoreStateMachine::getcurrentVolume Feb 17 10:55:09 volumio volumio[23582]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 10:55:09 volumio volumio[23694]: Forking 3 albumart workers Feb 17 10:55:09 volumio sudo[23711]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 10:55:09 volumio sudo[23711]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:55:10 volumio sudo[23714]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 10:55:10 volumio sudo[23711]: pam_unix(sudo:session): session closed for user root Feb 17 10:55:10 volumio sudo[23714]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:55:10 volumio sudo[23714]: pam_unix(sudo:session): session closed for user root Feb 17 10:55:10 volumio volumio[23582]: info: Volumio Network Manager: Network status updated: 1 Feb 17 10:55:10 volumio volumio[23582]: info: VolumeController:: Volume=100 Mute =false Feb 17 10:55:10 volumio volumio[23582]: info: CoreStateMachine::pushState Feb 17 10:55:10 volumio volumio[23582]: info: CorePlayQueue::getTrack 0 Feb 17 10:55:10 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 10:55:10 volumio volumio[23582]: info: CoreCommandRouter::volumioPushState Feb 17 10:55:10 volumio volumio[23582]: info: CoreStateMachine::updateTrackBlock Feb 17 10:55:10 volumio volumio[23582]: info: CorePlayQueue::getTrackBlock Feb 17 10:55:10 volumio volumio[23582]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 10:55:10 volumio volumio-remote-updater[25144]: [2026-02-17 10:55:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771300508 101 Feb 17 10:55:10 volumio volumio[23582]: 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 17 10:55:10 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:55:10 volumio volumio[23582]: info: Reloading queue from file Feb 17 10:55:10 volumio volumio[23582]: info: CoreStateMachine::setRepeat null single undefined Feb 17 10:55:10 volumio volumio[23582]: info: CoreStateMachine::pushState Feb 17 10:55:10 volumio volumio[23582]: info: CorePlayQueue::getTrack 0 Feb 17 10:55:10 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 10:55:10 volumio volumio[23582]: info: CoreCommandRouter::volumioPushState Feb 17 10:55:10 volumio volumio[23582]: info: CoreStateMachine::setRandom null Feb 17 10:55:10 volumio volumio[23582]: info: CoreStateMachine::pushState Feb 17 10:55:10 volumio volumio[23582]: info: CorePlayQueue::getTrack 0 Feb 17 10:55:10 volumio volumio[23582]: info: CoreCommandRouter::volumioPushState Feb 17 10:55:10 volumio volumio[23582]: info: Setting Device type: Raspberry PI Feb 17 10:55:10 volumio volumio[23582]: info: Completed loading Core Plugins Feb 17 10:55:10 volumio volumio[23582]: info: Preparing to generate the ALSA configuration file Feb 17 10:55:10 volumio volumio[23582]: info: VolumeController:: Volume=100 Mute =false Feb 17 10:55:10 volumio volumio[23582]: info: CoreStateMachine::pushState Feb 17 10:55:10 volumio volumio[23582]: info: CorePlayQueue::getTrack 0 Feb 17 10:55:10 volumio volumio[23582]: info: CoreCommandRouter::volumioPushState Feb 17 10:55:10 volumio volumio[23582]: info: Asound.conf file unchanged, so no further update is needed Feb 17 10:55:10 volumio volumio[23582]: info: Output device has changed, restarting MPD Feb 17 10:55:10 volumio volumio[23582]: info: Output device has changed, restarting Shairport Sync Feb 17 10:55:10 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:10 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:55:10 volumio sudo[23756]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 17 10:55:10 volumio sudo[23756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:55:10 volumio sudo[23758]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 10:55:11 volumio sudo[23758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:55:11 volumio sudo[23758]: pam_unix(sudo:session): session closed for user root Feb 17 10:55:11 volumio sudo[23760]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 10:55:11 volumio sudo[23760]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:55:11 volumio volumio[23582]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 10:55:11 volumio volumio[23582]: info: ___________ START PLUGINS ___________ Feb 17 10:55:11 volumio volumio[23582]: info: ControllerMpd::onStart: Initializing MPD Feb 17 10:55:11 volumio volumio[23582]: info: Creating MPD Configuration file Feb 17 10:55:11 volumio sudo[23756]: pam_unix(sudo:session): session closed for user root Feb 17 10:55:11 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 17 10:55:11 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 10:55:11 volumio volumio[23582]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 10:55:11 volumio volumio[23582]: info: [1771300511211] CoreMusicLibrary::Adding element Media Servers Feb 17 10:55:11 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 10:55:11 volumio sudo[23770]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 10:55:11 volumio sudo[23770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:55:11 volumio sudo[23770]: pam_unix(sudo:session): session closed for user root Feb 17 10:55:11 volumio volumio[23582]: info: UPNP Browser: Client initialized successfully Feb 17 10:55:11 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 17 10:55:11 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 17 10:55:11 volumio systemd[1]: mpd.service: Consumed 7.113s CPU time. Feb 17 10:55:11 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 17 10:55:11 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 17 10:55:11 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 17 10:55:11 volumio sudo[23772]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 10:55:11 volumio sudo[23772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:55:11 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:11 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:55:11 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 17 10:55:11 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 17 10:55:11 volumio volumio[23582]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 10:55:11 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:11 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:55:11 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 17 10:55:11 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 17 10:55:11 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 17 10:55:11 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 17 10:55:11 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 17 10:55:11 volumio volumio[23582]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 10:55:11 volumio volumio[23582]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 10:55:11 volumio volumio[23582]: info: [1771300511619] CoreMusicLibrary::Adding element Last_100 Feb 17 10:55:11 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 10:55:11 volumio volumio[23582]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 10:55:11 volumio volumio[23582]: info: [1771300511628] CoreMusicLibrary::Adding element Webradio Feb 17 10:55:11 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 10:55:11 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 10:55:11 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 17 10:55:11 volumio volumio[23582]: info: Initializing BBC Radios Feb 17 10:55:11 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 17 10:55:11 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 10:55:11 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:55:11 volumio volumio[23582]: info: Creating Spotify config file Feb 17 10:55:11 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 17 10:55:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:55:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:55:12 volumio go-librespot[23800]: go-librespot daemon starting... Feb 17 10:55:12 volumio sudo[23788]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 17 10:55:12 volumio sudo[23788]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 17 10:55:12 volumio sudo[23788]: pam_unix(sudo:session): session closed for user root Feb 17 10:55:12 volumio go-librespot[23801]: time="2026-02-17T10:55:12+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:55:12 volumio go-librespot[23801]: time="2026-02-17T10:55:12+07:00" level=info msg="zeroconf server listening on port 34549" Feb 17 10:55:13 volumio volumio[23582]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 10:55:13 volumio volumio[23582]: info: [1771300513041] CoreMusicLibrary::Adding element YouTube Music Feb 17 10:55:13 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 10:55:13 volumio volumio[23582]: Cannot find translation for source YouTube Music Feb 17 10:55:13 volumio volumio[23582]: info: Volumio Calling Home Feb 17 10:55:13 volumio sudo[23812]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 17 10:55:13 volumio sudo[23812]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:55:13 volumio sudo[23812]: pam_unix(sudo:session): session closed for user root Feb 17 10:55:13 volumio volumio[23712]: Starting albumart workers Feb 17 10:55:13 volumio volumio[23709]: Starting albumart workers Feb 17 10:55:13 volumio volumio[23708]: Starting albumart workers Feb 17 10:55:13 volumio volumio[23582]: info: MPD Permissions set Feb 17 10:55:13 volumio volumio[23582]: info: MPD Permissions set Feb 17 10:55:13 volumio volumio[23582]: info: Upmpdcli Daemon Started Feb 17 10:55:13 volumio volumio[23582]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 17 10:55:13 volumio volumio[23582]: info: Discovery: Found device Volumio Feb 17 10:55:13 volumio volumio[23582]: info: CoreCommandRouter::volumioGetState Feb 17 10:55:13 volumio volumio[23582]: info: CorePlayQueue::getTrack 0 Feb 17 10:55:13 volumio volumio[23582]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 17 10:55:13 volumio volumio[23582]: info: Discovery: Found device Volumio Feb 17 10:55:13 volumio volumio[23582]: info: CoreCommandRouter::volumioGetState Feb 17 10:55:13 volumio volumio[23582]: info: CorePlayQueue::getTrack 0 Feb 17 10:55:13 volumio volumio[23582]: info: Spotify config file written Feb 17 10:55:13 volumio sudo[23818]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 17 10:55:13 volumio sudo[23818]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:55:14 volumio volumio[23582]: 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 17 10:55:14 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:14 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:14 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:14 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:14 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:14 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:14 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:14 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:14 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:55:14 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:14 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:14 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:14 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:14 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:14 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:14 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:14 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:14 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:14 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 17 10:55:14 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 17 10:55:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:55:14 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:55:14 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 10:55:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:55:14 volumio go-librespot[23830]: go-librespot daemon starting... Feb 17 10:55:14 volumio volumio[23582]: info: No need to fix Spotify hosts Feb 17 10:55:14 volumio sudo[23818]: pam_unix(sudo:session): session closed for user root Feb 17 10:55:14 volumio go-librespot[23831]: time="2026-02-17T10:55:14+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:55:14 volumio go-librespot[23831]: time="2026-02-17T10:55:14+07:00" level=debug msg="app state loaded" Feb 17 10:55:14 volumio go-librespot[23831]: time="2026-02-17T10:55:14+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:55:14 volumio go-librespot[23831]: time="2026-02-17T10:55:14+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 17 10:55:14 volumio go-librespot[23831]: time="2026-02-17T10:55:14+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 17 10:55:14 volumio go-librespot[23831]: time="2026-02-17T10:55:14+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 17 10:55:14 volumio go-librespot[23831]: time="2026-02-17T10:55:14+07:00" level=info msg="zeroconf server listening on port 36089" Feb 17 10:55:15 volumio volumio[23582]: info: Volumio called home Feb 17 10:55:15 volumio go-librespot[23831]: time="2026-02-17T10:55:15+07:00" level=debug msg="obtained new client token: AADwy6axRj5ZkmGZenm/gWk3mC2Gv4Lndum1FIUAD+OBCLd3/wvf/+Ks5LUzlq+FzbB2cUg9u4uZjH9z4BdGu2NzCOR/43eetpjyCr8l18UXdo9SRiKLX3UeOf3KStEMW6SkdxJ9G8MZfllRa+AvJeHSEFDkH+xxiyaq/X8BjQGMg4n8uir3EDuqSzu8w5wPCHILlwJnLy7nNWBVUcaHw80gW2g94+2+5bICE6oMKt6wC179SFDXyKY=" Feb 17 10:55:15 volumio go-librespot[23831]: time="2026-02-17T10:55:15+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:55:15 volumio go-librespot[23831]: time="2026-02-17T10:55:15+07:00" level=debug msg="completed keyexchange" Feb 17 10:55:15 volumio go-librespot[23831]: time="2026-02-17T10:55:15+07:00" level=debug msg="completed challenge" Feb 17 10:55:15 volumio go-librespot[23831]: time="2026-02-17T10:55:15+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 10:55:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:55:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:55:15 volumio volumio[23582]: info: Starting Shairport Sync Feb 17 10:55:16 volumio volumio[23582]: info: Starting Shairport Sync Feb 17 10:55:16 volumio volumio[23582]: info: Starting Shairport Sync Feb 17 10:55:16 volumio sudo[23859]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 10:55:16 volumio sudo[23859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:55:16 volumio sudo[23863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 10:55:16 volumio sudo[23861]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 10:55:16 volumio sudo[23863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:55:16 volumio sudo[23861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:55:16 volumio volumio[23582]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 17 10:55:16 volumio volumio[23582]: SPOTIFY: BQCmN_UBFiJ89VjrZLEuomcxAztlo70A0TqIhO1PDK-k4l_NiVuK575hM_L_vyhYgrXkbrWLVcyryOwnQgL8QL9ZNIVy-TWKksu8IZ3eDEUE_McwH5u3X1CpxxxOD3cTw-UG2zj5t0NWfWKSUB8EElFxmysFdwMQHNN9B9zUdnEAu8jQ7R7zUZioBhMeCfy-HlL81tjHJ9pfETgmPRDw3ERfORlGMsxmcKjqvEt4uu0rlBjp5Y9h70tQCsD5fw7EnHC7tXM2lwxYWlHBycpcvJcOw9vUBEKOf2Buqb9-JVt6M_Qn4OU8W-Iq Feb 17 10:55:16 volumio volumio[23582]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 17 10:55:16 volumio volumio[23582]: info: New Spotify access token = BQCmN_UBFiJ89VjrZLEuomcxAztlo70A0TqIhO1PDK-k4l_NiVuK575hM_L_vyhYgrXkbrWLVcyryOwnQgL8QL9ZNIVy-TWKksu8IZ3eDEUE_McwH5u3X1CpxxxOD3cTw-UG2zj5t0NWfWKSUB8EElFxmysFdwMQHNN9B9zUdnEAu8jQ7R7zUZioBhMeCfy-HlL81tjHJ9pfETgmPRDw3ERfORlGMsxmcKjqvEt4uu0rlBjp5Y9h70tQCsD5fw7EnHC7tXM2lwxYWlHBycpcvJcOw9vUBEKOf2Buqb9-JVt6M_Qn4OU8W-Iq Feb 17 10:55:16 volumio volumio[23582]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 17 10:55:16 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 17 10:55:16 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 17 10:55:16 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 10:55:16 volumio systemd[1]: shairport-sync.service: Consumed 2.293s CPU time. Feb 17 10:55:16 volumio volumio[23582]: info: CoreCommandRouter::volumioGetState Feb 17 10:55:16 volumio volumio[23582]: info: CorePlayQueue::getTrack 0 Feb 17 10:55:16 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 10:55:16 volumio sudo[23863]: pam_unix(sudo:session): session closed for user root Feb 17 10:55:16 volumio sudo[23859]: pam_unix(sudo:session): session closed for user root Feb 17 10:55:16 volumio sudo[23861]: pam_unix(sudo:session): session closed for user root Feb 17 10:55:16 volumio volumio[23582]: info: Shairport-Sync Started Feb 17 10:55:16 volumio volumio[23582]: Error adding Membership: Error: addMembership EINVAL Feb 17 10:55:16 volumio volumio[23582]: info: Shairport-Sync Started Feb 17 10:55:16 volumio volumio[23582]: info: Shairport-Sync Started Feb 17 10:55:16 volumio volumio[23582]: 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 17 10:55:16 volumio volumio[23582]: info: Spotify Successfully logged in Feb 17 10:55:16 volumio volumio[23582]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 10:55:16 volumio volumio[23582]: info: [1771300516791] CoreMusicLibrary::Adding element Spotify Feb 17 10:55:16 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 10:55:16 volumio volumio[23582]: Cannot find translation for source YouTube Music Feb 17 10:55:16 volumio volumio[23582]: Cannot find translation for source Spotify Feb 17 10:55:17 volumio volumio[23582]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 17 10:55:17 volumio volumio[23582]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 10:55:17 volumio volumio[23582]: info: VolumeController:: Volume=100 Mute =false Feb 17 10:55:17 volumio volumio[23582]: info: CoreCommandRouter::volumioGetState Feb 17 10:55:17 volumio volumio[23582]: info: CorePlayQueue::getTrack 0 Feb 17 10:55:17 volumio volumio[23582]: info: CoreStateMachine::pushState Feb 17 10:55:17 volumio volumio[23582]: info: CorePlayQueue::getTrack 0 Feb 17 10:55:17 volumio volumio[23582]: info: CoreCommandRouter::volumioPushState Feb 17 10:55:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 17 10:55:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:55:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:55:18 volumio go-librespot[23899]: go-librespot daemon starting... Feb 17 10:55:18 volumio go-librespot[23900]: time="2026-02-17T10:55:18+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:55:18 volumio go-librespot[23900]: time="2026-02-17T10:55:18+07:00" level=debug msg="app state loaded" Feb 17 10:55:18 volumio go-librespot[23900]: time="2026-02-17T10:55:18+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:55:18 volumio volumio[23582]: info: go-librespot daemon successfully initialized Feb 17 10:55:19 volumio mpd[23809]: 2026-02-17T10:55:19 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 17 10:55:19 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 17 10:55:19 volumio sudo[23760]: pam_unix(sudo:session): session closed for user root Feb 17 10:55:19 volumio sudo[23772]: pam_unix(sudo:session): session closed for user root Feb 17 10:55:19 volumio volumio[23582]: error: MPD error: The expression evaluated to a falsy value: Feb 17 10:55:19 volumio volumio[23582]: assert.ok(self.idling) Feb 17 10:55:19 volumio volumio[23582]: error: The expression evaluated to a falsy value: Feb 17 10:55:19 volumio volumio[23582]: assert.ok(self.idling) Feb 17 10:55:19 volumio volumio[23582]: error: updateQueue error: null Feb 17 10:55:19 volumio volumio[23582]: info: MPD running with PID23809 Feb 17 10:55:19 volumio volumio[23582]: ,establishing connection Feb 17 10:55:19 volumio go-librespot[23900]: time="2026-02-17T10:55:19+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 17 10:55:19 volumio go-librespot[23900]: time="2026-02-17T10:55:19+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 17 10:55:19 volumio go-librespot[23900]: time="2026-02-17T10:55:19+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 17 10:55:19 volumio go-librespot[23900]: time="2026-02-17T10:55:19+07:00" level=info msg="zeroconf server listening on port 41557" Feb 17 10:55:19 volumio volumio[23582]: info: Completed starting Core Plugins Feb 17 10:55:19 volumio volumio[23582]: info: ------------------------------------------- Feb 17 10:55:19 volumio volumio[23582]: info: ----- MyVolumio plugins startup ---- Feb 17 10:55:19 volumio volumio[23582]: info: ------------------------------------------- Feb 17 10:55:19 volumio volumio[23582]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 17 10:55:19 volumio volumio[23582]: error: updateQueue error: null Feb 17 10:55:19 volumio go-librespot[23900]: time="2026-02-17T10:55:19+07:00" level=debug msg="obtained new client token: AABz5lGnAmH9ZrDsBkO1/SIHJuNYzATzdRVyypx086U6N6II5RJ036SFROEtIu/jKyhF0gtPdTSkGiXLY9+lgVIO38scLh2Q0YReR+06I0bOYLya3160iNRi+Z61Dd6VJfVFSdJT5UWoZpuOn1STeVJ+BZYYqxzjglzAkrjJAsj2KFH0+bDP82ZVxW1fnx7VwHDG7eajfS2tnMDsNnXNiPoQiGxPtFF8DYDzfAElM029F15l043TmypvBg==" Feb 17 10:55:19 volumio go-librespot[23900]: time="2026-02-17T10:55:19+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:55:19 volumio go-librespot[23900]: time="2026-02-17T10:55:19+07:00" level=debug msg="completed keyexchange" Feb 17 10:55:19 volumio go-librespot[23900]: time="2026-02-17T10:55:19+07:00" level=debug msg="completed challenge" Feb 17 10:55:19 volumio go-librespot[23900]: time="2026-02-17T10:55: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 17 10:55:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:55:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:55:21 volumio volumio[23582]: info: Initializing connection to go-librespot Websocket Feb 17 10:55:21 volumio volumio[23582]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 10:55:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 17 10:55:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:55:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:55:23 volumio go-librespot[23911]: go-librespot daemon starting... Feb 17 10:55:23 volumio go-librespot[23912]: time="2026-02-17T10:55:23+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:55:23 volumio go-librespot[23912]: time="2026-02-17T10:55:23+07:00" level=debug msg="app state loaded" Feb 17 10:55:23 volumio go-librespot[23912]: time="2026-02-17T10:55:23+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:55:23 volumio go-librespot[23912]: time="2026-02-17T10:55:23+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 17 10:55:23 volumio go-librespot[23912]: time="2026-02-17T10:55:23+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 17 10:55:23 volumio go-librespot[23912]: time="2026-02-17T10:55:23+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 17 10:55:23 volumio go-librespot[23912]: time="2026-02-17T10:55:23+07:00" level=info msg="zeroconf server listening on port 46665" Feb 17 10:55:23 volumio go-librespot[23912]: time="2026-02-17T10:55:23+07:00" level=debug msg="obtained new client token: AABRRMV2i1YkHPT9Kwen0JCM90TS3pk9lBa3UuOORlnZ1TYt3tIhN04g0DtqHiJBxVEB3q9I4+Hw3FdbHu51wbE6CzrarWeNMCbpPY7dJGL1vUgASyY6MripoKhGURAkWPdXtPdlp9ZFEchWpcCdrWkeTT7A1S2MgiXKZKktEYdL1QBPu7CzzrGnXmSfJSzyhc2CetpDcFtvkJaCAK+SDpAJlzX5xDfUUbtp7lUFG3bxOYijLmzEVEhFJQ==" Feb 17 10:55:23 volumio go-librespot[23912]: time="2026-02-17T10:55:23+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:55:24 volumio go-librespot[23912]: time="2026-02-17T10:55:24+07:00" level=debug msg="completed keyexchange" Feb 17 10:55:24 volumio go-librespot[23912]: time="2026-02-17T10:55:24+07:00" level=debug msg="completed challenge" Feb 17 10:55:24 volumio go-librespot[23912]: time="2026-02-17T10:55:24+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 17 10:55:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:55:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:55:24 volumio volumio[23582]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 17 10:55:24 volumio volumio[23582]: info: Initializing connection to go-librespot Websocket Feb 17 10:55:24 volumio volumio[23582]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 10:55:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 17 10:55:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:55:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:55:27 volumio go-librespot[23919]: go-librespot daemon starting... Feb 17 10:55:27 volumio go-librespot[23920]: time="2026-02-17T10:55:27+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:55:27 volumio go-librespot[23920]: time="2026-02-17T10:55:27+07:00" level=debug msg="app state loaded" Feb 17 10:55:27 volumio go-librespot[23920]: time="2026-02-17T10:55:27+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:55:27 volumio go-librespot[23920]: time="2026-02-17T10:55: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 17 10:55:27 volumio go-librespot[23920]: time="2026-02-17T10:55:27+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 17 10:55:27 volumio go-librespot[23920]: time="2026-02-17T10:55:27+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 17 10:55:27 volumio go-librespot[23920]: time="2026-02-17T10:55:27+07:00" level=info msg="zeroconf server listening on port 36771" Feb 17 10:55:27 volumio volumio[23582]: info: Initializing connection to go-librespot Websocket Feb 17 10:55:28 volumio go-librespot[23920]: time="2026-02-17T10:55:28+07:00" level=debug msg="new websocket client" Feb 17 10:55:28 volumio volumio[23582]: info: Connection to go-librespot Websocket established Feb 17 10:55:28 volumio go-librespot[23920]: time="2026-02-17T10:55:28+07:00" level=debug msg="obtained new client token: AAB1y8C7J660mDY1zh1EB3m5gMwatEth+L8gKSk7C1m3VIV6SegRQBcUmnRYC4rbdx3jR0gVDS2XRPIyz005Lp6Ur7pbRAiYBCZ7dsnZ0HCrwHOXpDoWxBVlXRQyWoubZXxd92PpCwFkWkzWDOQtKT63IOgS3fRRtcMSqZGe7bmWbEoLKgLG9Dx42dYASICkgVH4e1KWksPV+T/00FxgsC7/WWbrrLVdeItKFnru0buCi25uMzoHJl0=" Feb 17 10:55:28 volumio go-librespot[23920]: time="2026-02-17T10:55:28+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 17 10:55:28 volumio volumio[23582]: info: Adding plugin bluetooth to MyMusic Plugins Feb 17 10:55:28 volumio volumio[23582]: info: Adding plugin multiroom to MyMusic Plugins Feb 17 10:55:28 volumio volumio[23582]: info: Adding plugin metavolumio to MyMusic Plugins Feb 17 10:55:28 volumio volumio[23582]: info: Adding plugin cd_controller to MyMusic Plugins Feb 17 10:55:28 volumio volumio[23582]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 17 10:55:28 volumio volumio[23582]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 17 10:55:28 volumio volumio[23582]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 17 10:55:28 volumio volumio[23582]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 17 10:55:28 volumio go-librespot[23920]: time="2026-02-17T10:55:28+07:00" level=debug msg="completed keyexchange" Feb 17 10:55:28 volumio go-librespot[23920]: time="2026-02-17T10:55:28+07:00" level=debug msg="completed challenge" Feb 17 10:55:28 volumio go-librespot[23920]: time="2026-02-17T10:55:28+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 17 10:55:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:55:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:55:30 volumio volumio[23582]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 17 10:55:30 volumio volumio[23582]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 17 10:55:30 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:55:30 volumio volumio[23582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 10:55:30 volumio volumio[23582]: info: Starting MyVolumio Remote Streaming Endpoints Feb 17 10:55:30 volumio volumio[23582]: info: MyVolumio login type: Token Feb 17 10:55:30 volumio volumio[23582]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 17 10:55:30 volumio volumio[23582]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 17 10:55:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 17 10:55:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:55:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 10:55:31 volumio go-librespot[23942]: go-librespot daemon starting... Feb 17 10:55:31 volumio go-librespot[23943]: time="2026-02-17T10:55:31+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 10:55:31 volumio go-librespot[23943]: time="2026-02-17T10:55:31+07:00" level=debug msg="app state loaded" Feb 17 10:55:31 volumio go-librespot[23943]: time="2026-02-17T10:55:31+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 10:55:31 volumio volumio[23582]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 17 10:55:31 volumio volumio[23582]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 17 10:55:31 volumio volumio[23582]: info: Streaming services startup Feb 17 10:55:31 volumio volumio[23582]: info: Starting Streaming Daemon Feb 17 10:55:32 volumio sudo[23951]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 17 10:55:32 volumio sudo[23951]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 10:55:32 volumio volumio[23582]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 17 10:55:32 volumio sudo[23951]: pam_unix(sudo:session): session closed for user root Feb 17 10:55:32 volumio go-librespot[23943]: time="2026-02-17T10:55: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-gew1.spotify.com:80]" Feb 17 10:55:32 volumio go-librespot[23943]: time="2026-02-17T10:55:32+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 17 10:55:32 volumio go-librespot[23943]: time="2026-02-17T10:55:32+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 17 10:55:32 volumio volumio[23582]: info: Getting Spotify volume Feb 17 10:55:32 volumio go-librespot[23943]: time="2026-02-17T10:55:32+07:00" level=info msg="zeroconf server listening on port 33651" Feb 17 10:55:32 volumio volumio[23582]: info: Connection to go-librespot Websocket closed Feb 17 10:55:32 volumio volumio[23582]: error: Cannot start Volumio Streaming Daemon Feb 17 10:55:32 volumio volumio[23582]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 17 10:55:32 volumio volumio[23582]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 17 10:55:32 volumio volumio[23582]: 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 17 10:55:32 volumio go-librespot[23943]: time="2026-02-17T10:55:32+07:00" level=debug msg="obtained new client token: AACxO4YpK/gGlhdP9YRik0Cyn5lyj4gXE46lu0X849bYJgHlVmfs6n60+3dly3ineAmj8oxMG/TYKng5N7CBgPQzxPTYqKyJfachiwvH1tg1xigVFI6RLY3/5+RTz73NCpZwmlyhfivc/YxgPs1furhC6IMxejE0cs5D9B/wZaihYFvXdtP4VW1drM9+HwyRVZN+R/CCDpoaVMSMm2fkstlGDAZo7whVfu7GQwfYkG5YytZEhNCL8DQnLg==" Feb 17 10:55:32 volumio volumio[23582]: info: CoreCommandRouter::volumioGetState Feb 17 10:55:32 volumio volumio[23582]: info: CorePlayQueue::getTrack 0 Feb 17 10:55:32 volumio volumio[23582]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 17 10:55:32 volumio volumio[23582]: SPOTIFY: SPOTIFY VOLUME undefined Feb 17 10:55:32 volumio volumio[23582]: SPOTIFY: VOLUMIO VOLUME 100 Feb 17 10:55:32 volumio volumio[23582]: info: Aligning Spotify Volume to Volumio Volume Feb 17 10:55:32 volumio volumio[23582]: info: CoreCommandRouter::volumioGetState Feb 17 10:55:32 volumio volumio[23582]: info: CorePlayQueue::getTrack 0 Feb 17 10:55:32 volumio volumio[23582]: info: Setting Spotify Volume from Volumio: 100 Feb 17 10:55:32 volumio go-librespot[23943]: time="2026-02-17T10:55:32+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 10:55:32 volumio go-librespot[23943]: time="2026-02-17T10:55:32+07:00" level=debug msg="completed keyexchange" Feb 17 10:55:32 volumio go-librespot[23943]: time="2026-02-17T10:55:32+07:00" level=debug msg="completed challenge" Feb 17 10:55:32 volumio go-librespot[23943]: time="2026-02-17T10:55: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 17 10:55:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 10:55:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 10:55:32 volumio volumio[23582]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 10:55:32 volumio volumio[23582]: Error: socket hang up Feb 17 10:55:32 volumio volumio[23582]: at connResetException (node:internal/errors:720:14) Feb 17 10:55:32 volumio volumio[23582]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 17 10:55:32 volumio volumio[23582]: at Socket.emit (node:events:526:35) Feb 17 10:55:32 volumio volumio[23582]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 17 10:55:32 volumio volumio[23582]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 17 10:55:32 volumio volumio[23582]: code: 'ECONNRESET', Feb 17 10:55:32 volumio volumio[23582]: response: undefined Feb 17 10:55:32 volumio volumio[23582]: } Feb 17 10:55:32 volumio volumio[23582]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 10:55:34 volumio sudo[23971]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-17 10:54' Feb 17 10:55:34 volumio sudo[23971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="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"