Aug 29 10:49:00 deskplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Aug 29 10:49:00 deskplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:49:00 deskplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:49:00 deskplayer go-librespot[2856]: go-librespot daemon starting... Aug 29 10:49:00 deskplayer go-librespot[2857]: time="2026-08-29T10:49:00+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 10:49:00 deskplayer go-librespot[2857]: time="2026-08-29T10:49:00+02:00" level=debug msg="app state loaded" Aug 29 10:49:00 deskplayer go-librespot[2857]: time="2026-08-29T10:49:00+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 10:49:00 deskplayer go-librespot[2857]: time="2026-08-29T10:49:00+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 10:49:00 deskplayer go-librespot[2857]: time="2026-08-29T10:49:00+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 10:49:00 deskplayer go-librespot[2857]: time="2026-08-29T10:49:00+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 10:49:00 deskplayer go-librespot[2857]: time="2026-08-29T10:49:00+02:00" level=info msg="zeroconf server listening on port 36165" Aug 29 10:49:00 deskplayer go-librespot[2857]: time="2026-08-29T10:49:00+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 10:49:01 deskplayer go-librespot[2857]: time="2026-08-29T10:49:01+02:00" level=debug msg="obtained new client token: AAGz2szharR0t6gputI64IumqBoO47de6hgvW96hz4kmxNNzQtjsIbrtBpqwevgBNKSbkCKiWSVovNl2RnL7j+XctQBaf1BAXlTyVqDBI+ovw8lDco0cmKrYPTvB8jlVKr8QSFa+L9Az9sWgRe/5GLzkFCXWmvBXPtxlSVoUSd+HjatILdSBZMmHDfOLdHVmRWy1irQq1qS3hLXDq38XWUSaZCQOnrcuMNixxyuTh/ukX0gcGGK/wA==" Aug 29 10:49:01 deskplayer go-librespot[2857]: time="2026-08-29T10:49:01+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 10:49:01 deskplayer go-librespot[2857]: time="2026-08-29T10:49:01+02:00" level=debug msg="completed keyexchange" Aug 29 10:49:01 deskplayer go-librespot[2857]: time="2026-08-29T10:49:01+02:00" level=debug msg="completed challenge" Aug 29 10:49:01 deskplayer go-librespot[2857]: time="2026-08-29T10:49:01+02:00" level=info msg="authenticated AP" username="st**st" Aug 29 10:49:01 deskplayer go-librespot[2857]: time="2026-08-29T10:49:01+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 10:49:01 deskplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 10:49:01 deskplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 10:49:03 deskplayer volumio[2754]: info: Loading plugin "autostart"... Aug 29 10:49:03 deskplayer volumio[2754]: info: Applying required configuration parameters for plugin autostart Aug 29 10:49:03 deskplayer volumio[2754]: info: AutoStart - onVolumioStart - read config.json Aug 29 10:49:03 deskplayer volumio[2754]: info: Loading plugin "outputs"... Aug 29 10:49:03 deskplayer volumio[2754]: info: Loading plugin "albumart"... Aug 29 10:49:04 deskplayer volumio[2754]: info: Plugin example_plugin is not enabled Aug 29 10:49:04 deskplayer volumio[2754]: info: Loading plugin "inputs"... Aug 29 10:49:04 deskplayer volumio[2754]: info: Loading plugin "updater_comm"... Aug 29 10:49:04 deskplayer volumio[2754]: info: Plugin mpdemulation is not enabled Aug 29 10:49:04 deskplayer volumio[2754]: info: Loading plugin "rest_api"... Aug 29 10:49:04 deskplayer volumio[2754]: info: Loading plugin "websocket"... Aug 29 10:49:04 deskplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Aug 29 10:49:04 deskplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:49:04 deskplayer volumio[2754]: info: Starting Socket.io Server version 1.7.4 Aug 29 10:49:04 deskplayer volumio[2754]: info: Loading plugin "podcast"... Aug 29 10:49:04 deskplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:49:04 deskplayer go-librespot[2894]: go-librespot daemon starting... Aug 29 10:49:05 deskplayer go-librespot[2895]: time="2026-08-29T10:49:05+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 10:49:05 deskplayer go-librespot[2895]: time="2026-08-29T10:49:05+02:00" level=debug msg="app state loaded" Aug 29 10:49:05 deskplayer go-librespot[2895]: time="2026-08-29T10:49:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 10:49:05 deskplayer volumio5-onboarding[2314]: time=2026-08-29T10:49:05.370+02:00 level=ERROR msg="failed to update discovery on Wi-Fi info change" error="failed to get system info: could not get system info: context deadline exceeded" Aug 29 10:49:05 deskplayer go-librespot[2895]: time="2026-08-29T10:49:05+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 10:49:05 deskplayer go-librespot[2895]: time="2026-08-29T10:49:05+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 10:49:05 deskplayer go-librespot[2895]: time="2026-08-29T10:49:05+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 10:49:05 deskplayer go-librespot[2895]: time="2026-08-29T10:49:05+02:00" level=info msg="zeroconf server listening on port 37073" Aug 29 10:49:05 deskplayer go-librespot[2895]: time="2026-08-29T10:49:05+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 10:49:05 deskplayer go-librespot[2895]: time="2026-08-29T10:49:05+02:00" level=debug msg="obtained new client token: AAFEAs4h9Ug6+w9qWeOpV9j1uEzVyoUQvQa2SUKpQbTJHvy94N8Fg8Gxtafl2EUbJkh8YP3/kANdnyjkciNxzSL2SCcvXxlgFpr1BbzkCEGfcVyD3NBiTGoT2K8GOx0iUvGVGeXJHH+Ykk3hxbPmJzeChD4ZdkYGlcE40deNo7Dfg+Um0lXlFoRVNCP/fJl0I0tnYDI7H4APMYQEFgnvB2xL86qr3UsH5dj4fGvLsGsXJCqZ/xnCkKgH" Aug 29 10:49:05 deskplayer volumio[2869]: Forking 3 albumart workers Aug 29 10:49:06 deskplayer volumio[2754]: info: ControllerPodcast::constructor Aug 29 10:49:06 deskplayer go-librespot[2895]: time="2026-08-29T10:49:06+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 10:49:06 deskplayer go-librespot[2895]: time="2026-08-29T10:49:06+02:00" level=debug msg="completed keyexchange" Aug 29 10:49:06 deskplayer go-librespot[2895]: time="2026-08-29T10:49:06+02:00" level=debug msg="completed challenge" Aug 29 10:49:06 deskplayer go-librespot[2895]: time="2026-08-29T10:49:06+02:00" level=info msg="authenticated AP" username="st**st" Aug 29 10:49:06 deskplayer pirate_port[1714]: [2026-08-29T08:49:06.439Z] [ERROR] (VolumioClient.js:302) Socket.io connection error: { message: 'timeout' } Aug 29 10:49:06 deskplayer go-librespot[2895]: time="2026-08-29T10:49:06+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 10:49:06 deskplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 10:49:06 deskplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 10:49:07 deskplayer volumio[2754]: info: Loading plugin "backup_restore"... Aug 29 10:49:08 deskplayer volumio5-onboarding[2314]: time=2026-08-29T10:49:08.964+02:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:54682->127.0.0.1:3000: i/o timeout" Aug 29 10:49:09 deskplayer volumio-remote-updater[600]: [2026-08-29 10:49:09] [connect] Successful connection Aug 29 10:49:09 deskplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Aug 29 10:49:09 deskplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:49:09 deskplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:49:09 deskplayer go-librespot[2937]: go-librespot daemon starting... Aug 29 10:49:09 deskplayer go-librespot[2938]: time="2026-08-29T10:49:09+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 10:49:09 deskplayer go-librespot[2938]: time="2026-08-29T10:49:09+02:00" level=debug msg="app state loaded" Aug 29 10:49:10 deskplayer go-librespot[2938]: time="2026-08-29T10:49:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 10:49:10 deskplayer volumio[2754]: info: Applying required configuration parameters for plugin backup_restore Aug 29 10:49:10 deskplayer volumio[2754]: info: Loading plugin "pirate_port"... Aug 29 10:49:10 deskplayer volumio[2754]: info: Loading configuration from: /data/configuration/system_hardware/pirate_port/config.json Aug 29 10:49:11 deskplayer go-librespot[2938]: time="2026-08-29T10:49:11+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 10:49:11 deskplayer go-librespot[2938]: time="2026-08-29T10:49:11+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 10:49:11 deskplayer go-librespot[2938]: time="2026-08-29T10:49:11+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 10:49:11 deskplayer go-librespot[2938]: time="2026-08-29T10:49:11+02:00" level=info msg="zeroconf server listening on port 39203" Aug 29 10:49:11 deskplayer go-librespot[2938]: time="2026-08-29T10:49:11+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 10:49:11 deskplayer volumio[2754]: info: Configuration loaded successfully. Aug 29 10:49:11 deskplayer go-librespot[2938]: time="2026-08-29T10:49:11+02:00" level=debug msg="obtained new client token: AAFVgFx+k4AO+f8JRVBHo2ruqXhkAblAYwkxO9Iuqg2XDF0dSNAVMGsnDBAMDSDjZ95muR4Mz8RDMoxXva61dDZ7b0xWo5hiLO3Tic9yTqqE7+RG6VrfLRpcpwOiFuhWWpB2xGiCNn/hFxBX6eM6HjuwwH7CSOwFZmHkEVK1nydBUkiRVt/7H0+6ciCkBqS/7Gvn13/Y410EwoRUAS5zhjEJM9iH2gVVTeBy6ybmKSByz/hLyRBaaFJg" Aug 29 10:49:11 deskplayer volumio[2754]: info: Loading i18n strings for locale de Aug 29 10:49:11 deskplayer go-librespot[2938]: time="2026-08-29T10:49:11+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 10:49:11 deskplayer volumio[2754]: Updating browse sources language Aug 29 10:49:11 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 10:49:11 deskplayer go-librespot[2938]: time="2026-08-29T10:49:11+02:00" level=debug msg="completed keyexchange" Aug 29 10:49:11 deskplayer go-librespot[2938]: time="2026-08-29T10:49:11+02:00" level=debug msg="completed challenge" Aug 29 10:49:11 deskplayer go-librespot[2938]: time="2026-08-29T10:49:11+02:00" level=info msg="authenticated AP" username="st**st" Aug 29 10:49:12 deskplayer go-librespot[2938]: time="2026-08-29T10:49:12+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 10:49:12 deskplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 10:49:12 deskplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 10:49:12 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 10:49:12 deskplayer volumio[2754]: info: CoreCommandRouter::initPlayerControls Aug 29 10:49:12 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:12 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:12 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:12 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:12 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:12 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:12 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:12 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:12 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 10:49:12 deskplayer volumio[2754]: Express server listening on port 3000 Aug 29 10:49:12 deskplayer volumio[2754]: [Metrics] WebUI: 25s 807.22ms Aug 29 10:49:12 deskplayer volumio[2754]: info: CoreStateMachine::resetVolumioState Aug 29 10:49:12 deskplayer volumio[2754]: info: CoreStateMachine::getcurrentVolume Aug 29 10:49:12 deskplayer volumio[2754]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 10:49:13 deskplayer sudo[2957]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 10:49:13 deskplayer sudo[2957]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:49:13 deskplayer sudo[2957]: pam_unix(sudo:session): session closed for user root Aug 29 10:49:13 deskplayer sudo[2959]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 10:49:13 deskplayer sudo[2959]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:49:13 deskplayer sudo[2959]: pam_unix(sudo:session): session closed for user root Aug 29 10:49:13 deskplayer volumio[2754]: info: Volumio Network Manager: Network status updated: 2 Aug 29 10:49:13 deskplayer volumio[2754]: info: CoreStateMachine::pushState Aug 29 10:49:13 deskplayer volumio[2907]: Starting albumart workers Aug 29 10:49:13 deskplayer volumio[2754]: info: CorePlayQueue::getTrack 0 Aug 29 10:49:13 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 10:49:13 deskplayer volumio[2754]: info: CoreCommandRouter::volumioPushState Aug 29 10:49:13 deskplayer volumio[2754]: info: CoreStateMachine::updateTrackBlock Aug 29 10:49:13 deskplayer volumio[2754]: info: CorePlayQueue::getTrackBlock Aug 29 10:49:13 deskplayer volumio[2754]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 10:49:14 deskplayer volumio[2754]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Aug 29 10:49:14 deskplayer volumio[2754]: info: CoreStateMachine::pushState Aug 29 10:49:14 deskplayer volumio[2754]: info: CorePlayQueue::getTrack 0 Aug 29 10:49:14 deskplayer volumio[2754]: info: CoreCommandRouter::volumioPushState Aug 29 10:49:14 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 10:49:14 deskplayer volumio[2754]: info: Reloading queue from file Aug 29 10:49:14 deskplayer volumio[2754]: verbose: New Socket.io Connection to 192.168.1.181 from 192.168.1.210 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/152.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Aug 29 10:49:14 deskplayer volumio[2754]: info: CoreStateMachine::setRepeat false single undefined Aug 29 10:49:14 deskplayer volumio[2754]: info: CoreStateMachine::pushState Aug 29 10:49:14 deskplayer volumio[2754]: info: CorePlayQueue::getTrack 0 Aug 29 10:49:14 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 10:49:14 deskplayer volumio[2754]: info: CoreCommandRouter::volumioPushState Aug 29 10:49:15 deskplayer volumio[2754]: info: CoreStateMachine::setRandom false Aug 29 10:49:15 deskplayer volumio[2754]: info: CoreStateMachine::pushState Aug 29 10:49:15 deskplayer volumio[2754]: info: CorePlayQueue::getTrack 0 Aug 29 10:49:15 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 10:49:15 deskplayer volumio[2754]: info: CoreCommandRouter::volumioPushState Aug 29 10:49:15 deskplayer volumio[2754]: info: Setting Device type: Raspberry PI Aug 29 10:49:15 deskplayer volumio[2906]: Starting albumart workers Aug 29 10:49:15 deskplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Aug 29 10:49:15 deskplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:49:15 deskplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:49:15 deskplayer volumio[2754]: info: Completed loading Core Plugins Aug 29 10:49:15 deskplayer go-librespot[2993]: go-librespot daemon starting... Aug 29 10:49:15 deskplayer go-librespot[2994]: time="2026-08-29T10:49:15+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 10:49:15 deskplayer go-librespot[2994]: time="2026-08-29T10:49:15+02:00" level=debug msg="app state loaded" Aug 29 10:49:15 deskplayer volumio[2754]: info: Preparing to generate the ALSA configuration file Aug 29 10:49:15 deskplayer go-librespot[2994]: time="2026-08-29T10:49:15+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 10:49:15 deskplayer volumio[2908]: Starting albumart workers Aug 29 10:49:16 deskplayer volumio[2754]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 29 10:49:16 deskplayer volumio[2754]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Aug 29 10:49:16 deskplayer volumio[2754]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 29 10:49:16 deskplayer volumio[2754]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 29 10:49:16 deskplayer volumio[2754]: info: Reading ALSA contributions from plugins. Aug 29 10:49:16 deskplayer volumio[2754]: verbose: New Socket.io Connection to 192.168.1.181 from 192.168.1.210 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/152.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Aug 29 10:49:16 deskplayer go-librespot[2994]: time="2026-08-29T10:49:16+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 10:49:16 deskplayer go-librespot[2994]: time="2026-08-29T10:49:16+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 10:49:16 deskplayer go-librespot[2994]: time="2026-08-29T10:49:16+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 10:49:16 deskplayer go-librespot[2994]: time="2026-08-29T10:49:16+02:00" level=info msg="zeroconf server listening on port 45683" Aug 29 10:49:16 deskplayer volumio[2754]: info: Discovery: adding b39a8fe8-93df-4d7c-9fd4-35b3202c7627 Aug 29 10:49:16 deskplayer volumio[2754]: info: Discovery: Found device deskplayer Aug 29 10:49:16 deskplayer volumio[2754]: info: CoreCommandRouter::volumioGetState Aug 29 10:49:16 deskplayer volumio[2754]: info: CorePlayQueue::getTrack 0 Aug 29 10:49:16 deskplayer go-librespot[2994]: time="2026-08-29T10:49:16+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 10:49:16 deskplayer volumio[2754]: verbose: New Socket.io Connection to 192.168.1.181 from 192.168.1.210 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/152.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Aug 29 10:49:16 deskplayer sudo[3011]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 29 10:49:16 deskplayer sudo[3011]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:49:16 deskplayer volumio[2754]: verbose: New Socket.io Connection to 192.168.1.181 from 192.168.1.210 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/152.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 29 10:49:17 deskplayer go-librespot[2994]: time="2026-08-29T10:49:17+02:00" level=debug msg="obtained new client token: AAEYrVuyyYPwe+i++4EjZy0E0VYbJQSQ9ALt7dUPJ/YbgEPhxRbqjcQjsMOivKsAQKa2AKvkt6LG/w27ZC/8qWiWPfRdplrViKFcJLiejt7dgwzV3B0GkA5vYe9FMNJmP52kKR9t6m/4MbSRmNLL4kvY4w2Dkt3egXxkkzaOH2PdZd5erFa0/pJA+43uirRb8gRBYcIIoutL1jIRKhqMBbQ9iCkiJsvyjdkU5esr7025IGY1PBdnjM3+" Aug 29 10:49:17 deskplayer volumio[2754]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 29 10:49:17 deskplayer go-librespot[2994]: time="2026-08-29T10:49:17+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 10:49:17 deskplayer go-librespot[2994]: time="2026-08-29T10:49:17+02:00" level=debug msg="completed keyexchange" Aug 29 10:49:17 deskplayer go-librespot[2994]: time="2026-08-29T10:49:17+02:00" level=debug msg="completed challenge" Aug 29 10:49:17 deskplayer sudo[3011]: pam_unix(sudo:session): session closed for user root Aug 29 10:49:17 deskplayer go-librespot[2994]: time="2026-08-29T10:49:17+02:00" level=info msg="authenticated AP" username="st**st" Aug 29 10:49:17 deskplayer volumio[2754]: verbose: New Socket.io Connection to 192.168.1.181 from 192.168.1.210 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/152.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 29 10:49:17 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 10:49:17 deskplayer volumio[2754]: info: Discovery: this is already registered, b39a8fe8-93df-4d7c-9fd4-35b3202c7627 Aug 29 10:49:17 deskplayer volumio[2754]: info: Discovery: Found device deskplayer Aug 29 10:49:17 deskplayer volumio[2754]: info: CoreCommandRouter::volumioGetState Aug 29 10:49:17 deskplayer volumio[2754]: info: CorePlayQueue::getTrack 0 Aug 29 10:49:17 deskplayer volumio[2754]: info: Upmpdcli Daemon Started Aug 29 10:49:17 deskplayer go-librespot[2994]: time="2026-08-29T10:49:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 10:49:17 deskplayer volumio[2754]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 10:49:17 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 10:49:17 deskplayer volumio[2754]: info: CoreCommandRouter::volumioGetState Aug 29 10:49:17 deskplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 10:49:17 deskplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 10:49:17 deskplayer volumio[2754]: info: CorePlayQueue::getTrack 0 Aug 29 10:49:17 deskplayer volumio[2754]: info: CoreCommandRouter::volumioGetState Aug 29 10:49:17 deskplayer volumio[2754]: info: CorePlayQueue::getTrack 0 Aug 29 10:49:17 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 10:49:17 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 10:49:17 deskplayer volumio[2754]: info: Received Get System Info Aug 29 10:49:17 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 10:49:17 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 10:49:17 deskplayer volumio[2754]: info: Discovery: Getting this device information Aug 29 10:49:17 deskplayer volumio[2754]: info: CoreCommandRouter::volumioGetState Aug 29 10:49:17 deskplayer volumio[2754]: info: CorePlayQueue::getTrack 0 Aug 29 10:49:17 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 10:49:17 deskplayer volumio[2754]: info: CoreCommandRouter::volumioGetState Aug 29 10:49:17 deskplayer volumio[2754]: info: CorePlayQueue::getTrack 0 Aug 29 10:49:17 deskplayer volumio[2754]: info: Listing playlists Aug 29 10:49:18 deskplayer volumio[2754]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 8 Aug 29 10:49:18 deskplayer volumio[2754]: verbose: New Socket.io Connection to 192.168.1.181 from 192.168.1.210 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/152.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 29 10:49:18 deskplayer volumio[2754]: info: Asound.conf file unchanged, so no further update is needed Aug 29 10:49:18 deskplayer volumio[2754]: info: Output device has changed, restarting MPD Aug 29 10:49:18 deskplayer volumio[2754]: info: Output device has changed, restarting Shairport Sync Aug 29 10:49:18 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:18 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 10:49:18 deskplayer sudo[3024]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 10:49:18 deskplayer sudo[3024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:49:18 deskplayer sudo[3022]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 10:49:18 deskplayer sudo[3022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:49:18 deskplayer volumio[2754]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 10:49:18 deskplayer volumio[2754]: info: ___________ START PLUGINS ___________ Aug 29 10:49:18 deskplayer sudo[3022]: pam_unix(sudo:session): session closed for user root Aug 29 10:49:18 deskplayer systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 29 10:49:18 deskplayer volumio[2754]: info: ControllerMpd::onStart: Initializing MPD Aug 29 10:49:18 deskplayer volumio[2754]: info: Creating MPD Configuration file Aug 29 10:49:18 deskplayer sudo[3037]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 29 10:49:18 deskplayer sudo[3037]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:49:18 deskplayer sudo[3037]: pam_unix(sudo:session): session closed for user root Aug 29 10:49:18 deskplayer sudo[3040]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 10:49:18 deskplayer sudo[3040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:49:18 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 10:49:18 deskplayer volumio[2754]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 10:49:18 deskplayer volumio[2754]: info: [1787993358774] CoreMusicLibrary::Adding element Medienserver Aug 29 10:49:18 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 10:49:18 deskplayer sudo[3040]: pam_unix(sudo:session): session closed for user root Aug 29 10:49:18 deskplayer volumio[2754]: info: UPNP Browser: Client initialized successfully Aug 29 10:49:18 deskplayer sudo[3043]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 10:49:18 deskplayer sudo[3043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:49:19 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:19 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 10:49:19 deskplayer volumio[2754]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 10:49:19 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:19 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 10:49:19 deskplayer volumio[2754]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 10:49:19 deskplayer volumio[2754]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 10:49:19 deskplayer volumio[2754]: info: [1787993359430] CoreMusicLibrary::Adding element Last_100 Aug 29 10:49:19 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 10:49:19 deskplayer volumio[2754]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 10:49:19 deskplayer volumio[2754]: info: [1787993359435] CoreMusicLibrary::Adding element Webradio Aug 29 10:49:19 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 10:49:19 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 10:49:19 deskplayer volumio[2754]: info: Initializing BBC Radios Aug 29 10:49:19 deskplayer volumio[2754]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Aug 29 10:49:19 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 10:49:19 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 10:49:19 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 10:49:20 deskplayer volumio[2754]: info: Creating Spotify config file Aug 29 10:49:20 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:20 deskplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Aug 29 10:49:20 deskplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:49:20 deskplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:49:20 deskplayer go-librespot[3071]: go-librespot daemon starting... Aug 29 10:49:21 deskplayer go-librespot[3072]: time="2026-08-29T10:49:21+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 10:49:22 deskplayer volumio[2754]: info: AutoStart - onStart - waiting for system ready state Aug 29 10:49:22 deskplayer volumio[2754]: info: AutoStart - Polling config: interval=5000ms, maxAttempts=60 Aug 29 10:49:22 deskplayer volumio[2754]: info: AutoStart - Maximum wait time: 300 seconds Aug 29 10:49:22 deskplayer volumio[2754]: info: AutoStart - Startup volume enabled, level=50 Aug 29 10:49:22 deskplayer volumio[2754]: info: AutoStart - Check #1/60 - VOLUMIO_SYSTEM_STATUS = starting Aug 29 10:49:23 deskplayer go-librespot[3072]: time="2026-08-29T10:49:23+02:00" level=info msg="zeroconf server listening on port 39709" Aug 29 10:49:23 deskplayer go-librespot[3072]: time="2026-08-29T10:49:23+02:00" level=info msg="using built-in mDNS responder" Aug 29 10:49:23 deskplayer volumio[2754]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 10:49:23 deskplayer volumio[2754]: info: [1787993363121] CoreMusicLibrary::Adding element Podcast Aug 29 10:49:23 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 10:49:23 deskplayer volumio[2754]: Cannot find translation for source Podcast Aug 29 10:49:23 deskplayer volumio[2754]: info: Cannot retrieve data for calling home Aug 29 10:49:23 deskplayer sudo[3087]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start pirate_port.service Aug 29 10:49:23 deskplayer sudo[3087]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:49:23 deskplayer sudo[3087]: pam_unix(sudo:session): session closed for user root Aug 29 10:49:24 deskplayer volumio-remote-updater[600]: [2026-08-29 10:49:24] [connect] Successful connection Aug 29 10:49:25 deskplayer volumio[2754]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 29 10:49:26 deskplayer volumio[2754]: info: pirate_port service started Aug 29 10:49:26 deskplayer volumio[2754]: info: MPD Permissions set Aug 29 10:49:26 deskplayer volumio[2754]: info: MPD Permissions set Aug 29 10:49:26 deskplayer volumio[2754]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 29 10:49:27 deskplayer volumio[2754]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Aug 29 10:49:27 deskplayer volumio[2754]: info: Spotify config file written Aug 29 10:49:27 deskplayer volumio[2754]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 11 Aug 29 10:49:27 deskplayer volumio-remote-updater[600]: [2026-08-29 10:49:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1787993364 101 Aug 29 10:49:27 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:27 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:27 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:27 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:27 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:27 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:27 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:27 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:27 deskplayer sudo[3109]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 29 10:49:27 deskplayer sudo[3109]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:49:27 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 10:49:27 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:27 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:27 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:27 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:27 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:27 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:27 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:27 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:27 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:27 deskplayer systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Aug 29 10:49:27 deskplayer systemd[1]: go-librespot-daemon.service: Deactivated successfully. Aug 29 10:49:27 deskplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:49:27 deskplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:49:27 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 10:49:27 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:27 deskplayer go-librespot[3121]: go-librespot daemon starting... Aug 29 10:49:27 deskplayer sudo[3109]: pam_unix(sudo:session): session closed for user root Aug 29 10:49:27 deskplayer volumio[2754]: info: No need to fix Spotify hosts Aug 29 10:49:27 deskplayer go-librespot[3124]: time="2026-08-29T10:49:27+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 10:49:27 deskplayer go-librespot[3124]: time="2026-08-29T10:49:27+02:00" level=debug msg="app state loaded" Aug 29 10:49:28 deskplayer go-librespot[3124]: time="2026-08-29T10:49:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 10:49:28 deskplayer volumio[2754]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Aug 29 10:49:28 deskplayer volumio[2754]: info: AutoStart - Check #2/60 - VOLUMIO_SYSTEM_STATUS = starting Aug 29 10:49:28 deskplayer volumio[2754]: info: New Spotify access tokenBQDwViHTK1... Aug 29 10:49:28 deskplayer volumio[2754]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 29 10:49:29 deskplayer systemd[1]: mpd.service: Deactivated successfully. Aug 29 10:49:29 deskplayer systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 29 10:49:29 deskplayer systemd[1]: mpd.service: Consumed 12.942s CPU time. Aug 29 10:49:29 deskplayer systemd[1]: mpd.socket: Deactivated successfully. Aug 29 10:49:29 deskplayer systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 29 10:49:29 deskplayer systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 29 10:49:29 deskplayer systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 29 10:49:29 deskplayer systemd[1]: Starting mpd.service - Music Player Daemon... Aug 29 10:49:29 deskplayer go-librespot[3124]: time="2026-08-29T10:49:29+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 10:49:29 deskplayer go-librespot[3124]: time="2026-08-29T10:49:29+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 10:49:29 deskplayer go-librespot[3124]: time="2026-08-29T10:49:29+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 10:49:29 deskplayer go-librespot[3124]: time="2026-08-29T10:49:29+02:00" level=info msg="zeroconf server listening on port 40495" Aug 29 10:49:29 deskplayer go-librespot[3124]: time="2026-08-29T10:49:29+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 10:49:29 deskplayer go-librespot[3124]: time="2026-08-29T10:49:29+02:00" level=debug msg="obtained new client token: AAGPI8AzQoL+11ZI8B1e8GTRvFrdLyv0kmqoRVBVPz5iAECV8HhgJtNWNFAiO0/8035pOobzguJdeNgzRsxqlYFakkGdhnLMCXWvQ3WTY55cBSRcNvhmJOkMVCzlCWP3uSWsiKDkHbg9Dc7NQtlJTWzBIgr9szH/vmYqQbKKztZJCOJmnUlSAEtbFiQv40IBhXyQInV8RsZ0wrkLcZrFXKJBqc4SY7oJIBxMy0wUkMvpF1TZUb6q9Tsk" Aug 29 10:49:30 deskplayer go-librespot[3124]: time="2026-08-29T10:49:30+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 10:49:30 deskplayer volumio[2754]: info: Starting Shairport Sync Aug 29 10:49:30 deskplayer go-librespot[3124]: time="2026-08-29T10:49:30+02:00" level=debug msg="completed keyexchange" Aug 29 10:49:30 deskplayer go-librespot[3124]: time="2026-08-29T10:49:30+02:00" level=debug msg="completed challenge" Aug 29 10:49:30 deskplayer volumio[2754]: info: Starting Shairport Sync Aug 29 10:49:30 deskplayer go-librespot[3124]: time="2026-08-29T10:49:30+02:00" level=info msg="authenticated AP" username="st**st" Aug 29 10:49:30 deskplayer volumio[2754]: info: Starting Shairport Sync Aug 29 10:49:30 deskplayer sudo[3144]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 10:49:30 deskplayer sudo[3144]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:49:30 deskplayer sudo[3141]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 10:49:30 deskplayer sudo[3142]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 10:49:30 deskplayer systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 29 10:49:30 deskplayer systemd[1]: shairport-sync.service: Deactivated successfully. Aug 29 10:49:30 deskplayer sudo[3142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:49:30 deskplayer systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 10:49:30 deskplayer sudo[3137]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 10:49:30 deskplayer systemd[1]: shairport-sync.service: Consumed 2.201s CPU time. Aug 29 10:49:30 deskplayer sudo[3141]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:49:30 deskplayer go-librespot[3124]: time="2026-08-29T10:49:30+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 10:49:30 deskplayer systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 10:49:30 deskplayer sudo[3137]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 10:49:30 deskplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 10:49:30 deskplayer sudo[3144]: pam_unix(sudo:session): session closed for user root Aug 29 10:49:30 deskplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 10:49:30 deskplayer systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 29 10:49:30 deskplayer systemd[1]: shairport-sync.service: Deactivated successfully. Aug 29 10:49:30 deskplayer systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 10:49:30 deskplayer systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 10:49:30 deskplayer sudo[3142]: pam_unix(sudo:session): session closed for user root Aug 29 10:49:30 deskplayer sudo[3137]: pam_unix(sudo:session): session closed for user root Aug 29 10:49:30 deskplayer sudo[3141]: pam_unix(sudo:session): session closed for user root Aug 29 10:49:30 deskplayer volumio[2754]: info: CoreCommandRouter::volumioGetState Aug 29 10:49:30 deskplayer volumio[2754]: info: CorePlayQueue::getTrack 0 Aug 29 10:49:30 deskplayer volumio[2754]: info: CoreCommandRouter::volumioGetState Aug 29 10:49:30 deskplayer volumio[2754]: info: CorePlayQueue::getTrack 0 Aug 29 10:49:30 deskplayer volumio[2754]: info: Shairport-Sync Started Aug 29 10:49:30 deskplayer volumio[2754]: Error adding Membership: Error: addMembership EINVAL Aug 29 10:49:30 deskplayer volumio[2754]: info: Shairport-Sync Started Aug 29 10:49:30 deskplayer volumio[2754]: info: Shairport-Sync Started Aug 29 10:49:31 deskplayer volumio[2754]: info: CoreCommandRouter::volumioGetState Aug 29 10:49:31 deskplayer volumio[2754]: info: CorePlayQueue::getTrack 0 Aug 29 10:49:31 deskplayer volumio[2754]: SPOTIFY: User informations: {"account_id":"w8s8Q2MOh8","country":"AT","display_name":"stekst","email":"herbert.geier@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/stekst"},"followers":{"href":null,"total":6},"href":"https://api.spotify.com/v1/users/stekst","id":"stekst","images":[{"height":300,"url":"https://scontent-ams2-1.xx.fbcdn.net/v/t39.30808-1/464925005_8906164299408226_6672725636641885714_n.jpg?stp=dst-jpg_s320x320_tt6&_nc_cat=107&ccb=1-7&_nc_sid=08baa4&_nc_ohc=96nGI65eMcAQ7kNvwENnyZo&_nc_oc=AdoldEbb-ua5P-D6nxgKiLChrTUlP6ILm-lEgVWWdFLjHcDzrYE-nVxL2O5bEJrbiD4nf2dy2iiz-ZFn0Vti6dWx&_nc_zt=24&_nc_ht=scontent-ams2-1.xx&edm=AP4hL3IEAAAA&_nc_gid=KCaRNhDEv1glM_ildpa08Q&_nc_tpa=Q5bMBQKIchRIgJMJWGNyK7FtNsfI7ph3yOzbyRXnn1AX4c45RQpDuuDBVfTRVO0kx-DsNf4rnVXQ&oh=00_AQLOjPCFOXIwOYj1gmAiWI3Gf70YGsDn9avAIMjnWaAwiQ&oe=6A98552B","width":300},{"height":64,"url":"https://scontent-ams2-1.xx.fbcdn.net/v/t39.30808-1/464925005_8906164299408226_6672725636641885714_n.jpg?stp=cp0_dst-jpg_s50x50_tt6&_nc_cat=107&ccb=1-7&_nc_sid=28885b&_nc_ohc=96nGI65eMcAQ7kNvwENnyZo&_nc_oc=AdoldEbb-ua5P-D6nxgKiLChrTUlP6ILm-lEgVWWdFLjHcDzrYE-nVxL2O5bEJrbiD4nf2dy2iiz-ZFn0Vti6dWx&_nc_zt=24&_nc_ht=scontent-ams2-1.xx&edm=AP4hL3IEAAAA&_nc_gid=KCaRNhDEv1glM_ildpa08Q&_nc_tpa=Q5bMBQL6qk9gu451O7W253LfRCsfBhGt-YaQswl0ee3SBsLRhPECgzghpeILKByqIwoevGZaCL_Y&oh=00_AQL_qTx_tHYJQse2WKpWlYTEL8j6ieCvhtxHjNHC-znbcw&oe=6A98552B","width":64}],"product":"premium","type":"user","uri":"spotify:user:stekst"} Aug 29 10:49:31 deskplayer volumio[2754]: info: Spotify Successfully logged in Aug 29 10:49:31 deskplayer volumio[2754]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 10:49:31 deskplayer volumio[2754]: info: [1787993371124] CoreMusicLibrary::Adding element Spotify Aug 29 10:49:31 deskplayer volumio[2754]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 10:49:31 deskplayer volumio[2754]: Cannot find translation for source Podcast Aug 29 10:49:31 deskplayer volumio[2754]: Cannot find translation for source Spotify Aug 29 10:49:31 deskplayer pirate_port[1714]: [2026-08-29T08:49:31.904Z] [ERROR] (IconGenerator.js:426) Failed to create icon 'ok': Unknown icon type: ok Aug 29 10:49:31 deskplayer pirate_port[1714]: [2026-08-29T08:49:31.923Z] [ERROR] (ScreenRenderer.js:2104) showInfoScreen failed: Unknown icon type: ok Aug 29 10:49:32 deskplayer volumio[2754]: info: go-librespot daemon successfully initialized Aug 29 10:49:33 deskplayer volumio[2754]: info: AutoStart - Check #3/60 - VOLUMIO_SYSTEM_STATUS = starting Aug 29 10:49:33 deskplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Aug 29 10:49:33 deskplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:49:33 deskplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:49:33 deskplayer go-librespot[3181]: go-librespot daemon starting... Aug 29 10:49:34 deskplayer go-librespot[3182]: time="2026-08-29T10:49:34+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 10:49:34 deskplayer go-librespot[3182]: time="2026-08-29T10:49:34+02:00" level=debug msg="app state loaded" Aug 29 10:49:34 deskplayer go-librespot[3182]: time="2026-08-29T10:49:34+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 10:49:35 deskplayer go-librespot[3182]: time="2026-08-29T10:49:35+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 29 10:49:35 deskplayer go-librespot[3182]: time="2026-08-29T10:49:35+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 29 10:49:35 deskplayer go-librespot[3182]: time="2026-08-29T10:49:35+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 29 10:49:35 deskplayer go-librespot[3182]: time="2026-08-29T10:49:35+02:00" level=info msg="zeroconf server listening on port 36831" Aug 29 10:49:35 deskplayer go-librespot[3182]: time="2026-08-29T10:49:35+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 10:49:35 deskplayer volumio[2754]: info: Initializing connection to go-librespot Websocket Aug 29 10:49:35 deskplayer go-librespot[3182]: time="2026-08-29T10:49:35+02:00" level=debug msg="obtained new client token: AAGNyncv9bk880g3aD1dTpyQCNyKz3ms/RkyxLrK+G/DmQi+vteBUDicqgYMQ5+AJmAaEmXRB/lZmrMfRA1yS6lYC5mlQxN+LNeN80cINmebXKtJ4HvyRbYaF/bkxLMl1y/2cQ4VuCBGBGVnXRuCPKuxpA0henO6QfebhNV7xxp3peVfAfTZMiy11qS2QGbouaca5FFETv789mEzMXEx9SvC2ioKzCNtrZmX+mj0b+dwj3EJv19YlzDv" Aug 29 10:49:35 deskplayer go-librespot[3182]: time="2026-08-29T10:49:35+02:00" level=debug msg="new websocket client" Aug 29 10:49:35 deskplayer volumio[2754]: info: Connection to go-librespot Websocket established Aug 29 10:49:35 deskplayer go-librespot[3182]: time="2026-08-29T10:49:35+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 10:49:35 deskplayer go-librespot[3182]: time="2026-08-29T10:49:35+02:00" level=debug msg="completed keyexchange" Aug 29 10:49:35 deskplayer go-librespot[3182]: time="2026-08-29T10:49:35+02:00" level=debug msg="completed challenge" Aug 29 10:49:36 deskplayer go-librespot[3182]: time="2026-08-29T10:49:36+02:00" level=info msg="authenticated AP" username="st**st" Aug 29 10:49:36 deskplayer go-librespot[3182]: time="2026-08-29T10:49:36+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 10:49:36 deskplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 10:49:36 deskplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 10:49:36 deskplayer volumio[2754]: info: Connection to go-librespot Websocket closed Aug 29 10:49:38 deskplayer volumio[2754]: info: AutoStart - Check #4/60 - VOLUMIO_SYSTEM_STATUS = starting Aug 29 10:49:38 deskplayer volumio[2754]: info: Getting Spotify volume Aug 29 10:49:39 deskplayer volumio[2754]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 10:49:39 deskplayer volumio[2754]: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 10:49:39 deskplayer volumio[2754]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Aug 29 10:49:39 deskplayer volumio[2754]: errno: -111, Aug 29 10:49:39 deskplayer volumio[2754]: code: 'ECONNREFUSED', Aug 29 10:49:39 deskplayer volumio[2754]: syscall: 'connect', Aug 29 10:49:39 deskplayer volumio[2754]: address: '127.0.0.1', Aug 29 10:49:39 deskplayer volumio[2754]: port: 9879, Aug 29 10:49:39 deskplayer volumio[2754]: response: undefined Aug 29 10:49:39 deskplayer volumio[2754]: } Aug 29 10:49:39 deskplayer volumio[2754]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 10:49:39 deskplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Aug 29 10:49:39 deskplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:49:39 deskplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:49:39 deskplayer go-librespot[3218]: go-librespot daemon starting... Aug 29 10:49:39 deskplayer go-librespot[3219]: time="2026-08-29T10:49:39+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 10:49:39 deskplayer go-librespot[3219]: time="2026-08-29T10:49:39+02:00" level=debug msg="app state loaded" Aug 29 10:49:39 deskplayer go-librespot[3219]: time="2026-08-29T10:49:39+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 10:49:40 deskplayer go-librespot[3219]: time="2026-08-29T10:49:40+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 10:49:40 deskplayer go-librespot[3219]: time="2026-08-29T10:49:40+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 10:49:40 deskplayer go-librespot[3219]: time="2026-08-29T10:49:40+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 10:49:40 deskplayer go-librespot[3219]: time="2026-08-29T10:49:40+02:00" level=info msg="zeroconf server listening on port 44575" Aug 29 10:49:40 deskplayer go-librespot[3219]: time="2026-08-29T10:49:40+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 10:49:40 deskplayer sudo[3230]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-29 10:48' Aug 29 10:49:40 deskplayer sudo[3230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:49:40 deskplayer go-librespot[3219]: time="2026-08-29T10:49:40+02:00" level=debug msg="obtained new client token: AAFocjbONDDwb/NeRBpkG4Lj4C18aBCudWpCWSMm1eanARq7REdFQ+7C9FHdCDUL/n9q2BvGKIN8b3b3OtbIpPFBSDfjyjpQXM6tr6UK5OuwaZA8zuwgJacJgnXLqCRcMOCyrsm1shosTYt8T6lQTB2dfJFuU9JXmUws2C52Uqx0UX87Hz7pLz4UdozgWPGr1wla6qn3ARNNCNIE/oIWQo+fLRUnE6l16GeE6WID4zFm8lOsY8wF59y+" Aug 29 10:49:40 deskplayer go-librespot[3219]: time="2026-08-29T10:49:40+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 10:49:40 deskplayer go-librespot[3219]: time="2026-08-29T10:49:40+02:00" level=debug msg="completed keyexchange" Aug 29 10:49:40 deskplayer go-librespot[3219]: time="2026-08-29T10:49:40+02:00" level=debug msg="completed challenge" Aug 29 10:49:40 deskplayer go-librespot[3219]: time="2026-08-29T10:49:40+02:00" level=info msg="authenticated AP" username="st**st" Aug 29 10:49:40 deskplayer sudo[3230]: pam_unix(sudo:session): session closed for user root Aug 29 10:49:40 deskplayer go-librespot[3219]: time="2026-08-29T10:49:40+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 10:49:40 deskplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 10:49:40 deskplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 10:49:41 deskplayer volumio-remote-updater[600]: [2026-08-29 10:49:41] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 29 10:49:41 deskplayer volumio-remote-updater[600]: [2026-08-29 10:49:41] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 29 10:49:41 deskplayer systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 29 10:49:41 deskplayer sudo[3043]: pam_unix(sudo:session): session closed for user root Aug 29 10:49:41 deskplayer sudo[3024]: pam_unix(sudo:session): session closed for user root Aug 29 10:49:41 deskplayer systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 29 10:49:41 deskplayer systemd[1]: volumio.service: Consumed 2min 13.915s CPU time. Aug 29 10:49:41 deskplayer volumio5-onboarding[2314]: time=2026-08-29T10:49:41.286+02:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Aug 29 10:49:41 deskplayer systemd[1]: Started dynamicswap.service - dynamicswap service. Aug 29 10:49:41 deskplayer volumio5-onboarding[2314]: time=2026-08-29T10:49:41.367+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Aug 29 10:49:41 deskplayer systemd[1]: dynamicswap.service: Deactivated successfully. Aug 29 10:49:41 deskplayer systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Aug 29 10:49:41 deskplayer systemd[1]: Started dynamicswap.service - dynamicswap service. Aug 29 10:49:41 deskplayer systemd[1]: Stopped volumio.service - Volumio Backend Module. Aug 29 10:49:41 deskplayer systemd[1]: volumio.service: Consumed 2min 13.915s CPU time. Aug 29 10:49:41 deskplayer systemd[1]: Started volumio.service - Volumio Backend Module. Aug 29 10:49:41 deskplayer systemd[1]: dynamicswap.service: Deactivated successfully. Aug 29 10:49:42 deskplayer volumio5-onboarding[2314]: time=2026-08-29T10:49:42.373+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Aug 29 10:49:43 deskplayer pirate_port[1714]: [2026-08-29T08:49:43.108Z] [ERROR] (VolumioClient.js:302) Socket.io connection error: { Aug 29 10:49:43 deskplayer pirate_port[1714]: message: 'xhr poll error', Aug 29 10:49:43 deskplayer pirate_port[1714]: stack: 'Error: xhr poll error\n' + Aug 29 10:49:43 deskplayer pirate_port[1714]: ' at Transport.onError (/data/plugins/system_hardware/pirate_port/node_modules/engine.io-client/lib/transport.js:68:13)\n' + Aug 29 10:49:43 deskplayer pirate_port[1714]: ' at Request. (/data/plugins/system_hardware/pirate_port/node_modules/engine.io-client/lib/transports/polling-xhr.js:132:10)\n' + Aug 29 10:49:43 deskplayer pirate_port[1714]: ' at Emitter.emit (/data/plugins/system_hardware/pirate_port/node_modules/component-emitter/index.js:145:20)\n' + Aug 29 10:49:43 deskplayer pirate_port[1714]: ' at Request.onError (/data/plugins/system_hardware/pirate_port/node_modules/engine.io-client/lib/transports/polling-xhr.js:314:8)\n' + Aug 29 10:49:43 deskplayer pirate_port[1714]: ' at Timeout._onTimeout (/data/plugins/system_hardware/pirate_port/node_modules/engine.io-client/lib/transports/polling-xhr.js:261:18)\n' + Aug 29 10:49:43 deskplayer pirate_port[1714]: ' at listOnTimeout (node:internal/timers:573:17)\n' + Aug 29 10:49:43 deskplayer pirate_port[1714]: ' at process.processTimers (node:internal/timers:514:7)' Aug 29 10:49:43 deskplayer pirate_port[1714]: } Aug 29 10:49:43 deskplayer volumio5-onboarding[2314]: time=2026-08-29T10:49:43.377+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Aug 29 10:49:43 deskplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Aug 29 10:49:43 deskplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:49:43 deskplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:49:43 deskplayer go-librespot[3280]: go-librespot daemon starting... Aug 29 10:49:43 deskplayer go-librespot[3281]: time="2026-08-29T10:49:43+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 10:49:43 deskplayer go-librespot[3281]: time="2026-08-29T10:49:43+02:00" level=debug msg="app state loaded" Aug 29 10:49:43 deskplayer go-librespot[3281]: time="2026-08-29T10:49:43+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 10:49:44 deskplayer mpd[3151]: 2026-08-29T10:49:44 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 10:49:44 deskplayer systemd[1]: Started mpd.service - Music Player Daemon. Aug 29 10:49:44 deskplayer go-librespot[3281]: time="2026-08-29T10:49:44+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 29 10:49:44 deskplayer go-librespot[3281]: time="2026-08-29T10:49:44+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 29 10:49:44 deskplayer go-librespot[3281]: time="2026-08-29T10:49:44+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 29 10:49:44 deskplayer go-librespot[3281]: time="2026-08-29T10:49:44+02:00" level=info msg="zeroconf server listening on port 46387" Aug 29 10:49:44 deskplayer go-librespot[3281]: time="2026-08-29T10:49:44+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 10:49:44 deskplayer volumio5-onboarding[2314]: time=2026-08-29T10:49:44.379+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Aug 29 10:49:44 deskplayer go-librespot[3281]: time="2026-08-29T10:49:44+02:00" level=debug msg="obtained new client token: AAGcaQ/ozP9B2uUvgQPJn5TANUo4LbHx5vdArfuU+ZT8o/jh5+cb/O4dq8QqHoxzXHE0PiQn+s+EBgVomuuxdIyaqPfIB8sZ0erlZh4JyH2ivqTrSjjtKs/ePEAjvSvEep6vQEiqd26y8XUxPFsGjyPryhAc7i7u/nK2rfFstj7Km7rvF6xy3NR+apUheUB+ffKLtDqe3YqFHMoGGP5wr2m5g/8+CnENg+D1PoETALxicim0Pr7unkoS" Aug 29 10:49:44 deskplayer go-librespot[3281]: time="2026-08-29T10:49:44+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 10:49:44 deskplayer go-librespot[3281]: time="2026-08-29T10:49:44+02:00" level=debug msg="completed keyexchange" Aug 29 10:49:44 deskplayer go-librespot[3281]: time="2026-08-29T10:49:44+02:00" level=debug msg="completed challenge" Aug 29 10:49:44 deskplayer go-librespot[3281]: time="2026-08-29T10:49:44+02:00" level=info msg="authenticated AP" username="st**st" Aug 29 10:49:44 deskplayer go-librespot[3281]: time="2026-08-29T10:49:44+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 10:49:44 deskplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 10:49:44 deskplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 10:49:45 deskplayer volumio[3265]: info: ------------------------------------------- Aug 29 10:49:45 deskplayer volumio[3265]: info: ----- Volumio3 ---- Aug 29 10:49:45 deskplayer volumio[3265]: info: ------------------------------------------- Aug 29 10:49:45 deskplayer volumio[3265]: info: ----- System startup ---- Aug 29 10:49:45 deskplayer volumio[3265]: info: ------------------------------------------- Aug 29 10:49:46 deskplayer volumio-remote-updater[600]: [2026-08-29 10:49:46] [connect] Successful connection Aug 29 10:49:46 deskplayer volumio[3265]: info: MYVOLUMIO Environment detected Aug 29 10:49:46 deskplayer volumio[3265]: info: Plugin folders cleanup Aug 29 10:49:46 deskplayer volumio[3265]: info: Scanning into folder /volumio/app/plugins/ Aug 29 10:49:46 deskplayer volumio[3265]: info: Scanning category audio_interface Aug 29 10:49:46 deskplayer volumio[3265]: info: Scanning category miscellanea Aug 29 10:49:46 deskplayer volumio[3265]: info: Scanning category music_service Aug 29 10:49:46 deskplayer volumio[3265]: info: Scanning category plugins.json Aug 29 10:49:46 deskplayer volumio[3265]: info: Scanning category system_controller Aug 29 10:49:46 deskplayer volumio[3265]: info: Scanning category user_interface Aug 29 10:49:46 deskplayer volumio[3265]: info: Scanning into folder /data/plugins/ Aug 29 10:49:46 deskplayer volumio[3265]: info: Scanning category music_service Aug 29 10:49:46 deskplayer volumio[3265]: info: Scanning category system_controller Aug 29 10:49:46 deskplayer volumio[3265]: info: Scanning category system_hardware Aug 29 10:49:46 deskplayer volumio[3265]: info: Plugin folders cleanup completed Aug 29 10:49:46 deskplayer volumio[3265]: info: ------------------------------------------- Aug 29 10:49:46 deskplayer volumio[3265]: info: ----- Core plugins startup ---- Aug 29 10:49:46 deskplayer volumio[3265]: info: ------------------------------------------- Aug 29 10:49:46 deskplayer volumio[3265]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 10:49:46 deskplayer volumio[3265]: info: Adding plugin upnp to MyMusic Plugins Aug 29 10:49:46 deskplayer volumio[3265]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 10:49:46 deskplayer volumio[3265]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 10:49:46 deskplayer volumio[3265]: info: Loading plugins from folder /data/plugins/ Aug 29 10:49:46 deskplayer volumio[3265]: info: Loading plugin "system"... Aug 29 10:49:46 deskplayer volumio[3265]: info: Loading plugin "appearance"... Aug 29 10:49:48 deskplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Aug 29 10:49:48 deskplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:49:48 deskplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:49:48 deskplayer go-librespot[3307]: go-librespot daemon starting... Aug 29 10:49:48 deskplayer go-librespot[3308]: time="2026-08-29T10:49:48+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 10:49:48 deskplayer go-librespot[3308]: time="2026-08-29T10:49:48+02:00" level=debug msg="app state loaded" Aug 29 10:49:48 deskplayer go-librespot[3308]: time="2026-08-29T10:49:48+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 10:49:48 deskplayer go-librespot[3308]: time="2026-08-29T10:49:48+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 29 10:49:48 deskplayer go-librespot[3308]: time="2026-08-29T10:49:48+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 29 10:49:48 deskplayer go-librespot[3308]: time="2026-08-29T10:49:48+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 29 10:49:48 deskplayer go-librespot[3308]: time="2026-08-29T10:49:48+02:00" level=info msg="zeroconf server listening on port 45561" Aug 29 10:49:48 deskplayer go-librespot[3308]: time="2026-08-29T10:49:48+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 10:49:48 deskplayer go-librespot[3308]: time="2026-08-29T10:49:48+02:00" level=debug msg="obtained new client token: AAGa4k9ezHlikvFUBwS75HiRqysuIn3lKFQn+MMvZUludpTSFAf8gjDA+hSv3BZXW0ihcBysscJBCHOgF4WI0xY5YL5ir5qhBX0/BPO975pt87w9W6JY0H4w2l2s0rlo69+s+BY0b6Lk4siSUV9URDPOYibL9xprYn0oO6T+04QKgI3fFDOqxpSLOiW1WKX3kOQVtQKDO5RbMPWcvIMM5Njs6tC4EMF/Og+ScEwd8uEGfK8vH2jfv9sp" Aug 29 10:49:48 deskplayer go-librespot[3308]: time="2026-08-29T10:49:48+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Aug 29 10:49:48 deskplayer go-librespot[3308]: time="2026-08-29T10:49:48+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 29 10:49:48 deskplayer go-librespot[3308]: time="2026-08-29T10:49:48+02:00" level=debug msg="completed keyexchange" Aug 29 10:49:48 deskplayer go-librespot[3308]: time="2026-08-29T10:49:48+02:00" level=debug msg="completed challenge" Aug 29 10:49:48 deskplayer go-librespot[3308]: time="2026-08-29T10:49:48+02:00" level=info msg="authenticated AP" username="st**st" Aug 29 10:49:49 deskplayer go-librespot[3308]: time="2026-08-29T10:49:49+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 10:49:49 deskplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 10:49:49 deskplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 10:49:49 deskplayer volumio[3265]: info: Loading plugin "network"... Aug 29 10:49:49 deskplayer volumio[3265]: info: Refreshing Cached IP Addresses Aug 29 10:49:49 deskplayer sudo[3322]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 10:49:49 deskplayer sudo[3322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:49:49 deskplayer sudo[3324]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 10:49:49 deskplayer volumio[3265]: info: Loading plugin "services"... Aug 29 10:49:49 deskplayer sudo[3324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:49:49 deskplayer volumio[3265]: info: Loading plugin "volumio5onboarding"... Aug 29 10:49:49 deskplayer sudo[3324]: pam_unix(sudo:session): session closed for user root Aug 29 10:49:49 deskplayer volumio[3265]: info: Loading plugin "alsa_controller"... Aug 29 10:49:49 deskplayer sudo[3335]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 10:49:49 deskplayer sudo[3335]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:49:49 deskplayer sudo[3322]: pam_unix(sudo:session): session closed for user root Aug 29 10:49:49 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 10:49:49 deskplayer volumio[3265]: info: Loading plugin "wizard"... Aug 29 10:49:49 deskplayer volumio[3265]: info: Loading plugin "networkfs"... Aug 29 10:49:49 deskplayer volumio[3265]: info: Starting Udev Watcher for removable devices Aug 29 10:49:49 deskplayer volumio[3265]: info: Ignoring mount for partition: boot Aug 29 10:49:49 deskplayer volumio[3265]: info: Ignoring mount for partition: volumio Aug 29 10:49:49 deskplayer volumio[3265]: info: Ignoring mount for partition: volumio_data Aug 29 10:49:49 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 10:49:49 deskplayer volumio[3265]: info: Loading plugin "volumio_command_line_client"... Aug 29 10:49:49 deskplayer volumio[3265]: info: Loading plugin "upnp"... Aug 29 10:49:49 deskplayer volumio[3265]: info: [1787993389734] Starting Upmpd Daemon Aug 29 10:49:49 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 10:49:49 deskplayer volumio[3265]: info: Loading plugin "my_music"... Aug 29 10:49:49 deskplayer volumio[3265]: info: Loading plugin "mpd"... Aug 29 10:49:50 deskplayer volumio[3265]: info: Loading plugin "upnp_browser"... Aug 29 10:49:50 deskplayer sudo[3335]: pam_unix(sudo:session): session closed for user root Aug 29 10:49:51 deskplayer pirate_port[1714]: [2026-08-29T08:49:51.962Z] [ERROR] (VolumioClient.js:302) Socket.io connection error: { message: 'timeout' } Aug 29 10:49:52 deskplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Aug 29 10:49:52 deskplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:49:52 deskplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:49:52 deskplayer go-librespot[3356]: go-librespot daemon starting... Aug 29 10:49:52 deskplayer go-librespot[3358]: time="2026-08-29T10:49:52+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 10:49:52 deskplayer go-librespot[3358]: time="2026-08-29T10:49:52+02:00" level=debug msg="app state loaded" Aug 29 10:49:52 deskplayer go-librespot[3358]: time="2026-08-29T10:49:52+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 10:49:52 deskplayer volumio[3265]: info: Starting UPNP Browser Aug 29 10:49:52 deskplayer volumio[3265]: info: Loading plugin "alarm-clock"... Aug 29 10:49:52 deskplayer go-librespot[3358]: time="2026-08-29T10:49:52+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 29 10:49:52 deskplayer go-librespot[3358]: time="2026-08-29T10:49:52+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 29 10:49:52 deskplayer go-librespot[3358]: time="2026-08-29T10:49:52+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 29 10:49:52 deskplayer go-librespot[3358]: time="2026-08-29T10:49:52+02:00" level=info msg="zeroconf server listening on port 37323" Aug 29 10:49:52 deskplayer go-librespot[3358]: time="2026-08-29T10:49:52+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 10:49:52 deskplayer volumio[3265]: info: Loading plugin "airplay_emulation"... Aug 29 10:49:52 deskplayer volumio[3265]: info: Starting Shairport Sync Aug 29 10:49:52 deskplayer go-librespot[3358]: time="2026-08-29T10:49:52+02:00" level=debug msg="obtained new client token: AAFkQEQ9oQb9/oSvNBa6olHngWp7X0cQuY6zOmlKoiUZw2GJsKXto2tJxqILEOCmuNUj85Lu8NhfpTbMCjBlXJ05q7OgLiNfHWGdJGqVsW0LKTBkRvFkr+gZnjuIvx7IPtg/3SIXfsUbF5WsJCDdT52Prr7EfHGH0hX3KOFcd3b/xeySmk3pploHMmdtM3Gt1oeGMI77qbteoG/LneXJYEtNQqgHYHUBNLjIKIlkgXpV9sp6Dy6s5En5" Aug 29 10:49:52 deskplayer volumio[3265]: info: Loading plugin "last_100"... Aug 29 10:49:53 deskplayer volumio[3265]: info: Loading plugin "webradio"... Aug 29 10:49:53 deskplayer go-librespot[3358]: time="2026-08-29T10:49:53+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 10:49:53 deskplayer go-librespot[3358]: time="2026-08-29T10:49:53+02:00" level=debug msg="completed keyexchange" Aug 29 10:49:53 deskplayer go-librespot[3358]: time="2026-08-29T10:49:53+02:00" level=debug msg="completed challenge" Aug 29 10:49:53 deskplayer go-librespot[3358]: time="2026-08-29T10:49:53+02:00" level=info msg="authenticated AP" username="st**st" Aug 29 10:49:53 deskplayer volumio[3265]: info: Loading plugin "i2s_dacs"... Aug 29 10:49:53 deskplayer volumio[3265]: info: Loading plugin "volumiodiscovery"... Aug 29 10:49:53 deskplayer volumio[3265]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 10:49:53 deskplayer volumio[3265]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 10:49:53 deskplayer volumio[3265]: *** WARNING *** For more information see Aug 29 10:49:53 deskplayer volumio[3265]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 10:49:53 deskplayer volumio[3265]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 10:49:53 deskplayer volumio[3265]: *** WARNING *** For more information see Aug 29 10:49:53 deskplayer node[3265]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 10:49:53 deskplayer node[3265]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 10:49:53 deskplayer node[3265]: *** WARNING *** For more information see Aug 29 10:49:53 deskplayer node[3265]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 10:49:53 deskplayer node[3265]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 10:49:53 deskplayer node[3265]: *** WARNING *** For more information see Aug 29 10:49:53 deskplayer volumio[3265]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 10:49:53 deskplayer volumio[3265]: info: Discovery: Started advertising with name: deskplayer Aug 29 10:49:53 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 10:49:53 deskplayer volumio[3265]: info: Loading plugin "spop"... Aug 29 10:49:53 deskplayer go-librespot[3358]: time="2026-08-29T10:49:53+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 10:49:53 deskplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 10:49:53 deskplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 10:49:55 deskplayer volumio[3265]: info: Loading plugin "autostart"... Aug 29 10:49:55 deskplayer volumio5-onboarding[2314]: time=2026-08-29T10:49:55.384+02:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:35954->127.0.0.1:3000: i/o timeout" Aug 29 10:49:55 deskplayer volumio[3265]: info: Applying required configuration parameters for plugin autostart Aug 29 10:49:55 deskplayer volumio[3265]: info: AutoStart - onVolumioStart - read config.json Aug 29 10:49:55 deskplayer volumio[3265]: info: Loading plugin "outputs"... Aug 29 10:49:55 deskplayer volumio[3265]: info: Loading plugin "albumart"... Aug 29 10:49:55 deskplayer volumio[3265]: info: Plugin example_plugin is not enabled Aug 29 10:49:55 deskplayer volumio[3265]: info: Loading plugin "inputs"... Aug 29 10:49:55 deskplayer volumio[3265]: info: Loading plugin "updater_comm"... Aug 29 10:49:55 deskplayer volumio[3265]: info: Plugin mpdemulation is not enabled Aug 29 10:49:55 deskplayer volumio[3265]: info: Loading plugin "rest_api"... Aug 29 10:49:55 deskplayer volumio[3265]: info: Loading plugin "websocket"... Aug 29 10:49:55 deskplayer volumio[3265]: info: Starting Socket.io Server version 1.7.4 Aug 29 10:49:55 deskplayer volumio[3265]: info: Loading plugin "podcast"... Aug 29 10:49:56 deskplayer volumio[3265]: info: ControllerPodcast::constructor Aug 29 10:49:56 deskplayer volumio[3371]: Forking 3 albumart workers Aug 29 10:49:56 deskplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Aug 29 10:49:56 deskplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:49:56 deskplayer volumio[3265]: info: Loading plugin "backup_restore"... Aug 29 10:49:56 deskplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:49:56 deskplayer go-librespot[3411]: go-librespot daemon starting... Aug 29 10:49:56 deskplayer go-librespot[3412]: time="2026-08-29T10:49:56+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 10:49:56 deskplayer go-librespot[3412]: time="2026-08-29T10:49:56+02:00" level=debug msg="app state loaded" Aug 29 10:49:56 deskplayer go-librespot[3412]: time="2026-08-29T10:49:56+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 10:49:57 deskplayer volumio[3265]: info: Applying required configuration parameters for plugin backup_restore Aug 29 10:49:57 deskplayer volumio[3265]: info: Loading plugin "pirate_port"... Aug 29 10:49:57 deskplayer volumio[3265]: info: Loading configuration from: /data/configuration/system_hardware/pirate_port/config.json Aug 29 10:49:57 deskplayer go-librespot[3412]: time="2026-08-29T10:49:57+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 10:49:57 deskplayer go-librespot[3412]: time="2026-08-29T10:49:57+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 10:49:57 deskplayer go-librespot[3412]: time="2026-08-29T10:49:57+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 10:49:57 deskplayer go-librespot[3412]: time="2026-08-29T10:49:57+02:00" level=info msg="zeroconf server listening on port 44557" Aug 29 10:49:57 deskplayer go-librespot[3412]: time="2026-08-29T10:49:57+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 10:49:57 deskplayer volumio[3265]: info: Configuration loaded successfully. Aug 29 10:49:57 deskplayer volumio[3265]: info: Loading i18n strings for locale de Aug 29 10:49:57 deskplayer volumio[3265]: Updating browse sources language Aug 29 10:49:57 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 10:49:57 deskplayer go-librespot[3412]: time="2026-08-29T10:49:57+02:00" level=debug msg="obtained new client token: AAH5lH/o9UlT5qDwCVVvFo5D1o1yS6zHqQvb+QmYhv0SyZjNSvOBjpMNpMNlfwgLH16y6xJt/9rMLE3bRvqS6vR0zckU/7yV9hzNUwWKV8oars5FtdhXRo1QGHzs8OuaDhFn+LlIjTzPzMXqL8X04Q0+CmLmho5rUAeyvLASmU+N6vtIQ7ddavI4iWrezEQk0hp+w1OlU4QDr2GRqO2IwUWMYjuyHTaRuJSH9XXiV3dV5LcNFgHRh73N" Aug 29 10:49:58 deskplayer go-librespot[3412]: time="2026-08-29T10:49:58+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 10:49:58 deskplayer go-librespot[3412]: time="2026-08-29T10:49:58+02:00" level=debug msg="completed keyexchange" Aug 29 10:49:58 deskplayer go-librespot[3412]: time="2026-08-29T10:49:58+02:00" level=debug msg="completed challenge" Aug 29 10:49:58 deskplayer go-librespot[3412]: time="2026-08-29T10:49:58+02:00" level=info msg="authenticated AP" username="st**st" Aug 29 10:49:58 deskplayer volumio[3382]: Starting albumart workers Aug 29 10:49:58 deskplayer go-librespot[3412]: time="2026-08-29T10:49:58+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 10:49:58 deskplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 10:49:58 deskplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 10:49:58 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 10:49:58 deskplayer volumio[3265]: info: CoreCommandRouter::initPlayerControls Aug 29 10:49:58 deskplayer volumio[3381]: Starting albumart workers Aug 29 10:49:58 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:58 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:58 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:58 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:58 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:58 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:58 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:58 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:58 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 10:49:58 deskplayer volumio[3265]: Express server listening on port 3000 Aug 29 10:49:58 deskplayer volumio[3265]: [Metrics] WebUI: 14s 202.77ms Aug 29 10:49:58 deskplayer volumio[3383]: Starting albumart workers Aug 29 10:49:58 deskplayer volumio[3265]: info: CoreStateMachine::resetVolumioState Aug 29 10:49:58 deskplayer volumio[3265]: info: CoreStateMachine::getcurrentVolume Aug 29 10:49:58 deskplayer volumio[3265]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 10:49:58 deskplayer volumio-remote-updater[600]: [2026-08-29 10:49:58] [connect] Successful connection Aug 29 10:49:58 deskplayer volumio[3265]: info: Volumio Network Manager: Network status updated: 2 Aug 29 10:49:58 deskplayer volumio[3265]: verbose: New Socket.io Connection to 192.168.1.181 from 192.168.1.210 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/152.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 29 10:49:59 deskplayer volumio[3265]: info: CoreStateMachine::pushState Aug 29 10:49:59 deskplayer volumio[3265]: info: CorePlayQueue::getTrack 0 Aug 29 10:49:59 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 10:49:59 deskplayer volumio[3265]: info: CoreCommandRouter::volumioPushState Aug 29 10:49:59 deskplayer volumio[3265]: info: CoreStateMachine::updateTrackBlock Aug 29 10:49:59 deskplayer volumio[3265]: info: CorePlayQueue::getTrackBlock Aug 29 10:49:59 deskplayer volumio[3265]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 10:49:59 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 10:49:59 deskplayer volumio[3265]: info: Reloading queue from file Aug 29 10:49:59 deskplayer volumio[3265]: info: CoreStateMachine::pushState Aug 29 10:49:59 deskplayer volumio[3265]: info: CorePlayQueue::getTrack 0 Aug 29 10:49:59 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 10:49:59 deskplayer volumio[3265]: info: CoreCommandRouter::volumioPushState Aug 29 10:49:59 deskplayer volumio[3265]: info: CoreStateMachine::setRepeat false single undefined Aug 29 10:49:59 deskplayer volumio[3265]: info: CoreStateMachine::pushState Aug 29 10:49:59 deskplayer volumio[3265]: info: CorePlayQueue::getTrack 0 Aug 29 10:49:59 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 10:49:59 deskplayer volumio[3265]: info: CoreCommandRouter::volumioPushState Aug 29 10:49:59 deskplayer volumio[3265]: info: CoreStateMachine::setRandom false Aug 29 10:49:59 deskplayer volumio[3265]: info: CoreStateMachine::pushState Aug 29 10:49:59 deskplayer volumio[3265]: info: CorePlayQueue::getTrack 0 Aug 29 10:49:59 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 10:49:59 deskplayer volumio[3265]: info: CoreCommandRouter::volumioPushState Aug 29 10:49:59 deskplayer volumio[3265]: info: Setting Device type: Raspberry PI Aug 29 10:49:59 deskplayer volumio[3265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 29 10:49:59 deskplayer volumio[3265]: info: Completed loading Core Plugins Aug 29 10:49:59 deskplayer volumio[3265]: info: Preparing to generate the ALSA configuration file Aug 29 10:49:59 deskplayer volumio[3265]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 29 10:49:59 deskplayer volumio[3265]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Aug 29 10:49:59 deskplayer volumio[3265]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 29 10:49:59 deskplayer volumio[3265]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 29 10:49:59 deskplayer volumio[3265]: info: Reading ALSA contributions from plugins. Aug 29 10:49:59 deskplayer volumio[3265]: verbose: New Socket.io Connection to 192.168.1.181 from 192.168.1.210 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/152.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Aug 29 10:49:59 deskplayer volumio[3265]: verbose: New Socket.io Connection to 192.168.1.181 from 192.168.1.210 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/152.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Aug 29 10:49:59 deskplayer volumio[3265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 29 10:49:59 deskplayer volumio-remote-updater[600]: [2026-08-29 10:49:59] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1787993398 101 Aug 29 10:49:59 deskplayer sudo[3472]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 10:49:59 deskplayer sudo[3474]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 10:49:59 deskplayer sudo[3474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:49:59 deskplayer volumio[3265]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 6 Aug 29 10:49:59 deskplayer sudo[3472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:49:59 deskplayer sudo[3474]: pam_unix(sudo:session): session closed for user root Aug 29 10:49:59 deskplayer volumio[3265]: info: Asound.conf file unchanged, so no further update is needed Aug 29 10:49:59 deskplayer volumio[3265]: info: Output device has changed, restarting MPD Aug 29 10:49:59 deskplayer volumio[3265]: info: Output device has changed, restarting Shairport Sync Aug 29 10:49:59 deskplayer sudo[3472]: pam_unix(sudo:session): session closed for user root Aug 29 10:49:59 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:49:59 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 10:49:59 deskplayer sudo[3488]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 10:49:59 deskplayer sudo[3488]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:49:59 deskplayer sudo[3489]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 10:49:59 deskplayer sudo[3488]: pam_unix(sudo:session): session closed for user root Aug 29 10:49:59 deskplayer sudo[3489]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:50:00 deskplayer volumio[3265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 10:50:00 deskplayer volumio[3265]: info: ___________ START PLUGINS ___________ Aug 29 10:50:00 deskplayer volumio[3265]: info: ControllerMpd::onStart: Initializing MPD Aug 29 10:50:00 deskplayer volumio[3265]: info: Creating MPD Configuration file Aug 29 10:50:00 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 10:50:00 deskplayer volumio[3265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 10:50:00 deskplayer volumio[3265]: info: [1787993400083] CoreMusicLibrary::Adding element Medienserver Aug 29 10:50:00 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 10:50:00 deskplayer sudo[3497]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 29 10:50:00 deskplayer sudo[3497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:50:00 deskplayer sudo[3499]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 10:50:00 deskplayer sudo[3499]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:50:00 deskplayer sudo[3499]: pam_unix(sudo:session): session closed for user root Aug 29 10:50:00 deskplayer volumio[3265]: info: UPNP Browser: Client initialized successfully Aug 29 10:50:00 deskplayer sudo[3501]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 10:50:00 deskplayer sudo[3501]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:50:00 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:50:00 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 10:50:00 deskplayer volumio[3265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 10:50:00 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:50:00 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 10:50:00 deskplayer volumio[3265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 10:50:00 deskplayer volumio[3265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 10:50:00 deskplayer volumio[3265]: info: [1787993400243] CoreMusicLibrary::Adding element Last_100 Aug 29 10:50:00 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 10:50:00 deskplayer volumio[3265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 10:50:00 deskplayer volumio[3265]: info: [1787993400251] CoreMusicLibrary::Adding element Webradio Aug 29 10:50:00 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 10:50:00 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 10:50:00 deskplayer systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 29 10:50:00 deskplayer volumio[3265]: info: Initializing BBC Radios Aug 29 10:50:00 deskplayer sudo[3497]: pam_unix(sudo:session): session closed for user root Aug 29 10:50:00 deskplayer volumio[3265]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Aug 29 10:50:00 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 10:50:00 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 10:50:00 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 10:50:00 deskplayer volumio[3265]: info: Creating Spotify config file Aug 29 10:50:00 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:50:00 deskplayer volumio[3265]: info: AutoStart - onStart - waiting for system ready state Aug 29 10:50:00 deskplayer volumio[3265]: info: AutoStart - Polling config: interval=5000ms, maxAttempts=60 Aug 29 10:50:00 deskplayer volumio[3265]: info: AutoStart - Maximum wait time: 300 seconds Aug 29 10:50:00 deskplayer volumio[3265]: info: AutoStart - Startup volume enabled, level=50 Aug 29 10:50:00 deskplayer volumio[3265]: info: AutoStart - Check #1/60 - VOLUMIO_SYSTEM_STATUS = starting Aug 29 10:50:00 deskplayer volumio[3265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 10:50:00 deskplayer volumio[3265]: info: [1787993400856] CoreMusicLibrary::Adding element Podcast Aug 29 10:50:00 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 10:50:00 deskplayer volumio[3265]: Cannot find translation for source Podcast Aug 29 10:50:00 deskplayer volumio[3265]: info: Cannot retrieve data for calling home Aug 29 10:50:00 deskplayer sudo[3536]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start pirate_port.service Aug 29 10:50:00 deskplayer sudo[3536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:50:01 deskplayer volumio5-onboarding[2314]: time=2026-08-29T10:50:01.090+02:00 level=ERROR msg="failed to update discovery on Wi-Fi info change" error="failed to get system info: could not get system info: context deadline exceeded" Aug 29 10:50:01 deskplayer sudo[3536]: pam_unix(sudo:session): session closed for user root Aug 29 10:50:01 deskplayer volumio[3265]: info: Discovery: adding b39a8fe8-93df-4d7c-9fd4-35b3202c7627 Aug 29 10:50:01 deskplayer volumio[3265]: info: Discovery: Found device deskplayer Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::volumioGetState Aug 29 10:50:01 deskplayer volumio[3265]: info: CorePlayQueue::getTrack 0 Aug 29 10:50:01 deskplayer volumio[3265]: info: Discovery: this is already registered, b39a8fe8-93df-4d7c-9fd4-35b3202c7627 Aug 29 10:50:01 deskplayer volumio[3265]: info: Discovery: Found device deskplayer Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::volumioGetState Aug 29 10:50:01 deskplayer volumio[3265]: info: CorePlayQueue::getTrack 0 Aug 29 10:50:01 deskplayer sudo[3539]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 29 10:50:01 deskplayer sudo[3539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:50:01 deskplayer volumio[3265]: verbose: New Socket.io Connection to 192.168.1.181 from 192.168.1.210 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/152.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 29 10:50:01 deskplayer volumio[3265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Aug 29 10:50:01 deskplayer volumio[3265]: info: Received Get System Info Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 10:50:01 deskplayer volumio[3265]: info: Discovery: Getting this device information Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::volumioGetState Aug 29 10:50:01 deskplayer volumio[3265]: info: CorePlayQueue::getTrack 0 Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 10:50:01 deskplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Aug 29 10:50:01 deskplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:50:01 deskplayer volumio[3265]: info: pirate_port service started Aug 29 10:50:01 deskplayer volumio[3265]: info: MPD Permissions set Aug 29 10:50:01 deskplayer volumio[3265]: info: MPD Permissions set Aug 29 10:50:01 deskplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:50:01 deskplayer volumio[3265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 29 10:50:01 deskplayer sudo[3539]: pam_unix(sudo:session): session closed for user root Aug 29 10:50:01 deskplayer volumio[3265]: info: Spotify config file written Aug 29 10:50:01 deskplayer sudo[3549]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 29 10:50:01 deskplayer sudo[3549]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:50:01 deskplayer go-librespot[3541]: go-librespot daemon starting... Aug 29 10:50:01 deskplayer systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Aug 29 10:50:01 deskplayer systemd[1]: go-librespot-daemon.service: Deactivated successfully. Aug 29 10:50:01 deskplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:50:01 deskplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:50:01 deskplayer sudo[3549]: pam_unix(sudo:session): session closed for user root Aug 29 10:50:01 deskplayer go-librespot[3552]: go-librespot daemon starting... Aug 29 10:50:01 deskplayer volumio[3265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Aug 29 10:50:01 deskplayer volumio[3265]: info: No need to fix Spotify hosts Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 10:50:01 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 10:50:02 deskplayer volumio[3265]: info: Upmpdcli Daemon Started Aug 29 10:50:02 deskplayer volumio[3265]: info: New Spotify access tokenBQDYBLFOnI... Aug 29 10:50:02 deskplayer volumio[3265]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 29 10:50:02 deskplayer volumio[3265]: info: Starting Shairport Sync Aug 29 10:50:02 deskplayer volumio[3265]: info: Starting Shairport Sync Aug 29 10:50:02 deskplayer volumio[3265]: info: Starting Shairport Sync Aug 29 10:50:02 deskplayer sudo[3569]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 10:50:02 deskplayer sudo[3567]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 10:50:02 deskplayer sudo[3567]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:50:02 deskplayer sudo[3565]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 10:50:02 deskplayer sudo[3569]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:50:02 deskplayer sudo[3565]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:50:02 deskplayer volumio[3265]: info: CoreCommandRouter::volumioGetState Aug 29 10:50:02 deskplayer volumio[3265]: info: CorePlayQueue::getTrack 0 Aug 29 10:50:02 deskplayer volumio[3265]: SPOTIFY: User informations: {"account_id":"w8s8Q2MOh8","country":"AT","display_name":"stekst","email":"herbert.geier@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/stekst"},"followers":{"href":null,"total":6},"href":"https://api.spotify.com/v1/users/stekst","id":"stekst","images":[{"height":300,"url":"https://scontent-ams2-1.xx.fbcdn.net/v/t39.30808-1/464925005_8906164299408226_6672725636641885714_n.jpg?stp=dst-jpg_s320x320_tt6&_nc_cat=107&ccb=1-7&_nc_sid=08baa4&_nc_ohc=96nGI65eMcAQ7kNvwENnyZo&_nc_oc=AdoldEbb-ua5P-D6nxgKiLChrTUlP6ILm-lEgVWWdFLjHcDzrYE-nVxL2O5bEJrbiD4nf2dy2iiz-ZFn0Vti6dWx&_nc_zt=24&_nc_ht=scontent-ams2-1.xx&edm=AP4hL3IEAAAA&_nc_gid=KCaRNhDEv1glM_ildpa08Q&_nc_tpa=Q5bMBQKIchRIgJMJWGNyK7FtNsfI7ph3yOzbyRXnn1AX4c45RQpDuuDBVfTRVO0kx-DsNf4rnVXQ&oh=00_AQLOjPCFOXIwOYj1gmAiWI3Gf70YGsDn9avAIMjnWaAwiQ&oe=6A98552B","width":300},{"height":64,"url":"https://scontent-ams2-1.xx.fbcdn.net/v/t39.30808-1/464925005_8906164299408226_6672725636641885714_n.jpg?stp=cp0_dst-jpg_s50x50_tt6&_nc_cat=107&ccb=1-7&_nc_sid=28885b&_nc_ohc=96nGI65eMcAQ7kNvwENnyZo&_nc_oc=AdoldEbb-ua5P-D6nxgKiLChrTUlP6ILm-lEgVWWdFLjHcDzrYE-nVxL2O5bEJrbiD4nf2dy2iiz-ZFn0Vti6dWx&_nc_zt=24&_nc_ht=scontent-ams2-1.xx&edm=AP4hL3IEAAAA&_nc_gid=KCaRNhDEv1glM_ildpa08Q&_nc_tpa=Q5bMBQL6qk9gu451O7W253LfRCsfBhGt-YaQswl0ee3SBsLRhPECgzghpeILKByqIwoevGZaCL_Y&oh=00_AQL_qTx_tHYJQse2WKpWlYTEL8j6ieCvhtxHjNHC-znbcw&oe=6A98552B","width":64}],"product":"premium","type":"user","uri":"spotify:user:stekst"} Aug 29 10:50:03 deskplayer sudo[3565]: pam_unix(sudo:session): session closed for user root Aug 29 10:50:03 deskplayer systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 29 10:50:03 deskplayer volumio[3265]: info: Spotify Successfully logged in Aug 29 10:50:03 deskplayer volumio[3265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 10:50:03 deskplayer volumio[3265]: info: [1787993402489] CoreMusicLibrary::Adding element Spotify Aug 29 10:50:03 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 10:50:03 deskplayer volumio[3265]: Cannot find translation for source Podcast Aug 29 10:50:03 deskplayer volumio[3265]: Cannot find translation for source Spotify Aug 29 10:50:03 deskplayer sudo[3567]: pam_unix(sudo:session): session closed for user root Aug 29 10:50:03 deskplayer systemd[1]: shairport-sync.service: Deactivated successfully. Aug 29 10:50:03 deskplayer sudo[3569]: pam_unix(sudo:session): session closed for user root Aug 29 10:50:03 deskplayer systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 10:50:03 deskplayer systemd[1]: shairport-sync.service: Consumed 1.053s CPU time. Aug 29 10:50:03 deskplayer systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 10:50:03 deskplayer volumio[3265]: info: Shairport-Sync Started Aug 29 10:50:03 deskplayer volumio[3265]: Error adding Membership: Error: addMembership EINVAL Aug 29 10:50:03 deskplayer volumio[3265]: info: Shairport-Sync Started Aug 29 10:50:03 deskplayer volumio[3265]: info: Shairport-Sync Started Aug 29 10:50:03 deskplayer volumio[3265]: info: CoreCommandRouter::volumioGetState Aug 29 10:50:03 deskplayer volumio[3265]: info: CorePlayQueue::getTrack 0 Aug 29 10:50:04 deskplayer go-librespot[3553]: time="2026-08-29T10:50:04+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 10:50:04 deskplayer go-librespot[3553]: time="2026-08-29T10:50:04+02:00" level=debug msg="app state loaded" Aug 29 10:50:04 deskplayer go-librespot[3553]: time="2026-08-29T10:50:04+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 10:50:04 deskplayer systemd[1]: mpd.service: Deactivated successfully. Aug 29 10:50:04 deskplayer systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 29 10:50:04 deskplayer systemd[1]: mpd.service: Consumed 6.702s CPU time. Aug 29 10:50:04 deskplayer systemd[1]: mpd.socket: Deactivated successfully. Aug 29 10:50:04 deskplayer systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 29 10:50:04 deskplayer systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 29 10:50:04 deskplayer systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 29 10:50:04 deskplayer systemd[1]: Starting mpd.service - Music Player Daemon... Aug 29 10:50:05 deskplayer volumio[3265]: info: go-librespot daemon successfully initialized Aug 29 10:50:05 deskplayer volumio[3265]: info: CoreCommandRouter::volumioGetState Aug 29 10:50:05 deskplayer volumio[3265]: info: CorePlayQueue::getTrack 0 Aug 29 10:50:05 deskplayer pirate_port[1714]: [2026-08-29T08:50:05.167Z] [ERROR] (ScreenRenderer.js:2104) showInfoScreen failed: Unknown icon type: ok Aug 29 10:50:05 deskplayer sudo[3593]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 10:50:05 deskplayer sudo[3593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 10:50:05 deskplayer sudo[3593]: pam_unix(sudo:session): session closed for user root Aug 29 10:50:05 deskplayer go-librespot[3553]: time="2026-08-29T10:50:05+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 10:50:05 deskplayer go-librespot[3553]: time="2026-08-29T10:50:05+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 10:50:05 deskplayer go-librespot[3553]: time="2026-08-29T10:50:05+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 10:50:05 deskplayer go-librespot[3553]: time="2026-08-29T10:50:05+02:00" level=info msg="zeroconf server listening on port 33537" Aug 29 10:50:05 deskplayer go-librespot[3553]: time="2026-08-29T10:50:05+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 10:50:05 deskplayer volumio[3265]: verbose: New Socket.io Connection to 192.168.1.181 from 192.168.1.210 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/152.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Aug 29 10:50:05 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 10:50:05 deskplayer volumio[3265]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 10:50:05 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 10:50:05 deskplayer volumio[3265]: info: CoreCommandRouter::volumioGetState Aug 29 10:50:05 deskplayer volumio[3265]: info: CorePlayQueue::getTrack 0 Aug 29 10:50:05 deskplayer volumio[3265]: info: CoreCommandRouter::volumioGetState Aug 29 10:50:05 deskplayer volumio[3265]: info: CorePlayQueue::getTrack 0 Aug 29 10:50:05 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 10:50:05 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 10:50:05 deskplayer volumio[3265]: info: Received Get System Info Aug 29 10:50:05 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 10:50:05 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 10:50:05 deskplayer volumio[3265]: info: Discovery: Getting this device information Aug 29 10:50:05 deskplayer volumio[3265]: info: CoreCommandRouter::volumioGetState Aug 29 10:50:05 deskplayer volumio[3265]: info: CorePlayQueue::getTrack 0 Aug 29 10:50:05 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 10:50:05 deskplayer volumio[3265]: info: CoreCommandRouter::volumioGetState Aug 29 10:50:05 deskplayer volumio[3265]: info: CorePlayQueue::getTrack 0 Aug 29 10:50:05 deskplayer volumio[3265]: info: Listing playlists Aug 29 10:50:05 deskplayer volumio[3265]: info: AutoStart - Check #2/60 - VOLUMIO_SYSTEM_STATUS = starting Aug 29 10:50:05 deskplayer go-librespot[3553]: time="2026-08-29T10:50:05+02:00" level=debug msg="obtained new client token: AAE/qDPrYr+j4+yg//jrSTSy+0EjNgykRjhogwDrTK9wybmgp5PQx5syfYbOZGZLZuixLZet8GZbWddDBikKhhhY/lkI7MAq2uW2kKwJegsHDQ8WaHHDO5wCbgyK+gTi4W+5HHk6m2SuNsHxfoTXXZbe8p+h5G8Un/czoI+uQcI82bgNLSlcd9NZoKzuFoMk7L0dRVYg5KUtN7KSiWM2TFvSSqxi9ITUMJNLayNagEveTZ9hYKsFCQRB" Aug 29 10:50:05 deskplayer go-librespot[3553]: time="2026-08-29T10:50:05+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 10:50:06 deskplayer go-librespot[3553]: time="2026-08-29T10:50:06+02:00" level=debug msg="completed keyexchange" Aug 29 10:50:06 deskplayer go-librespot[3553]: time="2026-08-29T10:50:06+02:00" level=debug msg="completed challenge" Aug 29 10:50:06 deskplayer go-librespot[3553]: time="2026-08-29T10:50:06+02:00" level=info msg="authenticated AP" username="st**st" Aug 29 10:50:06 deskplayer go-librespot[3553]: time="2026-08-29T10:50:06+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 10:50:06 deskplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 10:50:06 deskplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 10:50:08 deskplayer volumio[3265]: info: Initializing connection to go-librespot Websocket Aug 29 10:50:08 deskplayer volumio[3265]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 10:50:09 deskplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Aug 29 10:50:09 deskplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:50:09 deskplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:50:09 deskplayer go-librespot[3615]: go-librespot daemon starting... Aug 29 10:50:09 deskplayer go-librespot[3620]: time="2026-08-29T10:50:09+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 10:50:09 deskplayer go-librespot[3620]: time="2026-08-29T10:50:09+02:00" level=debug msg="app state loaded" Aug 29 10:50:09 deskplayer go-librespot[3620]: time="2026-08-29T10:50:09+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 10:50:10 deskplayer go-librespot[3620]: time="2026-08-29T10:50:10+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 10:50:10 deskplayer go-librespot[3620]: time="2026-08-29T10:50:10+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 10:50:10 deskplayer go-librespot[3620]: time="2026-08-29T10:50:10+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 10:50:10 deskplayer go-librespot[3620]: time="2026-08-29T10:50:10+02:00" level=info msg="zeroconf server listening on port 43969" Aug 29 10:50:10 deskplayer go-librespot[3620]: time="2026-08-29T10:50:10+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 10:50:10 deskplayer go-librespot[3620]: time="2026-08-29T10:50:10+02:00" level=debug msg="obtained new client token: AAHkzBBOmQuPO0I0Dp4Vx23AGMJPDcCDbCt7bjb4h6LxG3umLONplWBwgCkUgIxQdOxSWnPWr3Xes1JPwQcRLFcpTLQr57IIdVT6ShqyS6P0GYXyEyB+aNw7c/X0MpjY6siLQiNqeeanA3yGKII15O87X2/NZa8wCyDVqipuL2K64gDFMWzKmKpeklMb7znY1Xm5GqoqRTIRWx/q0GovDt+mIbcxtW+UrVOS4w0/k7JN1+x1ayje7C5g" Aug 29 10:50:10 deskplayer go-librespot[3620]: time="2026-08-29T10:50:10+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 10:50:10 deskplayer go-librespot[3620]: time="2026-08-29T10:50:10+02:00" level=debug msg="completed keyexchange" Aug 29 10:50:10 deskplayer go-librespot[3620]: time="2026-08-29T10:50:10+02:00" level=debug msg="completed challenge" Aug 29 10:50:10 deskplayer go-librespot[3620]: time="2026-08-29T10:50:10+02:00" level=info msg="authenticated AP" username="st**st" Aug 29 10:50:10 deskplayer go-librespot[3620]: time="2026-08-29T10:50:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 10:50:10 deskplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 10:50:10 deskplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 10:50:10 deskplayer volumio[3265]: info: AutoStart - Check #3/60 - VOLUMIO_SYSTEM_STATUS = starting Aug 29 10:50:11 deskplayer volumio[3265]: info: Initializing connection to go-librespot Websocket Aug 29 10:50:11 deskplayer volumio[3265]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 10:50:14 deskplayer mpd[3613]: 2026-08-29T10:50:14 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 10:50:14 deskplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Aug 29 10:50:14 deskplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:50:14 deskplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:50:14 deskplayer systemd[1]: Started mpd.service - Music Player Daemon. Aug 29 10:50:14 deskplayer go-librespot[3641]: go-librespot daemon starting... Aug 29 10:50:14 deskplayer sudo[3501]: pam_unix(sudo:session): session closed for user root Aug 29 10:50:14 deskplayer sudo[3489]: pam_unix(sudo:session): session closed for user root Aug 29 10:50:14 deskplayer volumio[3265]: info: Initializing connection to go-librespot Websocket Aug 29 10:50:14 deskplayer volumio[3265]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 10:50:14 deskplayer volumio[3265]: info: Completed starting Core Plugins Aug 29 10:50:14 deskplayer volumio[3265]: info: ------------------------------------------- Aug 29 10:50:14 deskplayer volumio[3265]: info: ----- MyVolumio plugins startup ---- Aug 29 10:50:14 deskplayer volumio[3265]: info: ------------------------------------------- Aug 29 10:50:14 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 29 10:50:14 deskplayer volumio[3265]: error: MPD error: The expression evaluated to a falsy value: Aug 29 10:50:14 deskplayer volumio[3265]: assert.ok(self.idling) Aug 29 10:50:14 deskplayer volumio[3265]: error: The expression evaluated to a falsy value: Aug 29 10:50:14 deskplayer volumio[3265]: assert.ok(self.idling) Aug 29 10:50:14 deskplayer volumio[3265]: error: updateQueue error: null Aug 29 10:50:14 deskplayer volumio[3265]: info: MPD running with PID3613 Aug 29 10:50:14 deskplayer volumio[3265]: ,establishing connection Aug 29 10:50:14 deskplayer volumio[3265]: error: updateQueue error: null Aug 29 10:50:14 deskplayer go-librespot[3645]: time="2026-08-29T10:50:14+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 10:50:14 deskplayer go-librespot[3645]: time="2026-08-29T10:50:14+02:00" level=debug msg="app state loaded" Aug 29 10:50:15 deskplayer go-librespot[3645]: time="2026-08-29T10:50:15+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 10:50:15 deskplayer go-librespot[3645]: time="2026-08-29T10:50:15+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 10:50:15 deskplayer go-librespot[3645]: time="2026-08-29T10:50:15+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 10:50:15 deskplayer go-librespot[3645]: time="2026-08-29T10:50:15+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 10:50:15 deskplayer go-librespot[3645]: time="2026-08-29T10:50:15+02:00" level=info msg="zeroconf server listening on port 37303" Aug 29 10:50:15 deskplayer go-librespot[3645]: time="2026-08-29T10:50:15+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 10:50:15 deskplayer go-librespot[3645]: time="2026-08-29T10:50:15+02:00" level=debug msg="obtained new client token: AAEfM76Or1z36LL8D2ZZ3+YUxOwBifESYj7FdkFBgL/IvaFKAJ8gCL97pN8ky9GObACCGn/9h4HawtXOhuNHTik74/s84yBmafYP8EmOYWmUQPNa1ddcyqXKZa7l9uwsl2S+4BK1ojxYOqqy/yeoPSzr2sQSOGtSlQJgaq4otazpmaPhsJup38JQYRAOUm3MdznUprDmK/DM0B/fONWlSbpTVtoCyqx3ip7cJuCRhi31P7M0TG1duYF2" Aug 29 10:50:15 deskplayer go-librespot[3645]: time="2026-08-29T10:50:15+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 10:50:15 deskplayer go-librespot[3645]: time="2026-08-29T10:50:15+02:00" level=debug msg="completed keyexchange" Aug 29 10:50:15 deskplayer go-librespot[3645]: time="2026-08-29T10:50:15+02:00" level=debug msg="completed challenge" Aug 29 10:50:15 deskplayer go-librespot[3645]: time="2026-08-29T10:50:15+02:00" level=info msg="authenticated AP" username="st**st" Aug 29 10:50:15 deskplayer volumio[3265]: info: AutoStart - Check #4/60 - VOLUMIO_SYSTEM_STATUS = starting Aug 29 10:50:16 deskplayer go-librespot[3645]: time="2026-08-29T10:50:16+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 10:50:16 deskplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 10:50:16 deskplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 10:50:17 deskplayer volumio[3265]: info: Initializing connection to go-librespot Websocket Aug 29 10:50:17 deskplayer volumio[3265]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 10:50:19 deskplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Aug 29 10:50:19 deskplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:50:19 deskplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:50:19 deskplayer go-librespot[3656]: go-librespot daemon starting... Aug 29 10:50:19 deskplayer go-librespot[3657]: time="2026-08-29T10:50:19+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 10:50:19 deskplayer go-librespot[3657]: time="2026-08-29T10:50:19+02:00" level=debug msg="app state loaded" Aug 29 10:50:19 deskplayer volumio[3265]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 29 10:50:19 deskplayer go-librespot[3657]: time="2026-08-29T10:50:19+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 10:50:19 deskplayer go-librespot[3657]: time="2026-08-29T10:50:19+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 10:50:19 deskplayer go-librespot[3657]: time="2026-08-29T10:50:19+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 10:50:19 deskplayer go-librespot[3657]: time="2026-08-29T10:50:19+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 10:50:19 deskplayer go-librespot[3657]: time="2026-08-29T10:50:19+02:00" level=info msg="zeroconf server listening on port 35075" Aug 29 10:50:19 deskplayer go-librespot[3657]: time="2026-08-29T10:50:19+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 10:50:19 deskplayer go-librespot[3657]: time="2026-08-29T10:50:19+02:00" level=debug msg="obtained new client token: AAHOY2nt8qbeHQhuzHO1grMJ+9aU/EbyUpjFjuSWiXzuh1RVYcPYHYGKB/yrGMqGhu7Nckf7AIK/d1RrAstKHtLNDa4PxTXLedf61mvk/C86mpNdh6EKrPXc7e5WLm5AhlTn0RvgvpeYxit8kihJcOauGa5DUMumja39XxaUAM7vHjdObq6z4eYTrFgVQDI03iIkbzX7TX0FF4AtkgQJK5/s6A39+Qfxf+3El8rONdwK1a62IhTgT1zG" Aug 29 10:50:19 deskplayer go-librespot[3657]: time="2026-08-29T10:50:19+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 10:50:20 deskplayer go-librespot[3657]: time="2026-08-29T10:50:20+02:00" level=debug msg="completed keyexchange" Aug 29 10:50:20 deskplayer go-librespot[3657]: time="2026-08-29T10:50:20+02:00" level=debug msg="completed challenge" Aug 29 10:50:20 deskplayer go-librespot[3657]: time="2026-08-29T10:50:20+02:00" level=info msg="authenticated AP" username="st**st" Aug 29 10:50:20 deskplayer volumio[3265]: info: Initializing connection to go-librespot Websocket Aug 29 10:50:20 deskplayer go-librespot[3657]: time="2026-08-29T10:50:20+02:00" level=debug msg="new websocket client" Aug 29 10:50:20 deskplayer volumio[3265]: info: Connection to go-librespot Websocket established Aug 29 10:50:20 deskplayer go-librespot[3657]: time="2026-08-29T10:50:20+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 10:50:20 deskplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 10:50:20 deskplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 10:50:20 deskplayer volumio[3265]: info: Connection to go-librespot Websocket closed Aug 29 10:50:20 deskplayer volumio[3265]: info: AutoStart - Check #5/60 - VOLUMIO_SYSTEM_STATUS = starting Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 29 10:50:23 deskplayer volumio[3265]: info: Adding plugin bluetooth to MyMusic Plugins Aug 29 10:50:23 deskplayer volumio[3265]: info: Adding plugin multiroom to MyMusic Plugins Aug 29 10:50:23 deskplayer volumio[3265]: info: Adding plugin metavolumio to MyMusic Plugins Aug 29 10:50:23 deskplayer volumio[3265]: info: Adding plugin cd_controller to MyMusic Plugins Aug 29 10:50:23 deskplayer volumio[3265]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 29 10:50:23 deskplayer volumio[3265]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 29 10:50:23 deskplayer volumio[3265]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 29 10:50:23 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 29 10:50:23 deskplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Aug 29 10:50:23 deskplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:50:23 deskplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:50:23 deskplayer go-librespot[3684]: go-librespot daemon starting... Aug 29 10:50:23 deskplayer go-librespot[3685]: time="2026-08-29T10:50:23+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 10:50:23 deskplayer go-librespot[3685]: time="2026-08-29T10:50:23+02:00" level=debug msg="app state loaded" Aug 29 10:50:23 deskplayer go-librespot[3685]: time="2026-08-29T10:50:23+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 10:50:23 deskplayer go-librespot[3685]: time="2026-08-29T10:50:23+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 10:50:23 deskplayer go-librespot[3685]: time="2026-08-29T10:50:23+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 10:50:23 deskplayer go-librespot[3685]: time="2026-08-29T10:50:23+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 10:50:23 deskplayer go-librespot[3685]: time="2026-08-29T10:50:23+02:00" level=info msg="zeroconf server listening on port 40407" Aug 29 10:50:23 deskplayer go-librespot[3685]: time="2026-08-29T10:50:23+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 10:50:23 deskplayer go-librespot[3685]: time="2026-08-29T10:50:23+02:00" level=debug msg="obtained new client token: AAHP3XBIa8br/cg2F7ou9yNSNIRENWi1RhtCFxc5v3nIa6dSpoQziukqp+QkSxbw9PPks9KVRo46Oo5Cvo7DVkvSAXnS6/ahCdWeAGlYd4Nx+O06m+gxG5vZoyRmnmc5mdIZguOJawe6nthmGsXgmKax5JVMS3+lwvSm1ZAZcEok/Z0hSCIP+RpwIi6mdLojqZCu5cr5p/xD6emZUXD/nJXIaGxR4nqa2CBI/daA+zEbnjTnEP64vHUs" Aug 29 10:50:23 deskplayer go-librespot[3685]: time="2026-08-29T10:50:23+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 10:50:24 deskplayer go-librespot[3685]: time="2026-08-29T10:50:24+02:00" level=debug msg="completed keyexchange" Aug 29 10:50:24 deskplayer go-librespot[3685]: time="2026-08-29T10:50:24+02:00" level=debug msg="completed challenge" Aug 29 10:50:24 deskplayer go-librespot[3685]: time="2026-08-29T10:50:24+02:00" level=info msg="authenticated AP" username="st**st" Aug 29 10:50:24 deskplayer go-librespot[3685]: time="2026-08-29T10:50:24+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 10:50:24 deskplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 10:50:24 deskplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 10:50:25 deskplayer volumio[3265]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 29 10:50:25 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 29 10:50:25 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 10:50:25 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 10:50:25 deskplayer volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 10:50:25 deskplayer volumio[3265]: info: Starting MyVolumio Remote Streaming Endpoints Aug 29 10:50:25 deskplayer volumio[3265]: info: MyVolumio login type: Token Aug 29 10:50:25 deskplayer volumio[3265]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 29 10:50:25 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 29 10:50:27 deskplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Aug 29 10:50:27 deskplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:50:27 deskplayer go-librespot[3701]: go-librespot daemon starting... Aug 29 10:50:27 deskplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 10:50:27 deskplayer go-librespot[3702]: time="2026-08-29T10:50:27+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 10:50:27 deskplayer go-librespot[3702]: time="2026-08-29T10:50:27+02:00" level=debug msg="app state loaded" Aug 29 10:50:27 deskplayer go-librespot[3702]: time="2026-08-29T10:50:27+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 10:50:27 deskplayer go-librespot[3702]: time="2026-08-29T10:50:27+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 29 10:50:27 deskplayer go-librespot[3702]: time="2026-08-29T10:50:27+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 29 10:50:27 deskplayer go-librespot[3702]: time="2026-08-29T10:50:27+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 29 10:50:27 deskplayer go-librespot[3702]: time="2026-08-29T10:50:27+02:00" level=info msg="zeroconf server listening on port 42865" Aug 29 10:50:27 deskplayer go-librespot[3702]: time="2026-08-29T10:50:27+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 10:50:27 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 29 10:50:27 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 29 10:50:27 deskplayer volumio[3265]: info: Streaming services startup Aug 29 10:50:27 deskplayer volumio[3265]: info: Starting Streaming Daemon Aug 29 10:50:27 deskplayer volumio[3265]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 29 10:50:27 deskplayer sudo[3712]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 29 10:50:27 deskplayer sudo[3712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 10:50:27 deskplayer volumio[3265]: info: Getting Spotify volume Aug 29 10:50:27 deskplayer go-librespot[3702]: time="2026-08-29T10:50:27+02:00" level=debug msg="obtained new client token: AAEPun0C+DD6rKDrdW/aP+6H8L7CZ1s44jYM4ibdsdxOVLbI7Ph8vj3c0fq8UsyYTfQX5olVcMjwkfBQw6pUpj2oTOWCDh1pgosOuoPhuacllxE0RF4J1h4CkmfhLU002TgofkO3gp1n7dmyJvYCp2YNCmeEBAkEGzAEuT2wCxuymVNPaIBYCkSvf6FpzTskK8ZFPsGTcCHtBh+fdGe9Yzfk1XB2HjaCUTxaXlt9y93pvEeAxSQnwsX3" Aug 29 10:50:27 deskplayer volumio[3265]: info: Initializing connection to go-librespot Websocket Aug 29 10:50:28 deskplayer volumio[3265]: info: AutoStart - Check #6/60 - VOLUMIO_SYSTEM_STATUS = starting Aug 29 10:50:28 deskplayer go-librespot[3702]: time="2026-08-29T10:50:28+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 10:50:28 deskplayer sudo[3712]: pam_unix(sudo:session): session closed for user root Aug 29 10:50:28 deskplayer volumio[3265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Aug 29 10:50:28 deskplayer go-librespot[3702]: time="2026-08-29T10:50:28+02:00" level=debug msg="new websocket client" Aug 29 10:50:28 deskplayer go-librespot[3702]: time="2026-08-29T10:50:28+02:00" level=debug msg="completed keyexchange" Aug 29 10:50:28 deskplayer go-librespot[3702]: time="2026-08-29T10:50:28+02:00" level=debug msg="completed challenge" Aug 29 10:50:28 deskplayer volumio[3265]: info: Connection to go-librespot Websocket established Aug 29 10:50:28 deskplayer volumio[3265]: error: Cannot start Volumio Streaming Daemon Aug 29 10:50:28 deskplayer volumio[3265]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 29 10:50:28 deskplayer volumio[3265]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 29 10:50:28 deskplayer go-librespot[3702]: time="2026-08-29T10:50:28+02:00" level=info msg="authenticated AP" username="st**st" Aug 29 10:50:28 deskplayer volumio[3265]: info: CoreCommandRouter::volumioGetState Aug 29 10:50:28 deskplayer volumio[3265]: info: CorePlayQueue::getTrack 0 Aug 29 10:50:28 deskplayer volumio[3265]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Aug 29 10:50:28 deskplayer volumio[3265]: SPOTIFY: SPOTIFY VOLUME undefined Aug 29 10:50:28 deskplayer volumio[3265]: SPOTIFY: VOLUMIO VOLUME 51 Aug 29 10:50:28 deskplayer volumio[3265]: info: Aligning Spotify Volume to Volumio Volume Aug 29 10:50:28 deskplayer volumio[3265]: info: CoreCommandRouter::volumioGetState Aug 29 10:50:28 deskplayer volumio[3265]: info: CorePlayQueue::getTrack 0 Aug 29 10:50:28 deskplayer volumio[3265]: info: Setting Spotify Volume from Volumio: 51 Aug 29 10:50:28 deskplayer go-librespot[3702]: time="2026-08-29T10:50:28+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 29 10:50:28 deskplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 10:50:28 deskplayer volumio[3265]: info: Connection to go-librespot Websocket closed Aug 29 10:50:28 deskplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 10:50:28 deskplayer volumio[3265]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 10:50:28 deskplayer volumio[3265]: Error: socket hang up Aug 29 10:50:28 deskplayer volumio[3265]: at connResetException (node:internal/errors:720:14) Aug 29 10:50:28 deskplayer volumio[3265]: at Socket.socketOnEnd (node:_http_client:519:23) Aug 29 10:50:28 deskplayer volumio[3265]: at Socket.emit (node:events:526:35) Aug 29 10:50:28 deskplayer volumio[3265]: at endReadableNT (node:internal/streams/readable:1376:12) Aug 29 10:50:28 deskplayer volumio[3265]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Aug 29 10:50:28 deskplayer volumio[3265]: code: 'ECONNRESET', Aug 29 10:50:28 deskplayer volumio[3265]: response: undefined Aug 29 10:50:28 deskplayer volumio[3265]: } Aug 29 10:50:28 deskplayer volumio[3265]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 10:50:30 deskplayer sudo[3757]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-29 10:49' Aug 29 10:50:30 deskplayer sudo[3757]: 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"