Feb 23 17:51:02 volumio volumio[1391]: info: CoreCommandRouter::volumioGetState Feb 23 17:51:02 volumio volumio[1391]: info: CorePlayQueue::getTrack 0 Feb 23 17:51:16 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 17:51:16 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 23 17:51:20 volumio volumio[1391]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 17:51:33 volumio volumio5-onboarding[1749]: time=2026-02-23T17:51:33.558+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.181:52763 Feb 23 17:51:33 volumio volumio5-onboarding[1749]: time=2026-02-23T17:51:33.600+01:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.0.181:52763 @ 0x1b83260" latency=110.042509ms timeout=10s Feb 23 17:51:33 volumio volumio5-onboarding[1749]: time=2026-02-23T17:51:33.600+01:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.0.181:52763 @ 0x1b83260" Feb 23 17:51:33 volumio volumio5-onboarding[1749]: time=2026-02-23T17:51:33.600+01:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.0.181:52763 @ 0x1b83260" latency=110.059212ms platform=PLATFORM_IOS version=5.260120.0 Feb 23 17:51:33 volumio volumio[1391]: info: Received Get System Info Feb 23 17:51:33 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 17:51:33 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 17:51:33 volumio volumio[1391]: info: Discovery: Getting this device information Feb 23 17:51:33 volumio volumio[1391]: info: CoreCommandRouter::volumioGetState Feb 23 17:51:33 volumio volumio[1391]: info: CorePlayQueue::getTrack 0 Feb 23 17:51:33 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 17:51:33 volumio volumio5-onboarding[1749]: time=2026-02-23T17:51:33.602+01:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.0.181:52763 @ 0x1b83260" name=Volumio Feb 23 17:51:33 volumio volumio5-onboarding[1749]: time=2026-02-23T17:51:33.602+01:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.0.181:52763 @ 0x1b83260" language=pl Feb 23 17:51:33 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 23 17:51:33 volumio volumio5-onboarding[1749]: time=2026-02-23T17:51:33.603+01:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.0.181:52763 @ 0x1b83260" timezone=Europe/Warsaw Feb 23 17:51:33 volumio volumio5-onboarding[1749]: time=2026-02-23T17:51:33.604+01:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.0.181:52763 @ 0x1b83260" available=true connected=true macAddress=88:a2:9e:87:9c:41 ip4Address=192.168.0.191/24 ip6Address= Feb 23 17:51:33 volumio volumio5-onboarding[1749]: time=2026-02-23T17:51:33.604+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.0.181:52763 @ 0x1b83260" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 23 17:51:33 volumio volumio5-onboarding[1749]: time=2026-02-23T17:51:33.605+01:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.0.181:52763 @ 0x1b83260" setupComplete=true Feb 23 17:51:33 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Feb 23 17:51:33 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 23 17:51:33 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 17:51:33 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 23 17:51:33 volumio volumio[1391]: amixer -c 0 info | grep "vc4-hdmi-0" Feb 23 17:51:33 volumio volumio[1391]: Card sysdefault:0 'vc4hdmi0'/'vc4-hdmi-0' Feb 23 17:51:33 volumio volumio[1391]: amixer -c 1 info | grep "vc4-hdmi-1" Feb 23 17:51:33 volumio volumio[1391]: Card sysdefault:1 'vc4hdmi1'/'vc4-hdmi-1' Feb 23 17:51:33 volumio volumio[1391]: amixer -c 2 info | grep "snd_rpi_hifiberry_dac" Feb 23 17:51:33 volumio volumio[1391]: Card sysdefault:2 'sndrpihifiberry'/'snd_rpi_hifiberry_dac' Feb 23 17:51:33 volumio volumio[1391]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 2 Feb 23 17:51:33 volumio volumio[1391]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 23 17:51:33 volumio volumio[1391]: {"cmd":"/usr/local/bin/alsacap -C 2","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 2\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Feb 23 17:51:33 volumio volumio[1391]: amixer -c 2 info | grep "Generic I2S DAC" Feb 23 17:51:33 volumio volumio5-onboarding[1749]: time=2026-02-23T17:51:33.681+01:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.0.181:52763 @ 0x1b83260" selectedOutputId=2 Feb 23 17:51:33 volumio volumio[1391]: info: Received Get System Info Feb 23 17:51:33 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 17:51:33 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 17:51:33 volumio volumio[1391]: info: Discovery: Getting this device information Feb 23 17:51:33 volumio volumio[1391]: info: CoreCommandRouter::volumioGetState Feb 23 17:51:33 volumio volumio[1391]: info: CorePlayQueue::getTrack 0 Feb 23 17:51:33 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 17:51:33 volumio volumio5-onboarding[1749]: time=2026-02-23T17:51:33.704+01:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.0.181:52763 @ 0x1b83260" currentVersion=4.096 latestVersion=4.096 Feb 23 17:51:33 volumio volumio5-onboarding[1749]: time=2026-02-23T17:51:33.704+01:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.0.181:52763 @ 0x1b83260" status=UPDATE_STATUS_NONE progress=0 Feb 23 17:51:33 volumio volumio5-onboarding[1749]: time=2026-02-23T17:51:33.704+01:00 level=INFO msg="emitting user changed event" component=server peer="192.168.0.181:52763 @ 0x1b83260" userId= Feb 23 17:51:33 volumio volumio5-onboarding[1749]: time=2026-02-23T17:51:33.704+01:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.0.181:52763 @ 0x1b83260" providers=3 Feb 23 17:51:33 volumio volumio5-onboarding[1749]: time=2026-02-23T17:51:33.704+01:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.0.181:52763 @ 0x1b83260" plugins=63 Feb 23 17:51:33 volumio volumio[1391]: info: CoreCommandRouter::volumioGetState Feb 23 17:51:33 volumio volumio[1391]: info: CorePlayQueue::getTrack 0 Feb 23 17:51:33 volumio volumio5-onboarding[1749]: time=2026-02-23T17:51:33.705+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.181:52763 @ 0x1b83260" state=STATUS_STOPPED positionMs=0 volume=100 Feb 23 17:51:33 volumio volumio5-onboarding[1749]: time=2026-02-23T17:51:33.705+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.181:52763 @ 0x1b83260" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1811688" title="Radioparty.pl - Energy2000" Feb 23 17:51:33 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 17:51:33 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 17:51:33 volumio volumio[1391]: info: Discovery: Getting this device information Feb 23 17:51:33 volumio volumio[1391]: info: CoreCommandRouter::volumioGetState Feb 23 17:51:33 volumio volumio[1391]: info: CorePlayQueue::getTrack 0 Feb 23 17:51:33 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 17:51:33 volumio volumio[1391]: verbose: New Socket.io Connection to 192.168.0.191:3000 from 192.168.0.181 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Feb 23 17:51:33 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 23 17:51:33 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 23 17:51:34 volumio volumio5-onboarding[1749]: time=2026-02-23T17:51:34.430+01:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.181:52763 @ 0x1b83260" latency=100.113554ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Feb 23 17:51:35 volumio sudo[21419]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 17:51:35 volumio sudo[21419]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 17:51:35 volumio sudo[21419]: pam_unix(sudo:session): session closed for user root Feb 23 17:51:35 volumio sudo[21421]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 17:51:35 volumio sudo[21421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 17:51:35 volumio sudo[21421]: pam_unix(sudo:session): session closed for user root Feb 23 17:51:35 volumio volumio[1391]: verbose: New Socket.io Connection to 192.168.0.191 from 192.168.0.181 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Feb 23 17:51:35 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 23 17:51:35 volumio sudo[21439]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 17:51:35 volumio sudo[21439]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 17:51:35 volumio sudo[21439]: pam_unix(sudo:session): session closed for user root Feb 23 17:51:35 volumio sudo[21441]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 17:51:35 volumio sudo[21441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 17:51:35 volumio sudo[21441]: pam_unix(sudo:session): session closed for user root Feb 23 17:51:35 volumio volumio[1391]: verbose: New Socket.io Connection to 192.168.0.191 from 192.168.0.181 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Feb 23 17:51:35 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 23 17:51:35 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 23 17:51:35 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 23 17:51:35 volumio volumio[1391]: info: CoreCommandRouter::volumioGetVisibleSources Feb 23 17:51:35 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 17:51:35 volumio volumio[1391]: info: CoreCommandRouter::volumioGetState Feb 23 17:51:35 volumio volumio[1391]: info: CorePlayQueue::getTrack 0 Feb 23 17:51:35 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 23 17:51:35 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 23 17:51:35 volumio volumio[1391]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 23 17:51:35 volumio volumio[1391]: info: Received Get System Info Feb 23 17:51:35 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 17:51:35 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 17:51:35 volumio volumio[1391]: info: Discovery: Getting this device information Feb 23 17:51:35 volumio volumio[1391]: info: CoreCommandRouter::volumioGetState Feb 23 17:51:35 volumio volumio[1391]: info: CorePlayQueue::getTrack 0 Feb 23 17:51:35 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 17:51:35 volumio volumio[1391]: info: CoreCommandRouter::volumioGetState Feb 23 17:51:35 volumio volumio[1391]: info: CorePlayQueue::getTrack 0 Feb 23 17:51:35 volumio volumio[1391]: info: Listing playlists Feb 23 17:51:35 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 23 17:51:35 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 23 17:51:37 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 23 17:51:37 volumio volumio5-onboarding[1749]: time=2026-02-23T17:51:37.143+01:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.0.209:52227 error="websocket: close 1006 (abnormal closure): unexpected EOF" Feb 23 17:51:37 volumio volumio5-onboarding[1749]: time=2026-02-23T17:51:37.144+01:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.0.209:52227 Feb 23 17:51:37 volumio volumio5-onboarding[1749]: time=2026-02-23T17:51:37.144+01:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.0.209:52227 Feb 23 17:51:37 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 17:51:37 volumio volumio[1391]: info: Received Get System Info Feb 23 17:51:37 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 17:51:37 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 17:51:37 volumio volumio[1391]: info: Discovery: Getting this device information Feb 23 17:51:37 volumio volumio[1391]: info: CoreCommandRouter::volumioGetState Feb 23 17:51:37 volumio volumio[1391]: info: CorePlayQueue::getTrack 0 Feb 23 17:51:37 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 17:51:37 volumio volumio[1391]: info: CoreCommandRouter::volumioGetQueue Feb 23 17:51:37 volumio volumio[1391]: info: CoreStateMachine::getQueue Feb 23 17:51:37 volumio volumio[1391]: info: CorePlayQueue::getQueue Feb 23 17:51:39 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 17:51:39 volumio volumio[1391]: info: Received Get System Info Feb 23 17:51:39 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 17:51:39 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 17:51:39 volumio volumio[1391]: info: Discovery: Getting this device information Feb 23 17:51:39 volumio volumio[1391]: info: CoreCommandRouter::volumioGetState Feb 23 17:51:39 volumio volumio[1391]: info: CorePlayQueue::getTrack 0 Feb 23 17:51:39 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 17:51:41 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 17:51:41 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 23 17:51:44 volumio volumio[1391]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 17:51:45 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 23 17:51:48 volumio volumio[1391]: info: Received OAUTH Data Feb 23 17:51:48 volumio volumio[1391]: info: Executing Spotify Oauth Login Feb 23 17:51:48 volumio volumio[1391]: info: Saving Spotify Refresh Token Feb 23 17:51:48 volumio sudo[21462]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 17:51:48 volumio sudo[21462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 17:51:48 volumio sudo[21464]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 17:51:48 volumio sudo[21464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 17:51:48 volumio sudo[21462]: pam_unix(sudo:session): session closed for user root Feb 23 17:51:48 volumio sudo[21464]: pam_unix(sudo:session): session closed for user root Feb 23 17:51:48 volumio volumio[1391]: verbose: New Socket.io Connection to 192.168.0.191 from 192.168.0.181 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Feb 23 17:51:48 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 23 17:51:48 volumio volumio[1391]: info: CoreCommandRouter::volumioGetVisibleSources Feb 23 17:51:48 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 17:51:48 volumio volumio[1391]: info: CoreCommandRouter::volumioGetState Feb 23 17:51:48 volumio volumio[1391]: info: CorePlayQueue::getTrack 0 Feb 23 17:51:48 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 23 17:51:48 volumio volumio[1391]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 17:51:48 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 23 17:51:48 volumio volumio[1391]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 23 17:51:48 volumio volumio[1391]: info: Received Get System Info Feb 23 17:51:48 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 17:51:48 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 17:51:48 volumio volumio[1391]: info: Discovery: Getting this device information Feb 23 17:51:48 volumio volumio[1391]: info: CoreCommandRouter::volumioGetState Feb 23 17:51:48 volumio volumio[1391]: info: CorePlayQueue::getTrack 0 Feb 23 17:51:48 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 17:51:48 volumio volumio[1391]: info: CoreCommandRouter::volumioGetState Feb 23 17:51:48 volumio volumio[1391]: info: CorePlayQueue::getTrack 0 Feb 23 17:51:48 volumio volumio[1391]: info: Listing playlists Feb 23 17:51:48 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 23 17:51:48 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 23 17:51:48 volumio volumio[1391]: info: New Spotify access tokenBQDnDcilug... Feb 23 17:51:48 volumio volumio[1391]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 23 17:51:48 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 23 17:51:49 volumio volumio[1391]: SPOTIFY: User informations: {"country":"PL","display_name":"zmaniekok3","email":"zmaniekok3@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/zmaniekok3"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/zmaniekok3","id":"zmaniekok3","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee85532a9c1ffb7aa70c53ca943e","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b82532a9c1ffb7aa70c53ca943e","width":64}],"product":"premium","type":"user","uri":"spotify:user:zmaniekok3"} Feb 23 17:51:49 volumio volumio[1391]: info: Creating Spotify config file Feb 23 17:51:49 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 17:51:49 volumio volumio[1391]: info: Spotify config file written Feb 23 17:51:49 volumio sudo[21468]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 23 17:51:49 volumio sudo[21468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 17:51:49 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 23 17:51:49 volumio systemd[1]: go-librespot-daemon.service: Killing process 20988 (go-librespot) with signal SIGKILL. Feb 23 17:51:49 volumio systemd[1]: go-librespot-daemon.service: Killing process 20989 (go-librespot) with signal SIGKILL. Feb 23 17:51:49 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 23 17:51:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 17:51:49 volumio volumio[1391]: info: Connection to go-librespot Websocket closed Feb 23 17:51:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 17:51:49 volumio go-librespot[21470]: go-librespot daemon starting... Feb 23 17:51:49 volumio sudo[21468]: pam_unix(sudo:session): session closed for user root Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=info msg="running go-librespot 0.7.0" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=debug msg="app state loaded" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=debug msg="stored credentials not found" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 17:51:49 volumio volumio[1391]: info: New Spotify access tokenBQBcgRtLoa... Feb 23 17:51:49 volumio volumio[1391]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=info msg="zeroconf server listening on port 37287" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Feb 23 17:51:49 volumio volumio[1391]: SPOTIFY: User informations: {"country":"PL","display_name":"zmaniekok3","email":"zmaniekok3@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/zmaniekok3"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/zmaniekok3","id":"zmaniekok3","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee85532a9c1ffb7aa70c53ca943e","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b82532a9c1ffb7aa70c53ca943e","width":64}],"product":"premium","type":"user","uri":"spotify:user:zmaniekok3"} Feb 23 17:51:49 volumio volumio[1391]: info: Spotify Successfully logged in Feb 23 17:51:49 volumio volumio[1391]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 17:51:49 volumio volumio[1391]: info: [1771865509365] CoreMusicLibrary::Adding element Spotify Feb 23 17:51:49 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 17:51:49 volumio volumio[1391]: Cannot find translation for source TIDAL Feb 23 17:51:49 volumio volumio[1391]: Cannot find translation for source Spotify Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=debug msg="obtained new client token: AABPcERmUfJL6emNUNI3FsL+My0gHRHWVAt5Nn38gta+RVewvx1sRDUHPdYz2SfpYVig18ll2GR69cwthnDOkiC3ziKngQf3UiBrL0ndztROSlKuYbySpbu4JIE94D/tR4w2STPoM2JR37YBj7NsUd722L1G8i6MnjQl6//k/DVPB5/m8UU2eBbyTJ0glV/MEBuDCo+adzLVp1XAV+V0daSHaGN/sdbVNDzM6UzGnc/rnHAFAIZZdWQZNkU=" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.1.133:443: connect: connection refused" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=debug msg="completed keyexchange" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=debug msg="completed challenge" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=info msg="authenticated AP" username="zm******k3" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=info msg="authenticated Login5" username="zm******k3" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=debug msg="stored credentials" username="zm******k3" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=debug msg="initializing zeroconf session" username="zm******k3" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=debug msg="dealer connection opened" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=trace msg="starting accesspoint recv loop" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=trace msg="starting dealer recv loop" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=trace msg="received accesspoint ping" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 23 17:51:49 volumio go-librespot[21473]: time="2026-02-23T17:51:49+01:00" level=debug msg="received connection id: N2MzZGVhMDktZjIw...NjEyMTI1OUU4Qw==" Feb 23 17:51:50 volumio go-librespot[21473]: time="2026-02-23T17:51:50+01:00" level=trace msg="received accesspoint pong ack" Feb 23 17:51:50 volumio go-librespot[21473]: time="2026-02-23T17:51:50+01:00" level=debug msg="put connect state because NEW_DEVICE" Feb 23 17:51:50 volumio go-librespot[21473]: time="2026-02-23T17:51:50+01:00" level=debug msg="update volume requested to 65535/65535" Feb 23 17:51:50 volumio go-librespot[21473]: time="2026-02-23T17:51:50+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 23 17:51:50 volumio go-librespot[21473]: time="2026-02-23T17:51:50+01:00" level=trace msg="emitting websocket event: volume" Feb 23 17:51:50 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 23 17:51:50 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 17:51:50 volumio volumio[1391]: info: Received Get System Info Feb 23 17:51:50 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 17:51:50 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 17:51:50 volumio volumio[1391]: info: Discovery: Getting this device information Feb 23 17:51:50 volumio volumio[1391]: info: CoreCommandRouter::volumioGetState Feb 23 17:51:50 volumio volumio[1391]: info: CorePlayQueue::getTrack 0 Feb 23 17:51:50 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 17:51:51 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 17:51:51 volumio volumio[1391]: info: Received Get System Info Feb 23 17:51:51 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 17:51:51 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 17:51:51 volumio volumio[1391]: info: Discovery: Getting this device information Feb 23 17:51:51 volumio volumio[1391]: info: CoreCommandRouter::volumioGetState Feb 23 17:51:51 volumio volumio[1391]: info: CorePlayQueue::getTrack 0 Feb 23 17:51:51 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 17:51:52 volumio volumio[1391]: info: Initializing connection to go-librespot Websocket Feb 23 17:51:52 volumio go-librespot[21473]: time="2026-02-23T17:51:52+01:00" level=debug msg="new websocket client" Feb 23 17:51:52 volumio volumio[1391]: info: Connection to go-librespot Websocket established Feb 23 17:51:52 volumio volumio[1391]: info: go-librespot daemon successfully initialized Feb 23 17:51:55 volumio volumio[1391]: info: Getting Spotify volume Feb 23 17:51:55 volumio volumio[1391]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Feb 23 17:51:55 volumio volumio[1391]: info: Spotify volume: 100 Feb 23 17:51:55 volumio volumio[1391]: info: CoreCommandRouter::volumioGetState Feb 23 17:51:55 volumio volumio[1391]: info: CorePlayQueue::getTrack 0 Feb 23 17:51:55 volumio volumio[1391]: info: Initializing connection to go-librespot Websocket Feb 23 17:51:55 volumio go-librespot[21473]: time="2026-02-23T17:51:55+01:00" level=debug msg="new websocket client" Feb 23 17:51:55 volumio volumio[1391]: info: Connection to go-librespot Websocket established Feb 23 17:51:58 volumio volumio[1391]: info: Getting Spotify volume Feb 23 17:51:58 volumio volumio[1391]: info: Spotify volume: 100 Feb 23 17:51:58 volumio volumio[1391]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Feb 23 17:51:58 volumio volumio[1391]: info: CoreCommandRouter::volumioGetState Feb 23 17:51:58 volumio volumio[1391]: info: CorePlayQueue::getTrack 0 Feb 23 17:51:58 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 23 17:51:58 volumio volumio[1391]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Feb 23 17:51:58 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Feb 23 17:51:58 volumio volumio[1391]: info: Creating Spotify config file Feb 23 17:51:58 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 17:51:58 volumio volumio[1391]: info: Spotify config file written Feb 23 17:51:58 volumio sudo[21498]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 23 17:51:58 volumio sudo[21498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 17:51:58 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 23 17:51:58 volumio systemd[1]: go-librespot-daemon.service: Killing process 21474 (go-librespot) with signal SIGKILL. Feb 23 17:51:58 volumio systemd[1]: go-librespot-daemon.service: Killing process 21477 (go-librespot) with signal SIGKILL. Feb 23 17:51:58 volumio systemd[1]: go-librespot-daemon.service: Killing process 21478 (go-librespot) with signal SIGKILL. Feb 23 17:51:58 volumio systemd[1]: go-librespot-daemon.service: Killing process 21480 (go-librespot) with signal SIGKILL. Feb 23 17:51:58 volumio volumio[1391]: info: Connection to go-librespot Websocket closed Feb 23 17:51:58 volumio volumio[1391]: info: Connection to go-librespot Websocket closed Feb 23 17:51:58 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 23 17:51:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 17:51:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 17:51:58 volumio go-librespot[21500]: go-librespot daemon starting... Feb 23 17:51:58 volumio sudo[21498]: pam_unix(sudo:session): session closed for user root Feb 23 17:51:58 volumio go-librespot[21501]: time="2026-02-23T17:51:58+01:00" level=info msg="running go-librespot 0.7.0" Feb 23 17:51:58 volumio go-librespot[21501]: time="2026-02-23T17:51:58+01:00" level=debug msg="app state loaded" Feb 23 17:51:58 volumio go-librespot[21501]: time="2026-02-23T17:51:58+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=info msg="zeroconf server listening on port 45923" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=debug msg="obtained new client token: AADg55B+SxXxfLKyizv5m51kQWGOsVcCcr1sxnt/FB4pZSbVkxxdhxWPzw+gBkw/FUzUKlMTl5vwveauJ7NxqekVZJOvOs64QXMGddjcDftkyGFswYmyLjN5ZAegRfU0s4KZET2DyzwrnzQ0PTiGpVgHlEzWBU9m+//GyjqkH1SwitvAjmIaGaJUP8emCH/e5Atm00BXuFjcAreRUUJLuJAAB6Bcum/ZZ7QDX0nP45wPn19O6kOG1ufG" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=debug msg="completed keyexchange" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=debug msg="completed challenge" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=info msg="authenticated AP" username="zm******k3" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=info msg="authenticated Login5" username="zm******k3" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=debug msg="initializing zeroconf session" username="zm******k3" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=debug msg="dealer connection opened" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=trace msg="starting accesspoint recv loop" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=trace msg="starting dealer recv loop" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=trace msg="received accesspoint ping" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=debug msg="received connection id: NWYxYWMzZTUtODhh...MkY2NUFFMjFDQQ==" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=trace msg="received accesspoint pong ack" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=debug msg="put connect state because NEW_DEVICE" Feb 23 17:51:59 volumio go-librespot[21501]: time="2026-02-23T17:51:59+01:00" level=debug msg="update volume requested to 65535/65535" Feb 23 17:52:00 volumio go-librespot[21501]: time="2026-02-23T17:52:00+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 23 17:52:00 volumio go-librespot[21501]: time="2026-02-23T17:52:00+01:00" level=trace msg="emitting websocket event: volume" Feb 23 17:52:00 volumio volumio[1391]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Feb 23 17:52:00 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Feb 23 17:52:00 volumio volumio[1391]: info: Creating Spotify config file Feb 23 17:52:00 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 17:52:00 volumio volumio[1391]: info: Spotify config file written Feb 23 17:52:00 volumio sudo[21511]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 23 17:52:00 volumio sudo[21511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 17:52:00 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 23 17:52:00 volumio systemd[1]: go-librespot-daemon.service: Killing process 21505 (go-librespot) with signal SIGKILL. Feb 23 17:52:00 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 23 17:52:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 17:52:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 17:52:00 volumio sudo[21511]: pam_unix(sudo:session): session closed for user root Feb 23 17:52:00 volumio go-librespot[21513]: go-librespot daemon starting... Feb 23 17:52:00 volumio go-librespot[21514]: time="2026-02-23T17:52:00+01:00" level=info msg="running go-librespot 0.7.0" Feb 23 17:52:00 volumio go-librespot[21514]: time="2026-02-23T17:52:00+01:00" level=debug msg="app state loaded" Feb 23 17:52:00 volumio go-librespot[21514]: time="2026-02-23T17:52:00+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 17:52:00 volumio go-librespot[21514]: time="2026-02-23T17:52:00+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 23 17:52:00 volumio go-librespot[21514]: time="2026-02-23T17:52:00+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 23 17:52:00 volumio go-librespot[21514]: time="2026-02-23T17:52:00+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 23 17:52:00 volumio go-librespot[21514]: time="2026-02-23T17:52:00+01:00" level=info msg="zeroconf server listening on port 43187" Feb 23 17:52:00 volumio go-librespot[21514]: time="2026-02-23T17:52:00+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Feb 23 17:52:00 volumio go-librespot[21514]: time="2026-02-23T17:52:00+01:00" level=debug msg="obtained new client token: AADF+8pTUyW9nUmcW5snEqMPwbOsCCXTpOxxDdgmOsFAJBgSj1BTVwJfjG5oHTOnhmKMvb92q1wBurWim8ExcFM5keI3dpTsyETdmGMQLMqDu8DvXhxVIq6syDIwLUL1DW5cjDaoZXMOgv+/n+ykH8ZQ2cMnR1oVQm2rupL1RrV4iUvY0Khu/e+GVD+2UZlf7tjIg4gS05SP6SxUQQsjU1t35TQ+kuJKNo5wFPr5q2JYTX18ZOExNM4aXK0=" Feb 23 17:52:01 volumio go-librespot[21514]: time="2026-02-23T17:52:01+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 23 17:52:01 volumio go-librespot[21514]: time="2026-02-23T17:52:01+01:00" level=debug msg="completed keyexchange" Feb 23 17:52:01 volumio go-librespot[21514]: time="2026-02-23T17:52:01+01:00" level=debug msg="completed challenge" Feb 23 17:52:01 volumio go-librespot[21514]: time="2026-02-23T17:52:01+01:00" level=info msg="authenticated AP" username="zm******k3" Feb 23 17:52:01 volumio volumio[1391]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Feb 23 17:52:01 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Feb 23 17:52:01 volumio volumio[1391]: info: Creating Spotify config file Feb 23 17:52:01 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 17:52:01 volumio volumio[1391]: info: Spotify config file written Feb 23 17:52:01 volumio go-librespot[21514]: time="2026-02-23T17:52:01+01:00" level=info msg="authenticated Login5" username="zm******k3" Feb 23 17:52:01 volumio go-librespot[21514]: time="2026-02-23T17:52:01+01:00" level=debug msg="initializing zeroconf session" username="zm******k3" Feb 23 17:52:01 volumio sudo[21524]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 23 17:52:01 volumio sudo[21524]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 17:52:01 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 23 17:52:01 volumio systemd[1]: go-librespot-daemon.service: Killing process 21516 (go-librespot) with signal SIGKILL. Feb 23 17:52:01 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 23 17:52:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 17:52:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 17:52:01 volumio go-librespot[21526]: go-librespot daemon starting... Feb 23 17:52:01 volumio sudo[21524]: pam_unix(sudo:session): session closed for user root Feb 23 17:52:01 volumio go-librespot[21527]: time="2026-02-23T17:52:01+01:00" level=info msg="running go-librespot 0.7.0" Feb 23 17:52:01 volumio go-librespot[21527]: time="2026-02-23T17:52:01+01:00" level=debug msg="app state loaded" Feb 23 17:52:01 volumio go-librespot[21527]: time="2026-02-23T17:52:01+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 17:52:01 volumio go-librespot[21527]: time="2026-02-23T17:52:01+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 23 17:52:01 volumio go-librespot[21527]: time="2026-02-23T17:52:01+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 23 17:52:01 volumio go-librespot[21527]: time="2026-02-23T17:52:01+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 23 17:52:01 volumio go-librespot[21527]: time="2026-02-23T17:52:01+01:00" level=info msg="zeroconf server listening on port 36953" Feb 23 17:52:01 volumio go-librespot[21527]: time="2026-02-23T17:52:01+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Feb 23 17:52:01 volumio go-librespot[21527]: time="2026-02-23T17:52:01+01:00" level=debug msg="obtained new client token: AAAcCgfxMNQ20NDaG05UWDX9MCP+Y+CDMLseenbvndD/oAi6sg7EAB/jsxpkwXiPOq8Gci9lZjBI6j2QuymIjBa09JIzsoOS7VFor/Pqq71xm4Ro6VSJwuQTCqM5dsihDkJPKBA+E3H2xoqTtpuitTPJ8U6Ijgjj3J6+pbFbwsqJMqFhk3D3Lkn0fTYjeunCiRLOYNZOjQyg5F7pf3KRl1aqi2IPX/c1H+MYdMJsc8y/1PpUKF/SosTHFlc=" Feb 23 17:52:01 volumio go-librespot[21527]: time="2026-02-23T17:52:01+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 23 17:52:01 volumio go-librespot[21527]: time="2026-02-23T17:52:01+01:00" level=debug msg="completed keyexchange" Feb 23 17:52:01 volumio go-librespot[21527]: time="2026-02-23T17:52:01+01:00" level=debug msg="completed challenge" Feb 23 17:52:01 volumio volumio[1391]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Feb 23 17:52:01 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Feb 23 17:52:01 volumio volumio[1391]: info: Creating Spotify config file Feb 23 17:52:01 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 17:52:01 volumio volumio[1391]: info: Spotify config file written Feb 23 17:52:01 volumio sudo[21539]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 23 17:52:01 volumio sudo[21539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 17:52:01 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 23 17:52:01 volumio systemd[1]: go-librespot-daemon.service: Killing process 21531 (go-librespot) with signal SIGKILL. Feb 23 17:52:01 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 23 17:52:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 17:52:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 17:52:01 volumio go-librespot[21541]: go-librespot daemon starting... Feb 23 17:52:01 volumio sudo[21539]: pam_unix(sudo:session): session closed for user root Feb 23 17:52:01 volumio go-librespot[21542]: time="2026-02-23T17:52:01+01:00" level=info msg="running go-librespot 0.7.0" Feb 23 17:52:01 volumio go-librespot[21542]: time="2026-02-23T17:52:01+01:00" level=debug msg="app state loaded" Feb 23 17:52:01 volumio go-librespot[21542]: time="2026-02-23T17:52:01+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 17:52:01 volumio go-librespot[21542]: time="2026-02-23T17:52:01+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 23 17:52:01 volumio go-librespot[21542]: time="2026-02-23T17:52:01+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 23 17:52:01 volumio go-librespot[21542]: time="2026-02-23T17:52:01+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 23 17:52:01 volumio go-librespot[21542]: time="2026-02-23T17:52:01+01:00" level=info msg="zeroconf server listening on port 39285" Feb 23 17:52:01 volumio go-librespot[21542]: time="2026-02-23T17:52:01+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Feb 23 17:52:01 volumio volumio[1391]: info: Initializing connection to go-librespot Websocket Feb 23 17:52:01 volumio go-librespot[21542]: time="2026-02-23T17:52:01+01:00" level=debug msg="new websocket client" Feb 23 17:52:01 volumio volumio[1391]: info: Connection to go-librespot Websocket established Feb 23 17:52:01 volumio volumio[1391]: info: go-librespot daemon successfully initialized Feb 23 17:52:02 volumio go-librespot[21542]: time="2026-02-23T17:52:02+01:00" level=debug msg="obtained new client token: AABqVmADZHqCQCH2o/xlXo/Ibkft9+/0hzsAwI7OcfbjlkckZ/H3PdNHiyP7kCaCMHhKWU0Dr04u5VSAWSbipnXzCCf2d2WPtH5sh8SBduyVPc42gdCjKSo2ok/9EeQLTAmMRq6lS9Jvtipgez6xMjcN0wZmFC1gQEp82UCGwy6PhoMW6hipqdBrLgwV4inTVBilcCnHIds/q9uC0vTE4EpE1/1B59QjbCvyDmw1d0ST60XegD2O9NAC" Feb 23 17:52:02 volumio volumio[1391]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Feb 23 17:52:02 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Feb 23 17:52:02 volumio volumio[1391]: info: Creating Spotify config file Feb 23 17:52:02 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 17:52:02 volumio volumio[1391]: info: Spotify config file written Feb 23 17:52:02 volumio sudo[21553]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 23 17:52:02 volumio sudo[21553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 17:52:02 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 23 17:52:02 volumio volumio[1391]: info: Connection to go-librespot Websocket closed Feb 23 17:52:02 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 23 17:52:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 17:52:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 17:52:02 volumio go-librespot[21555]: go-librespot daemon starting... Feb 23 17:52:02 volumio sudo[21553]: pam_unix(sudo:session): session closed for user root Feb 23 17:52:02 volumio go-librespot[21556]: time="2026-02-23T17:52:02+01:00" level=info msg="running go-librespot 0.7.0" Feb 23 17:52:02 volumio go-librespot[21556]: time="2026-02-23T17:52:02+01:00" level=debug msg="app state loaded" Feb 23 17:52:02 volumio go-librespot[21556]: time="2026-02-23T17:52:02+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 17:52:02 volumio go-librespot[21556]: time="2026-02-23T17:52:02+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 23 17:52:02 volumio go-librespot[21556]: time="2026-02-23T17:52:02+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 23 17:52:02 volumio go-librespot[21556]: time="2026-02-23T17:52:02+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 23 17:52:02 volumio go-librespot[21556]: time="2026-02-23T17:52:02+01:00" level=info msg="zeroconf server listening on port 44157" Feb 23 17:52:02 volumio go-librespot[21556]: time="2026-02-23T17:52:02+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Feb 23 17:52:02 volumio go-librespot[21556]: time="2026-02-23T17:52:02+01:00" level=debug msg="obtained new client token: AACjhnTy2KdRVmphs7T+ozXUjJi5u6V2PBcJvtOd4XCpnICvGS3OOSd89EeF9npugR77tzUkB9M8ImBa3mZ0k06g2YX4urSPK6XPmlNP24WPmbPUhhCeBD9ICDOEtsJl1kK7GYn+yKt5a4+vaKDnWmOFk53DArZh92besKroEhS2Ws0xQBiHxwC8y97Rv94c52vu7RD2LbodmD1S13d3Jzmmke+CUvxXvbvn/IHb/obi9bz1xdFppLSZlZw=" Feb 23 17:52:02 volumio volumio[1391]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Feb 23 17:52:02 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Feb 23 17:52:02 volumio volumio[1391]: info: Creating Spotify config file Feb 23 17:52:02 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 17:52:02 volumio volumio[1391]: info: Spotify config file written Feb 23 17:52:02 volumio sudo[21566]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 23 17:52:02 volumio sudo[21566]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 17:52:02 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 23 17:52:02 volumio systemd[1]: go-librespot-daemon.service: Killing process 21559 (go-librespot) with signal SIGKILL. Feb 23 17:52:02 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 23 17:52:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 17:52:02 volumio go-librespot[21568]: go-librespot daemon starting... Feb 23 17:52:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 17:52:02 volumio sudo[21566]: pam_unix(sudo:session): session closed for user root Feb 23 17:52:02 volumio go-librespot[21569]: time="2026-02-23T17:52:02+01:00" level=info msg="running go-librespot 0.7.0" Feb 23 17:52:02 volumio go-librespot[21569]: time="2026-02-23T17:52:02+01:00" level=debug msg="app state loaded" Feb 23 17:52:02 volumio go-librespot[21569]: time="2026-02-23T17:52:02+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 17:52:02 volumio go-librespot[21569]: time="2026-02-23T17:52:02+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 23 17:52:02 volumio go-librespot[21569]: time="2026-02-23T17:52:02+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 23 17:52:02 volumio go-librespot[21569]: time="2026-02-23T17:52:02+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 23 17:52:02 volumio go-librespot[21569]: time="2026-02-23T17:52:02+01:00" level=info msg="zeroconf server listening on port 34017" Feb 23 17:52:02 volumio go-librespot[21569]: time="2026-02-23T17:52:02+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Feb 23 17:52:02 volumio go-librespot[21569]: time="2026-02-23T17:52:02+01:00" level=debug msg="obtained new client token: AAAiQWtrnv5tNr4meR6c4W5QTtlWnSz8JiZjN8CuXKOwLiyVy7Xfo7Be8+gtE2jdTVLNcdCAF9uPok8CBOLLxTMWBapSbjMcBb10Rq8xjpZBk7CRZX0dzqDR8DhKJ6x9scp1huFM38C84T7RFHlq7vKSj1QkQfy+wV/bIusXN2VX6+l3gNzk9Kb8E/oxITb0npvyWZHT7kJiJCrOoosWfA5U6KrO7+zqYoNgeELcnuopJggApOVrpfw3gaQ=" Feb 23 17:52:02 volumio volumio[1391]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Feb 23 17:52:02 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Feb 23 17:52:02 volumio volumio[1391]: info: Creating Spotify config file Feb 23 17:52:02 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 17:52:02 volumio volumio[1391]: info: Spotify config file written Feb 23 17:52:02 volumio sudo[21579]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 23 17:52:02 volumio sudo[21579]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 17:52:02 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 23 17:52:02 volumio systemd[1]: go-librespot-daemon.service: Killing process 21570 (go-librespot) with signal SIGKILL. Feb 23 17:52:02 volumio systemd[1]: go-librespot-daemon.service: Killing process 21573 (go-librespot) with signal SIGKILL. Feb 23 17:52:02 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 23 17:52:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 17:52:02 volumio systemd[1]: go-librespot-daemon.service: Start request repeated too quickly. Feb 23 17:52:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'start-limit-hit'. Feb 23 17:52:02 volumio systemd[1]: Failed to start go-librespot-daemon.service - go-librespot Daemon. Feb 23 17:52:02 volumio sudo[21579]: pam_unix(sudo:session): session closed for user root Feb 23 17:52:02 volumio volumio[1391]: error: Cannot start Go-librespot Daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Feb 23 17:52:02 volumio volumio[1391]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Feb 23 17:52:02 volumio volumio[1391]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Feb 23 17:52:02 volumio volumio[1391]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Feb 23 17:52:02 volumio volumio[1391]: followed by "systemctl start go-librespot-daemon.service" again. Feb 23 17:52:02 volumio volumio[1391]: error: Error initializing go-librespot daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Feb 23 17:52:02 volumio volumio[1391]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Feb 23 17:52:02 volumio volumio[1391]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Feb 23 17:52:02 volumio volumio[1391]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Feb 23 17:52:02 volumio volumio[1391]: followed by "systemctl start go-librespot-daemon.service" again. Feb 23 17:52:03 volumio volumio[1391]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Feb 23 17:52:03 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Feb 23 17:52:03 volumio volumio[1391]: info: Creating Spotify config file Feb 23 17:52:03 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 17:52:03 volumio volumio[1391]: info: Spotify config file written Feb 23 17:52:03 volumio sudo[21582]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 23 17:52:03 volumio sudo[21582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 17:52:03 volumio systemd[1]: go-librespot-daemon.service: Start request repeated too quickly. Feb 23 17:52:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'start-limit-hit'. Feb 23 17:52:03 volumio systemd[1]: Failed to start go-librespot-daemon.service - go-librespot Daemon. Feb 23 17:52:03 volumio sudo[21582]: pam_unix(sudo:session): session closed for user root Feb 23 17:52:03 volumio volumio[1391]: error: Cannot start Go-librespot Daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Feb 23 17:52:03 volumio volumio[1391]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Feb 23 17:52:03 volumio volumio[1391]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Feb 23 17:52:03 volumio volumio[1391]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Feb 23 17:52:03 volumio volumio[1391]: followed by "systemctl start go-librespot-daemon.service" again. Feb 23 17:52:03 volumio volumio[1391]: error: Error initializing go-librespot daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Feb 23 17:52:03 volumio volumio[1391]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Feb 23 17:52:03 volumio volumio[1391]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Feb 23 17:52:03 volumio volumio[1391]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Feb 23 17:52:03 volumio volumio[1391]: followed by "systemctl start go-librespot-daemon.service" again. Feb 23 17:52:03 volumio volumio[1391]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Feb 23 17:52:03 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Feb 23 17:52:03 volumio volumio[1391]: info: Creating Spotify config file Feb 23 17:52:03 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 17:52:03 volumio volumio[1391]: info: Spotify config file written Feb 23 17:52:03 volumio sudo[21585]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 23 17:52:03 volumio sudo[21585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 17:52:03 volumio systemd[1]: go-librespot-daemon.service: Start request repeated too quickly. Feb 23 17:52:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'start-limit-hit'. Feb 23 17:52:03 volumio systemd[1]: Failed to start go-librespot-daemon.service - go-librespot Daemon. Feb 23 17:52:03 volumio sudo[21585]: pam_unix(sudo:session): session closed for user root Feb 23 17:52:03 volumio volumio[1391]: error: Cannot start Go-librespot Daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Feb 23 17:52:03 volumio volumio[1391]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Feb 23 17:52:03 volumio volumio[1391]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Feb 23 17:52:03 volumio volumio[1391]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Feb 23 17:52:03 volumio volumio[1391]: followed by "systemctl start go-librespot-daemon.service" again. Feb 23 17:52:03 volumio volumio[1391]: error: Error initializing go-librespot daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Feb 23 17:52:03 volumio volumio[1391]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Feb 23 17:52:03 volumio volumio[1391]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Feb 23 17:52:03 volumio volumio[1391]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Feb 23 17:52:03 volumio volumio[1391]: followed by "systemctl start go-librespot-daemon.service" again. Feb 23 17:52:03 volumio volumio[1391]: info: go-librespot daemon successfully initialized Feb 23 17:52:03 volumio volumio[1391]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Feb 23 17:52:03 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Feb 23 17:52:03 volumio volumio[1391]: info: Creating Spotify config file Feb 23 17:52:03 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 17:52:03 volumio volumio[1391]: info: Spotify config file written Feb 23 17:52:03 volumio sudo[21588]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 23 17:52:03 volumio sudo[21588]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 17:52:03 volumio systemd[1]: go-librespot-daemon.service: Start request repeated too quickly. Feb 23 17:52:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'start-limit-hit'. Feb 23 17:52:03 volumio systemd[1]: Failed to start go-librespot-daemon.service - go-librespot Daemon. Feb 23 17:52:03 volumio sudo[21588]: pam_unix(sudo:session): session closed for user root Feb 23 17:52:03 volumio volumio[1391]: error: Cannot start Go-librespot Daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Feb 23 17:52:03 volumio volumio[1391]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Feb 23 17:52:03 volumio volumio[1391]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Feb 23 17:52:03 volumio volumio[1391]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Feb 23 17:52:03 volumio volumio[1391]: followed by "systemctl start go-librespot-daemon.service" again. Feb 23 17:52:03 volumio volumio[1391]: error: Error initializing go-librespot daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Feb 23 17:52:03 volumio volumio[1391]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Feb 23 17:52:03 volumio volumio[1391]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Feb 23 17:52:03 volumio volumio[1391]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Feb 23 17:52:03 volumio volumio[1391]: followed by "systemctl start go-librespot-daemon.service" again. Feb 23 17:52:04 volumio volumio[1391]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Feb 23 17:52:04 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Feb 23 17:52:04 volumio volumio[1391]: info: Creating Spotify config file Feb 23 17:52:04 volumio volumio[1391]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 17:52:04 volumio volumio[1391]: info: Spotify config file written Feb 23 17:52:04 volumio sudo[21591]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 23 17:52:04 volumio sudo[21591]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 17:52:04 volumio systemd[1]: go-librespot-daemon.service: Start request repeated too quickly. Feb 23 17:52:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'start-limit-hit'. Feb 23 17:52:04 volumio systemd[1]: Failed to start go-librespot-daemon.service - go-librespot Daemon. Feb 23 17:52:04 volumio sudo[21591]: pam_unix(sudo:session): session closed for user root Feb 23 17:52:04 volumio volumio[1391]: error: Cannot start Go-librespot Daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Feb 23 17:52:04 volumio volumio[1391]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Feb 23 17:52:04 volumio volumio[1391]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Feb 23 17:52:04 volumio volumio[1391]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Feb 23 17:52:04 volumio volumio[1391]: followed by "systemctl start go-librespot-daemon.service" again. Feb 23 17:52:04 volumio volumio[1391]: error: Error initializing go-librespot daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Feb 23 17:52:04 volumio volumio[1391]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Feb 23 17:52:04 volumio volumio[1391]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Feb 23 17:52:04 volumio volumio[1391]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Feb 23 17:52:04 volumio volumio[1391]: followed by "systemctl start go-librespot-daemon.service" again. Feb 23 17:52:04 volumio volumio[1391]: info: go-librespot daemon successfully initialized Feb 23 17:52:04 volumio volumio[1391]: info: go-librespot daemon successfully initialized Feb 23 17:52:04 volumio volumio[1391]: info: Getting Spotify volume Feb 23 17:52:04 volumio volumio[1391]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 17:52:04 volumio volumio[1391]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 17:52:04 volumio volumio[1391]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 23 17:52:04 volumio volumio[1391]: errno: -111, Feb 23 17:52:04 volumio volumio[1391]: code: 'ECONNREFUSED', Feb 23 17:52:04 volumio volumio[1391]: syscall: 'connect', Feb 23 17:52:04 volumio volumio[1391]: address: '127.0.0.1', Feb 23 17:52:04 volumio volumio[1391]: port: 9879, Feb 23 17:52:04 volumio volumio[1391]: response: undefined Feb 23 17:52:04 volumio volumio[1391]: } Feb 23 17:52:04 volumio volumio[1391]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 17:52:05 volumio sudo[21608]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-23 17:51' Feb 23 17:52:05 volumio sudo[21608]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"