Mar 24 17:26:00 volumio volumio[1003]: info: CoreCommandRouter::volumioGetState Mar 24 17:26:00 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 Mar 24 17:26:04 volumio volumio[1003]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/spop/4.3.4/volumio/bookworm/armhf Mar 24 17:26:04 volumio volumio5-onboarding[1514]: time=2026-03-24T17:26:04.625-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 24 17:26:06 volumio volumio[1003]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/download/spop/4.3.4/volumio/bookworm/armhf Mar 24 17:26:06 volumio volumio[1003]: info: Folder /tmp/plugins removed Mar 24 17:26:06 volumio volumio5-onboarding[1514]: time=2026-03-24T17:26:06.687-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 24 17:26:08 volumio volumio5-onboarding[1514]: time=2026-03-24T17:26:08.256-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 24 17:26:08 volumio volumio[1003]: info: Check plugin dependencies Mar 24 17:26:08 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 17:26:08 volumio volumio5-onboarding[1514]: time=2026-03-24T17:26:08.371-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 24 17:26:08 volumio volumio[1003]: info: Checking if plugin already exists Mar 24 17:26:08 volumio volumio5-onboarding[1514]: time=2026-03-24T17:26:08.389-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 24 17:26:08 volumio volumio[1003]: info: An error occurred installing the plugin. Rolling back config Mar 24 17:26:08 volumio volumio[1003]: info: Plugin folders cleanup Mar 24 17:26:08 volumio volumio[1003]: info: Scanning into folder /volumio/app/plugins/ Mar 24 17:26:08 volumio volumio[1003]: info: Scanning category audio_interface Mar 24 17:26:08 volumio volumio[1003]: info: Scanning category miscellanea Mar 24 17:26:08 volumio volumio[1003]: info: Scanning category music_service Mar 24 17:26:08 volumio volumio[1003]: info: Scanning category plugins.json Mar 24 17:26:08 volumio volumio[1003]: info: Scanning category system_controller Mar 24 17:26:08 volumio volumio[1003]: info: Scanning category user_interface Mar 24 17:26:08 volumio volumio[1003]: info: Scanning into folder /data/plugins/ Mar 24 17:26:08 volumio volumio[1003]: info: Scanning category music_service Mar 24 17:26:08 volumio volumio[1003]: info: Plugin folders cleanup completed Mar 24 17:26:08 volumio volumio[1003]: info: Error: Error Mar 24 17:26:08 volumio volumio[1003]: info: Folder /tmp/plugins removed Mar 24 17:26:08 volumio volumio[1003]: info: Folder /tmp/downloaded_plugin.zip removed Mar 24 17:26:08 volumio volumio[1003]: info: Folder /tmp/downloaded_plugin.zip removed Mar 24 17:26:09 volumio volumio[1003]: info: Folder /data/temp removed Mar 24 17:26:10 volumio volumio[1003]: info: CoreCommandRouter::volumioGetState Mar 24 17:26:10 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 Mar 24 17:26:10 volumio volumio[1003]: info: Listing playlists Mar 24 17:26:10 volumio volumio[1003]: info: Listing playlists Mar 24 17:26:20 volumio volumio[1003]: info: CoreCommandRouter::volumioGetState Mar 24 17:26:20 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 Mar 24 17:26:25 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 17:26:30 volumio volumio[1003]: info: CoreCommandRouter::volumioGetState Mar 24 17:26:30 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 Mar 24 17:26:30 volumio volumio[1003]: info: Listing playlists Mar 24 17:26:30 volumio volumio[1003]: info: Listing playlists Mar 24 17:26:36 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 17:26:40 volumio volumio[1003]: info: CoreCommandRouter::volumioGetState Mar 24 17:26:40 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 Mar 24 17:26:50 volumio volumio[1003]: info: CoreCommandRouter::volumioGetState Mar 24 17:26:50 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 Mar 24 17:26:50 volumio volumio[1003]: info: Listing playlists Mar 24 17:26:50 volumio volumio[1003]: info: Listing playlists Mar 24 17:26:53 volumio volumio[1003]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 17:27:00 volumio volumio[1003]: info: CoreCommandRouter::volumioGetState Mar 24 17:27:00 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 Mar 24 17:27:10 volumio volumio[1003]: info: CoreCommandRouter::volumioGetState Mar 24 17:27:10 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 Mar 24 17:27:10 volumio volumio[1003]: info: Listing playlists Mar 24 17:27:10 volumio volumio[1003]: info: Listing playlists Mar 24 17:27:14 volumio volumio[1003]: verbose: New Socket.io Connection to 192.168.2.183 from 192.168.2.117 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 24 17:27:14 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 24 17:27:14 volumio volumio[1003]: info: CoreCommandRouter::volumioGetVisibleSources Mar 24 17:27:14 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 17:27:14 volumio volumio[1003]: info: CoreCommandRouter::volumioGetState Mar 24 17:27:14 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 Mar 24 17:27:14 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 24 17:27:14 volumio volumio[1003]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 17:27:14 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 24 17:27:14 volumio volumio[1003]: info: Received Get System Info Mar 24 17:27:14 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 17:27:14 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 17:27:14 volumio volumio[1003]: info: Discovery: Getting this device information Mar 24 17:27:14 volumio volumio[1003]: info: CoreCommandRouter::volumioGetState Mar 24 17:27:14 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 Mar 24 17:27:14 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 17:27:14 volumio volumio[1003]: info: CoreCommandRouter::volumioGetState Mar 24 17:27:14 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 Mar 24 17:27:14 volumio volumio[1003]: info: Listing playlists Mar 24 17:27:14 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 24 17:27:14 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 24 17:27:14 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 24 17:27:15 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 24 17:27:16 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 17:27:16 volumio volumio[1003]: info: Received Get System Info Mar 24 17:27:16 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 17:27:16 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 17:27:16 volumio volumio[1003]: info: Discovery: Getting this device information Mar 24 17:27:16 volumio volumio[1003]: info: CoreCommandRouter::volumioGetState Mar 24 17:27:16 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 Mar 24 17:27:16 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 17:27:17 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 17:27:17 volumio volumio[1003]: info: Received Get System Info Mar 24 17:27:17 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 17:27:17 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 17:27:17 volumio volumio[1003]: info: Discovery: Getting this device information Mar 24 17:27:17 volumio volumio[1003]: info: CoreCommandRouter::volumioGetState Mar 24 17:27:17 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 Mar 24 17:27:17 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 17:27:17 volumio volumio[1003]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Mar 24 17:27:17 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Mar 24 17:27:17 volumio volumio[1003]: info: Creating Spotify config file Mar 24 17:27:17 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 17:27:17 volumio volumio[1003]: info: Spotify config file written Mar 24 17:27:17 volumio sudo[6387]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 24 17:27:17 volumio sudo[6387]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 17:27:17 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Mar 24 17:27:17 volumio systemd[1]: go-librespot-daemon.service: Killing process 5582 (go-librespot) with signal SIGKILL. Mar 24 17:27:17 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Mar 24 17:27:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 17:27:17 volumio volumio[1003]: info: Connection to go-librespot Websocket closed Mar 24 17:27:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 17:27:17 volumio go-librespot[6389]: go-librespot daemon starting... Mar 24 17:27:17 volumio sudo[6387]: pam_unix(sudo:session): session closed for user root Mar 24 17:27:17 volumio go-librespot[6390]: time="2026-03-24T17:27:17-06:00" level=info msg="running go-librespot 0.7.1" Mar 24 17:27:17 volumio go-librespot[6390]: time="2026-03-24T17:27:17-06:00" level=debug msg="app state loaded" Mar 24 17:27:17 volumio go-librespot[6390]: time="2026-03-24T17:27:17-06:00" level=debug msg="stored credentials not found" Mar 24 17:27:17 volumio go-librespot[6390]: time="2026-03-24T17:27:17-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 17:27:17 volumio go-librespot[6390]: time="2026-03-24T17:27:17-06: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]" Mar 24 17:27:17 volumio go-librespot[6390]: time="2026-03-24T17:27:17-06: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]" Mar 24 17:27:17 volumio go-librespot[6390]: time="2026-03-24T17:27:17-06: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]" Mar 24 17:27:18 volumio go-librespot[6390]: time="2026-03-24T17:27:18-06:00" level=info msg="zeroconf server listening on port 44981" Mar 24 17:27:18 volumio go-librespot[6390]: time="2026-03-24T17:27:18-06:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Mar 24 17:27:20 volumio volumio[1003]: info: CoreCommandRouter::volumioGetState Mar 24 17:27:20 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 Mar 24 17:27:20 volumio volumio[1003]: info: Initializing connection to go-librespot Websocket Mar 24 17:27:20 volumio go-librespot[6390]: time="2026-03-24T17:27:20-06:00" level=debug msg="new websocket client" Mar 24 17:27:20 volumio volumio[1003]: info: Connection to go-librespot Websocket established Mar 24 17:27:20 volumio volumio[1003]: info: go-librespot daemon successfully initialized Mar 24 17:27:23 volumio volumio[1003]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Mar 24 17:27:23 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Mar 24 17:27:23 volumio volumio[1003]: info: Creating Spotify config file Mar 24 17:27:23 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 17:27:23 volumio volumio[1003]: info: Spotify config file written Mar 24 17:27:23 volumio volumio[1003]: info: Getting Spotify volume Mar 24 17:27:23 volumio sudo[6414]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 24 17:27:23 volumio sudo[6414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 17:27:23 volumio volumio[1003]: info: Initializing connection to go-librespot Websocket Mar 24 17:27:23 volumio volumio[1003]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 24 17:27:23 volumio go-librespot[6390]: time="2026-03-24T17:27:23-06:00" level=debug msg="new websocket client" Mar 24 17:27:23 volumio volumio[1003]: info: Connection to go-librespot Websocket established Mar 24 17:27:23 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Mar 24 17:27:23 volumio systemd[1]: go-librespot-daemon.service: Killing process 6398 (go-librespot) with signal SIGKILL. Mar 24 17:27:23 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Mar 24 17:27:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 17:27:23 volumio volumio[1003]: info: Connection to go-librespot Websocket closed Mar 24 17:27:23 volumio volumio[1003]: info: Connection to go-librespot Websocket closed Mar 24 17:27:23 volumio volumio[1003]: info: CoreCommandRouter::volumioGetState Mar 24 17:27:23 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 Mar 24 17:27:23 volumio volumio[1003]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Mar 24 17:27:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 17:27:23 volumio go-librespot[6416]: go-librespot daemon starting... Mar 24 17:27:23 volumio sudo[6414]: pam_unix(sudo:session): session closed for user root Mar 24 17:27:23 volumio go-librespot[6418]: time="2026-03-24T17:27:23-06:00" level=info msg="running go-librespot 0.7.1" Mar 24 17:27:23 volumio go-librespot[6418]: time="2026-03-24T17:27:23-06:00" level=debug msg="app state loaded" Mar 24 17:27:23 volumio go-librespot[6418]: time="2026-03-24T17:27:23-06:00" level=debug msg="stored credentials not found" Mar 24 17:27:23 volumio go-librespot[6418]: time="2026-03-24T17:27:23-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 17:27:24 volumio volumio[1003]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Mar 24 17:27:24 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Mar 24 17:27:24 volumio volumio[1003]: info: Creating Spotify config file Mar 24 17:27:24 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 17:27:24 volumio go-librespot[6418]: time="2026-03-24T17:27:24-06:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]" Mar 24 17:27:24 volumio go-librespot[6418]: time="2026-03-24T17:27:24-06:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 24 17:27:24 volumio go-librespot[6418]: time="2026-03-24T17:27:24-06:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 24 17:27:24 volumio volumio[1003]: info: Spotify config file written Mar 24 17:27:24 volumio go-librespot[6418]: time="2026-03-24T17:27:24-06:00" level=info msg="zeroconf server listening on port 45147" Mar 24 17:27:24 volumio go-librespot[6418]: time="2026-03-24T17:27:24-06:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Mar 24 17:27:24 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 24 17:27:24 volumio sudo[6428]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 24 17:27:24 volumio sudo[6428]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 17:27:24 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Mar 24 17:27:24 volumio systemd[1]: go-librespot-daemon.service: Killing process 6423 (go-librespot) with signal SIGKILL. Mar 24 17:27:24 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Mar 24 17:27:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 17:27:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 17:27:24 volumio go-librespot[6430]: go-librespot daemon starting... Mar 24 17:27:24 volumio sudo[6428]: pam_unix(sudo:session): session closed for user root Mar 24 17:27:24 volumio go-librespot[6431]: time="2026-03-24T17:27:24-06:00" level=info msg="running go-librespot 0.7.1" Mar 24 17:27:24 volumio go-librespot[6431]: time="2026-03-24T17:27:24-06:00" level=debug msg="app state loaded" Mar 24 17:27:24 volumio go-librespot[6431]: time="2026-03-24T17:27:24-06:00" level=debug msg="stored credentials not found" Mar 24 17:27:24 volumio go-librespot[6431]: time="2026-03-24T17:27:24-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 17:27:24 volumio volumio[1003]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Mar 24 17:27:24 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Mar 24 17:27:24 volumio volumio[1003]: info: Creating Spotify config file Mar 24 17:27:24 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 17:27:24 volumio volumio[1003]: info: Spotify config file written Mar 24 17:27:24 volumio sudo[6441]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 24 17:27:24 volumio sudo[6441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 17:27:24 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Mar 24 17:27:24 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Mar 24 17:27:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 17:27:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 17:27:24 volumio go-librespot[6443]: go-librespot daemon starting... Mar 24 17:27:24 volumio sudo[6441]: pam_unix(sudo:session): session closed for user root Mar 24 17:27:24 volumio go-librespot[6445]: time="2026-03-24T17:27:24-06:00" level=info msg="running go-librespot 0.7.1" Mar 24 17:27:24 volumio go-librespot[6445]: time="2026-03-24T17:27:24-06:00" level=debug msg="app state loaded" Mar 24 17:27:24 volumio go-librespot[6445]: time="2026-03-24T17:27:24-06:00" level=debug msg="stored credentials not found" Mar 24 17:27:24 volumio volumio[1003]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Mar 24 17:27:24 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Mar 24 17:27:24 volumio volumio[1003]: info: Creating Spotify config file Mar 24 17:27:24 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 17:27:24 volumio volumio[1003]: info: Spotify config file written Mar 24 17:27:24 volumio go-librespot[6445]: time="2026-03-24T17:27:24-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 17:27:24 volumio sudo[6455]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 24 17:27:24 volumio sudo[6455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 17:27:25 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Mar 24 17:27:25 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Mar 24 17:27:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 17:27:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 17:27:25 volumio go-librespot[6457]: go-librespot daemon starting... Mar 24 17:27:25 volumio sudo[6455]: pam_unix(sudo:session): session closed for user root Mar 24 17:27:25 volumio volumio[1003]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Mar 24 17:27:25 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Mar 24 17:27:25 volumio volumio[1003]: info: Creating Spotify config file Mar 24 17:27:25 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 17:27:25 volumio volumio[1003]: info: Spotify config file written Mar 24 17:27:25 volumio go-librespot[6458]: time="2026-03-24T17:27:25-06:00" level=info msg="running go-librespot 0.7.1" Mar 24 17:27:25 volumio go-librespot[6458]: time="2026-03-24T17:27:25-06:00" level=debug msg="app state loaded" Mar 24 17:27:25 volumio go-librespot[6458]: time="2026-03-24T17:27:25-06:00" level=debug msg="stored credentials not found" Mar 24 17:27:25 volumio go-librespot[6458]: time="2026-03-24T17:27:25-06:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 17:27:25 volumio sudo[6465]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 24 17:27:25 volumio sudo[6465]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 17:27:25 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Mar 24 17:27:25 volumio systemd[1]: go-librespot-daemon.service: Killing process 6463 (go-librespot) with signal SIGKILL. Mar 24 17:27:25 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Mar 24 17:27:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 17:27:25 volumio systemd[1]: go-librespot-daemon.service: Start request repeated too quickly. Mar 24 17:27:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'start-limit-hit'. Mar 24 17:27:25 volumio systemd[1]: Failed to start go-librespot-daemon.service - go-librespot Daemon. Mar 24 17:27:25 volumio sudo[6465]: pam_unix(sudo:session): session closed for user root Mar 24 17:27:25 volumio volumio[1003]: error: Cannot start Go-librespot Daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Mar 24 17:27:25 volumio volumio[1003]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Mar 24 17:27:25 volumio volumio[1003]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Mar 24 17:27:25 volumio volumio[1003]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Mar 24 17:27:25 volumio volumio[1003]: followed by "systemctl start go-librespot-daemon.service" again. Mar 24 17:27:25 volumio volumio[1003]: error: Error initializing go-librespot daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Mar 24 17:27:25 volumio volumio[1003]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Mar 24 17:27:25 volumio volumio[1003]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Mar 24 17:27:25 volumio volumio[1003]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Mar 24 17:27:25 volumio volumio[1003]: followed by "systemctl start go-librespot-daemon.service" again. Mar 24 17:27:25 volumio volumio[1003]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Mar 24 17:27:25 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Mar 24 17:27:25 volumio volumio[1003]: info: Creating Spotify config file Mar 24 17:27:25 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 17:27:25 volumio volumio[1003]: info: Spotify config file written Mar 24 17:27:25 volumio sudo[6470]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 24 17:27:25 volumio sudo[6470]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 17:27:25 volumio systemd[1]: go-librespot-daemon.service: Start request repeated too quickly. Mar 24 17:27:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'start-limit-hit'. Mar 24 17:27:25 volumio systemd[1]: Failed to start go-librespot-daemon.service - go-librespot Daemon. Mar 24 17:27:25 volumio sudo[6470]: pam_unix(sudo:session): session closed for user root Mar 24 17:27:25 volumio volumio[1003]: error: Cannot start Go-librespot Daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Mar 24 17:27:25 volumio volumio[1003]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Mar 24 17:27:25 volumio volumio[1003]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Mar 24 17:27:25 volumio volumio[1003]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Mar 24 17:27:25 volumio volumio[1003]: followed by "systemctl start go-librespot-daemon.service" again. Mar 24 17:27:25 volumio volumio[1003]: error: Error initializing go-librespot daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Mar 24 17:27:25 volumio volumio[1003]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Mar 24 17:27:25 volumio volumio[1003]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Mar 24 17:27:25 volumio volumio[1003]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Mar 24 17:27:25 volumio volumio[1003]: followed by "systemctl start go-librespot-daemon.service" again. Mar 24 17:27:25 volumio volumio[1003]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Mar 24 17:27:25 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Mar 24 17:27:25 volumio volumio[1003]: info: Creating Spotify config file Mar 24 17:27:25 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 17:27:25 volumio volumio[1003]: info: Spotify config file written Mar 24 17:27:25 volumio sudo[6473]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 24 17:27:25 volumio sudo[6473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 17:27:25 volumio systemd[1]: go-librespot-daemon.service: Start request repeated too quickly. Mar 24 17:27:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'start-limit-hit'. Mar 24 17:27:25 volumio systemd[1]: Failed to start go-librespot-daemon.service - go-librespot Daemon. Mar 24 17:27:25 volumio sudo[6473]: pam_unix(sudo:session): session closed for user root Mar 24 17:27:25 volumio volumio[1003]: error: Cannot start Go-librespot Daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Mar 24 17:27:25 volumio volumio[1003]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Mar 24 17:27:25 volumio volumio[1003]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Mar 24 17:27:25 volumio volumio[1003]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Mar 24 17:27:25 volumio volumio[1003]: followed by "systemctl start go-librespot-daemon.service" again. Mar 24 17:27:25 volumio volumio[1003]: error: Error initializing go-librespot daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Mar 24 17:27:25 volumio volumio[1003]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Mar 24 17:27:25 volumio volumio[1003]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Mar 24 17:27:25 volumio volumio[1003]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Mar 24 17:27:25 volumio volumio[1003]: followed by "systemctl start go-librespot-daemon.service" again. Mar 24 17:27:25 volumio volumio[1003]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Mar 24 17:27:25 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Mar 24 17:27:25 volumio volumio[1003]: info: Creating Spotify config file Mar 24 17:27:25 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 17:27:25 volumio volumio[1003]: info: Spotify config file written Mar 24 17:27:25 volumio sudo[6476]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 24 17:27:25 volumio sudo[6476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 17:27:25 volumio systemd[1]: go-librespot-daemon.service: Start request repeated too quickly. Mar 24 17:27:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'start-limit-hit'. Mar 24 17:27:25 volumio systemd[1]: Failed to start go-librespot-daemon.service - go-librespot Daemon. Mar 24 17:27:25 volumio sudo[6476]: pam_unix(sudo:session): session closed for user root Mar 24 17:27:25 volumio volumio[1003]: error: Cannot start Go-librespot Daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Mar 24 17:27:25 volumio volumio[1003]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Mar 24 17:27:25 volumio volumio[1003]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Mar 24 17:27:25 volumio volumio[1003]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Mar 24 17:27:25 volumio volumio[1003]: followed by "systemctl start go-librespot-daemon.service" again. Mar 24 17:27:25 volumio volumio[1003]: error: Error initializing go-librespot daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Mar 24 17:27:25 volumio volumio[1003]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Mar 24 17:27:25 volumio volumio[1003]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Mar 24 17:27:25 volumio volumio[1003]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Mar 24 17:27:25 volumio volumio[1003]: followed by "systemctl start go-librespot-daemon.service" again. Mar 24 17:27:26 volumio volumio[1003]: info: Getting Spotify volume Mar 24 17:27:26 volumio volumio[1003]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 17:27:26 volumio volumio[1003]: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 17:27:26 volumio volumio[1003]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Mar 24 17:27:26 volumio volumio[1003]: errno: -111, Mar 24 17:27:26 volumio volumio[1003]: code: 'ECONNREFUSED', Mar 24 17:27:26 volumio volumio[1003]: syscall: 'connect', Mar 24 17:27:26 volumio volumio[1003]: address: '127.0.0.1', Mar 24 17:27:26 volumio volumio[1003]: port: 9879, Mar 24 17:27:26 volumio volumio[1003]: response: undefined Mar 24 17:27:26 volumio volumio[1003]: } Mar 24 17:27:26 volumio volumio[1003]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 17:27:27 volumio sudo[6492]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-24 17:26' Mar 24 17:27:27 volumio sudo[6492]: 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"