Sep 01 01:27:01 stefans-speaker volumio[24258]: info: ------------------------------------------- Sep 01 01:27:01 stefans-speaker volumio[24258]: info: ----- Volumio3 ---- Sep 01 01:27:01 stefans-speaker volumio[24258]: info: ------------------------------------------- Sep 01 01:27:01 stefans-speaker volumio[24258]: info: ----- System startup ---- Sep 01 01:27:01 stefans-speaker volumio[24258]: info: ------------------------------------------- Sep 01 01:27:02 stefans-speaker volumio[24258]: info: MYVOLUMIO Environment detected Sep 01 01:27:02 stefans-speaker volumio[24258]: info: Plugin folders cleanup Sep 01 01:27:02 stefans-speaker volumio[24258]: info: Scanning into folder /volumio/app/plugins/ Sep 01 01:27:02 stefans-speaker volumio[24258]: info: Scanning category audio_interface Sep 01 01:27:02 stefans-speaker volumio[24258]: info: Scanning category miscellanea Sep 01 01:27:02 stefans-speaker volumio[24258]: info: Scanning category music_service Sep 01 01:27:02 stefans-speaker volumio[24258]: info: Scanning category plugins.json Sep 01 01:27:02 stefans-speaker volumio[24258]: info: Scanning category system_controller Sep 01 01:27:02 stefans-speaker volumio[24258]: info: Scanning category user_interface Sep 01 01:27:02 stefans-speaker volumio[24258]: info: Scanning into folder /data/plugins/ Sep 01 01:27:02 stefans-speaker volumio[24258]: info: Scanning category music_service Sep 01 01:27:02 stefans-speaker volumio[24258]: info: Plugin folders cleanup completed Sep 01 01:27:02 stefans-speaker volumio[24258]: info: ------------------------------------------- Sep 01 01:27:02 stefans-speaker volumio[24258]: info: ----- Core plugins startup ---- Sep 01 01:27:02 stefans-speaker volumio[24258]: info: ------------------------------------------- Sep 01 01:27:02 stefans-speaker volumio[24258]: info: Loading plugins from folder /volumio/app/plugins/ Sep 01 01:27:02 stefans-speaker volumio[24258]: info: Adding plugin upnp to MyMusic Plugins Sep 01 01:27:02 stefans-speaker volumio[24258]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 01 01:27:02 stefans-speaker volumio[24258]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 01 01:27:02 stefans-speaker volumio[24258]: info: Loading plugins from folder /data/plugins/ Sep 01 01:27:02 stefans-speaker volumio[24258]: info: Loading plugin "system"... Sep 01 01:27:02 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Sep 01 01:27:02 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:02 stefans-speaker volumio[24258]: info: Loading plugin "appearance"... Sep 01 01:27:02 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:02 stefans-speaker go-librespot[24282]: go-librespot daemon starting... Sep 01 01:27:02 stefans-speaker go-librespot[24286]: time="2026-09-01T01:27:02+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:27:02 stefans-speaker go-librespot[24286]: time="2026-09-01T01:27:02+02:00" level=debug msg="app state loaded" Sep 01 01:27:02 stefans-speaker go-librespot[24286]: time="2026-09-01T01:27:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:27:02 stefans-speaker go-librespot[24286]: time="2026-09-01T01:27:02+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]" Sep 01 01:27:02 stefans-speaker go-librespot[24286]: time="2026-09-01T01:27:02+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]" Sep 01 01:27:02 stefans-speaker go-librespot[24286]: time="2026-09-01T01:27:02+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]" Sep 01 01:27:02 stefans-speaker go-librespot[24286]: time="2026-09-01T01:27:02+02:00" level=info msg="zeroconf server listening on port 40279" Sep 01 01:27:02 stefans-speaker go-librespot[24286]: time="2026-09-01T01:27:02+02:00" level=debug msg="obtained new client token: AAG07bpNvXbXY27PNIkVAD5EMdDQod3LadiOYro0L2h4yeE6Q4XIWd+dRpkPZS49ZdNfUBknR90INQihgqTUAPvZg/FP+JHQSEhCdGOHHEKvN/3PoY0Ww8fDfMvAZtKJHTWsO7iV4dOXYtz0Jpa5dDZHWpU9RlFMpNwki/rgE4eE04Kf2owCF5box+4iL5vIIAb/8TZEG8zrKUfl30BDV63Dz2dolDXpoh9dQ2c6yXxolQezBp2waZ31m6k=" Sep 01 01:27:02 stefans-speaker go-librespot[24286]: time="2026-09-01T01:27:02+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:27:02 stefans-speaker go-librespot[24286]: time="2026-09-01T01:27:02+02:00" level=debug msg="completed keyexchange" Sep 01 01:27:02 stefans-speaker go-librespot[24286]: time="2026-09-01T01:27:02+02:00" level=debug msg="completed challenge" Sep 01 01:27:02 stefans-speaker go-librespot[24286]: time="2026-09-01T01:27:02+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:27:03 stefans-speaker go-librespot[24286]: time="2026-09-01T01:27:03+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 01:27:03 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:27:03 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:27:03 stefans-speaker volumio[24258]: info: Loading plugin "network"... Sep 01 01:27:03 stefans-speaker volumio[24258]: info: Refreshing Cached IP Addresses Sep 01 01:27:03 stefans-speaker sudo[24295]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:03 stefans-speaker volumio[24258]: info: Loading plugin "services"... Sep 01 01:27:03 stefans-speaker sudo[24297]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:03 stefans-speaker sudo[24295]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 01:27:03 stefans-speaker volumio[24258]: info: Loading plugin "alsa_controller"... Sep 01 01:27:03 stefans-speaker sudo[24295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:03 stefans-speaker sudo[24295]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:03 stefans-speaker sudo[24297]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 01:27:03 stefans-speaker sudo[24297]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:03 stefans-speaker sudo[24297]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:03 stefans-speaker sudo[24304]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:03 stefans-speaker sudo[24304]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 01 01:27:03 stefans-speaker sudo[24304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:03 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:27:03 stefans-speaker volumio[24258]: info: Loading plugin "wizard"... Sep 01 01:27:03 stefans-speaker volumio[24258]: info: Loading plugin "networkfs"... Sep 01 01:27:03 stefans-speaker volumio[24258]: info: Starting Udev Watcher for removable devices Sep 01 01:27:03 stefans-speaker volumio[24258]: info: Ignoring mount for partition: boot Sep 01 01:27:03 stefans-speaker volumio[24258]: info: Ignoring mount for partition: volumio Sep 01 01:27:03 stefans-speaker volumio[24258]: info: Ignoring mount for partition: volumio_data Sep 01 01:27:03 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 01:27:03 stefans-speaker volumio[24258]: info: Loading plugin "volumio_command_line_client"... Sep 01 01:27:03 stefans-speaker volumio[24258]: info: Loading plugin "upnp"... Sep 01 01:27:03 stefans-speaker volumio[24258]: info: [1788218823684] Starting Upmpd Daemon Sep 01 01:27:03 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 01:27:03 stefans-speaker volumio[24258]: info: Loading plugin "my_music"... Sep 01 01:27:03 stefans-speaker volumio[24258]: info: Loading plugin "mpd"... Sep 01 01:27:04 stefans-speaker volumio[24258]: info: Loading plugin "upnp_browser"... Sep 01 01:27:04 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:27:04] [connect] Successful connection Sep 01 01:27:05 stefans-speaker volumio[24258]: info: Starting UPNP Browser Sep 01 01:27:05 stefans-speaker volumio[24258]: info: Loading plugin "alarm-clock"... Sep 01 01:27:05 stefans-speaker volumio[24258]: info: Loading plugin "airplay_emulation"... Sep 01 01:27:05 stefans-speaker volumio[24258]: info: Starting Shairport Sync Sep 01 01:27:05 stefans-speaker volumio[24258]: info: Loading plugin "last_100"... Sep 01 01:27:05 stefans-speaker volumio[24258]: info: Loading plugin "webradio"... Sep 01 01:27:05 stefans-speaker volumio[24258]: info: Loading plugin "i2s_dacs"... Sep 01 01:27:05 stefans-speaker volumio[24258]: info: I2S DAC not set, start Auto-detection Sep 01 01:27:05 stefans-speaker volumio[24258]: info: Loading plugin "volumiodiscovery"... Sep 01 01:27:05 stefans-speaker volumio[24258]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 01:27:05 stefans-speaker volumio[24258]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:27:05 stefans-speaker volumio[24258]: *** WARNING *** For more information see Sep 01 01:27:05 stefans-speaker volumio[24258]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 01:27:05 stefans-speaker volumio[24258]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:27:05 stefans-speaker node[24258]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 01:27:05 stefans-speaker volumio[24258]: *** WARNING *** For more information see Sep 01 01:27:05 stefans-speaker node[24258]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:27:05 stefans-speaker node[24258]: *** WARNING *** For more information see Sep 01 01:27:05 stefans-speaker node[24258]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 01:27:05 stefans-speaker node[24258]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:27:05 stefans-speaker node[24258]: *** WARNING *** For more information see Sep 01 01:27:05 stefans-speaker volumio[24258]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 01 01:27:05 stefans-speaker volumio[24258]: info: Discovery: Started advertising with name: Stefan’s Speaker Sep 01 01:27:06 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 01:27:06 stefans-speaker volumio[24258]: info: Loading plugin "spop"... Sep 01 01:27:06 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Sep 01 01:27:06 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:06 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:06 stefans-speaker go-librespot[24330]: go-librespot daemon starting... Sep 01 01:27:06 stefans-speaker go-librespot[24331]: time="2026-09-01T01:27:06+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:27:06 stefans-speaker go-librespot[24331]: time="2026-09-01T01:27:06+02:00" level=debug msg="app state loaded" Sep 01 01:27:06 stefans-speaker go-librespot[24331]: time="2026-09-01T01:27:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:27:06 stefans-speaker go-librespot[24331]: time="2026-09-01T01:27:06+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]" Sep 01 01:27:06 stefans-speaker go-librespot[24331]: time="2026-09-01T01:27:06+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]" Sep 01 01:27:06 stefans-speaker go-librespot[24331]: time="2026-09-01T01:27:06+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]" Sep 01 01:27:06 stefans-speaker go-librespot[24331]: time="2026-09-01T01:27:06+02:00" level=info msg="zeroconf server listening on port 36145" Sep 01 01:27:06 stefans-speaker sudo[24304]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:06 stefans-speaker go-librespot[24331]: time="2026-09-01T01:27:06+02:00" level=debug msg="obtained new client token: AAEwxeGnAsgu5bNHa82w0y0ReBLJB/JVCOaXF2jE9PSbgzp0Cz+u4N6Oa9Jb3Qb2WodODXFIHE4V/f588wFU6aHRVY6B7paBAcWD+bVmOQpnQpB7hhIwnzyEWsMhhL8AqnXYWRkPlaV9PKxr09UEVuAQ+iTAXzxNW8Y+k/DwX/G+743Cmp7TulgKSyORFfJt750KbnR4QhQ60j6lOGg7gF7Cj/3El1m9QT6VuKaw7QUFlxSFQhFw+iW+xkA=" Sep 01 01:27:07 stefans-speaker go-librespot[24331]: time="2026-09-01T01:27:07+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:27:07 stefans-speaker go-librespot[24331]: time="2026-09-01T01:27:07+02:00" level=debug msg="completed keyexchange" Sep 01 01:27:07 stefans-speaker go-librespot[24331]: time="2026-09-01T01:27:07+02:00" level=debug msg="completed challenge" Sep 01 01:27:07 stefans-speaker volumio[24258]: info: Loading plugin "ytcr"... Sep 01 01:27:07 stefans-speaker go-librespot[24331]: time="2026-09-01T01:27:07+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:27:07 stefans-speaker go-librespot[24331]: time="2026-09-01T01:27:07+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 01:27:07 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:27:07 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:27:08 stefans-speaker volumio[24258]: info: Loading plugin "outputs"... Sep 01 01:27:08 stefans-speaker volumio[24258]: info: Loading plugin "albumart"... Sep 01 01:27:08 stefans-speaker volumio[24258]: info: Plugin example_plugin is not enabled Sep 01 01:27:08 stefans-speaker volumio[24258]: info: Loading plugin "inputs"... Sep 01 01:27:08 stefans-speaker volumio[24258]: info: Loading plugin "updater_comm"... Sep 01 01:27:08 stefans-speaker volumio[24258]: info: Plugin mpdemulation is not enabled Sep 01 01:27:08 stefans-speaker volumio[24258]: info: Loading plugin "rest_api"... Sep 01 01:27:08 stefans-speaker volumio[24258]: info: Loading plugin "websocket"... Sep 01 01:27:08 stefans-speaker volumio[24258]: info: Starting Socket.io Server version 1.7.4 Sep 01 01:27:08 stefans-speaker volumio[24258]: info: Loading i18n strings for locale nl Sep 01 01:27:08 stefans-speaker volumio[24258]: Updating browse sources language Sep 01 01:27:08 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:27:08 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:27:08 stefans-speaker volumio[24258]: info: CoreCommandRouter::initPlayerControls Sep 01 01:27:08 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:08 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:08 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:08 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:08 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:08 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:08 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:08 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:08 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:27:08 stefans-speaker volumio[24258]: Express server listening on port 3000 Sep 01 01:27:08 stefans-speaker volumio[24258]: [Metrics] WebUI: 8s 131.21ms Sep 01 01:27:08 stefans-speaker volumio[24258]: info: CoreStateMachine::resetVolumioState Sep 01 01:27:08 stefans-speaker volumio[24258]: info: CoreStateMachine::getcurrentVolume Sep 01 01:27:08 stefans-speaker volumio[24258]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:09 stefans-speaker volumio[24258]: info: Cannot read play queue from file Sep 01 01:27:09 stefans-speaker volumio[24258]: info: Volumio Network Manager: Network status updated: 2 Sep 01 01:27:09 stefans-speaker volumio[24338]: Forking 3 albumart workers Sep 01 01:27:09 stefans-speaker volumio[24258]: info: VolumeController:: Volume=70 Mute =false Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CoreStateMachine::pushState Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CorePlayQueue::getTrack 0 Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CoreCommandRouter::volumioPushState Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CoreStateMachine::updateTrackBlock Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CorePlayQueue::getTrackBlock Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CoreStateMachine::setRepeat null single undefined Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CoreStateMachine::pushState Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CorePlayQueue::getTrack 0 Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CoreCommandRouter::volumioPushState Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CoreStateMachine::setRandom null Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CoreStateMachine::pushState Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CorePlayQueue::getTrack 0 Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CoreCommandRouter::volumioPushState Sep 01 01:27:09 stefans-speaker volumio[24258]: info: Setting Device type: Raspberry PI Sep 01 01:27:09 stefans-speaker volumio[24258]: info: VolumeController:: Volume=70 Mute =false Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CoreStateMachine::pushState Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CorePlayQueue::getTrack 0 Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CoreCommandRouter::volumioPushState Sep 01 01:27:09 stefans-speaker volumio[24258]: info: Completed loading Core Plugins Sep 01 01:27:09 stefans-speaker volumio[24258]: info: Preparing to generate the ALSA configuration file Sep 01 01:27:09 stefans-speaker volumio[24258]: info: Discovery: adding a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 01:27:09 stefans-speaker volumio[24258]: info: Discovery: Found device Stefan’s Speaker Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CoreCommandRouter::volumioGetState Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CorePlayQueue::getTrack 0 Sep 01 01:27:09 stefans-speaker volumio[24258]: info: Discovery: this is already registered, a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 01:27:09 stefans-speaker volumio[24258]: info: Discovery: Found device Stefan’s Speaker Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CoreCommandRouter::volumioGetState Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CorePlayQueue::getTrack 0 Sep 01 01:27:09 stefans-speaker volumio[24258]: info: Asound.conf file unchanged, so no further update is needed Sep 01 01:27:09 stefans-speaker volumio[24258]: info: Output device has changed, restarting MPD Sep 01 01:27:09 stefans-speaker volumio[24258]: info: Output device has changed, restarting Shairport Sync Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:09 stefans-speaker sudo[24396]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:09 stefans-speaker sudo[24398]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:09 stefans-speaker sudo[24396]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 01:27:09 stefans-speaker sudo[24396]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:09 stefans-speaker sudo[24398]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 01:27:09 stefans-speaker sudo[24396]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:09 stefans-speaker volumio[24258]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 01:27:09 stefans-speaker sudo[24398]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:09 stefans-speaker volumio[24258]: info: ___________ START PLUGINS ___________ Sep 01 01:27:09 stefans-speaker volumio[24258]: info: ControllerMpd::onStart: Initializing MPD Sep 01 01:27:09 stefans-speaker volumio[24258]: info: Creating MPD Configuration file Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:27:09 stefans-speaker volumio[24258]: info: [1788218829866] CoreMusicLibrary::Adding element Media Servers Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:27:09 stefans-speaker systemd[1]: Stopping mpd.service - Music Player Daemon... Sep 01 01:27:09 stefans-speaker volumio[24258]: info: UPNP Browser: Client initialized successfully Sep 01 01:27:09 stefans-speaker sudo[24406]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:09 stefans-speaker sudo[24406]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 01:27:09 stefans-speaker sudo[24408]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:09 stefans-speaker sudo[24406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:09 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:09 stefans-speaker sudo[24408]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 01:27:09 stefans-speaker sudo[24408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:09 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Sep 01 01:27:09 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 01 01:27:09 stefans-speaker systemd[1]: mpd.service: Consumed 4.436s CPU time. Sep 01 01:27:09 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Sep 01 01:27:09 stefans-speaker sudo[24406]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:09 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 01 01:27:09 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 01 01:27:10 stefans-speaker volumio[24258]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 01:27:10 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:10 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:10 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 01 01:27:10 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Sep 01 01:27:10 stefans-speaker volumio[24258]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 01:27:10 stefans-speaker volumio[24258]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:27:10 stefans-speaker volumio[24258]: info: [1788218830105] CoreMusicLibrary::Adding element Last_100 Sep 01 01:27:10 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:27:10 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Sep 01 01:27:10 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 01 01:27:10 stefans-speaker volumio[24258]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:27:10 stefans-speaker volumio[24258]: info: [1788218830123] CoreMusicLibrary::Adding element Webradio Sep 01 01:27:10 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:27:10 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Sep 01 01:27:10 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 01 01:27:10 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 01 01:27:10 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:27:10 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 01 01:27:10 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Sep 01 01:27:10 stefans-speaker volumio[24258]: info: Initializing BBC Radios Sep 01 01:27:10 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:27:10 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:10 stefans-speaker volumio[24258]: info: Creating Spotify config file Sep 01 01:27:10 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:10 stefans-speaker sudo[24422]: root : unable to resolve host stefans-speaker: System error Sep 01 01:27:10 stefans-speaker sudo[24422]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:27:10 stefans-speaker sudo[24422]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 01:27:10 stefans-speaker sudo[24422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 01 01:27:10 stefans-speaker sudo[24422]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:10 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Sep 01 01:27:10 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:10 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:10 stefans-speaker go-librespot[24438]: go-librespot daemon starting... Sep 01 01:27:10 stefans-speaker go-librespot[24439]: time="2026-09-01T01:27:10+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:27:10 stefans-speaker volumio[24258]: info: Volumio Calling Home Sep 01 01:27:11 stefans-speaker go-librespot[24439]: time="2026-09-01T01:27:11+02:00" level=info msg="zeroconf server listening on port 40477" Sep 01 01:27:11 stefans-speaker volumio[24355]: Starting albumart workers Sep 01 01:27:11 stefans-speaker volumio[24356]: Starting albumart workers Sep 01 01:27:11 stefans-speaker volumio[24354]: Starting albumart workers Sep 01 01:27:11 stefans-speaker volumio[24258]: info: MPD Permissions set Sep 01 01:27:11 stefans-speaker volumio[24258]: info: MPD Permissions set Sep 01 01:27:11 stefans-speaker volumio[24258]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 01 01:27:11 stefans-speaker volumio[24258]: info: Spotify config file written Sep 01 01:27:11 stefans-speaker sudo[24451]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:11 stefans-speaker sudo[24451]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 01 01:27:11 stefans-speaker sudo[24451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:11 stefans-speaker systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Sep 01 01:27:11 stefans-speaker systemd[1]: go-librespot-daemon.service: Killing process 24447 (go-librespot) with signal SIGKILL. Sep 01 01:27:11 stefans-speaker systemd[1]: go-librespot-daemon.service: Deactivated successfully. Sep 01 01:27:11 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:11 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:11 stefans-speaker go-librespot[24453]: go-librespot daemon starting... Sep 01 01:27:12 stefans-speaker sudo[24451]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:12 stefans-speaker go-librespot[24454]: time="2026-09-01T01:27:12+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:27:12 stefans-speaker go-librespot[24454]: time="2026-09-01T01:27:12+02:00" level=debug msg="app state loaded" Sep 01 01:27:12 stefans-speaker go-librespot[24454]: time="2026-09-01T01:27:12+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:27:12 stefans-speaker volumio[24258]: info: Volumio called home Sep 01 01:27:12 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:12 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:12 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:12 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:12 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:12 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:12 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:12 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:12 stefans-speaker go-librespot[24454]: time="2026-09-01T01:27:12+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]" Sep 01 01:27:12 stefans-speaker go-librespot[24454]: time="2026-09-01T01:27:12+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]" Sep 01 01:27:12 stefans-speaker go-librespot[24454]: time="2026-09-01T01:27:12+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]" Sep 01 01:27:12 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:12 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:12 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:12 stefans-speaker go-librespot[24454]: time="2026-09-01T01:27:12+02:00" level=info msg="zeroconf server listening on port 37371" Sep 01 01:27:12 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:12 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:12 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:12 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:12 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:12 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:12 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:12 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:12 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:12 stefans-speaker volumio[24258]: info: No need to fix Spotify hosts Sep 01 01:27:12 stefans-speaker go-librespot[24454]: time="2026-09-01T01:27:12+02:00" level=debug msg="obtained new client token: AAHaSM2Z0O338bVaZFqV/rbTLlwFOifmk0j7IuShmNd90kWruIOfwsh1L2R7fWvs8UwF9Xg6viwkHqBcdYj1uragbXXbRDgmF9A8F/wu3W6gYsbecTEyLMb+Omb+dD3h74+RO1R+xAo6Tg6xLmlcIfxTjvzRPEKRs3Xc29KHwricJ+KAd8sE14j5AyoJTp5LTTrz0iEaODCNlhfvtHt4bS57PLxCFZ4axuPmRtfNE1ZnjUkxZ8Zzn4zpZVo=" Sep 01 01:27:12 stefans-speaker go-librespot[24454]: time="2026-09-01T01:27:12+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:27:12 stefans-speaker go-librespot[24454]: time="2026-09-01T01:27:12+02:00" level=debug msg="completed keyexchange" Sep 01 01:27:12 stefans-speaker go-librespot[24454]: time="2026-09-01T01:27:12+02:00" level=debug msg="completed challenge" Sep 01 01:27:12 stefans-speaker go-librespot[24454]: time="2026-09-01T01:27:12+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:27:12 stefans-speaker volumio[24258]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 01:27:12 stefans-speaker volumio[24258]: SPOTIFY: BQCoy7jTPX6zH1PYxSBloJ0gA1JmAS3d53ygiw_M9fGd9fBPtsJtX934ebp53hzNIBYWLo7_PKVG7G1wRCF6Vpbay7XibzPlZoi0pGZ0jhfk__EavseOCgnlgkNk-6a-G2I2isJlZKInXvPHQEaTgvyzlQplswAR7kX63Zq1WVjB4bgosl4F85D8P_aCwEW7ssQV3RJkcyVFWePrB8xoo846fSz04R1IVQTvMtiLK58-3-659kMFWe6EHUjpSfpNhiydOdO-CNYw4Xkb0sIKiME1WBPHFMg3swvwtELuUsGZF8x82dojQoXi-4Kpang Sep 01 01:27:12 stefans-speaker volumio[24258]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 01:27:12 stefans-speaker volumio[24258]: info: New Spotify access token = BQCoy7jTPX6zH1PYxSBloJ0gA1JmAS3d53ygiw_M9fGd9fBPtsJtX934ebp53hzNIBYWLo7_PKVG7G1wRCF6Vpbay7XibzPlZoi0pGZ0jhfk__EavseOCgnlgkNk-6a-G2I2isJlZKInXvPHQEaTgvyzlQplswAR7kX63Zq1WVjB4bgosl4F85D8P_aCwEW7ssQV3RJkcyVFWePrB8xoo846fSz04R1IVQTvMtiLK58-3-659kMFWe6EHUjpSfpNhiydOdO-CNYw4Xkb0sIKiME1WBPHFMg3swvwtELuUsGZF8x82dojQoXi-4Kpang Sep 01 01:27:12 stefans-speaker volumio[24258]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 01 01:27:12 stefans-speaker go-librespot[24454]: time="2026-09-01T01:27:12+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 01:27:12 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:27:12 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:27:12 stefans-speaker volumio[24258]: info: Starting Shairport Sync Sep 01 01:27:12 stefans-speaker volumio[24258]: info: Starting Shairport Sync Sep 01 01:27:13 stefans-speaker volumio[24258]: info: Starting Shairport Sync Sep 01 01:27:13 stefans-speaker sudo[24490]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:13 stefans-speaker sudo[24490]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 01:27:13 stefans-speaker sudo[24490]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:13 stefans-speaker sudo[24492]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:13 stefans-speaker sudo[24492]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 01:27:13 stefans-speaker sudo[24492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:13 stefans-speaker sudo[24494]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:13 stefans-speaker sudo[24494]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 01:27:13 stefans-speaker sudo[24494]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:13 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 01 01:27:13 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Sep 01 01:27:13 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:27:13 stefans-speaker systemd[1]: shairport-sync.service: Consumed 1.950s CPU time. Sep 01 01:27:13 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:27:13 stefans-speaker sudo[24490]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:13 stefans-speaker sudo[24492]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:13 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 01 01:27:13 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Sep 01 01:27:13 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:27:13 stefans-speaker volumio[24258]: info: Shairport-Sync Started Sep 01 01:27:13 stefans-speaker volumio[24258]: Error adding Membership: Error: addMembership EINVAL Sep 01 01:27:13 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:27:13 stefans-speaker sudo[24494]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:13 stefans-speaker volumio[24258]: info: CoreCommandRouter::volumioGetState Sep 01 01:27:13 stefans-speaker volumio[24258]: info: CorePlayQueue::getTrack 0 Sep 01 01:27:13 stefans-speaker volumio[24258]: info: Shairport-Sync Started Sep 01 01:27:13 stefans-speaker volumio[24258]: info: Shairport-Sync Started Sep 01 01:27:13 stefans-speaker volumio[24258]: SPOTIFY: User informations: {"account_id":"zltuAJDW9c","country":"RO","display_name":"emanueluss","email":"emanuel.grecescu@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31mmtxvuufe76qdkk6caepwk3fcy"},"followers":{"href":null,"total":3},"href":"https://api.spotify.com/v1/users/31mmtxvuufe76qdkk6caepwk3fcy","id":"31mmtxvuufe76qdkk6caepwk3fcy","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee852e7ddfcb02af85c1462f3412","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b822e7ddfcb02af85c1462f3412","width":64}],"product":"premium","type":"user","uri":"spotify:user:31mmtxvuufe76qdkk6caepwk3fcy"} Sep 01 01:27:13 stefans-speaker volumio[24258]: info: Spotify Successfully logged in Sep 01 01:27:13 stefans-speaker volumio[24258]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:27:13 stefans-speaker volumio[24258]: info: [1788218833405] CoreMusicLibrary::Adding element Spotify Sep 01 01:27:13 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:27:13 stefans-speaker volumio[24258]: Cannot find translation for source Spotify Sep 01 01:27:13 stefans-speaker volumio[24258]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 01 01:27:13 stefans-speaker volumio[24258]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 01:27:13 stefans-speaker sudo[24532]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:13 stefans-speaker sudo[24532]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 01:27:13 stefans-speaker sudo[24532]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:13 stefans-speaker volumio[24258]: info: VolumeController:: Volume=70 Mute =false Sep 01 01:27:13 stefans-speaker volumio[24258]: info: CoreCommandRouter::volumioGetState Sep 01 01:27:13 stefans-speaker volumio[24258]: info: CorePlayQueue::getTrack 0 Sep 01 01:27:13 stefans-speaker volumio[24258]: info: CoreStateMachine::pushState Sep 01 01:27:13 stefans-speaker volumio[24258]: info: CorePlayQueue::getTrack 0 Sep 01 01:27:13 stefans-speaker volumio[24258]: info: CoreCommandRouter::volumioPushState Sep 01 01:27:13 stefans-speaker sudo[24532]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:13 stefans-speaker sudo[24530]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:13 stefans-speaker sudo[24530]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 01:27:13 stefans-speaker sudo[24530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:13 stefans-speaker sudo[24536]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:13 stefans-speaker sudo[24530]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:13 stefans-speaker sudo[24536]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 01 01:27:13 stefans-speaker sudo[24536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:13 stefans-speaker sudo[24536]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:14 stefans-speaker volumio[24258]: info: Upmpdcli Daemon Started Sep 01 01:27:14 stefans-speaker mpd[24437]: 2026-09-01T01:27:14 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 01:27:14 stefans-speaker systemd[1]: Started mpd.service - Music Player Daemon. Sep 01 01:27:14 stefans-speaker sudo[24398]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:14 stefans-speaker sudo[24408]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:15 stefans-speaker volumio[24258]: error: MPD error: The expression evaluated to a falsy value: Sep 01 01:27:15 stefans-speaker volumio[24258]: assert.ok(self.idling) Sep 01 01:27:15 stefans-speaker volumio[24258]: error: The expression evaluated to a falsy value: Sep 01 01:27:15 stefans-speaker volumio[24258]: assert.ok(self.idling) Sep 01 01:27:15 stefans-speaker volumio[24258]: info: MPD running with PID24437 Sep 01 01:27:15 stefans-speaker volumio[24258]: ,establishing connection Sep 01 01:27:15 stefans-speaker volumio[24258]: error: updateQueue error: null Sep 01 01:27:15 stefans-speaker volumio[24258]: info: Completed starting Core Plugins Sep 01 01:27:15 stefans-speaker volumio[24258]: info: ------------------------------------------- Sep 01 01:27:15 stefans-speaker volumio[24258]: info: ----- MyVolumio plugins startup ---- Sep 01 01:27:15 stefans-speaker volumio[24258]: info: ------------------------------------------- Sep 01 01:27:15 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 01 01:27:15 stefans-speaker volumio[24258]: error: updateQueue error: null Sep 01 01:27:15 stefans-speaker volumio[24258]: info: go-librespot daemon successfully initialized Sep 01 01:27:16 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Sep 01 01:27:16 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:16 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:16 stefans-speaker go-librespot[24545]: go-librespot daemon starting... Sep 01 01:27:16 stefans-speaker go-librespot[24546]: time="2026-09-01T01:27:16+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:27:16 stefans-speaker go-librespot[24546]: time="2026-09-01T01:27:16+02:00" level=debug msg="app state loaded" Sep 01 01:27:16 stefans-speaker go-librespot[24546]: time="2026-09-01T01:27:16+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:27:16 stefans-speaker go-librespot[24546]: time="2026-09-01T01:27: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 01:27:16 stefans-speaker go-librespot[24546]: time="2026-09-01T01:27:16+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]" Sep 01 01:27:16 stefans-speaker go-librespot[24546]: time="2026-09-01T01:27:16+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]" Sep 01 01:27:16 stefans-speaker go-librespot[24546]: time="2026-09-01T01:27:16+02:00" level=info msg="zeroconf server listening on port 32983" Sep 01 01:27:16 stefans-speaker go-librespot[24546]: time="2026-09-01T01:27:16+02:00" level=debug msg="obtained new client token: AAEDvrfhqekGT/YoPz0qw/H3nwZnQP/vv+OwlYnpPzjTizaZbhC2IZz4kX+0fF9Gs3WG9RkXi5/BtXqfkLfUtXGOfTW05CLimhUhOw+J2h7qV6Lc0P2uVo1JHVqWv3TB6vUvXEA7AMbb8+hxkWVacZH6VDjwgEZCa/zMTef0/27ipoN56ucp+82m+coerJPxMAqNIUyaO94LOldrZCYgvHEQrxh/7dHIu9+gyPCdNoOGA5mmHza/RYFofrc=" Sep 01 01:27:16 stefans-speaker go-librespot[24546]: time="2026-09-01T01:27:16+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:27:16 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:27:16] [connect] Successful connection Sep 01 01:27:16 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:27:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788218836 101 Sep 01 01:27:16 stefans-speaker volumio[24258]: 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: 2 Sep 01 01:27:16 stefans-speaker go-librespot[24546]: time="2026-09-01T01:27:16+02:00" level=debug msg="completed keyexchange" Sep 01 01:27:16 stefans-speaker go-librespot[24546]: time="2026-09-01T01:27:16+02:00" level=debug msg="completed challenge" Sep 01 01:27:16 stefans-speaker go-librespot[24546]: time="2026-09-01T01:27:16+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:27:16 stefans-speaker go-librespot[24546]: time="2026-09-01T01:27:16+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 01:27:16 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:27:16 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:27:17 stefans-speaker sudo[24556]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:18 stefans-speaker sudo[24558]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:18 stefans-speaker sudo[24556]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 01:27:18 stefans-speaker sudo[24556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:18 stefans-speaker sudo[24558]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 01:27:18 stefans-speaker sudo[24558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:18 stefans-speaker systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Sep 01 01:27:18 stefans-speaker systemd[1]: mpd_monitor.service: Deactivated successfully. Sep 01 01:27:18 stefans-speaker systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Sep 01 01:27:18 stefans-speaker systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Sep 01 01:27:18 stefans-speaker sudo[24556]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:18 stefans-speaker sudo[24558]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:18 stefans-speaker volumio[24258]: info: Successfully started MPD Monitor Sep 01 01:27:18 stefans-speaker mpd_monitor.sh[24561]: MPD Monitor Service: Starting MPD Monitor Service Sep 01 01:27:18 stefans-speaker volumio[24258]: info: Successfully started MPD Monitor Sep 01 01:27:18 stefans-speaker volumio[24258]: info: Initializing connection to go-librespot Websocket Sep 01 01:27:18 stefans-speaker volumio[24258]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:27:19 stefans-speaker volumio[24258]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 01 01:27:20 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Sep 01 01:27:20 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:20 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:20 stefans-speaker go-librespot[24565]: go-librespot daemon starting... Sep 01 01:27:20 stefans-speaker go-librespot[24566]: time="2026-09-01T01:27:20+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:27:20 stefans-speaker go-librespot[24566]: time="2026-09-01T01:27:20+02:00" level=debug msg="app state loaded" Sep 01 01:27:20 stefans-speaker go-librespot[24566]: time="2026-09-01T01:27:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:27:20 stefans-speaker go-librespot[24566]: time="2026-09-01T01:27:20+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 01:27:20 stefans-speaker go-librespot[24566]: time="2026-09-01T01:27:20+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 01:27:20 stefans-speaker go-librespot[24566]: time="2026-09-01T01:27:20+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 01:27:20 stefans-speaker go-librespot[24566]: time="2026-09-01T01:27:20+02:00" level=info msg="zeroconf server listening on port 44973" Sep 01 01:27:20 stefans-speaker go-librespot[24566]: time="2026-09-01T01:27:20+02:00" level=debug msg="obtained new client token: AAG+qNTUkNUmG+LJj9e22CwEW0VvGEENftbyxi634YMJB74v0JTUlr3Ty8W11UDn9p8ybSsf3Mlb6zKDx3Hxf9wn70g1scEjEtv3AOc7V56Ct1eRvWg6zs18qSP2M0hZ4zBeOuIg9HNSu82pIA16ORvoGckVtCd36ZTLVy++crh3Ey69UBtfX1DBxXf2sT03sxxyQiRHzI85rU7Z9/Z4e/QNeLKSAb+N2wmi92Vj+OK/qqLeB0ConSIqt/c=" Sep 01 01:27:20 stefans-speaker go-librespot[24566]: time="2026-09-01T01:27:20+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:27:20 stefans-speaker go-librespot[24566]: time="2026-09-01T01:27:20+02:00" level=debug msg="completed keyexchange" Sep 01 01:27:20 stefans-speaker go-librespot[24566]: time="2026-09-01T01:27:20+02:00" level=debug msg="completed challenge" Sep 01 01:27:20 stefans-speaker go-librespot[24566]: time="2026-09-01T01:27:20+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:27:20 stefans-speaker go-librespot[24566]: time="2026-09-01T01:27:20+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 01:27:20 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:27:20 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:27:21 stefans-speaker volumio[24258]: info: Initializing connection to go-librespot Websocket Sep 01 01:27:21 stefans-speaker volumio[24258]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 01 01:27:23 stefans-speaker volumio[24258]: info: Adding plugin bluetooth to MyMusic Plugins Sep 01 01:27:23 stefans-speaker volumio[24258]: info: Adding plugin multiroom to MyMusic Plugins Sep 01 01:27:23 stefans-speaker volumio[24258]: info: Adding plugin metavolumio to MyMusic Plugins Sep 01 01:27:23 stefans-speaker volumio[24258]: info: Adding plugin cd_controller to MyMusic Plugins Sep 01 01:27:23 stefans-speaker volumio[24258]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 01 01:27:23 stefans-speaker volumio[24258]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 01 01:27:23 stefans-speaker volumio[24258]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 01 01:27:23 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 01 01:27:24 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Sep 01 01:27:24 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:24 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:24 stefans-speaker go-librespot[24590]: go-librespot daemon starting... Sep 01 01:27:24 stefans-speaker go-librespot[24591]: time="2026-09-01T01:27:24+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:27:24 stefans-speaker go-librespot[24591]: time="2026-09-01T01:27:24+02:00" level=debug msg="app state loaded" Sep 01 01:27:24 stefans-speaker go-librespot[24591]: time="2026-09-01T01:27:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:27:24 stefans-speaker go-librespot[24591]: time="2026-09-01T01:27:24+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 01:27:24 stefans-speaker go-librespot[24591]: time="2026-09-01T01:27:24+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]" Sep 01 01:27:24 stefans-speaker go-librespot[24591]: time="2026-09-01T01:27:24+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]" Sep 01 01:27:24 stefans-speaker go-librespot[24591]: time="2026-09-01T01:27:24+02:00" level=info msg="zeroconf server listening on port 36677" Sep 01 01:27:24 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 01 01:27:24 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 01 01:27:24 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:24 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:24 stefans-speaker volumio[24258]: info: Starting MyVolumio Remote Streaming Endpoints Sep 01 01:27:24 stefans-speaker go-librespot[24591]: time="2026-09-01T01:27:24+02:00" level=debug msg="obtained new client token: AAGB/YysZmZENhv1CIrCZX1RAFp+1tPhWC0MVc4oWyVB3uRwoQ3GhNrUHo+mobhWhlunC3nVTGauL42Av5iywkfPLDiOTx9PYPJpoRPGIEmuQ7kbtMeOdl+mOKkXwEK86T1WpOe5+RaNAdrfnvb+ZSk8LG8Hle8anCSl+DK3hIC3TsDBvlNIlv0K1Gvg50CgRJsK79fp+zMR0Ei3fbvh271kOQ6LBQhzFm1zId2BnL2f7QED99Fjxq7jvNY=" Sep 01 01:27:24 stefans-speaker volumio[24258]: info: MyVolumio login type: Token Sep 01 01:27:24 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 01 01:27:24 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 01 01:27:24 stefans-speaker go-librespot[24591]: time="2026-09-01T01:27:24+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" Sep 01 01:27:24 stefans-speaker go-librespot[24591]: time="2026-09-01T01:27:24+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Sep 01 01:27:24 stefans-speaker go-librespot[24591]: time="2026-09-01T01:27:24+02:00" level=debug msg="completed keyexchange" Sep 01 01:27:24 stefans-speaker go-librespot[24591]: time="2026-09-01T01:27:24+02:00" level=debug msg="completed challenge" Sep 01 01:27:24 stefans-speaker go-librespot[24591]: time="2026-09-01T01:27:24+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:27:24 stefans-speaker go-librespot[24591]: time="2026-09-01T01:27:24+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 01:27:24 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:27:24 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:27:25 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 01 01:27:25 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 01 01:27:25 stefans-speaker volumio[24258]: info: Streaming services startup Sep 01 01:27:25 stefans-speaker volumio[24258]: info: Starting Streaming Daemon Sep 01 01:27:25 stefans-speaker sudo[24599]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:25 stefans-speaker volumio[24258]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 01 01:27:25 stefans-speaker sudo[24599]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 01:27:25 stefans-speaker sudo[24599]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:25 stefans-speaker sudo[24599]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:25 stefans-speaker volumio[24258]: info: Initializing connection to go-librespot Websocket Sep 01 01:27:25 stefans-speaker volumio[24258]: error: Cannot start Volumio Streaming Daemon Sep 01 01:27:25 stefans-speaker volumio[24258]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 01:27:25 stefans-speaker volumio[24258]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:27:25 stefans-speaker volumio[24258]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 01:27:25 stefans-speaker volumio[24258]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:27:26 stefans-speaker volumio[24258]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 01 01:27:26 stefans-speaker volumio[24258]: info: MyVolumio token set successfully Sep 01 01:27:26 stefans-speaker volumio[24258]: info: MYVOLUMIO: Adding device Sep 01 01:27:26 stefans-speaker volumio[24258]: info: MYVOLUMIO: Evaluating Server Sep 01 01:27:27 stefans-speaker volumio[24258]: info: MyVolumio status changed Sep 01 01:27:27 stefans-speaker volumio[24258]: info: Streaming services startup Sep 01 01:27:27 stefans-speaker volumio[24258]: info: Starting Streaming Daemon Sep 01 01:27:27 stefans-speaker volumio[24258]: info: Removing browser output: myVolumio user plan is not superstar Sep 01 01:27:27 stefans-speaker volumio[24258]: info: Removing audio output: Sep 01 01:27:27 stefans-speaker volumio[24258]: info: Stoppping Tunnel 1 Sep 01 01:27:27 stefans-speaker sudo[24626]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:27 stefans-speaker sudo[24628]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:27 stefans-speaker sudo[24626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 01:27:27 stefans-speaker sudo[24626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:27 stefans-speaker sudo[24628]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 01 01:27:27 stefans-speaker sudo[24628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:27 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 01:27:27 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 01:27:27 stefans-speaker sudo[24626]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:27 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 01:27:27 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 01:27:27 stefans-speaker volumio[24258]: error: Cannot start Volumio Streaming Daemon Sep 01 01:27:27 stefans-speaker volumio[24258]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 01:27:27 stefans-speaker volumio[24258]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:27:27 stefans-speaker volumio[24258]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 01:27:27 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 01:27:27 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 01:27:27 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 01:27:27 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 01:27:27 stefans-speaker sudo[24628]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:27 stefans-speaker volumio[24258]: info: Remote SSH Stopped Sep 01 01:27:27 stefans-speaker volumio[24258]: info: Setting Geolocation for MyVolumio to eu9 Sep 01 01:27:27 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:27 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:27 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:27 stefans-speaker volumio[24258]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 01 01:27:28 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Sep 01 01:27:28 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:28 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:28 stefans-speaker go-librespot[24631]: go-librespot daemon starting... Sep 01 01:27:28 stefans-speaker go-librespot[24632]: time="2026-09-01T01:27:28+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:27:28 stefans-speaker go-librespot[24632]: time="2026-09-01T01:27:28+02:00" level=debug msg="app state loaded" Sep 01 01:27:28 stefans-speaker go-librespot[24632]: time="2026-09-01T01:27:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:27:28 stefans-speaker volumio[24258]: info: Updating MyVolumio device info Sep 01 01:27:28 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:28 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:28 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:28 stefans-speaker go-librespot[24632]: time="2026-09-01T01:27:28+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 01:27:28 stefans-speaker go-librespot[24632]: time="2026-09-01T01:27:28+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]" Sep 01 01:27:28 stefans-speaker go-librespot[24632]: time="2026-09-01T01:27:28+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]" Sep 01 01:27:28 stefans-speaker go-librespot[24632]: time="2026-09-01T01:27:28+02:00" level=info msg="zeroconf server listening on port 41707" Sep 01 01:27:28 stefans-speaker go-librespot[24632]: time="2026-09-01T01:27:28+02:00" level=debug msg="obtained new client token: AAGnBdvBm0Pu7vBaIpa60JoWJhXwXumZ50pVl+LhTbZgUAtc8pb/HSwZVSg8FHLhwTHM9TygT/VW15p/GgUiGzlwvbEuSwxGOIHtdnPbvALb3yPs0vM3a/7O3/FDm7I3r6RJEfRwMtjEzeufDZ1YGI07pa+aKV0RCfB8mbNkmpqpOAiX0AdhUr0gMp0SAxopG0XnK1diejm51RqhGdnxKhpa8Wyhk1Nc1j6zjGlG4wNGo32FfeWFJrsp7oY=" Sep 01 01:27:28 stefans-speaker go-librespot[24632]: time="2026-09-01T01:27:28+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:27:28 stefans-speaker volumio[24258]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 01 01:27:28 stefans-speaker go-librespot[24632]: time="2026-09-01T01:27:28+02:00" level=debug msg="completed keyexchange" Sep 01 01:27:28 stefans-speaker go-librespot[24632]: time="2026-09-01T01:27:28+02:00" level=debug msg="completed challenge" Sep 01 01:27:28 stefans-speaker go-librespot[24632]: time="2026-09-01T01:27:28+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:27:28 stefans-speaker volumio[24258]: info: Initializing connection to go-librespot Websocket Sep 01 01:27:28 stefans-speaker go-librespot[24632]: time="2026-09-01T01:27:28+02:00" level=debug msg="new websocket client" Sep 01 01:27:28 stefans-speaker volumio[24258]: info: Connection to go-librespot Websocket established Sep 01 01:27:28 stefans-speaker go-librespot[24632]: time="2026-09-01T01:27:28+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 01:27:28 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:27:28 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:27:28 stefans-speaker volumio[24258]: info: Connection to go-librespot Websocket closed Sep 01 01:27:31 stefans-speaker volumio[24258]: info: MYVOLUMIO: Adding device Sep 01 01:27:31 stefans-speaker volumio[24258]: info: MYVOLUMIO: Evaluating Server Sep 01 01:27:31 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Sep 01 01:27:31 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:31 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:31 stefans-speaker go-librespot[24648]: go-librespot daemon starting... Sep 01 01:27:31 stefans-speaker go-librespot[24653]: time="2026-09-01T01:27:31+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:27:31 stefans-speaker go-librespot[24653]: time="2026-09-01T01:27:31+02:00" level=debug msg="app state loaded" Sep 01 01:27:31 stefans-speaker go-librespot[24653]: time="2026-09-01T01:27:31+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:27:31 stefans-speaker volumio[24258]: info: Getting Spotify volume Sep 01 01:27:31 stefans-speaker volumio[24258]: info: Initializing connection to go-librespot Websocket Sep 01 01:27:31 stefans-speaker go-librespot[24653]: time="2026-09-01T01:27:31+02:00" level=debug msg="new websocket client" Sep 01 01:27:31 stefans-speaker volumio[24258]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 01 01:27:31 stefans-speaker volumio[24258]: info: Connection to go-librespot Websocket established Sep 01 01:27:32 stefans-speaker volumio[24258]: info: CoreCommandRouter::volumioGetState Sep 01 01:27:32 stefans-speaker volumio[24258]: info: CorePlayQueue::getTrack 0 Sep 01 01:27:32 stefans-speaker volumio[24258]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Sep 01 01:27:32 stefans-speaker volumio[24258]: SPOTIFY: SPOTIFY VOLUME undefined Sep 01 01:27:32 stefans-speaker volumio[24258]: SPOTIFY: VOLUMIO VOLUME 70 Sep 01 01:27:32 stefans-speaker volumio[24258]: info: Aligning Spotify Volume to Volumio Volume Sep 01 01:27:32 stefans-speaker volumio[24258]: info: CoreCommandRouter::volumioGetState Sep 01 01:27:32 stefans-speaker volumio[24258]: info: CorePlayQueue::getTrack 0 Sep 01 01:27:32 stefans-speaker volumio[24258]: info: Setting Spotify Volume from Volumio: 70 Sep 01 01:27:32 stefans-speaker go-librespot[24653]: time="2026-09-01T01:27:32+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 01:27:32 stefans-speaker go-librespot[24653]: time="2026-09-01T01:27:32+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]" Sep 01 01:27:32 stefans-speaker go-librespot[24653]: time="2026-09-01T01:27:32+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]" Sep 01 01:27:32 stefans-speaker volumio[24258]: info: Setting Geolocation for MyVolumio to eu2 Sep 01 01:27:32 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:32 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:32 stefans-speaker volumio[24258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:32 stefans-speaker go-librespot[24653]: time="2026-09-01T01:27:32+02:00" level=info msg="zeroconf server listening on port 38339" Sep 01 01:27:32 stefans-speaker go-librespot[24653]: time="2026-09-01T01:27:32+02:00" level=debug msg="obtained new client token: AAEEx2xGyzPXlX+8WJKSFTFwvQXNeK+xP5AO8gHSHYUl6+KAgi31OjYGhuRcCYOBoRGw+xDzRySzoZbQVGVC/GjeA0V1BhACCnVpsUmeZAM/cn/dazDpaAtQjaBl8i7YVtTKT484k0YgTCeiLnYUsxe73qElFWPCvOSMY1uRfZR0tM7gKZthjOwpJ/0V6x6qFzQrb5mC2vOHU1iulI9cxDrDIvEoPU0NAzBXSnvvxsIRkEhpARN6QCLnkyQ=" Sep 01 01:27:32 stefans-speaker go-librespot[24653]: time="2026-09-01T01:27:32+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:27:32 stefans-speaker go-librespot[24653]: time="2026-09-01T01:27:32+02:00" level=debug msg="completed keyexchange" Sep 01 01:27:32 stefans-speaker go-librespot[24653]: time="2026-09-01T01:27:32+02:00" level=debug msg="completed challenge" Sep 01 01:27:32 stefans-speaker go-librespot[24653]: time="2026-09-01T01:27:32+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:27:32 stefans-speaker go-librespot[24653]: time="2026-09-01T01:27:32+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 01:27:32 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:27:32 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:27:32 stefans-speaker volumio[24258]: info: Connection to go-librespot Websocket closed Sep 01 01:27:32 stefans-speaker volumio[24258]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 01:27:32 stefans-speaker volumio[24258]: Error: socket hang up Sep 01 01:27:32 stefans-speaker volumio[24258]: at connResetException (node:internal/errors:720:14) Sep 01 01:27:32 stefans-speaker volumio[24258]: at Socket.socketOnEnd (node:_http_client:519:23) Sep 01 01:27:32 stefans-speaker volumio[24258]: at Socket.emit (node:events:526:35) Sep 01 01:27:32 stefans-speaker volumio[24258]: at endReadableNT (node:internal/streams/readable:1376:12) Sep 01 01:27:32 stefans-speaker volumio[24258]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Sep 01 01:27:32 stefans-speaker volumio[24258]: code: 'ECONNRESET', Sep 01 01:27:32 stefans-speaker volumio[24258]: response: undefined Sep 01 01:27:32 stefans-speaker volumio[24258]: } Sep 01 01:27:32 stefans-speaker volumio[24258]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 01:27:33 stefans-speaker sudo[24681]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:33 stefans-speaker sudo[24681]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-09-01 01:26' Sep 01 01:27:33 stefans-speaker sudo[24681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:33 stefans-speaker sudo[24681]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:34 stefans-speaker volumio[24258]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:27:34 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:27:34] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 01 01:27:34 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:27:34] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 01 01:27:34 stefans-speaker systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:27:34 stefans-speaker systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 01 01:27:34 stefans-speaker systemd[1]: volumio.service: Consumed 32.857s CPU time. Sep 01 01:27:34 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 01 01:27:34 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Sep 01 01:27:34 stefans-speaker systemd[1]: volumio.service: Scheduled restart job, restart counter is at 326. Sep 01 01:27:34 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 01 01:27:34 stefans-speaker systemd[1]: Stopped volumio.service - Volumio Backend Module. Sep 01 01:27:34 stefans-speaker systemd[1]: volumio.service: Consumed 32.857s CPU time. Sep 01 01:27:34 stefans-speaker systemd[1]: Started volumio.service - Volumio Backend Module. Sep 01 01:27:34 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Sep 01 01:27:35 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Sep 01 01:27:35 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:35 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:35 stefans-speaker go-librespot[24730]: go-librespot daemon starting... Sep 01 01:27:35 stefans-speaker go-librespot[24731]: time="2026-09-01T01:27:35+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:27:35 stefans-speaker go-librespot[24731]: time="2026-09-01T01:27:35+02:00" level=debug msg="app state loaded" Sep 01 01:27:35 stefans-speaker go-librespot[24731]: time="2026-09-01T01:27:35+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:27:36 stefans-speaker go-librespot[24731]: time="2026-09-01T01:27:36+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]" Sep 01 01:27:36 stefans-speaker go-librespot[24731]: time="2026-09-01T01:27:36+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]" Sep 01 01:27:36 stefans-speaker go-librespot[24731]: time="2026-09-01T01:27:36+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]" Sep 01 01:27:36 stefans-speaker go-librespot[24731]: time="2026-09-01T01:27:36+02:00" level=info msg="zeroconf server listening on port 34937" Sep 01 01:27:36 stefans-speaker go-librespot[24731]: time="2026-09-01T01:27:36+02:00" level=debug msg="obtained new client token: AAG+HHMSOfQ0MCzOFeIbpOFbD9D/iHqsgOzMN2tGCKUBx4zWTg/QamPripput9boTOGKgcC2KGn98AynkgpBCzHiiROsLSv/O9TtBb3dWSiifqJQPpzifxDNkis6cpwxpOHWgg9oMILQqnmpe5aUSuz8sFLdG8xvkEvLD49RLAVPMdinG7ZpN/ubqSN9hypX/wUMBejqZZxyf0pCyYr3jormV8E3i0E/bvsInrfsRiWE1X6ulpQQ3n5vfTg=" Sep 01 01:27:36 stefans-speaker go-librespot[24731]: time="2026-09-01T01:27:36+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:27:36 stefans-speaker volumio[24713]: info: ------------------------------------------- Sep 01 01:27:36 stefans-speaker volumio[24713]: info: ----- Volumio3 ---- Sep 01 01:27:36 stefans-speaker volumio[24713]: info: ------------------------------------------- Sep 01 01:27:36 stefans-speaker volumio[24713]: info: ----- System startup ---- Sep 01 01:27:36 stefans-speaker volumio[24713]: info: ------------------------------------------- Sep 01 01:27:36 stefans-speaker go-librespot[24731]: time="2026-09-01T01:27:36+02:00" level=debug msg="completed keyexchange" Sep 01 01:27:36 stefans-speaker go-librespot[24731]: time="2026-09-01T01:27:36+02:00" level=debug msg="completed challenge" Sep 01 01:27:36 stefans-speaker go-librespot[24731]: time="2026-09-01T01:27:36+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:27:36 stefans-speaker go-librespot[24731]: time="2026-09-01T01:27:36+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 01:27:36 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:27:36 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:27:37 stefans-speaker volumio[24713]: info: MYVOLUMIO Environment detected Sep 01 01:27:37 stefans-speaker volumio[24713]: info: Plugin folders cleanup Sep 01 01:27:37 stefans-speaker volumio[24713]: info: Scanning into folder /volumio/app/plugins/ Sep 01 01:27:37 stefans-speaker volumio[24713]: info: Scanning category audio_interface Sep 01 01:27:37 stefans-speaker volumio[24713]: info: Scanning category miscellanea Sep 01 01:27:37 stefans-speaker volumio[24713]: info: Scanning category music_service Sep 01 01:27:37 stefans-speaker volumio[24713]: info: Scanning category plugins.json Sep 01 01:27:37 stefans-speaker volumio[24713]: info: Scanning category system_controller Sep 01 01:27:37 stefans-speaker volumio[24713]: info: Scanning category user_interface Sep 01 01:27:37 stefans-speaker volumio[24713]: info: Scanning into folder /data/plugins/ Sep 01 01:27:37 stefans-speaker volumio[24713]: info: Scanning category music_service Sep 01 01:27:37 stefans-speaker volumio[24713]: info: Plugin folders cleanup completed Sep 01 01:27:37 stefans-speaker volumio[24713]: info: ------------------------------------------- Sep 01 01:27:37 stefans-speaker volumio[24713]: info: ----- Core plugins startup ---- Sep 01 01:27:37 stefans-speaker volumio[24713]: info: ------------------------------------------- Sep 01 01:27:37 stefans-speaker volumio[24713]: info: Loading plugins from folder /volumio/app/plugins/ Sep 01 01:27:37 stefans-speaker volumio[24713]: info: Adding plugin upnp to MyMusic Plugins Sep 01 01:27:37 stefans-speaker volumio[24713]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 01 01:27:37 stefans-speaker volumio[24713]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 01 01:27:37 stefans-speaker volumio[24713]: info: Loading plugins from folder /data/plugins/ Sep 01 01:27:37 stefans-speaker volumio[24713]: info: Loading plugin "system"... Sep 01 01:27:37 stefans-speaker volumio[24713]: info: Loading plugin "appearance"... Sep 01 01:27:38 stefans-speaker volumio[24713]: info: Loading plugin "network"... Sep 01 01:27:38 stefans-speaker volumio[24713]: info: Refreshing Cached IP Addresses Sep 01 01:27:38 stefans-speaker sudo[24754]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:38 stefans-speaker sudo[24756]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:38 stefans-speaker sudo[24754]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 01:27:38 stefans-speaker volumio[24713]: info: Loading plugin "services"... Sep 01 01:27:38 stefans-speaker sudo[24754]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:38 stefans-speaker volumio[24713]: info: Loading plugin "alsa_controller"... Sep 01 01:27:38 stefans-speaker sudo[24754]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:38 stefans-speaker sudo[24756]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 01:27:38 stefans-speaker sudo[24756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:38 stefans-speaker sudo[24763]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:38 stefans-speaker sudo[24756]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:38 stefans-speaker sudo[24763]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 01 01:27:38 stefans-speaker sudo[24763]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:38 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:27:38 stefans-speaker volumio[24713]: info: Loading plugin "wizard"... Sep 01 01:27:38 stefans-speaker volumio[24713]: info: Loading plugin "networkfs"... Sep 01 01:27:38 stefans-speaker volumio[24713]: info: Starting Udev Watcher for removable devices Sep 01 01:27:38 stefans-speaker volumio[24713]: info: Ignoring mount for partition: boot Sep 01 01:27:38 stefans-speaker volumio[24713]: info: Ignoring mount for partition: volumio Sep 01 01:27:38 stefans-speaker volumio[24713]: info: Ignoring mount for partition: volumio_data Sep 01 01:27:38 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 01:27:38 stefans-speaker volumio[24713]: info: Loading plugin "volumio_command_line_client"... Sep 01 01:27:38 stefans-speaker volumio[24713]: info: Loading plugin "upnp"... Sep 01 01:27:38 stefans-speaker volumio[24713]: info: [1788218858630] Starting Upmpd Daemon Sep 01 01:27:38 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 01:27:38 stefans-speaker volumio[24713]: info: Loading plugin "my_music"... Sep 01 01:27:38 stefans-speaker volumio[24713]: info: Loading plugin "mpd"... Sep 01 01:27:39 stefans-speaker volumio[24713]: info: Loading plugin "upnp_browser"... Sep 01 01:27:39 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:27:39] [connect] Successful connection Sep 01 01:27:39 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Sep 01 01:27:39 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:39 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:39 stefans-speaker go-librespot[24787]: go-librespot daemon starting... Sep 01 01:27:39 stefans-speaker go-librespot[24788]: time="2026-09-01T01:27:39+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:27:39 stefans-speaker go-librespot[24788]: time="2026-09-01T01:27:39+02:00" level=debug msg="app state loaded" Sep 01 01:27:39 stefans-speaker go-librespot[24788]: time="2026-09-01T01:27:39+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:27:40 stefans-speaker go-librespot[24788]: time="2026-09-01T01:27: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]" Sep 01 01:27:40 stefans-speaker go-librespot[24788]: time="2026-09-01T01:27: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]" Sep 01 01:27:40 stefans-speaker go-librespot[24788]: time="2026-09-01T01:27: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]" Sep 01 01:27:40 stefans-speaker go-librespot[24788]: time="2026-09-01T01:27:40+02:00" level=info msg="zeroconf server listening on port 35223" Sep 01 01:27:40 stefans-speaker volumio[24713]: info: Starting UPNP Browser Sep 01 01:27:40 stefans-speaker volumio[24713]: info: Loading plugin "alarm-clock"... Sep 01 01:27:40 stefans-speaker volumio[24713]: info: Loading plugin "airplay_emulation"... Sep 01 01:27:40 stefans-speaker volumio[24713]: info: Starting Shairport Sync Sep 01 01:27:40 stefans-speaker volumio[24713]: info: Loading plugin "last_100"... Sep 01 01:27:40 stefans-speaker volumio[24713]: info: Loading plugin "webradio"... Sep 01 01:27:40 stefans-speaker go-librespot[24788]: time="2026-09-01T01:27:40+02:00" level=debug msg="obtained new client token: AAHkxzWqkIPGfUXuKHszD3H07BLDQbYczv1OqepUBvt5s+Fa+6g3ffdBCHWqeU5/TDTBFfV+p2jFtn6e+W95+IYxsxlf76WpwLS2O8TwFmdneKNzcRIv7CQI50AvMCHU/qXHOPEs/KfhTHj92O7LmA/EP31VDl1uRB/RSUnXSDNrA/w0Vhj0rIz1aitUlapiK+1kTQW9fgnMo+LU1dmQdaD60XSYM33fWR30ThMWyTnS4mbZv+BzJLrnc0I=" Sep 01 01:27:40 stefans-speaker volumio[24713]: info: Loading plugin "i2s_dacs"... Sep 01 01:27:40 stefans-speaker volumio[24713]: info: I2S DAC not set, start Auto-detection Sep 01 01:27:40 stefans-speaker volumio[24713]: info: Loading plugin "volumiodiscovery"... Sep 01 01:27:40 stefans-speaker volumio[24713]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 01:27:40 stefans-speaker volumio[24713]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:27:40 stefans-speaker volumio[24713]: *** WARNING *** For more information see Sep 01 01:27:40 stefans-speaker volumio[24713]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 01:27:40 stefans-speaker volumio[24713]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:27:40 stefans-speaker volumio[24713]: *** WARNING *** For more information see Sep 01 01:27:40 stefans-speaker node[24713]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 01:27:40 stefans-speaker node[24713]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:27:40 stefans-speaker node[24713]: *** WARNING *** For more information see Sep 01 01:27:40 stefans-speaker node[24713]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 01:27:40 stefans-speaker node[24713]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:27:40 stefans-speaker node[24713]: *** WARNING *** For more information see Sep 01 01:27:40 stefans-speaker volumio[24713]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 01 01:27:40 stefans-speaker volumio[24713]: info: Discovery: Started advertising with name: Stefan’s Speaker Sep 01 01:27:40 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 01:27:40 stefans-speaker volumio[24713]: info: Loading plugin "spop"... Sep 01 01:27:41 stefans-speaker go-librespot[24788]: time="2026-09-01T01:27:41+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:27:41 stefans-speaker go-librespot[24788]: time="2026-09-01T01:27:41+02:00" level=debug msg="completed keyexchange" Sep 01 01:27:41 stefans-speaker go-librespot[24788]: time="2026-09-01T01:27:41+02:00" level=debug msg="completed challenge" Sep 01 01:27:41 stefans-speaker go-librespot[24788]: time="2026-09-01T01:27:41+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:27:41 stefans-speaker go-librespot[24788]: time="2026-09-01T01:27:41+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 01:27:41 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:27:41 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:27:41 stefans-speaker sudo[24763]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:42 stefans-speaker volumio[24713]: info: Loading plugin "ytcr"... Sep 01 01:27:43 stefans-speaker volumio[24713]: info: Loading plugin "outputs"... Sep 01 01:27:43 stefans-speaker volumio[24713]: info: Loading plugin "albumart"... Sep 01 01:27:43 stefans-speaker volumio[24713]: info: Plugin example_plugin is not enabled Sep 01 01:27:43 stefans-speaker volumio[24713]: info: Loading plugin "inputs"... Sep 01 01:27:43 stefans-speaker volumio[24713]: info: Loading plugin "updater_comm"... Sep 01 01:27:43 stefans-speaker volumio[24713]: info: Plugin mpdemulation is not enabled Sep 01 01:27:43 stefans-speaker volumio[24713]: info: Loading plugin "rest_api"... Sep 01 01:27:43 stefans-speaker volumio[24713]: info: Loading plugin "websocket"... Sep 01 01:27:43 stefans-speaker volumio[24713]: info: Starting Socket.io Server version 1.7.4 Sep 01 01:27:43 stefans-speaker volumio[24713]: info: Loading i18n strings for locale nl Sep 01 01:27:43 stefans-speaker volumio[24713]: Updating browse sources language Sep 01 01:27:43 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:27:43 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:27:43 stefans-speaker volumio[24713]: info: CoreCommandRouter::initPlayerControls Sep 01 01:27:43 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:43 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:43 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:43 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:43 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:43 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:43 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:43 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:43 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:27:43 stefans-speaker volumio[24713]: Express server listening on port 3000 Sep 01 01:27:43 stefans-speaker volumio[24713]: [Metrics] WebUI: 8s 119.77ms Sep 01 01:27:43 stefans-speaker volumio[24713]: info: CoreStateMachine::resetVolumioState Sep 01 01:27:43 stefans-speaker volumio[24713]: info: CoreStateMachine::getcurrentVolume Sep 01 01:27:43 stefans-speaker volumio[24713]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 01:27:43 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:43 stefans-speaker volumio[24713]: info: Cannot read play queue from file Sep 01 01:27:44 stefans-speaker volumio[24713]: info: Volumio Network Manager: Network status updated: 2 Sep 01 01:27:44 stefans-speaker volumio[24798]: Forking 3 albumart workers Sep 01 01:27:44 stefans-speaker volumio[24713]: info: VolumeController:: Volume=70 Mute =false Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CoreStateMachine::pushState Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CorePlayQueue::getTrack 0 Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CoreCommandRouter::volumioPushState Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CoreStateMachine::updateTrackBlock Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CorePlayQueue::getTrackBlock Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CoreStateMachine::setRepeat null single undefined Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CoreStateMachine::pushState Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CorePlayQueue::getTrack 0 Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CoreCommandRouter::volumioPushState Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CoreStateMachine::setRandom null Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CoreStateMachine::pushState Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CorePlayQueue::getTrack 0 Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CoreCommandRouter::volumioPushState Sep 01 01:27:44 stefans-speaker volumio[24713]: info: Setting Device type: Raspberry PI Sep 01 01:27:44 stefans-speaker volumio[24713]: info: VolumeController:: Volume=70 Mute =false Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CoreStateMachine::pushState Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CorePlayQueue::getTrack 0 Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CoreCommandRouter::volumioPushState Sep 01 01:27:44 stefans-speaker volumio[24713]: info: Completed loading Core Plugins Sep 01 01:27:44 stefans-speaker volumio[24713]: info: Preparing to generate the ALSA configuration file Sep 01 01:27:44 stefans-speaker volumio[24713]: info: Discovery: adding a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 01:27:44 stefans-speaker volumio[24713]: info: Discovery: Found device Stefan’s Speaker Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CoreCommandRouter::volumioGetState Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CorePlayQueue::getTrack 0 Sep 01 01:27:44 stefans-speaker volumio[24713]: info: Discovery: this is already registered, a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 01:27:44 stefans-speaker volumio[24713]: info: Discovery: Found device Stefan’s Speaker Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CoreCommandRouter::volumioGetState Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CorePlayQueue::getTrack 0 Sep 01 01:27:44 stefans-speaker volumio[24713]: info: Asound.conf file unchanged, so no further update is needed Sep 01 01:27:44 stefans-speaker volumio[24713]: info: Output device has changed, restarting MPD Sep 01 01:27:44 stefans-speaker volumio[24713]: info: Output device has changed, restarting Shairport Sync Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:44 stefans-speaker sudo[24854]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:44 stefans-speaker sudo[24854]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 01:27:44 stefans-speaker sudo[24854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:44 stefans-speaker volumio[24713]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 01:27:44 stefans-speaker volumio[24713]: info: ___________ START PLUGINS ___________ Sep 01 01:27:44 stefans-speaker sudo[24856]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:44 stefans-speaker sudo[24854]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:44 stefans-speaker volumio[24713]: info: ControllerMpd::onStart: Initializing MPD Sep 01 01:27:44 stefans-speaker volumio[24713]: info: Creating MPD Configuration file Sep 01 01:27:44 stefans-speaker sudo[24856]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 01:27:44 stefans-speaker sudo[24856]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:27:44 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Sep 01 01:27:44 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:44 stefans-speaker volumio[24713]: info: [1788218864777] CoreMusicLibrary::Adding element Media Servers Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:27:44 stefans-speaker volumio[24713]: info: UPNP Browser: Client initialized successfully Sep 01 01:27:44 stefans-speaker sudo[24864]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:44 stefans-speaker sudo[24866]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:44 stefans-speaker sudo[24864]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 01:27:44 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:44 stefans-speaker sudo[24864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:44 stefans-speaker go-librespot[24867]: go-librespot daemon starting... Sep 01 01:27:44 stefans-speaker sudo[24864]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:44 stefans-speaker sudo[24866]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 01:27:44 stefans-speaker sudo[24866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:44 stefans-speaker systemd[1]: Stopping mpd.service - Music Player Daemon... Sep 01 01:27:44 stefans-speaker go-librespot[24868]: time="2026-09-01T01:27:44+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:27:44 stefans-speaker go-librespot[24868]: time="2026-09-01T01:27:44+02:00" level=debug msg="app state loaded" Sep 01 01:27:44 stefans-speaker go-librespot[24868]: time="2026-09-01T01:27:44+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:27:44 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Sep 01 01:27:44 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 01 01:27:44 stefans-speaker systemd[1]: mpd.service: Consumed 4.415s CPU time. Sep 01 01:27:44 stefans-speaker volumio[24713]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:44 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:44 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Sep 01 01:27:44 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 01 01:27:44 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 01 01:27:45 stefans-speaker volumio[24713]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 01:27:45 stefans-speaker volumio[24713]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:27:45 stefans-speaker volumio[24713]: info: [1788218865011] CoreMusicLibrary::Adding element Last_100 Sep 01 01:27:45 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:27:45 stefans-speaker volumio[24713]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:27:45 stefans-speaker volumio[24713]: info: [1788218865017] CoreMusicLibrary::Adding element Webradio Sep 01 01:27:45 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:27:45 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:27:45 stefans-speaker volumio[24713]: info: Initializing BBC Radios Sep 01 01:27:45 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 01 01:27:45 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Sep 01 01:27:45 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:27:45 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:45 stefans-speaker volumio[24713]: info: Creating Spotify config file Sep 01 01:27:45 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:45 stefans-speaker go-librespot[24868]: time="2026-09-01T01:27:45+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 01:27:45 stefans-speaker go-librespot[24868]: time="2026-09-01T01:27:45+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]" Sep 01 01:27:45 stefans-speaker go-librespot[24868]: time="2026-09-01T01:27:45+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]" Sep 01 01:27:45 stefans-speaker sudo[24889]: root : unable to resolve host stefans-speaker: System error Sep 01 01:27:45 stefans-speaker sudo[24889]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:27:45 stefans-speaker go-librespot[24868]: time="2026-09-01T01:27:45+02:00" level=info msg="zeroconf server listening on port 43373" Sep 01 01:27:45 stefans-speaker sudo[24889]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 01:27:45 stefans-speaker sudo[24889]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 01 01:27:45 stefans-speaker sudo[24889]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:45 stefans-speaker go-librespot[24868]: time="2026-09-01T01:27:45+02:00" level=debug msg="obtained new client token: AAEbBFYEkoBePvU/FryIhCgxvJ1As+jHpXGoIkce2FZFfgq9q0J5WonoIKTm+5JmuSFw8wyTvrJxhRIevm+6ad9/4VKY21XhYZdmm2ttMgaaBp1S7gIsq8kG+aqb0qYXi28W3QLgHe6/QYxv4PJBHdW4O1qdDszwJsurA4VOtIkvo7TqYRot7YQNxgDJW9jVpLsb/XDa76BasQlY7zpY2/eqIFfkTtdxRSihMb+sesOoN01nuRGJPtNAPPw=" Sep 01 01:27:45 stefans-speaker go-librespot[24868]: time="2026-09-01T01:27:45+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:27:45 stefans-speaker go-librespot[24868]: time="2026-09-01T01:27:45+02:00" level=debug msg="completed keyexchange" Sep 01 01:27:45 stefans-speaker go-librespot[24868]: time="2026-09-01T01:27:45+02:00" level=debug msg="completed challenge" Sep 01 01:27:45 stefans-speaker go-librespot[24868]: time="2026-09-01T01:27:45+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:27:45 stefans-speaker go-librespot[24868]: time="2026-09-01T01:27:45+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 01:27:45 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:27:45 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:27:45 stefans-speaker volumio[24713]: info: Volumio Calling Home Sep 01 01:27:46 stefans-speaker volumio[24814]: Starting albumart workers Sep 01 01:27:46 stefans-speaker volumio[24816]: Starting albumart workers Sep 01 01:27:46 stefans-speaker volumio[24713]: info: MPD Permissions set Sep 01 01:27:46 stefans-speaker volumio[24713]: info: MPD Permissions set Sep 01 01:27:46 stefans-speaker volumio[24713]: info: Volumio called home Sep 01 01:27:46 stefans-speaker volumio[24713]: info: Spotify config file written Sep 01 01:27:46 stefans-speaker sudo[24906]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:46 stefans-speaker volumio[24713]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 01 01:27:46 stefans-speaker sudo[24906]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 01 01:27:46 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:46 stefans-speaker sudo[24906]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:46 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:46 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:46 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:46 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:46 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:46 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:46 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:46 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:46 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:46 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:46 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:46 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:46 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:46 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:46 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:46 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:46 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:46 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:46 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:46 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:27:46 stefans-speaker volumio[24713]: info: No need to fix Spotify hosts Sep 01 01:27:46 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:46 stefans-speaker go-librespot[24913]: go-librespot daemon starting... Sep 01 01:27:46 stefans-speaker sudo[24906]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:46 stefans-speaker go-librespot[24919]: time="2026-09-01T01:27:46+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:27:46 stefans-speaker go-librespot[24919]: time="2026-09-01T01:27:46+02:00" level=debug msg="app state loaded" Sep 01 01:27:46 stefans-speaker go-librespot[24919]: time="2026-09-01T01:27:46+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:27:46 stefans-speaker volumio[24815]: Starting albumart workers Sep 01 01:27:47 stefans-speaker go-librespot[24919]: time="2026-09-01T01:27:47+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]" Sep 01 01:27:47 stefans-speaker go-librespot[24919]: time="2026-09-01T01:27:47+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]" Sep 01 01:27:47 stefans-speaker go-librespot[24919]: time="2026-09-01T01:27:47+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]" Sep 01 01:27:47 stefans-speaker go-librespot[24919]: time="2026-09-01T01:27:47+02:00" level=info msg="zeroconf server listening on port 37693" Sep 01 01:27:47 stefans-speaker go-librespot[24919]: time="2026-09-01T01:27:47+02:00" level=debug msg="obtained new client token: AAG48R3avH/r/RB5tTfyNvfttCZfteFf5hcu0X9jZeNUphpHt1y/3hfhj94ZUDt8/3B/zHiAvM7SxnIfQvccxlFluzioLwJv2Sg6s94FpGOWquVfd4ZqOyrBqGpnT9Tc66g+7KWsk32hTKCpKpLsGX1RGLceeYi9Jri+71DlOIh7tGSBBL/dYeBBylsLmLWZ7EuOTP7Pvt+Q5593lwXPRP9CJ8cqri+DtXDzj6wqe/7cK8hIvTHqX7FR" Sep 01 01:27:47 stefans-speaker go-librespot[24919]: time="2026-09-01T01:27:47+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:27:47 stefans-speaker go-librespot[24919]: time="2026-09-01T01:27:47+02:00" level=debug msg="completed keyexchange" Sep 01 01:27:47 stefans-speaker go-librespot[24919]: time="2026-09-01T01:27:47+02:00" level=debug msg="completed challenge" Sep 01 01:27:47 stefans-speaker volumio[24713]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 01:27:47 stefans-speaker volumio[24713]: SPOTIFY: BQC3F4UmlI6A8tvT-UIrfh3uJLWU9qFKEKCbqh2hXwQZBOGittq3nNAz82wIxasyU0CXO9jMunh1w6QR7g6yX0AhV8EIOekZpXlHkcz2KTgZkQu2rq7EKoUXczotD4GeAwssdSeA-ATpDsAvmNuLhRk7Xt7r0dpKhDnUkvcVnrb6_PIEssm7-0XrP0O8C_BCFICgTPy4j40Bh3bySAijP-E_ffCKpEgF3vlAiOlwTGDX4_2LtfdqHuXHLS8p9L0tXFPq5IK7Yj3q4aBHFXdBuUU2y7cTxXv3AzZFOQ3sVJ3aruCCRbWzyqIg45VTw9o Sep 01 01:27:47 stefans-speaker volumio[24713]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 01:27:47 stefans-speaker volumio[24713]: info: New Spotify access token = BQC3F4UmlI6A8tvT-UIrfh3uJLWU9qFKEKCbqh2hXwQZBOGittq3nNAz82wIxasyU0CXO9jMunh1w6QR7g6yX0AhV8EIOekZpXlHkcz2KTgZkQu2rq7EKoUXczotD4GeAwssdSeA-ATpDsAvmNuLhRk7Xt7r0dpKhDnUkvcVnrb6_PIEssm7-0XrP0O8C_BCFICgTPy4j40Bh3bySAijP-E_ffCKpEgF3vlAiOlwTGDX4_2LtfdqHuXHLS8p9L0tXFPq5IK7Yj3q4aBHFXdBuUU2y7cTxXv3AzZFOQ3sVJ3aruCCRbWzyqIg45VTw9o Sep 01 01:27:47 stefans-speaker volumio[24713]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 01 01:27:47 stefans-speaker go-librespot[24919]: time="2026-09-01T01:27:47+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:27:47 stefans-speaker go-librespot[24919]: time="2026-09-01T01:27:47+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 01:27:47 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:27:47 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:27:47 stefans-speaker volumio[24713]: info: Starting Shairport Sync Sep 01 01:27:47 stefans-speaker volumio[24713]: info: Starting Shairport Sync Sep 01 01:27:47 stefans-speaker volumio[24713]: info: Starting Shairport Sync Sep 01 01:27:47 stefans-speaker sudo[24933]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:47 stefans-speaker sudo[24935]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:47 stefans-speaker sudo[24935]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 01:27:47 stefans-speaker sudo[24935]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:47 stefans-speaker sudo[24933]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 01:27:47 stefans-speaker sudo[24937]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:47 stefans-speaker sudo[24933]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:47 stefans-speaker sudo[24937]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 01:27:47 stefans-speaker sudo[24937]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:47 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 01 01:27:47 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Sep 01 01:27:47 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:27:47 stefans-speaker systemd[1]: shairport-sync.service: Consumed 1.831s CPU time. Sep 01 01:27:47 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:27:47 stefans-speaker sudo[24935]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:47 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 01 01:27:48 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Sep 01 01:27:48 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:27:48 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:27:48 stefans-speaker sudo[24933]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:48 stefans-speaker sudo[24937]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:48 stefans-speaker volumio[24713]: info: Shairport-Sync Started Sep 01 01:27:48 stefans-speaker volumio[24713]: Error adding Membership: Error: addMembership EINVAL Sep 01 01:27:48 stefans-speaker volumio[24713]: info: Shairport-Sync Started Sep 01 01:27:48 stefans-speaker volumio[24713]: info: Shairport-Sync Started Sep 01 01:27:48 stefans-speaker volumio[24713]: info: CoreCommandRouter::volumioGetState Sep 01 01:27:48 stefans-speaker volumio[24713]: info: CorePlayQueue::getTrack 0 Sep 01 01:27:48 stefans-speaker volumio[24713]: SPOTIFY: User informations: {"account_id":"zltuAJDW9c","country":"RO","display_name":"emanueluss","email":"emanuel.grecescu@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31mmtxvuufe76qdkk6caepwk3fcy"},"followers":{"href":null,"total":3},"href":"https://api.spotify.com/v1/users/31mmtxvuufe76qdkk6caepwk3fcy","id":"31mmtxvuufe76qdkk6caepwk3fcy","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee852e7ddfcb02af85c1462f3412","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b822e7ddfcb02af85c1462f3412","width":64}],"product":"premium","type":"user","uri":"spotify:user:31mmtxvuufe76qdkk6caepwk3fcy"} Sep 01 01:27:48 stefans-speaker volumio[24713]: info: Spotify Successfully logged in Sep 01 01:27:48 stefans-speaker volumio[24713]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:27:48 stefans-speaker volumio[24713]: info: [1788218868347] CoreMusicLibrary::Adding element Spotify Sep 01 01:27:48 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:27:48 stefans-speaker volumio[24713]: Cannot find translation for source Spotify Sep 01 01:27:48 stefans-speaker sudo[24986]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:48 stefans-speaker sudo[24986]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 01:27:48 stefans-speaker sudo[24984]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:48 stefans-speaker sudo[24984]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 01:27:48 stefans-speaker sudo[24984]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:48 stefans-speaker sudo[24986]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:48 stefans-speaker sudo[24984]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:48 stefans-speaker sudo[24986]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:48 stefans-speaker sudo[24990]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:48 stefans-speaker sudo[24990]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 01 01:27:48 stefans-speaker sudo[24990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:48 stefans-speaker volumio[24713]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 01 01:27:48 stefans-speaker volumio[24713]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 01:27:49 stefans-speaker sudo[24990]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:49 stefans-speaker volumio[24713]: info: Upmpdcli Daemon Started Sep 01 01:27:49 stefans-speaker volumio[24713]: info: VolumeController:: Volume=70 Mute =false Sep 01 01:27:49 stefans-speaker volumio[24713]: info: CoreCommandRouter::volumioGetState Sep 01 01:27:49 stefans-speaker volumio[24713]: info: CorePlayQueue::getTrack 0 Sep 01 01:27:49 stefans-speaker volumio[24713]: info: CoreStateMachine::pushState Sep 01 01:27:49 stefans-speaker volumio[24713]: info: CorePlayQueue::getTrack 0 Sep 01 01:27:49 stefans-speaker volumio[24713]: info: CoreCommandRouter::volumioPushState Sep 01 01:27:49 stefans-speaker mpd[24901]: 2026-09-01T01:27:49 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 01:27:49 stefans-speaker systemd[1]: Started mpd.service - Music Player Daemon. Sep 01 01:27:49 stefans-speaker sudo[24866]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:49 stefans-speaker sudo[24856]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:50 stefans-speaker volumio[24713]: error: MPD error: The expression evaluated to a falsy value: Sep 01 01:27:50 stefans-speaker volumio[24713]: assert.ok(self.idling) Sep 01 01:27:50 stefans-speaker volumio[24713]: error: The expression evaluated to a falsy value: Sep 01 01:27:50 stefans-speaker volumio[24713]: assert.ok(self.idling) Sep 01 01:27:50 stefans-speaker volumio[24713]: error: updateQueue error: null Sep 01 01:27:50 stefans-speaker volumio[24713]: info: MPD running with PID24901 Sep 01 01:27:50 stefans-speaker volumio[24713]: ,establishing connection Sep 01 01:27:50 stefans-speaker volumio[24713]: info: Completed starting Core Plugins Sep 01 01:27:50 stefans-speaker volumio[24713]: info: ------------------------------------------- Sep 01 01:27:50 stefans-speaker volumio[24713]: info: ----- MyVolumio plugins startup ---- Sep 01 01:27:50 stefans-speaker volumio[24713]: info: ------------------------------------------- Sep 01 01:27:50 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 01 01:27:50 stefans-speaker volumio[24713]: error: updateQueue error: null Sep 01 01:27:50 stefans-speaker volumio[24713]: info: go-librespot daemon successfully initialized Sep 01 01:27:50 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Sep 01 01:27:50 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:50 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:50 stefans-speaker go-librespot[25000]: go-librespot daemon starting... Sep 01 01:27:50 stefans-speaker go-librespot[25001]: time="2026-09-01T01:27:50+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:27:50 stefans-speaker go-librespot[25001]: time="2026-09-01T01:27:50+02:00" level=debug msg="app state loaded" Sep 01 01:27:50 stefans-speaker go-librespot[25001]: time="2026-09-01T01:27:50+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:27:51 stefans-speaker go-librespot[25001]: time="2026-09-01T01:27:51+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]" Sep 01 01:27:51 stefans-speaker go-librespot[25001]: time="2026-09-01T01:27:51+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]" Sep 01 01:27:51 stefans-speaker go-librespot[25001]: time="2026-09-01T01:27:51+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]" Sep 01 01:27:51 stefans-speaker go-librespot[25001]: time="2026-09-01T01:27:51+02:00" level=info msg="zeroconf server listening on port 42921" Sep 01 01:27:51 stefans-speaker go-librespot[25001]: time="2026-09-01T01:27:51+02:00" level=debug msg="obtained new client token: AAH2zH2tT6cc9e2hWE+VYf5YqFwSWhIDVg7W5j89CTHqAkGQKZjqeiTt7UFex+bBjV/W1sMAZipvl+hMGTo7Co9JjgOub9ovc1psL7PTMkCGeCJ3X9Ih2FwEeIjf978fIANkBFv3wBRMGCA6Wepog7BlOfgQ5eh8D6KCv8SN6Q5YFvoKZ8rRdXTSYy44StrG8PUFpJd9VW3bejS0XPo9QO7Qjv7iiFuLVg9nU3mIS2PE0PiR5Vh8HcOs" Sep 01 01:27:51 stefans-speaker go-librespot[25001]: time="2026-09-01T01:27:51+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:27:51 stefans-speaker go-librespot[25001]: time="2026-09-01T01:27:51+02:00" level=debug msg="completed keyexchange" Sep 01 01:27:51 stefans-speaker go-librespot[25001]: time="2026-09-01T01:27:51+02:00" level=debug msg="completed challenge" Sep 01 01:27:51 stefans-speaker go-librespot[25001]: time="2026-09-01T01:27:51+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:27:51 stefans-speaker go-librespot[25001]: time="2026-09-01T01:27:51+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 01:27:51 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:27:51 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:27:51 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:27:51] [connect] Successful connection Sep 01 01:27:51 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:27:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788218871 101 Sep 01 01:27:51 stefans-speaker volumio[24713]: 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: 2 Sep 01 01:27:52 stefans-speaker sudo[25011]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:52 stefans-speaker sudo[25011]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 01:27:52 stefans-speaker sudo[25011]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:52 stefans-speaker sudo[25013]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:27:53 stefans-speaker sudo[25013]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 01:27:53 stefans-speaker sudo[25013]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:27:53 stefans-speaker systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Sep 01 01:27:53 stefans-speaker systemd[1]: mpd_monitor.service: Deactivated successfully. Sep 01 01:27:53 stefans-speaker systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Sep 01 01:27:53 stefans-speaker systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Sep 01 01:27:53 stefans-speaker sudo[25011]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:53 stefans-speaker sudo[25013]: pam_unix(sudo:session): session closed for user root Sep 01 01:27:53 stefans-speaker mpd_monitor.sh[25016]: MPD Monitor Service: Starting MPD Monitor Service Sep 01 01:27:53 stefans-speaker volumio[24713]: info: Successfully started MPD Monitor Sep 01 01:27:53 stefans-speaker volumio[24713]: info: Successfully started MPD Monitor Sep 01 01:27:53 stefans-speaker volumio[24713]: info: Initializing connection to go-librespot Websocket Sep 01 01:27:53 stefans-speaker volumio[24713]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:27:54 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Sep 01 01:27:54 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:54 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:54 stefans-speaker go-librespot[25020]: go-librespot daemon starting... Sep 01 01:27:54 stefans-speaker go-librespot[25021]: time="2026-09-01T01:27:54+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:27:54 stefans-speaker go-librespot[25021]: time="2026-09-01T01:27:54+02:00" level=debug msg="app state loaded" Sep 01 01:27:54 stefans-speaker go-librespot[25021]: time="2026-09-01T01:27:54+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:27:54 stefans-speaker volumio[24713]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 01 01:27:55 stefans-speaker go-librespot[25021]: time="2026-09-01T01:27:55+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]" Sep 01 01:27:55 stefans-speaker go-librespot[25021]: time="2026-09-01T01:27:55+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]" Sep 01 01:27:55 stefans-speaker go-librespot[25021]: time="2026-09-01T01:27:55+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]" Sep 01 01:27:55 stefans-speaker go-librespot[25021]: time="2026-09-01T01:27:55+02:00" level=info msg="zeroconf server listening on port 43851" Sep 01 01:27:55 stefans-speaker go-librespot[25021]: time="2026-09-01T01:27:55+02:00" level=debug msg="obtained new client token: AAGjGZkGVgyVChyU60HyXCrITN9zPBDdgI70xsq0AnR97OmLwhnM5zor9vPMfVjP9zHdKCBomBMQAQwI+PplqZdfmXVPog9EeoYDZGRNYTAVRVJkok2v6Jsmi0M5T1AECXqKWm67hs6auY3gU2BtTdBBdSQwNZwwZOVwb7txaOXz9FKydLOrvI/03VE98Qut9s7yosB/A2VjbO+VMQ3JGlRHm0gQLeZTE+5vEAg81ZA1ikrgfMQl7Mvj" Sep 01 01:27:55 stefans-speaker go-librespot[25021]: time="2026-09-01T01:27:55+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:27:55 stefans-speaker go-librespot[25021]: time="2026-09-01T01:27:55+02:00" level=debug msg="completed keyexchange" Sep 01 01:27:55 stefans-speaker go-librespot[25021]: time="2026-09-01T01:27:55+02:00" level=debug msg="completed challenge" Sep 01 01:27:55 stefans-speaker go-librespot[25021]: time="2026-09-01T01:27:55+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:27:55 stefans-speaker go-librespot[25021]: time="2026-09-01T01:27:55+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 01:27:55 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:27:55 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:27:56 stefans-speaker volumio[24713]: info: Initializing connection to go-librespot Websocket Sep 01 01:27:56 stefans-speaker volumio[24713]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:27:58 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Sep 01 01:27:58 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:58 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:27:58 stefans-speaker go-librespot[25028]: go-librespot daemon starting... Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 01 01:27:58 stefans-speaker go-librespot[25033]: time="2026-09-01T01:27:58+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:27:58 stefans-speaker go-librespot[25033]: time="2026-09-01T01:27:58+02:00" level=debug msg="app state loaded" Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 01 01:27:58 stefans-speaker go-librespot[25033]: time="2026-09-01T01:27:58+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 01 01:27:58 stefans-speaker volumio[24713]: info: Adding plugin bluetooth to MyMusic Plugins Sep 01 01:27:58 stefans-speaker volumio[24713]: info: Adding plugin multiroom to MyMusic Plugins Sep 01 01:27:58 stefans-speaker volumio[24713]: info: Adding plugin metavolumio to MyMusic Plugins Sep 01 01:27:58 stefans-speaker volumio[24713]: info: Adding plugin cd_controller to MyMusic Plugins Sep 01 01:27:58 stefans-speaker volumio[24713]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 01 01:27:58 stefans-speaker volumio[24713]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 01 01:27:58 stefans-speaker volumio[24713]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 01 01:27:58 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 01 01:27:58 stefans-speaker go-librespot[25033]: time="2026-09-01T01:27:58+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]" Sep 01 01:27:58 stefans-speaker go-librespot[25033]: time="2026-09-01T01:27:58+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]" Sep 01 01:27:58 stefans-speaker go-librespot[25033]: time="2026-09-01T01:27:58+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]" Sep 01 01:27:58 stefans-speaker go-librespot[25033]: time="2026-09-01T01:27:58+02:00" level=info msg="zeroconf server listening on port 37137" Sep 01 01:27:59 stefans-speaker go-librespot[25033]: time="2026-09-01T01:27:59+02:00" level=debug msg="obtained new client token: AAFlwawrdpZL4IEdnsvt6m6uwgZWf0cETFBRYcDvSSAoxcs/MgQVMJjoc6gfiZas0Znmg+FJUG1eWQe8/YfZJM1DZBHm/6YCnT5eHFLVak4n06RrZRCHisuTxo2MmbH5mJOiPUpLrvZKOtc0ezvdKRRo/PXhg2ohokKgQdeKq9giCCQcH3D+Kg3r9GBz9CHLjtnt0uIpiLHmrmFUI0FHCLzizOi881E6v2/NJ42j/KHsdaBrd7mTMvap" Sep 01 01:27:59 stefans-speaker go-librespot[25033]: time="2026-09-01T01:27:59+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" Sep 01 01:27:59 stefans-speaker go-librespot[25033]: time="2026-09-01T01:27:59+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.1.133:443: connect: connection refused" Sep 01 01:27:59 stefans-speaker go-librespot[25033]: time="2026-09-01T01:27:59+02:00" level=debug msg="connected to ap-gew4.spotify.com:80" Sep 01 01:27:59 stefans-speaker go-librespot[25033]: time="2026-09-01T01:27:59+02:00" level=debug msg="completed keyexchange" Sep 01 01:27:59 stefans-speaker go-librespot[25033]: time="2026-09-01T01:27:59+02:00" level=debug msg="completed challenge" Sep 01 01:27:59 stefans-speaker go-librespot[25033]: time="2026-09-01T01:27:59+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:27:59 stefans-speaker go-librespot[25033]: time="2026-09-01T01:27:59+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 01:27:59 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:27:59 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:27:59 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 01 01:27:59 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 01 01:27:59 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:59 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:27:59 stefans-speaker volumio[24713]: info: Starting MyVolumio Remote Streaming Endpoints Sep 01 01:27:59 stefans-speaker volumio[24713]: info: MyVolumio login type: Token Sep 01 01:27:59 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 01 01:27:59 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 01 01:28:00 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 01 01:28:00 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 01 01:28:00 stefans-speaker volumio[24713]: info: Streaming services startup Sep 01 01:28:00 stefans-speaker volumio[24713]: info: Starting Streaming Daemon Sep 01 01:28:00 stefans-speaker sudo[25052]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:28:00 stefans-speaker volumio[24713]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 01 01:28:00 stefans-speaker sudo[25052]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 01:28:00 stefans-speaker sudo[25052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:28:00 stefans-speaker sudo[25052]: pam_unix(sudo:session): session closed for user root Sep 01 01:28:00 stefans-speaker volumio[24713]: info: Initializing connection to go-librespot Websocket Sep 01 01:28:00 stefans-speaker volumio[24713]: error: Cannot start Volumio Streaming Daemon Sep 01 01:28:00 stefans-speaker volumio[24713]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 01:28:00 stefans-speaker volumio[24713]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:28:00 stefans-speaker volumio[24713]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 01:28:00 stefans-speaker volumio[24713]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:28:01 stefans-speaker volumio[24713]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 01 01:28:01 stefans-speaker volumio[24713]: info: MyVolumio token set successfully Sep 01 01:28:01 stefans-speaker volumio[24713]: info: MYVOLUMIO: Adding device Sep 01 01:28:01 stefans-speaker volumio[24713]: info: MYVOLUMIO: Evaluating Server Sep 01 01:28:02 stefans-speaker volumio[24713]: info: MyVolumio status changed Sep 01 01:28:02 stefans-speaker volumio[24713]: info: Streaming services startup Sep 01 01:28:02 stefans-speaker volumio[24713]: info: Starting Streaming Daemon Sep 01 01:28:02 stefans-speaker volumio[24713]: info: Removing browser output: myVolumio user plan is not superstar Sep 01 01:28:02 stefans-speaker volumio[24713]: info: Removing audio output: Sep 01 01:28:02 stefans-speaker volumio[24713]: info: Stoppping Tunnel 1 Sep 01 01:28:02 stefans-speaker sudo[25080]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:28:02 stefans-speaker sudo[25080]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 01:28:02 stefans-speaker sudo[25080]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:28:02 stefans-speaker sudo[25082]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:28:02 stefans-speaker sudo[25082]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 01 01:28:02 stefans-speaker sudo[25082]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:28:02 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 01:28:02 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 01:28:02 stefans-speaker sudo[25080]: pam_unix(sudo:session): session closed for user root Sep 01 01:28:02 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 01:28:02 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 01:28:02 stefans-speaker volumio[24713]: error: Cannot start Volumio Streaming Daemon Sep 01 01:28:02 stefans-speaker volumio[24713]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 01:28:02 stefans-speaker volumio[24713]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:28:02 stefans-speaker volumio[24713]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 01:28:02 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 01:28:02 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 01:28:02 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 01:28:02 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 01:28:02 stefans-speaker sudo[25082]: pam_unix(sudo:session): session closed for user root Sep 01 01:28:02 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Sep 01 01:28:02 stefans-speaker volumio[24713]: info: Remote SSH Stopped Sep 01 01:28:02 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:28:02 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:28:02 stefans-speaker go-librespot[25085]: go-librespot daemon starting... Sep 01 01:28:02 stefans-speaker go-librespot[25086]: time="2026-09-01T01:28:02+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:28:02 stefans-speaker go-librespot[25086]: time="2026-09-01T01:28:02+02:00" level=debug msg="app state loaded" Sep 01 01:28:02 stefans-speaker go-librespot[25086]: time="2026-09-01T01:28:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:28:02 stefans-speaker go-librespot[25086]: time="2026-09-01T01:28:02+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]" Sep 01 01:28:02 stefans-speaker go-librespot[25086]: time="2026-09-01T01:28:02+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]" Sep 01 01:28:02 stefans-speaker go-librespot[25086]: time="2026-09-01T01:28:02+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]" Sep 01 01:28:02 stefans-speaker go-librespot[25086]: time="2026-09-01T01:28:02+02:00" level=info msg="zeroconf server listening on port 38515" Sep 01 01:28:02 stefans-speaker go-librespot[25086]: time="2026-09-01T01:28:02+02:00" level=debug msg="obtained new client token: AAFs9zEKLdpUHnUYgxTDvT6XEeLraKlihckJj+BTJ4FZkvWTw2x9mpkDzUlRH4R0IRFarrah5o4Lk8xx7Cn1FPAii46erEKuGkC33POVp9kYU5jz9s8sD+edoDOfA6DYNS7ifNhR9nkvGC1lgcpjt/+xBX6pEx8jkuFImtxrvrFjVg6b9mFGToxQhRJA3j6S2JhVPxPvLJ+U0ue/hHZ4iW9ZERSrqEZk+lf3p08vntZZek064qhjM4iL72M=" Sep 01 01:28:02 stefans-speaker go-librespot[25086]: time="2026-09-01T01:28:02+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:28:02 stefans-speaker go-librespot[25086]: time="2026-09-01T01:28:02+02:00" level=debug msg="completed keyexchange" Sep 01 01:28:02 stefans-speaker go-librespot[25086]: time="2026-09-01T01:28:02+02:00" level=debug msg="completed challenge" Sep 01 01:28:03 stefans-speaker go-librespot[25086]: time="2026-09-01T01:28:03+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:28:03 stefans-speaker go-librespot[25086]: time="2026-09-01T01:28:03+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 01:28:03 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:28:03 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:28:03 stefans-speaker volumio[24713]: info: Setting Geolocation for MyVolumio to eu9 Sep 01 01:28:03 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:28:03 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:28:03 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:28:03 stefans-speaker volumio[24713]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 01 01:28:03 stefans-speaker volumio[24713]: info: Initializing connection to go-librespot Websocket Sep 01 01:28:03 stefans-speaker volumio[24713]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:28:04 stefans-speaker volumio[24713]: info: Updating MyVolumio device info Sep 01 01:28:04 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:28:04 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:28:04 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:28:04 stefans-speaker volumio[24713]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 01 01:28:06 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Sep 01 01:28:06 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:28:06 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:28:06 stefans-speaker go-librespot[25094]: go-librespot daemon starting... Sep 01 01:28:06 stefans-speaker go-librespot[25095]: time="2026-09-01T01:28:06+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:28:06 stefans-speaker go-librespot[25095]: time="2026-09-01T01:28:06+02:00" level=debug msg="app state loaded" Sep 01 01:28:06 stefans-speaker go-librespot[25095]: time="2026-09-01T01:28:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:28:06 stefans-speaker go-librespot[25095]: time="2026-09-01T01:28:06+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]" Sep 01 01:28:06 stefans-speaker go-librespot[25095]: time="2026-09-01T01:28:06+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]" Sep 01 01:28:06 stefans-speaker go-librespot[25095]: time="2026-09-01T01:28:06+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]" Sep 01 01:28:06 stefans-speaker go-librespot[25095]: time="2026-09-01T01:28:06+02:00" level=info msg="zeroconf server listening on port 44485" Sep 01 01:28:06 stefans-speaker go-librespot[25095]: time="2026-09-01T01:28:06+02:00" level=debug msg="obtained new client token: AAFruFlI3jfDXWG8nLgFQlJaB6ahMZm95fi50lvlZedNfcqg+rlGnlDoS6/sC0rLw8a5i5nfl04VHEiDtu2B97ENmoh940+t7PNeScnpOko60Ux+rq1YEKori9KEKYMNTyU1IqCtqlq7WisMcgXOsjihDmMrKFpSMR6K/j8DKVAT6kI5oIWP0i7n4D2+8b/V4lAwZalvsu+nbkfAffZZ27vafQNndtp106A3qT4tOpLguIhuCO8jR/rbbaY=" Sep 01 01:28:06 stefans-speaker go-librespot[25095]: time="2026-09-01T01:28:06+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:28:06 stefans-speaker go-librespot[25095]: time="2026-09-01T01:28:06+02:00" level=debug msg="completed keyexchange" Sep 01 01:28:06 stefans-speaker go-librespot[25095]: time="2026-09-01T01:28:06+02:00" level=debug msg="completed challenge" Sep 01 01:28:06 stefans-speaker volumio[24713]: info: Initializing connection to go-librespot Websocket Sep 01 01:28:06 stefans-speaker go-librespot[25095]: time="2026-09-01T01:28:06+02:00" level=debug msg="new websocket client" Sep 01 01:28:06 stefans-speaker volumio[24713]: info: Connection to go-librespot Websocket established Sep 01 01:28:06 stefans-speaker go-librespot[25095]: time="2026-09-01T01:28:06+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:28:07 stefans-speaker go-librespot[25095]: time="2026-09-01T01:28:07+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 01:28:07 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:28:07 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:28:07 stefans-speaker volumio[24713]: info: Connection to go-librespot Websocket closed Sep 01 01:28:07 stefans-speaker volumio[24713]: info: MYVOLUMIO: Adding device Sep 01 01:28:07 stefans-speaker volumio[24713]: info: MYVOLUMIO: Evaluating Server Sep 01 01:28:08 stefans-speaker volumio[24713]: info: Setting Geolocation for MyVolumio to eu2 Sep 01 01:28:08 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:28:08 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:28:08 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:28:08 stefans-speaker volumio[24713]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 01 01:28:09 stefans-speaker volumio[24713]: info: Updating MyVolumio device info Sep 01 01:28:09 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:28:09 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:28:09 stefans-speaker volumio[24713]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:28:09 stefans-speaker volumio[24713]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 01 01:28:09 stefans-speaker volumio[24713]: info: Getting Spotify volume Sep 01 01:28:09 stefans-speaker volumio[24713]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 01:28:09 stefans-speaker volumio[24713]: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:28:09 stefans-speaker volumio[24713]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Sep 01 01:28:09 stefans-speaker volumio[24713]: errno: -111, Sep 01 01:28:09 stefans-speaker volumio[24713]: code: 'ECONNREFUSED', Sep 01 01:28:09 stefans-speaker volumio[24713]: syscall: 'connect', Sep 01 01:28:09 stefans-speaker volumio[24713]: address: '127.0.0.1', Sep 01 01:28:09 stefans-speaker volumio[24713]: port: 9879, Sep 01 01:28:09 stefans-speaker volumio[24713]: response: undefined Sep 01 01:28:09 stefans-speaker volumio[24713]: } Sep 01 01:28:09 stefans-speaker volumio[24713]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 01:28:10 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Sep 01 01:28:10 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:28:10 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:28:10 stefans-speaker go-librespot[25146]: go-librespot daemon starting... Sep 01 01:28:10 stefans-speaker go-librespot[25147]: time="2026-09-01T01:28:10+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:28:10 stefans-speaker go-librespot[25147]: time="2026-09-01T01:28:10+02:00" level=debug msg="app state loaded" Sep 01 01:28:10 stefans-speaker go-librespot[25147]: time="2026-09-01T01:28:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:28:10 stefans-speaker go-librespot[25147]: time="2026-09-01T01:28: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-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 01:28:10 stefans-speaker go-librespot[25147]: time="2026-09-01T01:28:10+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]" Sep 01 01:28:10 stefans-speaker go-librespot[25147]: time="2026-09-01T01:28:10+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]" Sep 01 01:28:10 stefans-speaker go-librespot[25147]: time="2026-09-01T01:28:10+02:00" level=info msg="zeroconf server listening on port 33373" Sep 01 01:28:10 stefans-speaker sudo[25156]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:28:10 stefans-speaker sudo[25156]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-09-01 01:27' Sep 01 01:28:10 stefans-speaker sudo[25156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:28:10 stefans-speaker go-librespot[25147]: time="2026-09-01T01:28:10+02:00" level=debug msg="obtained new client token: AAHXcxqOOmQBxPYpuKNtb8YfXu/8fFHEJMPqJGa1JzrzW/F/fmY+Ncwu7rwGk3C1G04kVQWShZ2kWT11kxPIfJr9vbohU8Z44AmQmZfvmC7kCzwClwbUz26eYCueBoRiBmSA+375i7pnDRGdiEJmJ4cG5yZqvCV6e6vxMe1Mj8ZP+FN+CmgIhRwLnUaZd2xZMGh/glhz0tOQAVfhXWLXhb7Q9sjaenTJh/SzdmEMqDyCshkB17xL+aQXRpE=" Sep 01 01:28:10 stefans-speaker go-librespot[25147]: time="2026-09-01T01:28:10+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:28:10 stefans-speaker go-librespot[25147]: time="2026-09-01T01:28:10+02:00" level=debug msg="completed keyexchange" Sep 01 01:28:10 stefans-speaker go-librespot[25147]: time="2026-09-01T01:28:10+02:00" level=debug msg="completed challenge" Sep 01 01:28:10 stefans-speaker go-librespot[25147]: time="2026-09-01T01:28:10+02:00" level=info msg="authenticated AP" username="31************************cy" PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"