Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 29 09:23:00 jukeboksi2 sudo[1747]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 09:23:00 jukeboksi2 sudo[1747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 09:23:00 jukeboksi2 sudo[1751]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 09:23:00 jukeboksi2 sudo[1751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 09:23:00 jukeboksi2 volumio5-onboarding[1640]: time=2026-08-29T09:23:00.088+03: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 29 09:23:00 jukeboksi2 volumio5-onboarding[1640]: time=2026-08-29T09:23:00.089+03: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 29 09:23:00 jukeboksi2 volumio5-onboarding[1640]: time=2026-08-29T09:23:00.089+03: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 29 09:23:00 jukeboksi2 volumio[1243]: info: Asound.conf file unchanged, so no further update is needed Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: Output device has changed, restarting MPD Aug 29 09:23:00 jukeboksi2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 29 09:23:00 jukeboksi2 systemd[1]: shairport-sync.service: Deactivated successfully. Aug 29 09:23:00 jukeboksi2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 09:23:00 jukeboksi2 systemd[1]: shairport-sync.service: Consumed 1.612s CPU time. Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: Output device has changed, restarting Shairport Sync Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 09:23:00 jukeboksi2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 09:23:00 jukeboksi2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 29 09:23:00 jukeboksi2 sudo[1747]: pam_unix(sudo:session): session closed for user root Aug 29 09:23:00 jukeboksi2 systemd[1]: shairport-sync.service: Deactivated successfully. Aug 29 09:23:00 jukeboksi2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 09:23:00 jukeboksi2 volumio[1243]: error: updateQueue error: null Aug 29 09:23:00 jukeboksi2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 09:23:00 jukeboksi2 sudo[1757]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 09:23:00 jukeboksi2 sudo[1757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 09:23:00 jukeboksi2 sudo[1751]: pam_unix(sudo:session): session closed for user root Aug 29 09:23:00 jukeboksi2 sudo[1749]: pam_unix(sudo:session): session closed for user root Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: Shairport-Sync Started Aug 29 09:23:00 jukeboksi2 sudo[1759]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 09:23:00 jukeboksi2 sudo[1759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 09:23:00 jukeboksi2 volumio[1243]: Error adding Membership: Error: addMembership EINVAL Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: Shairport-Sync Started Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: Shairport-Sync Started Aug 29 09:23:00 jukeboksi2 sudo[1757]: pam_unix(sudo:session): session closed for user root Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: Received Get System Info Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: Discovery: Getting this device information Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 09:23:00 jukeboksi2 systemd[1]: Stopping mpd_oled_plugin.service - MPD OLED Plugin... Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 09:23:00 jukeboksi2 volumio5-onboarding[1640]: time=2026-08-29T09:23:00.559+03:00 level=INFO msg="enabling local network discovery" Aug 29 09:23:00 jukeboksi2 systemd[1]: mpd_oled_plugin.service: Deactivated successfully. Aug 29 09:23:00 jukeboksi2 systemd[1]: Stopped mpd_oled_plugin.service - MPD OLED Plugin. Aug 29 09:23:00 jukeboksi2 systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 29 09:23:00 jukeboksi2 volumio5-onboarding[1640]: time=2026-08-29T09:23:00.596+03:00 level=INFO msg="enabling BLE discovery" Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: MPD Permissions set Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 09:23:00 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 09:23:00 jukeboksi2 systemd[1]: mpd.service: Deactivated successfully. Aug 29 09:23:00 jukeboksi2 systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 29 09:23:00 jukeboksi2 systemd[1]: mpd.service: Consumed 2.935s CPU time. Aug 29 09:23:00 jukeboksi2 systemd[1]: mpd.socket: Deactivated successfully. Aug 29 09:23:00 jukeboksi2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 29 09:23:00 jukeboksi2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 29 09:23:00 jukeboksi2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 29 09:23:00 jukeboksi2 systemd[1]: Starting mpd.service - Music Player Daemon... Aug 29 09:23:01 jukeboksi2 sudo[1796]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 09:23:01 jukeboksi2 sudo[1796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 09:23:01 jukeboksi2 sudo[1796]: pam_unix(sudo:session): session closed for user root Aug 29 09:23:01 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 09:23:01 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 09:23:01 jukeboksi2 volumio[1243]: error: Upnp client error: Error: This socket has been ended by the other party Aug 29 09:23:01 jukeboksi2 volumio5-onboarding[1640]: time=2026-08-29T09:23:01.506+03:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 29 09:23:01 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:01 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:01 jukeboksi2 volumio[1243]: info: Starting Shairport Sync Aug 29 09:23:01 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:01 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:01 jukeboksi2 sudo[1801]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 09:23:01 jukeboksi2 sudo[1801]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 09:23:02 jukeboksi2 volumio[1243]: SPOTIFY: User informations: {"account_id":"h1elnVPAy4","country":"FI","display_name":"quadron","email":"mikko.veijalainen@welho.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/quadron"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/quadron","id":"quadron","images":[],"product":"premium","type":"user","uri":"spotify:user:quadron"} Aug 29 09:23:02 jukeboksi2 volumio[1243]: info: Spotify Successfully logged in Aug 29 09:23:02 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 09:23:02 jukeboksi2 volumio[1243]: info: [1787984582012] CoreMusicLibrary::Adding element Spotify Aug 29 09:23:02 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 09:23:02 jukeboksi2 volumio[1243]: Cannot find translation for source Radio Paradise (RP2) Aug 29 09:23:02 jukeboksi2 volumio[1243]: Cannot find translation for source YouTube Music Aug 29 09:23:02 jukeboksi2 volumio[1243]: Cannot find translation for source 80s80s Radio Aug 29 09:23:02 jukeboksi2 volumio[1243]: Cannot find translation for source Spotify Aug 29 09:23:02 jukeboksi2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 29 09:23:02 jukeboksi2 systemd[1]: shairport-sync.service: Deactivated successfully. Aug 29 09:23:02 jukeboksi2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 09:23:02 jukeboksi2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 09:23:02 jukeboksi2 sudo[1801]: pam_unix(sudo:session): session closed for user root Aug 29 09:23:02 jukeboksi2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Aug 29 09:23:02 jukeboksi2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:02 jukeboksi2 volumio[1243]: info: go-librespot daemon successfully initialized Aug 29 09:23:02 jukeboksi2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:02 jukeboksi2 go-librespot[1821]: go-librespot daemon starting... Aug 29 09:23:02 jukeboksi2 volumio[1243]: info: Shairport-Sync Started Aug 29 09:23:02 jukeboksi2 go-librespot[1822]: time="2026-08-29T09:23:02+03:00" level=info msg="running go-librespot 0.7.1" Aug 29 09:23:02 jukeboksi2 go-librespot[1822]: time="2026-08-29T09:23:02+03:00" level=debug msg="app state loaded" Aug 29 09:23:02 jukeboksi2 go-librespot[1822]: time="2026-08-29T09:23:02+03:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:23:02 jukeboksi2 mpd[1798]: 2026-08-29T09:23:02 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 09:23:02 jukeboksi2 systemd[1]: Started mpd.service - Music Player Daemon. Aug 29 09:23:02 jukeboksi2 sudo[1759]: pam_unix(sudo:session): session closed for user root Aug 29 09:23:02 jukeboksi2 systemd[1]: Started mpd_oled_plugin.service - MPD OLED Plugin. Aug 29 09:23:03 jukeboksi2 go-librespot[1822]: time="2026-08-29T09:23:03+03: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 29 09:23:03 jukeboksi2 go-librespot[1822]: time="2026-08-29T09:23:03+03: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 29 09:23:03 jukeboksi2 go-librespot[1822]: time="2026-08-29T09:23:03+03: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 29 09:23:03 jukeboksi2 go-librespot[1822]: time="2026-08-29T09:23:03+03:00" level=info msg="zeroconf server listening on port 44571" Aug 29 09:23:03 jukeboksi2 go-librespot[1822]: time="2026-08-29T09:23:03+03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 09:23:03 jukeboksi2 go-librespot[1822]: time="2026-08-29T09:23:03+03:00" level=debug msg="obtained new client token: AAF6+pnqjXxFjdMk4zU84JjvR4L1nX1t+tZxENwcz2HMVs1oqxp0V+hYQ8q5jtBY/gWkJbJCgAb6FC4TKitZ7iLFjhQhJu6aSUSgdyIEGgs7GQYATLi0kvX/Op1WMWLPfowVGefJ/wyod+eU9GkPrOJ/LFT/l44qSrUvvDPcPGqPiboTN0LD64IttomhN5EiuaqpTIAR0uYhwhO5tzJ095gykLpjOwiFU9wUjpm6GzhfMxj4oPLrjw==" Aug 29 09:23:03 jukeboksi2 volumio[1243]: error: updateQueue error: null Aug 29 09:23:03 jukeboksi2 go-librespot[1822]: time="2026-08-29T09:23:03+03:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 09:23:03 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:03 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:03 jukeboksi2 go-librespot[1822]: time="2026-08-29T09:23:03+03:00" level=debug msg="completed keyexchange" Aug 29 09:23:03 jukeboksi2 go-librespot[1822]: time="2026-08-29T09:23:03+03:00" level=debug msg="completed challenge" Aug 29 09:23:03 jukeboksi2 go-librespot[1822]: time="2026-08-29T09:23:03+03:00" level=info msg="authenticated AP" username="qu***on" Aug 29 09:23:03 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:03 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:03 jukeboksi2 go-librespot[1822]: time="2026-08-29T09:23:03+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:23:03 jukeboksi2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:23:03 jukeboksi2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:23:03 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:03 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:03 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:03 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:04 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:04 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:04 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:04 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:04 jukeboksi2 volumio[1243]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 29 09:23:04 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:04 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:04 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:04 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:05 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:05 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:05 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:05 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:05 jukeboksi2 volumio[1243]: info: Initializing connection to go-librespot Websocket Aug 29 09:23:05 jukeboksi2 volumio[1243]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:23:05 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:05 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:05 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:05 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:06 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:06 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:06 jukeboksi2 sudo[1840]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 09:23:06 jukeboksi2 sudo[1840]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 09:23:06 jukeboksi2 sudo[1842]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 09:23:06 jukeboksi2 sudo[1842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 09:23:06 jukeboksi2 sudo[1840]: pam_unix(sudo:session): session closed for user root Aug 29 09:23:06 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:06 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:06 jukeboksi2 sudo[1842]: pam_unix(sudo:session): session closed for user root Aug 29 09:23:06 jukeboksi2 volumio[1243]: verbose: New Socket.io Connection to 192.168.10.59 from 192.168.10.243 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:154.0) Gecko/20100101 Firefox/154.0 Engine version: 3 Transport: polling Total Clients: 4 Aug 29 09:23:06 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 09:23:06 jukeboksi2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Aug 29 09:23:06 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:06 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:06 jukeboksi2 systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Aug 29 09:23:06 jukeboksi2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:06 jukeboksi2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:06 jukeboksi2 go-librespot[1861]: go-librespot daemon starting... Aug 29 09:23:06 jukeboksi2 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 29 09:23:06 jukeboksi2 systemd[1]: e2scrub_all.service: Deactivated successfully. Aug 29 09:23:06 jukeboksi2 systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Aug 29 09:23:06 jukeboksi2 go-librespot[1863]: time="2026-08-29T09:23:06+03:00" level=info msg="running go-librespot 0.7.1" Aug 29 09:23:06 jukeboksi2 go-librespot[1863]: time="2026-08-29T09:23:06+03:00" level=debug msg="app state loaded" Aug 29 09:23:06 jukeboksi2 go-librespot[1863]: time="2026-08-29T09:23:06+03:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:23:06 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 09:23:06 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 09:23:07 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:07 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:07 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 09:23:07 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 09:23:07 jukeboksi2 volumio[1243]: info: Received Get System Info Aug 29 09:23:07 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 09:23:07 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 09:23:07 jukeboksi2 volumio[1243]: info: Discovery: Getting this device information Aug 29 09:23:07 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:07 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:07 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 09:23:07 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:07 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:07 jukeboksi2 volumio[1243]: info: Listing playlists Aug 29 09:23:07 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 29 09:23:07 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 09:23:07 jukeboksi2 go-librespot[1863]: time="2026-08-29T09:23:07+03: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 29 09:23:07 jukeboksi2 go-librespot[1863]: time="2026-08-29T09:23:07+03: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 29 09:23:07 jukeboksi2 go-librespot[1863]: time="2026-08-29T09:23:07+03: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 29 09:23:07 jukeboksi2 go-librespot[1863]: time="2026-08-29T09:23:07+03:00" level=info msg="zeroconf server listening on port 38221" Aug 29 09:23:07 jukeboksi2 go-librespot[1863]: time="2026-08-29T09:23:07+03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 09:23:07 jukeboksi2 go-librespot[1863]: time="2026-08-29T09:23:07+03:00" level=debug msg="obtained new client token: AAH0C1JTFxTNhfEEp979yM7tSgFBITHLVQutrQQyZgopKjzPqWvA1MkzcbuxiudppabSn8CS+kxdcsHnLJQemChOiL4wN99oEO3sbsgVI3FXLDb/pEk1dOka1YCZCLf/2aZ6nlNEbHeDO+JxBmXdDJzD51x7n3FTogAHdSDyZGQbhpRDpBwylxZEfrYslaMi+uGOVQi+JMot8aMtQ14idHbc4UPiEF6hS402PDAU+nxhw3+g0BJ2jovv" Aug 29 09:23:07 jukeboksi2 go-librespot[1863]: time="2026-08-29T09:23:07+03: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 29 09:23:07 jukeboksi2 go-librespot[1863]: time="2026-08-29T09:23:07+03:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 29 09:23:07 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:07 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:07 jukeboksi2 go-librespot[1863]: time="2026-08-29T09:23:07+03:00" level=debug msg="completed keyexchange" Aug 29 09:23:07 jukeboksi2 go-librespot[1863]: time="2026-08-29T09:23:07+03:00" level=debug msg="completed challenge" Aug 29 09:23:07 jukeboksi2 go-librespot[1863]: time="2026-08-29T09:23:07+03:00" level=info msg="authenticated AP" username="qu***on" Aug 29 09:23:07 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:07 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:07 jukeboksi2 go-librespot[1863]: time="2026-08-29T09:23:07+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:23:07 jukeboksi2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:23:07 jukeboksi2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:23:07 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:07 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:07 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:07 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: Received Get System Info Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: Discovery: Getting this device information Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: Adding plugin bluetooth to MyMusic Plugins Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: Adding plugin multiroom to MyMusic Plugins Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: Adding plugin metavolumio to MyMusic Plugins Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: Adding plugin cd_controller to MyMusic Plugins Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 29 09:23:08 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 29 09:23:08 jukeboksi2 systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 29 09:23:08 jukeboksi2 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 29 09:23:09 jukeboksi2 upmpdcli[1904]: writing RSA key Aug 29 09:23:10 jukeboksi2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Aug 29 09:23:10 jukeboksi2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:11 jukeboksi2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:11 jukeboksi2 go-librespot[1908]: go-librespot daemon starting... Aug 29 09:23:11 jukeboksi2 go-librespot[1909]: time="2026-08-29T09:23:11+03:00" level=info msg="running go-librespot 0.7.1" Aug 29 09:23:11 jukeboksi2 go-librespot[1909]: time="2026-08-29T09:23:11+03:00" level=debug msg="app state loaded" Aug 29 09:23:11 jukeboksi2 go-librespot[1909]: time="2026-08-29T09:23:11+03:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:23:11 jukeboksi2 go-librespot[1909]: time="2026-08-29T09:23:11+03: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 29 09:23:11 jukeboksi2 go-librespot[1909]: time="2026-08-29T09:23:11+03: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 29 09:23:11 jukeboksi2 go-librespot[1909]: time="2026-08-29T09:23:11+03: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 29 09:23:11 jukeboksi2 go-librespot[1909]: time="2026-08-29T09:23:11+03:00" level=info msg="zeroconf server listening on port 34115" Aug 29 09:23:11 jukeboksi2 go-librespot[1909]: time="2026-08-29T09:23:11+03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 09:23:11 jukeboksi2 go-librespot[1909]: time="2026-08-29T09:23:11+03:00" level=debug msg="obtained new client token: AAEHbCSL2uPbcR0OV2hZ8J/qnJxC4wxNZyLo/BakIlDqs+VafMMSprKGmEbOmKafQ1zV6aN4VoKCSWe9NBpuRPKHG+mvDuIW+HCyzGFsr0EZwRUtkEUhRPWKwVzSZRbL4YK4xgecTfoQRuqBY+WGmariZUUEzgfe+iyb+J2rXvs0JCCy4l9RMdhNHoTEWzqjl4u59gwkwfFREjpbBztgcMrhwOK495/eqOFnYrp/u0mnfHJ1IkJI0d8X" Aug 29 09:23:11 jukeboksi2 go-librespot[1909]: time="2026-08-29T09:23:11+03:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 09:23:11 jukeboksi2 go-librespot[1909]: time="2026-08-29T09:23:11+03:00" level=debug msg="completed keyexchange" Aug 29 09:23:11 jukeboksi2 go-librespot[1909]: time="2026-08-29T09:23:11+03:00" level=debug msg="completed challenge" Aug 29 09:23:11 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 29 09:23:11 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 29 09:23:11 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 09:23:11 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 09:23:11 jukeboksi2 volumio[1243]: info: Starting MyVolumio Remote Streaming Endpoints Aug 29 09:23:11 jukeboksi2 volumio[1243]: info: MyVolumio login type: Token Aug 29 09:23:11 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 29 09:23:11 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 29 09:23:12 jukeboksi2 go-librespot[1909]: time="2026-08-29T09:23:12+03:00" level=info msg="authenticated AP" username="qu***on" Aug 29 09:23:12 jukeboksi2 go-librespot[1909]: time="2026-08-29T09:23:12+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:23:12 jukeboksi2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:23:12 jukeboksi2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:23:14 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 29 09:23:14 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 29 09:23:14 jukeboksi2 volumio[1243]: info: Streaming services startup Aug 29 09:23:14 jukeboksi2 volumio[1243]: info: Starting Streaming Daemon Aug 29 09:23:14 jukeboksi2 sudo[1919]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 29 09:23:14 jukeboksi2 volumio[1243]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 29 09:23:14 jukeboksi2 sudo[1919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 09:23:14 jukeboksi2 volumio[1243]: info: Initializing connection to go-librespot Websocket Aug 29 09:23:14 jukeboksi2 sudo[1919]: pam_unix(sudo:session): session closed for user root Aug 29 09:23:15 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 29 09:23:15 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Aug 29 09:23:15 jukeboksi2 volumio[1243]: info: MyVolumio login type: Token Aug 29 09:23:15 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 09:23:15 jukeboksi2 volumio[1243]: info: Received Get System Info Aug 29 09:23:15 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 09:23:15 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 09:23:15 jukeboksi2 volumio[1243]: info: Discovery: Getting this device information Aug 29 09:23:15 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:15 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:15 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 09:23:15 jukeboksi2 volumio[1243]: error: Cannot start Volumio Streaming Daemon Aug 29 09:23:15 jukeboksi2 volumio[1243]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 29 09:23:15 jukeboksi2 volumio[1243]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 29 09:23:15 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 09:23:15 jukeboksi2 volumio[1243]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:23:15 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:15 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:15 jukeboksi2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Aug 29 09:23:15 jukeboksi2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:15 jukeboksi2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:15 jukeboksi2 go-librespot[1925]: go-librespot daemon starting... Aug 29 09:23:15 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Aug 29 09:23:15 jukeboksi2 volumio[1243]: info: MyVolumio login type: Token Aug 29 09:23:15 jukeboksi2 volumio[1243]: error: MyVolumio Custom Token format not valid, refreshing it Aug 29 09:23:15 jukeboksi2 go-librespot[1926]: time="2026-08-29T09:23:15+03:00" level=info msg="running go-librespot 0.7.1" Aug 29 09:23:15 jukeboksi2 go-librespot[1926]: time="2026-08-29T09:23:15+03:00" level=debug msg="app state loaded" Aug 29 09:23:15 jukeboksi2 go-librespot[1926]: time="2026-08-29T09:23:15+03:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:23:15 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:15 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:15 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 09:23:15 jukeboksi2 go-librespot[1926]: time="2026-08-29T09:23:15+03: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 29 09:23:15 jukeboksi2 go-librespot[1926]: time="2026-08-29T09:23:15+03: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 29 09:23:15 jukeboksi2 go-librespot[1926]: time="2026-08-29T09:23:15+03: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 29 09:23:15 jukeboksi2 go-librespot[1926]: time="2026-08-29T09:23:15+03:00" level=info msg="zeroconf server listening on port 36307" Aug 29 09:23:15 jukeboksi2 go-librespot[1926]: time="2026-08-29T09:23:15+03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 09:23:16 jukeboksi2 go-librespot[1926]: time="2026-08-29T09:23:16+03:00" level=debug msg="obtained new client token: AAGHXXN091FcjPXwDCJadCVGFDEdYG/UDTEBaoMe2zuzkUu6Wqxysnx8jY0jGUbbbUY/iez7GwHRHe0ej+o+jM5DWQBYcqZQZDynxB44WaD2HrDxTZerDAtSlCK8onEa0E5ZPW00pTb1mWNX1PCmczx+s8qdmnfF5hBopnQFnLfbZPqXrhGZw+yXx72DJ5djUGpmkwmnJ2RVF30FzL0EoT2bu/sR1RQ2vAKDrWBtK2OeNhK7xTqxew==" Aug 29 09:23:16 jukeboksi2 go-librespot[1926]: time="2026-08-29T09:23:16+03:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 09:23:16 jukeboksi2 go-librespot[1926]: time="2026-08-29T09:23:16+03:00" level=debug msg="completed keyexchange" Aug 29 09:23:16 jukeboksi2 go-librespot[1926]: time="2026-08-29T09:23:16+03:00" level=debug msg="completed challenge" Aug 29 09:23:16 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:16 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:16 jukeboksi2 go-librespot[1926]: time="2026-08-29T09:23:16+03:00" level=info msg="authenticated AP" username="qu***on" Aug 29 09:23:16 jukeboksi2 volumio[1243]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 29 09:23:16 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 09:23:16 jukeboksi2 go-librespot[1926]: time="2026-08-29T09:23:16+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:23:16 jukeboksi2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:23:16 jukeboksi2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:23:16 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:16 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:16 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:16 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:16 jukeboksi2 volumio[1243]: info: MyVolumio login type: Token Aug 29 09:23:17 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:17 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:17 jukeboksi2 volumio[1243]: info: MyVolumio token set successfully Aug 29 09:23:17 jukeboksi2 volumio[1243]: info: MYVOLUMIO: Adding device Aug 29 09:23:17 jukeboksi2 volumio[1243]: info: MYVOLUMIO: Evaluating Server Aug 29 09:23:17 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:17 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:17 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:17 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: MyVolumio status changed Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: Streaming services startup Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: Starting Streaming Daemon Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: Removing browser output: myVolumio user plan is not superstar Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: Removing audio output: Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: Stoppping Tunnel 1 Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: Received Get System Info Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: Discovery: Getting this device information Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: Received Get System Info Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: Discovery: Getting this device information Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: Received Get System Info Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: Discovery: Getting this device information Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:18 jukeboksi2 sudo[1972]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 09:23:18 jukeboksi2 sudo[1972]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 09:23:18 jukeboksi2 sudo[1970]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 29 09:23:18 jukeboksi2 sudo[1970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: Initializing connection to go-librespot Websocket Aug 29 09:23:18 jukeboksi2 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 29 09:23:18 jukeboksi2 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 29 09:23:18 jukeboksi2 volumio[1243]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:23:18 jukeboksi2 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 29 09:23:18 jukeboksi2 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 29 09:23:18 jukeboksi2 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 29 09:23:18 jukeboksi2 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 29 09:23:18 jukeboksi2 sudo[1970]: pam_unix(sudo:session): session closed for user root Aug 29 09:23:18 jukeboksi2 sudo[1972]: pam_unix(sudo:session): session closed for user root Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: Remote SSH Stopped Aug 29 09:23:18 jukeboksi2 volumio[1243]: error: Cannot start Volumio Streaming Daemon Aug 29 09:23:18 jukeboksi2 volumio[1243]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 29 09:23:18 jukeboksi2 volumio[1243]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:18 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:19 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:19 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:19 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:19 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:19 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:19 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:19 jukeboksi2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Aug 29 09:23:19 jukeboksi2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:19 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:19 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:19 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:19 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:19 jukeboksi2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:19 jukeboksi2 go-librespot[1975]: go-librespot daemon starting... Aug 29 09:23:19 jukeboksi2 go-librespot[1976]: time="2026-08-29T09:23:19+03:00" level=info msg="running go-librespot 0.7.1" Aug 29 09:23:19 jukeboksi2 go-librespot[1976]: time="2026-08-29T09:23:19+03:00" level=debug msg="app state loaded" Aug 29 09:23:19 jukeboksi2 go-librespot[1976]: time="2026-08-29T09:23:19+03:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:23:19 jukeboksi2 volumio[1243]: info: Setting Geolocation for MyVolumio to eu2 Aug 29 09:23:19 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 09:23:19 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 09:23:19 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 09:23:20 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:20 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:20 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:20 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:20 jukeboksi2 go-librespot[1976]: time="2026-08-29T09:23:20+03: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 29 09:23:20 jukeboksi2 go-librespot[1976]: time="2026-08-29T09:23:20+03: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 29 09:23:20 jukeboksi2 go-librespot[1976]: time="2026-08-29T09:23:20+03: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 29 09:23:20 jukeboksi2 go-librespot[1976]: time="2026-08-29T09:23:20+03:00" level=info msg="zeroconf server listening on port 38395" Aug 29 09:23:20 jukeboksi2 go-librespot[1976]: time="2026-08-29T09:23:20+03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 09:23:20 jukeboksi2 volumio[1243]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 29 09:23:20 jukeboksi2 go-librespot[1976]: time="2026-08-29T09:23:20+03:00" level=debug msg="obtained new client token: AAGI/N0cvxjFy9AkjGnxjOWFQyHFkmQ1mrnpEuKyXRf08QlFkXXMZaiHrzxYm0rsYcx3J6iPw2cBsRsBc2ADCXWEBnnbxSiA4rfHzigXpK7ZGKtnf2YDw1rLTdx5khUGm0kHtQXLSHLphCM+Y9Po8XmHKJvV6eSjEzJJXP4CpcHd7fW3hN4TZN/gvQm0ro4OEA4UHTq1zSg1/z0dHyUzdvhcsMR1GybkseorEL9tpe9epBgoIW+7cF8N" Aug 29 09:23:20 jukeboksi2 go-librespot[1976]: time="2026-08-29T09:23:20+03:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 09:23:20 jukeboksi2 go-librespot[1976]: time="2026-08-29T09:23:20+03:00" level=debug msg="completed keyexchange" Aug 29 09:23:20 jukeboksi2 go-librespot[1976]: time="2026-08-29T09:23:20+03:00" level=debug msg="completed challenge" Aug 29 09:23:20 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:20 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:20 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:20 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:20 jukeboksi2 go-librespot[1976]: time="2026-08-29T09:23:20+03:00" level=info msg="authenticated AP" username="qu***on" Aug 29 09:23:20 jukeboksi2 go-librespot[1976]: time="2026-08-29T09:23:20+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:23:20 jukeboksi2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:23:20 jukeboksi2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:23:20 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:20 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:20 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:20 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:20 jukeboksi2 volumio[1243]: info: Updating MyVolumio device info Aug 29 09:23:20 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 09:23:20 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 09:23:20 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 09:23:21 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:21 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:21 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:21 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:21 jukeboksi2 volumio[1243]: info: Initializing connection to go-librespot Websocket Aug 29 09:23:21 jukeboksi2 volumio[1243]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:23:21 jukeboksi2 volumio[1243]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 29 09:23:21 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:21 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:21 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:21 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:21 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:21 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:21 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:21 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:22 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:22 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:22 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:22 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:22 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:22 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:22 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:22 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:23 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:23 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:23 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:23 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:23 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:23 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:23 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:23 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:23 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:23 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:23 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:23 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:23 jukeboksi2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Aug 29 09:23:23 jukeboksi2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:24 jukeboksi2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:24 jukeboksi2 go-librespot[1985]: go-librespot daemon starting... Aug 29 09:23:24 jukeboksi2 go-librespot[1986]: time="2026-08-29T09:23:24+03:00" level=info msg="running go-librespot 0.7.1" Aug 29 09:23:24 jukeboksi2 go-librespot[1986]: time="2026-08-29T09:23:24+03:00" level=debug msg="app state loaded" Aug 29 09:23:24 jukeboksi2 go-librespot[1986]: time="2026-08-29T09:23:24+03:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:23:24 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:24 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:24 jukeboksi2 volumio[1243]: info: MYVOLUMIO: Adding device Aug 29 09:23:24 jukeboksi2 volumio[1243]: info: MYVOLUMIO: Evaluating Server Aug 29 09:23:24 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:24 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:24 jukeboksi2 go-librespot[1986]: time="2026-08-29T09:23:24+03: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 29 09:23:24 jukeboksi2 go-librespot[1986]: time="2026-08-29T09:23:24+03: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 29 09:23:24 jukeboksi2 go-librespot[1986]: time="2026-08-29T09:23:24+03: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 29 09:23:24 jukeboksi2 go-librespot[1986]: time="2026-08-29T09:23:24+03:00" level=info msg="zeroconf server listening on port 43331" Aug 29 09:23:24 jukeboksi2 go-librespot[1986]: time="2026-08-29T09:23:24+03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 09:23:24 jukeboksi2 go-librespot[1986]: time="2026-08-29T09:23:24+03:00" level=debug msg="obtained new client token: AAEWO/TSIDHznsDer1fQ2L132jBxhXiT6oOXrJSWtwiQl0DjcEOX+oy1OCbvfi1W5HR8fq1YmS28lMC4X5FwkUtvyitB0stBp1FPpvSAVs71+mPOs/ONLG2ga7Xn5aiQ8IVhI8sYVk6yRsdpGBfTDnnoqqm5Im72B8SY9CuWc11Zm9cZDoqmlnfMsGDPYYKOLrZpYOvg40dLx1pEWAUnwfeEBfrXVUBXHik1GykiO03cF8T67elFY+3h" Aug 29 09:23:24 jukeboksi2 go-librespot[1986]: time="2026-08-29T09:23:24+03:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 09:23:24 jukeboksi2 go-librespot[1986]: time="2026-08-29T09:23:24+03:00" level=debug msg="completed keyexchange" Aug 29 09:23:24 jukeboksi2 go-librespot[1986]: time="2026-08-29T09:23:24+03:00" level=debug msg="completed challenge" Aug 29 09:23:24 jukeboksi2 go-librespot[1986]: time="2026-08-29T09:23:24+03:00" level=info msg="authenticated AP" username="qu***on" Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: Initializing connection to go-librespot Websocket Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: Received Get System Version Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: Received Get System Info Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: Discovery: Getting this device information Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 09:23:25 jukeboksi2 go-librespot[1986]: time="2026-08-29T09:23:25+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:23:25 jukeboksi2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:23:25 jukeboksi2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:25 jukeboksi2 volumio-remote-updater[814]: Test mode disabled Aug 29 09:23:25 jukeboksi2 volumio-remote-updater[814]: Alpha mode disabled Aug 29 09:23:25 jukeboksi2 volumio-remote-updater[814]: Alpha legacy test mode disabled Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 29 09:23:25 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 29 09:23:26 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:26 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:26 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:26 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:26 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:26 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:26 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:26 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:26 jukeboksi2 volumio[1243]: info: Setting Geolocation for MyVolumio to eu8 Aug 29 09:23:26 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 09:23:26 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 09:23:26 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 09:23:26 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:26 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:26 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 09:23:26 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:26 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:26 jukeboksi2 volumio[1243]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 29 09:23:27 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:27 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:27 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:27 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:27 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:27 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:27 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetQueue Aug 29 09:23:27 jukeboksi2 volumio[1243]: info: CoreStateMachine::getQueue Aug 29 09:23:27 jukeboksi2 volumio[1243]: info: CorePlayQueue::getQueue Aug 29 09:23:27 jukeboksi2 volumio[1243]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 29 09:23:27 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:27 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:27 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:27 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:27 jukeboksi2 volumio[1243]: info: Updating MyVolumio device info Aug 29 09:23:27 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 09:23:27 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 09:23:27 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 09:23:27 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:27 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:28 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:28 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:28 jukeboksi2 volumio[1243]: info: Initializing connection to go-librespot Websocket Aug 29 09:23:28 jukeboksi2 volumio[1243]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:23:28 jukeboksi2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Aug 29 09:23:28 jukeboksi2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:28 jukeboksi2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:28 jukeboksi2 go-librespot[2031]: go-librespot daemon starting... Aug 29 09:23:28 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:28 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:28 jukeboksi2 go-librespot[2032]: time="2026-08-29T09:23:28+03:00" level=info msg="running go-librespot 0.7.1" Aug 29 09:23:28 jukeboksi2 go-librespot[2032]: time="2026-08-29T09:23:28+03:00" level=debug msg="app state loaded" Aug 29 09:23:28 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:28 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:28 jukeboksi2 go-librespot[2032]: time="2026-08-29T09:23:28+03:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:23:28 jukeboksi2 volumio[1243]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 29 09:23:28 jukeboksi2 go-librespot[2032]: time="2026-08-29T09:23:28+03: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 29 09:23:28 jukeboksi2 go-librespot[2032]: time="2026-08-29T09:23:28+03: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 29 09:23:28 jukeboksi2 go-librespot[2032]: time="2026-08-29T09:23:28+03: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 29 09:23:28 jukeboksi2 go-librespot[2032]: time="2026-08-29T09:23:28+03:00" level=info msg="zeroconf server listening on port 35137" Aug 29 09:23:28 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:28 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:28 jukeboksi2 go-librespot[2032]: time="2026-08-29T09:23:28+03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 09:23:28 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:28 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:28 jukeboksi2 go-librespot[2032]: time="2026-08-29T09:23:28+03:00" level=debug msg="obtained new client token: AAETwczq0qDcds1NWTJ40L9ILKbuVJ8T+U19vnPdUSLSiJKl/kxrQ3HTgeZK95e7J18v2tDcg7y/fcKjbY64cqXgVlIX9gg1TCNJjqT3SN/d+i+n7k0sueaa3KGy1qzLI4yVtkyaMzsXTii8D8Gqh02FCVxsLeK6OYs60ZaSn3JsrpRrQ7nlPIxXaPD+K9GpuoaT12R3CWP1uq7HG47qqrtjWabBQnHFpyppGuiShPV5WkXspuAqgvDQ" Aug 29 09:23:28 jukeboksi2 go-librespot[2032]: time="2026-08-29T09:23:28+03:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 09:23:29 jukeboksi2 go-librespot[2032]: time="2026-08-29T09:23:29+03:00" level=debug msg="completed keyexchange" Aug 29 09:23:29 jukeboksi2 go-librespot[2032]: time="2026-08-29T09:23:29+03:00" level=debug msg="completed challenge" Aug 29 09:23:29 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:29 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:29 jukeboksi2 go-librespot[2032]: time="2026-08-29T09:23:29+03:00" level=info msg="authenticated AP" username="qu***on" Aug 29 09:23:29 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:29 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:29 jukeboksi2 go-librespot[2032]: time="2026-08-29T09:23:29+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:23:29 jukeboksi2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:23:29 jukeboksi2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:23:29 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:29 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:29 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:29 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:29 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:29 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:29 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:29 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:30 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:30 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:30 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:30 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:30 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:30 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:30 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:30 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:31 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:31 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:31 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:31 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:31 jukeboksi2 volumio[1243]: info: Initializing connection to go-librespot Websocket Aug 29 09:23:31 jukeboksi2 volumio[1243]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:23:31 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:31 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:31 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:31 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:31 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:31 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:31 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:31 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:31 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 09:23:31 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 09:23:31 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 29 09:23:31 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 29 09:23:31 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 29 09:23:31 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 29 09:23:31 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetBrowseSources Aug 29 09:23:31 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 09:23:32 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:32 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:32 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:32 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:32 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:32 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:32 jukeboksi2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Aug 29 09:23:32 jukeboksi2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:32 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:32 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:32 jukeboksi2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:32 jukeboksi2 go-librespot[2042]: go-librespot daemon starting... Aug 29 09:23:32 jukeboksi2 go-librespot[2043]: time="2026-08-29T09:23:32+03:00" level=info msg="running go-librespot 0.7.1" Aug 29 09:23:32 jukeboksi2 go-librespot[2043]: time="2026-08-29T09:23:32+03:00" level=debug msg="app state loaded" Aug 29 09:23:32 jukeboksi2 go-librespot[2043]: time="2026-08-29T09:23:32+03:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:23:32 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:32 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:32 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:32 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:32 jukeboksi2 go-librespot[2043]: time="2026-08-29T09:23:32+03: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 29 09:23:32 jukeboksi2 go-librespot[2043]: time="2026-08-29T09:23:32+03: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 29 09:23:32 jukeboksi2 go-librespot[2043]: time="2026-08-29T09:23:32+03: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 29 09:23:32 jukeboksi2 go-librespot[2043]: time="2026-08-29T09:23:32+03:00" level=info msg="zeroconf server listening on port 33211" Aug 29 09:23:32 jukeboksi2 go-librespot[2043]: time="2026-08-29T09:23:32+03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 09:23:33 jukeboksi2 go-librespot[2043]: time="2026-08-29T09:23:33+03:00" level=debug msg="obtained new client token: AAGFfOYvU8eqqdrU5jlxXflWT3BkCSVNgt8t9Bn1P6+/Rh1PCvlke5isgyN6ksgAFvYFtircRdV7dEPRUvjxwLB2pq3+l89xKZ7zPeryaq7I3wQpQr3rNzzHBEhPpOAIZyA/R85hG80EYrl4Za2RZ42jdZkC3dRI1hqjLfXM6+S1q7Y/NS9G7fgWexmFBhg3TSTjKutPke0xk/CGykPAHyBq25TxOAfi5+TMztanf8Fglod3/SjdTg==" Aug 29 09:23:33 jukeboksi2 go-librespot[2043]: time="2026-08-29T09:23:33+03:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 09:23:33 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:33 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:33 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:33 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:33 jukeboksi2 go-librespot[2043]: time="2026-08-29T09:23:33+03:00" level=debug msg="completed keyexchange" Aug 29 09:23:33 jukeboksi2 go-librespot[2043]: time="2026-08-29T09:23:33+03:00" level=debug msg="completed challenge" Aug 29 09:23:33 jukeboksi2 go-librespot[2043]: time="2026-08-29T09:23:33+03:00" level=info msg="authenticated AP" username="qu***on" Aug 29 09:23:33 jukeboksi2 go-librespot[2043]: time="2026-08-29T09:23:33+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:23:33 jukeboksi2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:23:33 jukeboksi2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:23:33 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:33 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:33 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:33 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:33 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Aug 29 09:23:33 jukeboksi2 volumio[1243]: info: [ytmusic-browse] browseUri: ytmusic Aug 29 09:23:33 jukeboksi2 volumio[1243]: info: Preload queue cleared Aug 29 09:23:33 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:33 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:33 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:33 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:34 jukeboksi2 volumio[1243]: info: Initializing connection to go-librespot Websocket Aug 29 09:23:34 jukeboksi2 volumio[1243]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:23:34 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:34 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:34 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:34 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:34 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:34 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:34 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:34 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:34 jukeboksi2 volumio[1243]: error: MyVolumio Plugin failed to authenticate in a timely fashion Aug 29 09:23:34 jukeboksi2 volumio[1243]: info: Completed starting MyVolumio Plugin Aug 29 09:23:34 jukeboksi2 volumio[1243]: [Metrics] CommandRouter: 62s 27.21ms Aug 29 09:23:34 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumiosetStartupVolume Aug 29 09:23:34 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 09:23:34 jukeboksi2 volumio[1243]: info: VolumeController:: Setting startup Volume 25 Aug 29 09:23:34 jukeboksi2 volumio[1243]: info: VolumeController::SetAlsaVolume25 Aug 29 09:23:34 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 09:23:34 jukeboksi2 volumio[1243]: info: CoreCommandRouter::Close All Modals sent Aug 29 09:23:34 jukeboksi2 volumio[1243]: info: CoreCommandRouter::Close All Modals sent Aug 29 09:23:34 jukeboksi2 volumio[1243]: info: CoreStateMachine::pushState Aug 29 09:23:34 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:34 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 09:23:34 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioPushState Aug 29 09:23:35 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:35 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:35 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:35 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:35 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:35 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:35 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:35 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:35 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:35 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:35 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:35 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:35 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 29 09:23:35 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 09:23:35 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 29 09:23:36 jukeboksi2 volumio[1243]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Aug 29 09:23:36 jukeboksi2 volumio[1243]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Aug 29 09:23:36 jukeboksi2 volumio[1243]: aplay: xrun:1636: status error: Broken pipe Aug 29 09:23:36 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:36 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:36 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:36 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:36 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:36 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:36 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:36 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:36 jukeboksi2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Aug 29 09:23:36 jukeboksi2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:36 jukeboksi2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:36 jukeboksi2 go-librespot[2055]: go-librespot daemon starting... Aug 29 09:23:36 jukeboksi2 go-librespot[2056]: time="2026-08-29T09:23:36+03:00" level=info msg="running go-librespot 0.7.1" Aug 29 09:23:36 jukeboksi2 go-librespot[2056]: time="2026-08-29T09:23:36+03:00" level=debug msg="app state loaded" Aug 29 09:23:36 jukeboksi2 go-librespot[2056]: time="2026-08-29T09:23:36+03:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:23:36 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:36 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:36 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:36 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:37 jukeboksi2 volumio[1243]: info: Initializing connection to go-librespot Websocket Aug 29 09:23:37 jukeboksi2 go-librespot[2056]: time="2026-08-29T09:23:37+03: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 29 09:23:37 jukeboksi2 go-librespot[2056]: time="2026-08-29T09:23:37+03: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 29 09:23:37 jukeboksi2 go-librespot[2056]: time="2026-08-29T09:23:37+03: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 29 09:23:37 jukeboksi2 go-librespot[2056]: time="2026-08-29T09:23:37+03:00" level=info msg="zeroconf server listening on port 39285" Aug 29 09:23:37 jukeboksi2 go-librespot[2056]: time="2026-08-29T09:23:37+03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 09:23:37 jukeboksi2 go-librespot[2056]: time="2026-08-29T09:23:37+03:00" level=debug msg="new websocket client" Aug 29 09:23:37 jukeboksi2 volumio[1243]: info: Connection to go-librespot Websocket established Aug 29 09:23:37 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:37 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:37 jukeboksi2 go-librespot[2056]: time="2026-08-29T09:23:37+03:00" level=debug msg="obtained new client token: AAGP9wm/X5kzZbZpafMNENNdmR2c9taJU/pkcv9oecFw+5erb9oaz1Q4DW64fb3Z3MYrd6fZzf5LS7WsIgGviSrLBvgLSy3iwvougMBbTEzjp63I4fW8gkaJRFzsASQ7+85quNHUtTHC/Q8APwFoGHTGyo5vI8V+vcfBKo5OjSAa37No00B4kUcmCe4zfNraF5GbQ9mFz0ZKs+fHD6uldG3MJDgxyyynAlcFXHP/TN5fM9HcUtINYxej" Aug 29 09:23:37 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:37 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:37 jukeboksi2 go-librespot[2056]: time="2026-08-29T09:23:37+03:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 09:23:37 jukeboksi2 go-librespot[2056]: time="2026-08-29T09:23:37+03:00" level=debug msg="completed keyexchange" Aug 29 09:23:37 jukeboksi2 go-librespot[2056]: time="2026-08-29T09:23:37+03:00" level=debug msg="completed challenge" Aug 29 09:23:37 jukeboksi2 go-librespot[2056]: time="2026-08-29T09:23:37+03:00" level=info msg="authenticated AP" username="qu***on" Aug 29 09:23:37 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:37 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:37 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:37 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:37 jukeboksi2 go-librespot[2056]: time="2026-08-29T09:23:37+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:23:37 jukeboksi2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:23:37 jukeboksi2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:23:37 jukeboksi2 volumio[1243]: info: Connection to go-librespot Websocket closed Aug 29 09:23:38 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:38 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:38 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:38 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:38 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:38 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:38 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:38 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:38 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:38 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:38 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:38 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:39 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:39 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:39 jukeboksi2 volumio[1243]: info: CoreCommandRouter::volumioGetState Aug 29 09:23:39 jukeboksi2 volumio[1243]: info: CorePlayQueue::getTrack 0 Aug 29 09:23:39 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Aug 29 09:23:39 jukeboksi2 volumio[1243]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_history%22%7D%7D Aug 29 09:23:40 jukeboksi2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Aug 29 09:23:40 jukeboksi2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:41 jukeboksi2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:41 jukeboksi2 go-librespot[2093]: go-librespot daemon starting... Aug 29 09:23:41 jukeboksi2 go-librespot[2094]: time="2026-08-29T09:23:41+03:00" level=info msg="running go-librespot 0.7.1" Aug 29 09:23:41 jukeboksi2 go-librespot[2094]: time="2026-08-29T09:23:41+03:00" level=debug msg="app state loaded" Aug 29 09:23:41 jukeboksi2 go-librespot[2094]: time="2026-08-29T09:23:41+03:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:23:41 jukeboksi2 go-librespot[2094]: time="2026-08-29T09:23:41+03: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 29 09:23:41 jukeboksi2 go-librespot[2094]: time="2026-08-29T09:23:41+03: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 29 09:23:41 jukeboksi2 go-librespot[2094]: time="2026-08-29T09:23:41+03: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 29 09:23:41 jukeboksi2 go-librespot[2094]: time="2026-08-29T09:23:41+03:00" level=info msg="zeroconf server listening on port 34943" Aug 29 09:23:41 jukeboksi2 go-librespot[2094]: time="2026-08-29T09:23:41+03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 09:23:41 jukeboksi2 go-librespot[2094]: time="2026-08-29T09:23:41+03:00" level=debug msg="obtained new client token: AAHEajzLhZ1OvQQedT650e/4bK+PofS9IscjdhI/gbCzSOqwp7lwjEa50f2MLYT6Uja9N4oSvQqOG38MPSDzLbY4x5w9zPP4Z3Z/Jpea+UlxfLP0xQadME/Xektq/zRyK3FXQMRIulVg3v0M90q/JCA9G2T7DUFZ9MvOoYjO7xq+jIS8iboDqF6dgflDL9VVZ42l+OPR3DRBalViYJOReho+2pZEVKjQ3aEbg/VxMpMZhAB6JVOFePea" Aug 29 09:23:41 jukeboksi2 go-librespot[2094]: time="2026-08-29T09:23:41+03:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 09:23:41 jukeboksi2 go-librespot[2094]: time="2026-08-29T09:23:41+03:00" level=debug msg="completed keyexchange" Aug 29 09:23:41 jukeboksi2 go-librespot[2094]: time="2026-08-29T09:23:41+03:00" level=debug msg="completed challenge" Aug 29 09:23:41 jukeboksi2 go-librespot[2094]: time="2026-08-29T09:23:41+03:00" level=info msg="authenticated AP" username="qu***on" Aug 29 09:23:42 jukeboksi2 go-librespot[2094]: time="2026-08-29T09:23:42+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:23:42 jukeboksi2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:23:42 jukeboksi2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:23:45 jukeboksi2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Aug 29 09:23:45 jukeboksi2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:45 jukeboksi2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:45 jukeboksi2 go-librespot[2103]: go-librespot daemon starting... Aug 29 09:23:45 jukeboksi2 go-librespot[2104]: time="2026-08-29T09:23:45+03:00" level=info msg="running go-librespot 0.7.1" Aug 29 09:23:45 jukeboksi2 go-librespot[2104]: time="2026-08-29T09:23:45+03:00" level=debug msg="app state loaded" Aug 29 09:23:45 jukeboksi2 go-librespot[2104]: time="2026-08-29T09:23:45+03:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:23:45 jukeboksi2 go-librespot[2104]: time="2026-08-29T09:23:45+03: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 29 09:23:45 jukeboksi2 go-librespot[2104]: time="2026-08-29T09:23:45+03: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 29 09:23:45 jukeboksi2 go-librespot[2104]: time="2026-08-29T09:23:45+03: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 29 09:23:45 jukeboksi2 go-librespot[2104]: time="2026-08-29T09:23:45+03:00" level=info msg="zeroconf server listening on port 42395" Aug 29 09:23:45 jukeboksi2 go-librespot[2104]: time="2026-08-29T09:23:45+03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 09:23:45 jukeboksi2 go-librespot[2104]: time="2026-08-29T09:23:45+03:00" level=debug msg="obtained new client token: AAH4Pqh+JC8eVY8QYPpK0mDldvpWL4ZiyY7or8X3V+bIm4jK4dPPvEXed6LjglWU0QUk6XBshOYoQ8O1t6kqEsd65lKhtZY4F+86gjz8YpiMy/L7oNvMfmaNbT0CfqFYuOQotFYVRRnSl20qM+hKGU1Q8pYiIyhhpSONZqlIQqNpiTcP5Yj505tJoO5/DKOQXk1dQ3hQzUPLWTToCHbzcpF//zjuXcVxh3XvWxXzHDqk3uczLrfNJmXg" Aug 29 09:23:45 jukeboksi2 go-librespot[2104]: time="2026-08-29T09:23:45+03:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 09:23:45 jukeboksi2 go-librespot[2104]: time="2026-08-29T09:23:45+03:00" level=debug msg="completed keyexchange" Aug 29 09:23:45 jukeboksi2 go-librespot[2104]: time="2026-08-29T09:23:45+03:00" level=debug msg="completed challenge" Aug 29 09:23:46 jukeboksi2 go-librespot[2104]: time="2026-08-29T09:23:46+03:00" level=info msg="authenticated AP" username="qu***on" Aug 29 09:23:46 jukeboksi2 go-librespot[2104]: time="2026-08-29T09:23:46+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:23:46 jukeboksi2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:23:46 jukeboksi2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:23:49 jukeboksi2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Aug 29 09:23:49 jukeboksi2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:49 jukeboksi2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:49 jukeboksi2 go-librespot[2128]: go-librespot daemon starting... Aug 29 09:23:49 jukeboksi2 go-librespot[2129]: time="2026-08-29T09:23:49+03:00" level=info msg="running go-librespot 0.7.1" Aug 29 09:23:49 jukeboksi2 go-librespot[2129]: time="2026-08-29T09:23:49+03:00" level=debug msg="app state loaded" Aug 29 09:23:49 jukeboksi2 go-librespot[2129]: time="2026-08-29T09:23:49+03:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:23:49 jukeboksi2 go-librespot[2129]: time="2026-08-29T09:23:49+03: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 29 09:23:49 jukeboksi2 go-librespot[2129]: time="2026-08-29T09:23:49+03: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 29 09:23:49 jukeboksi2 go-librespot[2129]: time="2026-08-29T09:23:49+03: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 29 09:23:49 jukeboksi2 go-librespot[2129]: time="2026-08-29T09:23:49+03:00" level=info msg="zeroconf server listening on port 45583" Aug 29 09:23:49 jukeboksi2 go-librespot[2129]: time="2026-08-29T09:23:49+03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 09:23:50 jukeboksi2 go-librespot[2129]: time="2026-08-29T09:23:50+03:00" level=debug msg="obtained new client token: AAE5RXwf5thQZyWWOLv4p+Cq7wpRA0mhUVtho/7tvPK6hTJUns8XoZVag44IcNCExYtRoj+P4sWDm6tAg/5VVVnv1jEqFpgfZKjOZbRRCaoI4iXTPVPSUhRMYOZip9vr5fI5gzAL1KBUtXtSA5hyM3y+4s5H51fbBXMHSsZMe3tIuKNkUjDgFx41BAOo8ip+zJCMbAHB7CkbfUahnP+Unsh5Yc9mFR1g8QGf8RW4YIQyp88cxxCimw==" Aug 29 09:23:50 jukeboksi2 go-librespot[2129]: time="2026-08-29T09:23:50+03:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 09:23:50 jukeboksi2 go-librespot[2129]: time="2026-08-29T09:23:50+03:00" level=debug msg="completed keyexchange" Aug 29 09:23:50 jukeboksi2 go-librespot[2129]: time="2026-08-29T09:23:50+03:00" level=debug msg="completed challenge" Aug 29 09:23:50 jukeboksi2 go-librespot[2129]: time="2026-08-29T09:23:50+03:00" level=info msg="authenticated AP" username="qu***on" Aug 29 09:23:50 jukeboksi2 go-librespot[2129]: time="2026-08-29T09:23:50+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:23:50 jukeboksi2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:23:50 jukeboksi2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:23:53 jukeboksi2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Aug 29 09:23:53 jukeboksi2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:53 jukeboksi2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:53 jukeboksi2 go-librespot[2142]: go-librespot daemon starting... Aug 29 09:23:53 jukeboksi2 go-librespot[2143]: time="2026-08-29T09:23:53+03:00" level=info msg="running go-librespot 0.7.1" Aug 29 09:23:53 jukeboksi2 go-librespot[2143]: time="2026-08-29T09:23:53+03:00" level=debug msg="app state loaded" Aug 29 09:23:53 jukeboksi2 go-librespot[2143]: time="2026-08-29T09:23:53+03:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:23:54 jukeboksi2 go-librespot[2143]: time="2026-08-29T09:23:54+03: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 29 09:23:54 jukeboksi2 go-librespot[2143]: time="2026-08-29T09:23:54+03: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 29 09:23:54 jukeboksi2 go-librespot[2143]: time="2026-08-29T09:23:54+03: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 29 09:23:54 jukeboksi2 go-librespot[2143]: time="2026-08-29T09:23:54+03:00" level=info msg="zeroconf server listening on port 33997" Aug 29 09:23:54 jukeboksi2 go-librespot[2143]: time="2026-08-29T09:23:54+03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 09:23:54 jukeboksi2 go-librespot[2143]: time="2026-08-29T09:23:54+03:00" level=debug msg="obtained new client token: AAFHY17SuH5NKckEkc0erXjVL33s5VQ6LUIHyqpljHzdzpjG6LKr04u/t0b5YqrWBzStQLYvkfBrhi8+PQQ2Oajqum+go1lb3tHmVBYwPq+64oKIbNmqbR1Q1TJOuaj9ZuWA0/8KAjznlVm1sSfSf3sEMsF2LIoTMIEeFT9PhhwXWAY6zsXLrcTCRN8qkwgxBrD31Cy2jxm3pnCZRtDYetF3tayl5FdFBBr7a+/JBiq8WZBYBlTkWoqj" Aug 29 09:23:54 jukeboksi2 go-librespot[2143]: time="2026-08-29T09:23:54+03:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 09:23:54 jukeboksi2 go-librespot[2143]: time="2026-08-29T09:23:54+03:00" level=debug msg="completed keyexchange" Aug 29 09:23:54 jukeboksi2 go-librespot[2143]: time="2026-08-29T09:23:54+03:00" level=debug msg="completed challenge" Aug 29 09:23:54 jukeboksi2 go-librespot[2143]: time="2026-08-29T09:23:54+03:00" level=info msg="authenticated AP" username="qu***on" Aug 29 09:23:54 jukeboksi2 go-librespot[2143]: time="2026-08-29T09:23:54+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:23:54 jukeboksi2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:23:54 jukeboksi2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:23:57 jukeboksi2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Aug 29 09:23:57 jukeboksi2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:58 jukeboksi2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:23:58 jukeboksi2 go-librespot[2168]: go-librespot daemon starting... Aug 29 09:23:58 jukeboksi2 go-librespot[2170]: time="2026-08-29T09:23:58+03:00" level=info msg="running go-librespot 0.7.1" Aug 29 09:23:58 jukeboksi2 go-librespot[2170]: time="2026-08-29T09:23:58+03:00" level=debug msg="app state loaded" Aug 29 09:23:58 jukeboksi2 go-librespot[2170]: time="2026-08-29T09:23:58+03:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:23:58 jukeboksi2 go-librespot[2170]: time="2026-08-29T09:23:58+03: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 29 09:23:58 jukeboksi2 go-librespot[2170]: time="2026-08-29T09:23:58+03: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 29 09:23:58 jukeboksi2 go-librespot[2170]: time="2026-08-29T09:23:58+03: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 29 09:23:58 jukeboksi2 go-librespot[2170]: time="2026-08-29T09:23:58+03:00" level=info msg="zeroconf server listening on port 42261" Aug 29 09:23:58 jukeboksi2 go-librespot[2170]: time="2026-08-29T09:23:58+03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 09:23:58 jukeboksi2 go-librespot[2170]: time="2026-08-29T09:23:58+03:00" level=debug msg="obtained new client token: AAHEAReln+F9S4h+BwIajuHCPW9aVlH9UMtRzDLYXbGQt5TopKCK8JCOotAsLmfvt6WF3Z6ygt45muNZ2IVW7NG4YorTMZU8eN0TdWWu0E9N6CafuHXPwYTFJ0Q/ZIJSh9f1NnqHV3+MZqqLrvmEPGTTOxpqOxrySDyELlnfiYmSjyytzwXtBHVQSv74kr8nZV00hNBM3WwpP0sahP4566/5FM0yd7OWw3gaX2kNvWIZ0GcI/UU2Bj8J" Aug 29 09:23:58 jukeboksi2 go-librespot[2170]: time="2026-08-29T09:23:58+03:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 09:23:58 jukeboksi2 go-librespot[2170]: time="2026-08-29T09:23:58+03:00" level=debug msg="completed keyexchange" Aug 29 09:23:58 jukeboksi2 go-librespot[2170]: time="2026-08-29T09:23:58+03:00" level=debug msg="completed challenge" Aug 29 09:23:58 jukeboksi2 go-librespot[2170]: time="2026-08-29T09:23:58+03:00" level=info msg="authenticated AP" username="qu***on" Aug 29 09:23:59 jukeboksi2 go-librespot[2170]: time="2026-08-29T09:23:59+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:23:59 jukeboksi2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:23:59 jukeboksi2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:24:02 jukeboksi2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Aug 29 09:24:02 jukeboksi2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:24:02 jukeboksi2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:24:02 jukeboksi2 go-librespot[2193]: go-librespot daemon starting... Aug 29 09:24:02 jukeboksi2 go-librespot[2194]: time="2026-08-29T09:24:02+03:00" level=info msg="running go-librespot 0.7.1" Aug 29 09:24:02 jukeboksi2 go-librespot[2194]: time="2026-08-29T09:24:02+03:00" level=debug msg="app state loaded" Aug 29 09:24:02 jukeboksi2 go-librespot[2194]: time="2026-08-29T09:24:02+03:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:24:02 jukeboksi2 go-librespot[2194]: time="2026-08-29T09:24:02+03: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 29 09:24:02 jukeboksi2 go-librespot[2194]: time="2026-08-29T09:24:02+03: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 29 09:24:02 jukeboksi2 go-librespot[2194]: time="2026-08-29T09:24:02+03: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 29 09:24:02 jukeboksi2 go-librespot[2194]: time="2026-08-29T09:24:02+03:00" level=info msg="zeroconf server listening on port 39823" Aug 29 09:24:02 jukeboksi2 go-librespot[2194]: time="2026-08-29T09:24:02+03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 09:24:02 jukeboksi2 go-librespot[2194]: time="2026-08-29T09:24:02+03:00" level=debug msg="obtained new client token: AAFIxshw9pBB5poBhqPLyw566q9ASqgxjb+6KFVI8R3iRsgYxmezmrdi9cqidjqVbLlcLP1z6RkvQK+2oc+8C4HJSrVwYtx3ppz1j8GpS6nDPPB3UrFJotaGfQppPAuD7D4NiMr03ecRyZnqbCZhrd9+cOadmbp8HqGodsqwSR0rMUOWWJcs3nE9zUD9lrv33MatYajFE4etmAsVV8XnnHqd5o4bMCvqZmFb5SZy9QMbEuVrjTs97grk" Aug 29 09:24:02 jukeboksi2 go-librespot[2194]: time="2026-08-29T09:24:02+03:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 09:24:03 jukeboksi2 go-librespot[2194]: time="2026-08-29T09:24:03+03:00" level=debug msg="completed keyexchange" Aug 29 09:24:03 jukeboksi2 go-librespot[2194]: time="2026-08-29T09:24:03+03:00" level=debug msg="completed challenge" Aug 29 09:24:03 jukeboksi2 go-librespot[2194]: time="2026-08-29T09:24:03+03:00" level=info msg="authenticated AP" username="qu***on" Aug 29 09:24:03 jukeboksi2 go-librespot[2194]: time="2026-08-29T09:24:03+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:24:03 jukeboksi2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:24:03 jukeboksi2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:24:06 jukeboksi2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Aug 29 09:24:06 jukeboksi2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:24:06 jukeboksi2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:24:06 jukeboksi2 go-librespot[2208]: go-librespot daemon starting... Aug 29 09:24:06 jukeboksi2 go-librespot[2209]: time="2026-08-29T09:24:06+03:00" level=info msg="running go-librespot 0.7.1" Aug 29 09:24:06 jukeboksi2 go-librespot[2209]: time="2026-08-29T09:24:06+03:00" level=debug msg="app state loaded" Aug 29 09:24:06 jukeboksi2 go-librespot[2209]: time="2026-08-29T09:24:06+03:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:24:06 jukeboksi2 go-librespot[2209]: time="2026-08-29T09:24:06+03: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 29 09:24:06 jukeboksi2 go-librespot[2209]: time="2026-08-29T09:24:06+03: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 29 09:24:06 jukeboksi2 go-librespot[2209]: time="2026-08-29T09:24:06+03: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 29 09:24:06 jukeboksi2 go-librespot[2209]: time="2026-08-29T09:24:06+03:00" level=info msg="zeroconf server listening on port 35353" Aug 29 09:24:06 jukeboksi2 go-librespot[2209]: time="2026-08-29T09:24:06+03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 09:24:07 jukeboksi2 go-librespot[2209]: time="2026-08-29T09:24:07+03:00" level=debug msg="obtained new client token: AAEZiqkrX1j/2NQrp6E7vywzmdRupj1gTMHef4UAr5Ss0i23gjAlS8JVpJOc0ZfPFhicG/oTrZ9QrmopaRqO+VjEJ3rUsDiaAMDLW7Sdlx2Ro6XEUcc4u+I5x7qZzMkoDYtzcDg7D5VDpLgw8anuMRksAR/zdjtbdcxic3/B+rBzyanagGPypcpKKla4Y9VP8kfO3G4LwztOM2XnC4C2wnmVgZhyZJcefLwdqU6S5DqRhut10tJGmg==" Aug 29 09:24:07 jukeboksi2 go-librespot[2209]: time="2026-08-29T09:24:07+03:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 09:24:07 jukeboksi2 go-librespot[2209]: time="2026-08-29T09:24:07+03:00" level=debug msg="completed keyexchange" Aug 29 09:24:07 jukeboksi2 go-librespot[2209]: time="2026-08-29T09:24:07+03:00" level=debug msg="completed challenge" Aug 29 09:24:07 jukeboksi2 go-librespot[2209]: time="2026-08-29T09:24:07+03:00" level=info msg="authenticated AP" username="qu***on" Aug 29 09:24:07 jukeboksi2 go-librespot[2209]: time="2026-08-29T09:24:07+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:24:07 jukeboksi2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:24:07 jukeboksi2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:24:10 jukeboksi2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Aug 29 09:24:10 jukeboksi2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:24:10 jukeboksi2 go-librespot[2236]: go-librespot daemon starting... Aug 29 09:24:10 jukeboksi2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:24:10 jukeboksi2 go-librespot[2238]: time="2026-08-29T09:24:10+03:00" level=info msg="running go-librespot 0.7.1" Aug 29 09:24:10 jukeboksi2 go-librespot[2238]: time="2026-08-29T09:24:10+03:00" level=debug msg="app state loaded" Aug 29 09:24:10 jukeboksi2 go-librespot[2238]: time="2026-08-29T09:24:10+03:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:24:11 jukeboksi2 go-librespot[2238]: time="2026-08-29T09:24:11+03: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 29 09:24:11 jukeboksi2 go-librespot[2238]: time="2026-08-29T09:24:11+03: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 29 09:24:11 jukeboksi2 go-librespot[2238]: time="2026-08-29T09:24:11+03: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 29 09:24:11 jukeboksi2 go-librespot[2238]: time="2026-08-29T09:24:11+03:00" level=info msg="zeroconf server listening on port 42627" Aug 29 09:24:11 jukeboksi2 go-librespot[2238]: time="2026-08-29T09:24:11+03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 09:24:11 jukeboksi2 go-librespot[2238]: time="2026-08-29T09:24:11+03:00" level=debug msg="obtained new client token: AAG4tUSWlgzWMF3NHYStS5Hege1SY+8lwpSkR+X0CvJotpL8ZJAnquXHWc46CPI3lMBjVlJdcp1p2bplDss79ad9MxBhUzMTpmQVj3j2udWk72t0wXPzTeDGU6TGAr1+M40eB7vb0//0CIQYPdKBMBVrIRM5kl05aXd8oSX+bpGCrIYsoP9d9dtmOD7lHPtn8sc0vi2E0CG+DSvTX07UF75dSCCZ8RszB5h44zVYFXGxTtjq8RcvJAUU" Aug 29 09:24:11 jukeboksi2 go-librespot[2238]: time="2026-08-29T09:24:11+03: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 29 09:24:11 jukeboksi2 go-librespot[2238]: time="2026-08-29T09:24:11+03:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 29 09:24:11 jukeboksi2 go-librespot[2238]: time="2026-08-29T09:24:11+03:00" level=debug msg="completed keyexchange" Aug 29 09:24:11 jukeboksi2 go-librespot[2238]: time="2026-08-29T09:24:11+03:00" level=debug msg="completed challenge" Aug 29 09:24:11 jukeboksi2 go-librespot[2238]: time="2026-08-29T09:24:11+03:00" level=info msg="authenticated AP" username="qu***on" Aug 29 09:24:11 jukeboksi2 go-librespot[2238]: time="2026-08-29T09:24:11+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:24:11 jukeboksi2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:24:11 jukeboksi2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:24:13 jukeboksi2 volumio[1243]: npm notice Aug 29 09:24:13 jukeboksi2 volumio[1243]: npm notice New major version of npm available! 9.8.0 -> 12.0.2 Aug 29 09:24:13 jukeboksi2 volumio[1243]: npm notice Changelog: Aug 29 09:24:13 jukeboksi2 volumio[1243]: npm notice Run `npm install -g npm@12.0.2` to update! Aug 29 09:24:13 jukeboksi2 volumio[1243]: npm notice Aug 29 09:24:13 jukeboksi2 volumio[1243]: info: [ytmusic] Innertube support service: Deno not installed or otherwise failed to start: Command failed: npx --no-install --yes deno --version Aug 29 09:24:13 jukeboksi2 volumio[1243]: npm notice Aug 29 09:24:13 jukeboksi2 volumio[1243]: npm notice New major version of npm available! 9.8.0 -> 12.0.2 Aug 29 09:24:13 jukeboksi2 volumio[1243]: npm notice Changelog: Aug 29 09:24:13 jukeboksi2 volumio[1243]: npm notice Run `npm install -g npm@12.0.2` to update! Aug 29 09:24:13 jukeboksi2 volumio[1243]: npm notice Aug 29 09:24:13 jukeboksi2 volumio[1243]: info: [ytmusic] Innertube support service: Start service with Node Aug 29 09:24:13 jukeboksi2 volumio[1243]: info: Getting Spotify volume Aug 29 09:24:13 jukeboksi2 volumio[1243]: info: Initializing connection to go-librespot Websocket Aug 29 09:24:13 jukeboksi2 volumio[1243]: info: BOOT COMPLETED Aug 29 09:24:13 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Aug 29 09:24:13 jukeboksi2 volumio[1243]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_history%22%7D%7D Aug 29 09:24:13 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Aug 29 09:24:13 jukeboksi2 volumio[1243]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_explore%22%7D%7D Aug 29 09:24:13 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Aug 29 09:24:13 jukeboksi2 volumio[1243]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_explore%22%7D%7D Aug 29 09:24:13 jukeboksi2 volumio[1243]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Aug 29 09:24:13 jukeboksi2 volumio[1243]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_explore%22%7D%7D Aug 29 09:24:14 jukeboksi2 volumio[1243]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 09:24:14 jukeboksi2 volumio[1243]: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 09:24:14 jukeboksi2 volumio[1243]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Aug 29 09:24:14 jukeboksi2 volumio[1243]: errno: -111, Aug 29 09:24:14 jukeboksi2 volumio[1243]: code: 'ECONNREFUSED', Aug 29 09:24:14 jukeboksi2 volumio[1243]: syscall: 'connect', Aug 29 09:24:14 jukeboksi2 volumio[1243]: address: '127.0.0.1', Aug 29 09:24:14 jukeboksi2 volumio[1243]: port: 9879, Aug 29 09:24:14 jukeboksi2 volumio[1243]: response: undefined Aug 29 09:24:14 jukeboksi2 volumio[1243]: } Aug 29 09:24:14 jukeboksi2 volumio[1243]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 09:24:14 jukeboksi2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Aug 29 09:24:14 jukeboksi2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:24:15 jukeboksi2 go-librespot[2277]: go-librespot daemon starting... Aug 29 09:24:15 jukeboksi2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 09:24:15 jukeboksi2 go-librespot[2278]: time="2026-08-29T09:24:15+03:00" level=info msg="running go-librespot 0.7.1" Aug 29 09:24:15 jukeboksi2 go-librespot[2278]: time="2026-08-29T09:24:15+03:00" level=debug msg="app state loaded" Aug 29 09:24:15 jukeboksi2 go-librespot[2278]: time="2026-08-29T09:24:15+03:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 09:24:15 jukeboksi2 go-librespot[2278]: time="2026-08-29T09:24:15+03: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 29 09:24:15 jukeboksi2 go-librespot[2278]: time="2026-08-29T09:24:15+03: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 29 09:24:15 jukeboksi2 go-librespot[2278]: time="2026-08-29T09:24:15+03: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 29 09:24:15 jukeboksi2 go-librespot[2278]: time="2026-08-29T09:24:15+03:00" level=info msg="zeroconf server listening on port 39593" Aug 29 09:24:15 jukeboksi2 go-librespot[2278]: time="2026-08-29T09:24:15+03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 09:24:15 jukeboksi2 go-librespot[2278]: time="2026-08-29T09:24:15+03:00" level=debug msg="obtained new client token: AAGsGRhrPx+cv5JqD+wNb7LYw5nUUJ64wZRuTLDyFJgCI/KOhjz8XAD39JA8pjqYO5GOXcUK8WdZ+0Ga/Py2B6A2fBKqc3tp4NUT1azivweKD0rgiOIvL5hF5nt6YAt+YtMYy/gH6iUBWmAIjIDsoMdwt0QfFvvpV/eJKYNxv0b1RzEOX4AW+oVCjOcLv6mNfaFB7T/uJE6wqC0f+jO+01fCMDtFMkuQYT9iLhgar+a8uHDOEu3daKSc" Aug 29 09:24:15 jukeboksi2 go-librespot[2278]: time="2026-08-29T09:24:15+03:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 09:24:15 jukeboksi2 go-librespot[2278]: time="2026-08-29T09:24:15+03:00" level=debug msg="completed keyexchange" Aug 29 09:24:15 jukeboksi2 go-librespot[2278]: time="2026-08-29T09:24:15+03:00" level=debug msg="completed challenge" Aug 29 09:24:15 jukeboksi2 go-librespot[2278]: time="2026-08-29T09:24:15+03:00" level=info msg="authenticated AP" username="qu***on" Aug 29 09:24:16 jukeboksi2 go-librespot[2278]: time="2026-08-29T09:24:16+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 09:24:16 jukeboksi2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 09:24:16 jukeboksi2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 09:24:18 jukeboksi2 sudo[2289]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-29 09:23' Aug 29 09:24:18 jukeboksi2 sudo[2289]: 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"