Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 31 23:06:00 stefans-speaker volumio[26309]: info: Adding plugin bluetooth to MyMusic Plugins Aug 31 23:06:00 stefans-speaker volumio[26309]: info: Adding plugin multiroom to MyMusic Plugins Aug 31 23:06:00 stefans-speaker volumio[26309]: info: Adding plugin metavolumio to MyMusic Plugins Aug 31 23:06:00 stefans-speaker volumio[26309]: info: Adding plugin cd_controller to MyMusic Plugins Aug 31 23:06:00 stefans-speaker volumio[26309]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 31 23:06:00 stefans-speaker volumio[26309]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 31 23:06:00 stefans-speaker volumio[26309]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 31 23:06:00 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 31 23:06:01 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 31 23:06:01 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 31 23:06:01 stefans-speaker volumio[26309]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:01 stefans-speaker volumio[26309]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:01 stefans-speaker volumio[26309]: info: Starting MyVolumio Remote Streaming Endpoints Aug 31 23:06:01 stefans-speaker volumio[26309]: info: MyVolumio login type: Token Aug 31 23:06:01 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 31 23:06:01 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 31 23:06:02 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 31 23:06:02 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 31 23:06:02 stefans-speaker volumio[26309]: info: Streaming services startup Aug 31 23:06:02 stefans-speaker volumio[26309]: info: Starting Streaming Daemon Aug 31 23:06:02 stefans-speaker volumio[26309]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 31 23:06:02 stefans-speaker sudo[26644]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:02 stefans-speaker sudo[26644]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 31 23:06:02 stefans-speaker sudo[26644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:02 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Aug 31 23:06:02 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:02 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:02 stefans-speaker go-librespot[26650]: go-librespot daemon starting... Aug 31 23:06:02 stefans-speaker volumio[26309]: info: Getting Spotify volume Aug 31 23:06:02 stefans-speaker sudo[26644]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:02 stefans-speaker volumio[26309]: info: Initializing connection to go-librespot Websocket Aug 31 23:06:02 stefans-speaker go-librespot[26651]: time="2026-08-31T23:06:02+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:06:02 stefans-speaker go-librespot[26651]: time="2026-08-31T23:06:02+02:00" level=debug msg="app state loaded" Aug 31 23:06:02 stefans-speaker go-librespot[26651]: time="2026-08-31T23:06:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:06:02 stefans-speaker volumio[26309]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 23:06:02 stefans-speaker volumio[26309]: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 23:06:02 stefans-speaker volumio[26309]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Aug 31 23:06:02 stefans-speaker volumio[26309]: errno: -111, Aug 31 23:06:02 stefans-speaker volumio[26309]: code: 'ECONNREFUSED', Aug 31 23:06:02 stefans-speaker volumio[26309]: syscall: 'connect', Aug 31 23:06:02 stefans-speaker volumio[26309]: address: '127.0.0.1', Aug 31 23:06:02 stefans-speaker volumio[26309]: port: 9879, Aug 31 23:06:02 stefans-speaker volumio[26309]: response: undefined Aug 31 23:06:02 stefans-speaker volumio[26309]: } Aug 31 23:06:02 stefans-speaker volumio[26309]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 23:06:03 stefans-speaker go-librespot[26651]: time="2026-08-31T23:06: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 31 23:06:03 stefans-speaker go-librespot[26651]: time="2026-08-31T23:06:03+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 23:06:03 stefans-speaker go-librespot[26651]: time="2026-08-31T23:06:03+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 23:06:03 stefans-speaker go-librespot[26651]: time="2026-08-31T23:06:03+02:00" level=info msg="zeroconf server listening on port 41089" Aug 31 23:06:03 stefans-speaker go-librespot[26651]: time="2026-08-31T23:06:03+02:00" level=debug msg="obtained new client token: AAGFEo1CWs0vRLYqBbba3ePEQ8UWliKVtaoMN18gGzJzUETo/I1rzhhxz4kXjorZJF8SH7U1O2WtXAfny3pWfnADiZaf6j6u5ED4VBII618scMHV6NVlvTpt9EcdGNiwCeyyBodeHt6ODS4hU4wJyJSJttTXtUEu+NR5UQeubHmOm3oWva5+/FK17U16gSjjRxDHjgzylq2a55INGCslbq9tmj1LfX5du+vr7lJ/163dqhk9zC9rxx+w" Aug 31 23:06:03 stefans-speaker go-librespot[26651]: time="2026-08-31T23:06:03+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 23:06:03 stefans-speaker go-librespot[26651]: time="2026-08-31T23:06:03+02:00" level=debug msg="completed keyexchange" Aug 31 23:06:03 stefans-speaker go-librespot[26651]: time="2026-08-31T23:06:03+02:00" level=debug msg="completed challenge" Aug 31 23:06:03 stefans-speaker go-librespot[26651]: time="2026-08-31T23:06:03+02:00" level=info msg="authenticated AP" username="31************************cy" Aug 31 23:06:03 stefans-speaker go-librespot[26651]: time="2026-08-31T23:06:03+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 31 23:06:03 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:06:03 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 23:06:03 stefans-speaker sudo[26673]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:03 stefans-speaker sudo[26673]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-31 23:05' Aug 31 23:06:03 stefans-speaker sudo[26673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:03 stefans-speaker sudo[26673]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:03 stefans-speaker volumio[26309]: sudo: unable to resolve host stefans-speaker: System error Aug 31 23:06:03 stefans-speaker volumio-remote-updater[679]: [2026-08-31 23:06:03] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 31 23:06:03 stefans-speaker volumio-remote-updater[679]: [2026-08-31 23:06:03] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 31 23:06:03 stefans-speaker systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:06:03 stefans-speaker systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 31 23:06:03 stefans-speaker systemd[1]: volumio.service: Consumed 31.085s CPU time. Aug 31 23:06:03 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Aug 31 23:06:04 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Aug 31 23:06:04 stefans-speaker systemd[1]: volumio.service: Scheduled restart job, restart counter is at 120. Aug 31 23:06:04 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Aug 31 23:06:04 stefans-speaker systemd[1]: Stopped volumio.service - Volumio Backend Module. Aug 31 23:06:04 stefans-speaker systemd[1]: volumio.service: Consumed 31.085s CPU time. Aug 31 23:06:04 stefans-speaker systemd[1]: Started volumio.service - Volumio Backend Module. Aug 31 23:06:04 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Aug 31 23:06:06 stefans-speaker volumio[26685]: info: ------------------------------------------- Aug 31 23:06:06 stefans-speaker volumio[26685]: info: ----- Volumio3 ---- Aug 31 23:06:06 stefans-speaker volumio[26685]: info: ------------------------------------------- Aug 31 23:06:06 stefans-speaker volumio[26685]: info: ----- System startup ---- Aug 31 23:06:06 stefans-speaker volumio[26685]: info: ------------------------------------------- Aug 31 23:06:06 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Aug 31 23:06:06 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:06 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:06 stefans-speaker go-librespot[26706]: go-librespot daemon starting... Aug 31 23:06:06 stefans-speaker go-librespot[26707]: time="2026-08-31T23:06:06+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:06:06 stefans-speaker go-librespot[26707]: time="2026-08-31T23:06:06+02:00" level=debug msg="app state loaded" Aug 31 23:06:06 stefans-speaker go-librespot[26707]: time="2026-08-31T23:06:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:06:07 stefans-speaker volumio[26685]: info: MYVOLUMIO Environment detected Aug 31 23:06:07 stefans-speaker go-librespot[26707]: time="2026-08-31T23:06: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 31 23:06:07 stefans-speaker go-librespot[26707]: time="2026-08-31T23:06:07+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 23:06:07 stefans-speaker go-librespot[26707]: time="2026-08-31T23:06:07+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 23:06:07 stefans-speaker go-librespot[26707]: time="2026-08-31T23:06:07+02:00" level=info msg="zeroconf server listening on port 37839" Aug 31 23:06:07 stefans-speaker volumio[26685]: info: Plugin folders cleanup Aug 31 23:06:07 stefans-speaker volumio[26685]: info: Scanning into folder /volumio/app/plugins/ Aug 31 23:06:07 stefans-speaker volumio[26685]: info: Scanning category audio_interface Aug 31 23:06:07 stefans-speaker volumio[26685]: info: Scanning category miscellanea Aug 31 23:06:07 stefans-speaker volumio[26685]: info: Scanning category music_service Aug 31 23:06:07 stefans-speaker volumio[26685]: info: Scanning category plugins.json Aug 31 23:06:07 stefans-speaker volumio[26685]: info: Scanning category system_controller Aug 31 23:06:07 stefans-speaker volumio[26685]: info: Scanning category user_interface Aug 31 23:06:07 stefans-speaker volumio[26685]: info: Scanning into folder /data/plugins/ Aug 31 23:06:07 stefans-speaker volumio[26685]: info: Scanning category music_service Aug 31 23:06:07 stefans-speaker volumio[26685]: info: Plugin folders cleanup completed Aug 31 23:06:07 stefans-speaker volumio[26685]: info: ------------------------------------------- Aug 31 23:06:07 stefans-speaker volumio[26685]: info: ----- Core plugins startup ---- Aug 31 23:06:07 stefans-speaker volumio[26685]: info: ------------------------------------------- Aug 31 23:06:07 stefans-speaker volumio[26685]: info: Loading plugins from folder /volumio/app/plugins/ Aug 31 23:06:07 stefans-speaker volumio[26685]: info: Adding plugin upnp to MyMusic Plugins Aug 31 23:06:07 stefans-speaker volumio[26685]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 31 23:06:07 stefans-speaker volumio[26685]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 31 23:06:07 stefans-speaker volumio[26685]: info: Loading plugins from folder /data/plugins/ Aug 31 23:06:07 stefans-speaker volumio[26685]: info: Loading plugin "system"... Aug 31 23:06:07 stefans-speaker go-librespot[26707]: time="2026-08-31T23:06:07+02:00" level=debug msg="obtained new client token: AAGpflAoRnZ+BwP0scknh8zfQtInAOIauJ1Ea/Z3NAuje2NaWLtZZ+ilD3E+dckfM5XiAJBGw+7OMbVVaXvtnNKwmgoBMAOwyeaQuef8h2Wkl60x9wE7LVZH+1y0D2zmVEoTo1kC7EGeil4CteNrU7De8mu8ehu1CZe0wnhZKUhFZYWephOSv4qMvgqnfzBvLbnHdDxbAPQ3ZUhEOPB7P8gIg3OGZnFcCMw1NGilM8mrl16GU+F9tQH1U4E=" Aug 31 23:06:07 stefans-speaker volumio[26685]: info: Loading plugin "appearance"... Aug 31 23:06:07 stefans-speaker go-librespot[26707]: time="2026-08-31T23:06:07+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 23:06:07 stefans-speaker go-librespot[26707]: time="2026-08-31T23:06:07+02:00" level=debug msg="completed keyexchange" Aug 31 23:06:07 stefans-speaker go-librespot[26707]: time="2026-08-31T23:06:07+02:00" level=debug msg="completed challenge" Aug 31 23:06:07 stefans-speaker go-librespot[26707]: time="2026-08-31T23:06:07+02:00" level=info msg="authenticated AP" username="31************************cy" Aug 31 23:06:07 stefans-speaker go-librespot[26707]: time="2026-08-31T23:06:07+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 31 23:06:07 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:06:07 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 23:06:08 stefans-speaker volumio[26685]: info: Loading plugin "network"... Aug 31 23:06:08 stefans-speaker volumio[26685]: info: Refreshing Cached IP Addresses Aug 31 23:06:08 stefans-speaker sudo[26722]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:08 stefans-speaker sudo[26724]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:08 stefans-speaker volumio[26685]: info: Loading plugin "services"... Aug 31 23:06:08 stefans-speaker sudo[26722]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 23:06:08 stefans-speaker sudo[26724]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 23:06:08 stefans-speaker sudo[26724]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:08 stefans-speaker sudo[26722]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:08 stefans-speaker volumio[26685]: info: Loading plugin "alsa_controller"... Aug 31 23:06:08 stefans-speaker sudo[26731]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:08 stefans-speaker sudo[26722]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:08 stefans-speaker sudo[26731]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 31 23:06:08 stefans-speaker sudo[26724]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:08 stefans-speaker sudo[26731]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:08 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 23:06:08 stefans-speaker volumio[26685]: info: Loading plugin "wizard"... Aug 31 23:06:08 stefans-speaker volumio[26685]: info: Loading plugin "networkfs"... Aug 31 23:06:08 stefans-speaker volumio[26685]: info: Starting Udev Watcher for removable devices Aug 31 23:06:08 stefans-speaker volumio[26685]: info: Ignoring mount for partition: boot Aug 31 23:06:08 stefans-speaker volumio[26685]: info: Ignoring mount for partition: volumio Aug 31 23:06:08 stefans-speaker volumio[26685]: info: Ignoring mount for partition: volumio_data Aug 31 23:06:08 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 23:06:08 stefans-speaker volumio[26685]: info: Loading plugin "volumio_command_line_client"... Aug 31 23:06:08 stefans-speaker volumio[26685]: info: Loading plugin "upnp"... Aug 31 23:06:08 stefans-speaker volumio[26685]: info: [1788210368670] Starting Upmpd Daemon Aug 31 23:06:08 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 23:06:08 stefans-speaker volumio[26685]: info: Loading plugin "my_music"... Aug 31 23:06:08 stefans-speaker volumio[26685]: info: Loading plugin "mpd"... Aug 31 23:06:08 stefans-speaker volumio-remote-updater[679]: [2026-08-31 23:06:08] [connect] Successful connection Aug 31 23:06:09 stefans-speaker volumio[26685]: info: Loading plugin "upnp_browser"... Aug 31 23:06:10 stefans-speaker volumio[26685]: info: Starting UPNP Browser Aug 31 23:06:10 stefans-speaker volumio[26685]: info: Loading plugin "alarm-clock"... Aug 31 23:06:10 stefans-speaker volumio[26685]: info: Loading plugin "airplay_emulation"... Aug 31 23:06:10 stefans-speaker volumio[26685]: info: Starting Shairport Sync Aug 31 23:06:10 stefans-speaker volumio[26685]: info: Loading plugin "last_100"... Aug 31 23:06:10 stefans-speaker volumio[26685]: info: Loading plugin "webradio"... Aug 31 23:06:10 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Aug 31 23:06:10 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:10 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:10 stefans-speaker go-librespot[26755]: go-librespot daemon starting... Aug 31 23:06:10 stefans-speaker volumio[26685]: info: Loading plugin "i2s_dacs"... Aug 31 23:06:10 stefans-speaker go-librespot[26756]: time="2026-08-31T23:06:10+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:06:10 stefans-speaker go-librespot[26756]: time="2026-08-31T23:06:10+02:00" level=debug msg="app state loaded" Aug 31 23:06:10 stefans-speaker volumio[26685]: info: I2S DAC not set, start Auto-detection Aug 31 23:06:10 stefans-speaker go-librespot[26756]: time="2026-08-31T23:06:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:06:10 stefans-speaker volumio[26685]: info: Loading plugin "volumiodiscovery"... Aug 31 23:06:10 stefans-speaker volumio[26685]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 31 23:06:10 stefans-speaker volumio[26685]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 23:06:10 stefans-speaker volumio[26685]: *** WARNING *** For more information see Aug 31 23:06:10 stefans-speaker volumio[26685]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 31 23:06:10 stefans-speaker volumio[26685]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 23:06:10 stefans-speaker volumio[26685]: *** WARNING *** For more information see Aug 31 23:06:10 stefans-speaker node[26685]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 31 23:06:10 stefans-speaker node[26685]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 23:06:10 stefans-speaker node[26685]: *** WARNING *** For more information see Aug 31 23:06:10 stefans-speaker node[26685]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 31 23:06:10 stefans-speaker node[26685]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 23:06:10 stefans-speaker node[26685]: *** WARNING *** For more information see Aug 31 23:06:10 stefans-speaker volumio[26685]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 31 23:06:10 stefans-speaker volumio[26685]: info: Discovery: Started advertising with name: Stefan’s Speaker Aug 31 23:06:10 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 23:06:10 stefans-speaker volumio[26685]: info: Loading plugin "spop"... Aug 31 23:06:11 stefans-speaker go-librespot[26756]: time="2026-08-31T23:06: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]" Aug 31 23:06:11 stefans-speaker go-librespot[26756]: time="2026-08-31T23:06: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]" Aug 31 23:06:11 stefans-speaker go-librespot[26756]: time="2026-08-31T23:06: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]" Aug 31 23:06:11 stefans-speaker go-librespot[26756]: time="2026-08-31T23:06:11+02:00" level=info msg="zeroconf server listening on port 39455" Aug 31 23:06:11 stefans-speaker go-librespot[26756]: time="2026-08-31T23:06:11+02:00" level=debug msg="obtained new client token: AAFgokCJHHRMXeYz6r9Eur5Ehzkpv7cU915FvcqcRj/2T55Ig82KN3lX1o3Y7OnSWem+7DKOW3OmJoTUNNxePUJKFiGI7S3QIQbipN5RkORLkJ/bVw5uJuhCowB4O+1g/bJWDSMDh+1SQm43Iw753hl9n0aBh0uA/Zf8iSR8q3F2BetC7x5N0shENkc0rR9yhVg9kkw5SKFz3UyIpP1rT09fS87wepSlhNJQYt4zlvLJxRuLkde6PLOThHc=" Aug 31 23:06:11 stefans-speaker sudo[26731]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:11 stefans-speaker go-librespot[26756]: time="2026-08-31T23:06:11+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 23:06:11 stefans-speaker go-librespot[26756]: time="2026-08-31T23:06:11+02:00" level=debug msg="completed keyexchange" Aug 31 23:06:11 stefans-speaker go-librespot[26756]: time="2026-08-31T23:06:11+02:00" level=debug msg="completed challenge" Aug 31 23:06:11 stefans-speaker go-librespot[26756]: time="2026-08-31T23:06:11+02:00" level=info msg="authenticated AP" username="31************************cy" Aug 31 23:06:12 stefans-speaker go-librespot[26756]: time="2026-08-31T23:06:12+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 31 23:06:12 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:06:12 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 23:06:12 stefans-speaker volumio[26685]: info: Loading plugin "ytcr"... Aug 31 23:06:13 stefans-speaker volumio[26685]: info: Loading plugin "outputs"... Aug 31 23:06:13 stefans-speaker volumio[26685]: info: Loading plugin "albumart"... Aug 31 23:06:13 stefans-speaker volumio[26685]: info: Plugin example_plugin is not enabled Aug 31 23:06:13 stefans-speaker volumio[26685]: info: Loading plugin "inputs"... Aug 31 23:06:13 stefans-speaker volumio[26685]: info: Loading plugin "updater_comm"... Aug 31 23:06:13 stefans-speaker volumio[26685]: info: Plugin mpdemulation is not enabled Aug 31 23:06:13 stefans-speaker volumio[26685]: info: Loading plugin "rest_api"... Aug 31 23:06:13 stefans-speaker volumio[26685]: info: Loading plugin "websocket"... Aug 31 23:06:13 stefans-speaker volumio[26685]: info: Starting Socket.io Server version 1.7.4 Aug 31 23:06:13 stefans-speaker volumio[26685]: info: Loading i18n strings for locale nl Aug 31 23:06:13 stefans-speaker volumio[26685]: Updating browse sources language Aug 31 23:06:13 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 23:06:13 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 23:06:13 stefans-speaker volumio[26685]: info: CoreCommandRouter::initPlayerControls Aug 31 23:06:13 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:13 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:13 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:13 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:13 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:13 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:13 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:13 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:13 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 23:06:13 stefans-speaker volumio[26685]: Express server listening on port 3000 Aug 31 23:06:13 stefans-speaker volumio[26685]: [Metrics] WebUI: 8s 173.84ms Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreStateMachine::resetVolumioState Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreStateMachine::getcurrentVolume Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreCommandRouter::volumioRetrievevolume Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:14 stefans-speaker volumio[26685]: info: Cannot read play queue from file Aug 31 23:06:14 stefans-speaker volumio[26685]: info: Volumio Network Manager: Network status updated: 2 Aug 31 23:06:14 stefans-speaker volumio[26767]: Forking 3 albumart workers Aug 31 23:06:14 stefans-speaker volumio[26685]: info: VolumeController:: Volume=70 Mute =false Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreStateMachine::pushState Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CorePlayQueue::getTrack 0 Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreCommandRouter::volumioPushState Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreStateMachine::updateTrackBlock Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CorePlayQueue::getTrackBlock Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreCommandRouter::volumioRetrievevolume Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreStateMachine::setRepeat null single undefined Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreStateMachine::pushState Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CorePlayQueue::getTrack 0 Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreCommandRouter::volumioPushState Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreStateMachine::setRandom null Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreStateMachine::pushState Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CorePlayQueue::getTrack 0 Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreCommandRouter::volumioPushState Aug 31 23:06:14 stefans-speaker volumio[26685]: info: Setting Device type: Raspberry PI Aug 31 23:06:14 stefans-speaker volumio[26685]: info: VolumeController:: Volume=70 Mute =false Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreStateMachine::pushState Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CorePlayQueue::getTrack 0 Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreCommandRouter::volumioPushState Aug 31 23:06:14 stefans-speaker volumio[26685]: info: Completed loading Core Plugins Aug 31 23:06:14 stefans-speaker volumio[26685]: info: Preparing to generate the ALSA configuration file Aug 31 23:06:14 stefans-speaker volumio[26685]: info: Discovery: adding a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Aug 31 23:06:14 stefans-speaker volumio[26685]: info: Discovery: Found device Stefan’s Speaker Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreCommandRouter::volumioGetState Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CorePlayQueue::getTrack 0 Aug 31 23:06:14 stefans-speaker volumio[26685]: info: Asound.conf file unchanged, so no further update is needed Aug 31 23:06:14 stefans-speaker volumio[26685]: info: Output device has changed, restarting MPD Aug 31 23:06:14 stefans-speaker volumio[26685]: info: Output device has changed, restarting Shairport Sync Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:14 stefans-speaker sudo[26823]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:14 stefans-speaker sudo[26824]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:14 stefans-speaker sudo[26823]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 23:06:14 stefans-speaker sudo[26823]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:14 stefans-speaker volumio[26685]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 23:06:14 stefans-speaker volumio[26685]: info: ___________ START PLUGINS ___________ Aug 31 23:06:14 stefans-speaker sudo[26823]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:14 stefans-speaker sudo[26824]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 23:06:14 stefans-speaker sudo[26824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:14 stefans-speaker volumio[26685]: info: ControllerMpd::onStart: Initializing MPD Aug 31 23:06:14 stefans-speaker volumio[26685]: info: Creating MPD Configuration file Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 23:06:14 stefans-speaker volumio[26685]: info: [1788210374838] CoreMusicLibrary::Adding element Media Servers Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 23:06:14 stefans-speaker systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 31 23:06:14 stefans-speaker sudo[26834]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:14 stefans-speaker sudo[26834]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 23:06:14 stefans-speaker sudo[26834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:14 stefans-speaker volumio[26685]: info: UPNP Browser: Client initialized successfully Aug 31 23:06:14 stefans-speaker sudo[26832]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:14 stefans-speaker sudo[26832]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 23:06:14 stefans-speaker sudo[26832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:14 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:14 stefans-speaker sudo[26832]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:14 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Aug 31 23:06:14 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 31 23:06:14 stefans-speaker systemd[1]: mpd.service: Consumed 4.529s CPU time. Aug 31 23:06:14 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Aug 31 23:06:14 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 31 23:06:14 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 31 23:06:15 stefans-speaker volumio[26685]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 23:06:15 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:15 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:15 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 23:06:15 stefans-speaker volumio[26685]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 23:06:15 stefans-speaker volumio[26685]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 23:06:15 stefans-speaker volumio[26685]: info: [1788210375064] CoreMusicLibrary::Adding element Last_100 Aug 31 23:06:15 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 23:06:15 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 23:06:15 stefans-speaker volumio[26685]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 23:06:15 stefans-speaker volumio[26685]: info: [1788210375084] CoreMusicLibrary::Adding element Webradio Aug 31 23:06:15 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 23:06:15 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 23:06:15 stefans-speaker volumio[26685]: info: Initializing BBC Radios Aug 31 23:06:15 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 23:06:15 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:15 stefans-speaker volumio[26685]: info: Creating Spotify config file Aug 31 23:06:15 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:15 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Aug 31 23:06:15 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:15 stefans-speaker sudo[26846]: root : unable to resolve host stefans-speaker: System error Aug 31 23:06:15 stefans-speaker sudo[26846]: sudo: unable to resolve host stefans-speaker: System error Aug 31 23:06:15 stefans-speaker sudo[26846]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 23:06:15 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:15 stefans-speaker go-librespot[26860]: go-librespot daemon starting... Aug 31 23:06:15 stefans-speaker sudo[26846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 23:06:15 stefans-speaker sudo[26846]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:15 stefans-speaker go-librespot[26863]: time="2026-08-31T23:06:15+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:06:15 stefans-speaker volumio[26685]: info: Volumio Calling Home Aug 31 23:06:15 stefans-speaker go-librespot[26863]: time="2026-08-31T23:06:15+02:00" level=info msg="zeroconf server listening on port 36781" Aug 31 23:06:16 stefans-speaker volumio[26685]: info: Discovery: this is already registered, a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Aug 31 23:06:16 stefans-speaker volumio[26685]: info: Discovery: Found device Stefan’s Speaker Aug 31 23:06:16 stefans-speaker volumio[26685]: info: CoreCommandRouter::volumioGetState Aug 31 23:06:16 stefans-speaker volumio[26685]: info: CorePlayQueue::getTrack 0 Aug 31 23:06:16 stefans-speaker volumio[26685]: info: MPD Permissions set Aug 31 23:06:16 stefans-speaker volumio[26685]: info: MPD Permissions set Aug 31 23:06:16 stefans-speaker volumio[26685]: info: Spotify config file written Aug 31 23:06:16 stefans-speaker volumio[26784]: Starting albumart workers Aug 31 23:06:16 stefans-speaker sudo[26877]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:16 stefans-speaker sudo[26877]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 31 23:06:16 stefans-speaker sudo[26877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:16 stefans-speaker volumio[26685]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Aug 31 23:06:16 stefans-speaker volumio[26685]: info: Volumio called home Aug 31 23:06:16 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:16 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:16 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:16 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:16 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:16 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:16 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:16 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:16 stefans-speaker systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Aug 31 23:06:16 stefans-speaker systemd[1]: go-librespot-daemon.service: Deactivated successfully. Aug 31 23:06:16 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:16 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:16 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:16 stefans-speaker volumio[26785]: Starting albumart workers Aug 31 23:06:16 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:16 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:16 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:16 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:16 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:16 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:16 stefans-speaker go-librespot[26884]: go-librespot daemon starting... Aug 31 23:06:16 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:16 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:16 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:16 stefans-speaker sudo[26877]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:16 stefans-speaker go-librespot[26885]: time="2026-08-31T23:06:16+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:06:16 stefans-speaker go-librespot[26885]: time="2026-08-31T23:06:16+02:00" level=debug msg="app state loaded" Aug 31 23:06:16 stefans-speaker go-librespot[26885]: time="2026-08-31T23:06:16+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:06:16 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:16 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:16 stefans-speaker volumio[26685]: info: No need to fix Spotify hosts Aug 31 23:06:16 stefans-speaker go-librespot[26885]: time="2026-08-31T23:06:16+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 31 23:06:16 stefans-speaker go-librespot[26885]: time="2026-08-31T23:06:16+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 31 23:06:16 stefans-speaker go-librespot[26885]: time="2026-08-31T23:06:16+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 31 23:06:16 stefans-speaker go-librespot[26885]: time="2026-08-31T23:06:16+02:00" level=info msg="zeroconf server listening on port 46801" Aug 31 23:06:17 stefans-speaker volumio[26783]: Starting albumart workers Aug 31 23:06:17 stefans-speaker go-librespot[26885]: time="2026-08-31T23:06:17+02:00" level=debug msg="obtained new client token: AAFYfx7yLg2J6MKvBe0Uv19wWS9FIrBBtYgkCflY5GbC49xEzS2PjjKmx0LNktAFA4eq2FTlkfDjbH7RIdIMEAoZLNX1CkZaRb/Li5S+UDZ2Dk3vqTGZKLZIBJ7k06gUkjDcw10CAf65Or3EklLW8oJ+81BALF8dMAH6SdfYgfxe+8POogYADu6Z45lb6KFFzRKQPr1ba3b/zWtTEDkrNrqy2Gm4sfSq1hOwKj2m9motrWCf3k8TLATj" Aug 31 23:06:17 stefans-speaker go-librespot[26885]: time="2026-08-31T23:06:17+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 23:06:17 stefans-speaker go-librespot[26885]: time="2026-08-31T23:06:17+02:00" level=debug msg="completed keyexchange" Aug 31 23:06:17 stefans-speaker go-librespot[26885]: time="2026-08-31T23:06:17+02:00" level=debug msg="completed challenge" Aug 31 23:06:17 stefans-speaker go-librespot[26885]: time="2026-08-31T23:06:17+02:00" level=info msg="authenticated AP" username="31************************cy" Aug 31 23:06:17 stefans-speaker go-librespot[26885]: time="2026-08-31T23:06:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 31 23:06:17 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:06:17 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 23:06:17 stefans-speaker volumio[26685]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Aug 31 23:06:17 stefans-speaker volumio[26685]: SPOTIFY: BQCIil8dFZqt0_XVV1oOLq2ObCHbtJXKg8xewo96dO7AsK6MQblPWSynJkH9sdAnZMxy96im5Qsg6rP9789U6WjW0BGHHBX_HX0Nf-TazBMMA35MPBo41uKs3p88DpvW38A1Q3i3lDGBlP_mrc4vXLcJ7ZfHndhUwoWTIB5iAFnk3BXIun9iLasd1x0sihz8e7nU-FNpHksM1LCZ6uAQcPhfauGV0FPd-ycvPJmueozOuxN90hWupubPJl3iuZK_KK62-DdEiuaJzAttt1e0WMziAyrtPMzQG1vaYwcqCSiOPi8-g0LEO6PsX9Yxyus Aug 31 23:06:17 stefans-speaker volumio[26685]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Aug 31 23:06:17 stefans-speaker volumio[26685]: info: New Spotify access token = BQCIil8dFZqt0_XVV1oOLq2ObCHbtJXKg8xewo96dO7AsK6MQblPWSynJkH9sdAnZMxy96im5Qsg6rP9789U6WjW0BGHHBX_HX0Nf-TazBMMA35MPBo41uKs3p88DpvW38A1Q3i3lDGBlP_mrc4vXLcJ7ZfHndhUwoWTIB5iAFnk3BXIun9iLasd1x0sihz8e7nU-FNpHksM1LCZ6uAQcPhfauGV0FPd-ycvPJmueozOuxN90hWupubPJl3iuZK_KK62-DdEiuaJzAttt1e0WMziAyrtPMzQG1vaYwcqCSiOPi8-g0LEO6PsX9Yxyus Aug 31 23:06:17 stefans-speaker volumio[26685]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 31 23:06:17 stefans-speaker volumio[26685]: info: Starting Shairport Sync Aug 31 23:06:17 stefans-speaker volumio[26685]: info: Starting Shairport Sync Aug 31 23:06:17 stefans-speaker volumio[26685]: info: Starting Shairport Sync Aug 31 23:06:17 stefans-speaker sudo[26906]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:17 stefans-speaker sudo[26908]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:17 stefans-speaker sudo[26906]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 23:06:17 stefans-speaker sudo[26906]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:17 stefans-speaker sudo[26908]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 23:06:17 stefans-speaker sudo[26908]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:17 stefans-speaker sudo[26910]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:17 stefans-speaker sudo[26910]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 23:06:17 stefans-speaker sudo[26910]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:17 stefans-speaker volumio[26685]: info: CoreCommandRouter::volumioGetState Aug 31 23:06:17 stefans-speaker volumio[26685]: info: CorePlayQueue::getTrack 0 Aug 31 23:06:18 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 31 23:06:18 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Aug 31 23:06:18 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 23:06:18 stefans-speaker systemd[1]: shairport-sync.service: Consumed 1.818s CPU time. Aug 31 23:06:18 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 23:06:18 stefans-speaker sudo[26906]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:18 stefans-speaker sudo[26908]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:18 stefans-speaker sudo[26910]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:18 stefans-speaker volumio[26685]: info: Shairport-Sync Started Aug 31 23:06:18 stefans-speaker volumio[26685]: Error adding Membership: Error: addMembership EINVAL Aug 31 23:06:18 stefans-speaker volumio[26685]: info: Shairport-Sync Started Aug 31 23:06:18 stefans-speaker volumio[26685]: info: Shairport-Sync Started Aug 31 23:06:18 stefans-speaker volumio[26685]: 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"} Aug 31 23:06:18 stefans-speaker volumio[26685]: info: Spotify Successfully logged in Aug 31 23:06:18 stefans-speaker volumio[26685]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 23:06:18 stefans-speaker volumio[26685]: info: [1788210378220] CoreMusicLibrary::Adding element Spotify Aug 31 23:06:18 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 23:06:18 stefans-speaker volumio[26685]: Cannot find translation for source Spotify Aug 31 23:06:18 stefans-speaker sudo[26957]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:18 stefans-speaker sudo[26958]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:18 stefans-speaker sudo[26958]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 23:06:18 stefans-speaker sudo[26958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:18 stefans-speaker sudo[26957]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 23:06:18 stefans-speaker sudo[26957]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:18 stefans-speaker volumio[26685]: info: [yt-cast-receiver] DIAL server listening on port 8098 Aug 31 23:06:18 stefans-speaker volumio[26685]: info: CoreCommandRouter::volumioRetrievevolume Aug 31 23:06:18 stefans-speaker sudo[26958]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:18 stefans-speaker sudo[26957]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:19 stefans-speaker volumio[26685]: info: VolumeController:: Volume=70 Mute =false Aug 31 23:06:19 stefans-speaker volumio[26685]: info: CoreCommandRouter::volumioGetState Aug 31 23:06:19 stefans-speaker volumio[26685]: info: CorePlayQueue::getTrack 0 Aug 31 23:06:19 stefans-speaker volumio[26685]: info: CoreStateMachine::pushState Aug 31 23:06:19 stefans-speaker volumio[26685]: info: CorePlayQueue::getTrack 0 Aug 31 23:06:19 stefans-speaker volumio[26685]: info: CoreCommandRouter::volumioPushState Aug 31 23:06:19 stefans-speaker sudo[26964]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:19 stefans-speaker sudo[26964]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 31 23:06:19 stefans-speaker sudo[26964]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:19 stefans-speaker sudo[26964]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:19 stefans-speaker volumio[26685]: info: Upmpdcli Daemon Started Aug 31 23:06:20 stefans-speaker mpd[26870]: 2026-08-31T23:06:20 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 23:06:20 stefans-speaker systemd[1]: Started mpd.service - Music Player Daemon. Aug 31 23:06:20 stefans-speaker sudo[26834]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:20 stefans-speaker sudo[26824]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:20 stefans-speaker volumio[26685]: error: MPD error: The expression evaluated to a falsy value: Aug 31 23:06:20 stefans-speaker volumio[26685]: assert.ok(self.idling) Aug 31 23:06:20 stefans-speaker volumio[26685]: error: The expression evaluated to a falsy value: Aug 31 23:06:20 stefans-speaker volumio[26685]: assert.ok(self.idling) Aug 31 23:06:20 stefans-speaker volumio[26685]: info: MPD running with PID26870 Aug 31 23:06:20 stefans-speaker volumio[26685]: ,establishing connection Aug 31 23:06:20 stefans-speaker volumio[26685]: error: updateQueue error: null Aug 31 23:06:20 stefans-speaker volumio[26685]: info: Completed starting Core Plugins Aug 31 23:06:20 stefans-speaker volumio[26685]: info: ------------------------------------------- Aug 31 23:06:20 stefans-speaker volumio[26685]: info: ----- MyVolumio plugins startup ---- Aug 31 23:06:20 stefans-speaker volumio[26685]: info: ------------------------------------------- Aug 31 23:06:20 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 31 23:06:20 stefans-speaker volumio[26685]: error: updateQueue error: null Aug 31 23:06:20 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Aug 31 23:06:20 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:20 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:20 stefans-speaker go-librespot[26971]: go-librespot daemon starting... Aug 31 23:06:20 stefans-speaker go-librespot[26972]: time="2026-08-31T23:06:20+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:06:20 stefans-speaker go-librespot[26972]: time="2026-08-31T23:06:20+02:00" level=debug msg="app state loaded" Aug 31 23:06:20 stefans-speaker go-librespot[26972]: time="2026-08-31T23:06:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:06:20 stefans-speaker volumio[26685]: info: go-librespot daemon successfully initialized Aug 31 23:06:20 stefans-speaker go-librespot[26972]: time="2026-08-31T23:06: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 31 23:06:20 stefans-speaker go-librespot[26972]: time="2026-08-31T23:06:20+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 23:06:20 stefans-speaker go-librespot[26972]: time="2026-08-31T23:06:20+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 23:06:20 stefans-speaker go-librespot[26972]: time="2026-08-31T23:06:20+02:00" level=info msg="zeroconf server listening on port 44491" Aug 31 23:06:20 stefans-speaker go-librespot[26972]: time="2026-08-31T23:06:20+02:00" level=debug msg="obtained new client token: AAHgcflCjoWXXxLMDgzZKlsR771c+O0wR0AVC0tgXZR3lqWIgYI2ttYPWBEHhGYfX2JpQoiyM4NjD54bBt9Ol2UQmELtptJAWL359t++YeEGxAVlcJvPeS8sBPygL2RMCnJnKXWX+MFkNSKKfniZwaRuhEojGr2lmxjC0nCiJJfG4U3F058vQu6XuJupmuX2o1dytGCEfr0WelY8LZscbrHRTXPYjsjajU+J4t1uAbUi19VkIswL6DPPqbs=" Aug 31 23:06:21 stefans-speaker go-librespot[26972]: time="2026-08-31T23:06:21+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 23:06:21 stefans-speaker go-librespot[26972]: time="2026-08-31T23:06:21+02:00" level=debug msg="completed keyexchange" Aug 31 23:06:21 stefans-speaker go-librespot[26972]: time="2026-08-31T23:06:21+02:00" level=debug msg="completed challenge" Aug 31 23:06:21 stefans-speaker go-librespot[26972]: time="2026-08-31T23:06:21+02:00" level=info msg="authenticated AP" username="31************************cy" Aug 31 23:06:21 stefans-speaker go-librespot[26972]: time="2026-08-31T23:06:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 31 23:06:21 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:06:21 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 23:06:21 stefans-speaker volumio-remote-updater[679]: [2026-08-31 23:06:21] [connect] Successful connection Aug 31 23:06:21 stefans-speaker volumio-remote-updater[679]: [2026-08-31 23:06:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788210381 101 Aug 31 23:06:21 stefans-speaker volumio[26685]: 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 Aug 31 23:06:23 stefans-speaker sudo[26982]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:23 stefans-speaker sudo[26984]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:23 stefans-speaker sudo[26982]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Aug 31 23:06:23 stefans-speaker sudo[26982]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:23 stefans-speaker sudo[26984]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Aug 31 23:06:23 stefans-speaker sudo[26984]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:23 stefans-speaker systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Aug 31 23:06:23 stefans-speaker systemd[1]: mpd_monitor.service: Deactivated successfully. Aug 31 23:06:23 stefans-speaker systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Aug 31 23:06:23 stefans-speaker systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Aug 31 23:06:23 stefans-speaker sudo[26982]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:23 stefans-speaker sudo[26984]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:23 stefans-speaker volumio[26685]: info: Successfully started MPD Monitor Aug 31 23:06:23 stefans-speaker mpd_monitor.sh[26987]: MPD Monitor Service: Starting MPD Monitor Service Aug 31 23:06:23 stefans-speaker volumio[26685]: info: Successfully started MPD Monitor Aug 31 23:06:23 stefans-speaker volumio[26685]: info: Initializing connection to go-librespot Websocket Aug 31 23:06:23 stefans-speaker volumio[26685]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 23:06:24 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Aug 31 23:06:24 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:24 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:24 stefans-speaker go-librespot[26991]: go-librespot daemon starting... Aug 31 23:06:24 stefans-speaker go-librespot[26992]: time="2026-08-31T23:06:24+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:06:24 stefans-speaker go-librespot[26992]: time="2026-08-31T23:06:24+02:00" level=debug msg="app state loaded" Aug 31 23:06:24 stefans-speaker go-librespot[26992]: time="2026-08-31T23:06:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:06:24 stefans-speaker go-librespot[26992]: time="2026-08-31T23:06:24+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 31 23:06:24 stefans-speaker go-librespot[26992]: time="2026-08-31T23:06:24+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 31 23:06:24 stefans-speaker go-librespot[26992]: time="2026-08-31T23:06:24+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 31 23:06:24 stefans-speaker go-librespot[26992]: time="2026-08-31T23:06:24+02:00" level=info msg="zeroconf server listening on port 37885" Aug 31 23:06:24 stefans-speaker go-librespot[26992]: time="2026-08-31T23:06:24+02:00" level=debug msg="obtained new client token: AAH9wdklC4DcpI2cOqGp7CLBKV5mspzGyiSgSBRW5YdSsIRLTxcTFXFARCSnKBnx+kDEH41IhSPILmAww7LBKn5sBWbp7KPwVPIBFx3VvyACbAiX9cNeuuO/XaT9M6DjMNnVH4MXuCwxDzCfhAGxJn2Kn3UHNvTTqQdmJq/Q69GTKbLkZ6jB/af617twUTM/SKS7uapJ2D3qFZuVYMb8fK1aQeJY71bezkRQwH2l+StW1tpVGHH7AgqChWY=" Aug 31 23:06:24 stefans-speaker go-librespot[26992]: time="2026-08-31T23:06:24+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 23:06:24 stefans-speaker go-librespot[26992]: time="2026-08-31T23:06:24+02:00" level=debug msg="completed keyexchange" Aug 31 23:06:24 stefans-speaker go-librespot[26992]: time="2026-08-31T23:06:24+02:00" level=debug msg="completed challenge" Aug 31 23:06:24 stefans-speaker go-librespot[26992]: time="2026-08-31T23:06:24+02:00" level=info msg="authenticated AP" username="31************************cy" Aug 31 23:06:25 stefans-speaker go-librespot[26992]: time="2026-08-31T23:06:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 31 23:06:25 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:06:25 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 23:06:25 stefans-speaker volumio[26685]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 31 23:06:26 stefans-speaker volumio[26685]: info: Initializing connection to go-librespot Websocket Aug 31 23:06:26 stefans-speaker volumio[26685]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 23:06:28 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Aug 31 23:06:28 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:28 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:28 stefans-speaker go-librespot[27000]: go-librespot daemon starting... Aug 31 23:06:28 stefans-speaker go-librespot[27001]: time="2026-08-31T23:06:28+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:06:28 stefans-speaker go-librespot[27001]: time="2026-08-31T23:06:28+02:00" level=debug msg="app state loaded" Aug 31 23:06:28 stefans-speaker go-librespot[27001]: time="2026-08-31T23:06:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:06:28 stefans-speaker go-librespot[27001]: time="2026-08-31T23:06: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]" Aug 31 23:06:28 stefans-speaker go-librespot[27001]: time="2026-08-31T23:06: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]" Aug 31 23:06:28 stefans-speaker go-librespot[27001]: time="2026-08-31T23:06: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]" Aug 31 23:06:28 stefans-speaker go-librespot[27001]: time="2026-08-31T23:06:28+02:00" level=info msg="zeroconf server listening on port 42287" Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 31 23:06:28 stefans-speaker volumio[26685]: info: Adding plugin bluetooth to MyMusic Plugins Aug 31 23:06:28 stefans-speaker volumio[26685]: info: Adding plugin multiroom to MyMusic Plugins Aug 31 23:06:28 stefans-speaker volumio[26685]: info: Adding plugin metavolumio to MyMusic Plugins Aug 31 23:06:28 stefans-speaker volumio[26685]: info: Adding plugin cd_controller to MyMusic Plugins Aug 31 23:06:28 stefans-speaker volumio[26685]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 31 23:06:28 stefans-speaker volumio[26685]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 31 23:06:28 stefans-speaker volumio[26685]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 31 23:06:28 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 31 23:06:28 stefans-speaker go-librespot[27001]: time="2026-08-31T23:06:28+02:00" level=debug msg="obtained new client token: AAG1dHLTO9XB5BEkbGmADuas0OdV+IouE5oJRpKEn4k2iVWMozA9qeRn4ATa7bqpvdYDyr3EHddLwTT9OmjkPsyKQNLJDBLR20pR3tv+7XM2ssuNlIAoVDxVfI6QQggYEoD46GUa69nFi5SDm8uLcQCMNaGBjBjIs/ruj6k49gQ12zZfSlxMJJZa2sOMO0+j6LiggyPAYI+OivJU4aOlaf70gsbWVmn6alsMA28ho0zICvmQThp8SmmIOgM=" Aug 31 23:06:28 stefans-speaker go-librespot[27001]: time="2026-08-31T23:06:28+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Aug 31 23:06:28 stefans-speaker go-librespot[27001]: time="2026-08-31T23:06:28+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 31 23:06:28 stefans-speaker go-librespot[27001]: time="2026-08-31T23:06:28+02:00" level=debug msg="completed keyexchange" Aug 31 23:06:28 stefans-speaker go-librespot[27001]: time="2026-08-31T23:06:28+02:00" level=debug msg="completed challenge" Aug 31 23:06:28 stefans-speaker go-librespot[27001]: time="2026-08-31T23:06:28+02:00" level=info msg="authenticated AP" username="31************************cy" Aug 31 23:06:29 stefans-speaker go-librespot[27001]: time="2026-08-31T23:06:29+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 31 23:06:29 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:06:29 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 23:06:29 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 31 23:06:29 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 31 23:06:29 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:29 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:29 stefans-speaker volumio[26685]: info: Starting MyVolumio Remote Streaming Endpoints Aug 31 23:06:29 stefans-speaker volumio[26685]: info: MyVolumio login type: Token Aug 31 23:06:29 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 31 23:06:29 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 31 23:06:30 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 31 23:06:30 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 31 23:06:30 stefans-speaker volumio[26685]: info: Streaming services startup Aug 31 23:06:30 stefans-speaker volumio[26685]: info: Starting Streaming Daemon Aug 31 23:06:30 stefans-speaker volumio[26685]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 31 23:06:30 stefans-speaker sudo[27023]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:30 stefans-speaker sudo[27023]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 31 23:06:30 stefans-speaker sudo[27023]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:30 stefans-speaker sudo[27023]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:30 stefans-speaker volumio[26685]: info: Initializing connection to go-librespot Websocket Aug 31 23:06:30 stefans-speaker volumio[26685]: error: Cannot start Volumio Streaming Daemon Aug 31 23:06:30 stefans-speaker volumio[26685]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 31 23:06:30 stefans-speaker volumio[26685]: sudo: unable to resolve host stefans-speaker: System error Aug 31 23:06:30 stefans-speaker volumio[26685]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 31 23:06:30 stefans-speaker volumio[26685]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 23:06:31 stefans-speaker volumio[26685]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 31 23:06:31 stefans-speaker volumio[26685]: info: MyVolumio token set successfully Aug 31 23:06:31 stefans-speaker volumio[26685]: info: MYVOLUMIO: Adding device Aug 31 23:06:31 stefans-speaker volumio[26685]: info: MYVOLUMIO: Evaluating Server Aug 31 23:06:32 stefans-speaker volumio[26685]: info: MyVolumio status changed Aug 31 23:06:32 stefans-speaker volumio[26685]: info: Streaming services startup Aug 31 23:06:32 stefans-speaker volumio[26685]: info: Starting Streaming Daemon Aug 31 23:06:32 stefans-speaker volumio[26685]: info: Removing browser output: myVolumio user plan is not superstar Aug 31 23:06:32 stefans-speaker volumio[26685]: info: Removing audio output: Aug 31 23:06:32 stefans-speaker volumio[26685]: info: Stoppping Tunnel 1 Aug 31 23:06:32 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Aug 31 23:06:32 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:32 stefans-speaker sudo[27051]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:32 stefans-speaker sudo[27051]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 31 23:06:32 stefans-speaker sudo[27053]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:32 stefans-speaker sudo[27051]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:32 stefans-speaker sudo[27053]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 31 23:06:32 stefans-speaker sudo[27053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:32 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:32 stefans-speaker go-librespot[27054]: go-librespot daemon starting... Aug 31 23:06:32 stefans-speaker go-librespot[27057]: time="2026-08-31T23:06:32+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:06:32 stefans-speaker go-librespot[27057]: time="2026-08-31T23:06:32+02:00" level=debug msg="app state loaded" Aug 31 23:06:32 stefans-speaker go-librespot[27057]: time="2026-08-31T23:06:32+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:06:32 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. Aug 31 23:06:32 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. Aug 31 23:06:32 stefans-speaker sudo[27051]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:32 stefans-speaker volumio[26685]: error: Cannot start Volumio Streaming Daemon Aug 31 23:06:32 stefans-speaker volumio[26685]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 31 23:06:32 stefans-speaker volumio[26685]: sudo: unable to resolve host stefans-speaker: System error Aug 31 23:06:32 stefans-speaker volumio[26685]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 31 23:06:32 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. Aug 31 23:06:32 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. Aug 31 23:06:32 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. Aug 31 23:06:32 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. Aug 31 23:06:32 stefans-speaker sudo[27053]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:32 stefans-speaker volumio[26685]: info: Remote SSH Stopped Aug 31 23:06:32 stefans-speaker volumio[26685]: info: Setting Geolocation for MyVolumio to eu2 Aug 31 23:06:32 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:32 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:32 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:32 stefans-speaker go-librespot[27057]: time="2026-08-31T23:06:32+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 31 23:06:32 stefans-speaker go-librespot[27057]: time="2026-08-31T23:06:32+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 23:06:32 stefans-speaker go-librespot[27057]: time="2026-08-31T23:06:32+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 23:06:32 stefans-speaker go-librespot[27057]: time="2026-08-31T23:06:32+02:00" level=info msg="zeroconf server listening on port 39763" Aug 31 23:06:32 stefans-speaker volumio[26685]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 31 23:06:32 stefans-speaker go-librespot[27057]: time="2026-08-31T23:06:32+02:00" level=debug msg="obtained new client token: AAFMH/weJyGARyqngYGWbV80+IvtwlNG1FYkgRqS9ut0geITz/0pzvwaaMMcV4nU1IUwn7TKgozQkcItedISIk4KtWGqarJ3URF7yyQB/69zg8szt0ftN3LybocfHfVndg5WPJbpLZy70t9Y/1h/bDm1cEHl9s+IW61DuLdO0uAyQ1h9EMgjzoPv1Eyv8POuYRfiPL6//AT66mOsdQd4I1/xMDFxHffGzeVVae+DVbtcb4Y4ajlKwzneweM=" Aug 31 23:06:32 stefans-speaker go-librespot[27057]: time="2026-08-31T23:06:32+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 23:06:32 stefans-speaker go-librespot[27057]: time="2026-08-31T23:06:32+02:00" level=debug msg="completed keyexchange" Aug 31 23:06:32 stefans-speaker go-librespot[27057]: time="2026-08-31T23:06:32+02:00" level=debug msg="completed challenge" Aug 31 23:06:32 stefans-speaker go-librespot[27057]: time="2026-08-31T23:06:32+02:00" level=info msg="authenticated AP" username="31************************cy" Aug 31 23:06:33 stefans-speaker go-librespot[27057]: time="2026-08-31T23:06:33+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 31 23:06:33 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:06:33 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 23:06:33 stefans-speaker volumio[26685]: info: Updating MyVolumio device info Aug 31 23:06:33 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:33 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:33 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:33 stefans-speaker volumio[26685]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 31 23:06:33 stefans-speaker volumio[26685]: info: Initializing connection to go-librespot Websocket Aug 31 23:06:33 stefans-speaker volumio[26685]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 23:06:36 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Aug 31 23:06:36 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:36 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:36 stefans-speaker go-librespot[27064]: go-librespot daemon starting... Aug 31 23:06:36 stefans-speaker go-librespot[27065]: time="2026-08-31T23:06:36+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:06:36 stefans-speaker go-librespot[27065]: time="2026-08-31T23:06:36+02:00" level=debug msg="app state loaded" Aug 31 23:06:36 stefans-speaker go-librespot[27065]: time="2026-08-31T23:06:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:06:36 stefans-speaker volumio[26685]: info: MYVOLUMIO: Adding device Aug 31 23:06:36 stefans-speaker volumio[26685]: info: MYVOLUMIO: Evaluating Server Aug 31 23:06:37 stefans-speaker volumio[26685]: info: Initializing connection to go-librespot Websocket Aug 31 23:06:37 stefans-speaker go-librespot[27065]: time="2026-08-31T23:06:37+02:00" level=debug msg="new websocket client" Aug 31 23:06:37 stefans-speaker volumio[26685]: info: Connection to go-librespot Websocket established Aug 31 23:06:37 stefans-speaker volumio[26685]: info: Setting Geolocation for MyVolumio to eu2 Aug 31 23:06:37 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:37 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:37 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:37 stefans-speaker volumio[26685]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 31 23:06:37 stefans-speaker go-librespot[27065]: time="2026-08-31T23:06:37+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 31 23:06:37 stefans-speaker go-librespot[27065]: time="2026-08-31T23:06:37+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 23:06:37 stefans-speaker go-librespot[27065]: time="2026-08-31T23:06:37+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 23:06:37 stefans-speaker go-librespot[27065]: time="2026-08-31T23:06:37+02:00" level=info msg="zeroconf server listening on port 37841" Aug 31 23:06:37 stefans-speaker go-librespot[27065]: time="2026-08-31T23:06:37+02:00" level=debug msg="obtained new client token: AAFyRY2dAUtYRcKGido2AnyYMdxuDcaWITPwRffq9TyIzKLo+IzToo3P8pVeCkHQ2LTdbjLCqBFFt8aQLnxHJZSBNFa7Msl4mvo5SmImLe+ulUeCtoJRmH8dEDgI7NG2Nlvv9HlpHBNQrpwe/1z2tscY5nIasFiBhJ1ILfgufgvUT8blhhYO9r08w/3wg8WXQS4OjdEZRaJxDfxHKozHgATySi+r024IQxld8wfA8oWhwQCDfpLKpAC96dk=" Aug 31 23:06:37 stefans-speaker go-librespot[27065]: time="2026-08-31T23:06:37+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 23:06:37 stefans-speaker go-librespot[27065]: time="2026-08-31T23:06:37+02:00" level=debug msg="completed keyexchange" Aug 31 23:06:37 stefans-speaker go-librespot[27065]: time="2026-08-31T23:06:37+02:00" level=debug msg="completed challenge" Aug 31 23:06:37 stefans-speaker go-librespot[27065]: time="2026-08-31T23:06:37+02:00" level=info msg="authenticated AP" username="31************************cy" Aug 31 23:06:38 stefans-speaker go-librespot[27065]: time="2026-08-31T23:06:38+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 31 23:06:38 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:06:38 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 23:06:38 stefans-speaker volumio[26685]: info: Connection to go-librespot Websocket closed Aug 31 23:06:38 stefans-speaker volumio[26685]: info: Updating MyVolumio device info Aug 31 23:06:38 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:38 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:38 stefans-speaker volumio[26685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:39 stefans-speaker volumio[26685]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 31 23:06:40 stefans-speaker volumio[26685]: info: Getting Spotify volume Aug 31 23:06:40 stefans-speaker volumio[26685]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 23:06:40 stefans-speaker volumio[26685]: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 23:06:40 stefans-speaker volumio[26685]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Aug 31 23:06:40 stefans-speaker volumio[26685]: errno: -111, Aug 31 23:06:40 stefans-speaker volumio[26685]: code: 'ECONNREFUSED', Aug 31 23:06:40 stefans-speaker volumio[26685]: syscall: 'connect', Aug 31 23:06:40 stefans-speaker volumio[26685]: address: '127.0.0.1', Aug 31 23:06:40 stefans-speaker volumio[26685]: port: 9879, Aug 31 23:06:40 stefans-speaker volumio[26685]: response: undefined Aug 31 23:06:40 stefans-speaker volumio[26685]: } Aug 31 23:06:40 stefans-speaker volumio[26685]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 23:06:40 stefans-speaker sudo[27119]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:40 stefans-speaker sudo[27119]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-31 23:05' Aug 31 23:06:40 stefans-speaker sudo[27119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:41 stefans-speaker sudo[27119]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:41 stefans-speaker volumio[26685]: sudo: unable to resolve host stefans-speaker: System error Aug 31 23:06:41 stefans-speaker volumio-remote-updater[679]: [2026-08-31 23:06:41] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 31 23:06:41 stefans-speaker volumio-remote-updater[679]: [2026-08-31 23:06:41] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 31 23:06:41 stefans-speaker systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:06:41 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Aug 31 23:06:41 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:41 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:41 stefans-speaker go-librespot[27124]: go-librespot daemon starting... Aug 31 23:06:41 stefans-speaker systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 31 23:06:41 stefans-speaker systemd[1]: volumio.service: Consumed 33.016s CPU time. Aug 31 23:06:41 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Aug 31 23:06:41 stefans-speaker go-librespot[27127]: time="2026-08-31T23:06:41+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:06:41 stefans-speaker go-librespot[27127]: time="2026-08-31T23:06:41+02:00" level=debug msg="app state loaded" Aug 31 23:06:41 stefans-speaker go-librespot[27127]: time="2026-08-31T23:06:41+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:06:41 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Aug 31 23:06:41 stefans-speaker go-librespot[27127]: time="2026-08-31T23:06:41+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 31 23:06:41 stefans-speaker go-librespot[27127]: time="2026-08-31T23:06:41+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 23:06:41 stefans-speaker go-librespot[27127]: time="2026-08-31T23:06:41+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 23:06:41 stefans-speaker go-librespot[27127]: time="2026-08-31T23:06:41+02:00" level=info msg="zeroconf server listening on port 40759" Aug 31 23:06:41 stefans-speaker systemd[1]: volumio.service: Scheduled restart job, restart counter is at 121. Aug 31 23:06:41 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Aug 31 23:06:41 stefans-speaker systemd[1]: Stopped volumio.service - Volumio Backend Module. Aug 31 23:06:41 stefans-speaker systemd[1]: volumio.service: Consumed 33.016s CPU time. Aug 31 23:06:41 stefans-speaker systemd[1]: Started volumio.service - Volumio Backend Module. Aug 31 23:06:41 stefans-speaker go-librespot[27127]: time="2026-08-31T23:06:41+02:00" level=debug msg="obtained new client token: AAF6WI4WSHBFnxS8b7l7Ym7izG2Z4Cm/ORERtQ4+z6PKKaxX29qF0tgqNHuSa8hUt1erkpQKjilnFkRiD/moRVtfsEkTYeAqmYk0REKJUWT8/khlW5VJ+W7jqIM+hHKGmfxM5poe2ezjsAbJd7LK4wunuXn07rTFINTJ5ExrDYyWevWn71W4mK0xW0VX8RG94ky1vlzM0f9sYZQkZKvwipKKeOPIpi3AmpEhTNHz0R6ylJwjwh3yU2HYi30=" Aug 31 23:06:41 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Aug 31 23:06:41 stefans-speaker go-librespot[27127]: time="2026-08-31T23:06:41+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Aug 31 23:06:41 stefans-speaker go-librespot[27127]: time="2026-08-31T23:06:41+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 31 23:06:41 stefans-speaker go-librespot[27127]: time="2026-08-31T23:06:41+02:00" level=debug msg="completed keyexchange" Aug 31 23:06:41 stefans-speaker go-librespot[27127]: time="2026-08-31T23:06:41+02:00" level=debug msg="completed challenge" Aug 31 23:06:41 stefans-speaker go-librespot[27127]: time="2026-08-31T23:06:41+02:00" level=info msg="authenticated AP" username="31************************cy" Aug 31 23:06:42 stefans-speaker go-librespot[27127]: time="2026-08-31T23:06:42+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 31 23:06:42 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:06:42 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 23:06:43 stefans-speaker volumio[27141]: info: ------------------------------------------- Aug 31 23:06:43 stefans-speaker volumio[27141]: info: ----- Volumio3 ---- Aug 31 23:06:43 stefans-speaker volumio[27141]: info: ------------------------------------------- Aug 31 23:06:43 stefans-speaker volumio[27141]: info: ----- System startup ---- Aug 31 23:06:43 stefans-speaker volumio[27141]: info: ------------------------------------------- Aug 31 23:06:44 stefans-speaker volumio[27141]: info: MYVOLUMIO Environment detected Aug 31 23:06:44 stefans-speaker volumio[27141]: info: Plugin folders cleanup Aug 31 23:06:44 stefans-speaker volumio[27141]: info: Scanning into folder /volumio/app/plugins/ Aug 31 23:06:44 stefans-speaker volumio[27141]: info: Scanning category audio_interface Aug 31 23:06:44 stefans-speaker volumio[27141]: info: Scanning category miscellanea Aug 31 23:06:44 stefans-speaker volumio[27141]: info: Scanning category music_service Aug 31 23:06:44 stefans-speaker volumio[27141]: info: Scanning category plugins.json Aug 31 23:06:44 stefans-speaker volumio[27141]: info: Scanning category system_controller Aug 31 23:06:44 stefans-speaker volumio[27141]: info: Scanning category user_interface Aug 31 23:06:44 stefans-speaker volumio[27141]: info: Scanning into folder /data/plugins/ Aug 31 23:06:44 stefans-speaker volumio[27141]: info: Scanning category music_service Aug 31 23:06:44 stefans-speaker volumio[27141]: info: Plugin folders cleanup completed Aug 31 23:06:44 stefans-speaker volumio[27141]: info: ------------------------------------------- Aug 31 23:06:44 stefans-speaker volumio[27141]: info: ----- Core plugins startup ---- Aug 31 23:06:44 stefans-speaker volumio[27141]: info: ------------------------------------------- Aug 31 23:06:44 stefans-speaker volumio[27141]: info: Loading plugins from folder /volumio/app/plugins/ Aug 31 23:06:44 stefans-speaker volumio[27141]: info: Adding plugin upnp to MyMusic Plugins Aug 31 23:06:44 stefans-speaker volumio[27141]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 31 23:06:44 stefans-speaker volumio[27141]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 31 23:06:44 stefans-speaker volumio[27141]: info: Loading plugins from folder /data/plugins/ Aug 31 23:06:44 stefans-speaker volumio[27141]: info: Loading plugin "system"... Aug 31 23:06:44 stefans-speaker volumio[27141]: info: Loading plugin "appearance"... Aug 31 23:06:45 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Aug 31 23:06:45 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:45 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:45 stefans-speaker go-librespot[27171]: go-librespot daemon starting... Aug 31 23:06:45 stefans-speaker go-librespot[27172]: time="2026-08-31T23:06:45+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:06:45 stefans-speaker go-librespot[27172]: time="2026-08-31T23:06:45+02:00" level=debug msg="app state loaded" Aug 31 23:06:45 stefans-speaker go-librespot[27172]: time="2026-08-31T23:06:45+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:06:45 stefans-speaker go-librespot[27172]: time="2026-08-31T23:06:45+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 31 23:06:45 stefans-speaker go-librespot[27172]: time="2026-08-31T23:06:45+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 31 23:06:45 stefans-speaker go-librespot[27172]: time="2026-08-31T23:06:45+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 31 23:06:45 stefans-speaker go-librespot[27172]: time="2026-08-31T23:06:45+02:00" level=info msg="zeroconf server listening on port 38417" Aug 31 23:06:45 stefans-speaker go-librespot[27172]: time="2026-08-31T23:06:45+02:00" level=debug msg="obtained new client token: AAGuQmFB+pM8DsR3VOCqKt6HRraQdHbOiIP47lnUvsnf09N5QrpNYFL+fWwxWcANPnzfcdKDn0TdO79kbNQ+idmClT56fi/FAQpbCf5RqMGmvRftMqDBO53E5gLp7RiAgJCBSXimRFVU6hmcn99OfibfQ+BBzZLMPsrdnRq+aUU/5uzTjedhtE83FO6v+TVyxkpDFiCmclbNAy7KIIRmOMM/P/XcLNDYHef+L7vbPkRkfPG1xZTEAPUiMdM=" Aug 31 23:06:45 stefans-speaker go-librespot[27172]: time="2026-08-31T23:06:45+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 23:06:45 stefans-speaker go-librespot[27172]: time="2026-08-31T23:06:45+02:00" level=debug msg="completed keyexchange" Aug 31 23:06:45 stefans-speaker go-librespot[27172]: time="2026-08-31T23:06:45+02:00" level=debug msg="completed challenge" Aug 31 23:06:45 stefans-speaker volumio[27141]: info: Loading plugin "network"... Aug 31 23:06:45 stefans-speaker volumio[27141]: info: Refreshing Cached IP Addresses Aug 31 23:06:45 stefans-speaker go-librespot[27172]: time="2026-08-31T23:06:45+02:00" level=info msg="authenticated AP" username="31************************cy" Aug 31 23:06:45 stefans-speaker sudo[27181]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:45 stefans-speaker sudo[27183]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:45 stefans-speaker sudo[27181]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 23:06:45 stefans-speaker sudo[27181]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:45 stefans-speaker sudo[27183]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 23:06:45 stefans-speaker sudo[27183]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:45 stefans-speaker volumio[27141]: info: Loading plugin "services"... Aug 31 23:06:45 stefans-speaker sudo[27183]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:45 stefans-speaker sudo[27181]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:45 stefans-speaker volumio[27141]: info: Loading plugin "alsa_controller"... Aug 31 23:06:45 stefans-speaker sudo[27190]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:45 stefans-speaker sudo[27190]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 31 23:06:45 stefans-speaker sudo[27190]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:45 stefans-speaker go-librespot[27172]: time="2026-08-31T23:06:45+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 31 23:06:45 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:06:45 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 23:06:45 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 23:06:45 stefans-speaker volumio[27141]: info: Loading plugin "wizard"... Aug 31 23:06:45 stefans-speaker volumio[27141]: info: Loading plugin "networkfs"... Aug 31 23:06:45 stefans-speaker volumio[27141]: info: Starting Udev Watcher for removable devices Aug 31 23:06:45 stefans-speaker volumio[27141]: info: Ignoring mount for partition: boot Aug 31 23:06:45 stefans-speaker volumio[27141]: info: Ignoring mount for partition: volumio Aug 31 23:06:45 stefans-speaker volumio[27141]: info: Ignoring mount for partition: volumio_data Aug 31 23:06:45 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 23:06:45 stefans-speaker volumio[27141]: info: Loading plugin "volumio_command_line_client"... Aug 31 23:06:45 stefans-speaker volumio[27141]: info: Loading plugin "upnp"... Aug 31 23:06:45 stefans-speaker volumio[27141]: info: [1788210405932] Starting Upmpd Daemon Aug 31 23:06:45 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 23:06:45 stefans-speaker volumio[27141]: info: Loading plugin "my_music"... Aug 31 23:06:45 stefans-speaker volumio[27141]: info: Loading plugin "mpd"... Aug 31 23:06:46 stefans-speaker volumio-remote-updater[679]: [2026-08-31 23:06:46] [connect] Successful connection Aug 31 23:06:46 stefans-speaker volumio[27141]: info: Loading plugin "upnp_browser"... Aug 31 23:06:47 stefans-speaker volumio[27141]: info: Starting UPNP Browser Aug 31 23:06:47 stefans-speaker volumio[27141]: info: Loading plugin "alarm-clock"... Aug 31 23:06:48 stefans-speaker volumio[27141]: info: Loading plugin "airplay_emulation"... Aug 31 23:06:48 stefans-speaker volumio[27141]: info: Starting Shairport Sync Aug 31 23:06:48 stefans-speaker volumio[27141]: info: Loading plugin "last_100"... Aug 31 23:06:48 stefans-speaker volumio[27141]: info: Loading plugin "webradio"... Aug 31 23:06:48 stefans-speaker volumio[27141]: info: Loading plugin "i2s_dacs"... Aug 31 23:06:48 stefans-speaker volumio[27141]: info: I2S DAC not set, start Auto-detection Aug 31 23:06:48 stefans-speaker volumio[27141]: info: Loading plugin "volumiodiscovery"... Aug 31 23:06:48 stefans-speaker volumio[27141]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 31 23:06:48 stefans-speaker volumio[27141]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 23:06:48 stefans-speaker volumio[27141]: *** WARNING *** For more information see Aug 31 23:06:48 stefans-speaker volumio[27141]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 31 23:06:48 stefans-speaker volumio[27141]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 23:06:48 stefans-speaker volumio[27141]: *** WARNING *** For more information see Aug 31 23:06:48 stefans-speaker node[27141]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 31 23:06:48 stefans-speaker node[27141]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 23:06:48 stefans-speaker node[27141]: *** WARNING *** For more information see Aug 31 23:06:48 stefans-speaker node[27141]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 31 23:06:48 stefans-speaker node[27141]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 23:06:48 stefans-speaker node[27141]: *** WARNING *** For more information see Aug 31 23:06:48 stefans-speaker volumio[27141]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 31 23:06:48 stefans-speaker volumio[27141]: info: Discovery: Started advertising with name: Stefan’s Speaker Aug 31 23:06:48 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 23:06:48 stefans-speaker volumio[27141]: info: Loading plugin "spop"... Aug 31 23:06:49 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Aug 31 23:06:49 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:49 stefans-speaker sudo[27190]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:49 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:49 stefans-speaker go-librespot[27216]: go-librespot daemon starting... Aug 31 23:06:49 stefans-speaker go-librespot[27217]: time="2026-08-31T23:06:49+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:06:49 stefans-speaker go-librespot[27217]: time="2026-08-31T23:06:49+02:00" level=debug msg="app state loaded" Aug 31 23:06:49 stefans-speaker go-librespot[27217]: time="2026-08-31T23:06:49+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:06:49 stefans-speaker volumio[27141]: info: Loading plugin "ytcr"... Aug 31 23:06:49 stefans-speaker go-librespot[27217]: time="2026-08-31T23:06:49+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 31 23:06:49 stefans-speaker go-librespot[27217]: time="2026-08-31T23:06:49+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 23:06:49 stefans-speaker go-librespot[27217]: time="2026-08-31T23:06:49+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 23:06:49 stefans-speaker go-librespot[27217]: time="2026-08-31T23:06:49+02:00" level=info msg="zeroconf server listening on port 41681" Aug 31 23:06:49 stefans-speaker go-librespot[27217]: time="2026-08-31T23:06:49+02:00" level=debug msg="obtained new client token: AAHWrEpvtVdoVnse7URTKtZS1wfp8CrCFlFeNPv0uJ0jd71ZAl0tzk/JUZ8FP+FqVvRrY9VAXl2Z0ZM4OSPyNWXm4476Ha00c8bCKSciKKbWp9mK/sud0nGe4Vgm0cATzsPJ0SowkNaA4PjfYLczj0+n8xxMmSdiDIBPqVSWsiWhrkroJ2ZEt029X3HRkMljcVNUq19aGQw8U2IbWaG4GPh+aJ5I7aElDq/0kewNQoiDG7Rhe/OLBGTSQcg=" Aug 31 23:06:49 stefans-speaker go-librespot[27217]: time="2026-08-31T23:06:49+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 23:06:49 stefans-speaker go-librespot[27217]: time="2026-08-31T23:06:49+02:00" level=debug msg="completed keyexchange" Aug 31 23:06:49 stefans-speaker go-librespot[27217]: time="2026-08-31T23:06:49+02:00" level=debug msg="completed challenge" Aug 31 23:06:49 stefans-speaker go-librespot[27217]: time="2026-08-31T23:06:49+02:00" level=info msg="authenticated AP" username="31************************cy" Aug 31 23:06:49 stefans-speaker go-librespot[27217]: time="2026-08-31T23:06:49+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 31 23:06:49 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:06:49 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 23:06:50 stefans-speaker volumio[27141]: info: Loading plugin "outputs"... Aug 31 23:06:50 stefans-speaker volumio[27141]: info: Loading plugin "albumart"... Aug 31 23:06:50 stefans-speaker volumio[27141]: info: Plugin example_plugin is not enabled Aug 31 23:06:50 stefans-speaker volumio[27141]: info: Loading plugin "inputs"... Aug 31 23:06:50 stefans-speaker volumio[27141]: info: Loading plugin "updater_comm"... Aug 31 23:06:51 stefans-speaker volumio[27141]: info: Plugin mpdemulation is not enabled Aug 31 23:06:51 stefans-speaker volumio[27141]: info: Loading plugin "rest_api"... Aug 31 23:06:51 stefans-speaker volumio[27141]: info: Loading plugin "websocket"... Aug 31 23:06:51 stefans-speaker volumio[27141]: info: Starting Socket.io Server version 1.7.4 Aug 31 23:06:51 stefans-speaker volumio[27141]: info: Loading i18n strings for locale nl Aug 31 23:06:51 stefans-speaker volumio[27141]: Updating browse sources language Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreCommandRouter::initPlayerControls Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 23:06:51 stefans-speaker volumio[27141]: Express server listening on port 3000 Aug 31 23:06:51 stefans-speaker volumio[27141]: [Metrics] WebUI: 8s 173.12ms Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreStateMachine::resetVolumioState Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreStateMachine::getcurrentVolume Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreCommandRouter::volumioRetrievevolume Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:51 stefans-speaker volumio[27141]: info: Cannot read play queue from file Aug 31 23:06:51 stefans-speaker volumio[27141]: info: Volumio Network Manager: Network status updated: 2 Aug 31 23:06:51 stefans-speaker volumio[27224]: Forking 3 albumart workers Aug 31 23:06:51 stefans-speaker volumio[27141]: info: VolumeController:: Volume=70 Mute =false Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreStateMachine::pushState Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CorePlayQueue::getTrack 0 Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreCommandRouter::volumioPushState Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreStateMachine::updateTrackBlock Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CorePlayQueue::getTrackBlock Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreCommandRouter::volumioRetrievevolume Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreStateMachine::setRepeat null single undefined Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreStateMachine::pushState Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CorePlayQueue::getTrack 0 Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreCommandRouter::volumioPushState Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreStateMachine::setRandom null Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreStateMachine::pushState Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CorePlayQueue::getTrack 0 Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreCommandRouter::volumioPushState Aug 31 23:06:51 stefans-speaker volumio[27141]: info: Setting Device type: Raspberry PI Aug 31 23:06:51 stefans-speaker volumio[27141]: info: VolumeController:: Volume=70 Mute =false Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreStateMachine::pushState Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CorePlayQueue::getTrack 0 Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreCommandRouter::volumioPushState Aug 31 23:06:51 stefans-speaker volumio[27141]: info: Completed loading Core Plugins Aug 31 23:06:51 stefans-speaker volumio[27141]: info: Preparing to generate the ALSA configuration file Aug 31 23:06:51 stefans-speaker volumio[27141]: info: Discovery: adding a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Aug 31 23:06:51 stefans-speaker volumio[27141]: info: Discovery: Found device Stefan’s Speaker Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreCommandRouter::volumioGetState Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CorePlayQueue::getTrack 0 Aug 31 23:06:51 stefans-speaker volumio[27141]: info: Discovery: this is already registered, a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Aug 31 23:06:51 stefans-speaker volumio[27141]: info: Discovery: Found device Stefan’s Speaker Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreCommandRouter::volumioGetState Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CorePlayQueue::getTrack 0 Aug 31 23:06:51 stefans-speaker volumio[27141]: info: Asound.conf file unchanged, so no further update is needed Aug 31 23:06:51 stefans-speaker volumio[27141]: info: Output device has changed, restarting MPD Aug 31 23:06:51 stefans-speaker volumio[27141]: info: Output device has changed, restarting Shairport Sync Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:51 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:52 stefans-speaker volumio[27141]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 23:06:52 stefans-speaker sudo[27279]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:52 stefans-speaker sudo[27282]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:52 stefans-speaker volumio[27141]: info: ___________ START PLUGINS ___________ Aug 31 23:06:52 stefans-speaker sudo[27279]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 23:06:52 stefans-speaker sudo[27279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:52 stefans-speaker volumio[27141]: info: ControllerMpd::onStart: Initializing MPD Aug 31 23:06:52 stefans-speaker volumio[27141]: info: Creating MPD Configuration file Aug 31 23:06:52 stefans-speaker sudo[27282]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 23:06:52 stefans-speaker sudo[27282]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:52 stefans-speaker sudo[27279]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:52 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 23:06:52 stefans-speaker volumio[27141]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 23:06:52 stefans-speaker volumio[27141]: info: [1788210412074] CoreMusicLibrary::Adding element Media Servers Aug 31 23:06:52 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 23:06:52 stefans-speaker sudo[27289]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:52 stefans-speaker sudo[27289]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 23:06:52 stefans-speaker volumio[27141]: info: UPNP Browser: Client initialized successfully Aug 31 23:06:52 stefans-speaker sudo[27291]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:52 stefans-speaker systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 31 23:06:52 stefans-speaker sudo[27289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:52 stefans-speaker sudo[27291]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 23:06:52 stefans-speaker sudo[27291]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:52 stefans-speaker sudo[27289]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:52 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:52 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:52 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Aug 31 23:06:52 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 31 23:06:52 stefans-speaker systemd[1]: mpd.service: Consumed 4.456s CPU time. Aug 31 23:06:52 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Aug 31 23:06:52 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 31 23:06:52 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 31 23:06:52 stefans-speaker volumio[27141]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 23:06:52 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:52 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:52 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 23:06:52 stefans-speaker volumio[27141]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 23:06:52 stefans-speaker volumio[27141]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 23:06:52 stefans-speaker volumio[27141]: info: [1788210412289] CoreMusicLibrary::Adding element Last_100 Aug 31 23:06:52 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 23:06:52 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 23:06:52 stefans-speaker volumio[27141]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 23:06:52 stefans-speaker volumio[27141]: info: [1788210412302] CoreMusicLibrary::Adding element Webradio Aug 31 23:06:52 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 23:06:52 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Aug 31 23:06:52 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 31 23:06:52 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 23:06:52 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Aug 31 23:06:52 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 31 23:06:52 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 31 23:06:52 stefans-speaker volumio[27141]: info: Initializing BBC Radios Aug 31 23:06:52 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 23:06:52 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 23:06:52 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 23:06:52 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:52 stefans-speaker volumio[27141]: info: Creating Spotify config file Aug 31 23:06:52 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:52 stefans-speaker sudo[27307]: root : unable to resolve host stefans-speaker: System error Aug 31 23:06:52 stefans-speaker sudo[27307]: sudo: unable to resolve host stefans-speaker: System error Aug 31 23:06:52 stefans-speaker sudo[27307]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 23:06:52 stefans-speaker sudo[27307]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 23:06:52 stefans-speaker sudo[27307]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:53 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Aug 31 23:06:53 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:53 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:53 stefans-speaker go-librespot[27321]: go-librespot daemon starting... Aug 31 23:06:53 stefans-speaker go-librespot[27322]: time="2026-08-31T23:06:53+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:06:53 stefans-speaker volumio[27242]: Starting albumart workers Aug 31 23:06:53 stefans-speaker volumio[27141]: info: Volumio Calling Home Aug 31 23:06:53 stefans-speaker go-librespot[27322]: time="2026-08-31T23:06:53+02:00" level=info msg="zeroconf server listening on port 46827" Aug 31 23:06:53 stefans-speaker volumio[27241]: Starting albumart workers Aug 31 23:06:53 stefans-speaker volumio[27240]: Starting albumart workers Aug 31 23:06:54 stefans-speaker volumio[27141]: info: MPD Permissions set Aug 31 23:06:54 stefans-speaker volumio[27141]: info: MPD Permissions set Aug 31 23:06:54 stefans-speaker volumio[27141]: info: Volumio called home Aug 31 23:06:54 stefans-speaker volumio[27141]: info: Spotify config file written Aug 31 23:06:54 stefans-speaker sudo[27334]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:54 stefans-speaker sudo[27334]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 31 23:06:54 stefans-speaker sudo[27334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:54 stefans-speaker systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Aug 31 23:06:54 stefans-speaker systemd[1]: go-librespot-daemon.service: Deactivated successfully. Aug 31 23:06:54 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:54 stefans-speaker go-librespot[27342]: go-librespot daemon starting... Aug 31 23:06:54 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:54 stefans-speaker sudo[27334]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:54 stefans-speaker go-librespot[27343]: time="2026-08-31T23:06:54+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:06:54 stefans-speaker go-librespot[27343]: time="2026-08-31T23:06:54+02:00" level=debug msg="app state loaded" Aug 31 23:06:54 stefans-speaker go-librespot[27343]: time="2026-08-31T23:06:54+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:06:54 stefans-speaker volumio[27141]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Aug 31 23:06:54 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:54 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:54 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:54 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:54 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:54 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:54 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:54 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:54 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:54 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:54 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:54 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:54 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:54 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:54 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:54 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:54 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:54 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:54 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:06:54 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:06:54 stefans-speaker volumio[27141]: info: No need to fix Spotify hosts Aug 31 23:06:54 stefans-speaker go-librespot[27343]: time="2026-08-31T23:06:54+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 31 23:06:54 stefans-speaker go-librespot[27343]: time="2026-08-31T23:06:54+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 23:06:54 stefans-speaker go-librespot[27343]: time="2026-08-31T23:06:54+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 23:06:54 stefans-speaker go-librespot[27343]: time="2026-08-31T23:06:54+02:00" level=info msg="zeroconf server listening on port 34349" Aug 31 23:06:55 stefans-speaker go-librespot[27343]: time="2026-08-31T23:06:55+02:00" level=debug msg="obtained new client token: AAEXFqtGHbVPj30dtwiD3gqjnW4KAAS7mO8gSjjp35J+cLuaa8/4sfFP0HHg9jToU7nvZKtPskuWCk3uj38p3Mhdf2FBs57eDebJhpH5CC42XKMNshbaHGxR6Q/aty8f8MAGI4TisISD2gB10bZDNLA6c4W68oikAc1wFdz+764i7M3UlvxGKATUPeEXn72BpZ9I9Hy7fjqg0G8jHHKM93DeMDe7aw69fWcE3HAfGV4eMsv+iVgJ3n4b" Aug 31 23:06:55 stefans-speaker go-librespot[27343]: time="2026-08-31T23:06:55+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 23:06:55 stefans-speaker go-librespot[27343]: time="2026-08-31T23:06:55+02:00" level=debug msg="completed keyexchange" Aug 31 23:06:55 stefans-speaker go-librespot[27343]: time="2026-08-31T23:06:55+02:00" level=debug msg="completed challenge" Aug 31 23:06:55 stefans-speaker volumio[27141]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Aug 31 23:06:55 stefans-speaker volumio[27141]: SPOTIFY: BQCxk77AYWPUBAo6EjkTt6MQHf8wlyzccmlFljlfvIiuIzULN1BQa_9AYtpHWhypJDiRITCdQ1MQxzr-MFh5vHk9X7mnCNby6UjOBnw08Tcz83sgEuBxB9amJn5VscryfnPq9gv9cYM2-psvtJj0W-T7uWGB0_Afos9iV3KCmYJ4BF9XiwSa4ZfFqBcJUTLkA_NRAGhgw7Tw5U8LEz-x5E_PS8Y4z_ojqpqZRy3beyTKwVmFH_8JbemotDoz7TxFVytZSo8LJ24O3FhRwYnqD-I58_BL571pxyUpIc2GQyn15pZx31vMmsDQ2Jb89PI Aug 31 23:06:55 stefans-speaker volumio[27141]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Aug 31 23:06:55 stefans-speaker volumio[27141]: info: New Spotify access token = BQCxk77AYWPUBAo6EjkTt6MQHf8wlyzccmlFljlfvIiuIzULN1BQa_9AYtpHWhypJDiRITCdQ1MQxzr-MFh5vHk9X7mnCNby6UjOBnw08Tcz83sgEuBxB9amJn5VscryfnPq9gv9cYM2-psvtJj0W-T7uWGB0_Afos9iV3KCmYJ4BF9XiwSa4ZfFqBcJUTLkA_NRAGhgw7Tw5U8LEz-x5E_PS8Y4z_ojqpqZRy3beyTKwVmFH_8JbemotDoz7TxFVytZSo8LJ24O3FhRwYnqD-I58_BL571pxyUpIc2GQyn15pZx31vMmsDQ2Jb89PI Aug 31 23:06:55 stefans-speaker volumio[27141]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 31 23:06:55 stefans-speaker go-librespot[27343]: time="2026-08-31T23:06:55+02:00" level=info msg="authenticated AP" username="31************************cy" Aug 31 23:06:55 stefans-speaker volumio[27141]: info: Starting Shairport Sync Aug 31 23:06:55 stefans-speaker volumio[27141]: info: Starting Shairport Sync Aug 31 23:06:55 stefans-speaker volumio[27141]: info: Starting Shairport Sync Aug 31 23:06:55 stefans-speaker sudo[27374]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:55 stefans-speaker sudo[27374]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 23:06:55 stefans-speaker sudo[27374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:55 stefans-speaker sudo[27378]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:55 stefans-speaker sudo[27376]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:55 stefans-speaker sudo[27378]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 23:06:55 stefans-speaker sudo[27378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:55 stefans-speaker sudo[27376]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 23:06:55 stefans-speaker sudo[27376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:55 stefans-speaker go-librespot[27343]: time="2026-08-31T23:06:55+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 31 23:06:55 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:06:55 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 23:06:55 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 31 23:06:55 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Aug 31 23:06:55 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 23:06:55 stefans-speaker systemd[1]: shairport-sync.service: Consumed 1.837s CPU time. Aug 31 23:06:55 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 23:06:55 stefans-speaker sudo[27378]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:55 stefans-speaker volumio[27141]: info: CoreCommandRouter::volumioGetState Aug 31 23:06:55 stefans-speaker volumio[27141]: info: CorePlayQueue::getTrack 0 Aug 31 23:06:55 stefans-speaker sudo[27374]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:55 stefans-speaker sudo[27376]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:55 stefans-speaker volumio[27141]: info: Shairport-Sync Started Aug 31 23:06:55 stefans-speaker volumio[27141]: Error adding Membership: Error: addMembership EINVAL Aug 31 23:06:55 stefans-speaker volumio[27141]: info: Shairport-Sync Started Aug 31 23:06:55 stefans-speaker volumio[27141]: info: Shairport-Sync Started Aug 31 23:06:55 stefans-speaker volumio[27141]: 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"} Aug 31 23:06:55 stefans-speaker volumio[27141]: info: Spotify Successfully logged in Aug 31 23:06:55 stefans-speaker volumio[27141]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 23:06:55 stefans-speaker volumio[27141]: info: [1788210415803] CoreMusicLibrary::Adding element Spotify Aug 31 23:06:55 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 23:06:55 stefans-speaker volumio[27141]: Cannot find translation for source Spotify Aug 31 23:06:56 stefans-speaker sudo[27413]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:56 stefans-speaker sudo[27413]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 23:06:56 stefans-speaker sudo[27414]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:56 stefans-speaker sudo[27413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:56 stefans-speaker sudo[27413]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:56 stefans-speaker sudo[27414]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 23:06:56 stefans-speaker sudo[27414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:56 stefans-speaker sudo[27414]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:56 stefans-speaker volumio[27141]: info: [yt-cast-receiver] DIAL server listening on port 8098 Aug 31 23:06:56 stefans-speaker volumio[27141]: info: CoreCommandRouter::volumioRetrievevolume Aug 31 23:06:56 stefans-speaker sudo[27418]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:06:56 stefans-speaker sudo[27418]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 31 23:06:56 stefans-speaker sudo[27418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:06:56 stefans-speaker volumio[27141]: info: VolumeController:: Volume=70 Mute =false Aug 31 23:06:56 stefans-speaker volumio[27141]: info: CoreCommandRouter::volumioGetState Aug 31 23:06:56 stefans-speaker volumio[27141]: info: CorePlayQueue::getTrack 0 Aug 31 23:06:56 stefans-speaker sudo[27418]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:56 stefans-speaker volumio[27141]: info: CoreStateMachine::pushState Aug 31 23:06:56 stefans-speaker volumio[27141]: info: CorePlayQueue::getTrack 0 Aug 31 23:06:56 stefans-speaker volumio[27141]: info: CoreCommandRouter::volumioPushState Aug 31 23:06:56 stefans-speaker volumio[27141]: info: Upmpdcli Daemon Started Aug 31 23:06:57 stefans-speaker mpd[27320]: 2026-08-31T23:06:57 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 23:06:57 stefans-speaker systemd[1]: Started mpd.service - Music Player Daemon. Aug 31 23:06:57 stefans-speaker sudo[27282]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:57 stefans-speaker sudo[27291]: pam_unix(sudo:session): session closed for user root Aug 31 23:06:57 stefans-speaker volumio[27141]: error: MPD error: The expression evaluated to a falsy value: Aug 31 23:06:57 stefans-speaker volumio[27141]: assert.ok(self.idling) Aug 31 23:06:57 stefans-speaker volumio[27141]: error: The expression evaluated to a falsy value: Aug 31 23:06:57 stefans-speaker volumio[27141]: assert.ok(self.idling) Aug 31 23:06:57 stefans-speaker volumio[27141]: error: updateQueue error: null Aug 31 23:06:57 stefans-speaker volumio[27141]: info: MPD running with PID27320 Aug 31 23:06:57 stefans-speaker volumio[27141]: ,establishing connection Aug 31 23:06:57 stefans-speaker volumio[27141]: info: Completed starting Core Plugins Aug 31 23:06:57 stefans-speaker volumio[27141]: info: ------------------------------------------- Aug 31 23:06:57 stefans-speaker volumio[27141]: info: ----- MyVolumio plugins startup ---- Aug 31 23:06:57 stefans-speaker volumio[27141]: info: ------------------------------------------- Aug 31 23:06:57 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 31 23:06:57 stefans-speaker volumio[27141]: error: updateQueue error: null Aug 31 23:06:58 stefans-speaker volumio[27141]: info: go-librespot daemon successfully initialized Aug 31 23:06:58 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Aug 31 23:06:58 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:58 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:06:58 stefans-speaker go-librespot[27429]: go-librespot daemon starting... Aug 31 23:06:58 stefans-speaker go-librespot[27430]: time="2026-08-31T23:06:58+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:06:58 stefans-speaker go-librespot[27430]: time="2026-08-31T23:06:58+02:00" level=debug msg="app state loaded" Aug 31 23:06:58 stefans-speaker go-librespot[27430]: time="2026-08-31T23:06:58+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:06:58 stefans-speaker volumio-remote-updater[679]: [2026-08-31 23:06:58] [connect] Successful connection Aug 31 23:06:58 stefans-speaker volumio-remote-updater[679]: [2026-08-31 23:06:58] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788210418 101 Aug 31 23:06:58 stefans-speaker volumio[27141]: 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 Aug 31 23:06:58 stefans-speaker go-librespot[27430]: time="2026-08-31T23:06:58+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 31 23:06:58 stefans-speaker go-librespot[27430]: time="2026-08-31T23:06:58+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 31 23:06:58 stefans-speaker go-librespot[27430]: time="2026-08-31T23:06:58+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 31 23:06:58 stefans-speaker go-librespot[27430]: time="2026-08-31T23:06:58+02:00" level=info msg="zeroconf server listening on port 40879" Aug 31 23:06:58 stefans-speaker go-librespot[27430]: time="2026-08-31T23:06:58+02:00" level=debug msg="obtained new client token: AAGWnwEtivahReI7RMsf8Ltqa23sIOr4KfSnGKxQnmMfmDY8lByvqLeH30y9tXc4wg1qQOuG1lNdj0N5BXpVLxFvZb0c/sTJcIdlu7FYFfPO58RrpI1FZMC/+lWWM272YWrM9G0ZB8lieLaBymAYp3qISkLNx6w/rXw3MbzuMx9yzWRZ+jzgCX0QgNUqzK/pgcoG7ZgLEsq4ViSKzhZNtOazAmQD/cUkGNzWmCCImiOePaur44tXeo+/D1I=" Aug 31 23:06:59 stefans-speaker go-librespot[27430]: time="2026-08-31T23:06:59+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 23:06:59 stefans-speaker go-librespot[27430]: time="2026-08-31T23:06:59+02:00" level=debug msg="completed keyexchange" Aug 31 23:06:59 stefans-speaker go-librespot[27430]: time="2026-08-31T23:06:59+02:00" level=debug msg="completed challenge" Aug 31 23:06:59 stefans-speaker go-librespot[27430]: time="2026-08-31T23:06:59+02:00" level=info msg="authenticated AP" username="31************************cy" Aug 31 23:06:59 stefans-speaker go-librespot[27430]: time="2026-08-31T23:06:59+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 31 23:06:59 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:06:59 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 23:07:00 stefans-speaker sudo[27440]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:07:00 stefans-speaker sudo[27442]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:07:00 stefans-speaker sudo[27440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Aug 31 23:07:00 stefans-speaker sudo[27440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:07:00 stefans-speaker sudo[27442]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Aug 31 23:07:00 stefans-speaker sudo[27442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:07:00 stefans-speaker systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Aug 31 23:07:00 stefans-speaker systemd[1]: mpd_monitor.service: Deactivated successfully. Aug 31 23:07:00 stefans-speaker systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Aug 31 23:07:00 stefans-speaker systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Aug 31 23:07:00 stefans-speaker sudo[27442]: pam_unix(sudo:session): session closed for user root Aug 31 23:07:00 stefans-speaker sudo[27440]: pam_unix(sudo:session): session closed for user root Aug 31 23:07:00 stefans-speaker mpd_monitor.sh[27445]: MPD Monitor Service: Starting MPD Monitor Service Aug 31 23:07:00 stefans-speaker volumio[27141]: info: Successfully started MPD Monitor Aug 31 23:07:00 stefans-speaker volumio[27141]: info: Successfully started MPD Monitor Aug 31 23:07:01 stefans-speaker volumio[27141]: info: Initializing connection to go-librespot Websocket Aug 31 23:07:01 stefans-speaker volumio[27141]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 23:07:02 stefans-speaker volumio[27141]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 31 23:07:02 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Aug 31 23:07:02 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:07:02 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:07:02 stefans-speaker go-librespot[27449]: go-librespot daemon starting... Aug 31 23:07:02 stefans-speaker go-librespot[27450]: time="2026-08-31T23:07:02+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:07:02 stefans-speaker go-librespot[27450]: time="2026-08-31T23:07:02+02:00" level=debug msg="app state loaded" Aug 31 23:07:02 stefans-speaker go-librespot[27450]: time="2026-08-31T23:07:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:07:02 stefans-speaker go-librespot[27450]: time="2026-08-31T23:07:02+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 31 23:07:02 stefans-speaker go-librespot[27450]: time="2026-08-31T23:07:02+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 23:07:02 stefans-speaker go-librespot[27450]: time="2026-08-31T23:07:02+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 23:07:02 stefans-speaker go-librespot[27450]: time="2026-08-31T23:07:02+02:00" level=info msg="zeroconf server listening on port 42329" Aug 31 23:07:02 stefans-speaker go-librespot[27450]: time="2026-08-31T23:07:02+02:00" level=debug msg="obtained new client token: AAEtNzgNNllXf5ZYYGek+nkZFsVZU1pg96ApVsZ+gsBGTDn+sA9LN4NUdfnAveT83WJ5sTz/bN4esjlNfkkO3yLSRCsNWnLHdxMsqqUDlDH4Hh9F7B8NuFjYEU8XwV3qnHxxdQkLiW5RojwuCKCrbllJT5sGgvJ7UE7KecI91iHpZ2Kk87NOUigjQe1G32iNMAlLkloDXujbKsBRPC3PdDyLlfA5K2p5gPBHDVUub9yMdo59105xeiquNrA=" Aug 31 23:07:03 stefans-speaker go-librespot[27450]: time="2026-08-31T23:07:03+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 23:07:03 stefans-speaker go-librespot[27450]: time="2026-08-31T23:07:03+02:00" level=debug msg="completed keyexchange" Aug 31 23:07:03 stefans-speaker go-librespot[27450]: time="2026-08-31T23:07:03+02:00" level=debug msg="completed challenge" Aug 31 23:07:03 stefans-speaker go-librespot[27450]: time="2026-08-31T23:07:03+02:00" level=info msg="authenticated AP" username="31************************cy" Aug 31 23:07:03 stefans-speaker go-librespot[27450]: time="2026-08-31T23:07:03+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 31 23:07:03 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:07:03 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 23:07:04 stefans-speaker volumio[27141]: info: Initializing connection to go-librespot Websocket Aug 31 23:07:04 stefans-speaker volumio[27141]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 31 23:07:05 stefans-speaker volumio[27141]: info: Adding plugin bluetooth to MyMusic Plugins Aug 31 23:07:05 stefans-speaker volumio[27141]: info: Adding plugin multiroom to MyMusic Plugins Aug 31 23:07:05 stefans-speaker volumio[27141]: info: Adding plugin metavolumio to MyMusic Plugins Aug 31 23:07:05 stefans-speaker volumio[27141]: info: Adding plugin cd_controller to MyMusic Plugins Aug 31 23:07:05 stefans-speaker volumio[27141]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 31 23:07:05 stefans-speaker volumio[27141]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 31 23:07:05 stefans-speaker volumio[27141]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 31 23:07:05 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 31 23:07:06 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Aug 31 23:07:06 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:07:06 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:07:06 stefans-speaker go-librespot[27471]: go-librespot daemon starting... Aug 31 23:07:06 stefans-speaker go-librespot[27472]: time="2026-08-31T23:07:06+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:07:06 stefans-speaker go-librespot[27472]: time="2026-08-31T23:07:06+02:00" level=debug msg="app state loaded" Aug 31 23:07:06 stefans-speaker go-librespot[27472]: time="2026-08-31T23:07:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:07:06 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 31 23:07:06 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 31 23:07:06 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:07:06 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:07:06 stefans-speaker volumio[27141]: info: Starting MyVolumio Remote Streaming Endpoints Aug 31 23:07:06 stefans-speaker volumio[27141]: info: MyVolumio login type: Token Aug 31 23:07:06 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 31 23:07:06 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 31 23:07:06 stefans-speaker go-librespot[27472]: time="2026-08-31T23:07:06+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 31 23:07:06 stefans-speaker go-librespot[27472]: time="2026-08-31T23:07:06+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 23:07:06 stefans-speaker go-librespot[27472]: time="2026-08-31T23:07:06+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 23:07:06 stefans-speaker go-librespot[27472]: time="2026-08-31T23:07:06+02:00" level=info msg="zeroconf server listening on port 46393" Aug 31 23:07:07 stefans-speaker go-librespot[27472]: time="2026-08-31T23:07:07+02:00" level=debug msg="obtained new client token: AAHZPLzgd5CWfN/WFD5UA/97BCwQru8WZMQwWBQ/quq+iPxtUh3jA2CvYtitk+pGxsFqt70DZ38bU8maidRgcGe2q9w4XWRbZuAYPnPYS0/kiSd+qYPd7K32Pe/hoCUu8kubSsGubG1PiiaBDYPlkB8kB7Z95kdNcRFVr9lfaQrXWi9Sp+6GBmbYVEkO19FQVK66lmBZeOUAMMMKmBe+rVB7h6DbRWQRTqlWOSRqX9rZ9BEQ06xMJzvT" Aug 31 23:07:07 stefans-speaker go-librespot[27472]: time="2026-08-31T23:07:07+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 23:07:07 stefans-speaker go-librespot[27472]: time="2026-08-31T23:07:07+02:00" level=debug msg="completed keyexchange" Aug 31 23:07:07 stefans-speaker go-librespot[27472]: time="2026-08-31T23:07:07+02:00" level=debug msg="completed challenge" Aug 31 23:07:07 stefans-speaker go-librespot[27472]: time="2026-08-31T23:07:07+02:00" level=info msg="authenticated AP" username="31************************cy" Aug 31 23:07:07 stefans-speaker go-librespot[27472]: time="2026-08-31T23:07:07+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 31 23:07:07 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:07:07 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 23:07:07 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 31 23:07:07 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 31 23:07:07 stefans-speaker volumio[27141]: info: Streaming services startup Aug 31 23:07:07 stefans-speaker volumio[27141]: info: Starting Streaming Daemon Aug 31 23:07:07 stefans-speaker sudo[27480]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:07:07 stefans-speaker volumio[27141]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 31 23:07:07 stefans-speaker sudo[27480]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 31 23:07:07 stefans-speaker sudo[27480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:07:07 stefans-speaker sudo[27480]: pam_unix(sudo:session): session closed for user root Aug 31 23:07:07 stefans-speaker volumio[27141]: info: Initializing connection to go-librespot Websocket Aug 31 23:07:07 stefans-speaker volumio[27141]: error: Cannot start Volumio Streaming Daemon Aug 31 23:07:07 stefans-speaker volumio[27141]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 31 23:07:07 stefans-speaker volumio[27141]: sudo: unable to resolve host stefans-speaker: System error Aug 31 23:07:07 stefans-speaker volumio[27141]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 31 23:07:07 stefans-speaker volumio[27141]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 23:07:08 stefans-speaker volumio[27141]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 31 23:07:08 stefans-speaker volumio[27141]: info: MyVolumio token set successfully Aug 31 23:07:08 stefans-speaker volumio[27141]: info: MYVOLUMIO: Adding device Aug 31 23:07:08 stefans-speaker volumio[27141]: info: MYVOLUMIO: Evaluating Server Aug 31 23:07:09 stefans-speaker volumio[27141]: info: MyVolumio status changed Aug 31 23:07:09 stefans-speaker volumio[27141]: info: Streaming services startup Aug 31 23:07:09 stefans-speaker volumio[27141]: info: Starting Streaming Daemon Aug 31 23:07:09 stefans-speaker volumio[27141]: info: Removing browser output: myVolumio user plan is not superstar Aug 31 23:07:09 stefans-speaker volumio[27141]: info: Removing audio output: Aug 31 23:07:09 stefans-speaker volumio[27141]: info: Stoppping Tunnel 1 Aug 31 23:07:09 stefans-speaker sudo[27508]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:07:09 stefans-speaker sudo[27508]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 31 23:07:09 stefans-speaker sudo[27508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:07:09 stefans-speaker sudo[27510]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:07:09 stefans-speaker sudo[27510]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 31 23:07:09 stefans-speaker sudo[27510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:07:09 stefans-speaker sudo[27508]: pam_unix(sudo:session): session closed for user root Aug 31 23:07:09 stefans-speaker volumio[27141]: error: Cannot start Volumio Streaming Daemon Aug 31 23:07:09 stefans-speaker volumio[27141]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 31 23:07:09 stefans-speaker volumio[27141]: sudo: unable to resolve host stefans-speaker: System error Aug 31 23:07:09 stefans-speaker volumio[27141]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 31 23:07:09 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. Aug 31 23:07:09 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. Aug 31 23:07:09 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. Aug 31 23:07:09 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. Aug 31 23:07:09 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. Aug 31 23:07:09 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. Aug 31 23:07:09 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. Aug 31 23:07:09 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. Aug 31 23:07:09 stefans-speaker sudo[27510]: pam_unix(sudo:session): session closed for user root Aug 31 23:07:09 stefans-speaker volumio[27141]: info: Remote SSH Stopped Aug 31 23:07:10 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Aug 31 23:07:10 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:07:10 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:07:10 stefans-speaker go-librespot[27513]: go-librespot daemon starting... Aug 31 23:07:10 stefans-speaker go-librespot[27514]: time="2026-08-31T23:07:10+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:07:10 stefans-speaker go-librespot[27514]: time="2026-08-31T23:07:10+02:00" level=debug msg="app state loaded" Aug 31 23:07:10 stefans-speaker go-librespot[27514]: time="2026-08-31T23:07:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:07:10 stefans-speaker go-librespot[27514]: time="2026-08-31T23:07:10+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 31 23:07:10 stefans-speaker go-librespot[27514]: time="2026-08-31T23:07:10+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 23:07:10 stefans-speaker go-librespot[27514]: time="2026-08-31T23:07:10+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 23:07:10 stefans-speaker go-librespot[27514]: time="2026-08-31T23:07:10+02:00" level=info msg="zeroconf server listening on port 45711" Aug 31 23:07:10 stefans-speaker volumio[27141]: info: Initializing connection to go-librespot Websocket Aug 31 23:07:10 stefans-speaker go-librespot[27514]: time="2026-08-31T23:07:10+02:00" level=debug msg="obtained new client token: AAGrJuiK7GPn/5GXb1yrD+Gve72g33Ze28Dlx9SopES1EJ5nmzlVxOwOjWCKCqFA4IQuog/iVN5TV/HzO6BmeMhIh5tBvrlT4h0tP04zOUZ1ros9w+z3HTYEpmiH+uK+rGCEgGPWnbY6iHmwQA8KFhH8p6gQ5G5zSwx/B9Ad1Bd6nMAzkFz+tHrgD70r8BFMj8iAWc1YE1KXqDHno82nqXc1MVQ2GF2THag7N0Nqn185f4G5KSMFiLC/jEw=" Aug 31 23:07:10 stefans-speaker go-librespot[27514]: time="2026-08-31T23:07:10+02:00" level=debug msg="new websocket client" Aug 31 23:07:10 stefans-speaker volumio[27141]: info: Connection to go-librespot Websocket established Aug 31 23:07:11 stefans-speaker go-librespot[27514]: time="2026-08-31T23:07:11+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 23:07:11 stefans-speaker volumio[27141]: info: Setting Geolocation for MyVolumio to eu12 Aug 31 23:07:11 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:07:11 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:07:11 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:07:11 stefans-speaker volumio[27141]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 31 23:07:11 stefans-speaker go-librespot[27514]: time="2026-08-31T23:07:11+02:00" level=debug msg="completed keyexchange" Aug 31 23:07:11 stefans-speaker go-librespot[27514]: time="2026-08-31T23:07:11+02:00" level=debug msg="completed challenge" Aug 31 23:07:11 stefans-speaker go-librespot[27514]: time="2026-08-31T23:07:11+02:00" level=info msg="authenticated AP" username="31************************cy" Aug 31 23:07:11 stefans-speaker go-librespot[27514]: time="2026-08-31T23:07:11+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 31 23:07:11 stefans-speaker volumio[27141]: info: Connection to go-librespot Websocket closed Aug 31 23:07:11 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:07:11 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 23:07:12 stefans-speaker volumio[27141]: info: Updating MyVolumio device info Aug 31 23:07:12 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:07:12 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:07:12 stefans-speaker volumio[27141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:07:12 stefans-speaker volumio[27141]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 31 23:07:14 stefans-speaker volumio[27141]: info: Getting Spotify volume Aug 31 23:07:14 stefans-speaker volumio[27141]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 23:07:14 stefans-speaker volumio[27141]: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 23:07:14 stefans-speaker volumio[27141]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Aug 31 23:07:14 stefans-speaker volumio[27141]: errno: -111, Aug 31 23:07:14 stefans-speaker volumio[27141]: code: 'ECONNREFUSED', Aug 31 23:07:14 stefans-speaker volumio[27141]: syscall: 'connect', Aug 31 23:07:14 stefans-speaker volumio[27141]: address: '127.0.0.1', Aug 31 23:07:14 stefans-speaker volumio[27141]: port: 9879, Aug 31 23:07:14 stefans-speaker volumio[27141]: response: undefined Aug 31 23:07:14 stefans-speaker volumio[27141]: } Aug 31 23:07:14 stefans-speaker volumio[27141]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 23:07:14 stefans-speaker sudo[27535]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:07:14 stefans-speaker sudo[27535]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-31 23:06' Aug 31 23:07:14 stefans-speaker sudo[27535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:07:14 stefans-speaker sudo[27535]: pam_unix(sudo:session): session closed for user root Aug 31 23:07:15 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Aug 31 23:07:15 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:07:15 stefans-speaker volumio[27141]: sudo: unable to resolve host stefans-speaker: System error Aug 31 23:07:15 stefans-speaker volumio-remote-updater[679]: [2026-08-31 23:07:15] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 31 23:07:15 stefans-speaker volumio-remote-updater[679]: [2026-08-31 23:07:15] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 31 23:07:15 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:07:15 stefans-speaker go-librespot[27540]: go-librespot daemon starting... Aug 31 23:07:15 stefans-speaker systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:07:15 stefans-speaker systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 31 23:07:15 stefans-speaker systemd[1]: volumio.service: Consumed 32.418s CPU time. Aug 31 23:07:15 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Aug 31 23:07:15 stefans-speaker go-librespot[27541]: time="2026-08-31T23:07:15+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:07:15 stefans-speaker go-librespot[27541]: time="2026-08-31T23:07:15+02:00" level=debug msg="app state loaded" Aug 31 23:07:15 stefans-speaker go-librespot[27541]: time="2026-08-31T23:07:15+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:07:15 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Aug 31 23:07:15 stefans-speaker systemd[1]: volumio.service: Scheduled restart job, restart counter is at 122. Aug 31 23:07:15 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Aug 31 23:07:15 stefans-speaker systemd[1]: Stopped volumio.service - Volumio Backend Module. Aug 31 23:07:15 stefans-speaker systemd[1]: volumio.service: Consumed 32.418s CPU time. Aug 31 23:07:15 stefans-speaker systemd[1]: Started volumio.service - Volumio Backend Module. Aug 31 23:07:15 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Aug 31 23:07:15 stefans-speaker go-librespot[27541]: time="2026-08-31T23:07:15+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 31 23:07:15 stefans-speaker go-librespot[27541]: time="2026-08-31T23:07:15+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 23:07:15 stefans-speaker go-librespot[27541]: time="2026-08-31T23:07:15+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 23:07:15 stefans-speaker go-librespot[27541]: time="2026-08-31T23:07:15+02:00" level=info msg="zeroconf server listening on port 45787" Aug 31 23:07:15 stefans-speaker go-librespot[27541]: time="2026-08-31T23:07:15+02:00" level=debug msg="obtained new client token: AAF44zXYDsRGfKF8VMtyvE713nhvd8VYx9/58v8FcN05DDMuz0dAgS+H6aGQkZDlMj+rfbM11WEeY+Y++MmlDyes8SeyuvDF0UR1UbRJWc9pKCWHlcHjkz9RKmAG1zDV8YwuFZNDXMm2kcady64rW1UA7chQOk8UR/0PA0fEWfhe2V4u7KAmdF8tH9b7bO3UZtmKBlIhVrieGfFFJ2txhnMeN75I9d/xiYKPdzNr5LLs/ZViLcBXnagJAoo=" Aug 31 23:07:15 stefans-speaker go-librespot[27541]: time="2026-08-31T23:07:15+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 23:07:15 stefans-speaker go-librespot[27541]: time="2026-08-31T23:07:15+02:00" level=debug msg="completed keyexchange" Aug 31 23:07:15 stefans-speaker go-librespot[27541]: time="2026-08-31T23:07:15+02:00" level=debug msg="completed challenge" Aug 31 23:07:15 stefans-speaker go-librespot[27541]: time="2026-08-31T23:07:15+02:00" level=info msg="authenticated AP" username="31************************cy" Aug 31 23:07:15 stefans-speaker go-librespot[27541]: time="2026-08-31T23:07:15+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 31 23:07:15 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:07:15 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 23:07:17 stefans-speaker volumio[27555]: info: ------------------------------------------- Aug 31 23:07:17 stefans-speaker volumio[27555]: info: ----- Volumio3 ---- Aug 31 23:07:17 stefans-speaker volumio[27555]: info: ------------------------------------------- Aug 31 23:07:17 stefans-speaker volumio[27555]: info: ----- System startup ---- Aug 31 23:07:17 stefans-speaker volumio[27555]: info: ------------------------------------------- Aug 31 23:07:18 stefans-speaker volumio[27555]: info: MYVOLUMIO Environment detected Aug 31 23:07:18 stefans-speaker volumio[27555]: info: Plugin folders cleanup Aug 31 23:07:18 stefans-speaker volumio[27555]: info: Scanning into folder /volumio/app/plugins/ Aug 31 23:07:18 stefans-speaker volumio[27555]: info: Scanning category audio_interface Aug 31 23:07:18 stefans-speaker volumio[27555]: info: Scanning category miscellanea Aug 31 23:07:18 stefans-speaker volumio[27555]: info: Scanning category music_service Aug 31 23:07:18 stefans-speaker volumio[27555]: info: Scanning category plugins.json Aug 31 23:07:18 stefans-speaker volumio[27555]: info: Scanning category system_controller Aug 31 23:07:18 stefans-speaker volumio[27555]: info: Scanning category user_interface Aug 31 23:07:18 stefans-speaker volumio[27555]: info: Scanning into folder /data/plugins/ Aug 31 23:07:18 stefans-speaker volumio[27555]: info: Scanning category music_service Aug 31 23:07:18 stefans-speaker volumio[27555]: info: Plugin folders cleanup completed Aug 31 23:07:18 stefans-speaker volumio[27555]: info: ------------------------------------------- Aug 31 23:07:18 stefans-speaker volumio[27555]: info: ----- Core plugins startup ---- Aug 31 23:07:18 stefans-speaker volumio[27555]: info: ------------------------------------------- Aug 31 23:07:18 stefans-speaker volumio[27555]: info: Loading plugins from folder /volumio/app/plugins/ Aug 31 23:07:18 stefans-speaker volumio[27555]: info: Adding plugin upnp to MyMusic Plugins Aug 31 23:07:18 stefans-speaker volumio[27555]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 31 23:07:18 stefans-speaker volumio[27555]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 31 23:07:18 stefans-speaker volumio[27555]: info: Loading plugins from folder /data/plugins/ Aug 31 23:07:18 stefans-speaker volumio[27555]: info: Loading plugin "system"... Aug 31 23:07:18 stefans-speaker volumio[27555]: info: Loading plugin "appearance"... Aug 31 23:07:19 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Aug 31 23:07:19 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:07:19 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:07:19 stefans-speaker go-librespot[27585]: go-librespot daemon starting... Aug 31 23:07:19 stefans-speaker go-librespot[27586]: time="2026-08-31T23:07:19+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:07:19 stefans-speaker go-librespot[27586]: time="2026-08-31T23:07:19+02:00" level=debug msg="app state loaded" Aug 31 23:07:19 stefans-speaker go-librespot[27586]: time="2026-08-31T23:07:19+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:07:19 stefans-speaker volumio[27555]: info: Loading plugin "network"... Aug 31 23:07:19 stefans-speaker volumio[27555]: info: Refreshing Cached IP Addresses Aug 31 23:07:19 stefans-speaker go-librespot[27586]: time="2026-08-31T23:07:19+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 31 23:07:19 stefans-speaker go-librespot[27586]: time="2026-08-31T23:07:19+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 31 23:07:19 stefans-speaker go-librespot[27586]: time="2026-08-31T23:07:19+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 31 23:07:19 stefans-speaker sudo[27594]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:07:19 stefans-speaker sudo[27596]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:07:19 stefans-speaker go-librespot[27586]: time="2026-08-31T23:07:19+02:00" level=info msg="zeroconf server listening on port 32983" Aug 31 23:07:19 stefans-speaker volumio[27555]: info: Loading plugin "services"... Aug 31 23:07:19 stefans-speaker sudo[27594]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 23:07:19 stefans-speaker sudo[27594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:07:19 stefans-speaker volumio[27555]: info: Loading plugin "alsa_controller"... Aug 31 23:07:19 stefans-speaker sudo[27596]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 23:07:19 stefans-speaker sudo[27594]: pam_unix(sudo:session): session closed for user root Aug 31 23:07:19 stefans-speaker sudo[27596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:07:19 stefans-speaker sudo[27596]: pam_unix(sudo:session): session closed for user root Aug 31 23:07:19 stefans-speaker sudo[27603]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:07:19 stefans-speaker sudo[27603]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 31 23:07:19 stefans-speaker sudo[27603]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:07:19 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 23:07:19 stefans-speaker volumio[27555]: info: Loading plugin "wizard"... Aug 31 23:07:19 stefans-speaker volumio[27555]: info: Loading plugin "networkfs"... Aug 31 23:07:19 stefans-speaker volumio[27555]: info: Starting Udev Watcher for removable devices Aug 31 23:07:19 stefans-speaker volumio[27555]: info: Ignoring mount for partition: boot Aug 31 23:07:19 stefans-speaker volumio[27555]: info: Ignoring mount for partition: volumio Aug 31 23:07:19 stefans-speaker volumio[27555]: info: Ignoring mount for partition: volumio_data Aug 31 23:07:19 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 23:07:19 stefans-speaker volumio[27555]: info: Loading plugin "volumio_command_line_client"... Aug 31 23:07:19 stefans-speaker volumio[27555]: info: Loading plugin "upnp"... Aug 31 23:07:19 stefans-speaker volumio[27555]: info: [1788210439604] Starting Upmpd Daemon Aug 31 23:07:19 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 23:07:19 stefans-speaker volumio[27555]: info: Loading plugin "my_music"... Aug 31 23:07:19 stefans-speaker volumio[27555]: info: Loading plugin "mpd"... Aug 31 23:07:19 stefans-speaker go-librespot[27586]: time="2026-08-31T23:07:19+02:00" level=debug msg="obtained new client token: AAHUDDhePJd1Y8WgXARDeTLOHpe5WE6HYtsTCpsbxO9J/GyUE0/xhiv4scmsMbzVHXlaSjFjFtTm+0M8r73xZWB/FbdudSlB6lmVwSrIZGPZ+m28+U1woWAiE1Td3/0kMt5SDT2+GDO+xCfp8JYQy13NW0WGKW4y4XH1NIPJnOQBDalVGTjkpr0cTAVEdDQ+Okn85yDJm5rS1gbR8yHXe4tzMPhOVYWiMPSiTJLNeBdf23jHfszYShWyubw=" Aug 31 23:07:19 stefans-speaker go-librespot[27586]: time="2026-08-31T23:07:19+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 23:07:19 stefans-speaker go-librespot[27586]: time="2026-08-31T23:07:19+02:00" level=debug msg="completed keyexchange" Aug 31 23:07:19 stefans-speaker go-librespot[27586]: time="2026-08-31T23:07:19+02:00" level=debug msg="completed challenge" Aug 31 23:07:19 stefans-speaker volumio[27555]: info: Loading plugin "upnp_browser"... Aug 31 23:07:20 stefans-speaker go-librespot[27586]: time="2026-08-31T23:07:20+02:00" level=info msg="authenticated AP" username="31************************cy" Aug 31 23:07:20 stefans-speaker volumio-remote-updater[679]: [2026-08-31 23:07:20] [connect] Successful connection Aug 31 23:07:20 stefans-speaker go-librespot[27586]: time="2026-08-31T23:07:20+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 31 23:07:20 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:07:20 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 23:07:21 stefans-speaker volumio[27555]: info: Starting UPNP Browser Aug 31 23:07:21 stefans-speaker volumio[27555]: info: Loading plugin "alarm-clock"... Aug 31 23:07:21 stefans-speaker volumio[27555]: info: Loading plugin "airplay_emulation"... Aug 31 23:07:21 stefans-speaker volumio[27555]: info: Starting Shairport Sync Aug 31 23:07:21 stefans-speaker volumio[27555]: info: Loading plugin "last_100"... Aug 31 23:07:21 stefans-speaker volumio[27555]: info: Loading plugin "webradio"... Aug 31 23:07:21 stefans-speaker volumio[27555]: info: Loading plugin "i2s_dacs"... Aug 31 23:07:21 stefans-speaker volumio[27555]: info: I2S DAC not set, start Auto-detection Aug 31 23:07:21 stefans-speaker volumio[27555]: info: Loading plugin "volumiodiscovery"... Aug 31 23:07:21 stefans-speaker volumio[27555]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 31 23:07:21 stefans-speaker volumio[27555]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 23:07:21 stefans-speaker volumio[27555]: *** WARNING *** For more information see Aug 31 23:07:21 stefans-speaker volumio[27555]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 31 23:07:21 stefans-speaker volumio[27555]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 23:07:21 stefans-speaker volumio[27555]: *** WARNING *** For more information see Aug 31 23:07:21 stefans-speaker node[27555]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 31 23:07:21 stefans-speaker node[27555]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 23:07:21 stefans-speaker node[27555]: *** WARNING *** For more information see Aug 31 23:07:21 stefans-speaker node[27555]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 31 23:07:21 stefans-speaker node[27555]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 23:07:21 stefans-speaker node[27555]: *** WARNING *** For more information see Aug 31 23:07:21 stefans-speaker volumio[27555]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 31 23:07:21 stefans-speaker volumio[27555]: info: Discovery: Started advertising with name: Stefan’s Speaker Aug 31 23:07:21 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 23:07:21 stefans-speaker volumio[27555]: info: Loading plugin "spop"... Aug 31 23:07:22 stefans-speaker sudo[27603]: pam_unix(sudo:session): session closed for user root Aug 31 23:07:22 stefans-speaker volumio[27555]: info: Loading plugin "ytcr"... Aug 31 23:07:23 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Aug 31 23:07:23 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:07:23 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:07:23 stefans-speaker go-librespot[27632]: go-librespot daemon starting... Aug 31 23:07:23 stefans-speaker go-librespot[27633]: time="2026-08-31T23:07:23+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:07:23 stefans-speaker go-librespot[27633]: time="2026-08-31T23:07:23+02:00" level=debug msg="app state loaded" Aug 31 23:07:23 stefans-speaker go-librespot[27633]: time="2026-08-31T23:07:23+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:07:23 stefans-speaker go-librespot[27633]: time="2026-08-31T23:07:23+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 31 23:07:23 stefans-speaker go-librespot[27633]: time="2026-08-31T23:07:23+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 23:07:23 stefans-speaker go-librespot[27633]: time="2026-08-31T23:07:23+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 23:07:23 stefans-speaker go-librespot[27633]: time="2026-08-31T23:07:23+02:00" level=info msg="zeroconf server listening on port 43351" Aug 31 23:07:24 stefans-speaker go-librespot[27633]: time="2026-08-31T23:07:24+02:00" level=debug msg="obtained new client token: AAGTpW7RB+v1nJFK22TCqYcmn6a1pOgdT27FPZUEw0tBPZvBntYedRo2QwWo7abG4UbysgXNWDc7SyG0VyBW6sForSm7bDBwUKRAUICTtEZ2v4riOeqM0w26A+lJQjrJ1TCsQscyI8VH+kC/GKz7CVLeYe1gH5yohbMv/u2vKUnkwH8CYCMiI+/HvoO1V0JVouPLmnrH42QGnj3qYuHlbN9Te8iszrHVZlQDPqI0bT4f71uCuQK1tOmp" Aug 31 23:07:24 stefans-speaker go-librespot[27633]: time="2026-08-31T23:07:24+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 23:07:24 stefans-speaker go-librespot[27633]: time="2026-08-31T23:07:24+02:00" level=debug msg="completed keyexchange" Aug 31 23:07:24 stefans-speaker go-librespot[27633]: time="2026-08-31T23:07:24+02:00" level=debug msg="completed challenge" Aug 31 23:07:24 stefans-speaker go-librespot[27633]: time="2026-08-31T23:07:24+02:00" level=info msg="authenticated AP" username="31************************cy" Aug 31 23:07:24 stefans-speaker go-librespot[27633]: time="2026-08-31T23:07:24+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 31 23:07:24 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:07:24 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 23:07:24 stefans-speaker volumio[27555]: info: Loading plugin "outputs"... Aug 31 23:07:24 stefans-speaker volumio[27555]: info: Loading plugin "albumart"... Aug 31 23:07:24 stefans-speaker volumio[27555]: info: Plugin example_plugin is not enabled Aug 31 23:07:24 stefans-speaker volumio[27555]: info: Loading plugin "inputs"... Aug 31 23:07:24 stefans-speaker volumio[27555]: info: Loading plugin "updater_comm"... Aug 31 23:07:24 stefans-speaker volumio[27555]: info: Plugin mpdemulation is not enabled Aug 31 23:07:24 stefans-speaker volumio[27555]: info: Loading plugin "rest_api"... Aug 31 23:07:24 stefans-speaker volumio[27555]: info: Loading plugin "websocket"... Aug 31 23:07:24 stefans-speaker volumio[27555]: info: Starting Socket.io Server version 1.7.4 Aug 31 23:07:24 stefans-speaker volumio[27555]: info: Loading i18n strings for locale nl Aug 31 23:07:24 stefans-speaker volumio[27555]: Updating browse sources language Aug 31 23:07:24 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 23:07:24 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 23:07:24 stefans-speaker volumio[27555]: info: CoreCommandRouter::initPlayerControls Aug 31 23:07:24 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:24 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:24 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:24 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:24 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:24 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:24 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:24 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:24 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 23:07:24 stefans-speaker volumio[27555]: Express server listening on port 3000 Aug 31 23:07:24 stefans-speaker volumio[27555]: [Metrics] WebUI: 8s 143.70ms Aug 31 23:07:24 stefans-speaker volumio[27555]: info: CoreStateMachine::resetVolumioState Aug 31 23:07:24 stefans-speaker volumio[27555]: info: CoreStateMachine::getcurrentVolume Aug 31 23:07:24 stefans-speaker volumio[27555]: info: CoreCommandRouter::volumioRetrievevolume Aug 31 23:07:24 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:07:25 stefans-speaker volumio[27555]: info: Cannot read play queue from file Aug 31 23:07:25 stefans-speaker volumio[27555]: info: Volumio Network Manager: Network status updated: 2 Aug 31 23:07:25 stefans-speaker volumio[27641]: Forking 3 albumart workers Aug 31 23:07:25 stefans-speaker volumio[27555]: info: VolumeController:: Volume=70 Mute =false Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CoreStateMachine::pushState Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CorePlayQueue::getTrack 0 Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CoreCommandRouter::volumioPushState Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CoreStateMachine::updateTrackBlock Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CorePlayQueue::getTrackBlock Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CoreCommandRouter::volumioRetrievevolume Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CoreStateMachine::setRepeat null single undefined Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CoreStateMachine::pushState Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CorePlayQueue::getTrack 0 Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CoreCommandRouter::volumioPushState Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CoreStateMachine::setRandom null Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CoreStateMachine::pushState Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CorePlayQueue::getTrack 0 Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CoreCommandRouter::volumioPushState Aug 31 23:07:25 stefans-speaker volumio[27555]: info: Setting Device type: Raspberry PI Aug 31 23:07:25 stefans-speaker volumio[27555]: info: VolumeController:: Volume=70 Mute =false Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CoreStateMachine::pushState Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CorePlayQueue::getTrack 0 Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CoreCommandRouter::volumioPushState Aug 31 23:07:25 stefans-speaker volumio[27555]: info: Completed loading Core Plugins Aug 31 23:07:25 stefans-speaker volumio[27555]: info: Preparing to generate the ALSA configuration file Aug 31 23:07:25 stefans-speaker volumio[27555]: info: Asound.conf file unchanged, so no further update is needed Aug 31 23:07:25 stefans-speaker volumio[27555]: info: Output device has changed, restarting MPD Aug 31 23:07:25 stefans-speaker volumio[27555]: info: Output device has changed, restarting Shairport Sync Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:07:25 stefans-speaker sudo[27696]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:07:25 stefans-speaker sudo[27696]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 23:07:25 stefans-speaker sudo[27696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:07:25 stefans-speaker sudo[27696]: pam_unix(sudo:session): session closed for user root Aug 31 23:07:25 stefans-speaker sudo[27698]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:07:25 stefans-speaker volumio[27555]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 23:07:25 stefans-speaker volumio[27555]: info: ___________ START PLUGINS ___________ Aug 31 23:07:25 stefans-speaker sudo[27698]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 23:07:25 stefans-speaker sudo[27698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:07:25 stefans-speaker volumio[27555]: info: ControllerMpd::onStart: Initializing MPD Aug 31 23:07:25 stefans-speaker volumio[27555]: info: Creating MPD Configuration file Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 23:07:25 stefans-speaker systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 31 23:07:25 stefans-speaker sudo[27706]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 23:07:25 stefans-speaker volumio[27555]: info: [1788210445796] CoreMusicLibrary::Adding element Media Servers Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 23:07:25 stefans-speaker sudo[27706]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 23:07:25 stefans-speaker sudo[27706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:07:25 stefans-speaker sudo[27706]: pam_unix(sudo:session): session closed for user root Aug 31 23:07:25 stefans-speaker volumio[27555]: info: UPNP Browser: Client initialized successfully Aug 31 23:07:25 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Aug 31 23:07:25 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 31 23:07:25 stefans-speaker systemd[1]: mpd.service: Consumed 4.485s CPU time. Aug 31 23:07:25 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Aug 31 23:07:25 stefans-speaker sudo[27708]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:07:25 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 31 23:07:25 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 31 23:07:25 stefans-speaker sudo[27708]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 23:07:25 stefans-speaker sudo[27708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:07:25 stefans-speaker volumio[27555]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:25 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:07:25 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 23:07:25 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 23:07:26 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Aug 31 23:07:26 stefans-speaker volumio[27555]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 23:07:26 stefans-speaker volumio[27555]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 23:07:26 stefans-speaker volumio[27555]: info: [1788210446014] CoreMusicLibrary::Adding element Last_100 Aug 31 23:07:26 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 23:07:26 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 31 23:07:26 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Aug 31 23:07:26 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 31 23:07:26 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 31 23:07:26 stefans-speaker volumio[27555]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 23:07:26 stefans-speaker volumio[27555]: info: [1788210446035] CoreMusicLibrary::Adding element Webradio Aug 31 23:07:26 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 23:07:26 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 23:07:26 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 23:07:26 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 23:07:26 stefans-speaker volumio[27555]: info: Initializing BBC Radios Aug 31 23:07:26 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 23:07:26 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:07:26 stefans-speaker volumio[27555]: info: Creating Spotify config file Aug 31 23:07:26 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:26 stefans-speaker sudo[27722]: root : unable to resolve host stefans-speaker: System error Aug 31 23:07:26 stefans-speaker sudo[27722]: sudo: unable to resolve host stefans-speaker: System error Aug 31 23:07:26 stefans-speaker sudo[27722]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 23:07:26 stefans-speaker sudo[27722]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 23:07:26 stefans-speaker sudo[27722]: pam_unix(sudo:session): session closed for user root Aug 31 23:07:26 stefans-speaker volumio[27657]: Starting albumart workers Aug 31 23:07:27 stefans-speaker volumio[27555]: info: Volumio Calling Home Aug 31 23:07:27 stefans-speaker volumio[27659]: Starting albumart workers Aug 31 23:07:27 stefans-speaker volumio[27658]: Starting albumart workers Aug 31 23:07:27 stefans-speaker volumio[27555]: info: Discovery: adding a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Aug 31 23:07:27 stefans-speaker volumio[27555]: info: Discovery: Found device Stefan’s Speaker Aug 31 23:07:27 stefans-speaker volumio[27555]: info: CoreCommandRouter::volumioGetState Aug 31 23:07:27 stefans-speaker volumio[27555]: info: CorePlayQueue::getTrack 0 Aug 31 23:07:27 stefans-speaker volumio[27555]: info: Discovery: this is already registered, a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Aug 31 23:07:27 stefans-speaker volumio[27555]: info: Discovery: Found device Stefan’s Speaker Aug 31 23:07:27 stefans-speaker volumio[27555]: info: CoreCommandRouter::volumioGetState Aug 31 23:07:27 stefans-speaker volumio[27555]: info: CorePlayQueue::getTrack 0 Aug 31 23:07:27 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Aug 31 23:07:27 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:07:27 stefans-speaker volumio[27555]: info: MPD Permissions set Aug 31 23:07:27 stefans-speaker volumio[27555]: info: MPD Permissions set Aug 31 23:07:27 stefans-speaker volumio[27555]: info: Spotify config file written Aug 31 23:07:27 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:07:27 stefans-speaker go-librespot[27741]: go-librespot daemon starting... Aug 31 23:07:27 stefans-speaker volumio[27555]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Aug 31 23:07:27 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:27 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:27 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:27 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:27 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:27 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:27 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:27 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:27 stefans-speaker go-librespot[27744]: time="2026-08-31T23:07:27+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:07:27 stefans-speaker go-librespot[27744]: time="2026-08-31T23:07:27+02:00" level=debug msg="app state loaded" Aug 31 23:07:27 stefans-speaker go-librespot[27744]: time="2026-08-31T23:07:27+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:07:27 stefans-speaker sudo[27743]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:07:27 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:07:27 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:27 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:27 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:27 stefans-speaker sudo[27743]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 31 23:07:27 stefans-speaker sudo[27743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:07:27 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:27 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:27 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:27 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:27 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:27 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:27 stefans-speaker systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Aug 31 23:07:27 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:07:27 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 23:07:27 stefans-speaker systemd[1]: go-librespot-daemon.service: Deactivated successfully. Aug 31 23:07:27 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:07:27 stefans-speaker volumio[27555]: info: No need to fix Spotify hosts Aug 31 23:07:27 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:07:27 stefans-speaker go-librespot[27761]: go-librespot daemon starting... Aug 31 23:07:27 stefans-speaker sudo[27743]: pam_unix(sudo:session): session closed for user root Aug 31 23:07:27 stefans-speaker go-librespot[27762]: time="2026-08-31T23:07:27+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:07:27 stefans-speaker go-librespot[27762]: time="2026-08-31T23:07:27+02:00" level=debug msg="app state loaded" Aug 31 23:07:27 stefans-speaker go-librespot[27762]: time="2026-08-31T23:07:27+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:07:28 stefans-speaker go-librespot[27762]: time="2026-08-31T23:07:28+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 31 23:07:28 stefans-speaker go-librespot[27762]: time="2026-08-31T23:07:28+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 23:07:28 stefans-speaker go-librespot[27762]: time="2026-08-31T23:07:28+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 23:07:28 stefans-speaker volumio[27555]: info: Volumio called home Aug 31 23:07:28 stefans-speaker go-librespot[27762]: time="2026-08-31T23:07:28+02:00" level=info msg="zeroconf server listening on port 34547" Aug 31 23:07:28 stefans-speaker go-librespot[27762]: time="2026-08-31T23:07:28+02:00" level=debug msg="obtained new client token: AAHpK7Bn+qag4+u074yF1JirBqf2/vSW2DJER4cDfQlkdVwO3kGpOY9liXx90Zz6ibPcHGr1vTlItnMZ1QYt6IEukWHfzpJKNRXgF9rAg0NI/3PljMCIXCa6OffsoBvQgmJDbYFUfyE43dMHVbqRsPCCfiWvieKqBwENQFsB1J9dgYZLj11+EyrpC8uvOQJ96t4DFds8r99PDvAGOzTK9e+E58cmhyhM4O7mWK+BsKXz7ecjhYNAr/uUiu4=" Aug 31 23:07:28 stefans-speaker go-librespot[27762]: time="2026-08-31T23:07:28+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 23:07:28 stefans-speaker go-librespot[27762]: time="2026-08-31T23:07:28+02:00" level=debug msg="completed keyexchange" Aug 31 23:07:28 stefans-speaker go-librespot[27762]: time="2026-08-31T23:07:28+02:00" level=debug msg="completed challenge" Aug 31 23:07:28 stefans-speaker go-librespot[27762]: time="2026-08-31T23:07:28+02:00" level=info msg="authenticated AP" username="31************************cy" Aug 31 23:07:28 stefans-speaker volumio[27555]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Aug 31 23:07:28 stefans-speaker volumio[27555]: SPOTIFY: BQDLU72MOz3F8YhiBwq6b5xKdtzdorwc1pkT-M_VBJ6nNoHe_P_uqxYy3Esl40YgsPnPBQGBMDiM_2p4oUPdnE_DkTaWmY3h-XVuiaHuE_2kR4iW5CBpYFV0c9BqKWZ3OUiTKClIFdz9jjLf-JQH4pFzY1ZNzq18vWXEwFrZ8u30dLTvmt_fqoUthcTtK3OCtNISmWiSumEd9rzA33Hf8zj2X9X0aM20ySwLneGs5LpfM0RUvd12Hv2f_dr5YMWDQ5IgHYtC-QmKe4Ifgu1c6iJDC4LL8GoK4_6tzHqMyv4XNTTdm8efmuytTEip91A Aug 31 23:07:28 stefans-speaker volumio[27555]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Aug 31 23:07:28 stefans-speaker volumio[27555]: info: New Spotify access token = BQDLU72MOz3F8YhiBwq6b5xKdtzdorwc1pkT-M_VBJ6nNoHe_P_uqxYy3Esl40YgsPnPBQGBMDiM_2p4oUPdnE_DkTaWmY3h-XVuiaHuE_2kR4iW5CBpYFV0c9BqKWZ3OUiTKClIFdz9jjLf-JQH4pFzY1ZNzq18vWXEwFrZ8u30dLTvmt_fqoUthcTtK3OCtNISmWiSumEd9rzA33Hf8zj2X9X0aM20ySwLneGs5LpfM0RUvd12Hv2f_dr5YMWDQ5IgHYtC-QmKe4Ifgu1c6iJDC4LL8GoK4_6tzHqMyv4XNTTdm8efmuytTEip91A Aug 31 23:07:28 stefans-speaker volumio[27555]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 31 23:07:28 stefans-speaker volumio[27555]: info: Starting Shairport Sync Aug 31 23:07:28 stefans-speaker volumio[27555]: info: Starting Shairport Sync Aug 31 23:07:28 stefans-speaker volumio[27555]: info: Starting Shairport Sync Aug 31 23:07:28 stefans-speaker go-librespot[27762]: time="2026-08-31T23:07:28+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 31 23:07:28 stefans-speaker sudo[27789]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:07:28 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:07:28 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 23:07:28 stefans-speaker sudo[27793]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:07:28 stefans-speaker sudo[27793]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 23:07:28 stefans-speaker sudo[27793]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:07:28 stefans-speaker sudo[27789]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 23:07:28 stefans-speaker sudo[27789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:07:28 stefans-speaker sudo[27792]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:07:28 stefans-speaker sudo[27792]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 23:07:28 stefans-speaker sudo[27792]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:07:28 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 31 23:07:28 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Aug 31 23:07:28 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 23:07:28 stefans-speaker systemd[1]: shairport-sync.service: Consumed 1.826s CPU time. Aug 31 23:07:29 stefans-speaker volumio[27555]: info: CoreCommandRouter::volumioGetState Aug 31 23:07:29 stefans-speaker volumio[27555]: info: CorePlayQueue::getTrack 0 Aug 31 23:07:29 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 23:07:29 stefans-speaker sudo[27793]: pam_unix(sudo:session): session closed for user root Aug 31 23:07:29 stefans-speaker sudo[27792]: pam_unix(sudo:session): session closed for user root Aug 31 23:07:29 stefans-speaker sudo[27789]: pam_unix(sudo:session): session closed for user root Aug 31 23:07:29 stefans-speaker volumio[27555]: info: Shairport-Sync Started Aug 31 23:07:29 stefans-speaker volumio[27555]: Error adding Membership: Error: addMembership EINVAL Aug 31 23:07:29 stefans-speaker volumio[27555]: info: Shairport-Sync Started Aug 31 23:07:29 stefans-speaker volumio[27555]: info: Shairport-Sync Started Aug 31 23:07:29 stefans-speaker volumio[27555]: 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"} Aug 31 23:07:29 stefans-speaker volumio[27555]: info: Spotify Successfully logged in Aug 31 23:07:29 stefans-speaker volumio[27555]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 23:07:29 stefans-speaker volumio[27555]: info: [1788210449281] CoreMusicLibrary::Adding element Spotify Aug 31 23:07:29 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 23:07:29 stefans-speaker volumio[27555]: Cannot find translation for source Spotify Aug 31 23:07:29 stefans-speaker sudo[27829]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:07:29 stefans-speaker sudo[27829]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 23:07:29 stefans-speaker sudo[27829]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:07:29 stefans-speaker sudo[27830]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:07:29 stefans-speaker volumio[27555]: info: [yt-cast-receiver] DIAL server listening on port 8098 Aug 31 23:07:29 stefans-speaker sudo[27830]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 23:07:29 stefans-speaker sudo[27829]: pam_unix(sudo:session): session closed for user root Aug 31 23:07:29 stefans-speaker sudo[27830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:07:29 stefans-speaker sudo[27830]: pam_unix(sudo:session): session closed for user root Aug 31 23:07:29 stefans-speaker volumio[27555]: info: CoreCommandRouter::volumioRetrievevolume Aug 31 23:07:29 stefans-speaker volumio[27555]: info: VolumeController:: Volume=70 Mute =false Aug 31 23:07:29 stefans-speaker volumio[27555]: info: CoreCommandRouter::volumioGetState Aug 31 23:07:29 stefans-speaker volumio[27555]: info: CorePlayQueue::getTrack 0 Aug 31 23:07:29 stefans-speaker volumio[27555]: info: CoreStateMachine::pushState Aug 31 23:07:29 stefans-speaker volumio[27555]: info: CorePlayQueue::getTrack 0 Aug 31 23:07:29 stefans-speaker volumio[27555]: info: CoreCommandRouter::volumioPushState Aug 31 23:07:30 stefans-speaker sudo[27836]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:07:30 stefans-speaker sudo[27836]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 31 23:07:30 stefans-speaker sudo[27836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:07:30 stefans-speaker sudo[27836]: pam_unix(sudo:session): session closed for user root Aug 31 23:07:30 stefans-speaker volumio[27555]: info: Upmpdcli Daemon Started Aug 31 23:07:30 stefans-speaker mpd[27737]: 2026-08-31T23:07:30 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 23:07:30 stefans-speaker systemd[1]: Started mpd.service - Music Player Daemon. Aug 31 23:07:30 stefans-speaker sudo[27708]: pam_unix(sudo:session): session closed for user root Aug 31 23:07:30 stefans-speaker sudo[27698]: pam_unix(sudo:session): session closed for user root Aug 31 23:07:30 stefans-speaker volumio[27555]: error: MPD error: The expression evaluated to a falsy value: Aug 31 23:07:30 stefans-speaker volumio[27555]: assert.ok(self.idling) Aug 31 23:07:30 stefans-speaker volumio[27555]: error: The expression evaluated to a falsy value: Aug 31 23:07:30 stefans-speaker volumio[27555]: assert.ok(self.idling) Aug 31 23:07:30 stefans-speaker volumio[27555]: info: MPD running with PID27737 Aug 31 23:07:30 stefans-speaker volumio[27555]: ,establishing connection Aug 31 23:07:30 stefans-speaker volumio[27555]: error: updateQueue error: null Aug 31 23:07:30 stefans-speaker volumio[27555]: info: Completed starting Core Plugins Aug 31 23:07:30 stefans-speaker volumio[27555]: info: ------------------------------------------- Aug 31 23:07:30 stefans-speaker volumio[27555]: info: ----- MyVolumio plugins startup ---- Aug 31 23:07:30 stefans-speaker volumio[27555]: info: ------------------------------------------- Aug 31 23:07:30 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 31 23:07:30 stefans-speaker volumio[27555]: error: updateQueue error: null Aug 31 23:07:31 stefans-speaker volumio[27555]: info: go-librespot daemon successfully initialized Aug 31 23:07:32 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Aug 31 23:07:32 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:07:32 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:07:32 stefans-speaker go-librespot[27844]: go-librespot daemon starting... Aug 31 23:07:32 stefans-speaker go-librespot[27845]: time="2026-08-31T23:07:32+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:07:32 stefans-speaker go-librespot[27845]: time="2026-08-31T23:07:32+02:00" level=debug msg="app state loaded" Aug 31 23:07:32 stefans-speaker go-librespot[27845]: time="2026-08-31T23:07:32+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:07:32 stefans-speaker go-librespot[27845]: time="2026-08-31T23:07:32+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 31 23:07:32 stefans-speaker go-librespot[27845]: time="2026-08-31T23:07:32+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 23:07:32 stefans-speaker go-librespot[27845]: time="2026-08-31T23:07:32+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 23:07:32 stefans-speaker go-librespot[27845]: time="2026-08-31T23:07:32+02:00" level=info msg="zeroconf server listening on port 34699" Aug 31 23:07:32 stefans-speaker go-librespot[27845]: time="2026-08-31T23:07:32+02:00" level=debug msg="obtained new client token: AAFyKVWsCfc8tBiNUgR7u7H6b0MtInsBOOmzGqUjY7FJdsaDs2yircxCShNCNCmMdrM8vC9bpv06+9ohLz99U/2bVpQEU940gKeXa+z3vnhPdivM/spWE0c/5riq1W93tedOA7WEqfEMcXFn/DMmYNaM39yoxvXUsNyG9uZa+p6iK9YCepqsefZRZfy5nj8sQmbJpLkQZhDKWnBHdxSTVPF3eOEp4RcL1liik0xuuCx6ABRfEyT/wHU4n50=" Aug 31 23:07:32 stefans-speaker volumio-remote-updater[679]: [2026-08-31 23:07:32] [connect] Successful connection Aug 31 23:07:32 stefans-speaker volumio-remote-updater[679]: [2026-08-31 23:07:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788210452 101 Aug 31 23:07:32 stefans-speaker volumio[27555]: 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 Aug 31 23:07:32 stefans-speaker go-librespot[27845]: time="2026-08-31T23:07:32+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 23:07:32 stefans-speaker go-librespot[27845]: time="2026-08-31T23:07:32+02:00" level=debug msg="completed keyexchange" Aug 31 23:07:32 stefans-speaker go-librespot[27845]: time="2026-08-31T23:07:32+02:00" level=debug msg="completed challenge" Aug 31 23:07:32 stefans-speaker go-librespot[27845]: time="2026-08-31T23:07:32+02:00" level=info msg="authenticated AP" username="31************************cy" Aug 31 23:07:32 stefans-speaker go-librespot[27845]: time="2026-08-31T23:07:32+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 31 23:07:32 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:07:32 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 23:07:33 stefans-speaker sudo[27855]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:07:33 stefans-speaker sudo[27857]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:07:33 stefans-speaker sudo[27855]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Aug 31 23:07:33 stefans-speaker sudo[27855]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:07:33 stefans-speaker sudo[27857]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Aug 31 23:07:33 stefans-speaker sudo[27857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:07:33 stefans-speaker systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Aug 31 23:07:33 stefans-speaker systemd[1]: mpd_monitor.service: Deactivated successfully. Aug 31 23:07:33 stefans-speaker systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Aug 31 23:07:34 stefans-speaker systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Aug 31 23:07:34 stefans-speaker sudo[27857]: pam_unix(sudo:session): session closed for user root Aug 31 23:07:34 stefans-speaker sudo[27855]: pam_unix(sudo:session): session closed for user root Aug 31 23:07:34 stefans-speaker volumio[27555]: info: Successfully started MPD Monitor Aug 31 23:07:34 stefans-speaker mpd_monitor.sh[27860]: MPD Monitor Service: Starting MPD Monitor Service Aug 31 23:07:34 stefans-speaker volumio[27555]: info: Successfully started MPD Monitor Aug 31 23:07:34 stefans-speaker volumio[27555]: info: Initializing connection to go-librespot Websocket Aug 31 23:07:34 stefans-speaker volumio[27555]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 23:07:35 stefans-speaker volumio[27555]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 31 23:07:36 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Aug 31 23:07:36 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:07:36 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:07:36 stefans-speaker go-librespot[27864]: go-librespot daemon starting... Aug 31 23:07:36 stefans-speaker go-librespot[27865]: time="2026-08-31T23:07:36+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:07:36 stefans-speaker go-librespot[27865]: time="2026-08-31T23:07:36+02:00" level=debug msg="app state loaded" Aug 31 23:07:36 stefans-speaker go-librespot[27865]: time="2026-08-31T23:07:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:07:36 stefans-speaker go-librespot[27865]: time="2026-08-31T23:07: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 31 23:07:36 stefans-speaker go-librespot[27865]: time="2026-08-31T23:07:36+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 23:07:36 stefans-speaker go-librespot[27865]: time="2026-08-31T23:07:36+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 23:07:36 stefans-speaker go-librespot[27865]: time="2026-08-31T23:07:36+02:00" level=info msg="zeroconf server listening on port 38737" Aug 31 23:07:36 stefans-speaker go-librespot[27865]: time="2026-08-31T23:07:36+02:00" level=debug msg="obtained new client token: AAF8NVzf638uKxh4KdGCka7QYFmA3A8rel7lDcRrwR1OB7YxemlxCVjSxK1Eea+0nPHUhcOvYWAU8LktaT0Qco6sGd/DOsbL0jIc6WZYbgQ3Sz+eCC4Ph69pfqz9OJykPKZ4sYQmL8GMfY7ua5tP+bc/NWXTpKLmuIzrY+hzTNf/+/koIZxbWKXxlG6JR56bOD3Lm7iVh2sZBO9Py8DXGd+9tYbV5Jgt6jRKUL/MAW0ZyK2VpWvtHgas6ZI=" Aug 31 23:07:36 stefans-speaker go-librespot[27865]: time="2026-08-31T23:07:36+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 23:07:36 stefans-speaker go-librespot[27865]: time="2026-08-31T23:07:36+02:00" level=debug msg="completed keyexchange" Aug 31 23:07:36 stefans-speaker go-librespot[27865]: time="2026-08-31T23:07:36+02:00" level=debug msg="completed challenge" Aug 31 23:07:36 stefans-speaker go-librespot[27865]: time="2026-08-31T23:07:36+02:00" level=info msg="authenticated AP" username="31************************cy" Aug 31 23:07:37 stefans-speaker volumio[27555]: info: Initializing connection to go-librespot Websocket Aug 31 23:07:37 stefans-speaker go-librespot[27865]: time="2026-08-31T23:07:37+02:00" level=debug msg="new websocket client" Aug 31 23:07:37 stefans-speaker volumio[27555]: info: Connection to go-librespot Websocket established Aug 31 23:07:37 stefans-speaker go-librespot[27865]: time="2026-08-31T23:07:37+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 31 23:07:37 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:07:37 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 23:07:37 stefans-speaker volumio[27555]: info: Connection to go-librespot Websocket closed Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 31 23:07:39 stefans-speaker volumio[27555]: info: Adding plugin bluetooth to MyMusic Plugins Aug 31 23:07:39 stefans-speaker volumio[27555]: info: Adding plugin multiroom to MyMusic Plugins Aug 31 23:07:39 stefans-speaker volumio[27555]: info: Adding plugin metavolumio to MyMusic Plugins Aug 31 23:07:39 stefans-speaker volumio[27555]: info: Adding plugin cd_controller to MyMusic Plugins Aug 31 23:07:39 stefans-speaker volumio[27555]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 31 23:07:39 stefans-speaker volumio[27555]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 31 23:07:39 stefans-speaker volumio[27555]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 31 23:07:39 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 31 23:07:40 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 31 23:07:40 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 31 23:07:40 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:07:40 stefans-speaker volumio[27555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 23:07:40 stefans-speaker volumio[27555]: info: Starting MyVolumio Remote Streaming Endpoints Aug 31 23:07:40 stefans-speaker volumio[27555]: info: MyVolumio login type: Token Aug 31 23:07:40 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 31 23:07:40 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 31 23:07:41 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Aug 31 23:07:41 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:07:41 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 31 23:07:41 stefans-speaker go-librespot[27887]: go-librespot daemon starting... Aug 31 23:07:41 stefans-speaker go-librespot[27888]: time="2026-08-31T23:07:41+02:00" level=info msg="running go-librespot 0.4.0" Aug 31 23:07:41 stefans-speaker go-librespot[27888]: time="2026-08-31T23:07:41+02:00" level=debug msg="app state loaded" Aug 31 23:07:41 stefans-speaker go-librespot[27888]: time="2026-08-31T23:07:41+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 23:07:41 stefans-speaker go-librespot[27888]: time="2026-08-31T23:07:41+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 31 23:07:41 stefans-speaker go-librespot[27888]: time="2026-08-31T23:07:41+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 23:07:41 stefans-speaker go-librespot[27888]: time="2026-08-31T23:07:41+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 23:07:41 stefans-speaker go-librespot[27888]: time="2026-08-31T23:07:41+02:00" level=info msg="zeroconf server listening on port 34653" Aug 31 23:07:41 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 31 23:07:41 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 31 23:07:41 stefans-speaker volumio[27555]: info: Streaming services startup Aug 31 23:07:41 stefans-speaker volumio[27555]: info: Starting Streaming Daemon Aug 31 23:07:41 stefans-speaker go-librespot[27888]: time="2026-08-31T23:07:41+02:00" level=debug msg="obtained new client token: AAEJ34Zh6B/E8BSmPN4HRATDxugpLAbYPM9++wfzc5S/K49M6vpN6rgF6dxAoIAib6NJD/MxqiKrBVz69Uwa8u2wLdRNJnCCDET96hSKQnJ/GnVfAMYwuB1XuzVYqi3kYC1ajduZKztWGXVFI8UD/0D7MnwYg1f6W5CMm9ZnPCFrtog6n9pj/KC60Rzk2SSaUdwIs6t2rw2M+br2SIof771dHf+nYZCrFqfABq6pqm2hFuAJJTUdQmkdTsw=" Aug 31 23:07:41 stefans-speaker volumio[27555]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 31 23:07:41 stefans-speaker sudo[27896]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:07:41 stefans-speaker sudo[27896]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 31 23:07:41 stefans-speaker sudo[27896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 23:07:41 stefans-speaker sudo[27896]: pam_unix(sudo:session): session closed for user root Aug 31 23:07:41 stefans-speaker go-librespot[27888]: time="2026-08-31T23:07:41+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 23:07:41 stefans-speaker volumio[27555]: info: Getting Spotify volume Aug 31 23:07:41 stefans-speaker volumio[27555]: info: Initializing connection to go-librespot Websocket Aug 31 23:07:41 stefans-speaker volumio[27555]: error: Cannot start Volumio Streaming Daemon Aug 31 23:07:41 stefans-speaker volumio[27555]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 31 23:07:41 stefans-speaker volumio[27555]: sudo: unable to resolve host stefans-speaker: System error Aug 31 23:07:41 stefans-speaker volumio[27555]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 31 23:07:41 stefans-speaker go-librespot[27888]: time="2026-08-31T23:07:41+02:00" level=debug msg="completed keyexchange" Aug 31 23:07:41 stefans-speaker go-librespot[27888]: time="2026-08-31T23:07:41+02:00" level=debug msg="new websocket client" Aug 31 23:07:41 stefans-speaker go-librespot[27888]: time="2026-08-31T23:07:41+02:00" level=debug msg="completed challenge" Aug 31 23:07:41 stefans-speaker volumio[27555]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 31 23:07:41 stefans-speaker volumio[27555]: info: Connection to go-librespot Websocket established Aug 31 23:07:41 stefans-speaker go-librespot[27888]: time="2026-08-31T23:07:41+02:00" level=info msg="authenticated AP" username="31************************cy" Aug 31 23:07:41 stefans-speaker volumio[27555]: info: CoreCommandRouter::volumioGetState Aug 31 23:07:41 stefans-speaker volumio[27555]: info: CorePlayQueue::getTrack 0 Aug 31 23:07:41 stefans-speaker volumio[27555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Aug 31 23:07:41 stefans-speaker volumio[27555]: SPOTIFY: SPOTIFY VOLUME undefined Aug 31 23:07:41 stefans-speaker volumio[27555]: SPOTIFY: VOLUMIO VOLUME 70 Aug 31 23:07:41 stefans-speaker volumio[27555]: info: Aligning Spotify Volume to Volumio Volume Aug 31 23:07:41 stefans-speaker volumio[27555]: info: CoreCommandRouter::volumioGetState Aug 31 23:07:41 stefans-speaker volumio[27555]: info: CorePlayQueue::getTrack 0 Aug 31 23:07:41 stefans-speaker volumio[27555]: info: Setting Spotify Volume from Volumio: 70 Aug 31 23:07:41 stefans-speaker go-librespot[27888]: time="2026-08-31T23:07:41+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 31 23:07:41 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 23:07:41 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 23:07:41 stefans-speaker volumio[27555]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 23:07:41 stefans-speaker volumio[27555]: Error: socket hang up Aug 31 23:07:41 stefans-speaker volumio[27555]: at connResetException (node:internal/errors:720:14) Aug 31 23:07:41 stefans-speaker volumio[27555]: at Socket.socketOnEnd (node:_http_client:519:23) Aug 31 23:07:41 stefans-speaker volumio[27555]: at Socket.emit (node:events:526:35) Aug 31 23:07:41 stefans-speaker volumio[27555]: at endReadableNT (node:internal/streams/readable:1376:12) Aug 31 23:07:41 stefans-speaker volumio[27555]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Aug 31 23:07:41 stefans-speaker volumio[27555]: code: 'ECONNRESET', Aug 31 23:07:41 stefans-speaker volumio[27555]: response: undefined Aug 31 23:07:41 stefans-speaker volumio[27555]: } Aug 31 23:07:41 stefans-speaker volumio[27555]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 23:07:42 stefans-speaker sudo[27917]: volumio : unable to resolve host stefans-speaker: System error Aug 31 23:07:42 stefans-speaker sudo[27917]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-31 23:06' Aug 31 23:07:42 stefans-speaker sudo[27917]: 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"