Aug 30 21:39:01 wozivol volumio[1266]: info: Loading plugin "outputs"... Aug 30 21:39:01 wozivol volumio[1266]: info: Loading plugin "albumart"... Aug 30 21:39:01 wozivol volumio[1266]: info: Plugin example_plugin is not enabled Aug 30 21:39:01 wozivol volumio[1266]: info: Loading plugin "inputs"... Aug 30 21:39:01 wozivol volumio[1266]: info: Loading plugin "updater_comm"... Aug 30 21:39:01 wozivol volumio[1266]: info: Plugin mpdemulation is not enabled Aug 30 21:39:01 wozivol volumio[1266]: info: Loading plugin "rest_api"... Aug 30 21:39:01 wozivol volumio[1266]: info: Loading plugin "websocket"... Aug 30 21:39:01 wozivol volumio[1266]: info: Starting Socket.io Server version 1.7.4 Aug 30 21:39:01 wozivol volumio[1266]: info: Loading i18n strings for locale de Aug 30 21:39:01 wozivol volumio[1266]: Updating browse sources language Aug 30 21:39:01 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 21:39:02 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 21:39:02 wozivol volumio[1266]: info: CoreCommandRouter::initPlayerControls Aug 30 21:39:02 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:02 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:02 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:02 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:02 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:02 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:02 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:02 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:02 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 21:39:02 wozivol volumio[1266]: Express server listening on port 3000 Aug 30 21:39:02 wozivol volumio[1266]: [Metrics] WebUI: 13s 645.21ms Aug 30 21:39:02 wozivol volumio[1266]: info: CoreStateMachine::resetVolumioState Aug 30 21:39:02 wozivol volumio[1266]: info: CoreStateMachine::getcurrentVolume Aug 30 21:39:02 wozivol volumio[1266]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 21:39:02 wozivol volumio[1266]: info: Volumio Network Manager: Network status updated: 1 Aug 30 21:39:02 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:39:02 wozivol volumio[1266]: info: Reloading queue from file Aug 30 21:39:02 wozivol volumio[1341]: Forking 3 albumart workers Aug 30 21:39:02 wozivol volumio[1266]: info: VolumeController:: Volume=60 Mute =false Aug 30 21:39:02 wozivol volumio[1266]: info: CoreStateMachine::pushState Aug 30 21:39:02 wozivol volumio[1266]: info: CorePlayQueue::getTrack 0 Aug 30 21:39:02 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 21:39:02 wozivol volumio[1266]: info: CoreCommandRouter::volumioPushState Aug 30 21:39:02 wozivol volumio[1266]: info: CoreStateMachine::updateTrackBlock Aug 30 21:39:02 wozivol volumio[1266]: info: CorePlayQueue::getTrackBlock Aug 30 21:39:02 wozivol volumio[1266]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 21:39:02 wozivol volumio[1266]: info: CoreStateMachine::setRepeat null single undefined Aug 30 21:39:02 wozivol volumio[1266]: info: CoreStateMachine::pushState Aug 30 21:39:02 wozivol volumio[1266]: info: CorePlayQueue::getTrack 0 Aug 30 21:39:02 wozivol volumio[1266]: info: CoreCommandRouter::volumioPushState Aug 30 21:39:02 wozivol volumio[1266]: info: CoreStateMachine::setRandom null Aug 30 21:39:02 wozivol volumio[1266]: info: CoreStateMachine::pushState Aug 30 21:39:02 wozivol volumio[1266]: info: CorePlayQueue::getTrack 0 Aug 30 21:39:02 wozivol volumio[1266]: info: CoreCommandRouter::volumioPushState Aug 30 21:39:03 wozivol volumio[1266]: info: Setting Device type: Raspberry PI Aug 30 21:39:03 wozivol volumio[1266]: info: Completed loading Core Plugins Aug 30 21:39:03 wozivol volumio[1266]: info: Preparing to generate the ALSA configuration file Aug 30 21:39:03 wozivol volumio[1266]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 30 21:39:03 wozivol volumio[1266]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Aug 30 21:39:03 wozivol volumio[1266]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 30 21:39:03 wozivol systemd[1]: systemd-fsckd.service: Deactivated successfully. Aug 30 21:39:03 wozivol volumio[1266]: info: Discovery: adding 0ffacb34-c812-435f-be37-47130cc06451 Aug 30 21:39:03 wozivol volumio[1266]: info: Discovery: Found device WoZi_Vol Aug 30 21:39:03 wozivol volumio[1266]: info: CoreCommandRouter::volumioGetState Aug 30 21:39:03 wozivol volumio[1266]: info: CorePlayQueue::getTrack 0 Aug 30 21:39:03 wozivol volumio[1266]: info: Discovery: this is already registered, 0ffacb34-c812-435f-be37-47130cc06451 Aug 30 21:39:03 wozivol volumio[1266]: info: Discovery: Found device WoZi_Vol Aug 30 21:39:03 wozivol volumio[1266]: info: CoreCommandRouter::volumioGetState Aug 30 21:39:03 wozivol volumio[1266]: info: CorePlayQueue::getTrack 0 Aug 30 21:39:03 wozivol volumio[1266]: info: VolumeController:: Volume=60 Mute =false Aug 30 21:39:03 wozivol volumio[1266]: info: CoreStateMachine::pushState Aug 30 21:39:03 wozivol volumio[1266]: info: CorePlayQueue::getTrack 0 Aug 30 21:39:03 wozivol volumio[1266]: info: CoreCommandRouter::volumioPushState Aug 30 21:39:03 wozivol volumio[1266]: info: Asound.conf file unchanged, so no further update is needed Aug 30 21:39:03 wozivol volumio[1266]: info: Output device has changed, restarting MPD Aug 30 21:39:03 wozivol volumio[1266]: info: Output device has changed, restarting Shairport Sync Aug 30 21:39:03 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:03 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:39:03 wozivol sudo[1397]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 21:39:03 wozivol sudo[1397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 21:39:03 wozivol sudo[1400]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 21:39:03 wozivol sudo[1400]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 21:39:03 wozivol sudo[1397]: pam_unix(sudo:session): session closed for user root Aug 30 21:39:04 wozivol volumio[1266]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 21:39:04 wozivol volumio-remote-updater[715]: [2026-08-30 21:39:04] [connect] Successful connection Aug 30 21:39:04 wozivol volumio[1266]: info: ___________ START PLUGINS ___________ Aug 30 21:39:04 wozivol systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 30 21:39:04 wozivol systemd[1]: Starting mpd.service - Music Player Daemon... Aug 30 21:39:04 wozivol volumio[1266]: info: ControllerMpd::onStart: Initializing MPD Aug 30 21:39:04 wozivol volumio[1266]: info: Creating MPD Configuration file Aug 30 21:39:04 wozivol sudo[1410]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 30 21:39:04 wozivol sudo[1410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 21:39:04 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 21:39:04 wozivol volumio[1266]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 21:39:04 wozivol volumio[1266]: info: [1788118744563] CoreMusicLibrary::Adding element Medienserver Aug 30 21:39:04 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 21:39:04 wozivol sudo[1411]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 21:39:04 wozivol sudo[1411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 30 21:39:04 wozivol sudo[1417]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 30 21:39:04 wozivol sudo[1411]: pam_unix(sudo:session): session closed for user root Aug 30 21:39:04 wozivol volumio[1266]: info: UPNP Browser: Client initialized successfully Aug 30 21:39:04 wozivol sudo[1413]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 21:39:04 wozivol sudo[1413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 21:39:04 wozivol sudo[1415]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 21:39:04 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:04 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:39:04 wozivol sudo[1415]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 21:39:04 wozivol systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 30 21:39:04 wozivol sudo[1410]: pam_unix(sudo:session): session closed for user root Aug 30 21:39:05 wozivol sudo[1413]: pam_unix(sudo:session): session closed for user root Aug 30 21:39:05 wozivol systemd[1]: mpd.service: Deactivated successfully. Aug 30 21:39:05 wozivol systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 30 21:39:05 wozivol systemd[1]: mpd.socket: Deactivated successfully. Aug 30 21:39:05 wozivol systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 30 21:39:05 wozivol systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 30 21:39:05 wozivol systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 30 21:39:05 wozivol systemd[1]: Starting mpd.service - Music Player Daemon... Aug 30 21:39:05 wozivol volumio[1266]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 21:39:05 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:05 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:39:05 wozivol sudo[1439]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 21:39:05 wozivol sudo[1439]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 30 21:39:05 wozivol sudo[1449]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 30 21:39:05 wozivol sudo[1439]: pam_unix(sudo:session): session closed for user root Aug 30 21:39:05 wozivol volumio5-onboarding[1426]: time=2026-08-30T21:39:05.675+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 30 21:39:05 wozivol volumio[1266]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 21:39:05 wozivol volumio[1266]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 21:39:05 wozivol volumio[1266]: info: [1788118745680] CoreMusicLibrary::Adding element Last_100 Aug 30 21:39:05 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 21:39:05 wozivol volumio[1266]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 21:39:05 wozivol volumio[1266]: info: [1788118745684] CoreMusicLibrary::Adding element Webradio Aug 30 21:39:05 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 21:39:05 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 21:39:05 wozivol volumio[1266]: info: Initializing BBC Radios Aug 30 21:39:06 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 21:39:06 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:39:06 wozivol volumio[1266]: info: Creating Spotify config file Aug 30 21:39:06 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:07 wozivol systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Aug 30 21:39:07 wozivol systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 21:39:07 wozivol systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 21:39:07 wozivol upmpdcli[1468]: Could not open config: /tmp/upmpdcli.conf Aug 30 21:39:07 wozivol systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 30 21:39:07 wozivol systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 30 21:39:09 wozivol systemd[1]: systemd-hostnamed.service: Deactivated successfully. Aug 30 21:39:10 wozivol volumio[1266]: info: Volumio Calling Home Aug 30 21:39:13 wozivol mpd[1450]: 2026-08-30T21:39:13 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 30 21:39:13 wozivol sudo[1478]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 21:39:13 wozivol sudo[1478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 21:39:13 wozivol systemd[1]: Started mpd.service - Music Player Daemon. Aug 30 21:39:13 wozivol sudo[1415]: pam_unix(sudo:session): session closed for user root Aug 30 21:39:13 wozivol sudo[1400]: pam_unix(sudo:session): session closed for user root Aug 30 21:39:13 wozivol volumio[1368]: Starting albumart workers Aug 30 21:39:13 wozivol sudo[1478]: pam_unix(sudo:session): session closed for user root Aug 30 21:39:13 wozivol sudo[1476]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 21:39:13 wozivol sudo[1476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 21:39:13 wozivol sudo[1476]: pam_unix(sudo:session): session closed for user root Aug 30 21:39:14 wozivol volumio[1266]: info: MPD Permissions set Aug 30 21:39:14 wozivol volumio[1266]: info: MPD Permissions set Aug 30 21:39:14 wozivol volumio[1266]: info: Completed starting Core Plugins Aug 30 21:39:14 wozivol volumio[1266]: info: ------------------------------------------- Aug 30 21:39:14 wozivol volumio[1266]: info: ----- MyVolumio plugins startup ---- Aug 30 21:39:14 wozivol volumio[1266]: info: ------------------------------------------- Aug 30 21:39:14 wozivol volumio[1266]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 30 21:39:14 wozivol volumio[1266]: info: Volumio called home Aug 30 21:39:14 wozivol volumio[1266]: info: Spotify config file written Aug 30 21:39:14 wozivol sudo[1489]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 30 21:39:14 wozivol sudo[1489]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 21:39:15 wozivol systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 30 21:39:15 wozivol systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 30 21:39:15 wozivol systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 30 21:39:15 wozivol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 21:39:15 wozivol go-librespot[1504]: go-librespot daemon starting... Aug 30 21:39:15 wozivol sudo[1489]: pam_unix(sudo:session): session closed for user root Aug 30 21:39:15 wozivol volumio5-onboarding[1426]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:50734->127.0.0.1:3000: i/o timeout Aug 30 21:39:15 wozivol systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Aug 30 21:39:15 wozivol systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Aug 30 21:39:15 wozivol volumio[1366]: Starting albumart workers Aug 30 21:39:15 wozivol systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Aug 30 21:39:15 wozivol systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 30 21:39:15 wozivol systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 30 21:39:15 wozivol volumio5-onboarding[1528]: time=2026-08-30T21:39:15.984+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 30 21:39:16 wozivol volumio[1365]: Starting albumart workers Aug 30 21:39:16 wozivol go-librespot[1508]: time="2026-08-30T21:39:16+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 21:39:16 wozivol go-librespot[1508]: time="2026-08-30T21:39:16+02:00" level=debug msg="app state loaded" Aug 30 21:39:16 wozivol go-librespot[1508]: time="2026-08-30T21:39:16+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 21:39:16 wozivol go-librespot[1508]: time="2026-08-30T21:39:16+02: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]" Aug 30 21:39:16 wozivol go-librespot[1508]: time="2026-08-30T21:39:16+02: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]" Aug 30 21:39:16 wozivol go-librespot[1508]: time="2026-08-30T21:39:16+02: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]" Aug 30 21:39:16 wozivol go-librespot[1508]: time="2026-08-30T21:39:16+02:00" level=info msg="zeroconf server listening on port 33253" Aug 30 21:39:16 wozivol go-librespot[1508]: time="2026-08-30T21:39:16+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 21:39:16 wozivol go-librespot[1508]: time="2026-08-30T21:39:16+02:00" level=debug msg="obtained new client token: AAEXbXTzL3+G3KNlxSmf/P3KhO55MYcqR3ZQ7aqud+wGOs8THlir44hjhlSOy+y0FtCam3WM4+SFIV2Pv2PAfZ8SYfVOvR2N++HkEGNEAYG1awf6AxvqX125kfG0ADgW5yhBNNpCi7E5toy2ELGAPAq3NK3OjxdgPzenDtxSpBgxsNpEsMJzQVXo5RbjtamBREMb6BrtyDMotOSUj9Z+JnlQMWhnqj/HXSv3Y2TJK88y3EBviKY+cI8DWgU=" Aug 30 21:39:16 wozivol go-librespot[1508]: time="2026-08-30T21:39:16+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 21:39:16 wozivol go-librespot[1508]: time="2026-08-30T21:39:16+02:00" level=debug msg="completed keyexchange" Aug 30 21:39:16 wozivol go-librespot[1508]: time="2026-08-30T21:39:16+02:00" level=debug msg="completed challenge" Aug 30 21:39:16 wozivol go-librespot[1508]: time="2026-08-30T21:39:16+02:00" level=info msg="authenticated AP" username="u4*********************18" Aug 30 21:39:17 wozivol go-librespot[1508]: time="2026-08-30T21:39:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 21:39:17 wozivol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 21:39:17 wozivol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 21:39:17 wozivol systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 30 21:39:17 wozivol systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 30 21:39:17 wozivol systemd[1]: setdatetime-helper.service: Consumed 1.113s CPU time. Aug 30 21:39:17 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:17 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:17 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:17 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:17 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:17 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:17 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:17 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:18 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:39:18 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:18 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:18 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:18 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:18 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:18 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:18 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:18 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:18 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:18 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:39:18 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:18 wozivol volumio[1266]: info: No need to fix Spotify hosts Aug 30 21:39:19 wozivol volumio-remote-updater[715]: [2026-08-30 21:39:19] [connect] Successful connection Aug 30 21:39:19 wozivol volumio[1266]: error: MPD error: The expression evaluated to a falsy value: Aug 30 21:39:19 wozivol volumio[1266]: assert.ok(self.idling) Aug 30 21:39:19 wozivol volumio[1266]: error: The expression evaluated to a falsy value: Aug 30 21:39:19 wozivol volumio[1266]: assert.ok(self.idling) Aug 30 21:39:19 wozivol volumio[1266]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Aug 30 21:39:20 wozivol volumio[1266]: info: MPD running with PID1450 Aug 30 21:39:20 wozivol volumio[1266]: ,establishing connection Aug 30 21:39:20 wozivol volumio[1266]: error: updateQueue error: null Aug 30 21:39:20 wozivol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Aug 30 21:39:20 wozivol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 21:39:20 wozivol volumio[1266]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Aug 30 21:39:20 wozivol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 21:39:20 wozivol go-librespot[1570]: go-librespot daemon starting... Aug 30 21:39:20 wozivol go-librespot[1572]: time="2026-08-30T21:39:20+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 21:39:20 wozivol go-librespot[1572]: time="2026-08-30T21:39:20+02:00" level=debug msg="app state loaded" Aug 30 21:39:20 wozivol go-librespot[1572]: time="2026-08-30T21:39:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 21:39:20 wozivol volumio[1266]: info: New Spotify access tokenBQAQfx0xjG... Aug 30 21:39:20 wozivol volumio[1266]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 30 21:39:20 wozivol go-librespot[1572]: time="2026-08-30T21:39:20+02: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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 21:39:20 wozivol go-librespot[1572]: time="2026-08-30T21:39:20+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 21:39:20 wozivol go-librespot[1572]: time="2026-08-30T21:39:20+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 21:39:20 wozivol go-librespot[1572]: time="2026-08-30T21:39:20+02:00" level=info msg="zeroconf server listening on port 41691" Aug 30 21:39:20 wozivol go-librespot[1572]: time="2026-08-30T21:39:20+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 21:39:20 wozivol volumio[1266]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Aug 30 21:39:21 wozivol volumio[1266]: info: Received Get System Info Aug 30 21:39:21 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 21:39:21 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 21:39:21 wozivol volumio[1266]: info: Discovery: Getting this device information Aug 30 21:39:21 wozivol volumio[1266]: info: CoreCommandRouter::volumioGetState Aug 30 21:39:21 wozivol volumio[1266]: info: CorePlayQueue::getTrack 0 Aug 30 21:39:21 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 21:39:21 wozivol go-librespot[1572]: time="2026-08-30T21:39:21+02:00" level=debug msg="obtained new client token: AAHx/hBBFmlfCmLd/bCAjblX89P0XCEWft8CMZBeJnki+DhRs7DhHZLZx8lFqj1nL6UDWb0TapPMSIJ8AALpNhykVWuNUDw8rnIZiO+CyJsZ9eAMVJnPnGtAkAV7sD+WAnFq4n9FM5itlmhXeuf7d6odZjYM/0FdnziRYbV72FjoQQNLUElkfLYkkO0sHP0cJ6aFlwS54pcafAh5Q2904tCvplciAcH6pSEOchwk/XRLB5gef03ecaHo" Aug 30 21:39:21 wozivol go-librespot[1572]: time="2026-08-30T21:39:21+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 21:39:21 wozivol volumio5-onboarding[1528]: time=2026-08-30T21:39:21.132+02:00 level=INFO msg="system info for 3fe722d250a307bf39cdbf9e78fe05ae" deviceName=WoZi_Vol deviceVariant=volumio deviceModel= softwareVersion=4.119 Aug 30 21:39:21 wozivol volumio5-onboarding[1528]: time=2026-08-30T21:39:21.151+02:00 level=INFO msg="bootstrapping state" hasInternet=true Aug 30 21:39:21 wozivol go-librespot[1572]: time="2026-08-30T21:39:21+02:00" level=debug msg="completed keyexchange" Aug 30 21:39:21 wozivol go-librespot[1572]: time="2026-08-30T21:39:21+02:00" level=debug msg="completed challenge" Aug 30 21:39:21 wozivol volumio-remote-updater[715]: [2026-08-30 21:39:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788118759 101 Aug 30 21:39:21 wozivol volumio[1266]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 3 Aug 30 21:39:21 wozivol go-librespot[1572]: time="2026-08-30T21:39:21+02:00" level=info msg="authenticated AP" username="u4*********************18" Aug 30 21:39:21 wozivol volumio[1266]: info: Starting Shairport Sync Aug 30 21:39:21 wozivol volumio[1266]: info: Starting Shairport Sync Aug 30 21:39:21 wozivol go-librespot[1572]: time="2026-08-30T21:39:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 21:39:21 wozivol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 21:39:21 wozivol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 21:39:21 wozivol volumio[1266]: info: Starting Shairport Sync Aug 30 21:39:21 wozivol sudo[1589]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 21:39:21 wozivol sudo[1589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 21:39:21 wozivol sudo[1594]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 21:39:21 wozivol sudo[1594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 21:39:21 wozivol sudo[1591]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 21:39:21 wozivol sudo[1591]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 21:39:21 wozivol volumio[1266]: info: Received Get System Info Aug 30 21:39:21 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 21:39:21 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 21:39:21 wozivol volumio[1266]: info: Discovery: Getting this device information Aug 30 21:39:21 wozivol volumio[1266]: info: CoreCommandRouter::volumioGetState Aug 30 21:39:21 wozivol volumio[1266]: info: CorePlayQueue::getTrack 0 Aug 30 21:39:21 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 21:39:21 wozivol volumio[1266]: error: updateQueue error: null Aug 30 21:39:21 wozivol systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 30 21:39:21 wozivol systemd[1]: shairport-sync.service: Deactivated successfully. Aug 30 21:39:21 wozivol systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 30 21:39:21 wozivol systemd[1]: shairport-sync.service: Consumed 2.051s CPU time. Aug 30 21:39:21 wozivol sudo[1596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 30 21:39:21 wozivol sudo[1596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 21:39:21 wozivol systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 30 21:39:21 wozivol systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 30 21:39:21 wozivol systemd[1]: shairport-sync.service: Deactivated successfully. Aug 30 21:39:21 wozivol systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 30 21:39:21 wozivol volumio-remote-updater[715]: Test mode disabled Aug 30 21:39:21 wozivol volumio-remote-updater[715]: Alpha mode disabled Aug 30 21:39:21 wozivol volumio-remote-updater[715]: Alpha legacy test mode disabled Aug 30 21:39:21 wozivol sudo[1589]: pam_unix(sudo:session): session closed for user root Aug 30 21:39:21 wozivol systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 30 21:39:21 wozivol sudo[1591]: pam_unix(sudo:session): session closed for user root Aug 30 21:39:21 wozivol systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 30 21:39:21 wozivol systemd[1]: shairport-sync.service: Deactivated successfully. Aug 30 21:39:21 wozivol systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 30 21:39:21 wozivol systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 30 21:39:21 wozivol sudo[1594]: pam_unix(sudo:session): session closed for user root Aug 30 21:39:22 wozivol volumio[1266]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 30 21:39:22 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 30 21:39:22 wozivol volumio[1266]: info: Shairport-Sync Started Aug 30 21:39:22 wozivol volumio[1266]: Error adding Membership: Error: addMembership EINVAL Aug 30 21:39:22 wozivol volumio[1266]: info: Shairport-Sync Started Aug 30 21:39:22 wozivol volumio[1266]: info: Shairport-Sync Started Aug 30 21:39:22 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 21:39:22 wozivol volumio5-onboarding[1528]: time=2026-08-30T21:39:22.542+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Aug 30 21:39:22 wozivol volumio5-onboarding[1528]: time=2026-08-30T21:39:22.544+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Aug 30 21:39:22 wozivol volumio5-onboarding[1528]: time=2026-08-30T21:39:22.544+02:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Aug 30 21:39:22 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 21:39:22 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 21:39:22 wozivol systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Aug 30 21:39:22 wozivol systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 21:39:22 wozivol volumio[1266]: info: Received Get System Info Aug 30 21:39:22 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 21:39:22 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 21:39:22 wozivol volumio[1266]: info: Discovery: Getting this device information Aug 30 21:39:22 wozivol volumio[1266]: info: CoreCommandRouter::volumioGetState Aug 30 21:39:22 wozivol volumio[1266]: info: CorePlayQueue::getTrack 0 Aug 30 21:39:22 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 21:39:22 wozivol systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 21:39:22 wozivol sudo[1596]: pam_unix(sudo:session): session closed for user root Aug 30 21:39:22 wozivol volumio[1266]: info: CoreCommandRouter::volumioGetState Aug 30 21:39:22 wozivol volumio[1266]: info: CorePlayQueue::getTrack 0 Aug 30 21:39:23 wozivol volumio[1266]: info: go-librespot daemon successfully initialized Aug 30 21:39:23 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 21:39:23 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 21:39:23 wozivol volumio5-onboarding[1528]: time=2026-08-30T21:39:23.038+02:00 level=INFO msg="enabling local network discovery" Aug 30 21:39:23 wozivol volumio5-onboarding[1528]: time=2026-08-30T21:39:23.081+02:00 level=INFO msg="enabling BLE discovery" Aug 30 21:39:23 wozivol volumio[1266]: info: Upmpdcli Daemon Started Aug 30 21:39:23 wozivol volumio[1266]: SPOTIFY: User informations: {"account_id":"rxGrRLXS68","country":"DE","display_name":"Krehbohr","email":"michabohrmann@gmx.net","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/u4vbrnjbf15eqmf05ft5kck18"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/u4vbrnjbf15eqmf05ft5kck18","id":"u4vbrnjbf15eqmf05ft5kck18","images":[],"product":"premium","type":"user","uri":"spotify:user:u4vbrnjbf15eqmf05ft5kck18"} Aug 30 21:39:23 wozivol volumio[1266]: info: Spotify Successfully logged in Aug 30 21:39:23 wozivol volumio[1266]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 21:39:23 wozivol volumio[1266]: info: [1788118763954] CoreMusicLibrary::Adding element Spotify Aug 30 21:39:23 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 21:39:23 wozivol volumio[1266]: Cannot find translation for source Spotify Aug 30 21:39:24 wozivol volumio5-onboarding[1528]: time=2026-08-30T21:39:24.013+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 30 21:39:24 wozivol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Aug 30 21:39:24 wozivol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 30 21:39:24 wozivol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 21:39:24 wozivol go-librespot[1643]: go-librespot daemon starting... Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 30 21:39:24 wozivol go-librespot[1644]: time="2026-08-30T21:39:24+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 21:39:24 wozivol go-librespot[1644]: time="2026-08-30T21:39:24+02:00" level=debug msg="app state loaded" Aug 30 21:39:24 wozivol go-librespot[1644]: time="2026-08-30T21:39:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 30 21:39:24 wozivol volumio[1266]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 30 21:39:24 wozivol volumio[1266]: info: Adding plugin bluetooth to MyMusic Plugins Aug 30 21:39:24 wozivol volumio[1266]: info: Adding plugin multiroom to MyMusic Plugins Aug 30 21:39:24 wozivol go-librespot[1644]: time="2026-08-30T21:39:24+02: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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 21:39:24 wozivol go-librespot[1644]: time="2026-08-30T21:39:24+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 21:39:24 wozivol go-librespot[1644]: time="2026-08-30T21:39:24+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 21:39:24 wozivol volumio[1266]: info: Adding plugin metavolumio to MyMusic Plugins Aug 30 21:39:24 wozivol go-librespot[1644]: time="2026-08-30T21:39:24+02:00" level=info msg="zeroconf server listening on port 34585" Aug 30 21:39:24 wozivol go-librespot[1644]: time="2026-08-30T21:39:24+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 21:39:24 wozivol volumio[1266]: info: Adding plugin cd_controller to MyMusic Plugins Aug 30 21:39:25 wozivol go-librespot[1644]: time="2026-08-30T21:39:25+02:00" level=debug msg="obtained new client token: AAHDF//u3jtCVANXKZhm1H6EPt6bmCAaAjAjbSCPGeYUM2LoltUc4HbvVvNzwY9zOQs2BwuVPQazXRas3kNaByYE1+uQx1UvrmqaQo+l77aMQDBShyAYqounXmOOOsF1RRRi9apv37OajnWOqeUogegwtSLfdFs1XVh+cCK832Mm7hijWPN+BZBjBvze9mSveVadI+VLs6zsHnZCqgxHjfnxuaqr3RY8/B7qwwC0dAQNi45S+LBSAebOChU=" Aug 30 21:39:25 wozivol volumio[1266]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 30 21:39:25 wozivol volumio[1266]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 30 21:39:25 wozivol go-librespot[1644]: time="2026-08-30T21:39:25+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 21:39:25 wozivol volumio[1266]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 30 21:39:25 wozivol go-librespot[1644]: time="2026-08-30T21:39:25+02:00" level=debug msg="completed keyexchange" Aug 30 21:39:25 wozivol go-librespot[1644]: time="2026-08-30T21:39:25+02:00" level=debug msg="completed challenge" Aug 30 21:39:25 wozivol volumio[1266]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 30 21:39:25 wozivol go-librespot[1644]: time="2026-08-30T21:39:25+02:00" level=info msg="authenticated AP" username="u4*********************18" Aug 30 21:39:25 wozivol go-librespot[1644]: time="2026-08-30T21:39:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 21:39:25 wozivol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 21:39:25 wozivol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 21:39:28 wozivol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Aug 30 21:39:28 wozivol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 21:39:28 wozivol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 21:39:28 wozivol go-librespot[1667]: go-librespot daemon starting... Aug 30 21:39:28 wozivol go-librespot[1668]: time="2026-08-30T21:39:28+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 21:39:28 wozivol go-librespot[1668]: time="2026-08-30T21:39:28+02:00" level=debug msg="app state loaded" Aug 30 21:39:28 wozivol go-librespot[1668]: time="2026-08-30T21:39:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 21:39:28 wozivol go-librespot[1668]: time="2026-08-30T21:39:28+02: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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 21:39:28 wozivol go-librespot[1668]: time="2026-08-30T21:39:28+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 21:39:28 wozivol go-librespot[1668]: time="2026-08-30T21:39:28+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 21:39:28 wozivol go-librespot[1668]: time="2026-08-30T21:39:28+02:00" level=info msg="zeroconf server listening on port 40419" Aug 30 21:39:28 wozivol go-librespot[1668]: time="2026-08-30T21:39:28+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 21:39:29 wozivol go-librespot[1668]: time="2026-08-30T21:39:29+02:00" level=debug msg="obtained new client token: AAETykINsAz7a7qoS3qLMZJym6xygH27LwTev8+Aq+qlveWmXzcCyz8u6H423TGSGl5zPSp+eh9yxbUaEEGg8aD7vTLfpmLstqdnNC5M/3igbx0+CXdhHotFjTyX0U2MVFfGKZnAu/YBbi3hDuiDCajQVENqi5SLorR19AUORzNtUU7+NCP4O/fEX/FJybTX2ww4uV5KZqtgcesxUPHcgkT8LrOuZs1GSixqhEF/gk9oTKxp5mI9nvj8JvM=" Aug 30 21:39:29 wozivol go-librespot[1668]: time="2026-08-30T21:39:29+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 21:39:29 wozivol go-librespot[1668]: time="2026-08-30T21:39:29+02:00" level=debug msg="completed keyexchange" Aug 30 21:39:29 wozivol go-librespot[1668]: time="2026-08-30T21:39:29+02:00" level=debug msg="completed challenge" Aug 30 21:39:29 wozivol go-librespot[1668]: time="2026-08-30T21:39:29+02:00" level=info msg="authenticated AP" username="u4*********************18" Aug 30 21:39:29 wozivol go-librespot[1668]: time="2026-08-30T21:39:29+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 21:39:29 wozivol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 21:39:29 wozivol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 21:39:32 wozivol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Aug 30 21:39:32 wozivol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 21:39:32 wozivol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 21:39:32 wozivol go-librespot[1678]: go-librespot daemon starting... Aug 30 21:39:32 wozivol go-librespot[1679]: time="2026-08-30T21:39:32+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 21:39:32 wozivol go-librespot[1679]: time="2026-08-30T21:39:32+02:00" level=debug msg="app state loaded" Aug 30 21:39:32 wozivol go-librespot[1679]: time="2026-08-30T21:39:32+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 21:39:32 wozivol go-librespot[1679]: time="2026-08-30T21:39:32+02: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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 30 21:39:32 wozivol go-librespot[1679]: time="2026-08-30T21:39:32+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 30 21:39:32 wozivol go-librespot[1679]: time="2026-08-30T21:39:32+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 30 21:39:32 wozivol go-librespot[1679]: time="2026-08-30T21:39:32+02:00" level=info msg="zeroconf server listening on port 36749" Aug 30 21:39:32 wozivol go-librespot[1679]: time="2026-08-30T21:39:32+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 21:39:33 wozivol go-librespot[1679]: time="2026-08-30T21:39:33+02:00" level=debug msg="obtained new client token: AAHQ/rhcYd4Xeth+Qh0CLxMOEurtf3+M2skwi9sSnkc1DFuY8Lltf6vOT+GAEY3mEM/eW0FVOavmOycpTzDaYafYl74++xIioqC6RaOybFg6qHQhULBPCcDxmEGbkJUOwR0YqVPvD+npIVXu4czrY4pZzBlpAxKhGfBTb+x55RDjOJO9ljpvaMo7xIrH1qlrRJPq9cElYWz3rRjJfCtlNrUE2wK3pbOlMcfrmiwjJqNG8vpyebBEnI+8" Aug 30 21:39:33 wozivol go-librespot[1679]: time="2026-08-30T21:39:33+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 21:39:33 wozivol go-librespot[1679]: time="2026-08-30T21:39:33+02:00" level=debug msg="completed keyexchange" Aug 30 21:39:33 wozivol go-librespot[1679]: time="2026-08-30T21:39:33+02:00" level=debug msg="completed challenge" Aug 30 21:39:33 wozivol go-librespot[1679]: time="2026-08-30T21:39:33+02:00" level=info msg="authenticated AP" username="u4*********************18" Aug 30 21:39:33 wozivol go-librespot[1679]: time="2026-08-30T21:39:33+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 21:39:33 wozivol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 21:39:33 wozivol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 21:39:36 wozivol volumio[1266]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 30 21:39:36 wozivol volumio[1266]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 30 21:39:36 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:39:36 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:39:36 wozivol volumio[1266]: info: Starting MyVolumio Remote Streaming Endpoints Aug 30 21:39:36 wozivol volumio[1266]: info: MyVolumio login type: Token Aug 30 21:39:36 wozivol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Aug 30 21:39:36 wozivol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 21:39:36 wozivol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 21:39:36 wozivol go-librespot[1701]: go-librespot daemon starting... Aug 30 21:39:36 wozivol go-librespot[1702]: time="2026-08-30T21:39:36+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 21:39:36 wozivol volumio[1266]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 30 21:39:36 wozivol volumio[1266]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 30 21:39:36 wozivol go-librespot[1702]: time="2026-08-30T21:39:36+02:00" level=debug msg="app state loaded" Aug 30 21:39:36 wozivol go-librespot[1702]: time="2026-08-30T21:39:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 21:39:37 wozivol go-librespot[1702]: time="2026-08-30T21:39:37+02: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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 21:39:37 wozivol go-librespot[1702]: time="2026-08-30T21:39:37+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 21:39:37 wozivol go-librespot[1702]: time="2026-08-30T21:39:37+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 21:39:37 wozivol go-librespot[1702]: time="2026-08-30T21:39:37+02:00" level=info msg="zeroconf server listening on port 41603" Aug 30 21:39:37 wozivol go-librespot[1702]: time="2026-08-30T21:39:37+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 21:39:37 wozivol go-librespot[1702]: time="2026-08-30T21:39:37+02:00" level=debug msg="obtained new client token: AAFqzm5+0Xafwi2ke9MgCOWE5ngBUsOGjTeRf3T8jytPzE8wKQ5kZxTHK39XSB3dJpt3PtL6E8zb2/I3amfWoHSIesozch/HubEKT8yLC3Tzlnvcj5H9+8zAzQomYSX5m+DGCR8mZ5ypYVq6G0t6k4FxqMK6dSsmcW2MWhO49ig85fQYthB+PgKJ5aj9BiwvA0eo+bbO8BS1voy2l5lV5Gt7oQP9lLYpPn4jkEb3B8bYpIa6KVEhoBEy" Aug 30 21:39:37 wozivol go-librespot[1702]: time="2026-08-30T21:39:37+02: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" Aug 30 21:39:37 wozivol go-librespot[1702]: time="2026-08-30T21:39:37+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 30 21:39:37 wozivol go-librespot[1702]: time="2026-08-30T21:39:37+02:00" level=debug msg="completed keyexchange" Aug 30 21:39:37 wozivol go-librespot[1702]: time="2026-08-30T21:39:37+02:00" level=debug msg="completed challenge" Aug 30 21:39:37 wozivol go-librespot[1702]: time="2026-08-30T21:39:37+02:00" level=info msg="authenticated AP" username="u4*********************18" Aug 30 21:39:37 wozivol go-librespot[1702]: time="2026-08-30T21:39:37+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 21:39:37 wozivol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 21:39:37 wozivol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 21:39:40 wozivol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Aug 30 21:39:40 wozivol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 21:39:40 wozivol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 21:39:40 wozivol go-librespot[1712]: go-librespot daemon starting... Aug 30 21:39:40 wozivol go-librespot[1713]: time="2026-08-30T21:39:40+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 21:39:40 wozivol go-librespot[1713]: time="2026-08-30T21:39:40+02:00" level=debug msg="app state loaded" Aug 30 21:39:40 wozivol go-librespot[1713]: time="2026-08-30T21:39:40+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 21:39:41 wozivol go-librespot[1713]: time="2026-08-30T21:39:41+02: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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 30 21:39:41 wozivol go-librespot[1713]: time="2026-08-30T21:39:41+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 30 21:39:41 wozivol go-librespot[1713]: time="2026-08-30T21:39:41+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 30 21:39:41 wozivol go-librespot[1713]: time="2026-08-30T21:39:41+02:00" level=info msg="zeroconf server listening on port 38583" Aug 30 21:39:41 wozivol go-librespot[1713]: time="2026-08-30T21:39:41+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 21:39:41 wozivol go-librespot[1713]: time="2026-08-30T21:39:41+02:00" level=debug msg="obtained new client token: AAEVzQ0Y8glwrqrMHdMT79KNeO1hTecBI4Wb5vg0wmwsAvxPjkZk9hxHeUb9A1f/MvrdCgN/B9Avq7fvGMrKmisgyCEkjOHaBmcATMWry9ufn0tL/qr5Sk85xYSMIDIfwxZEGZ9QWYbFXLKJX+7tAZq/xw6xOGQLPZ+JOIXOlbPjetiMrGdaDaUKH/3GmgLFlyf4+0w6TUp1NfxbHIxBZf2A575U5VcVa8gyAdIYN1Hi6ieKcFqQGGAli+I=" Aug 30 21:39:41 wozivol go-librespot[1713]: time="2026-08-30T21:39:41+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 21:39:41 wozivol go-librespot[1713]: time="2026-08-30T21:39:41+02:00" level=debug msg="completed keyexchange" Aug 30 21:39:41 wozivol go-librespot[1713]: time="2026-08-30T21:39:41+02:00" level=debug msg="completed challenge" Aug 30 21:39:41 wozivol go-librespot[1713]: time="2026-08-30T21:39:41+02:00" level=info msg="authenticated AP" username="u4*********************18" Aug 30 21:39:41 wozivol go-librespot[1713]: time="2026-08-30T21:39:41+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 21:39:41 wozivol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 21:39:41 wozivol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 21:39:44 wozivol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Aug 30 21:39:44 wozivol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 21:39:44 wozivol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 21:39:44 wozivol go-librespot[1722]: go-librespot daemon starting... Aug 30 21:39:44 wozivol go-librespot[1723]: time="2026-08-30T21:39:44+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 21:39:44 wozivol go-librespot[1723]: time="2026-08-30T21:39:44+02:00" level=debug msg="app state loaded" Aug 30 21:39:45 wozivol go-librespot[1723]: time="2026-08-30T21:39:45+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 21:39:45 wozivol go-librespot[1723]: time="2026-08-30T21:39:45+02: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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 21:39:45 wozivol go-librespot[1723]: time="2026-08-30T21:39:45+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 21:39:45 wozivol go-librespot[1723]: time="2026-08-30T21:39:45+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 21:39:45 wozivol go-librespot[1723]: time="2026-08-30T21:39:45+02:00" level=info msg="zeroconf server listening on port 44817" Aug 30 21:39:45 wozivol go-librespot[1723]: time="2026-08-30T21:39:45+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 21:39:45 wozivol go-librespot[1723]: time="2026-08-30T21:39:45+02:00" level=debug msg="obtained new client token: AAHKHxW9pmeuEesnZAVwVBApSfM8LSzL4UU92061XRlpWI4eR+ISMH+A5k80Q77rMasF2ktjfODmUjDax7nRxCqU1IQCRuU452W1E8qukGqw1WUrq4+sG+xq04EneFzd93KMUNOG4VfOTyk6J1ZABMncKXfabj8V2cEgB/UGG0ZR7pvHOaYv8QRYVzFTMTwNJYoTzIgZwcY2R/D5OTErw48DGbUPPvIELGeMQzLjRVrPthkOZtHyQTCJWNI=" Aug 30 21:39:45 wozivol go-librespot[1723]: time="2026-08-30T21:39:45+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 21:39:45 wozivol go-librespot[1723]: time="2026-08-30T21:39:45+02:00" level=debug msg="completed keyexchange" Aug 30 21:39:45 wozivol go-librespot[1723]: time="2026-08-30T21:39:45+02:00" level=debug msg="completed challenge" Aug 30 21:39:45 wozivol go-librespot[1723]: time="2026-08-30T21:39:45+02:00" level=info msg="authenticated AP" username="u4*********************18" Aug 30 21:39:45 wozivol volumio[1266]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 30 21:39:45 wozivol volumio[1266]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 30 21:39:45 wozivol volumio[1266]: info: Streaming services startup Aug 30 21:39:45 wozivol volumio[1266]: info: Starting Streaming Daemon Aug 30 21:39:45 wozivol go-librespot[1723]: time="2026-08-30T21:39:45+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 21:39:45 wozivol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 21:39:45 wozivol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 21:39:45 wozivol volumio[1266]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 30 21:39:45 wozivol sudo[1733]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 30 21:39:45 wozivol sudo[1733]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 21:39:46 wozivol sudo[1733]: pam_unix(sudo:session): session closed for user root Aug 30 21:39:46 wozivol volumio[1266]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 30 21:39:46 wozivol volumio[1266]: info: Initializing connection to go-librespot Websocket Aug 30 21:39:46 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 21:39:46 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:39:46 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 21:39:46 wozivol volumio[1266]: error: Cannot start Volumio Streaming Daemon Aug 30 21:39:46 wozivol volumio[1266]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 30 21:39:46 wozivol volumio[1266]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 30 21:39:47 wozivol volumio[1266]: error: An error occurred while listing Spotify new albums Error: Client network socket disconnected before secure TLS connection was established Aug 30 21:39:47 wozivol volumio[1266]: info: An error occurred while getting Spotify ROOT Discover Folders: Aug 30 21:39:47 wozivol volumio[1266]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 21:39:48 wozivol volumio[1266]: error: MyVolumio Custom Token format not valid, refreshing it Aug 30 21:39:48 wozivol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Aug 30 21:39:48 wozivol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 21:39:48 wozivol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 21:39:48 wozivol go-librespot[1756]: go-librespot daemon starting... Aug 30 21:39:48 wozivol go-librespot[1757]: time="2026-08-30T21:39:48+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 21:39:48 wozivol go-librespot[1757]: time="2026-08-30T21:39:48+02:00" level=debug msg="app state loaded" Aug 30 21:39:49 wozivol go-librespot[1757]: time="2026-08-30T21:39:49+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 21:39:49 wozivol go-librespot[1757]: time="2026-08-30T21:39:49+02: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]" Aug 30 21:39:49 wozivol go-librespot[1757]: time="2026-08-30T21:39:49+02: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]" Aug 30 21:39:49 wozivol go-librespot[1757]: time="2026-08-30T21:39:49+02: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]" Aug 30 21:39:49 wozivol go-librespot[1757]: time="2026-08-30T21:39:49+02:00" level=info msg="zeroconf server listening on port 35883" Aug 30 21:39:49 wozivol go-librespot[1757]: time="2026-08-30T21:39:49+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 21:39:49 wozivol volumio-remote-updater[715]: Test mode disabled Aug 30 21:39:49 wozivol volumio-remote-updater[715]: Alpha mode disabled Aug 30 21:39:49 wozivol volumio-remote-updater[715]: Alpha legacy test mode disabled Aug 30 21:39:49 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 30 21:39:49 wozivol go-librespot[1757]: time="2026-08-30T21:39:49+02:00" level=debug msg="obtained new client token: AAEKb0uW73/k19jV5iFH9kW9bKjqPpZqMZwn6mk9agIc/dRcPS1vwThBYG8O2kNiz8c7YmO1A2M0KUB+Mq5pXQkmoQaLvR60YnextvD5ZwvT5yYkn82OKX7Jw4EJUqvTZQNqqd0B/CTXppW8POsdf4e0mg4tdOURo3kX07KgXkylxPheT7eRzh58c4KRSK9stnXAbcHLxkbJJb7HdVMSGa/THKsiSiFqU0A1HLV5W6/rfGyo23rkRDRcFXs=" Aug 30 21:39:49 wozivol volumio[1266]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 30 21:39:49 wozivol go-librespot[1757]: time="2026-08-30T21:39:49+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 21:39:49 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 30 21:39:49 wozivol go-librespot[1757]: time="2026-08-30T21:39:49+02:00" level=debug msg="completed keyexchange" Aug 30 21:39:49 wozivol go-librespot[1757]: time="2026-08-30T21:39:49+02:00" level=debug msg="completed challenge" Aug 30 21:39:49 wozivol go-librespot[1757]: time="2026-08-30T21:39:49+02:00" level=info msg="authenticated AP" username="u4*********************18" Aug 30 21:39:49 wozivol go-librespot[1757]: time="2026-08-30T21:39:49+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 21:39:49 wozivol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 21:39:49 wozivol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 21:39:50 wozivol volumio[1266]: info: Initializing connection to go-librespot Websocket Aug 30 21:39:50 wozivol volumio[1266]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 21:39:51 wozivol volumio[1266]: info: MyVolumio login type: Token Aug 30 21:39:51 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 21:39:51 wozivol volumio[1266]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 30 21:39:52 wozivol volumio[1266]: info: CoreCommandRouter::volumioGetState Aug 30 21:39:52 wozivol volumio[1266]: info: CorePlayQueue::getTrack 0 Aug 30 21:39:52 wozivol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Aug 30 21:39:52 wozivol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 21:39:52 wozivol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 21:39:52 wozivol go-librespot[1767]: go-librespot daemon starting... Aug 30 21:39:52 wozivol go-librespot[1768]: time="2026-08-30T21:39:52+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 21:39:52 wozivol go-librespot[1768]: time="2026-08-30T21:39:52+02:00" level=debug msg="app state loaded" Aug 30 21:39:53 wozivol volumio[1266]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 30 21:39:53 wozivol go-librespot[1768]: time="2026-08-30T21:39:53+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 21:39:53 wozivol go-librespot[1768]: time="2026-08-30T21:39:53+02: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]" Aug 30 21:39:53 wozivol go-librespot[1768]: time="2026-08-30T21:39:53+02: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]" Aug 30 21:39:53 wozivol go-librespot[1768]: time="2026-08-30T21:39:53+02: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]" Aug 30 21:39:53 wozivol go-librespot[1768]: time="2026-08-30T21:39:53+02:00" level=info msg="zeroconf server listening on port 46575" Aug 30 21:39:53 wozivol go-librespot[1768]: time="2026-08-30T21:39:53+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 21:39:53 wozivol go-librespot[1768]: time="2026-08-30T21:39:53+02:00" level=debug msg="obtained new client token: AAFhvzPPbbWXz/Y24a1JZcsVFRRhftAOAai2POszhvzeK7WniJSpPzagxSDuQ3w/uHPx9R4M4sRbT/nFJ/twWsX0bTVaG8kut1956r2p5ZE9/D0vizDclt9sl+sjSNKj2+BZzHzDeFJgE1D38GMHkSGj45sbFXlbdJT8e75ljYxv7VxXTu72vpNfx/eJaehwhRzZUh69zCIJfcXHYphHZ9OhkpyFPF49Lwp28+RD0govUGWe0d9yDluVfpc=" Aug 30 21:39:53 wozivol volumio[1266]: info: Initializing connection to go-librespot Websocket Aug 30 21:39:53 wozivol go-librespot[1768]: time="2026-08-30T21:39:53+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 21:39:53 wozivol go-librespot[1768]: time="2026-08-30T21:39:53+02:00" level=debug msg="new websocket client" Aug 30 21:39:53 wozivol volumio[1266]: info: Connection to go-librespot Websocket established Aug 30 21:39:53 wozivol go-librespot[1768]: time="2026-08-30T21:39:53+02:00" level=debug msg="completed keyexchange" Aug 30 21:39:53 wozivol go-librespot[1768]: time="2026-08-30T21:39:53+02:00" level=debug msg="completed challenge" Aug 30 21:39:53 wozivol go-librespot[1768]: time="2026-08-30T21:39:53+02:00" level=info msg="authenticated AP" username="u4*********************18" Aug 30 21:39:53 wozivol volumio[1266]: info: MyVolumio token set successfully Aug 30 21:39:53 wozivol volumio[1266]: info: MYVOLUMIO: Adding device Aug 30 21:39:53 wozivol volumio[1266]: info: MYVOLUMIO: Evaluating Server Aug 30 21:39:53 wozivol go-librespot[1768]: time="2026-08-30T21:39:53+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 21:39:53 wozivol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 21:39:53 wozivol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 21:39:53 wozivol volumio[1266]: info: Connection to go-librespot Websocket closed Aug 30 21:39:55 wozivol volumio[1266]: info: MyVolumio status changed Aug 30 21:39:55 wozivol volumio[1266]: info: Streaming services startup Aug 30 21:39:55 wozivol volumio[1266]: info: Starting Streaming Daemon Aug 30 21:39:55 wozivol volumio[1266]: info: Removing browser output: myVolumio user plan is not superstar Aug 30 21:39:55 wozivol volumio[1266]: info: Removing audio output: Aug 30 21:39:55 wozivol volumio[1266]: info: Stoppping Tunnel 1 Aug 30 21:39:55 wozivol sudo[1799]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 30 21:39:55 wozivol sudo[1799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 21:39:55 wozivol volumio[1266]: info: Setting Geolocation for MyVolumio to eu7 Aug 30 21:39:55 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:39:55 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:39:55 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:39:55 wozivol sudo[1801]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 30 21:39:55 wozivol sudo[1801]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 21:39:55 wozivol volumio[1266]: error: Cannot start Volumio Streaming Daemon Aug 30 21:39:55 wozivol volumio[1266]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 30 21:39:55 wozivol volumio[1266]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 30 21:39:55 wozivol sudo[1799]: pam_unix(sudo:session): session closed for user root Aug 30 21:39:55 wozivol systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 30 21:39:55 wozivol systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 30 21:39:55 wozivol systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 30 21:39:55 wozivol systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 30 21:39:55 wozivol volumio[1266]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 30 21:39:55 wozivol systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 30 21:39:55 wozivol systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 30 21:39:55 wozivol sudo[1801]: pam_unix(sudo:session): session closed for user root Aug 30 21:39:56 wozivol volumio[1266]: info: Remote SSH Stopped Aug 30 21:39:56 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:56 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:39:56 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 30 21:39:56 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 30 21:39:56 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 30 21:39:56 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 30 21:39:56 wozivol volumio[1266]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 21:39:56 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 21:39:56 wozivol volumio[1266]: info: Getting Spotify volume Aug 30 21:39:56 wozivol volumio[1266]: info: Updating MyVolumio device info Aug 30 21:39:56 wozivol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Aug 30 21:39:56 wozivol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 21:39:56 wozivol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 21:39:56 wozivol go-librespot[1804]: go-librespot daemon starting... Aug 30 21:39:56 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:39:56 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:39:56 wozivol volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:39:56 wozivol go-librespot[1805]: time="2026-08-30T21:39:56+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 21:39:56 wozivol volumio[1266]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 21:39:56 wozivol go-librespot[1805]: time="2026-08-30T21:39:56+02:00" level=debug msg="app state loaded" Aug 30 21:39:57 wozivol go-librespot[1805]: time="2026-08-30T21:39:57+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 21:39:57 wozivol volumio[1266]: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 21:39:57 wozivol volumio[1266]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Aug 30 21:39:57 wozivol volumio[1266]: errno: -111, Aug 30 21:39:57 wozivol volumio[1266]: code: 'ECONNREFUSED', Aug 30 21:39:57 wozivol volumio[1266]: syscall: 'connect', Aug 30 21:39:57 wozivol volumio[1266]: address: '127.0.0.1', Aug 30 21:39:57 wozivol volumio[1266]: port: 9879, Aug 30 21:39:57 wozivol volumio[1266]: response: undefined Aug 30 21:39:57 wozivol volumio[1266]: } Aug 30 21:39:57 wozivol volumio[1266]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 21:39:57 wozivol go-librespot[1805]: time="2026-08-30T21:39:57+02: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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 21:39:57 wozivol go-librespot[1805]: time="2026-08-30T21:39:57+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 21:39:57 wozivol go-librespot[1805]: time="2026-08-30T21:39:57+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 21:39:57 wozivol go-librespot[1805]: time="2026-08-30T21:39:57+02:00" level=info msg="zeroconf server listening on port 46285" Aug 30 21:39:57 wozivol go-librespot[1805]: time="2026-08-30T21:39:57+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 21:39:57 wozivol go-librespot[1805]: time="2026-08-30T21:39:57+02:00" level=debug msg="obtained new client token: AAExqLnopgbfjI/d9rgVk91P7FFStGtqxNMiB/RiZQ34QzHIsRAUQ1MVXR2qGaVmQSJ5pXn4RS4mIyFcc9iSPSH+3tKNbXg3JaxtwjN2HTd6Y1ORghJjP4Sj7+AJ45QfdsosrtWGtFn5rQXkHrUfROHoUYvreR/3v6LkbDbYP01Lbj7tzMPhWP2Lt7MCSvzZAugEJjVRtpVVFYzTo0SojPnVrQcdq0KH8pTsIj5yLMyPbqGtIVvUOAMo+Dg=" Aug 30 21:39:57 wozivol go-librespot[1805]: time="2026-08-30T21:39:57+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 21:39:57 wozivol go-librespot[1805]: time="2026-08-30T21:39:57+02:00" level=debug msg="completed keyexchange" Aug 30 21:39:57 wozivol go-librespot[1805]: time="2026-08-30T21:39:57+02:00" level=debug msg="completed challenge" Aug 30 21:39:57 wozivol go-librespot[1805]: time="2026-08-30T21:39:57+02:00" level=info msg="authenticated AP" username="u4*********************18" Aug 30 21:39:57 wozivol go-librespot[1805]: time="2026-08-30T21:39:57+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 21:39:57 wozivol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 21:39:57 wozivol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 21:39:59 wozivol upmpdcli[1840]: writing RSA key Aug 30 21:40:00 wozivol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Aug 30 21:40:00 wozivol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 21:40:01 wozivol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 21:40:01 wozivol go-librespot[1850]: go-librespot daemon starting... Aug 30 21:40:01 wozivol go-librespot[1851]: time="2026-08-30T21:40:01+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 21:40:01 wozivol go-librespot[1851]: time="2026-08-30T21:40:01+02:00" level=debug msg="app state loaded" Aug 30 21:40:01 wozivol go-librespot[1851]: time="2026-08-30T21:40:01+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 21:40:01 wozivol go-librespot[1851]: time="2026-08-30T21:40:01+02: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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 21:40:01 wozivol go-librespot[1851]: time="2026-08-30T21:40:01+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 21:40:01 wozivol go-librespot[1851]: time="2026-08-30T21:40:01+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 21:40:01 wozivol go-librespot[1851]: time="2026-08-30T21:40:01+02:00" level=info msg="zeroconf server listening on port 37813" Aug 30 21:40:01 wozivol go-librespot[1851]: time="2026-08-30T21:40:01+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 21:40:01 wozivol go-librespot[1851]: time="2026-08-30T21:40:01+02:00" level=debug msg="obtained new client token: AAHUezlQxwWDQ/+a4JR/HUtalZfpkavkXQPDkb/JEas5tgihVp23BEtecap81EyOt+85exX78IK0TCWAWQ0E+xVyGJCQco7Y+RAGp9e6emP5gV/R9M9NISYrz+62pT60GUBH5o62g2qSW07OAxDl6WarbxdTfPqedNkDQFl3uVYQAdybuqLgO0rMJt7YE77CnbHIhCrIBv9667siCWLtT9MI8HCl7KUMXeJiLq/dODzb+K16LMetILubegA=" Aug 30 21:40:01 wozivol go-librespot[1851]: time="2026-08-30T21:40:01+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 21:40:01 wozivol go-librespot[1851]: time="2026-08-30T21:40:01+02:00" level=debug msg="completed keyexchange" Aug 30 21:40:01 wozivol go-librespot[1851]: time="2026-08-30T21:40:01+02:00" level=debug msg="completed challenge" Aug 30 21:40:01 wozivol go-librespot[1851]: time="2026-08-30T21:40:01+02:00" level=info msg="authenticated AP" username="u4*********************18" Aug 30 21:40:01 wozivol go-librespot[1851]: time="2026-08-30T21:40:01+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 21:40:01 wozivol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 21:40:01 wozivol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 21:40:03 wozivol sudo[1863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-30 21:39' Aug 30 21:40:03 wozivol sudo[1863]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"