Aug 29 20:15:09 dragon volumio[1136]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/bookworm/armhf Aug 29 20:15:16 dragon volumio[1136]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/bookworm/armhf Aug 29 20:15:16 dragon go-librespot[1487]: time="2026-08-29T20:15:16-05:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Aug 29 20:15:16 dragon volumio[1136]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Aug 29 20:15:16 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 20:15:16 dragon volumio[1136]: Cannot find translation for source YouTube Music Aug 29 20:15:16 dragon sudo[2064]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Aug 29 20:15:16 dragon sudo[2064]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:15:16 dragon volumio[1136]: info: Connection to go-librespot Websocket closed Aug 29 20:15:16 dragon systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Aug 29 20:15:16 dragon systemd[1]: go-librespot-daemon.service: Deactivated successfully. Aug 29 20:15:16 dragon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 20:15:16 dragon systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 29 20:15:16 dragon systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 29 20:15:16 dragon systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 29 20:15:16 dragon systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 29 20:15:16 dragon systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 29 20:15:16 dragon systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 29 20:15:16 dragon sudo[2064]: pam_unix(sudo:session): session closed for user root Aug 29 20:15:18 dragon volumio[1136]: info: Check plugin dependencies Aug 29 20:15:18 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 20:15:18 dragon volumio[1136]: info: Rename folder Aug 29 20:15:18 dragon volumio[1136]: info: Folder /tmp/downloaded_plugin.zip removed Aug 29 20:15:18 dragon volumio[1136]: info: Move to category Aug 29 20:15:20 dragon volumio[1136]: info: Checking if install.sh is present Aug 29 20:15:20 dragon volumio[1136]: info: Executing install.sh Aug 29 20:15:21 dragon sudo[2115]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/spop/install.sh Aug 29 20:15:21 dragon sudo[2115]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:15:21 dragon volumio[1136]: info: Installing Go-librespot Aug 29 20:15:21 dragon volumio[1136]: info: Checking old vollibrespot installs Aug 29 20:15:21 dragon systemd[1]: Reloading. Aug 29 20:15:23 dragon volumio[1136]: info: Downloading daemon Aug 29 20:15:24 dragon systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 29 20:15:24 dragon systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 29 20:15:24 dragon systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 29 20:15:24 dragon systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 29 20:15:24 dragon systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 29 20:15:24 dragon systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 29 20:15:37 dragon volumio[1136]: info: Creating directories Aug 29 20:15:37 dragon volumio[1136]: info: Creating Start Script Aug 29 20:15:37 dragon systemd[1]: Reloading. Aug 29 20:15:39 dragon sudo[2115]: pam_unix(sudo:session): session closed for user root Aug 29 20:15:39 dragon volumio[1136]: Plugin install end detected on script Aug 29 20:15:39 dragon volumio[1136]: info: Install script completed Aug 29 20:15:39 dragon volumio[1136]: info: Done installing plugin. Aug 29 20:15:39 dragon volumio[1136]: info: Enabling plugin spop Aug 29 20:15:39 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 20:15:39 dragon volumio[1136]: info: [{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.4.2","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"YouTube Cast Receiver","name":"ytcr","category":"music_service","version":"2.1.0","icon":"fa-youtube","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"2.4.0","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true}] Aug 29 20:15:39 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 29 20:15:39 dragon systemd[1]: Starting fstrim.service - Discard unused blocks on filesystems from /etc/fstab... Aug 29 20:15:40 dragon volumio[1136]: info: Folder /tmp/plugins removed Aug 29 20:15:40 dragon volumio[1136]: info: Folder /tmp/downloaded_plugin.zip removed Aug 29 20:15:40 dragon volumio[1136]: info: Folder /data/temp removed Aug 29 20:15:40 dragon fstrim[2201]: /boot: 270.2 MiB (283295744 bytes) trimmed on /dev/mmcblk0p1 Aug 29 20:15:40 dragon systemd[1]: fstrim.service: Deactivated successfully. Aug 29 20:15:40 dragon systemd[1]: Finished fstrim.service - Discard unused blocks on filesystems from /etc/fstab. Aug 29 20:16:22 dragon volumio[1136]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 20:16:29 dragon volumio[1136]: info: Received OAUTH Data Aug 29 20:16:29 dragon volumio[1136]: info: Executing Spotify Oauth Login Aug 29 20:16:29 dragon volumio[1136]: info: Saving Spotify Refresh Token Aug 29 20:16:29 dragon sudo[2286]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 20:16:29 dragon sudo[2286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:16:29 dragon sudo[2286]: pam_unix(sudo:session): session closed for user root Aug 29 20:16:29 dragon sudo[2288]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 20:16:29 dragon sudo[2288]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:16:29 dragon sudo[2288]: pam_unix(sudo:session): session closed for user root Aug 29 20:16:29 dragon volumio[1136]: verbose: New Socket.io Connection to 192.168.40.6 from 192.168.40.12 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 29 20:16:29 dragon volumio[1136]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Aug 29 20:16:29 dragon volumio[1136]: SPOTIFY: BQCt2LWot59b1z6gHYR9w0QVVWaGrp4VKMQE4x2cnRwVprskDj_4Tb3IxaRsEnXbzj1D_-NPO4WJ3PLYpxN0iNYhFSdRpiwnGAUOIx7BAdAKPoV8WRU8kIRx75dD7pzcv-TxD_4Sa7F_vdLzH57l58f8_W9tRMjRZfHG-OtziEK6yqHmaf5m940zvyr6WhnZoOv1LHFKd7snDZQ650JLpdHBzMI0iL_B0Y2oa1KDElOEPbuNQ7qMynUtTxOnB2hhpaG2QoHYKsIyNbw6NjuTv-YlieV7nuurV2DNIoI3 Aug 29 20:16:29 dragon volumio[1136]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Aug 29 20:16:29 dragon volumio[1136]: info: New Spotify access token = BQCt2LWot59b1z6gHYR9w0QVVWaGrp4VKMQE4x2cnRwVprskDj_4Tb3IxaRsEnXbzj1D_-NPO4WJ3PLYpxN0iNYhFSdRpiwnGAUOIx7BAdAKPoV8WRU8kIRx75dD7pzcv-TxD_4Sa7F_vdLzH57l58f8_W9tRMjRZfHG-OtziEK6yqHmaf5m940zvyr6WhnZoOv1LHFKd7snDZQ650JLpdHBzMI0iL_B0Y2oa1KDElOEPbuNQ7qMynUtTxOnB2hhpaG2QoHYKsIyNbw6NjuTv-YlieV7nuurV2DNIoI3 Aug 29 20:16:29 dragon volumio[1136]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 29 20:16:29 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 20:16:29 dragon volumio[1136]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 20:16:29 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 20:16:29 dragon volumio[1136]: info: CoreCommandRouter::volumioGetState Aug 29 20:16:29 dragon volumio[1136]: info: CorePlayQueue::getTrack 0 Aug 29 20:16:29 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 20:16:29 dragon volumio[1136]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 20:16:29 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 20:16:29 dragon volumio[1136]: info: Received Get System Info Aug 29 20:16:29 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 20:16:29 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 20:16:29 dragon volumio[1136]: info: Discovery: Getting this device information Aug 29 20:16:29 dragon volumio[1136]: info: CoreCommandRouter::volumioGetState Aug 29 20:16:29 dragon volumio[1136]: info: CorePlayQueue::getTrack 0 Aug 29 20:16:29 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 20:16:29 dragon volumio[1136]: info: CoreCommandRouter::volumioGetState Aug 29 20:16:29 dragon volumio[1136]: info: CorePlayQueue::getTrack 0 Aug 29 20:16:29 dragon volumio[1136]: info: Listing playlists Aug 29 20:16:29 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 29 20:16:29 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 20:16:29 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 29 20:16:30 dragon volumio[1136]: SPOTIFY: User informations: {"account_id":"xJsXqtdGaE","country":"CO","display_name":"Luis Toscano Castilla","email":"commentariosweb@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/12125718319"},"followers":{"href":null,"total":39},"href":"https://api.spotify.com/v1/users/12125718319","id":"12125718319","images":[{"height":300,"url":"https://scontent-iad3-2.xx.fbcdn.net/v/t39.30808-1/475965661_10162516803328748_8047716657087339810_n.jpg?stp=dst-jpg_s320x320_tt6&_nc_cat=103&ccb=1-7&_nc_sid=08baa4&_nc_ohc=kT-PcRe47nMQ7kNvwG_oqUw&_nc_oc=AdolKFgTxOdYECf5ZDtgMt3qh5ue-c_kWkcSX7hRdNxoqAC2Xy2wPmOmKU7lmA0z9Grw8NGjL0ikQKCJZy4zWI0x&_nc_zt=24&_nc_ht=scontent-iad3-2.xx&edm=AP4hL3IEAAAA&_nc_gid=oep5lAKLuwSvGA5IKEreqA&_nc_tpa=Q5bMBQLLuu1jhB64zS_GwybtCoL5rlp08nWLimMw2eX_PWOdCGrS1CJT7jOgphhTOPk4hIBwKOzV&oh=00_AQJYdU-HYXGfhpdsYv0ICjSmKvYeigI3jMPo_Tp08Y3LbA&oe=6A991115","width":300},{"height":64,"url":"https://scontent-iad3-2.xx.fbcdn.net/v/t39.30808-1/475965661_10162516803328748_8047716657087339810_n.jpg?stp=cp0_dst-jpg_s50x50_tt6&_nc_cat=103&ccb=1-7&_nc_sid=28885b&_nc_ohc=kT-PcRe47nMQ7kNvwG_oqUw&_nc_oc=AdolKFgTxOdYECf5ZDtgMt3qh5ue-c_kWkcSX7hRdNxoqAC2Xy2wPmOmKU7lmA0z9Grw8NGjL0ikQKCJZy4zWI0x&_nc_zt=24&_nc_ht=scontent-iad3-2.xx&edm=AP4hL3IEAAAA&_nc_gid=oep5lAKLuwSvGA5IKEreqA&_nc_tpa=Q5bMBQJxJSYzbJ9-mgc5WyTTUqiXPYw6vFOCS5W3OCmzBAbEn0jFcmBv-4bcIno6Z6waJElNk1Ic&oh=00_AQKoY8oSnlL31wv5bcEuB0rHufrKdmUtUoUlM6vrGixYxQ&oe=6A991115","width":64}],"product":"premium","type":"user","uri":"spotify:user:12125718319"} Aug 29 20:16:30 dragon volumio[1136]: info: Creating Spotify config file Aug 29 20:16:30 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:16:30 dragon volumio[1136]: info: Spotify config file written Aug 29 20:16:30 dragon sudo[2295]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 29 20:16:30 dragon sudo[2295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:16:30 dragon systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 29 20:16:30 dragon systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 29 20:16:30 dragon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 20:16:30 dragon go-librespot[2297]: go-librespot daemon starting... Aug 29 20:16:30 dragon sudo[2295]: pam_unix(sudo:session): session closed for user root Aug 29 20:16:30 dragon go-librespot[2298]: time="2026-08-29T20:16:30-05:00" level=info msg="running go-librespot 0.7.1" Aug 29 20:16:30 dragon go-librespot[2298]: time="2026-08-29T20:16:30-05:00" level=debug msg="app state loaded" Aug 29 20:16:30 dragon go-librespot[2298]: time="2026-08-29T20:16:30-05:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 20:16:30 dragon volumio[1136]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Aug 29 20:16:30 dragon volumio[1136]: SPOTIFY: BQD8BhCadjEx9LhqF9crUcVV1BK6JgWb1clngEndYNLX6EecnHHnE7vIcAxvbRRvyCRKx6eYHoXFJ64Q8JKTjIFjmrBXNTw2bL7qSOUKfjjpRkFi7dJ1oUTRLgEiqRNpM__Br2mFkMrJLE7RYMaeKUXyssDgpYR7dXfLwVhSdo3xRZl0RPiTkn18Fse2FraUx2R-hEnrLD2MB6H8nrbPP2TmgyONaGUfcDpnu2Atz8re4ujGg813xUmej4r6RRt89_2XBv3cpiH-nvwNcODOWulSkwLMmxCYarzxyC6O Aug 29 20:16:30 dragon volumio[1136]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Aug 29 20:16:30 dragon volumio[1136]: info: New Spotify access token = BQD8BhCadjEx9LhqF9crUcVV1BK6JgWb1clngEndYNLX6EecnHHnE7vIcAxvbRRvyCRKx6eYHoXFJ64Q8JKTjIFjmrBXNTw2bL7qSOUKfjjpRkFi7dJ1oUTRLgEiqRNpM__Br2mFkMrJLE7RYMaeKUXyssDgpYR7dXfLwVhSdo3xRZl0RPiTkn18Fse2FraUx2R-hEnrLD2MB6H8nrbPP2TmgyONaGUfcDpnu2Atz8re4ujGg813xUmej4r6RRt89_2XBv3cpiH-nvwNcODOWulSkwLMmxCYarzxyC6O Aug 29 20:16:30 dragon volumio[1136]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 29 20:16:30 dragon volumio[1136]: SPOTIFY: User informations: {"account_id":"xJsXqtdGaE","country":"CO","display_name":"Luis Toscano Castilla","email":"commentariosweb@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/12125718319"},"followers":{"href":null,"total":39},"href":"https://api.spotify.com/v1/users/12125718319","id":"12125718319","images":[{"height":300,"url":"https://scontent-iad3-2.xx.fbcdn.net/v/t39.30808-1/475965661_10162516803328748_8047716657087339810_n.jpg?stp=dst-jpg_s320x320_tt6&_nc_cat=103&ccb=1-7&_nc_sid=08baa4&_nc_ohc=kT-PcRe47nMQ7kNvwG_oqUw&_nc_oc=AdolKFgTxOdYECf5ZDtgMt3qh5ue-c_kWkcSX7hRdNxoqAC2Xy2wPmOmKU7lmA0z9Grw8NGjL0ikQKCJZy4zWI0x&_nc_zt=24&_nc_ht=scontent-iad3-2.xx&edm=AP4hL3IEAAAA&_nc_gid=oep5lAKLuwSvGA5IKEreqA&_nc_tpa=Q5bMBQLLuu1jhB64zS_GwybtCoL5rlp08nWLimMw2eX_PWOdCGrS1CJT7jOgphhTOPk4hIBwKOzV&oh=00_AQJYdU-HYXGfhpdsYv0ICjSmKvYeigI3jMPo_Tp08Y3LbA&oe=6A991115","width":300},{"height":64,"url":"https://scontent-iad3-2.xx.fbcdn.net/v/t39.30808-1/475965661_10162516803328748_8047716657087339810_n.jpg?stp=cp0_dst-jpg_s50x50_tt6&_nc_cat=103&ccb=1-7&_nc_sid=28885b&_nc_ohc=kT-PcRe47nMQ7kNvwG_oqUw&_nc_oc=AdolKFgTxOdYECf5ZDtgMt3qh5ue-c_kWkcSX7hRdNxoqAC2Xy2wPmOmKU7lmA0z9Grw8NGjL0ikQKCJZy4zWI0x&_nc_zt=24&_nc_ht=scontent-iad3-2.xx&edm=AP4hL3IEAAAA&_nc_gid=oep5lAKLuwSvGA5IKEreqA&_nc_tpa=Q5bMBQJxJSYzbJ9-mgc5WyTTUqiXPYw6vFOCS5W3OCmzBAbEn0jFcmBv-4bcIno6Z6waJElNk1Ic&oh=00_AQKoY8oSnlL31wv5bcEuB0rHufrKdmUtUoUlM6vrGixYxQ&oe=6A991115","width":64}],"product":"premium","type":"user","uri":"spotify:user:12125718319"} Aug 29 20:16:30 dragon volumio[1136]: info: Spotify Successfully logged in Aug 29 20:16:30 dragon volumio[1136]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 20:16:30 dragon volumio[1136]: info: [1788052590964] CoreMusicLibrary::Adding element Spotify Aug 29 20:16:30 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 20:16:30 dragon volumio[1136]: Cannot find translation for source YouTube Music Aug 29 20:16:30 dragon volumio[1136]: Cannot find translation for source Spotify Aug 29 20:16:31 dragon go-librespot[2298]: time="2026-08-29T20:16:31-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 29 20:16:31 dragon go-librespot[2298]: time="2026-08-29T20:16:31-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 29 20:16:31 dragon go-librespot[2298]: time="2026-08-29T20:16:31-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 29 20:16:31 dragon go-librespot[2298]: time="2026-08-29T20:16:31-05:00" level=info msg="zeroconf server listening on port 33797" Aug 29 20:16:31 dragon go-librespot[2298]: time="2026-08-29T20:16:31-05:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 20:16:31 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 20:16:31 dragon volumio[1136]: info: Received Get System Info Aug 29 20:16:31 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 20:16:31 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 20:16:31 dragon volumio[1136]: info: Discovery: Getting this device information Aug 29 20:16:31 dragon volumio[1136]: info: CoreCommandRouter::volumioGetState Aug 29 20:16:31 dragon volumio[1136]: info: CorePlayQueue::getTrack 0 Aug 29 20:16:31 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 20:16:31 dragon go-librespot[2298]: time="2026-08-29T20:16:31-05:00" level=debug msg="obtained new client token: AAGmlthdoJEdPuMiipln/HO8+WPhK6J+6HDoVo51n/cmUC1gX+8h3lIQZqMZpcQ9HNv0lqjQOUUgX3QHYiiTrwIdofoP8CLn4MELCQdR2hDAXeOrCRZLkWb5EOc+Xo3/FkesTbrkZyT6jWk1IeKUHEP5cPllXvNvOR+JW2W1F1zuydiypJ6MO5r2phZmivRQAVUKs+dxCU3iZCZ7F4X0D2wgCrmg8iLKqP3XxXyNouVSuLshyqwm+AFAGg==" Aug 29 20:16:31 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 29 20:16:31 dragon go-librespot[2298]: time="2026-08-29T20:16:31-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 29 20:16:31 dragon go-librespot[2298]: time="2026-08-29T20:16:31-05:00" level=debug msg="completed keyexchange" Aug 29 20:16:31 dragon go-librespot[2298]: time="2026-08-29T20:16:31-05:00" level=debug msg="completed challenge" Aug 29 20:16:31 dragon go-librespot[2298]: time="2026-08-29T20:16:31-05:00" level=info msg="authenticated AP" username="12*******19" Aug 29 20:16:31 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 20:16:32 dragon go-librespot[2298]: time="2026-08-29T20:16:32-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 20:16:32 dragon volumio[1136]: info: Received Get System Info Aug 29 20:16:32 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 20:16:32 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 20:16:32 dragon volumio[1136]: info: Discovery: Getting this device information Aug 29 20:16:32 dragon volumio[1136]: info: CoreCommandRouter::volumioGetState Aug 29 20:16:32 dragon volumio[1136]: info: CorePlayQueue::getTrack 0 Aug 29 20:16:32 dragon volumio[1136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 20:16:32 dragon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 20:16:32 dragon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 20:16:33 dragon volumio[1136]: info: go-librespot daemon successfully initialized Aug 29 20:16:35 dragon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Aug 29 20:16:35 dragon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 20:16:35 dragon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 20:16:35 dragon go-librespot[2307]: go-librespot daemon starting... Aug 29 20:16:35 dragon go-librespot[2308]: time="2026-08-29T20:16:35-05:00" level=info msg="running go-librespot 0.7.1" Aug 29 20:16:35 dragon go-librespot[2308]: time="2026-08-29T20:16:35-05:00" level=debug msg="app state loaded" Aug 29 20:16:35 dragon go-librespot[2308]: time="2026-08-29T20:16:35-05:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 20:16:35 dragon go-librespot[2308]: time="2026-08-29T20:16:35-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 29 20:16:35 dragon go-librespot[2308]: time="2026-08-29T20:16:35-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 29 20:16:35 dragon go-librespot[2308]: time="2026-08-29T20:16:35-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 29 20:16:35 dragon go-librespot[2308]: time="2026-08-29T20:16:35-05:00" level=info msg="zeroconf server listening on port 43969" Aug 29 20:16:35 dragon go-librespot[2308]: time="2026-08-29T20:16:35-05:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 20:16:36 dragon go-librespot[2308]: time="2026-08-29T20:16:36-05:00" level=debug msg="obtained new client token: AAHxn2nLKpJ58fjBdOchq8chLY8pBgEoIlRJZeuTB+ytoqspp/XJ0PmuXTNpuUejdLBgNDXJJKeM/GZFNE16fDMagWUCuZzJ8Bn8nbX4yQgFP5ODLjSwB93YDazCI4sE6vftc2X/gf2phXEajbSuoVOZJLEp/4t2IPohr5Lk2Gyn6pMTmP9ce6qzUr0O0QdUcLpPT6Dxi0PgVy7U74filaeS657F21KElW8lio5vhWIyamleeHszL6o=" Aug 29 20:16:36 dragon go-librespot[2308]: time="2026-08-29T20:16:36-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 29 20:16:36 dragon volumio[1136]: info: Initializing connection to go-librespot Websocket Aug 29 20:16:36 dragon go-librespot[2308]: time="2026-08-29T20:16:36-05:00" level=debug msg="new websocket client" Aug 29 20:16:36 dragon volumio[1136]: info: Connection to go-librespot Websocket established Aug 29 20:16:36 dragon go-librespot[2308]: time="2026-08-29T20:16:36-05:00" level=debug msg="completed keyexchange" Aug 29 20:16:36 dragon go-librespot[2308]: time="2026-08-29T20:16:36-05:00" level=debug msg="completed challenge" Aug 29 20:16:36 dragon go-librespot[2308]: time="2026-08-29T20:16:36-05:00" level=info msg="authenticated AP" username="12*******19" Aug 29 20:16:36 dragon volumio[1136]: info: CoreCommandRouter::volumioGetState Aug 29 20:16:36 dragon volumio[1136]: info: CorePlayQueue::getTrack 0 Aug 29 20:16:36 dragon go-librespot[2308]: time="2026-08-29T20:16:36-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 20:16:36 dragon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 20:16:36 dragon volumio[1136]: info: Connection to go-librespot Websocket closed Aug 29 20:16:36 dragon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 20:16:39 dragon volumio[1136]: info: Getting Spotify volume Aug 29 20:16:39 dragon volumio[1136]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 20:16:39 dragon volumio[1136]: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:16:39 dragon volumio[1136]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Aug 29 20:16:39 dragon volumio[1136]: errno: -111, Aug 29 20:16:39 dragon volumio[1136]: code: 'ECONNREFUSED', Aug 29 20:16:39 dragon volumio[1136]: syscall: 'connect', Aug 29 20:16:39 dragon volumio[1136]: address: '127.0.0.1', Aug 29 20:16:39 dragon volumio[1136]: port: 9879, Aug 29 20:16:39 dragon volumio[1136]: response: undefined Aug 29 20:16:39 dragon volumio[1136]: } Aug 29 20:16:39 dragon volumio[1136]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 20:16:40 dragon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Aug 29 20:16:40 dragon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 20:16:40 dragon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 20:16:40 dragon go-librespot[2345]: go-librespot daemon starting... Aug 29 20:16:40 dragon go-librespot[2346]: time="2026-08-29T20:16:40-05:00" level=info msg="running go-librespot 0.7.1" Aug 29 20:16:40 dragon go-librespot[2346]: time="2026-08-29T20:16:40-05:00" level=debug msg="app state loaded" Aug 29 20:16:40 dragon go-librespot[2346]: time="2026-08-29T20:16:40-05:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 20:16:40 dragon sudo[2357]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-29 20:15' Aug 29 20:16:40 dragon sudo[2357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:16:40 dragon go-librespot[2346]: time="2026-08-29T20:16:40-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]" Aug 29 20:16:40 dragon go-librespot[2346]: time="2026-08-29T20:16:40-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 29 20:16:40 dragon go-librespot[2346]: time="2026-08-29T20:16:40-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" 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="3a3cfe435f8bb2bc94a920662e91a4ef35bc8e7c" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Dec 2 11:51:19 UTC 2025" VOLUMIO_VERSION="4.072" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e5001581b220b096097cf8f46a54d4f1"