Sep 01 01:11:00 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Sep 01 01:11:00 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:00 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:00 stefans-speaker go-librespot[12548]: go-librespot daemon starting... Sep 01 01:11:00 stefans-speaker volumio[12527]: info: MYVOLUMIO Environment detected Sep 01 01:11:00 stefans-speaker go-librespot[12552]: time="2026-09-01T01:11:00+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:11:00 stefans-speaker go-librespot[12552]: time="2026-09-01T01:11:00+02:00" level=debug msg="app state loaded" Sep 01 01:11:00 stefans-speaker go-librespot[12552]: time="2026-09-01T01:11:00+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:11:00 stefans-speaker volumio[12527]: info: Plugin folders cleanup Sep 01 01:11:00 stefans-speaker volumio[12527]: info: Scanning into folder /volumio/app/plugins/ Sep 01 01:11:00 stefans-speaker volumio[12527]: info: Scanning category audio_interface Sep 01 01:11:00 stefans-speaker volumio[12527]: info: Scanning category miscellanea Sep 01 01:11:00 stefans-speaker volumio[12527]: info: Scanning category music_service Sep 01 01:11:00 stefans-speaker volumio[12527]: info: Scanning category plugins.json Sep 01 01:11:00 stefans-speaker volumio[12527]: info: Scanning category system_controller Sep 01 01:11:00 stefans-speaker volumio[12527]: info: Scanning category user_interface Sep 01 01:11:00 stefans-speaker volumio[12527]: info: Scanning into folder /data/plugins/ Sep 01 01:11:00 stefans-speaker volumio[12527]: info: Scanning category music_service Sep 01 01:11:00 stefans-speaker volumio[12527]: info: Plugin folders cleanup completed Sep 01 01:11:00 stefans-speaker volumio[12527]: info: ------------------------------------------- Sep 01 01:11:00 stefans-speaker volumio[12527]: info: ----- Core plugins startup ---- Sep 01 01:11:00 stefans-speaker volumio[12527]: info: ------------------------------------------- Sep 01 01:11:00 stefans-speaker volumio[12527]: info: Loading plugins from folder /volumio/app/plugins/ Sep 01 01:11:00 stefans-speaker volumio[12527]: info: Adding plugin upnp to MyMusic Plugins Sep 01 01:11:00 stefans-speaker volumio[12527]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 01 01:11:00 stefans-speaker volumio[12527]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 01 01:11:00 stefans-speaker volumio[12527]: info: Loading plugins from folder /data/plugins/ Sep 01 01:11:00 stefans-speaker volumio[12527]: info: Loading plugin "system"... Sep 01 01:11:00 stefans-speaker volumio[12527]: info: Loading plugin "appearance"... Sep 01 01:11:00 stefans-speaker go-librespot[12552]: time="2026-09-01T01:11:00+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 01:11:00 stefans-speaker go-librespot[12552]: time="2026-09-01T01:11:00+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:11:00 stefans-speaker go-librespot[12552]: time="2026-09-01T01:11:00+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:11:00 stefans-speaker go-librespot[12552]: time="2026-09-01T01:11:00+02:00" level=info msg="zeroconf server listening on port 36145" Sep 01 01:11:00 stefans-speaker go-librespot[12552]: time="2026-09-01T01:11:00+02:00" level=debug msg="obtained new client token: AAFDmO5biraWY6QHCSEPwwXBqKKLBfKokNH4JKZvqA0q+bTABmgQvTVj0Jcf+zWLDbm3FuahnTBW41hhFn3GFS3zPbUsiwoZ8yNB2kmz8SvR7Q1mj8pwb++FxI3k084uvjstXLbGMTxRz97YPnvqtXbBa8GL9xyAemL3zpQP7TNQCh0VIeHcIyNvdWamxQ0acIIw6Nqu6np+3kLM5TW85x3C6s6j0DB+gtKdcq6yvMiNu9iKu5KicAGsNdI=" Sep 01 01:11:00 stefans-speaker go-librespot[12552]: time="2026-09-01T01:11:00+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:11:00 stefans-speaker go-librespot[12552]: time="2026-09-01T01:11:00+02:00" level=debug msg="completed keyexchange" Sep 01 01:11:00 stefans-speaker go-librespot[12552]: time="2026-09-01T01:11:00+02:00" level=debug msg="completed challenge" Sep 01 01:11:00 stefans-speaker go-librespot[12552]: time="2026-09-01T01:11:00+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:11:00 stefans-speaker go-librespot[12552]: time="2026-09-01T01:11:00+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:11:00 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:11:00 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:11:01 stefans-speaker volumio[12527]: info: Loading plugin "network"... Sep 01 01:11:01 stefans-speaker volumio[12527]: info: Refreshing Cached IP Addresses Sep 01 01:11:01 stefans-speaker sudo[12563]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:01 stefans-speaker sudo[12565]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:01 stefans-speaker volumio[12527]: info: Loading plugin "services"... Sep 01 01:11:01 stefans-speaker sudo[12565]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 01:11:01 stefans-speaker sudo[12565]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:01 stefans-speaker volumio[12527]: info: Loading plugin "alsa_controller"... Sep 01 01:11:01 stefans-speaker sudo[12563]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 01:11:01 stefans-speaker sudo[12563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:01 stefans-speaker sudo[12565]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:01 stefans-speaker sudo[12563]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:01 stefans-speaker sudo[12572]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:01 stefans-speaker sudo[12572]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 01 01:11:01 stefans-speaker sudo[12572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:01 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:11:01 stefans-speaker volumio[12527]: info: Loading plugin "wizard"... Sep 01 01:11:01 stefans-speaker volumio[12527]: info: Loading plugin "networkfs"... Sep 01 01:11:01 stefans-speaker volumio[12527]: info: Starting Udev Watcher for removable devices Sep 01 01:11:01 stefans-speaker volumio[12527]: info: Ignoring mount for partition: boot Sep 01 01:11:01 stefans-speaker volumio[12527]: info: Ignoring mount for partition: volumio Sep 01 01:11:01 stefans-speaker volumio[12527]: info: Ignoring mount for partition: volumio_data Sep 01 01:11:01 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 01:11:01 stefans-speaker volumio[12527]: info: Loading plugin "volumio_command_line_client"... Sep 01 01:11:01 stefans-speaker volumio[12527]: info: Loading plugin "upnp"... Sep 01 01:11:01 stefans-speaker volumio[12527]: info: [1788217861648] Starting Upmpd Daemon Sep 01 01:11:01 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 01:11:01 stefans-speaker volumio[12527]: info: Loading plugin "my_music"... Sep 01 01:11:01 stefans-speaker volumio[12527]: info: Loading plugin "mpd"... Sep 01 01:11:01 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:11:01] [connect] Successful connection Sep 01 01:11:02 stefans-speaker volumio[12527]: info: Loading plugin "upnp_browser"... Sep 01 01:11:03 stefans-speaker volumio[12527]: info: Starting UPNP Browser Sep 01 01:11:03 stefans-speaker volumio[12527]: info: Loading plugin "alarm-clock"... Sep 01 01:11:03 stefans-speaker volumio[12527]: info: Loading plugin "airplay_emulation"... Sep 01 01:11:03 stefans-speaker volumio[12527]: info: Starting Shairport Sync Sep 01 01:11:03 stefans-speaker volumio[12527]: info: Loading plugin "last_100"... Sep 01 01:11:03 stefans-speaker volumio[12527]: info: Loading plugin "webradio"... Sep 01 01:11:03 stefans-speaker volumio[12527]: info: Loading plugin "i2s_dacs"... Sep 01 01:11:03 stefans-speaker volumio[12527]: info: I2S DAC not set, start Auto-detection Sep 01 01:11:03 stefans-speaker volumio[12527]: info: Loading plugin "volumiodiscovery"... Sep 01 01:11:03 stefans-speaker volumio[12527]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 01:11:03 stefans-speaker volumio[12527]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:11:03 stefans-speaker volumio[12527]: *** WARNING *** For more information see Sep 01 01:11:03 stefans-speaker volumio[12527]: *** 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:11:03 stefans-speaker volumio[12527]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:11:03 stefans-speaker volumio[12527]: *** WARNING *** For more information see Sep 01 01:11:03 stefans-speaker node[12527]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 01:11:03 stefans-speaker node[12527]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:11:03 stefans-speaker node[12527]: *** WARNING *** For more information see Sep 01 01:11:03 stefans-speaker node[12527]: *** 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:11:03 stefans-speaker node[12527]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:11:03 stefans-speaker node[12527]: *** WARNING *** For more information see Sep 01 01:11:03 stefans-speaker volumio[12527]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 01 01:11:03 stefans-speaker volumio[12527]: info: Discovery: Started advertising with name: Stefan’s Speaker Sep 01 01:11:03 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 01:11:03 stefans-speaker volumio[12527]: info: Loading plugin "spop"... Sep 01 01:11:04 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Sep 01 01:11:04 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:04 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:04 stefans-speaker go-librespot[12598]: go-librespot daemon starting... Sep 01 01:11:04 stefans-speaker go-librespot[12599]: time="2026-09-01T01:11:04+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:11:04 stefans-speaker go-librespot[12599]: time="2026-09-01T01:11:04+02:00" level=debug msg="app state loaded" Sep 01 01:11:04 stefans-speaker go-librespot[12599]: time="2026-09-01T01:11:04+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:11:04 stefans-speaker go-librespot[12599]: time="2026-09-01T01:11:04+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:11:04 stefans-speaker go-librespot[12599]: time="2026-09-01T01:11:04+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:11:04 stefans-speaker go-librespot[12599]: time="2026-09-01T01:11:04+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:11:04 stefans-speaker go-librespot[12599]: time="2026-09-01T01:11:04+02:00" level=info msg="zeroconf server listening on port 37735" Sep 01 01:11:04 stefans-speaker sudo[12572]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:04 stefans-speaker go-librespot[12599]: time="2026-09-01T01:11:04+02:00" level=debug msg="obtained new client token: AAEh6A1tvzseA/X99s2pTBpL6VJJx7vL4fV7EpjhVqv6txyl0VuKa5Y17/wq8sWKUHBJBYuZvGy9IFh7nyauqtb3aGpTCBfoyaY1WS3cvTEvq/77uh5b3ojLJ86ypgl1KA1hDpsmjm3VHIzvWVRpBJjAk5xgXfnVa8R7LGgDAbJG6/C8tfoUGCcxoJoxsnRGuL/W6QXuJbHAcmioN52vy4NrXQsimfM8DAj4zUnbvvXJle5XhWkAVgnj7bc=" Sep 01 01:11:04 stefans-speaker go-librespot[12599]: time="2026-09-01T01:11:04+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:11:04 stefans-speaker go-librespot[12599]: time="2026-09-01T01:11:04+02:00" level=debug msg="completed keyexchange" Sep 01 01:11:04 stefans-speaker go-librespot[12599]: time="2026-09-01T01:11:04+02:00" level=debug msg="completed challenge" Sep 01 01:11:04 stefans-speaker go-librespot[12599]: time="2026-09-01T01:11:04+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:11:05 stefans-speaker volumio[12527]: info: Loading plugin "ytcr"... Sep 01 01:11:05 stefans-speaker go-librespot[12599]: time="2026-09-01T01:11:05+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:11:05 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:11:05 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:11:06 stefans-speaker volumio[12527]: info: Loading plugin "outputs"... Sep 01 01:11:06 stefans-speaker volumio[12527]: info: Loading plugin "albumart"... Sep 01 01:11:06 stefans-speaker volumio[12527]: info: Plugin example_plugin is not enabled Sep 01 01:11:06 stefans-speaker volumio[12527]: info: Loading plugin "inputs"... Sep 01 01:11:06 stefans-speaker volumio[12527]: info: Loading plugin "updater_comm"... Sep 01 01:11:06 stefans-speaker volumio[12527]: info: Plugin mpdemulation is not enabled Sep 01 01:11:06 stefans-speaker volumio[12527]: info: Loading plugin "rest_api"... Sep 01 01:11:06 stefans-speaker volumio[12527]: info: Loading plugin "websocket"... Sep 01 01:11:06 stefans-speaker volumio[12527]: info: Starting Socket.io Server version 1.7.4 Sep 01 01:11:06 stefans-speaker volumio[12527]: info: Loading i18n strings for locale nl Sep 01 01:11:06 stefans-speaker volumio[12527]: Updating browse sources language Sep 01 01:11:06 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:11:06 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:11:06 stefans-speaker volumio[12527]: info: CoreCommandRouter::initPlayerControls Sep 01 01:11:06 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:06 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:06 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:06 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:06 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:06 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:06 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:06 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:06 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:11:06 stefans-speaker volumio[12527]: Express server listening on port 3000 Sep 01 01:11:06 stefans-speaker volumio[12527]: [Metrics] WebUI: 8s 192.03ms Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreStateMachine::resetVolumioState Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreStateMachine::getcurrentVolume Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:11:07 stefans-speaker volumio[12527]: info: Cannot read play queue from file Sep 01 01:11:07 stefans-speaker volumio[12527]: info: Volumio Network Manager: Network status updated: 2 Sep 01 01:11:07 stefans-speaker volumio[12606]: Forking 3 albumart workers Sep 01 01:11:07 stefans-speaker volumio[12527]: info: VolumeController:: Volume=70 Mute =false Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreStateMachine::pushState Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CorePlayQueue::getTrack 0 Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreCommandRouter::volumioPushState Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreStateMachine::updateTrackBlock Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CorePlayQueue::getTrackBlock Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreStateMachine::setRepeat null single undefined Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreStateMachine::pushState Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CorePlayQueue::getTrack 0 Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreCommandRouter::volumioPushState Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreStateMachine::setRandom null Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreStateMachine::pushState Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CorePlayQueue::getTrack 0 Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreCommandRouter::volumioPushState Sep 01 01:11:07 stefans-speaker volumio[12527]: info: Setting Device type: Raspberry PI Sep 01 01:11:07 stefans-speaker volumio[12527]: info: VolumeController:: Volume=70 Mute =false Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreStateMachine::pushState Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CorePlayQueue::getTrack 0 Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreCommandRouter::volumioPushState Sep 01 01:11:07 stefans-speaker volumio[12527]: info: Completed loading Core Plugins Sep 01 01:11:07 stefans-speaker volumio[12527]: info: Preparing to generate the ALSA configuration file Sep 01 01:11:07 stefans-speaker volumio[12527]: info: Discovery: adding a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 01:11:07 stefans-speaker volumio[12527]: info: Discovery: Found device Stefan’s Speaker Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreCommandRouter::volumioGetState Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CorePlayQueue::getTrack 0 Sep 01 01:11:07 stefans-speaker volumio[12527]: info: Discovery: this is already registered, a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 01:11:07 stefans-speaker volumio[12527]: info: Discovery: Found device Stefan’s Speaker Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreCommandRouter::volumioGetState Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CorePlayQueue::getTrack 0 Sep 01 01:11:07 stefans-speaker volumio[12527]: info: Asound.conf file unchanged, so no further update is needed Sep 01 01:11:07 stefans-speaker volumio[12527]: info: Output device has changed, restarting MPD Sep 01 01:11:07 stefans-speaker volumio[12527]: info: Output device has changed, restarting Shairport Sync Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:11:07 stefans-speaker sudo[12660]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:07 stefans-speaker volumio[12527]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 01:11:07 stefans-speaker volumio[12527]: info: ___________ START PLUGINS ___________ Sep 01 01:11:07 stefans-speaker sudo[12662]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:07 stefans-speaker sudo[12662]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 01:11:07 stefans-speaker sudo[12660]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 01:11:07 stefans-speaker sudo[12660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:07 stefans-speaker sudo[12662]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:07 stefans-speaker volumio[12527]: info: ControllerMpd::onStart: Initializing MPD Sep 01 01:11:07 stefans-speaker sudo[12660]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:07 stefans-speaker volumio[12527]: info: Creating MPD Configuration file Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:11:07 stefans-speaker volumio[12527]: info: [1788217867864] CoreMusicLibrary::Adding element Media Servers Sep 01 01:11:07 stefans-speaker systemd[1]: Stopping mpd.service - Music Player Daemon... Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:11:07 stefans-speaker sudo[12672]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:07 stefans-speaker sudo[12671]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:07 stefans-speaker volumio[12527]: info: UPNP Browser: Client initialized successfully Sep 01 01:11:07 stefans-speaker sudo[12671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 01:11:07 stefans-speaker sudo[12671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:07 stefans-speaker sudo[12672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 01:11:07 stefans-speaker sudo[12672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:07 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Sep 01 01:11:07 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 01 01:11:07 stefans-speaker systemd[1]: mpd.service: Consumed 4.421s CPU time. Sep 01 01:11:07 stefans-speaker sudo[12671]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:07 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Sep 01 01:11:07 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 01 01:11:07 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:07 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:11:08 stefans-speaker volumio[12527]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 01:11:08 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:08 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:11:08 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 01 01:11:08 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Sep 01 01:11:08 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Sep 01 01:11:08 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 01 01:11:08 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Sep 01 01:11:08 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 01 01:11:08 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 01 01:11:08 stefans-speaker volumio[12527]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 01:11:08 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 01 01:11:08 stefans-speaker volumio[12527]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:11:08 stefans-speaker volumio[12527]: info: [1788217868142] CoreMusicLibrary::Adding element Last_100 Sep 01 01:11:08 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:11:08 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Sep 01 01:11:08 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Sep 01 01:11:08 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:08 stefans-speaker volumio[12527]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:11:08 stefans-speaker volumio[12527]: info: [1788217868169] CoreMusicLibrary::Adding element Webradio Sep 01 01:11:08 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:11:08 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:08 stefans-speaker go-librespot[12687]: go-librespot daemon starting... Sep 01 01:11:08 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:11:08 stefans-speaker volumio[12527]: info: Initializing BBC Radios Sep 01 01:11:08 stefans-speaker go-librespot[12688]: time="2026-09-01T01:11:08+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:11:08 stefans-speaker go-librespot[12688]: time="2026-09-01T01:11:08+02:00" level=debug msg="app state loaded" Sep 01 01:11:08 stefans-speaker go-librespot[12688]: time="2026-09-01T01:11:08+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:11:08 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:11:08 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:11:08 stefans-speaker volumio[12527]: info: Creating Spotify config file Sep 01 01:11:08 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:08 stefans-speaker sudo[12686]: root : unable to resolve host stefans-speaker: System error Sep 01 01:11:08 stefans-speaker sudo[12686]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:11:08 stefans-speaker sudo[12686]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 01:11:08 stefans-speaker sudo[12686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 01 01:11:08 stefans-speaker sudo[12686]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:08 stefans-speaker go-librespot[12688]: time="2026-09-01T01:11:08+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:11:08 stefans-speaker go-librespot[12688]: time="2026-09-01T01:11:08+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:11:08 stefans-speaker go-librespot[12688]: time="2026-09-01T01:11:08+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:11:08 stefans-speaker go-librespot[12688]: time="2026-09-01T01:11:08+02:00" level=info msg="zeroconf server listening on port 46015" Sep 01 01:11:08 stefans-speaker go-librespot[12688]: time="2026-09-01T01:11:08+02:00" level=debug msg="obtained new client token: AAFnustlbVnNdQ5g83sMlplpNTRoPW+kdI82AEGCAJhbjQ1KFfuQa/gpvpfvXOyAXH/EPQ3uGexTjpsceLUSMr7nkTcV8GMwlQw+R0rZvrAAqJZkxK/GUYHiD54gJtb8++uOQsiAcHTlPuIaxXaEorGY1pmgSePRuSu8ZypC9xEkNk6x1gVoDMIAuIyGTsqrVkj33WN3U+qg7FZg2rpAWnrJpBdjPmB+0ccANg5DBDDGhPmT349NwObDEVM=" Sep 01 01:11:08 stefans-speaker go-librespot[12688]: time="2026-09-01T01:11:08+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:11:08 stefans-speaker go-librespot[12688]: time="2026-09-01T01:11:08+02:00" level=debug msg="completed keyexchange" Sep 01 01:11:08 stefans-speaker go-librespot[12688]: time="2026-09-01T01:11:08+02:00" level=debug msg="completed challenge" Sep 01 01:11:09 stefans-speaker go-librespot[12688]: time="2026-09-01T01:11:09+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:11:09 stefans-speaker volumio[12622]: Starting albumart workers Sep 01 01:11:09 stefans-speaker go-librespot[12688]: time="2026-09-01T01:11:09+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:11:09 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:11:09 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:11:09 stefans-speaker volumio[12527]: info: Volumio Calling Home Sep 01 01:11:09 stefans-speaker volumio[12621]: Starting albumart workers Sep 01 01:11:09 stefans-speaker volumio[12623]: Starting albumart workers Sep 01 01:11:09 stefans-speaker volumio[12527]: info: MPD Permissions set Sep 01 01:11:09 stefans-speaker volumio[12527]: info: MPD Permissions set Sep 01 01:11:09 stefans-speaker volumio[12527]: info: Volumio called home Sep 01 01:11:09 stefans-speaker volumio[12527]: info: Spotify config file written Sep 01 01:11:09 stefans-speaker sudo[12714]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:09 stefans-speaker sudo[12714]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 01 01:11:09 stefans-speaker sudo[12714]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:09 stefans-speaker volumio[12527]: 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:11:09 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:09 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:09 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:09 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:09 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:09 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:09 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:09 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:09 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:09 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:11:09 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:09 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:09 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:09 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:09 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:10 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:10 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:10 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:10 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:10 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:10 stefans-speaker go-librespot[12718]: go-librespot daemon starting... Sep 01 01:11:10 stefans-speaker sudo[12714]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:10 stefans-speaker go-librespot[12723]: time="2026-09-01T01:11:10+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:11:10 stefans-speaker go-librespot[12723]: time="2026-09-01T01:11:10+02:00" level=debug msg="app state loaded" Sep 01 01:11:10 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:11:10 stefans-speaker go-librespot[12723]: time="2026-09-01T01:11:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:11:10 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:10 stefans-speaker volumio[12527]: info: No need to fix Spotify hosts Sep 01 01:11:10 stefans-speaker go-librespot[12723]: time="2026-09-01T01:11:10+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 01:11:10 stefans-speaker go-librespot[12723]: time="2026-09-01T01:11:10+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 01:11:10 stefans-speaker go-librespot[12723]: time="2026-09-01T01:11:10+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 01:11:10 stefans-speaker go-librespot[12723]: time="2026-09-01T01:11:10+02:00" level=info msg="zeroconf server listening on port 36343" Sep 01 01:11:10 stefans-speaker go-librespot[12723]: time="2026-09-01T01:11:10+02:00" level=debug msg="obtained new client token: AAFt9qQX9nfNeMIT/a+RtTpy4wIwASLzIZoY6vawKS5SL54d+pR05dM0MhlFZUmtDhYxRaR+Lf2t9yRusdkjARM2g0jDeezc5rA5lf1woTS470X0xQm49aXvQ7Ttjv86tMzVOmyI8A9hatSk7l8F8hI1OLWpGUC6lenaLZeowqLizettCevzqx8q0YyNpJ28SneevYw0r8LRSqmE3JtLOQtr45vrPBNjhe6l7aG0MGDMhx2Dd45I34trK1s=" Sep 01 01:11:10 stefans-speaker go-librespot[12723]: time="2026-09-01T01:11:10+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:11:10 stefans-speaker go-librespot[12723]: time="2026-09-01T01:11:10+02:00" level=debug msg="completed keyexchange" Sep 01 01:11:10 stefans-speaker go-librespot[12723]: time="2026-09-01T01:11:10+02:00" level=debug msg="completed challenge" Sep 01 01:11:10 stefans-speaker go-librespot[12723]: time="2026-09-01T01:11:10+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:11:10 stefans-speaker volumio[12527]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 01:11:10 stefans-speaker volumio[12527]: SPOTIFY: BQAyoPXpPcnea_C5qWl76UCBm8GsIuyfO_ULu6UMFII9zTleILeNZFKh3blXDUxFu61_rIag_rqPYIq8GBD2evnmqXD_yWEqkihmVcN6nnJ0vUkh6D2SEXdwlSy0QRus9scGoDvML4ZisggheGSspCNk_z1PU5jszRwdPmyKodayi9svwxuePxSEVqYdT8sUniUZgi1P-EtDKaljrS4j6L11AlDQbkiDKopryyw8dD-RK12w7T_1YH3yiR5cvc8mot5pXyLy6RxxyyMm8AIwJqh9dftaD78ZhFaA02CqZGymjWj55zzEqtjJL9-uxLQ Sep 01 01:11:10 stefans-speaker volumio[12527]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 01:11:10 stefans-speaker volumio[12527]: info: New Spotify access token = BQAyoPXpPcnea_C5qWl76UCBm8GsIuyfO_ULu6UMFII9zTleILeNZFKh3blXDUxFu61_rIag_rqPYIq8GBD2evnmqXD_yWEqkihmVcN6nnJ0vUkh6D2SEXdwlSy0QRus9scGoDvML4ZisggheGSspCNk_z1PU5jszRwdPmyKodayi9svwxuePxSEVqYdT8sUniUZgi1P-EtDKaljrS4j6L11AlDQbkiDKopryyw8dD-RK12w7T_1YH3yiR5cvc8mot5pXyLy6RxxyyMm8AIwJqh9dftaD78ZhFaA02CqZGymjWj55zzEqtjJL9-uxLQ Sep 01 01:11:10 stefans-speaker volumio[12527]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 01 01:11:10 stefans-speaker volumio[12527]: info: Starting Shairport Sync Sep 01 01:11:10 stefans-speaker volumio[12527]: info: Starting Shairport Sync Sep 01 01:11:10 stefans-speaker volumio[12527]: info: Starting Shairport Sync Sep 01 01:11:11 stefans-speaker go-librespot[12723]: time="2026-09-01T01:11:10+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:11:11 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:11:11 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:11:11 stefans-speaker sudo[12757]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:11 stefans-speaker sudo[12757]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 01:11:11 stefans-speaker sudo[12757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:11 stefans-speaker sudo[12759]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:11 stefans-speaker sudo[12759]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 01:11:11 stefans-speaker sudo[12759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:11 stefans-speaker sudo[12761]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:11 stefans-speaker sudo[12761]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 01:11:11 stefans-speaker sudo[12761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:11 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 01 01:11:11 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Sep 01 01:11:11 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:11:11 stefans-speaker systemd[1]: shairport-sync.service: Consumed 1.984s CPU time. Sep 01 01:11:11 stefans-speaker volumio[12527]: info: CoreCommandRouter::volumioGetState Sep 01 01:11:11 stefans-speaker volumio[12527]: info: CorePlayQueue::getTrack 0 Sep 01 01:11:11 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:11:11 stefans-speaker sudo[12759]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:11 stefans-speaker sudo[12761]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:11 stefans-speaker sudo[12757]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:11 stefans-speaker volumio[12527]: info: Shairport-Sync Started Sep 01 01:11:11 stefans-speaker volumio[12527]: Error adding Membership: Error: addMembership EINVAL Sep 01 01:11:11 stefans-speaker volumio[12527]: info: Shairport-Sync Started Sep 01 01:11:11 stefans-speaker volumio[12527]: info: Shairport-Sync Started Sep 01 01:11:11 stefans-speaker volumio[12527]: 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:11:11 stefans-speaker volumio[12527]: info: Spotify Successfully logged in Sep 01 01:11:11 stefans-speaker volumio[12527]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:11:11 stefans-speaker volumio[12527]: info: [1788217871439] CoreMusicLibrary::Adding element Spotify Sep 01 01:11:11 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:11:11 stefans-speaker volumio[12527]: Cannot find translation for source Spotify Sep 01 01:11:11 stefans-speaker sudo[12797]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:11 stefans-speaker sudo[12795]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:11 stefans-speaker sudo[12797]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 01:11:11 stefans-speaker sudo[12795]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 01:11:11 stefans-speaker sudo[12795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:11 stefans-speaker sudo[12797]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:11 stefans-speaker sudo[12795]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:11 stefans-speaker sudo[12797]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:11 stefans-speaker sudo[12801]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:11 stefans-speaker sudo[12801]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 01 01:11:11 stefans-speaker sudo[12801]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:11 stefans-speaker sudo[12801]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:11 stefans-speaker volumio[12527]: info: Upmpdcli Daemon Started Sep 01 01:11:12 stefans-speaker volumio[12527]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 01 01:11:12 stefans-speaker volumio[12527]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 01:11:12 stefans-speaker volumio[12527]: info: VolumeController:: Volume=70 Mute =false Sep 01 01:11:12 stefans-speaker volumio[12527]: info: CoreCommandRouter::volumioGetState Sep 01 01:11:12 stefans-speaker volumio[12527]: info: CorePlayQueue::getTrack 0 Sep 01 01:11:12 stefans-speaker volumio[12527]: info: CoreStateMachine::pushState Sep 01 01:11:12 stefans-speaker volumio[12527]: info: CorePlayQueue::getTrack 0 Sep 01 01:11:12 stefans-speaker volumio[12527]: info: CoreCommandRouter::volumioPushState Sep 01 01:11:12 stefans-speaker mpd[12708]: 2026-09-01T01:11:12 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 01:11:12 stefans-speaker systemd[1]: Started mpd.service - Music Player Daemon. Sep 01 01:11:12 stefans-speaker sudo[12672]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:12 stefans-speaker sudo[12662]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:13 stefans-speaker volumio[12527]: error: MPD error: The expression evaluated to a falsy value: Sep 01 01:11:13 stefans-speaker volumio[12527]: assert.ok(self.idling) Sep 01 01:11:13 stefans-speaker volumio[12527]: error: The expression evaluated to a falsy value: Sep 01 01:11:13 stefans-speaker volumio[12527]: assert.ok(self.idling) Sep 01 01:11:13 stefans-speaker volumio[12527]: info: MPD running with PID12708 Sep 01 01:11:13 stefans-speaker volumio[12527]: ,establishing connection Sep 01 01:11:13 stefans-speaker volumio[12527]: error: updateQueue error: null Sep 01 01:11:13 stefans-speaker volumio[12527]: info: Completed starting Core Plugins Sep 01 01:11:13 stefans-speaker volumio[12527]: info: ------------------------------------------- Sep 01 01:11:13 stefans-speaker volumio[12527]: info: ----- MyVolumio plugins startup ---- Sep 01 01:11:13 stefans-speaker volumio[12527]: info: ------------------------------------------- Sep 01 01:11:13 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 01 01:11:13 stefans-speaker volumio[12527]: error: updateQueue error: null Sep 01 01:11:13 stefans-speaker volumio[12527]: info: go-librespot daemon successfully initialized Sep 01 01:11:14 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Sep 01 01:11:14 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:14 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:14 stefans-speaker go-librespot[12814]: go-librespot daemon starting... Sep 01 01:11:14 stefans-speaker go-librespot[12815]: time="2026-09-01T01:11:14+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:11:14 stefans-speaker go-librespot[12815]: time="2026-09-01T01:11:14+02:00" level=debug msg="app state loaded" Sep 01 01:11:14 stefans-speaker go-librespot[12815]: time="2026-09-01T01:11:14+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:11:14 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:11:14] [connect] Successful connection Sep 01 01:11:14 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:11:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788217874 101 Sep 01 01:11:14 stefans-speaker volumio[12527]: 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:11:14 stefans-speaker go-librespot[12815]: time="2026-09-01T01:11:14+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:11:14 stefans-speaker go-librespot[12815]: time="2026-09-01T01:11:14+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:11:14 stefans-speaker go-librespot[12815]: time="2026-09-01T01:11:14+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:11:14 stefans-speaker go-librespot[12815]: time="2026-09-01T01:11:14+02:00" level=info msg="zeroconf server listening on port 46275" Sep 01 01:11:14 stefans-speaker go-librespot[12815]: time="2026-09-01T01:11:14+02:00" level=debug msg="obtained new client token: AAE18kSR6mpCj5SRs9E2eEP12fO7vzILZPmvNZYM0VJXHsRPPeqU8lJQmksBIyeTbjyFrlOsIn0hkma+Zf0RdMOypq9k6jmA02YyX1GVDVRlZA0bxIfcPTNw1nBRD5mIbv1P3Zy0l8+31g1yjkg57SRQKCV7D++3h5F9PPpXf51i3oP5BllLdI3GeVmX3vtj6URo0ZEw1N/W/XqZUGl4oUNqESe4J1CL1PxiwvOLzhPz5BOXIa2xmDkCZ8g=" Sep 01 01:11:14 stefans-speaker go-librespot[12815]: time="2026-09-01T01:11:14+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:11:14 stefans-speaker go-librespot[12815]: time="2026-09-01T01:11:14+02:00" level=debug msg="completed keyexchange" Sep 01 01:11:14 stefans-speaker go-librespot[12815]: time="2026-09-01T01:11:14+02:00" level=debug msg="completed challenge" Sep 01 01:11:14 stefans-speaker go-librespot[12815]: time="2026-09-01T01:11:14+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:11:15 stefans-speaker go-librespot[12815]: time="2026-09-01T01:11:15+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:11:15 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:11:15 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:11:16 stefans-speaker sudo[12827]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:16 stefans-speaker sudo[12825]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:16 stefans-speaker sudo[12827]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 01:11:16 stefans-speaker sudo[12825]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 01:11:16 stefans-speaker sudo[12825]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:16 stefans-speaker sudo[12827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:16 stefans-speaker systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Sep 01 01:11:16 stefans-speaker systemd[1]: mpd_monitor.service: Deactivated successfully. Sep 01 01:11:16 stefans-speaker systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Sep 01 01:11:16 stefans-speaker systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Sep 01 01:11:16 stefans-speaker sudo[12827]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:16 stefans-speaker sudo[12825]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:16 stefans-speaker mpd_monitor.sh[12830]: MPD Monitor Service: Starting MPD Monitor Service Sep 01 01:11:16 stefans-speaker volumio[12527]: info: Successfully started MPD Monitor Sep 01 01:11:16 stefans-speaker volumio[12527]: info: Successfully started MPD Monitor Sep 01 01:11:16 stefans-speaker volumio[12527]: info: Initializing connection to go-librespot Websocket Sep 01 01:11:16 stefans-speaker volumio[12527]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:11:18 stefans-speaker volumio[12527]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 01 01:11:18 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Sep 01 01:11:18 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:18 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:18 stefans-speaker go-librespot[12835]: go-librespot daemon starting... Sep 01 01:11:18 stefans-speaker go-librespot[12836]: time="2026-09-01T01:11:18+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:11:18 stefans-speaker go-librespot[12836]: time="2026-09-01T01:11:18+02:00" level=debug msg="app state loaded" Sep 01 01:11:18 stefans-speaker go-librespot[12836]: time="2026-09-01T01:11:18+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:11:18 stefans-speaker go-librespot[12836]: time="2026-09-01T01:11:18+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:11:18 stefans-speaker go-librespot[12836]: time="2026-09-01T01:11:18+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:11:18 stefans-speaker go-librespot[12836]: time="2026-09-01T01:11:18+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:11:18 stefans-speaker go-librespot[12836]: time="2026-09-01T01:11:18+02:00" level=info msg="zeroconf server listening on port 38603" Sep 01 01:11:18 stefans-speaker go-librespot[12836]: time="2026-09-01T01:11:18+02:00" level=debug msg="obtained new client token: AAE7tsM3EUNHW83w1HYexfvMuu67Y89JhNybQja0AmQ48SAHwmJhjGnmi1hHW6VOoUkO7FJi5/WejRZeRIkTyNH1kxJFt+5NkZI7+vZdMjMNlDLl3ZwVpgHxtsqk2fiizVckz1EmANL0FnXqcWQ7dIHGFlZfOoHkYacqATyhR3RRMZZ95xW6DJPYQ9nQ6hoKKdlTXMm+smQKA5wPfZVgpB+qPnD3OUpALFQlwCau0L7/grlBA9+ZLykQz4Y=" Sep 01 01:11:18 stefans-speaker go-librespot[12836]: time="2026-09-01T01:11:18+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:11:18 stefans-speaker go-librespot[12836]: time="2026-09-01T01:11:18+02:00" level=debug msg="completed keyexchange" Sep 01 01:11:18 stefans-speaker go-librespot[12836]: time="2026-09-01T01:11:18+02:00" level=debug msg="completed challenge" Sep 01 01:11:18 stefans-speaker go-librespot[12836]: time="2026-09-01T01:11:18+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:11:18 stefans-speaker go-librespot[12836]: time="2026-09-01T01:11:18+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:11:18 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:11:18 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:11:19 stefans-speaker volumio[12527]: info: Initializing connection to go-librespot Websocket Sep 01 01:11:19 stefans-speaker volumio[12527]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 01 01:11:21 stefans-speaker volumio[12527]: info: Adding plugin bluetooth to MyMusic Plugins Sep 01 01:11:21 stefans-speaker volumio[12527]: info: Adding plugin multiroom to MyMusic Plugins Sep 01 01:11:21 stefans-speaker volumio[12527]: info: Adding plugin metavolumio to MyMusic Plugins Sep 01 01:11:21 stefans-speaker volumio[12527]: info: Adding plugin cd_controller to MyMusic Plugins Sep 01 01:11:21 stefans-speaker volumio[12527]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 01 01:11:21 stefans-speaker volumio[12527]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 01 01:11:21 stefans-speaker volumio[12527]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 01 01:11:21 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 01 01:11:21 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Sep 01 01:11:21 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:21 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:21 stefans-speaker go-librespot[12857]: go-librespot daemon starting... Sep 01 01:11:21 stefans-speaker go-librespot[12858]: time="2026-09-01T01:11:21+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:11:21 stefans-speaker go-librespot[12858]: time="2026-09-01T01:11:21+02:00" level=debug msg="app state loaded" Sep 01 01:11:21 stefans-speaker go-librespot[12858]: time="2026-09-01T01:11:21+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:11:22 stefans-speaker go-librespot[12858]: time="2026-09-01T01:11:22+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:11:22 stefans-speaker go-librespot[12858]: time="2026-09-01T01:11:22+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:11:22 stefans-speaker go-librespot[12858]: time="2026-09-01T01:11:22+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:11:22 stefans-speaker go-librespot[12858]: time="2026-09-01T01:11:22+02:00" level=info msg="zeroconf server listening on port 36713" Sep 01 01:11:22 stefans-speaker go-librespot[12858]: time="2026-09-01T01:11:22+02:00" level=debug msg="obtained new client token: AAGINwP10lNCZN8Qlo6ShWVG5J+1bqCCZVyT4xCoNbxFi6vbX9i/IqCXAymOkFXppA7mUhTm4esCeTYo22irkU9KJ6PENZzO28TUKVW0xf+jf+tNrG8Nn0BwV+JMSXcIeVoDCdBLWEU0i1nm7KtFud5ez9Jy0WsQydLYIqSk9yUzry+HSoL5h3uREor7OY8HsexsAafP89t0QqVFoPgs1WLdBAXuE0KTSetUhbjxwjaYlAZnkFjI2ot61uc=" Sep 01 01:11:22 stefans-speaker go-librespot[12858]: time="2026-09-01T01:11:22+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:11:22 stefans-speaker go-librespot[12858]: time="2026-09-01T01:11:22+02:00" level=debug msg="completed keyexchange" Sep 01 01:11:22 stefans-speaker go-librespot[12858]: time="2026-09-01T01:11:22+02:00" level=debug msg="completed challenge" Sep 01 01:11:22 stefans-speaker go-librespot[12858]: time="2026-09-01T01:11:22+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:11:22 stefans-speaker go-librespot[12858]: time="2026-09-01T01:11:22+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:11:22 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:11:22 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:11:22 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 01 01:11:22 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 01 01:11:22 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:11:22 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:11:22 stefans-speaker volumio[12527]: info: Starting MyVolumio Remote Streaming Endpoints Sep 01 01:11:22 stefans-speaker volumio[12527]: info: MyVolumio login type: Token Sep 01 01:11:22 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 01 01:11:22 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 01 01:11:23 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 01 01:11:23 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 01 01:11:23 stefans-speaker volumio[12527]: info: Streaming services startup Sep 01 01:11:23 stefans-speaker volumio[12527]: info: Starting Streaming Daemon Sep 01 01:11:23 stefans-speaker sudo[12867]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:23 stefans-speaker volumio[12527]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 01 01:11:23 stefans-speaker sudo[12867]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 01:11:23 stefans-speaker sudo[12867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:23 stefans-speaker sudo[12867]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:23 stefans-speaker volumio[12527]: info: Initializing connection to go-librespot Websocket Sep 01 01:11:23 stefans-speaker volumio[12527]: error: Cannot start Volumio Streaming Daemon Sep 01 01:11:23 stefans-speaker volumio[12527]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 01:11:23 stefans-speaker volumio[12527]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:11:23 stefans-speaker volumio[12527]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 01:11:23 stefans-speaker volumio[12527]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:11:24 stefans-speaker volumio[12527]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 01 01:11:24 stefans-speaker volumio[12527]: info: MyVolumio token set successfully Sep 01 01:11:24 stefans-speaker volumio[12527]: info: MYVOLUMIO: Adding device Sep 01 01:11:24 stefans-speaker volumio[12527]: info: MYVOLUMIO: Evaluating Server Sep 01 01:11:25 stefans-speaker volumio[12527]: info: MyVolumio status changed Sep 01 01:11:25 stefans-speaker volumio[12527]: info: Streaming services startup Sep 01 01:11:25 stefans-speaker volumio[12527]: info: Starting Streaming Daemon Sep 01 01:11:25 stefans-speaker volumio[12527]: info: Removing browser output: myVolumio user plan is not superstar Sep 01 01:11:25 stefans-speaker volumio[12527]: info: Removing audio output: Sep 01 01:11:25 stefans-speaker volumio[12527]: info: Stoppping Tunnel 1 Sep 01 01:11:25 stefans-speaker sudo[12895]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:25 stefans-speaker sudo[12895]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 01:11:25 stefans-speaker sudo[12895]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:25 stefans-speaker sudo[12897]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:25 stefans-speaker sudo[12897]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 01 01:11:25 stefans-speaker sudo[12897]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:25 stefans-speaker sudo[12895]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:25 stefans-speaker volumio[12527]: error: Cannot start Volumio Streaming Daemon Sep 01 01:11:25 stefans-speaker volumio[12527]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 01:11:25 stefans-speaker volumio[12527]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:11:25 stefans-speaker volumio[12527]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 01:11:25 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:11:25 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:11:25 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:11:25 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:11:25 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:11:25 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:11:25 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:11:25 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:11:25 stefans-speaker sudo[12897]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:25 stefans-speaker volumio[12527]: info: Remote SSH Stopped Sep 01 01:11:25 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Sep 01 01:11:25 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:25 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:25 stefans-speaker go-librespot[12900]: go-librespot daemon starting... Sep 01 01:11:25 stefans-speaker go-librespot[12901]: time="2026-09-01T01:11:25+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:11:25 stefans-speaker go-librespot[12901]: time="2026-09-01T01:11:25+02:00" level=debug msg="app state loaded" Sep 01 01:11:25 stefans-speaker go-librespot[12901]: time="2026-09-01T01:11:25+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:11:26 stefans-speaker go-librespot[12901]: time="2026-09-01T01:11:26+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:11:26 stefans-speaker go-librespot[12901]: time="2026-09-01T01:11:26+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:11:26 stefans-speaker go-librespot[12901]: time="2026-09-01T01:11:26+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:11:26 stefans-speaker go-librespot[12901]: time="2026-09-01T01:11:26+02:00" level=info msg="zeroconf server listening on port 42235" Sep 01 01:11:26 stefans-speaker go-librespot[12901]: time="2026-09-01T01:11:26+02:00" level=debug msg="obtained new client token: AAFKZKuIJY+4Zk74FYJGN0BHhzVAl6fhmdEOM8bqIsiQx7U/lUXobnZmv/RHbynQ/LtGrujIQe6nJoDAauIBx12phjr/8ElCNM2gbcAwloX69p0NtP3mrkER7YAnncaK4tcJ5wjc2NK7T6tkB+27lvXTx25flDpyLU7XyhSykxfNL1I3cmc0wJGVh50E7f/eJQ4loOvlMlRh2jgrOYfLYdUfxXsiyhtk1BjPIl2CZWr4BNTFKiCaIA1M" Sep 01 01:11:26 stefans-speaker go-librespot[12901]: time="2026-09-01T01:11:26+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:11:26 stefans-speaker go-librespot[12901]: time="2026-09-01T01:11:26+02:00" level=debug msg="completed keyexchange" Sep 01 01:11:26 stefans-speaker go-librespot[12901]: time="2026-09-01T01:11:26+02:00" level=debug msg="completed challenge" Sep 01 01:11:26 stefans-speaker go-librespot[12901]: time="2026-09-01T01:11:26+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:11:26 stefans-speaker go-librespot[12901]: time="2026-09-01T01:11:26+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:11:26 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:11:26 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:11:26 stefans-speaker volumio[12527]: info: Initializing connection to go-librespot Websocket Sep 01 01:11:26 stefans-speaker volumio[12527]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:11:27 stefans-speaker volumio[12527]: info: Setting Geolocation for MyVolumio to eu2 Sep 01 01:11:27 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:11:27 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:11:27 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:11:27 stefans-speaker volumio[12527]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 01 01:11:28 stefans-speaker volumio[12527]: info: Updating MyVolumio device info Sep 01 01:11:28 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:11:28 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:11:28 stefans-speaker volumio[12527]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:11:28 stefans-speaker volumio[12527]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 01 01:11:29 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Sep 01 01:11:29 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:29 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:29 stefans-speaker go-librespot[12908]: go-librespot daemon starting... Sep 01 01:11:29 stefans-speaker go-librespot[12909]: time="2026-09-01T01:11:29+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:11:29 stefans-speaker go-librespot[12909]: time="2026-09-01T01:11:29+02:00" level=debug msg="app state loaded" Sep 01 01:11:29 stefans-speaker go-librespot[12909]: time="2026-09-01T01:11:29+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:11:29 stefans-speaker go-librespot[12909]: time="2026-09-01T01:11:29+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 01:11:29 stefans-speaker go-librespot[12909]: time="2026-09-01T01:11:29+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 01 01:11:29 stefans-speaker go-librespot[12909]: time="2026-09-01T01:11:29+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 01 01:11:29 stefans-speaker volumio[12527]: info: Initializing connection to go-librespot Websocket Sep 01 01:11:29 stefans-speaker go-librespot[12909]: time="2026-09-01T01:11:29+02:00" level=info msg="zeroconf server listening on port 42453" Sep 01 01:11:29 stefans-speaker go-librespot[12909]: time="2026-09-01T01:11:29+02:00" level=debug msg="new websocket client" Sep 01 01:11:29 stefans-speaker volumio[12527]: info: Connection to go-librespot Websocket established Sep 01 01:11:29 stefans-speaker go-librespot[12909]: time="2026-09-01T01:11:29+02:00" level=debug msg="obtained new client token: AAGpQJdot+AtaE6lT7Rg6ax/CqDyCvnNcnw69OazssvF1LvrZihDXMrbqIGhHst7fAcomnB1Qg/HwSJBFkj5sDYV6KJSHCeNndqDdYaYAynkQKoCV0CBtA+3Gk0oVgxn+kaHaZNwOesRiQ5VRzuEgG+a3pzBVNCwjODfE9R4IhYrrD0x5ahOSENGe6CjdfBL58kULsq3p8RvETHTFZvAoVLNJzLvhPZ8vOMNW3nsGUWFWMGoSoT1zrk/dco=" Sep 01 01:11:30 stefans-speaker go-librespot[12909]: time="2026-09-01T01:11:30+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:11:30 stefans-speaker go-librespot[12909]: time="2026-09-01T01:11:30+02:00" level=debug msg="completed keyexchange" Sep 01 01:11:30 stefans-speaker go-librespot[12909]: time="2026-09-01T01:11:30+02:00" level=debug msg="completed challenge" Sep 01 01:11:30 stefans-speaker go-librespot[12909]: time="2026-09-01T01:11:30+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:11:30 stefans-speaker go-librespot[12909]: time="2026-09-01T01:11:30+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:11:30 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:11:30 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:11:30 stefans-speaker volumio[12527]: info: Connection to go-librespot Websocket closed Sep 01 01:11:31 stefans-speaker volumio[12527]: info: MYVOLUMIO: Adding device Sep 01 01:11:31 stefans-speaker volumio[12527]: info: MYVOLUMIO: Evaluating Server Sep 01 01:11:32 stefans-speaker volumio[12527]: info: Getting Spotify volume Sep 01 01:11:32 stefans-speaker volumio[12527]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 01:11:32 stefans-speaker volumio[12527]: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:11:32 stefans-speaker volumio[12527]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Sep 01 01:11:32 stefans-speaker volumio[12527]: errno: -111, Sep 01 01:11:32 stefans-speaker volumio[12527]: code: 'ECONNREFUSED', Sep 01 01:11:32 stefans-speaker volumio[12527]: syscall: 'connect', Sep 01 01:11:32 stefans-speaker volumio[12527]: address: '127.0.0.1', Sep 01 01:11:32 stefans-speaker volumio[12527]: port: 9879, Sep 01 01:11:32 stefans-speaker volumio[12527]: response: undefined Sep 01 01:11:32 stefans-speaker volumio[12527]: } Sep 01 01:11:32 stefans-speaker volumio[12527]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 01:11:33 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Sep 01 01:11:33 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:33 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:33 stefans-speaker go-librespot[12960]: go-librespot daemon starting... Sep 01 01:11:33 stefans-speaker go-librespot[12961]: time="2026-09-01T01:11:33+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:11:33 stefans-speaker go-librespot[12961]: time="2026-09-01T01:11:33+02:00" level=debug msg="app state loaded" Sep 01 01:11:33 stefans-speaker go-librespot[12961]: time="2026-09-01T01:11:33+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:11:33 stefans-speaker go-librespot[12961]: time="2026-09-01T01:11:33+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:11:33 stefans-speaker go-librespot[12961]: time="2026-09-01T01:11:33+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:11:33 stefans-speaker go-librespot[12961]: time="2026-09-01T01:11:33+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:11:33 stefans-speaker go-librespot[12961]: time="2026-09-01T01:11:33+02:00" level=info msg="zeroconf server listening on port 34317" Sep 01 01:11:33 stefans-speaker sudo[12970]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:33 stefans-speaker sudo[12970]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-09-01 01:10' Sep 01 01:11:33 stefans-speaker sudo[12970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:33 stefans-speaker go-librespot[12961]: time="2026-09-01T01:11:33+02:00" level=debug msg="obtained new client token: AAFUB3f7L9O3LNlCrc/JfgVMUnVJsJ3j+jNKKWmblDKWCN5Vlel0rosseDNhdwIpbff0+n3WMwAd5MeCI/W8hyzzL1PIDnVGrryZmH7UaAEeSfO13mQT28tRjRxBxsqXsCewcvgUw4dYaNbp82qZup22pF0hg1s+YPHQf9YnpuQ7EQYqB77sQYRp3Hq4s3JeffudRBdVJJ+RwEFLHQA2XjaE5WiX04Hj+F6herCyeRfZvBfQy2ELyGnpbYE=" Sep 01 01:11:33 stefans-speaker go-librespot[12961]: time="2026-09-01T01:11:33+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:11:33 stefans-speaker go-librespot[12961]: time="2026-09-01T01:11:33+02:00" level=debug msg="completed keyexchange" Sep 01 01:11:33 stefans-speaker go-librespot[12961]: time="2026-09-01T01:11:33+02:00" level=debug msg="completed challenge" Sep 01 01:11:33 stefans-speaker sudo[12970]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:33 stefans-speaker go-librespot[12961]: time="2026-09-01T01:11:33+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:11:33 stefans-speaker volumio[12527]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:11:34 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:11:34] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 01 01:11:34 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:11:34] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 01 01:11:34 stefans-speaker systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:11:34 stefans-speaker systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 01 01:11:34 stefans-speaker systemd[1]: volumio.service: Consumed 32.808s CPU time. Sep 01 01:11:34 stefans-speaker go-librespot[12961]: time="2026-09-01T01:11:34+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:11:34 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 01 01:11:34 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:11:34 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:11:34 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Sep 01 01:11:34 stefans-speaker systemd[1]: volumio.service: Scheduled restart job, restart counter is at 300. Sep 01 01:11:34 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 01 01:11:34 stefans-speaker systemd[1]: Stopped volumio.service - Volumio Backend Module. Sep 01 01:11:34 stefans-speaker systemd[1]: volumio.service: Consumed 32.808s CPU time. Sep 01 01:11:34 stefans-speaker systemd[1]: Started volumio.service - Volumio Backend Module. Sep 01 01:11:34 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Sep 01 01:11:36 stefans-speaker volumio[12982]: info: ------------------------------------------- Sep 01 01:11:36 stefans-speaker volumio[12982]: info: ----- Volumio3 ---- Sep 01 01:11:36 stefans-speaker volumio[12982]: info: ------------------------------------------- Sep 01 01:11:36 stefans-speaker volumio[12982]: info: ----- System startup ---- Sep 01 01:11:36 stefans-speaker volumio[12982]: info: ------------------------------------------- Sep 01 01:11:37 stefans-speaker volumio[12982]: info: MYVOLUMIO Environment detected Sep 01 01:11:37 stefans-speaker volumio[12982]: info: Plugin folders cleanup Sep 01 01:11:37 stefans-speaker volumio[12982]: info: Scanning into folder /volumio/app/plugins/ Sep 01 01:11:37 stefans-speaker volumio[12982]: info: Scanning category audio_interface Sep 01 01:11:37 stefans-speaker volumio[12982]: info: Scanning category miscellanea Sep 01 01:11:37 stefans-speaker volumio[12982]: info: Scanning category music_service Sep 01 01:11:37 stefans-speaker volumio[12982]: info: Scanning category plugins.json Sep 01 01:11:37 stefans-speaker volumio[12982]: info: Scanning category system_controller Sep 01 01:11:37 stefans-speaker volumio[12982]: info: Scanning category user_interface Sep 01 01:11:37 stefans-speaker volumio[12982]: info: Scanning into folder /data/plugins/ Sep 01 01:11:37 stefans-speaker volumio[12982]: info: Scanning category music_service Sep 01 01:11:37 stefans-speaker volumio[12982]: info: Plugin folders cleanup completed Sep 01 01:11:37 stefans-speaker volumio[12982]: info: ------------------------------------------- Sep 01 01:11:37 stefans-speaker volumio[12982]: info: ----- Core plugins startup ---- Sep 01 01:11:37 stefans-speaker volumio[12982]: info: ------------------------------------------- Sep 01 01:11:37 stefans-speaker volumio[12982]: info: Loading plugins from folder /volumio/app/plugins/ Sep 01 01:11:37 stefans-speaker volumio[12982]: info: Adding plugin upnp to MyMusic Plugins Sep 01 01:11:37 stefans-speaker volumio[12982]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 01 01:11:37 stefans-speaker volumio[12982]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 01 01:11:37 stefans-speaker volumio[12982]: info: Loading plugins from folder /data/plugins/ Sep 01 01:11:37 stefans-speaker volumio[12982]: info: Loading plugin "system"... Sep 01 01:11:37 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Sep 01 01:11:37 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:37 stefans-speaker volumio[12982]: info: Loading plugin "appearance"... Sep 01 01:11:37 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:37 stefans-speaker go-librespot[13009]: go-librespot daemon starting... Sep 01 01:11:37 stefans-speaker go-librespot[13013]: time="2026-09-01T01:11:37+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:11:37 stefans-speaker go-librespot[13013]: time="2026-09-01T01:11:37+02:00" level=debug msg="app state loaded" Sep 01 01:11:37 stefans-speaker go-librespot[13013]: time="2026-09-01T01:11:37+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:11:37 stefans-speaker go-librespot[13013]: time="2026-09-01T01:11:37+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 01:11:37 stefans-speaker go-librespot[13013]: time="2026-09-01T01:11:37+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:11:37 stefans-speaker go-librespot[13013]: time="2026-09-01T01:11:37+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:11:37 stefans-speaker go-librespot[13013]: time="2026-09-01T01:11:37+02:00" level=info msg="zeroconf server listening on port 43977" Sep 01 01:11:37 stefans-speaker go-librespot[13013]: time="2026-09-01T01:11:37+02:00" level=debug msg="obtained new client token: AAHW4PYNfvWxQ3M2H+dc5ILVsa6rpU8Pg3/FgPnITz5MOXUOZMrKWSqGUxOiTERsD+OWBXED315uC3hduGWzWP8JNtjGIOQIl4QkFifE80aJYePzFJfrDdTeUoY/6BdlM/7VhC0/ai/2IV+Qm1ifXCJJALAabDe7OExqxwpyzzLc26bF6N5bIwCJbgfPBG15cwaIRaIx81yknfSQQzHEgXWNIj3P9G1GBXQf4pLzGsdwUTLWmTSMQI2Bs+0=" Sep 01 01:11:37 stefans-speaker go-librespot[13013]: time="2026-09-01T01:11:37+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:11:37 stefans-speaker go-librespot[13013]: time="2026-09-01T01:11:37+02:00" level=debug msg="completed keyexchange" Sep 01 01:11:37 stefans-speaker go-librespot[13013]: time="2026-09-01T01:11:37+02:00" level=debug msg="completed challenge" Sep 01 01:11:37 stefans-speaker go-librespot[13013]: time="2026-09-01T01:11:37+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:11:38 stefans-speaker go-librespot[13013]: time="2026-09-01T01:11:38+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:11:38 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:11:38 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:11:38 stefans-speaker volumio[12982]: info: Loading plugin "network"... Sep 01 01:11:38 stefans-speaker volumio[12982]: info: Refreshing Cached IP Addresses Sep 01 01:11:38 stefans-speaker volumio[12982]: info: Loading plugin "services"... Sep 01 01:11:38 stefans-speaker volumio[12982]: info: Loading plugin "alsa_controller"... Sep 01 01:11:38 stefans-speaker sudo[13021]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:38 stefans-speaker sudo[13023]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:38 stefans-speaker sudo[13030]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:38 stefans-speaker sudo[13023]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 01:11:38 stefans-speaker sudo[13023]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:38 stefans-speaker sudo[13021]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 01:11:38 stefans-speaker sudo[13021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:38 stefans-speaker sudo[13030]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 01 01:11:38 stefans-speaker sudo[13030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:38 stefans-speaker sudo[13021]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:38 stefans-speaker sudo[13023]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:38 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:11:38 stefans-speaker volumio[12982]: info: Loading plugin "wizard"... Sep 01 01:11:38 stefans-speaker volumio[12982]: info: Loading plugin "networkfs"... Sep 01 01:11:38 stefans-speaker volumio[12982]: info: Starting Udev Watcher for removable devices Sep 01 01:11:38 stefans-speaker volumio[12982]: info: Ignoring mount for partition: boot Sep 01 01:11:38 stefans-speaker volumio[12982]: info: Ignoring mount for partition: volumio Sep 01 01:11:38 stefans-speaker volumio[12982]: info: Ignoring mount for partition: volumio_data Sep 01 01:11:38 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 01:11:38 stefans-speaker volumio[12982]: info: Loading plugin "volumio_command_line_client"... Sep 01 01:11:38 stefans-speaker volumio[12982]: info: Loading plugin "upnp"... Sep 01 01:11:38 stefans-speaker volumio[12982]: info: [1788217898692] Starting Upmpd Daemon Sep 01 01:11:38 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 01:11:38 stefans-speaker volumio[12982]: info: Loading plugin "my_music"... Sep 01 01:11:38 stefans-speaker volumio[12982]: info: Loading plugin "mpd"... Sep 01 01:11:39 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:11:39] [connect] Successful connection Sep 01 01:11:39 stefans-speaker volumio[12982]: info: Loading plugin "upnp_browser"... Sep 01 01:11:40 stefans-speaker volumio[12982]: info: Starting UPNP Browser Sep 01 01:11:40 stefans-speaker volumio[12982]: info: Loading plugin "alarm-clock"... Sep 01 01:11:40 stefans-speaker volumio[12982]: info: Loading plugin "airplay_emulation"... Sep 01 01:11:40 stefans-speaker volumio[12982]: info: Starting Shairport Sync Sep 01 01:11:40 stefans-speaker volumio[12982]: info: Loading plugin "last_100"... Sep 01 01:11:40 stefans-speaker volumio[12982]: info: Loading plugin "webradio"... Sep 01 01:11:40 stefans-speaker volumio[12982]: info: Loading plugin "i2s_dacs"... Sep 01 01:11:40 stefans-speaker volumio[12982]: info: I2S DAC not set, start Auto-detection Sep 01 01:11:40 stefans-speaker volumio[12982]: info: Loading plugin "volumiodiscovery"... Sep 01 01:11:40 stefans-speaker volumio[12982]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 01:11:40 stefans-speaker volumio[12982]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:11:40 stefans-speaker volumio[12982]: *** WARNING *** For more information see Sep 01 01:11:40 stefans-speaker volumio[12982]: *** 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:11:40 stefans-speaker volumio[12982]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:11:40 stefans-speaker volumio[12982]: *** WARNING *** For more information see Sep 01 01:11:40 stefans-speaker node[12982]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 01:11:40 stefans-speaker node[12982]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:11:40 stefans-speaker node[12982]: *** WARNING *** For more information see Sep 01 01:11:40 stefans-speaker node[12982]: *** 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:11:40 stefans-speaker node[12982]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:11:40 stefans-speaker node[12982]: *** WARNING *** For more information see Sep 01 01:11:40 stefans-speaker volumio[12982]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 01 01:11:40 stefans-speaker volumio[12982]: info: Discovery: Started advertising with name: Stefan’s Speaker Sep 01 01:11:40 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 01:11:40 stefans-speaker volumio[12982]: info: Loading plugin "spop"... Sep 01 01:11:41 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Sep 01 01:11:41 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:41 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:41 stefans-speaker go-librespot[13057]: go-librespot daemon starting... Sep 01 01:11:41 stefans-speaker go-librespot[13058]: time="2026-09-01T01:11:41+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:11:41 stefans-speaker go-librespot[13058]: time="2026-09-01T01:11:41+02:00" level=debug msg="app state loaded" Sep 01 01:11:41 stefans-speaker go-librespot[13058]: time="2026-09-01T01:11:41+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:11:41 stefans-speaker go-librespot[13058]: time="2026-09-01T01:11:41+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 01:11:41 stefans-speaker go-librespot[13058]: time="2026-09-01T01:11:41+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:11:41 stefans-speaker go-librespot[13058]: time="2026-09-01T01:11:41+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:11:41 stefans-speaker go-librespot[13058]: time="2026-09-01T01:11:41+02:00" level=info msg="zeroconf server listening on port 41167" Sep 01 01:11:41 stefans-speaker sudo[13030]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:41 stefans-speaker go-librespot[13058]: time="2026-09-01T01:11:41+02:00" level=debug msg="obtained new client token: AAFS1DYAYpfcruMAISnYwjaczKH7gRo8AqsQ6KBvXpO4D9dAc9h5fzcV+MB0RfirZBEHNfyV/2Z/Smn3RZzC7gNhrMpE0gctqxBfWkby9Y/ozRucJoYYtmPp6lhc87+UTOxVJYoy5mXkepRetNsD3/iRRcRvBZu7GjLh16UdjpaW7/OsT9AxTm6UB69i9UHwq7ElRh+esONArYXTT3xl+bDQCImlWN0KqhalApiQzM3+itrouB5DbXZl9+U=" Sep 01 01:11:41 stefans-speaker go-librespot[13058]: time="2026-09-01T01:11:41+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:11:42 stefans-speaker go-librespot[13058]: time="2026-09-01T01:11:42+02:00" level=debug msg="completed keyexchange" Sep 01 01:11:42 stefans-speaker go-librespot[13058]: time="2026-09-01T01:11:42+02:00" level=debug msg="completed challenge" Sep 01 01:11:42 stefans-speaker volumio[12982]: info: Loading plugin "ytcr"... Sep 01 01:11:42 stefans-speaker go-librespot[13058]: time="2026-09-01T01:11:42+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:11:42 stefans-speaker go-librespot[13058]: time="2026-09-01T01:11:42+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:11:42 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:11:42 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:11:43 stefans-speaker volumio[12982]: info: Loading plugin "outputs"... Sep 01 01:11:43 stefans-speaker volumio[12982]: info: Loading plugin "albumart"... Sep 01 01:11:43 stefans-speaker volumio[12982]: info: Plugin example_plugin is not enabled Sep 01 01:11:43 stefans-speaker volumio[12982]: info: Loading plugin "inputs"... Sep 01 01:11:43 stefans-speaker volumio[12982]: info: Loading plugin "updater_comm"... Sep 01 01:11:43 stefans-speaker volumio[12982]: info: Plugin mpdemulation is not enabled Sep 01 01:11:43 stefans-speaker volumio[12982]: info: Loading plugin "rest_api"... Sep 01 01:11:43 stefans-speaker volumio[12982]: info: Loading plugin "websocket"... Sep 01 01:11:43 stefans-speaker volumio[12982]: info: Starting Socket.io Server version 1.7.4 Sep 01 01:11:43 stefans-speaker volumio[12982]: info: Loading i18n strings for locale nl Sep 01 01:11:43 stefans-speaker volumio[12982]: Updating browse sources language Sep 01 01:11:43 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:11:43 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:11:43 stefans-speaker volumio[12982]: info: CoreCommandRouter::initPlayerControls Sep 01 01:11:43 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:43 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:43 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:43 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:43 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:43 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:43 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:43 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:43 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:11:43 stefans-speaker volumio[12982]: Express server listening on port 3000 Sep 01 01:11:43 stefans-speaker volumio[12982]: [Metrics] WebUI: 8s 100.57ms Sep 01 01:11:43 stefans-speaker volumio[12982]: info: CoreStateMachine::resetVolumioState Sep 01 01:11:43 stefans-speaker volumio[12982]: info: CoreStateMachine::getcurrentVolume Sep 01 01:11:43 stefans-speaker volumio[12982]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 01:11:43 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:11:44 stefans-speaker volumio[12982]: info: Cannot read play queue from file Sep 01 01:11:44 stefans-speaker volumio[12982]: info: Volumio Network Manager: Network status updated: 2 Sep 01 01:11:44 stefans-speaker volumio[13065]: Forking 3 albumart workers Sep 01 01:11:44 stefans-speaker volumio[12982]: info: VolumeController:: Volume=70 Mute =false Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreStateMachine::pushState Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CorePlayQueue::getTrack 0 Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreCommandRouter::volumioPushState Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreStateMachine::updateTrackBlock Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CorePlayQueue::getTrackBlock Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreStateMachine::setRepeat null single undefined Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreStateMachine::pushState Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CorePlayQueue::getTrack 0 Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreCommandRouter::volumioPushState Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreStateMachine::setRandom null Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreStateMachine::pushState Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CorePlayQueue::getTrack 0 Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreCommandRouter::volumioPushState Sep 01 01:11:44 stefans-speaker volumio[12982]: info: Setting Device type: Raspberry PI Sep 01 01:11:44 stefans-speaker volumio[12982]: info: VolumeController:: Volume=70 Mute =false Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreStateMachine::pushState Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CorePlayQueue::getTrack 0 Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreCommandRouter::volumioPushState Sep 01 01:11:44 stefans-speaker volumio[12982]: info: Completed loading Core Plugins Sep 01 01:11:44 stefans-speaker volumio[12982]: info: Preparing to generate the ALSA configuration file Sep 01 01:11:44 stefans-speaker volumio[12982]: info: Discovery: adding a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 01:11:44 stefans-speaker volumio[12982]: info: Discovery: Found device Stefan’s Speaker Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreCommandRouter::volumioGetState Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CorePlayQueue::getTrack 0 Sep 01 01:11:44 stefans-speaker volumio[12982]: info: Discovery: this is already registered, a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 01:11:44 stefans-speaker volumio[12982]: info: Discovery: Found device Stefan’s Speaker Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreCommandRouter::volumioGetState Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CorePlayQueue::getTrack 0 Sep 01 01:11:44 stefans-speaker volumio[12982]: info: Asound.conf file unchanged, so no further update is needed Sep 01 01:11:44 stefans-speaker volumio[12982]: info: Output device has changed, restarting MPD Sep 01 01:11:44 stefans-speaker volumio[12982]: info: Output device has changed, restarting Shairport Sync Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:11:44 stefans-speaker sudo[13121]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:44 stefans-speaker volumio[12982]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 01:11:44 stefans-speaker sudo[13122]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:44 stefans-speaker volumio[12982]: info: ___________ START PLUGINS ___________ Sep 01 01:11:44 stefans-speaker sudo[13121]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 01:11:44 stefans-speaker sudo[13121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:44 stefans-speaker sudo[13122]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 01:11:44 stefans-speaker sudo[13122]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:44 stefans-speaker volumio[12982]: info: ControllerMpd::onStart: Initializing MPD Sep 01 01:11:44 stefans-speaker volumio[12982]: info: Creating MPD Configuration file Sep 01 01:11:44 stefans-speaker sudo[13121]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:11:44 stefans-speaker volumio[12982]: info: [1788217904793] CoreMusicLibrary::Adding element Media Servers Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:11:44 stefans-speaker volumio[12982]: info: UPNP Browser: Client initialized successfully Sep 01 01:11:44 stefans-speaker sudo[13130]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:44 stefans-speaker sudo[13132]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:44 stefans-speaker sudo[13130]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 01:11:44 stefans-speaker systemd[1]: Stopping mpd.service - Music Player Daemon... Sep 01 01:11:44 stefans-speaker sudo[13130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:44 stefans-speaker sudo[13132]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 01:11:44 stefans-speaker sudo[13130]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:44 stefans-speaker sudo[13132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:11:44 stefans-speaker volumio[12982]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:11:44 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Sep 01 01:11:44 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 01 01:11:44 stefans-speaker systemd[1]: mpd.service: Consumed 4.459s CPU time. Sep 01 01:11:44 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Sep 01 01:11:44 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 01 01:11:44 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 01 01:11:44 stefans-speaker volumio[12982]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:11:44 stefans-speaker volumio[12982]: info: [1788217904963] CoreMusicLibrary::Adding element Last_100 Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:11:44 stefans-speaker volumio[12982]: info: [1788217904973] CoreMusicLibrary::Adding element Webradio Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:11:44 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 01 01:11:44 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Sep 01 01:11:44 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:11:45 stefans-speaker volumio[12982]: info: Initializing BBC Radios Sep 01 01:11:45 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:11:45 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:11:45 stefans-speaker volumio[12982]: info: Creating Spotify config file Sep 01 01:11:45 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:45 stefans-speaker sudo[13144]: root : unable to resolve host stefans-speaker: System error Sep 01 01:11:45 stefans-speaker sudo[13144]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:11:45 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Sep 01 01:11:45 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:45 stefans-speaker sudo[13144]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 01:11:45 stefans-speaker sudo[13144]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 01 01:11:45 stefans-speaker sudo[13144]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:45 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:45 stefans-speaker go-librespot[13159]: go-librespot daemon starting... Sep 01 01:11:45 stefans-speaker go-librespot[13160]: time="2026-09-01T01:11:45+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:11:45 stefans-speaker go-librespot[13160]: time="2026-09-01T01:11:45+02:00" level=info msg="zeroconf server listening on port 35055" Sep 01 01:11:46 stefans-speaker volumio[12982]: info: Volumio Calling Home Sep 01 01:11:46 stefans-speaker volumio[13081]: Starting albumart workers Sep 01 01:11:46 stefans-speaker volumio[13082]: Starting albumart workers Sep 01 01:11:46 stefans-speaker volumio[13083]: Starting albumart workers Sep 01 01:11:46 stefans-speaker volumio[12982]: info: MPD Permissions set Sep 01 01:11:46 stefans-speaker volumio[12982]: info: MPD Permissions set Sep 01 01:11:46 stefans-speaker volumio[12982]: info: Volumio called home Sep 01 01:11:46 stefans-speaker volumio[12982]: info: Spotify config file written Sep 01 01:11:46 stefans-speaker sudo[13172]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:46 stefans-speaker sudo[13172]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 01 01:11:46 stefans-speaker sudo[13172]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:47 stefans-speaker systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Sep 01 01:11:47 stefans-speaker systemd[1]: go-librespot-daemon.service: Deactivated successfully. Sep 01 01:11:47 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:47 stefans-speaker volumio[12982]: 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:11:47 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:47 stefans-speaker go-librespot[13180]: go-librespot daemon starting... Sep 01 01:11:47 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:47 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:47 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:47 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:47 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:47 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:47 stefans-speaker sudo[13172]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:47 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:47 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:47 stefans-speaker go-librespot[13181]: time="2026-09-01T01:11:47+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:11:47 stefans-speaker go-librespot[13181]: time="2026-09-01T01:11:47+02:00" level=debug msg="app state loaded" Sep 01 01:11:47 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:11:47 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:47 stefans-speaker go-librespot[13181]: time="2026-09-01T01:11:47+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:11:47 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:47 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:47 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:47 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:47 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:47 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:47 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:47 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:47 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:11:47 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:11:47 stefans-speaker volumio[12982]: info: No need to fix Spotify hosts Sep 01 01:11:47 stefans-speaker go-librespot[13181]: time="2026-09-01T01:11: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Sep 01 01:11:47 stefans-speaker go-librespot[13181]: time="2026-09-01T01:11:47+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:11:47 stefans-speaker go-librespot[13181]: time="2026-09-01T01:11:47+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:11:47 stefans-speaker go-librespot[13181]: time="2026-09-01T01:11:47+02:00" level=info msg="zeroconf server listening on port 45449" Sep 01 01:11:47 stefans-speaker go-librespot[13181]: time="2026-09-01T01:11:47+02:00" level=debug msg="obtained new client token: AAGIut7dMsops0uU0IqJELeF9poZ55OkXVwW9Q6aPHUVjmK0u9FWcxIwrwKbbcIYgcy4bjWn6C63bzpiU8/vKn7BdwrwWyElsN6g4Frw8dgX8Ix/xCkadnyI5T96CzdewCqol52sGQyiUIOoFLUUpClTiND+uIF9Uf/C4Wki3XLzwHHktBbq1A8jB0mcLJSPwVETW4CLj6PCzOyXWPB7+xSYytZu67g2lQ2iuba48DUFI8kPMq42NaIpadY=" Sep 01 01:11:47 stefans-speaker go-librespot[13181]: time="2026-09-01T01:11:47+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:11:47 stefans-speaker go-librespot[13181]: time="2026-09-01T01:11:47+02:00" level=debug msg="completed keyexchange" Sep 01 01:11:47 stefans-speaker volumio[12982]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 01:11:47 stefans-speaker go-librespot[13181]: time="2026-09-01T01:11:47+02:00" level=debug msg="completed challenge" Sep 01 01:11:47 stefans-speaker volumio[12982]: SPOTIFY: BQDs_H-xmACWjx1taKowfJ_OMqpO5pTOAMKeubzpSlYP22SPrMTUH-yQQitl62xsEDmmBopSSdxoOnpp6_ML6qtTl9QNj0IqwSa8vOVYgOtJzPMkF6chtzDMi3HMpPvAHLSw17pHN06qE87hjjR2Xvzc4etLKH5B99SVop5wJ9o1gs-KaCcdt9SSGxpsXu076egVtVxIMIZlCpYCXEiUiA5CVTEDbIMXuVzwQcICBZQYgaHSehrKzZ-pwV2DPeByT4BRG8FgwWhqab1IYOtN1E_M2ZhHjK-q5gF2sjVJ5-GhTaHjBSOleJ-bRTPClIw Sep 01 01:11:47 stefans-speaker volumio[12982]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 01:11:47 stefans-speaker volumio[12982]: info: New Spotify access token = BQDs_H-xmACWjx1taKowfJ_OMqpO5pTOAMKeubzpSlYP22SPrMTUH-yQQitl62xsEDmmBopSSdxoOnpp6_ML6qtTl9QNj0IqwSa8vOVYgOtJzPMkF6chtzDMi3HMpPvAHLSw17pHN06qE87hjjR2Xvzc4etLKH5B99SVop5wJ9o1gs-KaCcdt9SSGxpsXu076egVtVxIMIZlCpYCXEiUiA5CVTEDbIMXuVzwQcICBZQYgaHSehrKzZ-pwV2DPeByT4BRG8FgwWhqab1IYOtN1E_M2ZhHjK-q5gF2sjVJ5-GhTaHjBSOleJ-bRTPClIw Sep 01 01:11:47 stefans-speaker volumio[12982]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 01 01:11:47 stefans-speaker volumio[12982]: info: Starting Shairport Sync Sep 01 01:11:47 stefans-speaker volumio[12982]: info: Starting Shairport Sync Sep 01 01:11:47 stefans-speaker volumio[12982]: info: Starting Shairport Sync Sep 01 01:11:47 stefans-speaker go-librespot[13181]: time="2026-09-01T01:11:47+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:11:47 stefans-speaker sudo[13211]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:47 stefans-speaker sudo[13211]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 01:11:47 stefans-speaker sudo[13211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:47 stefans-speaker sudo[13215]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:48 stefans-speaker sudo[13218]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:48 stefans-speaker sudo[13215]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 01:11:48 stefans-speaker sudo[13215]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:48 stefans-speaker sudo[13218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 01:11:48 stefans-speaker sudo[13218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:48 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 01 01:11:48 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Sep 01 01:11:48 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:11:48 stefans-speaker systemd[1]: shairport-sync.service: Consumed 1.828s CPU time. Sep 01 01:11:48 stefans-speaker go-librespot[13181]: time="2026-09-01T01:11:48+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:11:48 stefans-speaker volumio[12982]: info: CoreCommandRouter::volumioGetState Sep 01 01:11:48 stefans-speaker volumio[12982]: info: CorePlayQueue::getTrack 0 Sep 01 01:11:48 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:11:48 stefans-speaker sudo[13211]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:48 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:11:48 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:11:48 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 01 01:11:48 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Sep 01 01:11:48 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:11:48 stefans-speaker volumio[12982]: info: Shairport-Sync Started Sep 01 01:11:48 stefans-speaker volumio[12982]: Error adding Membership: Error: addMembership EINVAL Sep 01 01:11:48 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:11:48 stefans-speaker volumio[12982]: 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:11:48 stefans-speaker volumio[12982]: info: Spotify Successfully logged in Sep 01 01:11:48 stefans-speaker sudo[13218]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:48 stefans-speaker sudo[13215]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:48 stefans-speaker volumio[12982]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:11:48 stefans-speaker volumio[12982]: info: [1788217908416] CoreMusicLibrary::Adding element Spotify Sep 01 01:11:48 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:11:48 stefans-speaker volumio[12982]: Cannot find translation for source Spotify Sep 01 01:11:48 stefans-speaker volumio[12982]: info: Shairport-Sync Started Sep 01 01:11:48 stefans-speaker volumio[12982]: info: Shairport-Sync Started Sep 01 01:11:48 stefans-speaker sudo[13252]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:48 stefans-speaker sudo[13252]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 01:11:48 stefans-speaker sudo[13254]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:48 stefans-speaker sudo[13252]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:48 stefans-speaker sudo[13254]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 01:11:48 stefans-speaker sudo[13254]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:48 stefans-speaker sudo[13252]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:48 stefans-speaker sudo[13254]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:48 stefans-speaker volumio[12982]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 01 01:11:48 stefans-speaker volumio[12982]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 01:11:48 stefans-speaker volumio[12982]: info: VolumeController:: Volume=70 Mute =false Sep 01 01:11:48 stefans-speaker volumio[12982]: info: CoreCommandRouter::volumioGetState Sep 01 01:11:48 stefans-speaker volumio[12982]: info: CorePlayQueue::getTrack 0 Sep 01 01:11:48 stefans-speaker volumio[12982]: info: CoreStateMachine::pushState Sep 01 01:11:48 stefans-speaker volumio[12982]: info: CorePlayQueue::getTrack 0 Sep 01 01:11:48 stefans-speaker volumio[12982]: info: CoreCommandRouter::volumioPushState Sep 01 01:11:49 stefans-speaker sudo[13260]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:49 stefans-speaker sudo[13260]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 01 01:11:49 stefans-speaker sudo[13260]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:49 stefans-speaker sudo[13260]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:49 stefans-speaker volumio[12982]: info: Upmpdcli Daemon Started Sep 01 01:11:49 stefans-speaker mpd[13161]: 2026-09-01T01:11:49 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 01:11:49 stefans-speaker systemd[1]: Started mpd.service - Music Player Daemon. Sep 01 01:11:49 stefans-speaker sudo[13122]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:49 stefans-speaker sudo[13132]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:50 stefans-speaker volumio[12982]: error: MPD error: The expression evaluated to a falsy value: Sep 01 01:11:50 stefans-speaker volumio[12982]: assert.ok(self.idling) Sep 01 01:11:50 stefans-speaker volumio[12982]: error: The expression evaluated to a falsy value: Sep 01 01:11:50 stefans-speaker volumio[12982]: assert.ok(self.idling) Sep 01 01:11:50 stefans-speaker volumio[12982]: error: updateQueue error: null Sep 01 01:11:50 stefans-speaker volumio[12982]: info: MPD running with PID13161 Sep 01 01:11:50 stefans-speaker volumio[12982]: ,establishing connection Sep 01 01:11:50 stefans-speaker volumio[12982]: info: Completed starting Core Plugins Sep 01 01:11:50 stefans-speaker volumio[12982]: info: ------------------------------------------- Sep 01 01:11:50 stefans-speaker volumio[12982]: info: ----- MyVolumio plugins startup ---- Sep 01 01:11:50 stefans-speaker volumio[12982]: info: ------------------------------------------- Sep 01 01:11:50 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 01 01:11:50 stefans-speaker volumio[12982]: error: updateQueue error: null Sep 01 01:11:50 stefans-speaker volumio[12982]: info: go-librespot daemon successfully initialized Sep 01 01:11:51 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Sep 01 01:11:51 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:51 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:51 stefans-speaker go-librespot[13268]: go-librespot daemon starting... Sep 01 01:11:51 stefans-speaker go-librespot[13269]: time="2026-09-01T01:11:51+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:11:51 stefans-speaker go-librespot[13269]: time="2026-09-01T01:11:51+02:00" level=debug msg="app state loaded" Sep 01 01:11:51 stefans-speaker go-librespot[13269]: time="2026-09-01T01:11:51+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:11:51 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:11:51] [connect] Successful connection Sep 01 01:11:51 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:11:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788217911 101 Sep 01 01:11:51 stefans-speaker volumio[12982]: 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:11:51 stefans-speaker go-librespot[13269]: time="2026-09-01T01:11: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-gae2.spotify.com:80]" Sep 01 01:11:51 stefans-speaker go-librespot[13269]: time="2026-09-01T01:11:51+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:11:51 stefans-speaker go-librespot[13269]: time="2026-09-01T01:11:51+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:11:51 stefans-speaker go-librespot[13269]: time="2026-09-01T01:11:51+02:00" level=info msg="zeroconf server listening on port 38861" Sep 01 01:11:51 stefans-speaker go-librespot[13269]: time="2026-09-01T01:11:51+02:00" level=debug msg="obtained new client token: AAHqw0l38VkjPTLnrrLmiQWtCKfSEYK9ghf36eXFXDn9IeT6Mi48yIpgMBOsbSR1k5c6UVO5cWYpEfhMdXjWeDQ/lWOH9v1trq47mWUNcFjd2H3+4LKQbbofF9Da20KDLsTw+Je1CXQvsCrw/Sia15dZUDdWviJW5WUAKw+Uc8VKJ+Rvi/Sw/2MMNEyHD6gb3nZfPXi+g8/MnlUp5ZZMfdX5ygLnCidpWxulejOludJavndRILBCeWFGO98=" Sep 01 01:11:51 stefans-speaker go-librespot[13269]: time="2026-09-01T01:11:51+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:11:51 stefans-speaker go-librespot[13269]: time="2026-09-01T01:11:51+02:00" level=debug msg="completed keyexchange" Sep 01 01:11:51 stefans-speaker go-librespot[13269]: time="2026-09-01T01:11:51+02:00" level=debug msg="completed challenge" Sep 01 01:11:51 stefans-speaker go-librespot[13269]: time="2026-09-01T01:11:51+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:11:51 stefans-speaker go-librespot[13269]: time="2026-09-01T01:11: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:11:51 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:11:51 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:11:52 stefans-speaker sudo[13279]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:53 stefans-speaker sudo[13281]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:11:53 stefans-speaker sudo[13279]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 01:11:53 stefans-speaker sudo[13279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:53 stefans-speaker sudo[13281]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 01:11:53 stefans-speaker sudo[13281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:11:53 stefans-speaker systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Sep 01 01:11:53 stefans-speaker systemd[1]: mpd_monitor.service: Deactivated successfully. Sep 01 01:11:53 stefans-speaker systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Sep 01 01:11:53 stefans-speaker systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Sep 01 01:11:53 stefans-speaker sudo[13281]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:53 stefans-speaker sudo[13279]: pam_unix(sudo:session): session closed for user root Sep 01 01:11:53 stefans-speaker mpd_monitor.sh[13284]: MPD Monitor Service: Starting MPD Monitor Service Sep 01 01:11:53 stefans-speaker volumio[12982]: info: Successfully started MPD Monitor Sep 01 01:11:53 stefans-speaker volumio[12982]: info: Successfully started MPD Monitor Sep 01 01:11:53 stefans-speaker volumio[12982]: info: Initializing connection to go-librespot Websocket Sep 01 01:11:53 stefans-speaker volumio[12982]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:11:54 stefans-speaker volumio[12982]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 01 01:11:55 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Sep 01 01:11:55 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:55 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:55 stefans-speaker go-librespot[13288]: go-librespot daemon starting... Sep 01 01:11:55 stefans-speaker go-librespot[13289]: time="2026-09-01T01:11:55+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:11:55 stefans-speaker go-librespot[13289]: time="2026-09-01T01:11:55+02:00" level=debug msg="app state loaded" Sep 01 01:11:55 stefans-speaker go-librespot[13289]: time="2026-09-01T01:11:55+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:11:55 stefans-speaker go-librespot[13289]: time="2026-09-01T01:11: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-gew1.spotify.com:80]" Sep 01 01:11:55 stefans-speaker go-librespot[13289]: time="2026-09-01T01:11:55+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:11:55 stefans-speaker go-librespot[13289]: time="2026-09-01T01:11:55+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:11:55 stefans-speaker go-librespot[13289]: time="2026-09-01T01:11:55+02:00" level=info msg="zeroconf server listening on port 46303" Sep 01 01:11:55 stefans-speaker go-librespot[13289]: time="2026-09-01T01:11:55+02:00" level=debug msg="obtained new client token: AAHW2qou+htgVZeE+0FHK/YA0Tw96VVEmv0xCaRr+kYYLHXVRg37XlaSYC0NEJ25JIB2ptWgPHkJbg217HvAbzm2+RtC0nzA+ScIoRB9YLHyZUPugSZArGV2TM+MgRDv5ZaF3GyiTDArlYxNIZ+KY39YuDkQnIUIgdA74RZ71v+iEeT9tBepzf5qKc0geGxXJf/sYI5qb2fkNUI4idJBOnBFI1qgaS26iHank7q+oXBrky95iixkNnMKZxg=" Sep 01 01:11:55 stefans-speaker go-librespot[13289]: time="2026-09-01T01:11:55+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:11:55 stefans-speaker go-librespot[13289]: time="2026-09-01T01:11:55+02:00" level=debug msg="completed keyexchange" Sep 01 01:11:55 stefans-speaker go-librespot[13289]: time="2026-09-01T01:11:55+02:00" level=debug msg="completed challenge" Sep 01 01:11:55 stefans-speaker go-librespot[13289]: time="2026-09-01T01:11:55+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:11:55 stefans-speaker go-librespot[13289]: time="2026-09-01T01:11: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:11:55 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:11:55 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:11:56 stefans-speaker volumio[12982]: info: Initializing connection to go-librespot Websocket Sep 01 01:11:56 stefans-speaker volumio[12982]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 01 01:11:58 stefans-speaker volumio[12982]: info: Adding plugin bluetooth to MyMusic Plugins Sep 01 01:11:58 stefans-speaker volumio[12982]: info: Adding plugin multiroom to MyMusic Plugins Sep 01 01:11:58 stefans-speaker volumio[12982]: info: Adding plugin metavolumio to MyMusic Plugins Sep 01 01:11:58 stefans-speaker volumio[12982]: info: Adding plugin cd_controller to MyMusic Plugins Sep 01 01:11:58 stefans-speaker volumio[12982]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 01 01:11:58 stefans-speaker volumio[12982]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 01 01:11:58 stefans-speaker volumio[12982]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 01 01:11:58 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 01 01:11:59 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Sep 01 01:11:59 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:59 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:11:59 stefans-speaker go-librespot[13310]: go-librespot daemon starting... Sep 01 01:11:59 stefans-speaker go-librespot[13311]: time="2026-09-01T01:11:59+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:11:59 stefans-speaker go-librespot[13311]: time="2026-09-01T01:11:59+02:00" level=debug msg="app state loaded" Sep 01 01:11:59 stefans-speaker go-librespot[13311]: time="2026-09-01T01:11:59+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:11:59 stefans-speaker go-librespot[13311]: time="2026-09-01T01:11:59+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:11:59 stefans-speaker go-librespot[13311]: time="2026-09-01T01:11:59+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:11:59 stefans-speaker go-librespot[13311]: time="2026-09-01T01:11:59+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:11:59 stefans-speaker go-librespot[13311]: time="2026-09-01T01:11:59+02:00" level=info msg="zeroconf server listening on port 45935" Sep 01 01:11:59 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 01 01:11:59 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 01 01:11:59 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:11:59 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:11:59 stefans-speaker volumio[12982]: info: Starting MyVolumio Remote Streaming Endpoints Sep 01 01:11:59 stefans-speaker volumio[12982]: info: MyVolumio login type: Token Sep 01 01:11:59 stefans-speaker go-librespot[13311]: time="2026-09-01T01:11:59+02:00" level=debug msg="obtained new client token: AAGAiMZTHdFne75ZvEkyL5ZshnH4qwhC+mmEPqkEta6Jbmnpe6ezCooAV2QvVLq7dSAHqQ+9XLw49xtXHbuaTdQrexB6FjDBEJMimXgsxusWiSD7uD9UPrlGc+6ND0qq2gjGboZIETm6Cmlv6guTmTGAEx9vmENVGrhbcuMy6VI36DQQwt8vb/PMMb4TlhR9ERyQPhvTk+IIsltYSiIc4aIINW7xJ+iqjswU7LF0l15QKUnu1teX7n8LB88=" Sep 01 01:11:59 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 01 01:11:59 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 01 01:11:59 stefans-speaker go-librespot[13311]: time="2026-09-01T01:11:59+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:11:59 stefans-speaker go-librespot[13311]: time="2026-09-01T01:11:59+02:00" level=debug msg="completed keyexchange" Sep 01 01:11:59 stefans-speaker go-librespot[13311]: time="2026-09-01T01:11:59+02:00" level=debug msg="completed challenge" Sep 01 01:11:59 stefans-speaker go-librespot[13311]: time="2026-09-01T01:11:59+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:11:59 stefans-speaker go-librespot[13311]: time="2026-09-01T01:11: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:11:59 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:11:59 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:12:00 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 01 01:12:00 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 01 01:12:00 stefans-speaker volumio[12982]: info: Streaming services startup Sep 01 01:12:00 stefans-speaker volumio[12982]: info: Starting Streaming Daemon Sep 01 01:12:00 stefans-speaker sudo[13320]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:00 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 01 01:12:00 stefans-speaker sudo[13320]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 01:12:00 stefans-speaker sudo[13320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:00 stefans-speaker sudo[13320]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:00 stefans-speaker volumio[12982]: info: Initializing connection to go-librespot Websocket Sep 01 01:12:00 stefans-speaker volumio[12982]: error: Cannot start Volumio Streaming Daemon Sep 01 01:12:00 stefans-speaker volumio[12982]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 01:12:00 stefans-speaker volumio[12982]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:12:00 stefans-speaker volumio[12982]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 01:12:00 stefans-speaker volumio[12982]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:12:01 stefans-speaker volumio[12982]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 01 01:12:02 stefans-speaker volumio[12982]: info: MyVolumio token set successfully Sep 01 01:12:02 stefans-speaker volumio[12982]: info: MYVOLUMIO: Adding device Sep 01 01:12:02 stefans-speaker volumio[12982]: info: MYVOLUMIO: Evaluating Server Sep 01 01:12:02 stefans-speaker volumio[12982]: info: MyVolumio status changed Sep 01 01:12:02 stefans-speaker volumio[12982]: info: Streaming services startup Sep 01 01:12:02 stefans-speaker volumio[12982]: info: Starting Streaming Daemon Sep 01 01:12:02 stefans-speaker volumio[12982]: info: Removing browser output: myVolumio user plan is not superstar Sep 01 01:12:02 stefans-speaker volumio[12982]: info: Removing audio output: Sep 01 01:12:02 stefans-speaker volumio[12982]: info: Stoppping Tunnel 1 Sep 01 01:12:02 stefans-speaker sudo[13349]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:02 stefans-speaker sudo[13349]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 01:12:02 stefans-speaker sudo[13349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:02 stefans-speaker sudo[13351]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:02 stefans-speaker sudo[13351]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 01 01:12:02 stefans-speaker sudo[13351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:02 stefans-speaker sudo[13349]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:02 stefans-speaker volumio[12982]: error: Cannot start Volumio Streaming Daemon Sep 01 01:12:02 stefans-speaker volumio[12982]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 01:12:02 stefans-speaker volumio[12982]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:12:02 stefans-speaker volumio[12982]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 01:12: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:12: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:12: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:12: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:12: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:12: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:12:02 stefans-speaker sudo[13351]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:02 stefans-speaker volumio[12982]: info: Remote SSH Stopped Sep 01 01:12:02 stefans-speaker volumio[12982]: info: Setting Geolocation for MyVolumio to eu4 Sep 01 01:12:02 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:02 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:02 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:03 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Sep 01 01:12:03 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:03 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:03 stefans-speaker go-librespot[13354]: go-librespot daemon starting... Sep 01 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+02:00" level=debug msg="app state loaded" Sep 01 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:12:03 stefans-speaker volumio[12982]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 01 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+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:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+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:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+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:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+02:00" level=info msg="zeroconf server listening on port 43581" Sep 01 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+02:00" level=debug msg="obtained new client token: AAFRUiCmkfHuD0BSAWpQm2+OJ7Y3FMemb4xRRw5a5pc2VLQ+/aoRNU8MKu4Z2yCgN3qaSSACxejqGDxXwsAuBGDwQBf5j86RcQgJ151kWK/LG5isXvY4bacsK0gEpOIehK2qW2x9UrATY2zBqIEcagSY6SiIPzQArnyyppxFhSl7SJQunxQERvH3Ar0BSo9vFKSQihuGjzuDtn15sBMQY2Q+HSxmOm/UA4O92s7wOQvifoSqnxHaiCf9xCw=" Sep 01 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:12:03 stefans-speaker volumio[12982]: info: Updating MyVolumio device info Sep 01 01:12:03 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:03 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:03 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+02:00" level=debug msg="completed keyexchange" Sep 01 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+02:00" level=debug msg="completed challenge" Sep 01 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:12:03 stefans-speaker volumio[12982]: info: Initializing connection to go-librespot Websocket Sep 01 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+02:00" level=debug msg="new websocket client" Sep 01 01:12:03 stefans-speaker volumio[12982]: info: Connection to go-librespot Websocket established Sep 01 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12: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:12:03 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:12:03 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:12:03 stefans-speaker volumio[12982]: info: Connection to go-librespot Websocket closed Sep 01 01:12:03 stefans-speaker volumio[12982]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 01 01:12:06 stefans-speaker volumio[12982]: info: Getting Spotify volume Sep 01 01:12:06 stefans-speaker volumio[12982]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 01:12:06 stefans-speaker volumio[12982]: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:12:06 stefans-speaker volumio[12982]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Sep 01 01:12:06 stefans-speaker volumio[12982]: errno: -111, Sep 01 01:12:06 stefans-speaker volumio[12982]: code: 'ECONNREFUSED', Sep 01 01:12:06 stefans-speaker volumio[12982]: syscall: 'connect', Sep 01 01:12:06 stefans-speaker volumio[12982]: address: '127.0.0.1', Sep 01 01:12:06 stefans-speaker volumio[12982]: port: 9879, Sep 01 01:12:06 stefans-speaker volumio[12982]: response: undefined Sep 01 01:12:06 stefans-speaker volumio[12982]: } Sep 01 01:12:06 stefans-speaker volumio[12982]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 01:12:07 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Sep 01 01:12:07 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:07 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:07 stefans-speaker go-librespot[13374]: go-librespot daemon starting... Sep 01 01:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12:07+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12:07+02:00" level=debug msg="app state loaded" Sep 01 01:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12:07+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:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12:07+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:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12:07+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:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12:07+02:00" level=info msg="zeroconf server listening on port 34539" Sep 01 01:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12:07+02:00" level=debug msg="obtained new client token: AAE94UV6iWd99kqXaRIdxIgB1lnYwjiYW9jMoTrquj4GbUdNCRTlUvZVvlD83AK8KQYmd2AoXHqt5/Ax3LjDdPDxVT8p2gXEuHAuxQrCMKQTTt5m8chPQXWV0s+SJBx0JmaHAH6pud8WJ1cNOhDeIe6xL9+R98by5E2QssAK1FnTq02mYvtFxSdPH4JdZow2f7dsqbEj+8A3XqeFN+pQWvfUVhWg2u7zFX2vi+oYv2Qk1tW0B6+k4AszYhg=" Sep 01 01:12:07 stefans-speaker sudo[13384]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:07 stefans-speaker sudo[13384]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-09-01 01:11' Sep 01 01:12:07 stefans-speaker sudo[13384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12:07+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12:07+02:00" level=debug msg="completed keyexchange" Sep 01 01:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12:07+02:00" level=debug msg="completed challenge" Sep 01 01:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12:07+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:12:07 stefans-speaker sudo[13384]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:07 stefans-speaker volumio[12982]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:12:07 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:12:07] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 01 01:12:07 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:12:07] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 01 01:12:07 stefans-speaker systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:12:07 stefans-speaker systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 01 01:12:07 stefans-speaker systemd[1]: volumio.service: Consumed 32.398s CPU time. Sep 01 01:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12: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:12:07 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 01 01:12:07 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:12:07 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:12:07 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Sep 01 01:12:08 stefans-speaker systemd[1]: volumio.service: Scheduled restart job, restart counter is at 301. Sep 01 01:12:08 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 01 01:12:08 stefans-speaker systemd[1]: Stopped volumio.service - Volumio Backend Module. Sep 01 01:12:08 stefans-speaker systemd[1]: volumio.service: Consumed 32.398s CPU time. Sep 01 01:12:08 stefans-speaker systemd[1]: Started volumio.service - Volumio Backend Module. Sep 01 01:12:08 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Sep 01 01:12:10 stefans-speaker volumio[13396]: info: ------------------------------------------- Sep 01 01:12:10 stefans-speaker volumio[13396]: info: ----- Volumio3 ---- Sep 01 01:12:10 stefans-speaker volumio[13396]: info: ------------------------------------------- Sep 01 01:12:10 stefans-speaker volumio[13396]: info: ----- System startup ---- Sep 01 01:12:10 stefans-speaker volumio[13396]: info: ------------------------------------------- Sep 01 01:12:10 stefans-speaker volumio[13396]: info: MYVOLUMIO Environment detected Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Plugin folders cleanup Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Scanning into folder /volumio/app/plugins/ Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Scanning category audio_interface Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Scanning category miscellanea Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Scanning category music_service Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Scanning category plugins.json Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Scanning category system_controller Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Scanning category user_interface Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Scanning into folder /data/plugins/ Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Scanning category music_service Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Plugin folders cleanup completed Sep 01 01:12:10 stefans-speaker volumio[13396]: info: ------------------------------------------- Sep 01 01:12:10 stefans-speaker volumio[13396]: info: ----- Core plugins startup ---- Sep 01 01:12:10 stefans-speaker volumio[13396]: info: ------------------------------------------- Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Loading plugins from folder /volumio/app/plugins/ Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Adding plugin upnp to MyMusic Plugins Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Loading plugins from folder /data/plugins/ Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Loading plugin "system"... Sep 01 01:12:11 stefans-speaker volumio[13396]: info: Loading plugin "appearance"... Sep 01 01:12:11 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Sep 01 01:12:11 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:11 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:11 stefans-speaker go-librespot[13423]: go-librespot daemon starting... Sep 01 01:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+02:00" level=debug msg="app state loaded" Sep 01 01:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Sep 01 01:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+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:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+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:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+02:00" level=info msg="zeroconf server listening on port 43787" Sep 01 01:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+02:00" level=debug msg="obtained new client token: AAG37slzB8Uyw3GXIat8qLxMkx5jxggH6iUE97FDs2apQ6Y+wq/oqmum15TiIIP77oklQo2ivzBu+VuqgnkLdBt29hlFeht4Se7WMegWhKffCr2JqpL/JibWF+xWQIA5BhgLYXZuhSEolrQ1EURDsLL8irwK4I+Odvj9Lcpfe//7E1DuwxN92R+ovcZDykOAHDnDTcVbyMPepymXpJkzmZbo8E4FXqXhZYO/ISZKkriwVvH/vURAxJJVdzI=" Sep 01 01:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+02:00" level=debug msg="completed keyexchange" Sep 01 01:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+02:00" level=debug msg="completed challenge" Sep 01 01:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+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:12:11 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:12:11 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Loading plugin "network"... Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Refreshing Cached IP Addresses Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Loading plugin "services"... Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Loading plugin "alsa_controller"... Sep 01 01:12:12 stefans-speaker sudo[13434]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:12 stefans-speaker sudo[13432]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:12 stefans-speaker sudo[13432]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 01:12:12 stefans-speaker sudo[13434]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 01:12:12 stefans-speaker sudo[13434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:12 stefans-speaker sudo[13432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:12 stefans-speaker sudo[13441]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:12 stefans-speaker sudo[13434]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:12 stefans-speaker sudo[13432]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:12 stefans-speaker sudo[13441]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 01 01:12:12 stefans-speaker sudo[13441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:12 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Loading plugin "wizard"... Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Loading plugin "networkfs"... Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Starting Udev Watcher for removable devices Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Ignoring mount for partition: boot Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Ignoring mount for partition: volumio Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Ignoring mount for partition: volumio_data Sep 01 01:12:12 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Loading plugin "volumio_command_line_client"... Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Loading plugin "upnp"... Sep 01 01:12:12 stefans-speaker volumio[13396]: info: [1788217932384] Starting Upmpd Daemon Sep 01 01:12:12 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Loading plugin "my_music"... Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Loading plugin "mpd"... Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Loading plugin "upnp_browser"... Sep 01 01:12:12 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:12:12] [connect] Successful connection Sep 01 01:12:14 stefans-speaker volumio[13396]: info: Starting UPNP Browser Sep 01 01:12:14 stefans-speaker volumio[13396]: info: Loading plugin "alarm-clock"... Sep 01 01:12:14 stefans-speaker volumio[13396]: info: Loading plugin "airplay_emulation"... Sep 01 01:12:14 stefans-speaker volumio[13396]: info: Starting Shairport Sync Sep 01 01:12:14 stefans-speaker volumio[13396]: info: Loading plugin "last_100"... Sep 01 01:12:14 stefans-speaker volumio[13396]: info: Loading plugin "webradio"... Sep 01 01:12:14 stefans-speaker volumio[13396]: info: Loading plugin "i2s_dacs"... Sep 01 01:12:14 stefans-speaker volumio[13396]: info: I2S DAC not set, start Auto-detection Sep 01 01:12:14 stefans-speaker volumio[13396]: info: Loading plugin "volumiodiscovery"... Sep 01 01:12:14 stefans-speaker volumio[13396]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 01:12:14 stefans-speaker volumio[13396]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:12:14 stefans-speaker volumio[13396]: *** WARNING *** For more information see Sep 01 01:12:14 stefans-speaker volumio[13396]: *** 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:12:14 stefans-speaker volumio[13396]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:12:14 stefans-speaker volumio[13396]: *** WARNING *** For more information see Sep 01 01:12:14 stefans-speaker node[13396]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 01:12:14 stefans-speaker node[13396]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:12:14 stefans-speaker node[13396]: *** WARNING *** For more information see Sep 01 01:12:14 stefans-speaker node[13396]: *** 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:12:14 stefans-speaker node[13396]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:12:14 stefans-speaker node[13396]: *** WARNING *** For more information see Sep 01 01:12:14 stefans-speaker volumio[13396]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 01 01:12:14 stefans-speaker volumio[13396]: info: Discovery: Started advertising with name: Stefan’s Speaker Sep 01 01:12:14 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 01:12:14 stefans-speaker volumio[13396]: info: Loading plugin "spop"... Sep 01 01:12:15 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Sep 01 01:12:15 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:15 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:15 stefans-speaker go-librespot[13470]: go-librespot daemon starting... Sep 01 01:12:15 stefans-speaker go-librespot[13471]: time="2026-09-01T01:12:15+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:12:15 stefans-speaker go-librespot[13471]: time="2026-09-01T01:12:15+02:00" level=debug msg="app state loaded" Sep 01 01:12:15 stefans-speaker go-librespot[13471]: time="2026-09-01T01:12:15+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:12:15 stefans-speaker go-librespot[13471]: time="2026-09-01T01:12:15+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Sep 01 01:12:15 stefans-speaker go-librespot[13471]: time="2026-09-01T01:12:15+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:12:15 stefans-speaker go-librespot[13471]: time="2026-09-01T01:12:15+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:12:15 stefans-speaker go-librespot[13471]: time="2026-09-01T01:12:15+02:00" level=info msg="zeroconf server listening on port 44041" Sep 01 01:12:15 stefans-speaker sudo[13441]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:15 stefans-speaker go-librespot[13471]: time="2026-09-01T01:12:15+02:00" level=debug msg="obtained new client token: AAH7eWQdS+UdYnzpX3Jgw/5/uJjb+JzWTYSp9rYcZBMvnCZf/hWdqjEmYIuNw4sQU8l/4en+bO2sfhfHhPs0dP4/m8HSKDRYWMU21u3pWEbeBhkPXAtKUFvMIFyobFzxSEIMGz3CF6aaMPEb0oalO7EDGenPE4u7FtXB1l0E+RXCr7LEwB+t4bXJxgRsA4Fr1ktTiwNR47njaAzlY5FOVRALkh0CDybdHflPMYU18iAKeZKsZCWkOalN8vc=" Sep 01 01:12:15 stefans-speaker go-librespot[13471]: time="2026-09-01T01:12:15+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:12:15 stefans-speaker go-librespot[13471]: time="2026-09-01T01:12:15+02:00" level=debug msg="completed keyexchange" Sep 01 01:12:15 stefans-speaker go-librespot[13471]: time="2026-09-01T01:12:15+02:00" level=debug msg="completed challenge" Sep 01 01:12:15 stefans-speaker volumio[13396]: info: Loading plugin "ytcr"... Sep 01 01:12:15 stefans-speaker go-librespot[13471]: time="2026-09-01T01:12:15+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Sep 01 01:12:15 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:12:15 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:12:17 stefans-speaker volumio[13396]: info: Loading plugin "outputs"... Sep 01 01:12:17 stefans-speaker volumio[13396]: info: Loading plugin "albumart"... Sep 01 01:12:17 stefans-speaker volumio[13396]: info: Plugin example_plugin is not enabled Sep 01 01:12:17 stefans-speaker volumio[13396]: info: Loading plugin "inputs"... Sep 01 01:12:17 stefans-speaker volumio[13396]: info: Loading plugin "updater_comm"... Sep 01 01:12:17 stefans-speaker volumio[13396]: info: Plugin mpdemulation is not enabled Sep 01 01:12:17 stefans-speaker volumio[13396]: info: Loading plugin "rest_api"... Sep 01 01:12:17 stefans-speaker volumio[13396]: info: Loading plugin "websocket"... Sep 01 01:12:17 stefans-speaker volumio[13396]: info: Starting Socket.io Server version 1.7.4 Sep 01 01:12:17 stefans-speaker volumio[13396]: info: Loading i18n strings for locale nl Sep 01 01:12:17 stefans-speaker volumio[13396]: Updating browse sources language Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::initPlayerControls Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:12:17 stefans-speaker volumio[13396]: Express server listening on port 3000 Sep 01 01:12:17 stefans-speaker volumio[13396]: [Metrics] WebUI: 8s 110.08ms Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreStateMachine::resetVolumioState Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreStateMachine::getcurrentVolume Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:17 stefans-speaker volumio[13396]: info: Cannot read play queue from file Sep 01 01:12:17 stefans-speaker volumio[13396]: info: Volumio Network Manager: Network status updated: 2 Sep 01 01:12:17 stefans-speaker volumio[13478]: Forking 3 albumart workers Sep 01 01:12:17 stefans-speaker volumio[13396]: info: VolumeController:: Volume=70 Mute =false Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreStateMachine::pushState Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioPushState Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreStateMachine::updateTrackBlock Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CorePlayQueue::getTrackBlock Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreStateMachine::setRepeat null single undefined Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreStateMachine::pushState Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioPushState Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreStateMachine::setRandom null Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreStateMachine::pushState Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioPushState Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Setting Device type: Raspberry PI Sep 01 01:12:18 stefans-speaker volumio[13396]: info: VolumeController:: Volume=70 Mute =false Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreStateMachine::pushState Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioPushState Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Completed loading Core Plugins Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Preparing to generate the ALSA configuration file Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Discovery: adding a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Discovery: Found device Stefan’s Speaker Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioGetState Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Discovery: this is already registered, a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Discovery: Found device Stefan’s Speaker Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioGetState Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Asound.conf file unchanged, so no further update is needed Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Output device has changed, restarting MPD Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Output device has changed, restarting Shairport Sync Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:18 stefans-speaker sudo[13534]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:18 stefans-speaker sudo[13536]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:18 stefans-speaker sudo[13534]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 01:12:18 stefans-speaker sudo[13534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:18 stefans-speaker sudo[13536]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 01:12:18 stefans-speaker sudo[13536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:18 stefans-speaker sudo[13534]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 01:12:18 stefans-speaker volumio[13396]: info: ___________ START PLUGINS ___________ Sep 01 01:12:18 stefans-speaker volumio[13396]: info: ControllerMpd::onStart: Initializing MPD Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Creating MPD Configuration file Sep 01 01:12:18 stefans-speaker systemd[1]: Stopping mpd.service - Music Player Daemon... Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:12:18 stefans-speaker volumio[13396]: info: [1788217938504] CoreMusicLibrary::Adding element Media Servers Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:12:18 stefans-speaker volumio[13396]: info: UPNP Browser: Client initialized successfully Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:18 stefans-speaker sudo[13544]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:18 stefans-speaker sudo[13546]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:18 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Sep 01 01:12:18 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 01 01:12:18 stefans-speaker systemd[1]: mpd.service: Consumed 4.598s CPU time. Sep 01 01:12:18 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Sep 01 01:12:18 stefans-speaker sudo[13544]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 01:12:18 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 01 01:12:18 stefans-speaker sudo[13544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:18 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 01 01:12:18 stefans-speaker sudo[13544]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:18 stefans-speaker sudo[13546]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 01:12:18 stefans-speaker sudo[13546]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:12:18 stefans-speaker volumio[13396]: info: [1788217938661] CoreMusicLibrary::Adding element Last_100 Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:12:18 stefans-speaker volumio[13396]: info: [1788217938666] CoreMusicLibrary::Adding element Webradio Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:12:18 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Initializing BBC Radios Sep 01 01:12:18 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Sep 01 01:12:18 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Sep 01 01:12:18 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 01 01:12:18 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Sep 01 01:12:18 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 01 01:12:18 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 01 01:12:18 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 01 01:12:18 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Creating Spotify config file Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:19 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Sep 01 01:12:19 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:19 stefans-speaker sudo[13565]: root : unable to resolve host stefans-speaker: System error Sep 01 01:12:19 stefans-speaker sudo[13565]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:12:19 stefans-speaker sudo[13565]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 01:12:19 stefans-speaker sudo[13565]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 01 01:12:19 stefans-speaker sudo[13565]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:19 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:19 stefans-speaker go-librespot[13574]: go-librespot daemon starting... Sep 01 01:12:19 stefans-speaker go-librespot[13576]: time="2026-09-01T01:12:19+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:12:19 stefans-speaker go-librespot[13576]: time="2026-09-01T01:12:19+02:00" level=info msg="zeroconf server listening on port 43703" Sep 01 01:12:19 stefans-speaker volumio[13396]: info: Volumio Calling Home Sep 01 01:12:20 stefans-speaker volumio[13494]: Starting albumart workers Sep 01 01:12:20 stefans-speaker volumio[13495]: Starting albumart workers Sep 01 01:12:20 stefans-speaker volumio[13496]: Starting albumart workers Sep 01 01:12:20 stefans-speaker volumio[13396]: info: MPD Permissions set Sep 01 01:12:20 stefans-speaker volumio[13396]: info: MPD Permissions set Sep 01 01:12:20 stefans-speaker volumio[13396]: info: Volumio called home Sep 01 01:12:20 stefans-speaker volumio[13396]: info: Spotify config file written Sep 01 01:12:20 stefans-speaker volumio[13396]: 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:12:20 stefans-speaker sudo[13589]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker sudo[13589]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 01 01:12:20 stefans-speaker sudo[13589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Sep 01 01:12:20 stefans-speaker systemd[1]: go-librespot-daemon.service: Killing process 13579 (go-librespot) with signal SIGKILL. Sep 01 01:12:20 stefans-speaker systemd[1]: go-librespot-daemon.service: Deactivated successfully. Sep 01 01:12:20 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:20 stefans-speaker go-librespot[13596]: go-librespot daemon starting... Sep 01 01:12:20 stefans-speaker sudo[13589]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12:20+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:12:20 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12:20+02:00" level=debug msg="app state loaded" Sep 01 01:12:20 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:12:20 stefans-speaker volumio[13396]: info: No need to fix Spotify hosts Sep 01 01:12:20 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12: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:12:20 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12: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:12:20 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12: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:12:20 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12:20+02:00" level=info msg="zeroconf server listening on port 38703" Sep 01 01:12:21 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12:21+02:00" level=debug msg="obtained new client token: AAHfqGWmEiw4MIAYBPTAkFWxtgCAiBUtuG9Zdj5hUy3PX9y8gFFNSfx22Inf+0ACxvjKcnaQWLfcD5IhzWNTajAu+l5bBkcQSyiCmDkloXbo5zYEjqKUpxMiBr6LyedzP5pGGh92EsxLmn/VOkduKR1ePmSW6xx+l3lf2x76r7z/nploiuTx7d51euYwq8rAyuuM2qCKlUri7GLBEtiLI60ecifqEUZn1+tV/OYiMxBBC8uRulmACa/R" Sep 01 01:12:21 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12:21+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:12:21 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12:21+02:00" level=debug msg="completed keyexchange" Sep 01 01:12:21 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12:21+02:00" level=debug msg="completed challenge" Sep 01 01:12:21 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12:21+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:12:21 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12:21+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:12:21 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:12:21 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:12:21 stefans-speaker volumio[13396]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 01:12:21 stefans-speaker volumio[13396]: SPOTIFY: BQB6FU-p1L_I6B_YhKhDi2Vjj0Hi742tUfMHH1e_FKOuLMAWQVX-EeoUtsrino_3oMMpIuHO-6wROH8N_mSnmcvQc9ubovrAV_2TEBlflDitKffdAbONTGscACVPAm4bpNFXDLw-CWgRU6cR7G0oS5zzLB-e7o0XMBC-3xbWyxreFLYd1pxLg1nV1Dd0BhT6pWlpZBK23cZcckmNlEAPtbSA9aCaKNJF3MD-0gzqIzEGtbULj1wVihAx96bD0Q7bWrlEmRzV4S0sBXVMA4mdxg9b5NHqWPB_TyZMsikbGykBcorlvqpRGKFACDVipFU Sep 01 01:12:21 stefans-speaker volumio[13396]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 01:12:21 stefans-speaker volumio[13396]: info: New Spotify access token = BQB6FU-p1L_I6B_YhKhDi2Vjj0Hi742tUfMHH1e_FKOuLMAWQVX-EeoUtsrino_3oMMpIuHO-6wROH8N_mSnmcvQc9ubovrAV_2TEBlflDitKffdAbONTGscACVPAm4bpNFXDLw-CWgRU6cR7G0oS5zzLB-e7o0XMBC-3xbWyxreFLYd1pxLg1nV1Dd0BhT6pWlpZBK23cZcckmNlEAPtbSA9aCaKNJF3MD-0gzqIzEGtbULj1wVihAx96bD0Q7bWrlEmRzV4S0sBXVMA4mdxg9b5NHqWPB_TyZMsikbGykBcorlvqpRGKFACDVipFU Sep 01 01:12:21 stefans-speaker volumio[13396]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 01 01:12:21 stefans-speaker volumio[13396]: info: Starting Shairport Sync Sep 01 01:12:21 stefans-speaker volumio[13396]: info: Starting Shairport Sync Sep 01 01:12:21 stefans-speaker volumio[13396]: info: Starting Shairport Sync Sep 01 01:12:21 stefans-speaker sudo[13629]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:21 stefans-speaker sudo[13629]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 01:12:21 stefans-speaker sudo[13629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:21 stefans-speaker sudo[13631]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:21 stefans-speaker sudo[13631]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 01:12:21 stefans-speaker sudo[13631]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:21 stefans-speaker sudo[13633]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:21 stefans-speaker sudo[13633]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 01:12:21 stefans-speaker sudo[13633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:21 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 01 01:12:21 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Sep 01 01:12:21 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:12:21 stefans-speaker systemd[1]: shairport-sync.service: Consumed 1.825s CPU time. Sep 01 01:12:21 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioGetState Sep 01 01:12:21 stefans-speaker volumio[13396]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:21 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:12:21 stefans-speaker sudo[13629]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:21 stefans-speaker sudo[13633]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:21 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 01 01:12:21 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Sep 01 01:12:21 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:12:22 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:12:22 stefans-speaker sudo[13631]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:22 stefans-speaker volumio[13396]: info: Shairport-Sync Started Sep 01 01:12:22 stefans-speaker volumio[13396]: Error adding Membership: Error: addMembership EINVAL Sep 01 01:12:22 stefans-speaker volumio[13396]: info: Shairport-Sync Started Sep 01 01:12:22 stefans-speaker volumio[13396]: info: Shairport-Sync Started Sep 01 01:12:22 stefans-speaker volumio[13396]: 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:12:22 stefans-speaker volumio[13396]: info: Spotify Successfully logged in Sep 01 01:12:22 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:12:22 stefans-speaker volumio[13396]: info: [1788217942204] CoreMusicLibrary::Adding element Spotify Sep 01 01:12:22 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:12:22 stefans-speaker volumio[13396]: Cannot find translation for source Spotify Sep 01 01:12:22 stefans-speaker sudo[13668]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:22 stefans-speaker sudo[13668]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 01:12:22 stefans-speaker sudo[13670]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:22 stefans-speaker sudo[13668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:22 stefans-speaker sudo[13670]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 01:12:22 stefans-speaker sudo[13670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:22 stefans-speaker sudo[13668]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:22 stefans-speaker sudo[13670]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:22 stefans-speaker sudo[13674]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:22 stefans-speaker sudo[13674]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 01 01:12:22 stefans-speaker sudo[13674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:22 stefans-speaker sudo[13674]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:22 stefans-speaker volumio[13396]: info: Upmpdcli Daemon Started Sep 01 01:12:22 stefans-speaker volumio[13396]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 01 01:12:22 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 01:12:22 stefans-speaker volumio[13396]: info: VolumeController:: Volume=70 Mute =false Sep 01 01:12:22 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioGetState Sep 01 01:12:22 stefans-speaker volumio[13396]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:22 stefans-speaker volumio[13396]: info: CoreStateMachine::pushState Sep 01 01:12:22 stefans-speaker volumio[13396]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:22 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioPushState Sep 01 01:12:23 stefans-speaker mpd[13577]: 2026-09-01T01:12:23 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 01:12:23 stefans-speaker systemd[1]: Started mpd.service - Music Player Daemon. Sep 01 01:12:23 stefans-speaker sudo[13536]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:23 stefans-speaker sudo[13546]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:23 stefans-speaker volumio[13396]: error: MPD error: The expression evaluated to a falsy value: Sep 01 01:12:23 stefans-speaker volumio[13396]: assert.ok(self.idling) Sep 01 01:12:23 stefans-speaker volumio[13396]: error: The expression evaluated to a falsy value: Sep 01 01:12:23 stefans-speaker volumio[13396]: assert.ok(self.idling) Sep 01 01:12:23 stefans-speaker volumio[13396]: error: updateQueue error: null Sep 01 01:12:23 stefans-speaker volumio[13396]: info: MPD running with PID13577 Sep 01 01:12:23 stefans-speaker volumio[13396]: ,establishing connection Sep 01 01:12:23 stefans-speaker volumio[13396]: info: Completed starting Core Plugins Sep 01 01:12:23 stefans-speaker volumio[13396]: info: ------------------------------------------- Sep 01 01:12:23 stefans-speaker volumio[13396]: info: ----- MyVolumio plugins startup ---- Sep 01 01:12:23 stefans-speaker volumio[13396]: info: ------------------------------------------- Sep 01 01:12:23 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 01 01:12:23 stefans-speaker volumio[13396]: error: updateQueue error: null Sep 01 01:12:24 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Sep 01 01:12:24 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:24 stefans-speaker volumio[13396]: info: go-librespot daemon successfully initialized Sep 01 01:12:24 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:24 stefans-speaker go-librespot[13684]: go-librespot daemon starting... Sep 01 01:12:24 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12:24+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:12:24 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12:24+02:00" level=debug msg="app state loaded" Sep 01 01:12:24 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:12:24 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12: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:12:24 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12: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:12:24 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12: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:12:24 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12:24+02:00" level=info msg="zeroconf server listening on port 34689" Sep 01 01:12:24 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12:24+02:00" level=debug msg="obtained new client token: AAGlE+mTd/iJxnvI5jyX0eMLvLIVnQxiMHbHaz6QIyas0036Qw6dapPPhVySVdpkVus38VsAruxQ69lLw8YsiKgD54gUibJ/tsvgQ7gqHokVTo0ChslDW6jtXHQdWWnsIddxW+EwygoOF8u9jpXl1yr23tABuDlF5g189YMp7znP35vChzLbTPmN6U1t7lHbirvm4IW8A0V1HKmTqO1Fsy+aOOhBzb8ywwfimDhRjojxXiNpjtHgUgmSnbI=" Sep 01 01:12:25 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12:25+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:12:25 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12:25+02:00" level=debug msg="completed keyexchange" Sep 01 01:12:25 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12:25+02:00" level=debug msg="completed challenge" Sep 01 01:12:25 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12:25+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:12:25 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12:25+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:12:25 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:12:25 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:12:25 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:12:25] [connect] Successful connection Sep 01 01:12:25 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:12:25] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788217945 101 Sep 01 01:12:25 stefans-speaker volumio[13396]: 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:12:26 stefans-speaker sudo[13695]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:26 stefans-speaker sudo[13697]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:26 stefans-speaker sudo[13695]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 01:12:26 stefans-speaker sudo[13695]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:26 stefans-speaker sudo[13697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 01:12:26 stefans-speaker sudo[13697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:26 stefans-speaker systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Sep 01 01:12:26 stefans-speaker systemd[1]: mpd_monitor.service: Deactivated successfully. Sep 01 01:12:26 stefans-speaker systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Sep 01 01:12:26 stefans-speaker systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Sep 01 01:12:26 stefans-speaker sudo[13697]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:26 stefans-speaker sudo[13695]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:27 stefans-speaker mpd_monitor.sh[13700]: MPD Monitor Service: Starting MPD Monitor Service Sep 01 01:12:27 stefans-speaker volumio[13396]: info: Successfully started MPD Monitor Sep 01 01:12:27 stefans-speaker volumio[13396]: info: Successfully started MPD Monitor Sep 01 01:12:27 stefans-speaker volumio[13396]: info: Initializing connection to go-librespot Websocket Sep 01 01:12:27 stefans-speaker volumio[13396]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:12:28 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Sep 01 01:12:28 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:28 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:28 stefans-speaker go-librespot[13704]: go-librespot daemon starting... Sep 01 01:12:28 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12:28+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:12:28 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12:28+02:00" level=debug msg="app state loaded" Sep 01 01:12:28 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:12:28 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 01:12:28 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12:28+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:12:28 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12:28+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:12:28 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12:28+02:00" level=info msg="zeroconf server listening on port 38339" Sep 01 01:12:28 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12:28+02:00" level=debug msg="obtained new client token: AAGYRLuIEOfZ2NUn2S4FVkyn0DcqOa+3izR3dwfh4vUrQMdrHEM9PDr2fJ3GnTjsxdwcWe0nXULXIdhPUMM/47HGGqb89ASSLCvD9od5l/C1ENshYlI3TlmN5vCAyh0IkYUD9sJk7Xjl8U9uuevPndloQG60Kay1JFMt64GwBvOJ6pCggUL5w98iMWtgSNqChzl03Pjlh5vCSwp+0p0gK8LOzGC48NK/R/Et3W8qMC/Fq9tfBssA0Z1zFxU=" Sep 01 01:12:28 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12:28+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:12:28 stefans-speaker volumio[13396]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 01 01:12:28 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12:28+02:00" level=debug msg="completed keyexchange" Sep 01 01:12:28 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12:28+02:00" level=debug msg="completed challenge" Sep 01 01:12:28 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12:28+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:12:29 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12:29+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:12:29 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:12:29 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:12:30 stefans-speaker volumio[13396]: info: Initializing connection to go-librespot Websocket Sep 01 01:12:30 stefans-speaker volumio[13396]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 01 01:12:32 stefans-speaker volumio[13396]: info: Adding plugin bluetooth to MyMusic Plugins Sep 01 01:12:32 stefans-speaker volumio[13396]: info: Adding plugin multiroom to MyMusic Plugins Sep 01 01:12:32 stefans-speaker volumio[13396]: info: Adding plugin metavolumio to MyMusic Plugins Sep 01 01:12:32 stefans-speaker volumio[13396]: info: Adding plugin cd_controller to MyMusic Plugins Sep 01 01:12:32 stefans-speaker volumio[13396]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 01 01:12:32 stefans-speaker volumio[13396]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 01 01:12:32 stefans-speaker volumio[13396]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 01 01:12:32 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Sep 01 01:12:32 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:32 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:32 stefans-speaker go-librespot[13714]: go-librespot daemon starting... Sep 01 01:12:32 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12:32+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:12:32 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12:32+02:00" level=debug msg="app state loaded" Sep 01 01:12:32 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12:32+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:12:32 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 01:12:32 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12:32+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:12:32 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12:32+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:12:32 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12:32+02:00" level=info msg="zeroconf server listening on port 39627" Sep 01 01:12:32 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12:32+02:00" level=debug msg="obtained new client token: AAGtx91NHfUW0SN/GcZAj2iRgR94ZIdKFkyXjUIiZK+crPHEnZ4LNTPL5TUrgDtDkHUS2ITPZdqcKaUOxbmleRsIP/AMuSY2VOOqc0jboTpkOgVtsgW8K47R0TBBtmXCDMiJi/bApddegTTvQQqmj8qxYytbSkxyPjSmefCBKvGupeBP4H4YvJXOmMM9HqU+09Nd3X1KFRuUEK/CX/nZKXpt87MA90Vtzm9hxvuILYjBa2aJSraEYjAWDso=" Sep 01 01:12:32 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12:32+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:12:32 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12:32+02:00" level=debug msg="completed keyexchange" Sep 01 01:12:32 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12:32+02:00" level=debug msg="completed challenge" Sep 01 01:12:32 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12:32+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:12:33 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12:33+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:12:33 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:12:33 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:12:33 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 01 01:12:33 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 01 01:12:33 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:33 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:33 stefans-speaker volumio[13396]: info: Starting MyVolumio Remote Streaming Endpoints Sep 01 01:12:33 stefans-speaker volumio[13396]: info: MyVolumio login type: Token Sep 01 01:12:33 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 01 01:12:33 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 01 01:12:34 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 01 01:12:34 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 01 01:12:34 stefans-speaker volumio[13396]: info: Streaming services startup Sep 01 01:12:34 stefans-speaker volumio[13396]: info: Starting Streaming Daemon Sep 01 01:12:34 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 01 01:12:34 stefans-speaker sudo[13737]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:34 stefans-speaker sudo[13737]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 01:12:34 stefans-speaker sudo[13737]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:34 stefans-speaker sudo[13737]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:34 stefans-speaker volumio[13396]: info: Initializing connection to go-librespot Websocket Sep 01 01:12:34 stefans-speaker volumio[13396]: error: Cannot start Volumio Streaming Daemon Sep 01 01:12:34 stefans-speaker volumio[13396]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 01:12:34 stefans-speaker volumio[13396]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:12:34 stefans-speaker volumio[13396]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 01:12:34 stefans-speaker volumio[13396]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:12:34 stefans-speaker volumio[13396]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 01 01:12:35 stefans-speaker volumio[13396]: info: MyVolumio token set successfully Sep 01 01:12:35 stefans-speaker volumio[13396]: info: MYVOLUMIO: Adding device Sep 01 01:12:35 stefans-speaker volumio[13396]: info: MYVOLUMIO: Evaluating Server Sep 01 01:12:35 stefans-speaker volumio[13396]: info: MyVolumio status changed Sep 01 01:12:35 stefans-speaker volumio[13396]: info: Streaming services startup Sep 01 01:12:35 stefans-speaker volumio[13396]: info: Starting Streaming Daemon Sep 01 01:12:35 stefans-speaker volumio[13396]: info: Removing browser output: myVolumio user plan is not superstar Sep 01 01:12:35 stefans-speaker volumio[13396]: info: Removing audio output: Sep 01 01:12:35 stefans-speaker volumio[13396]: info: Stoppping Tunnel 1 Sep 01 01:12:35 stefans-speaker sudo[13765]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:35 stefans-speaker sudo[13765]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 01:12:35 stefans-speaker sudo[13765]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:35 stefans-speaker sudo[13767]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:35 stefans-speaker sudo[13767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 01 01:12:35 stefans-speaker sudo[13767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:35 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:12:35 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:12:35 stefans-speaker sudo[13765]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:35 stefans-speaker volumio[13396]: error: Cannot start Volumio Streaming Daemon Sep 01 01:12:35 stefans-speaker volumio[13396]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 01:12:35 stefans-speaker volumio[13396]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:12:35 stefans-speaker volumio[13396]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 01:12:35 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:12:35 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:12:35 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:12:35 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:12:35 stefans-speaker sudo[13767]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:35 stefans-speaker volumio[13396]: info: Remote SSH Stopped Sep 01 01:12:35 stefans-speaker volumio[13396]: info: Setting Geolocation for MyVolumio to eu5 Sep 01 01:12:35 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:35 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:35 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:36 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Sep 01 01:12:36 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:36 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:36 stefans-speaker go-librespot[13770]: go-librespot daemon starting... Sep 01 01:12:36 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12:36+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:12:36 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12:36+02:00" level=debug msg="app state loaded" Sep 01 01:12:36 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:12:36 stefans-speaker volumio[13396]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 01 01:12:36 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12: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-gae2.spotify.com:80]" Sep 01 01:12:36 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12:36+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:12:36 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12:36+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:12:36 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12:36+02:00" level=info msg="zeroconf server listening on port 44625" Sep 01 01:12:36 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12:36+02:00" level=debug msg="obtained new client token: AAGXRXUSkG+2r+m8s+O6Itkd75cq5MXRLo/mil+akZqlQdzxSBCFRKZhnl1AOnPu9KrEOpqBKu254GUlM6vMmuP1cFlI1IWtJv1XlqdOYEhTtsVbQqISlo0cMAQWgIIY0Bqd0pO0fLzousqgb07JhPNkilffObiQFk2b4nIZWcaNFnD8tm5aOloHGqlc2TswSRMVrv+2w4DAK/5NWqPTgQuGwgQD95DAGd6S7HzFspgMme//1yt9nk72nUI=" Sep 01 01:12:36 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12:36+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:12:36 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12:36+02:00" level=debug msg="completed keyexchange" Sep 01 01:12:36 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12:36+02:00" level=debug msg="completed challenge" Sep 01 01:12:36 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12:36+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:12:36 stefans-speaker volumio[13396]: info: Updating MyVolumio device info Sep 01 01:12:36 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:36 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:36 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:37 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12:37+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:12:37 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:12:37 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:12:37 stefans-speaker volumio[13396]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 01 01:12:37 stefans-speaker volumio[13396]: info: Initializing connection to go-librespot Websocket Sep 01 01:12:37 stefans-speaker volumio[13396]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:12:40 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Sep 01 01:12:40 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:40 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:40 stefans-speaker go-librespot[13778]: go-librespot daemon starting... Sep 01 01:12:40 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:40+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:12:40 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:40+02:00" level=debug msg="app state loaded" Sep 01 01:12:40 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:40+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:12:40 stefans-speaker volumio[13396]: info: MYVOLUMIO: Adding device Sep 01 01:12:40 stefans-speaker volumio[13396]: info: MYVOLUMIO: Evaluating Server Sep 01 01:12:40 stefans-speaker volumio[13396]: info: Initializing connection to go-librespot Websocket Sep 01 01:12:40 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:40+02:00" level=debug msg="new websocket client" Sep 01 01:12:40 stefans-speaker volumio[13396]: info: Connection to go-librespot Websocket established Sep 01 01:12:40 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12: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-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 01:12:40 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:40+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:12:40 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:40+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:12:40 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:40+02:00" level=info msg="zeroconf server listening on port 46111" Sep 01 01:12:41 stefans-speaker volumio[13396]: info: Setting Geolocation for MyVolumio to eu2 Sep 01 01:12:41 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:41 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:41 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:41 stefans-speaker volumio[13396]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 01 01:12:42 stefans-speaker volumio[13396]: info: Updating MyVolumio device info Sep 01 01:12:42 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:42 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:42 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:43 stefans-speaker volumio[13396]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 01 01:12:43 stefans-speaker volumio[13396]: info: Getting Spotify volume Sep 01 01:12:43 stefans-speaker volumio[13396]: 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:12:43 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioGetState Sep 01 01:12:43 stefans-speaker volumio[13396]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:43 stefans-speaker volumio[13396]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Sep 01 01:12:43 stefans-speaker volumio[13396]: SPOTIFY: SPOTIFY VOLUME undefined Sep 01 01:12:43 stefans-speaker volumio[13396]: SPOTIFY: VOLUMIO VOLUME 70 Sep 01 01:12:43 stefans-speaker volumio[13396]: info: Aligning Spotify Volume to Volumio Volume Sep 01 01:12:43 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioGetState Sep 01 01:12:43 stefans-speaker volumio[13396]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:43 stefans-speaker volumio[13396]: info: Setting Spotify Volume from Volumio: 70 Sep 01 01:12:45 stefans-speaker volumio[13396]: SPOTIFY: SETTING SPOTIFY VOLUME 70 Sep 01 01:12:45 stefans-speaker volumio[13396]: info: Sending Spotify command with payload to local API: /player/volume Sep 01 01:12:45 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:45+02:00" level=debug msg="obtained new client token: AAHurpmg362lM4gpKFfSy5fFPeZYbLZ3g2fSNbLOrKkaGLRAggJhXf2UIIimzSDJnmDzvhJ6ar8stpXSi57d4xByqMZuP+sXAUFHkABVdX05lyjXlXa2do4QeZt4Vq6NIZtqec4Ze2MWZbo19YdfeWmYo5RQPGiyEzXRaYbx1XkMTGtl3Gv39sPFHp9YRCg0bxFNw3/GHVImd3VP7z4yqdBIdiwJgIWv7b5uKGHg5NoubowSDfRzYW5zVCA=" Sep 01 01:12:45 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:45+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:12:45 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:45+02:00" level=debug msg="completed keyexchange" Sep 01 01:12:45 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:45+02:00" level=debug msg="completed challenge" Sep 01 01:12:45 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:45+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:12:46 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:46+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:12:46 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:12:46 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:12:46 stefans-speaker volumio[13396]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 01 01:12:46 stefans-speaker volumio[13396]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 01:12:46 stefans-speaker volumio[13396]: Error: socket hang up Sep 01 01:12:46 stefans-speaker volumio[13396]: at connResetException (node:internal/errors:720:14) Sep 01 01:12:46 stefans-speaker volumio[13396]: at Socket.socketOnEnd (node:_http_client:519:23) Sep 01 01:12:46 stefans-speaker volumio[13396]: at Socket.emit (node:events:526:35) Sep 01 01:12:46 stefans-speaker volumio[13396]: at endReadableNT (node:internal/streams/readable:1376:12) Sep 01 01:12:46 stefans-speaker volumio[13396]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Sep 01 01:12:46 stefans-speaker volumio[13396]: code: 'ECONNRESET', Sep 01 01:12:46 stefans-speaker volumio[13396]: response: undefined Sep 01 01:12:46 stefans-speaker volumio[13396]: } Sep 01 01:12:46 stefans-speaker volumio[13396]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 01:12:46 stefans-speaker sudo[13833]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:46 stefans-speaker sudo[13833]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-09-01 01:11' Sep 01 01:12:46 stefans-speaker sudo[13833]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="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"