Aug 31 22:58:00 stefans-speaker volumio[20483]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Aug 31 22:58:00 stefans-speaker volumio[20483]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Aug 31 22:58:00 stefans-speaker volumio[20483]: info: Streaming services startup
Aug 31 22:58:00 stefans-speaker volumio[20483]: info: Starting Streaming Daemon
Aug 31 22:58:00 stefans-speaker sudo[20822]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:58:00 stefans-speaker volumio[20483]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Aug 31 22:58:00 stefans-speaker sudo[20822]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Aug 31 22:58:00 stefans-speaker sudo[20822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:58:00 stefans-speaker sudo[20822]: pam_unix(sudo:session): session closed for user root
Aug 31 22:58:00 stefans-speaker volumio[20483]: info: Initializing connection to go-librespot Websocket
Aug 31 22:58:01 stefans-speaker volumio[20483]: error: Cannot start Volumio Streaming Daemon
Aug 31 22:58:01 stefans-speaker volumio[20483]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Aug 31 22:58:01 stefans-speaker volumio[20483]: sudo: unable to resolve host stefans-speaker: System error
Aug 31 22:58:01 stefans-speaker volumio[20483]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Aug 31 22:58:01 stefans-speaker volumio[20483]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 31 22:58:01 stefans-speaker volumio[20483]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Aug 31 22:58:02 stefans-speaker volumio[20483]: info: MyVolumio token set successfully
Aug 31 22:58:02 stefans-speaker volumio[20483]: info: MYVOLUMIO: Adding device
Aug 31 22:58:02 stefans-speaker volumio[20483]: info: MYVOLUMIO: Evaluating Server
Aug 31 22:58:02 stefans-speaker volumio[20483]: info: MyVolumio status changed
Aug 31 22:58:02 stefans-speaker volumio[20483]: info: Streaming services startup
Aug 31 22:58:02 stefans-speaker volumio[20483]: info: Starting Streaming Daemon
Aug 31 22:58:02 stefans-speaker volumio[20483]: info: Removing browser output: myVolumio user plan is not superstar
Aug 31 22:58:02 stefans-speaker volumio[20483]: info: Removing audio output:
Aug 31 22:58:02 stefans-speaker volumio[20483]: info: Stoppping Tunnel 1
Aug 31 22:58:02 stefans-speaker sudo[20850]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:58:02 stefans-speaker sudo[20850]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Aug 31 22:58:02 stefans-speaker sudo[20850]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:58:02 stefans-speaker sudo[20852]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:58:02 stefans-speaker sudo[20852]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Aug 31 22:58:02 stefans-speaker sudo[20852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:58:02 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 31 22:58:02 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 31 22:58:02 stefans-speaker sudo[20850]: pam_unix(sudo:session): session closed for user root
Aug 31 22:58:02 stefans-speaker volumio[20483]: error: Cannot start Volumio Streaming Daemon
Aug 31 22:58:02 stefans-speaker volumio[20483]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Aug 31 22:58:02 stefans-speaker volumio[20483]: sudo: unable to resolve host stefans-speaker: System error
Aug 31 22:58:02 stefans-speaker volumio[20483]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Aug 31 22:58:02 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 31 22:58:02 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 31 22:58:02 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 31 22:58:02 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 31 22:58:02 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 31 22:58:02 stefans-speaker systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 31 22:58:02 stefans-speaker sudo[20852]: pam_unix(sudo:session): session closed for user root
Aug 31 22:58:02 stefans-speaker volumio[20483]: info: Remote SSH Stopped
Aug 31 22:58:02 stefans-speaker volumio[20483]: info: Setting Geolocation for MyVolumio to eu8
Aug 31 22:58:02 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:58:02 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:58:02 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:58:03 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4.
Aug 31 22:58:03 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:03 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:03 stefans-speaker go-librespot[20855]: go-librespot daemon starting...
Aug 31 22:58:03 stefans-speaker go-librespot[20856]: time="2026-08-31T22:58:03+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:58:03 stefans-speaker go-librespot[20856]: time="2026-08-31T22:58:03+02:00" level=debug msg="app state loaded"
Aug 31 22:58:03 stefans-speaker go-librespot[20856]: time="2026-08-31T22:58:03+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 31 22:58:03 stefans-speaker volumio[20483]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Aug 31 22:58:03 stefans-speaker go-librespot[20856]: time="2026-08-31T22:58: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Aug 31 22:58:03 stefans-speaker go-librespot[20856]: time="2026-08-31T22:58:03+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 22:58:03 stefans-speaker go-librespot[20856]: time="2026-08-31T22:58:03+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 22:58:03 stefans-speaker go-librespot[20856]: time="2026-08-31T22:58:03+02:00" level=info msg="zeroconf server listening on port 43495"
Aug 31 22:58:03 stefans-speaker go-librespot[20856]: time="2026-08-31T22:58:03+02:00" level=debug msg="obtained new client token: AAHX/oaegtgsIfBjch7a3FPVWKMB+7n4DXsqiozwCNNNLG1iTF+gmapRQJV3oJmGLS7+q0uEbcSveF21yYa3cNxFchYoTYjByEoN7/8VvcsHI3Yzcs68Kq6ozIZaqTAqRN+IxewHcnfvMkk9GnM146GLI5xemWspMnLe/BibL/s6p3ewnsP2g0Lel6DmQrU7YRTqzd6orDpzERn2+znnnp5rXe9vUf7d8hRgncsd2xvfpBYSFMXyIExZZeE="
Aug 31 22:58:03 stefans-speaker go-librespot[20856]: time="2026-08-31T22:58:03+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Aug 31 22:58:03 stefans-speaker go-librespot[20856]: time="2026-08-31T22:58:03+02:00" level=debug msg="completed keyexchange"
Aug 31 22:58:03 stefans-speaker go-librespot[20856]: time="2026-08-31T22:58:03+02:00" level=debug msg="completed challenge"
Aug 31 22:58:03 stefans-speaker volumio[20483]: info: Updating MyVolumio device info
Aug 31 22:58:03 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:58:03 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:58:03 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:58:03 stefans-speaker go-librespot[20856]: time="2026-08-31T22:58:03+02:00" level=info msg="authenticated AP" username="31************************cy"
Aug 31 22:58:03 stefans-speaker go-librespot[20856]: time="2026-08-31T22:58: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 22:58:03 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:58:03 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 31 22:58:03 stefans-speaker volumio[20483]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Aug 31 22:58:04 stefans-speaker volumio[20483]: info: Initializing connection to go-librespot Websocket
Aug 31 22:58:04 stefans-speaker volumio[20483]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 31 22:58:07 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5.
Aug 31 22:58:07 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:07 stefans-speaker volumio[20483]: info: Initializing connection to go-librespot Websocket
Aug 31 22:58:07 stefans-speaker volumio[20483]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 31 22:58:07 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:07 stefans-speaker go-librespot[20865]: go-librespot daemon starting...
Aug 31 22:58:07 stefans-speaker go-librespot[20866]: time="2026-08-31T22:58:07+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:58:07 stefans-speaker go-librespot[20866]: time="2026-08-31T22:58:07+02:00" level=debug msg="app state loaded"
Aug 31 22:58:07 stefans-speaker go-librespot[20866]: time="2026-08-31T22:58:07+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 31 22:58:07 stefans-speaker volumio[20483]: info: MYVOLUMIO: Adding device
Aug 31 22:58:07 stefans-speaker volumio[20483]: info: MYVOLUMIO: Evaluating Server
Aug 31 22:58:07 stefans-speaker go-librespot[20866]: time="2026-08-31T22:58:07+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Aug 31 22:58:07 stefans-speaker go-librespot[20866]: time="2026-08-31T22:58:07+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 22:58:07 stefans-speaker go-librespot[20866]: time="2026-08-31T22:58:07+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 22:58:07 stefans-speaker go-librespot[20866]: time="2026-08-31T22:58:07+02:00" level=info msg="zeroconf server listening on port 36229"
Aug 31 22:58:07 stefans-speaker go-librespot[20866]: time="2026-08-31T22:58:07+02:00" level=debug msg="obtained new client token: AAFeqQAQJNZzfFkOtb525PN1D2iM05zlZ09jf/sodVDben3Vnb/96dxfGqQES1Cpb7Uq1IxDLITLmYASSqGyAem/y8s6uNLnXIZA2g64hsM+ig2AkltHF2FICuXzyrKncyDedqAppgGGG/WLW2xUfjd2yCkhked+u7426hh4hg2jn73mnxO6y9aUtYF7vkAszSdRI9gCicXcjggcRnVvNgxOLGzFPCiHbZAjWRo97gptnP+6N6wxmc8W1Cw="
Aug 31 22:58:07 stefans-speaker go-librespot[20866]: time="2026-08-31T22:58:07+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Aug 31 22:58:07 stefans-speaker go-librespot[20866]: time="2026-08-31T22:58:07+02:00" level=debug msg="completed keyexchange"
Aug 31 22:58:07 stefans-speaker go-librespot[20866]: time="2026-08-31T22:58:07+02:00" level=debug msg="completed challenge"
Aug 31 22:58:07 stefans-speaker go-librespot[20866]: time="2026-08-31T22:58:07+02:00" level=info msg="authenticated AP" username="31************************cy"
Aug 31 22:58:07 stefans-speaker volumio[20483]: info: Setting Geolocation for MyVolumio to eu2
Aug 31 22:58:07 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:58:07 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:58:07 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:58:07 stefans-speaker go-librespot[20866]: time="2026-08-31T22:58: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 22:58:07 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:58:07 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 31 22:58:08 stefans-speaker volumio[20483]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Aug 31 22:58:08 stefans-speaker volumio[20483]: info: Updating MyVolumio device info
Aug 31 22:58:08 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:58:08 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:58:08 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:58:09 stefans-speaker volumio[20483]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Aug 31 22:58:10 stefans-speaker volumio[20483]: info: Initializing connection to go-librespot Websocket
Aug 31 22:58:10 stefans-speaker volumio[20483]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 31 22:58:11 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6.
Aug 31 22:58:11 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:11 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:11 stefans-speaker go-librespot[20905]: go-librespot daemon starting...
Aug 31 22:58:11 stefans-speaker go-librespot[20906]: time="2026-08-31T22:58:11+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:58:11 stefans-speaker go-librespot[20906]: time="2026-08-31T22:58:11+02:00" level=debug msg="app state loaded"
Aug 31 22:58:11 stefans-speaker go-librespot[20906]: time="2026-08-31T22:58:11+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 31 22:58:11 stefans-speaker go-librespot[20906]: time="2026-08-31T22:58: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Aug 31 22:58:11 stefans-speaker go-librespot[20906]: time="2026-08-31T22:58:11+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 22:58:11 stefans-speaker go-librespot[20906]: time="2026-08-31T22:58:11+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 22:58:11 stefans-speaker go-librespot[20906]: time="2026-08-31T22:58:11+02:00" level=info msg="zeroconf server listening on port 40497"
Aug 31 22:58:11 stefans-speaker go-librespot[20906]: time="2026-08-31T22:58:11+02:00" level=debug msg="obtained new client token: AAGCkMp2XMWdWK/AF0tGMzcNWakYKiRGr98ciU+MMdkSZE1WFpUy8LU0wl+LOMlpKRkVOentnDD3AZtxjKKCOfx0o+soar5BRAcOO7Hy/xj9Ft4fIvhvza4BGGO5y9J3NuPAH/l3IrYoiluuNqUI7JYAzRoHjiS2cfT+6J3Vm/+fOkxm4QO7if2UhgkuE0E4lVcBjrBFZEUst76mUt2xi+dYgsjlxXl4wzo0/wcouPVFlfFYEzfuAw5YtC8="
Aug 31 22:58:11 stefans-speaker go-librespot[20906]: time="2026-08-31T22:58:11+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Aug 31 22:58:11 stefans-speaker go-librespot[20906]: time="2026-08-31T22:58:11+02:00" level=debug msg="completed keyexchange"
Aug 31 22:58:11 stefans-speaker go-librespot[20906]: time="2026-08-31T22:58:11+02:00" level=debug msg="completed challenge"
Aug 31 22:58:11 stefans-speaker go-librespot[20906]: time="2026-08-31T22:58:11+02:00" level=info msg="authenticated AP" username="31************************cy"
Aug 31 22:58:11 stefans-speaker go-librespot[20906]: time="2026-08-31T22:58: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 22:58:11 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:58:11 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 31 22:58:13 stefans-speaker volumio[20483]: info: Initializing connection to go-librespot Websocket
Aug 31 22:58:13 stefans-speaker volumio[20483]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 31 22:58:14 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Aug 31 22:58:14 stefans-speaker volumio[20483]: 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 22:58:14 stefans-speaker volumio[20483]: info: CoreCommandRouter::volumioGetState
Aug 31 22:58:14 stefans-speaker volumio[20483]: info: CorePlayQueue::getTrack 0
Aug 31 22:58:15 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7.
Aug 31 22:58:15 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:15 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:15 stefans-speaker go-librespot[20916]: go-librespot daemon starting...
Aug 31 22:58:15 stefans-speaker go-librespot[20917]: time="2026-08-31T22:58:15+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:58:15 stefans-speaker go-librespot[20917]: time="2026-08-31T22:58:15+02:00" level=debug msg="app state loaded"
Aug 31 22:58:15 stefans-speaker go-librespot[20917]: time="2026-08-31T22:58:15+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 31 22:58:15 stefans-speaker go-librespot[20917]: time="2026-08-31T22:58: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 22:58:15 stefans-speaker go-librespot[20917]: time="2026-08-31T22:58: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 22:58:15 stefans-speaker go-librespot[20917]: time="2026-08-31T22:58: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 22:58:15 stefans-speaker go-librespot[20917]: time="2026-08-31T22:58:15+02:00" level=info msg="zeroconf server listening on port 38319"
Aug 31 22:58:15 stefans-speaker go-librespot[20917]: time="2026-08-31T22:58:15+02:00" level=debug msg="obtained new client token: AAF1UsdwkmC8Yd24GKv15n3S0ZI+1L7MtqKeZa3TlwEpTg2M5LOMqhqp3/qX+ybATAhx0rracCuEqCe8XtosacfxO3ZV1geFzAFLIO6h4c8h/v61OwvYoAxgF9Ye8h3nQ1Tuz18/KsXrZjj695GcI+KNL9aOVAU4cP/bQulvJKbW9jocCcugVBICOBi8tjpzTVZlWahwicx6TPEkcFZzUihIXl9y+bxpui0khlo+rKpRdIyjC90TFsUjU2c="
Aug 31 22:58:15 stefans-speaker go-librespot[20917]: time="2026-08-31T22:58:15+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Aug 31 22:58:15 stefans-speaker go-librespot[20917]: time="2026-08-31T22:58:15+02:00" level=debug msg="completed keyexchange"
Aug 31 22:58:15 stefans-speaker go-librespot[20917]: time="2026-08-31T22:58:15+02:00" level=debug msg="completed challenge"
Aug 31 22:58:15 stefans-speaker go-librespot[20917]: time="2026-08-31T22:58:15+02:00" level=info msg="authenticated AP" username="31************************cy"
Aug 31 22:58:15 stefans-speaker go-librespot[20917]: time="2026-08-31T22:58: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 22:58:15 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:58:15 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 31 22:58:16 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 31 22:58:16 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:58:16 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Aug 31 22:58:16 stefans-speaker volumio[20483]: info: Initializing connection to go-librespot Websocket
Aug 31 22:58:16 stefans-speaker volumio[20483]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 31 22:58:16 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Aug 31 22:58:16 stefans-speaker volumio-remote-updater[679]: Test mode disabled
Aug 31 22:58:16 stefans-speaker volumio-remote-updater[679]: Alpha mode disabled
Aug 31 22:58:16 stefans-speaker volumio-remote-updater[679]: Alpha legacy test mode disabled
Aug 31 22:58:16 stefans-speaker volumio[20483]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Fix for missing PWM backlight module
- Fix for crash loop on Onboarding plugin
NEW ADDITIONS
- Bump RPi/CM4 kernel to 6.12.74
","title":"Update v4.119","updateavailable":true}
Aug 31 22:58:16 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Aug 31 22:58:19 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8.
Aug 31 22:58:19 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:19 stefans-speaker volumio[20483]: info: Initializing connection to go-librespot Websocket
Aug 31 22:58:19 stefans-speaker volumio[20483]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 31 22:58:19 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:19 stefans-speaker go-librespot[20942]: go-librespot daemon starting...
Aug 31 22:58:19 stefans-speaker go-librespot[20943]: time="2026-08-31T22:58:19+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:58:19 stefans-speaker go-librespot[20943]: time="2026-08-31T22:58:19+02:00" level=debug msg="app state loaded"
Aug 31 22:58:19 stefans-speaker go-librespot[20943]: time="2026-08-31T22:58:19+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 31 22:58:19 stefans-speaker go-librespot[20943]: time="2026-08-31T22:58: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 22:58:19 stefans-speaker go-librespot[20943]: time="2026-08-31T22:58: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 22:58:19 stefans-speaker go-librespot[20943]: time="2026-08-31T22:58: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 22:58:19 stefans-speaker go-librespot[20943]: time="2026-08-31T22:58:19+02:00" level=info msg="zeroconf server listening on port 38641"
Aug 31 22:58:19 stefans-speaker go-librespot[20943]: time="2026-08-31T22:58:19+02:00" level=debug msg="obtained new client token: AAGc8UQ+zy3t/+yjJxq8LFMRXVtczFuUrlql9+QIdhMaJ0+JVJtHbT35YX2XQsdbCGvaIDcSwcImr5QHZX9Rai4Q5NakK0P0K16dg/ALFQruSPzEA9Miha+Zs0QH3LKGTMNHwA/37kwh5/GL+3KuMmno2v1O/BjoQWr3iBSz1ty6Qu83Wkc3KfWlNya91Ck5u4qXdOu+OdyreGwAPzbxE4/YBlm8jrEsbGhYnId9H6rsPsMkgTko3LtJqFg="
Aug 31 22:58:19 stefans-speaker go-librespot[20943]: time="2026-08-31T22:58:19+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Aug 31 22:58:19 stefans-speaker go-librespot[20943]: time="2026-08-31T22:58:19+02:00" level=debug msg="completed keyexchange"
Aug 31 22:58:19 stefans-speaker go-librespot[20943]: time="2026-08-31T22:58:19+02:00" level=debug msg="completed challenge"
Aug 31 22:58:19 stefans-speaker go-librespot[20943]: time="2026-08-31T22:58:19+02:00" level=info msg="authenticated AP" username="31************************cy"
Aug 31 22:58:19 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:19 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:19 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Aug 31 22:58:19 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Aug 31 22:58:19 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Aug 31 22:58:19 stefans-speaker volumio[20483]: info: CoreCommandRouter::volumioGetBrowseSources
Aug 31 22:58:19 stefans-speaker go-librespot[20943]: time="2026-08-31T22:58:19+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 22:58:19 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 31 22:58:19 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:58:19 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 31 22:58:20 stefans-speaker volumio[20483]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Aug 31 22:58:20 stefans-speaker volumio[20483]: info: Completed starting MyVolumio Plugin
Aug 31 22:58:20 stefans-speaker volumio[20483]: [Metrics] CommandRouter: 44s 354.64ms
Aug 31 22:58:20 stefans-speaker volumio[20483]: info: CoreCommandRouter::volumiosetStartupVolume
Aug 31 22:58:20 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:20 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:58:20 stefans-speaker volumio[20483]: info: CoreCommandRouter::Close All Modals sent
Aug 31 22:58:20 stefans-speaker volumio[20483]: info: CoreCommandRouter::Close All Modals sent
Aug 31 22:58:21 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Aug 31 22:58:21 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Aug 31 22:58:21 stefans-speaker volumio[20483]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Aug 31 22:58:22 stefans-speaker volumio[20483]: info: Initializing connection to go-librespot Websocket
Aug 31 22:58:22 stefans-speaker volumio[20483]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 31 22:58:23 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9.
Aug 31 22:58:23 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:23 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:23 stefans-speaker go-librespot[20956]: go-librespot daemon starting...
Aug 31 22:58:23 stefans-speaker go-librespot[20957]: time="2026-08-31T22:58:23+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:58:23 stefans-speaker go-librespot[20957]: time="2026-08-31T22:58:23+02:00" level=debug msg="app state loaded"
Aug 31 22:58:23 stefans-speaker go-librespot[20957]: time="2026-08-31T22:58:23+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 31 22:58:23 stefans-speaker go-librespot[20957]: time="2026-08-31T22:58: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Aug 31 22:58:23 stefans-speaker go-librespot[20957]: time="2026-08-31T22:58:23+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 22:58:23 stefans-speaker go-librespot[20957]: time="2026-08-31T22:58:23+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 22:58:23 stefans-speaker go-librespot[20957]: time="2026-08-31T22:58:23+02:00" level=info msg="zeroconf server listening on port 37419"
Aug 31 22:58:23 stefans-speaker go-librespot[20957]: time="2026-08-31T22:58:23+02:00" level=debug msg="obtained new client token: AAGye5Zu4qOjP6AeZ9D9t2ObCASm9mKjJ6m5ufWHZV1EFJotw6c1GIlwRbKN/lKQfoDNA2JhhVTqWDv/phxTbCy0PsI9/pRKAv2u7jjFVZT53IbB3OsSnX+qmHr0wrpC2/7q6E2Im0xUfNBQtULpSVAV5Lq9P14InbaO53Z2grwjU5yD8OQEqgwe7eKp6VW7mcwYKaVwmkGPF9mM+tD1YFAl8HCcPwsvGTFBtQDk8exvGzn+SR/tOwAPq6E="
Aug 31 22:58:23 stefans-speaker go-librespot[20957]: time="2026-08-31T22:58:23+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Aug 31 22:58:23 stefans-speaker go-librespot[20957]: time="2026-08-31T22:58:23+02:00" level=debug msg="completed keyexchange"
Aug 31 22:58:23 stefans-speaker go-librespot[20957]: time="2026-08-31T22:58:23+02:00" level=debug msg="completed challenge"
Aug 31 22:58:23 stefans-speaker go-librespot[20957]: time="2026-08-31T22:58:23+02:00" level=info msg="authenticated AP" username="31************************cy"
Aug 31 22:58:23 stefans-speaker go-librespot[20957]: time="2026-08-31T22:58:23+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 22:58:23 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:58:23 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 31 22:58:25 stefans-speaker volumio[20483]: info: Initializing connection to go-librespot Websocket
Aug 31 22:58:25 stefans-speaker volumio[20483]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 31 22:58:27 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10.
Aug 31 22:58:27 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:27 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:27 stefans-speaker go-librespot[20964]: go-librespot daemon starting...
Aug 31 22:58:27 stefans-speaker go-librespot[20965]: time="2026-08-31T22:58:27+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:58:27 stefans-speaker go-librespot[20965]: time="2026-08-31T22:58:27+02:00" level=debug msg="app state loaded"
Aug 31 22:58:27 stefans-speaker go-librespot[20965]: time="2026-08-31T22:58:27+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 31 22:58:27 stefans-speaker go-librespot[20965]: time="2026-08-31T22:58:27+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Aug 31 22:58:27 stefans-speaker go-librespot[20965]: time="2026-08-31T22:58:27+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 22:58:27 stefans-speaker go-librespot[20965]: time="2026-08-31T22:58:27+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 22:58:27 stefans-speaker go-librespot[20965]: time="2026-08-31T22:58:27+02:00" level=info msg="zeroconf server listening on port 40261"
Aug 31 22:58:27 stefans-speaker go-librespot[20965]: time="2026-08-31T22:58:27+02:00" level=debug msg="obtained new client token: AAFdBB8UHD+8zYwXl5e13bvD9jk3MFoHzU1zUswvI/gDMxMqJZoyQnukjMucEvZTQvBFMyUyfOh3ckV8AQTfaa7cq6+yvP20kv3fXyDR8aPwvoAcaruNRrvzTa8mhFfbywlBaOr3JOVwQ3ZvIhGLUZ9Qi9/QdgKlK5xNlBVuKBQ0VddtwiWbRKg8J0pvf1JBd0f0Or9zQff50gs9voDQmrR1hWptj1eU2EYcu/fnkC/w+Xrs4Eww9hz1MbY="
Aug 31 22:58:27 stefans-speaker go-librespot[20965]: time="2026-08-31T22:58:27+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Aug 31 22:58:27 stefans-speaker go-librespot[20965]: time="2026-08-31T22:58:27+02:00" level=debug msg="completed keyexchange"
Aug 31 22:58:27 stefans-speaker go-librespot[20965]: time="2026-08-31T22:58:27+02:00" level=debug msg="completed challenge"
Aug 31 22:58:27 stefans-speaker go-librespot[20965]: time="2026-08-31T22:58:27+02:00" level=info msg="authenticated AP" username="31************************cy"
Aug 31 22:58:27 stefans-speaker go-librespot[20965]: time="2026-08-31T22:58:27+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 22:58:27 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:58:27 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 31 22:58:27 stefans-speaker volumio[20483]: info: BOOT COMPLETED
Aug 31 22:58:28 stefans-speaker volumio[20483]: info: Initializing connection to go-librespot Websocket
Aug 31 22:58:28 stefans-speaker volumio[20483]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 31 22:58:30 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11.
Aug 31 22:58:30 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:30 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:30 stefans-speaker go-librespot[20986]: go-librespot daemon starting...
Aug 31 22:58:30 stefans-speaker go-librespot[20987]: time="2026-08-31T22:58:30+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:58:30 stefans-speaker go-librespot[20987]: time="2026-08-31T22:58:30+02:00" level=debug msg="app state loaded"
Aug 31 22:58:30 stefans-speaker go-librespot[20987]: time="2026-08-31T22:58:30+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 31 22:58:31 stefans-speaker volumio[20483]: info: Initializing connection to go-librespot Websocket
Aug 31 22:58:31 stefans-speaker go-librespot[20987]: time="2026-08-31T22:58:31+02:00" level=debug msg="new websocket client"
Aug 31 22:58:31 stefans-speaker volumio[20483]: info: Connection to go-librespot Websocket established
Aug 31 22:58:31 stefans-speaker go-librespot[20987]: time="2026-08-31T22:58:31+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 22:58:31 stefans-speaker go-librespot[20987]: time="2026-08-31T22:58:31+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 22:58:31 stefans-speaker go-librespot[20987]: time="2026-08-31T22:58:31+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 22:58:31 stefans-speaker go-librespot[20987]: time="2026-08-31T22:58:31+02:00" level=info msg="zeroconf server listening on port 35125"
Aug 31 22:58:31 stefans-speaker go-librespot[20987]: time="2026-08-31T22:58:31+02:00" level=debug msg="obtained new client token: AAEUi5NPnxDYIxzuVePJRrLHfpVBqzEk8iBVnZa8TKgoQwJC+UppT1LQqrQEP+76CuJ9CeQKOC9om6GAyG4jxKwgTQ31ifJI8d0vV2RmA0Uz2+oeSzT0fvRNwkYfJyo71v7O5AMtFcDSLy0fJC53GfhL118IHum0r3VzDGng5Y2nwhfzpIMSfLvGwuBLAsM5tlSo/B7g4ZpqWIwgaz3q/qPtm+53I9GP53Mwv0J2g0jhS5vRGAxvCFU5"
Aug 31 22:58:31 stefans-speaker go-librespot[20987]: time="2026-08-31T22:58:31+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Aug 31 22:58:31 stefans-speaker go-librespot[20987]: time="2026-08-31T22:58:31+02:00" level=debug msg="completed keyexchange"
Aug 31 22:58:31 stefans-speaker go-librespot[20987]: time="2026-08-31T22:58:31+02:00" level=debug msg="completed challenge"
Aug 31 22:58:31 stefans-speaker go-librespot[20987]: time="2026-08-31T22:58:31+02:00" level=info msg="authenticated AP" username="31************************cy"
Aug 31 22:58:31 stefans-speaker go-librespot[20987]: time="2026-08-31T22:58:31+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 22:58:31 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:58:31 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 31 22:58:31 stefans-speaker volumio[20483]: info: Connection to go-librespot Websocket closed
Aug 31 22:58:34 stefans-speaker volumio[20483]: info: Getting Spotify volume
Aug 31 22:58:34 stefans-speaker volumio[20483]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 31 22:58:34 stefans-speaker volumio[20483]: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 31 22:58:34 stefans-speaker volumio[20483]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Aug 31 22:58:34 stefans-speaker volumio[20483]: errno: -111,
Aug 31 22:58:34 stefans-speaker volumio[20483]: code: 'ECONNREFUSED',
Aug 31 22:58:34 stefans-speaker volumio[20483]: syscall: 'connect',
Aug 31 22:58:34 stefans-speaker volumio[20483]: address: '127.0.0.1',
Aug 31 22:58:34 stefans-speaker volumio[20483]: port: 9879,
Aug 31 22:58:34 stefans-speaker volumio[20483]: response: undefined
Aug 31 22:58:34 stefans-speaker volumio[20483]: }
Aug 31 22:58:34 stefans-speaker volumio[20483]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 31 22:58:34 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12.
Aug 31 22:58:34 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:34 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:34 stefans-speaker go-librespot[21009]: go-librespot daemon starting...
Aug 31 22:58:34 stefans-speaker go-librespot[21010]: time="2026-08-31T22:58:34+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:58:34 stefans-speaker go-librespot[21010]: time="2026-08-31T22:58:34+02:00" level=debug msg="app state loaded"
Aug 31 22:58:34 stefans-speaker go-librespot[21010]: time="2026-08-31T22:58:34+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 31 22:58:34 stefans-speaker go-librespot[21010]: time="2026-08-31T22:58:34+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 22:58:34 stefans-speaker go-librespot[21010]: time="2026-08-31T22:58:34+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 22:58:34 stefans-speaker go-librespot[21010]: time="2026-08-31T22:58:34+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 22:58:34 stefans-speaker sudo[21019]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:58:34 stefans-speaker go-librespot[21010]: time="2026-08-31T22:58:34+02:00" level=info msg="zeroconf server listening on port 35847"
Aug 31 22:58:34 stefans-speaker sudo[21019]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-31 22:57'
Aug 31 22:58:34 stefans-speaker sudo[21019]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:58:35 stefans-speaker go-librespot[21010]: time="2026-08-31T22:58:35+02:00" level=debug msg="obtained new client token: AAFRn4e8eLK6cPcYLI6jX5Iv3sUGKGrfuGdpB2amLIwWxI6TFuAyrX6UsqZXElpMDM0BVynB/3qc0CQAyidi1VXJy7GpQcqxNKG3mkoDjO9H3OjKeRcaBLoAfrr3eedj1fR19d65Vh/LEo/md7/U5VaCdNudE0fywTafaCRF9mMYsXIFQfMR++sygmpwxi7B86eeorEvKIQy5Huej23mUOozppTGBKiBMM8WMMKwI++D1XwVGUzPk72z"
Aug 31 22:58:35 stefans-speaker go-librespot[21010]: time="2026-08-31T22:58:35+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Aug 31 22:58:35 stefans-speaker sudo[21019]: pam_unix(sudo:session): session closed for user root
Aug 31 22:58:35 stefans-speaker go-librespot[21010]: time="2026-08-31T22:58:35+02:00" level=debug msg="completed keyexchange"
Aug 31 22:58:35 stefans-speaker go-librespot[21010]: time="2026-08-31T22:58:35+02:00" level=debug msg="completed challenge"
Aug 31 22:58:35 stefans-speaker volumio[20483]: sudo: unable to resolve host stefans-speaker: System error
Aug 31 22:58:35 stefans-speaker volumio-remote-updater[679]: [2026-08-31 22:58:35] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Aug 31 22:58:35 stefans-speaker volumio-remote-updater[679]: [2026-08-31 22:58:35] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Aug 31 22:58:35 stefans-speaker systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:58:35 stefans-speaker systemd[1]: volumio.service: Failed with result 'exit-code'.
Aug 31 22:58:35 stefans-speaker systemd[1]: volumio.service: Consumed 35.400s CPU time.
Aug 31 22:58:35 stefans-speaker go-librespot[21010]: time="2026-08-31T22:58:35+02:00" level=info msg="authenticated AP" username="31************************cy"
Aug 31 22:58:35 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service.
Aug 31 22:58:35 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully.
Aug 31 22:58:35 stefans-speaker systemd[1]: volumio.service: Scheduled restart job, restart counter is at 107.
Aug 31 22:58:35 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service.
Aug 31 22:58:35 stefans-speaker systemd[1]: Stopped volumio.service - Volumio Backend Module.
Aug 31 22:58:35 stefans-speaker systemd[1]: volumio.service: Consumed 35.400s CPU time.
Aug 31 22:58:35 stefans-speaker systemd[1]: Started volumio.service - Volumio Backend Module.
Aug 31 22:58:35 stefans-speaker go-librespot[21010]: time="2026-08-31T22:58:35+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 22:58:35 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:58:35 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 31 22:58:35 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully.
Aug 31 22:58:37 stefans-speaker volumio[21031]: info: -------------------------------------------
Aug 31 22:58:37 stefans-speaker volumio[21031]: info: ----- Volumio3 ----
Aug 31 22:58:37 stefans-speaker volumio[21031]: info: -------------------------------------------
Aug 31 22:58:37 stefans-speaker volumio[21031]: info: ----- System startup ----
Aug 31 22:58:37 stefans-speaker volumio[21031]: info: -------------------------------------------
Aug 31 22:58:38 stefans-speaker volumio[21031]: info: MYVOLUMIO Environment detected
Aug 31 22:58:38 stefans-speaker volumio[21031]: info: Plugin folders cleanup
Aug 31 22:58:38 stefans-speaker volumio[21031]: info: Scanning into folder /volumio/app/plugins/
Aug 31 22:58:38 stefans-speaker volumio[21031]: info: Scanning category audio_interface
Aug 31 22:58:38 stefans-speaker volumio[21031]: info: Scanning category miscellanea
Aug 31 22:58:38 stefans-speaker volumio[21031]: info: Scanning category music_service
Aug 31 22:58:38 stefans-speaker volumio[21031]: info: Scanning category plugins.json
Aug 31 22:58:38 stefans-speaker volumio[21031]: info: Scanning category system_controller
Aug 31 22:58:38 stefans-speaker volumio[21031]: info: Scanning category user_interface
Aug 31 22:58:38 stefans-speaker volumio[21031]: info: Scanning into folder /data/plugins/
Aug 31 22:58:38 stefans-speaker volumio[21031]: info: Scanning category music_service
Aug 31 22:58:38 stefans-speaker volumio[21031]: info: Plugin folders cleanup completed
Aug 31 22:58:38 stefans-speaker volumio[21031]: info: -------------------------------------------
Aug 31 22:58:38 stefans-speaker volumio[21031]: info: ----- Core plugins startup ----
Aug 31 22:58:38 stefans-speaker volumio[21031]: info: -------------------------------------------
Aug 31 22:58:38 stefans-speaker volumio[21031]: info: Loading plugins from folder /volumio/app/plugins/
Aug 31 22:58:38 stefans-speaker volumio[21031]: info: Adding plugin upnp to MyMusic Plugins
Aug 31 22:58:38 stefans-speaker volumio[21031]: info: Adding plugin airplay_emulation to MyMusic Plugins
Aug 31 22:58:38 stefans-speaker volumio[21031]: info: Adding plugin upnp_browser to MyMusic Plugins
Aug 31 22:58:38 stefans-speaker volumio[21031]: info: Loading plugins from folder /data/plugins/
Aug 31 22:58:38 stefans-speaker volumio[21031]: info: Loading plugin "system"...
Aug 31 22:58:38 stefans-speaker volumio[21031]: info: Loading plugin "appearance"...
Aug 31 22:58:38 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13.
Aug 31 22:58:38 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:38 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:38 stefans-speaker go-librespot[21058]: go-librespot daemon starting...
Aug 31 22:58:38 stefans-speaker go-librespot[21059]: time="2026-08-31T22:58:38+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:58:38 stefans-speaker go-librespot[21059]: time="2026-08-31T22:58:38+02:00" level=debug msg="app state loaded"
Aug 31 22:58:38 stefans-speaker go-librespot[21059]: time="2026-08-31T22:58:38+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 31 22:58:38 stefans-speaker go-librespot[21059]: time="2026-08-31T22:58:38+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 22:58:38 stefans-speaker go-librespot[21059]: time="2026-08-31T22:58:38+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 22:58:38 stefans-speaker go-librespot[21059]: time="2026-08-31T22:58:38+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 22:58:38 stefans-speaker go-librespot[21059]: time="2026-08-31T22:58:38+02:00" level=info msg="zeroconf server listening on port 44849"
Aug 31 22:58:39 stefans-speaker go-librespot[21059]: time="2026-08-31T22:58:39+02:00" level=debug msg="obtained new client token: AAFSb8NpZ4rmKrA7mX5444zcHgMjk8VC2RaZw1Vvwano1qjj1vG/PWhxYB/tmHo6JFnSUgeqNj+gjOylzHsT0izNW7UfvfVVYMA7kyG28hhsQWq7jnJq2+2gLTpRVn0aI/qUvOA+rarinAQSDNzS+UTX5zpVZn1Tk59DwuBxxS4Pen7XM3I8E8Zahe8Np58nC+MpDLexP6zqZQyJjeoSBVoWspOwS9CBJWoQErsBRnm06Uvep8xmamcl"
Aug 31 22:58:39 stefans-speaker go-librespot[21059]: time="2026-08-31T22:58:39+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Aug 31 22:58:39 stefans-speaker go-librespot[21059]: time="2026-08-31T22:58:39+02:00" level=debug msg="completed keyexchange"
Aug 31 22:58:39 stefans-speaker go-librespot[21059]: time="2026-08-31T22:58:39+02:00" level=debug msg="completed challenge"
Aug 31 22:58:39 stefans-speaker go-librespot[21059]: time="2026-08-31T22:58:39+02:00" level=info msg="authenticated AP" username="31************************cy"
Aug 31 22:58:39 stefans-speaker go-librespot[21059]: time="2026-08-31T22:58:39+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 22:58:39 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:58:39 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 31 22:58:39 stefans-speaker volumio[21031]: info: Loading plugin "network"...
Aug 31 22:58:39 stefans-speaker volumio[21031]: info: Refreshing Cached IP Addresses
Aug 31 22:58:39 stefans-speaker sudo[21067]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:58:39 stefans-speaker sudo[21067]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Aug 31 22:58:39 stefans-speaker volumio[21031]: info: Loading plugin "services"...
Aug 31 22:58:39 stefans-speaker sudo[21067]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:58:39 stefans-speaker volumio[21031]: info: Loading plugin "alsa_controller"...
Aug 31 22:58:39 stefans-speaker sudo[21069]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:58:39 stefans-speaker sudo[21067]: pam_unix(sudo:session): session closed for user root
Aug 31 22:58:39 stefans-speaker sudo[21069]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 31 22:58:39 stefans-speaker sudo[21069]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:58:39 stefans-speaker sudo[21076]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:58:39 stefans-speaker sudo[21069]: pam_unix(sudo:session): session closed for user root
Aug 31 22:58:39 stefans-speaker sudo[21076]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Aug 31 22:58:39 stefans-speaker sudo[21076]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:58:39 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 31 22:58:39 stefans-speaker volumio[21031]: info: Loading plugin "wizard"...
Aug 31 22:58:39 stefans-speaker volumio[21031]: info: Loading plugin "networkfs"...
Aug 31 22:58:39 stefans-speaker volumio[21031]: info: Starting Udev Watcher for removable devices
Aug 31 22:58:39 stefans-speaker volumio[21031]: info: Ignoring mount for partition: boot
Aug 31 22:58:39 stefans-speaker volumio[21031]: info: Ignoring mount for partition: volumio
Aug 31 22:58:39 stefans-speaker volumio[21031]: info: Ignoring mount for partition: volumio_data
Aug 31 22:58:39 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Aug 31 22:58:39 stefans-speaker volumio[21031]: info: Loading plugin "volumio_command_line_client"...
Aug 31 22:58:39 stefans-speaker volumio[21031]: info: Loading plugin "upnp"...
Aug 31 22:58:39 stefans-speaker volumio[21031]: info: [1788209919677] Starting Upmpd Daemon
Aug 31 22:58:39 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Aug 31 22:58:39 stefans-speaker volumio[21031]: info: Loading plugin "my_music"...
Aug 31 22:58:39 stefans-speaker volumio[21031]: info: Loading plugin "mpd"...
Aug 31 22:58:40 stefans-speaker volumio[21031]: info: Loading plugin "upnp_browser"...
Aug 31 22:58:40 stefans-speaker volumio-remote-updater[679]: [2026-08-31 22:58:40] [connect] Successful connection
Aug 31 22:58:41 stefans-speaker volumio[21031]: info: Starting UPNP Browser
Aug 31 22:58:41 stefans-speaker volumio[21031]: info: Loading plugin "alarm-clock"...
Aug 31 22:58:41 stefans-speaker volumio[21031]: info: Loading plugin "airplay_emulation"...
Aug 31 22:58:41 stefans-speaker volumio[21031]: info: Starting Shairport Sync
Aug 31 22:58:41 stefans-speaker volumio[21031]: info: Loading plugin "last_100"...
Aug 31 22:58:41 stefans-speaker volumio[21031]: info: Loading plugin "webradio"...
Aug 31 22:58:41 stefans-speaker volumio[21031]: info: Loading plugin "i2s_dacs"...
Aug 31 22:58:41 stefans-speaker volumio[21031]: info: I2S DAC not set, start Auto-detection
Aug 31 22:58:41 stefans-speaker volumio[21031]: info: Loading plugin "volumiodiscovery"...
Aug 31 22:58:41 stefans-speaker volumio[21031]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Aug 31 22:58:41 stefans-speaker node[21031]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Aug 31 22:58:41 stefans-speaker volumio[21031]: *** WARNING *** Please fix your application to use the native API of Avahi!
Aug 31 22:58:41 stefans-speaker node[21031]: *** WARNING *** Please fix your application to use the native API of Avahi!
Aug 31 22:58:41 stefans-speaker volumio[21031]: *** WARNING *** For more information see
Aug 31 22:58:41 stefans-speaker node[21031]: *** WARNING *** For more information see
Aug 31 22:58:41 stefans-speaker volumio[21031]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Aug 31 22:58:41 stefans-speaker node[21031]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Aug 31 22:58:41 stefans-speaker volumio[21031]: *** WARNING *** Please fix your application to use the native API of Avahi!
Aug 31 22:58:41 stefans-speaker node[21031]: *** WARNING *** Please fix your application to use the native API of Avahi!
Aug 31 22:58:41 stefans-speaker volumio[21031]: *** WARNING *** For more information see
Aug 31 22:58:41 stefans-speaker node[21031]: *** WARNING *** For more information see
Aug 31 22:58:41 stefans-speaker volumio[21031]: info: Applying required configuration parameters for plugin volumiodiscovery
Aug 31 22:58:41 stefans-speaker volumio[21031]: info: Discovery: Started advertising with name: Stefan’s Speaker
Aug 31 22:58:41 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Aug 31 22:58:41 stefans-speaker volumio[21031]: info: Loading plugin "spop"...
Aug 31 22:58:42 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14.
Aug 31 22:58:42 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:42 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:42 stefans-speaker go-librespot[21103]: go-librespot daemon starting...
Aug 31 22:58:42 stefans-speaker go-librespot[21104]: time="2026-08-31T22:58:42+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:58:42 stefans-speaker go-librespot[21104]: time="2026-08-31T22:58:42+02:00" level=debug msg="app state loaded"
Aug 31 22:58:42 stefans-speaker go-librespot[21104]: time="2026-08-31T22:58:42+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 31 22:58:42 stefans-speaker sudo[21076]: pam_unix(sudo:session): session closed for user root
Aug 31 22:58:42 stefans-speaker go-librespot[21104]: time="2026-08-31T22:58:42+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 22:58:42 stefans-speaker go-librespot[21104]: time="2026-08-31T22:58:42+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 22:58:42 stefans-speaker go-librespot[21104]: time="2026-08-31T22:58:42+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 22:58:42 stefans-speaker go-librespot[21104]: time="2026-08-31T22:58:42+02:00" level=info msg="zeroconf server listening on port 44351"
Aug 31 22:58:43 stefans-speaker volumio[21031]: info: Loading plugin "ytcr"...
Aug 31 22:58:43 stefans-speaker go-librespot[21104]: time="2026-08-31T22:58:43+02:00" level=debug msg="obtained new client token: AAFWovVe+/tjO0RI/IEGlwI5Vcu+JDd1eqk2wiXB5gkM698O+DThDWYdLnm09gFKE0z+Sso9ceY16b1pTvOss54dswwOshME8Ace/2oRrz1JiNZbTtYF1H89M+ygzxU+/AOcYNT6A6i+DMDppbcuH7Y0B98gNoe+pogu+HwZblAdp4tLrf91BrbPzaqIbWWSbP5h+yFalzhux6NnX/fMxhawUza7OuWzTRKV//kYJBmZMSC5jVzvXfkN"
Aug 31 22:58:43 stefans-speaker go-librespot[21104]: time="2026-08-31T22:58:43+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Aug 31 22:58:43 stefans-speaker go-librespot[21104]: time="2026-08-31T22:58:43+02:00" level=debug msg="completed keyexchange"
Aug 31 22:58:43 stefans-speaker go-librespot[21104]: time="2026-08-31T22:58:43+02:00" level=debug msg="completed challenge"
Aug 31 22:58:43 stefans-speaker go-librespot[21104]: time="2026-08-31T22:58:43+02:00" level=info msg="authenticated AP" username="31************************cy"
Aug 31 22:58:43 stefans-speaker go-librespot[21104]: time="2026-08-31T22:58:43+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 22:58:43 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:58:43 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: Loading plugin "outputs"...
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: Loading plugin "albumart"...
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: Plugin example_plugin is not enabled
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: Loading plugin "inputs"...
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: Loading plugin "updater_comm"...
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: Plugin mpdemulation is not enabled
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: Loading plugin "rest_api"...
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: Loading plugin "websocket"...
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: Starting Socket.io Server version 1.7.4
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: Loading i18n strings for locale nl
Aug 31 22:58:44 stefans-speaker volumio[21031]: Updating browse sources language
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: CoreCommandRouter::initPlayerControls
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 31 22:58:44 stefans-speaker volumio[21031]: Express server listening on port 3000
Aug 31 22:58:44 stefans-speaker volumio[21031]: [Metrics] WebUI: 8s 107.25ms
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: CoreStateMachine::resetVolumioState
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: CoreStateMachine::getcurrentVolume
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: CoreCommandRouter::volumioRetrievevolume
Aug 31 22:58:44 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: Cannot read play queue from file
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: Volumio Network Manager: Network status updated: 2
Aug 31 22:58:45 stefans-speaker volumio-remote-updater[679]: [2026-08-31 22:58:45] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788209920 101
Aug 31 22:58:45 stefans-speaker volumio[21113]: Forking 3 albumart workers
Aug 31 22:58:45 stefans-speaker volumio[21031]: 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: 1
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: VolumeController:: Volume=70 Mute =false
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CoreStateMachine::pushState
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CorePlayQueue::getTrack 0
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CoreCommandRouter::volumioPushState
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CoreStateMachine::updateTrackBlock
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CorePlayQueue::getTrackBlock
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CoreCommandRouter::volumioRetrievevolume
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CoreStateMachine::setRepeat null single undefined
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CoreStateMachine::pushState
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CorePlayQueue::getTrack 0
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CoreCommandRouter::volumioPushState
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CoreStateMachine::setRandom null
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CoreStateMachine::pushState
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CorePlayQueue::getTrack 0
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CoreCommandRouter::volumioPushState
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: Setting Device type: Raspberry PI
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: VolumeController:: Volume=70 Mute =false
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CoreStateMachine::pushState
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CorePlayQueue::getTrack 0
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CoreCommandRouter::volumioPushState
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: Completed loading Core Plugins
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: Preparing to generate the ALSA configuration file
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: Discovery: adding a312d0fa-c0eb-45ad-a25b-79fcdf237e8a
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: Discovery: Found device Stefan’s Speaker
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CoreCommandRouter::volumioGetState
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CorePlayQueue::getTrack 0
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: Discovery: this is already registered, a312d0fa-c0eb-45ad-a25b-79fcdf237e8a
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: Discovery: Found device Stefan’s Speaker
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CoreCommandRouter::volumioGetState
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CorePlayQueue::getTrack 0
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: Asound.conf file unchanged, so no further update is needed
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: Output device has changed, restarting MPD
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: Output device has changed, restarting Shairport Sync
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:58:45 stefans-speaker sudo[21168]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:58:45 stefans-speaker sudo[21170]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:58:45 stefans-speaker sudo[21168]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 31 22:58:45 stefans-speaker sudo[21168]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: ___________ START PLUGINS ___________
Aug 31 22:58:45 stefans-speaker sudo[21168]: pam_unix(sudo:session): session closed for user root
Aug 31 22:58:45 stefans-speaker sudo[21170]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 31 22:58:45 stefans-speaker sudo[21170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: ControllerMpd::onStart: Initializing MPD
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: Creating MPD Configuration file
Aug 31 22:58:45 stefans-speaker systemd[1]: Stopping mpd.service - Music Player Daemon...
Aug 31 22:58:45 stefans-speaker sudo[21178]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: [1788209925908] CoreMusicLibrary::Adding element Media Servers
Aug 31 22:58:45 stefans-speaker sudo[21178]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 31 22:58:45 stefans-speaker sudo[21178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:58:45 stefans-speaker systemd[1]: mpd.service: Deactivated successfully.
Aug 31 22:58:45 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 31 22:58:45 stefans-speaker systemd[1]: mpd.service: Consumed 4.541s CPU time.
Aug 31 22:58:45 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully.
Aug 31 22:58:45 stefans-speaker sudo[21178]: pam_unix(sudo:session): session closed for user root
Aug 31 22:58:45 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 31 22:58:45 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: UPNP Browser: Client initialized successfully
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:45 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:58:45 stefans-speaker sudo[21180]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:58:45 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 31 22:58:45 stefans-speaker sudo[21180]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 31 22:58:45 stefans-speaker sudo[21180]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:58:45 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 31 22:58:46 stefans-speaker volumio[21031]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 31 22:58:46 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:46 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:58:46 stefans-speaker volumio[21031]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 31 22:58:46 stefans-speaker systemd[1]: mpd.service: Deactivated successfully.
Aug 31 22:58:46 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 31 22:58:46 stefans-speaker volumio[21031]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Aug 31 22:58:46 stefans-speaker volumio[21031]: info: [1788209926089] CoreMusicLibrary::Adding element Last_100
Aug 31 22:58:46 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 31 22:58:46 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully.
Aug 31 22:58:46 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 31 22:58:46 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 31 22:58:46 stefans-speaker volumio[21031]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Aug 31 22:58:46 stefans-speaker volumio[21031]: info: [1788209926105] CoreMusicLibrary::Adding element Webradio
Aug 31 22:58:46 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 31 22:58:46 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 31 22:58:46 stefans-speaker volumio[21031]: info: Initializing BBC Radios
Aug 31 22:58:46 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 31 22:58:46 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 31 22:58:46 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 31 22:58:46 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:58:46 stefans-speaker volumio[21031]: info: Creating Spotify config file
Aug 31 22:58:46 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:46 stefans-speaker sudo[21202]: root : unable to resolve host stefans-speaker: System error
Aug 31 22:58:46 stefans-speaker sudo[21202]: sudo: unable to resolve host stefans-speaker: System error
Aug 31 22:58:46 stefans-speaker sudo[21202]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Aug 31 22:58:46 stefans-speaker sudo[21202]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 31 22:58:46 stefans-speaker sudo[21202]: pam_unix(sudo:session): session closed for user root
Aug 31 22:58:46 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15.
Aug 31 22:58:46 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:46 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:46 stefans-speaker go-librespot[21210]: go-librespot daemon starting...
Aug 31 22:58:46 stefans-speaker go-librespot[21211]: time="2026-08-31T22:58:46+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:58:47 stefans-speaker volumio[21129]: Starting albumart workers
Aug 31 22:58:47 stefans-speaker go-librespot[21211]: time="2026-08-31T22:58:47+02:00" level=info msg="zeroconf server listening on port 41391"
Aug 31 22:58:47 stefans-speaker volumio[21031]: info: Volumio Calling Home
Aug 31 22:58:47 stefans-speaker volumio[21130]: Starting albumart workers
Aug 31 22:58:47 stefans-speaker volumio[21131]: Starting albumart workers
Aug 31 22:58:47 stefans-speaker volumio[21031]: info: MPD Permissions set
Aug 31 22:58:47 stefans-speaker volumio[21031]: info: MPD Permissions set
Aug 31 22:58:47 stefans-speaker volumio[21031]: info: Spotify config file written
Aug 31 22:58:48 stefans-speaker sudo[21223]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:58:48 stefans-speaker sudo[21223]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Aug 31 22:58:48 stefans-speaker sudo[21223]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:58:48 stefans-speaker systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon...
Aug 31 22:58:48 stefans-speaker systemd[1]: go-librespot-daemon.service: Killing process 21215 (go-librespot) with signal SIGKILL.
Aug 31 22:58:48 stefans-speaker systemd[1]: go-librespot-daemon.service: Deactivated successfully.
Aug 31 22:58:48 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:48 stefans-speaker volumio[21031]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: Volumio called home
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:48 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:48 stefans-speaker go-librespot[21231]: go-librespot daemon starting...
Aug 31 22:58:48 stefans-speaker sudo[21223]: pam_unix(sudo:session): session closed for user root
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:48 stefans-speaker go-librespot[21232]: time="2026-08-31T22:58:48+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:58:48 stefans-speaker go-librespot[21232]: time="2026-08-31T22:58:48+02:00" level=debug msg="app state loaded"
Aug 31 22:58:48 stefans-speaker go-librespot[21232]: time="2026-08-31T22:58:48+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: No need to fix Spotify hosts
Aug 31 22:58:48 stefans-speaker go-librespot[21232]: time="2026-08-31T22:58:48+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Aug 31 22:58:48 stefans-speaker go-librespot[21232]: time="2026-08-31T22:58:48+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Aug 31 22:58:48 stefans-speaker go-librespot[21232]: time="2026-08-31T22:58:48+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Aug 31 22:58:48 stefans-speaker go-librespot[21232]: time="2026-08-31T22:58:48+02:00" level=info msg="zeroconf server listening on port 44339"
Aug 31 22:58:48 stefans-speaker go-librespot[21232]: time="2026-08-31T22:58:48+02:00" level=debug msg="obtained new client token: AAGVdSLF7BakBFC4WtAnnR79X/hgjz+Nj+tF2XxaSmNyTMyFCx8bYW6aoYaWariUn1HSrjjJKXFAErzSUM2GLn0PrKxJ/DfNlUweZ6S7Daf+EnHJX+biPS83jCPc/JjJo+vG+UEz9WIXzhTk9AtYdZDZIV5WPE3qC6NajybhG7Rhi2R3pe0nxOUQwSf2o1nGtrw1DVj6+fZ+KaRXPcfEhwQihXqOIfjDj1o14f0QZ9m+O/qKzgrKsGACXUg="
Aug 31 22:58:48 stefans-speaker go-librespot[21232]: time="2026-08-31T22:58:48+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Aug 31 22:58:48 stefans-speaker volumio[21031]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Aug 31 22:58:48 stefans-speaker volumio[21031]: SPOTIFY: BQC5jIk2DFAecDxSU9d7qwImbEzLToGPvb5-FCclozoGLVkwt3p-FxEYiAY3PNPYITBIMfBn4wblG5bkasCpqEmo7i1wCZnfJOKtM4RhT_obkimHMQUMHy_nTvdEWbBL0NWf3zfztJmmexfzOhUdNMHodQf5ixYqDJ_kpMpVBfnbbG_oxx8HbyjEwkFqENfeiq-q82xNO_XAvHN0d-qvDa7X0kCwuYBCg74g6_xVdzbrEUBQr2pRyMAhbzKt-0q6FoiHmTPAO-K9XuXANE8VJNQ_i0YuY51KAekvOXLj9IdDdCJaTvoRNKMxfG2pNTc
Aug 31 22:58:48 stefans-speaker volumio[21031]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: New Spotify access token = BQC5jIk2DFAecDxSU9d7qwImbEzLToGPvb5-FCclozoGLVkwt3p-FxEYiAY3PNPYITBIMfBn4wblG5bkasCpqEmo7i1wCZnfJOKtM4RhT_obkimHMQUMHy_nTvdEWbBL0NWf3zfztJmmexfzOhUdNMHodQf5ixYqDJ_kpMpVBfnbbG_oxx8HbyjEwkFqENfeiq-q82xNO_XAvHN0d-qvDa7X0kCwuYBCg74g6_xVdzbrEUBQr2pRyMAhbzKt-0q6FoiHmTPAO-K9XuXANE8VJNQ_i0YuY51KAekvOXLj9IdDdCJaTvoRNKMxfG2pNTc
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: Spotify credentials grant success - running version from March 24, 2019
Aug 31 22:58:48 stefans-speaker go-librespot[21232]: time="2026-08-31T22:58:48+02:00" level=debug msg="completed keyexchange"
Aug 31 22:58:48 stefans-speaker go-librespot[21232]: time="2026-08-31T22:58:48+02:00" level=debug msg="completed challenge"
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: Starting Shairport Sync
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: Starting Shairport Sync
Aug 31 22:58:48 stefans-speaker volumio[21031]: info: Starting Shairport Sync
Aug 31 22:58:48 stefans-speaker go-librespot[21232]: time="2026-08-31T22:58:48+02:00" level=info msg="authenticated AP" username="31************************cy"
Aug 31 22:58:48 stefans-speaker sudo[21263]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:58:49 stefans-speaker sudo[21265]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:58:49 stefans-speaker sudo[21263]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 31 22:58:49 stefans-speaker sudo[21263]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:58:49 stefans-speaker sudo[21265]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 31 22:58:49 stefans-speaker sudo[21267]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:58:49 stefans-speaker sudo[21265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:58:49 stefans-speaker sudo[21267]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 31 22:58:49 stefans-speaker sudo[21267]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:58:49 stefans-speaker go-librespot[21232]: time="2026-08-31T22:58: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 22:58:49 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:58:49 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 31 22:58:49 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Aug 31 22:58:49 stefans-speaker volumio[21031]: info: CoreCommandRouter::volumioGetState
Aug 31 22:58:49 stefans-speaker volumio[21031]: info: CorePlayQueue::getTrack 0
Aug 31 22:58:49 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully.
Aug 31 22:58:49 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 31 22:58:49 stefans-speaker systemd[1]: shairport-sync.service: Consumed 1.946s CPU time.
Aug 31 22:58:49 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 31 22:58:49 stefans-speaker sudo[21267]: pam_unix(sudo:session): session closed for user root
Aug 31 22:58:49 stefans-speaker sudo[21263]: pam_unix(sudo:session): session closed for user root
Aug 31 22:58:49 stefans-speaker sudo[21265]: pam_unix(sudo:session): session closed for user root
Aug 31 22:58:49 stefans-speaker volumio[21031]: 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 22:58:49 stefans-speaker volumio[21031]: info: Spotify Successfully logged in
Aug 31 22:58:49 stefans-speaker volumio[21031]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Aug 31 22:58:49 stefans-speaker volumio[21031]: info: [1788209929411] CoreMusicLibrary::Adding element Spotify
Aug 31 22:58:49 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 31 22:58:49 stefans-speaker volumio[21031]: Cannot find translation for source Spotify
Aug 31 22:58:49 stefans-speaker volumio[21031]: info: Shairport-Sync Started
Aug 31 22:58:49 stefans-speaker volumio[21031]: Error adding Membership: Error: addMembership EINVAL
Aug 31 22:58:49 stefans-speaker volumio[21031]: info: Shairport-Sync Started
Aug 31 22:58:49 stefans-speaker volumio[21031]: info: Shairport-Sync Started
Aug 31 22:58:49 stefans-speaker sudo[21301]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:58:49 stefans-speaker sudo[21301]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Aug 31 22:58:49 stefans-speaker sudo[21301]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:58:49 stefans-speaker sudo[21301]: pam_unix(sudo:session): session closed for user root
Aug 31 22:58:49 stefans-speaker sudo[21303]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:58:49 stefans-speaker sudo[21303]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 31 22:58:49 stefans-speaker sudo[21303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:58:49 stefans-speaker sudo[21303]: pam_unix(sudo:session): session closed for user root
Aug 31 22:58:49 stefans-speaker volumio[21031]: info: [yt-cast-receiver] DIAL server listening on port 8098
Aug 31 22:58:49 stefans-speaker volumio[21031]: info: CoreCommandRouter::volumioRetrievevolume
Aug 31 22:58:50 stefans-speaker volumio[21031]: info: VolumeController:: Volume=70 Mute =false
Aug 31 22:58:50 stefans-speaker volumio[21031]: info: CoreCommandRouter::volumioGetState
Aug 31 22:58:50 stefans-speaker volumio[21031]: info: CorePlayQueue::getTrack 0
Aug 31 22:58:50 stefans-speaker volumio[21031]: info: CoreStateMachine::pushState
Aug 31 22:58:50 stefans-speaker volumio[21031]: info: CorePlayQueue::getTrack 0
Aug 31 22:58:50 stefans-speaker volumio[21031]: info: CoreCommandRouter::volumioPushState
Aug 31 22:58:50 stefans-speaker sudo[21309]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:58:50 stefans-speaker sudo[21309]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Aug 31 22:58:50 stefans-speaker sudo[21309]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:58:50 stefans-speaker sudo[21309]: pam_unix(sudo:session): session closed for user root
Aug 31 22:58:50 stefans-speaker volumio[21031]: info: Upmpdcli Daemon Started
Aug 31 22:58:51 stefans-speaker mpd[21209]: 2026-08-31T22:58:50 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Aug 31 22:58:51 stefans-speaker systemd[1]: Started mpd.service - Music Player Daemon.
Aug 31 22:58:51 stefans-speaker sudo[21180]: pam_unix(sudo:session): session closed for user root
Aug 31 22:58:51 stefans-speaker sudo[21170]: pam_unix(sudo:session): session closed for user root
Aug 31 22:58:51 stefans-speaker volumio[21031]: error: MPD error: The expression evaluated to a falsy value:
Aug 31 22:58:51 stefans-speaker volumio[21031]: assert.ok(self.idling)
Aug 31 22:58:51 stefans-speaker volumio[21031]: error: The expression evaluated to a falsy value:
Aug 31 22:58:51 stefans-speaker volumio[21031]: assert.ok(self.idling)
Aug 31 22:58:51 stefans-speaker volumio[21031]: info: MPD running with PID21209
Aug 31 22:58:51 stefans-speaker volumio[21031]: ,establishing connection
Aug 31 22:58:51 stefans-speaker volumio[21031]: error: updateQueue error: null
Aug 31 22:58:51 stefans-speaker volumio[21031]: info: Completed starting Core Plugins
Aug 31 22:58:51 stefans-speaker volumio[21031]: info: -------------------------------------------
Aug 31 22:58:51 stefans-speaker volumio[21031]: info: ----- MyVolumio plugins startup ----
Aug 31 22:58:51 stefans-speaker volumio[21031]: info: -------------------------------------------
Aug 31 22:58:51 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Fetching plans data....
Aug 31 22:58:51 stefans-speaker volumio[21031]: error: updateQueue error: null
Aug 31 22:58:51 stefans-speaker volumio[21031]: info: go-librespot daemon successfully initialized
Aug 31 22:58:52 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1.
Aug 31 22:58:52 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:52 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:52 stefans-speaker go-librespot[21317]: go-librespot daemon starting...
Aug 31 22:58:52 stefans-speaker go-librespot[21318]: time="2026-08-31T22:58:52+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:58:52 stefans-speaker go-librespot[21318]: time="2026-08-31T22:58:52+02:00" level=debug msg="app state loaded"
Aug 31 22:58:52 stefans-speaker go-librespot[21318]: time="2026-08-31T22:58:52+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 31 22:58:52 stefans-speaker go-librespot[21318]: time="2026-08-31T22:58:52+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Aug 31 22:58:52 stefans-speaker go-librespot[21318]: time="2026-08-31T22:58:52+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 22:58:52 stefans-speaker go-librespot[21318]: time="2026-08-31T22:58:52+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 22:58:52 stefans-speaker go-librespot[21318]: time="2026-08-31T22:58:52+02:00" level=info msg="zeroconf server listening on port 41105"
Aug 31 22:58:52 stefans-speaker go-librespot[21318]: time="2026-08-31T22:58:52+02:00" level=debug msg="obtained new client token: AAEdpNAuMVinMKpYs5Wv2VO4P0VI2zfzEHVNggkk96Xa6cUfqrj27BadMpXdvpHp2jcZwVkpYUbq2T1G1Ej4ph5tyCAaHGW8Zjsg7HG5Fwx02iIcpFCb8bGUSiPK3xxBNSt9zPv5apwuwyl5tFIZo1EOAbmDvVqa1ez8yt4+Mc/eQeOnUlAO6z8p8LaosTb0B91TsnjYdgWvYWtnonl/kqR/aKz6xvYOGNbaOpm5OOoQt6EuMB2uiNzwCfs="
Aug 31 22:58:52 stefans-speaker go-librespot[21318]: time="2026-08-31T22:58:52+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Aug 31 22:58:52 stefans-speaker go-librespot[21318]: time="2026-08-31T22:58:52+02:00" level=debug msg="completed keyexchange"
Aug 31 22:58:52 stefans-speaker go-librespot[21318]: time="2026-08-31T22:58:52+02:00" level=debug msg="completed challenge"
Aug 31 22:58:52 stefans-speaker go-librespot[21318]: time="2026-08-31T22:58:52+02:00" level=info msg="authenticated AP" username="31************************cy"
Aug 31 22:58:53 stefans-speaker go-librespot[21318]: time="2026-08-31T22:58:53+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Aug 31 22:58:53 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:58:53 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 31 22:58:54 stefans-speaker sudo[21332]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:58:54 stefans-speaker sudo[21332]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Aug 31 22:58:54 stefans-speaker sudo[21334]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:58:54 stefans-speaker sudo[21332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:58:54 stefans-speaker sudo[21334]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Aug 31 22:58:54 stefans-speaker sudo[21334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:58:54 stefans-speaker systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service...
Aug 31 22:58:54 stefans-speaker systemd[1]: mpd_monitor.service: Deactivated successfully.
Aug 31 22:58:54 stefans-speaker systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service.
Aug 31 22:58:54 stefans-speaker systemd[1]: Started mpd_monitor.service - MPD Monitor Service.
Aug 31 22:58:54 stefans-speaker sudo[21332]: pam_unix(sudo:session): session closed for user root
Aug 31 22:58:54 stefans-speaker sudo[21334]: pam_unix(sudo:session): session closed for user root
Aug 31 22:58:54 stefans-speaker mpd_monitor.sh[21337]: MPD Monitor Service: Starting MPD Monitor Service
Aug 31 22:58:54 stefans-speaker volumio[21031]: info: Successfully started MPD Monitor
Aug 31 22:58:54 stefans-speaker volumio[21031]: info: Successfully started MPD Monitor
Aug 31 22:58:54 stefans-speaker volumio[21031]: info: Initializing connection to go-librespot Websocket
Aug 31 22:58:54 stefans-speaker volumio[21031]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 31 22:58:56 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2.
Aug 31 22:58:56 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:56 stefans-speaker volumio[21031]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Aug 31 22:58:56 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:58:56 stefans-speaker go-librespot[21341]: go-librespot daemon starting...
Aug 31 22:58:56 stefans-speaker go-librespot[21342]: time="2026-08-31T22:58:56+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:58:56 stefans-speaker go-librespot[21342]: time="2026-08-31T22:58:56+02:00" level=debug msg="app state loaded"
Aug 31 22:58:56 stefans-speaker go-librespot[21342]: time="2026-08-31T22:58:56+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 31 22:58:56 stefans-speaker go-librespot[21342]: time="2026-08-31T22:58:56+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 22:58:56 stefans-speaker go-librespot[21342]: time="2026-08-31T22:58:56+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 22:58:56 stefans-speaker go-librespot[21342]: time="2026-08-31T22:58:56+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 22:58:56 stefans-speaker go-librespot[21342]: time="2026-08-31T22:58:56+02:00" level=info msg="zeroconf server listening on port 41509"
Aug 31 22:58:56 stefans-speaker go-librespot[21342]: time="2026-08-31T22:58:56+02:00" level=debug msg="obtained new client token: AAHDxlWB/5Jv5lvgX9GFm1ivrbj8phJ08NoQNZubnfTMkW5h/pW2FLMOUwjsxmhoX8mkpFFJfTkmrxafjgVb0x9/vJGpvXSe2KtPxehj9lSwPbLKBn7lC9sBVvCIr0VEbForUcv7NcYrjihxuHUuf71Up/Bn4JDTx7A/qxurCPAAzy6QFdSQZlXxqTevZH7+ynXyOdiWyejvn/X9O8QpW7uv/pQhq75ShF/xtAiUZ3MwaK5Uv7ixgp8fvQA="
Aug 31 22:58:56 stefans-speaker go-librespot[21342]: time="2026-08-31T22:58:56+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Aug 31 22:58:56 stefans-speaker go-librespot[21342]: time="2026-08-31T22:58:56+02:00" level=debug msg="completed keyexchange"
Aug 31 22:58:56 stefans-speaker go-librespot[21342]: time="2026-08-31T22:58:56+02:00" level=debug msg="completed challenge"
Aug 31 22:58:56 stefans-speaker go-librespot[21342]: time="2026-08-31T22:58:56+02:00" level=info msg="authenticated AP" username="31************************cy"
Aug 31 22:58:56 stefans-speaker go-librespot[21342]: time="2026-08-31T22:58:56+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 22:58:56 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:58:56 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 31 22:58:57 stefans-speaker volumio[21031]: info: Initializing connection to go-librespot Websocket
Aug 31 22:58:57 stefans-speaker volumio[21031]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: Adding plugin bluetooth to MyMusic Plugins
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: Adding plugin multiroom to MyMusic Plugins
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: Adding plugin metavolumio to MyMusic Plugins
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: Adding plugin cd_controller to MyMusic Plugins
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: Adding plugin qobuzconnect to MyMusic Plugins
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: Adding plugin smart_inputs to MyMusic Plugins
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: Adding plugin tidalconnect to MyMusic Plugins
Aug 31 22:58:59 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Aug 31 22:59:00 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3.
Aug 31 22:59:00 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:59:00 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:59:00 stefans-speaker go-librespot[21363]: go-librespot daemon starting...
Aug 31 22:59:00 stefans-speaker go-librespot[21364]: time="2026-08-31T22:59:00+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:59:00 stefans-speaker go-librespot[21364]: time="2026-08-31T22:59:00+02:00" level=debug msg="app state loaded"
Aug 31 22:59:00 stefans-speaker go-librespot[21364]: time="2026-08-31T22:59:00+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 31 22:59:00 stefans-speaker go-librespot[21364]: time="2026-08-31T22:59:00+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Aug 31 22:59:00 stefans-speaker go-librespot[21364]: time="2026-08-31T22:59:00+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 22:59:00 stefans-speaker go-librespot[21364]: time="2026-08-31T22:59:00+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 22:59:00 stefans-speaker go-librespot[21364]: time="2026-08-31T22:59:00+02:00" level=info msg="zeroconf server listening on port 41805"
Aug 31 22:59:00 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Aug 31 22:59:00 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Aug 31 22:59:00 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:59:00 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:59:00 stefans-speaker volumio[21031]: info: Starting MyVolumio Remote Streaming Endpoints
Aug 31 22:59:00 stefans-speaker volumio[21031]: info: MyVolumio login type: Token
Aug 31 22:59:00 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Aug 31 22:59:00 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Aug 31 22:59:01 stefans-speaker nmbd[975]: [2026/08/31 22:59:01.161142, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response)
Aug 31 22:59:01 stefans-speaker nmbd[975]: register_name_response: Answer name <00> differs from question name <00>.
Aug 31 22:59:01 stefans-speaker nmbd[975]: [2026/08/31 22:59:01.163383, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response)
Aug 31 22:59:01 stefans-speaker nmbd[975]: register_name_response: Answer name <03> differs from question name <03>.
Aug 31 22:59:01 stefans-speaker nmbd[975]: [2026/08/31 22:59:01.163766, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response)
Aug 31 22:59:01 stefans-speaker nmbd[975]: register_name_response: Answer name <20> differs from question name <20>.
Aug 31 22:59:01 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Aug 31 22:59:01 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Aug 31 22:59:01 stefans-speaker volumio[21031]: info: Streaming services startup
Aug 31 22:59:01 stefans-speaker volumio[21031]: info: Starting Streaming Daemon
Aug 31 22:59:01 stefans-speaker volumio[21031]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Aug 31 22:59:01 stefans-speaker sudo[21373]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:59:01 stefans-speaker sudo[21373]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Aug 31 22:59:01 stefans-speaker sudo[21373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:59:01 stefans-speaker sudo[21373]: pam_unix(sudo:session): session closed for user root
Aug 31 22:59:01 stefans-speaker volumio[21031]: info: Initializing connection to go-librespot Websocket
Aug 31 22:59:01 stefans-speaker volumio[21031]: error: Cannot start Volumio Streaming Daemon
Aug 31 22:59:01 stefans-speaker volumio[21031]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Aug 31 22:59:01 stefans-speaker volumio[21031]: sudo: unable to resolve host stefans-speaker: System error
Aug 31 22:59:01 stefans-speaker volumio[21031]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Aug 31 22:59:01 stefans-speaker go-librespot[21364]: time="2026-08-31T22:59:01+02:00" level=debug msg="new websocket client"
Aug 31 22:59:01 stefans-speaker volumio[21031]: info: Connection to go-librespot Websocket established
Aug 31 22:59:02 stefans-speaker volumio[21031]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Aug 31 22:59:02 stefans-speaker volumio[21031]: info: MyVolumio token set successfully
Aug 31 22:59:02 stefans-speaker volumio[21031]: info: MYVOLUMIO: Adding device
Aug 31 22:59:02 stefans-speaker volumio[21031]: info: MYVOLUMIO: Evaluating Server
Aug 31 22:59:03 stefans-speaker volumio[21031]: info: MyVolumio status changed
Aug 31 22:59:03 stefans-speaker volumio[21031]: info: Streaming services startup
Aug 31 22:59:03 stefans-speaker volumio[21031]: info: Starting Streaming Daemon
Aug 31 22:59:03 stefans-speaker volumio[21031]: info: Removing browser output: myVolumio user plan is not superstar
Aug 31 22:59:03 stefans-speaker volumio[21031]: info: Removing audio output:
Aug 31 22:59:03 stefans-speaker volumio[21031]: info: Stoppping Tunnel 1
Aug 31 22:59:03 stefans-speaker sudo[21400]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:59:03 stefans-speaker sudo[21400]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Aug 31 22:59:03 stefans-speaker sudo[21400]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:59:03 stefans-speaker sudo[21402]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:59:03 stefans-speaker sudo[21402]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Aug 31 22:59:03 stefans-speaker sudo[21402]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:59:03 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 22:59:03 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 22:59:03 stefans-speaker sudo[21400]: pam_unix(sudo:session): session closed for user root
Aug 31 22:59:03 stefans-speaker volumio[21031]: error: Cannot start Volumio Streaming Daemon
Aug 31 22:59:03 stefans-speaker volumio[21031]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Aug 31 22:59:03 stefans-speaker volumio[21031]: sudo: unable to resolve host stefans-speaker: System error
Aug 31 22:59:03 stefans-speaker volumio[21031]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Aug 31 22:59:03 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 22:59:03 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 22:59:03 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 22:59:03 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 22:59:03 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 22:59:03 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 22:59:03 stefans-speaker sudo[21402]: pam_unix(sudo:session): session closed for user root
Aug 31 22:59:03 stefans-speaker volumio[21031]: info: Remote SSH Stopped
Aug 31 22:59:03 stefans-speaker volumio[21031]: info: Setting Geolocation for MyVolumio to eu12
Aug 31 22:59:03 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:59:03 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:59:03 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:59:04 stefans-speaker volumio[21031]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Aug 31 22:59:04 stefans-speaker volumio[21031]: info: Updating MyVolumio device info
Aug 31 22:59:04 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:59:04 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:59:04 stefans-speaker volumio[21031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:59:04 stefans-speaker volumio[21031]: info: Getting Spotify volume
Aug 31 22:59:04 stefans-speaker volumio[21031]: 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 22:59:04 stefans-speaker volumio[21031]: info: CoreCommandRouter::volumioGetState
Aug 31 22:59:04 stefans-speaker volumio[21031]: info: CorePlayQueue::getTrack 0
Aug 31 22:59:05 stefans-speaker volumio[21031]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70
Aug 31 22:59:05 stefans-speaker volumio[21031]: SPOTIFY: SPOTIFY VOLUME undefined
Aug 31 22:59:05 stefans-speaker volumio[21031]: SPOTIFY: VOLUMIO VOLUME 70
Aug 31 22:59:05 stefans-speaker volumio[21031]: info: Aligning Spotify Volume to Volumio Volume
Aug 31 22:59:05 stefans-speaker volumio[21031]: info: CoreCommandRouter::volumioGetState
Aug 31 22:59:05 stefans-speaker volumio[21031]: info: CorePlayQueue::getTrack 0
Aug 31 22:59:05 stefans-speaker volumio[21031]: info: Setting Spotify Volume from Volumio: 70
Aug 31 22:59:05 stefans-speaker volumio[21031]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Aug 31 22:59:05 stefans-speaker go-librespot[21364]: time="2026-08-31T22:59:05+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: invalid status code from clienttoken: 503"
Aug 31 22:59:05 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:59:05 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 31 22:59:05 stefans-speaker volumio[21031]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 31 22:59:05 stefans-speaker volumio[21031]: Error: socket hang up
Aug 31 22:59:05 stefans-speaker volumio[21031]: at connResetException (node:internal/errors:720:14)
Aug 31 22:59:05 stefans-speaker volumio[21031]: at Socket.socketOnEnd (node:_http_client:519:23)
Aug 31 22:59:05 stefans-speaker volumio[21031]: at Socket.emit (node:events:526:35)
Aug 31 22:59:05 stefans-speaker volumio[21031]: at endReadableNT (node:internal/streams/readable:1376:12)
Aug 31 22:59:05 stefans-speaker volumio[21031]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) {
Aug 31 22:59:05 stefans-speaker volumio[21031]: code: 'ECONNRESET',
Aug 31 22:59:05 stefans-speaker volumio[21031]: response: undefined
Aug 31 22:59:05 stefans-speaker volumio[21031]: }
Aug 31 22:59:05 stefans-speaker volumio[21031]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 31 22:59:05 stefans-speaker sudo[21420]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:59:05 stefans-speaker sudo[21420]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-31 22:58'
Aug 31 22:59:05 stefans-speaker sudo[21420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:59:06 stefans-speaker sudo[21420]: pam_unix(sudo:session): session closed for user root
Aug 31 22:59:06 stefans-speaker volumio[21031]: sudo: unable to resolve host stefans-speaker: System error
Aug 31 22:59:06 stefans-speaker volumio-remote-updater[679]: [2026-08-31 22:59:06] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Aug 31 22:59:06 stefans-speaker volumio-remote-updater[679]: [2026-08-31 22:59:06] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Aug 31 22:59:06 stefans-speaker systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:59:06 stefans-speaker systemd[1]: volumio.service: Failed with result 'exit-code'.
Aug 31 22:59:06 stefans-speaker systemd[1]: volumio.service: Consumed 32.457s CPU time.
Aug 31 22:59:06 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service.
Aug 31 22:59:06 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully.
Aug 31 22:59:07 stefans-speaker systemd[1]: volumio.service: Scheduled restart job, restart counter is at 108.
Aug 31 22:59:07 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service.
Aug 31 22:59:07 stefans-speaker systemd[1]: Stopped volumio.service - Volumio Backend Module.
Aug 31 22:59:07 stefans-speaker systemd[1]: volumio.service: Consumed 32.457s CPU time.
Aug 31 22:59:07 stefans-speaker systemd[1]: Started volumio.service - Volumio Backend Module.
Aug 31 22:59:07 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully.
Aug 31 22:59:08 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4.
Aug 31 22:59:08 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:59:08 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:59:08 stefans-speaker go-librespot[21451]: go-librespot daemon starting...
Aug 31 22:59:08 stefans-speaker go-librespot[21452]: time="2026-08-31T22:59:08+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:59:08 stefans-speaker go-librespot[21452]: time="2026-08-31T22:59:08+02:00" level=debug msg="app state loaded"
Aug 31 22:59:08 stefans-speaker go-librespot[21452]: time="2026-08-31T22:59:08+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 31 22:59:08 stefans-speaker go-librespot[21452]: time="2026-08-31T22:59:08+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Aug 31 22:59:08 stefans-speaker go-librespot[21452]: time="2026-08-31T22:59:08+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Aug 31 22:59:08 stefans-speaker go-librespot[21452]: time="2026-08-31T22:59:08+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Aug 31 22:59:08 stefans-speaker go-librespot[21452]: time="2026-08-31T22:59:08+02:00" level=info msg="zeroconf server listening on port 33909"
Aug 31 22:59:08 stefans-speaker go-librespot[21452]: time="2026-08-31T22:59:08+02:00" level=debug msg="obtained new client token: AAGeB6HZagxBPR5Rhhba8eFu4fhV1outti+1cRH7X717DT0U/icLMrdrt/3KQjd3dEz0kbanGY5K2X27NpJ04LPghEpQJEode4Bfj0HTy5rp83k0TXyKT8N5U1C1Gbvr9ah7gld8kCyCOhHG8eX+XqraC+2Th4aBC/XxmmiOw57Fe+pu5d5Id1G+C2AP2fPmI2ofvgAevQ2IPy6+JW0B+TRbLN/h3bMe1C/w7XQAGEO9GF4SKrj9aRc+yNE="
Aug 31 22:59:08 stefans-speaker go-librespot[21452]: time="2026-08-31T22:59:08+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 22:59:08 stefans-speaker go-librespot[21452]: time="2026-08-31T22:59:08+02:00" level=debug msg="connected to ap-gew4.spotify.com:443"
Aug 31 22:59:08 stefans-speaker go-librespot[21452]: time="2026-08-31T22:59:08+02:00" level=debug msg="completed keyexchange"
Aug 31 22:59:08 stefans-speaker go-librespot[21452]: time="2026-08-31T22:59:08+02:00" level=debug msg="completed challenge"
Aug 31 22:59:08 stefans-speaker go-librespot[21452]: time="2026-08-31T22:59:08+02:00" level=info msg="authenticated AP" username="31************************cy"
Aug 31 22:59:09 stefans-speaker go-librespot[21452]: time="2026-08-31T22:59:09+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 22:59:09 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:59:09 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 31 22:59:09 stefans-speaker volumio[21436]: info: -------------------------------------------
Aug 31 22:59:09 stefans-speaker volumio[21436]: info: ----- Volumio3 ----
Aug 31 22:59:09 stefans-speaker volumio[21436]: info: -------------------------------------------
Aug 31 22:59:09 stefans-speaker volumio[21436]: info: ----- System startup ----
Aug 31 22:59:09 stefans-speaker volumio[21436]: info: -------------------------------------------
Aug 31 22:59:09 stefans-speaker volumio[21436]: info: MYVOLUMIO Environment detected
Aug 31 22:59:10 stefans-speaker volumio[21436]: info: Plugin folders cleanup
Aug 31 22:59:10 stefans-speaker volumio[21436]: info: Scanning into folder /volumio/app/plugins/
Aug 31 22:59:10 stefans-speaker volumio[21436]: info: Scanning category audio_interface
Aug 31 22:59:10 stefans-speaker volumio[21436]: info: Scanning category miscellanea
Aug 31 22:59:10 stefans-speaker volumio[21436]: info: Scanning category music_service
Aug 31 22:59:10 stefans-speaker volumio[21436]: info: Scanning category plugins.json
Aug 31 22:59:10 stefans-speaker volumio[21436]: info: Scanning category system_controller
Aug 31 22:59:10 stefans-speaker volumio[21436]: info: Scanning category user_interface
Aug 31 22:59:10 stefans-speaker volumio[21436]: info: Scanning into folder /data/plugins/
Aug 31 22:59:10 stefans-speaker volumio[21436]: info: Scanning category music_service
Aug 31 22:59:10 stefans-speaker volumio[21436]: info: Plugin folders cleanup completed
Aug 31 22:59:10 stefans-speaker volumio[21436]: info: -------------------------------------------
Aug 31 22:59:10 stefans-speaker volumio[21436]: info: ----- Core plugins startup ----
Aug 31 22:59:10 stefans-speaker volumio[21436]: info: -------------------------------------------
Aug 31 22:59:10 stefans-speaker volumio[21436]: info: Loading plugins from folder /volumio/app/plugins/
Aug 31 22:59:10 stefans-speaker volumio[21436]: info: Adding plugin upnp to MyMusic Plugins
Aug 31 22:59:10 stefans-speaker volumio[21436]: info: Adding plugin airplay_emulation to MyMusic Plugins
Aug 31 22:59:10 stefans-speaker volumio[21436]: info: Adding plugin upnp_browser to MyMusic Plugins
Aug 31 22:59:10 stefans-speaker volumio[21436]: info: Loading plugins from folder /data/plugins/
Aug 31 22:59:10 stefans-speaker volumio[21436]: info: Loading plugin "system"...
Aug 31 22:59:10 stefans-speaker volumio[21436]: info: Loading plugin "appearance"...
Aug 31 22:59:11 stefans-speaker volumio[21436]: info: Loading plugin "network"...
Aug 31 22:59:11 stefans-speaker volumio[21436]: info: Refreshing Cached IP Addresses
Aug 31 22:59:11 stefans-speaker sudo[21472]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:59:11 stefans-speaker sudo[21474]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:59:11 stefans-speaker volumio[21436]: info: Loading plugin "services"...
Aug 31 22:59:11 stefans-speaker sudo[21472]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Aug 31 22:59:11 stefans-speaker sudo[21472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:59:11 stefans-speaker volumio[21436]: info: Loading plugin "alsa_controller"...
Aug 31 22:59:11 stefans-speaker sudo[21474]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 31 22:59:11 stefans-speaker sudo[21474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:59:11 stefans-speaker sudo[21472]: pam_unix(sudo:session): session closed for user root
Aug 31 22:59:11 stefans-speaker sudo[21481]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:59:11 stefans-speaker sudo[21474]: pam_unix(sudo:session): session closed for user root
Aug 31 22:59:11 stefans-speaker sudo[21481]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Aug 31 22:59:11 stefans-speaker sudo[21481]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:59:11 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 31 22:59:11 stefans-speaker volumio[21436]: info: Loading plugin "wizard"...
Aug 31 22:59:11 stefans-speaker volumio[21436]: info: Loading plugin "networkfs"...
Aug 31 22:59:11 stefans-speaker volumio[21436]: info: Starting Udev Watcher for removable devices
Aug 31 22:59:11 stefans-speaker volumio[21436]: info: Ignoring mount for partition: boot
Aug 31 22:59:11 stefans-speaker volumio[21436]: info: Ignoring mount for partition: volumio
Aug 31 22:59:11 stefans-speaker volumio[21436]: info: Ignoring mount for partition: volumio_data
Aug 31 22:59:11 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Aug 31 22:59:11 stefans-speaker volumio[21436]: info: Loading plugin "volumio_command_line_client"...
Aug 31 22:59:11 stefans-speaker volumio[21436]: info: Loading plugin "upnp"...
Aug 31 22:59:11 stefans-speaker volumio[21436]: info: [1788209951388] Starting Upmpd Daemon
Aug 31 22:59:11 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Aug 31 22:59:11 stefans-speaker volumio[21436]: info: Loading plugin "my_music"...
Aug 31 22:59:11 stefans-speaker volumio[21436]: info: Loading plugin "mpd"...
Aug 31 22:59:11 stefans-speaker volumio-remote-updater[679]: [2026-08-31 22:59:11] [connect] Successful connection
Aug 31 22:59:11 stefans-speaker volumio[21436]: info: Loading plugin "upnp_browser"...
Aug 31 22:59:12 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5.
Aug 31 22:59:12 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:59:12 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:59:12 stefans-speaker go-librespot[21505]: go-librespot daemon starting...
Aug 31 22:59:12 stefans-speaker go-librespot[21506]: time="2026-08-31T22:59:12+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:59:12 stefans-speaker go-librespot[21506]: time="2026-08-31T22:59:12+02:00" level=debug msg="app state loaded"
Aug 31 22:59:12 stefans-speaker go-librespot[21506]: time="2026-08-31T22:59:12+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 31 22:59:12 stefans-speaker go-librespot[21506]: time="2026-08-31T22:59:12+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Aug 31 22:59:12 stefans-speaker go-librespot[21506]: time="2026-08-31T22:59:12+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 22:59:12 stefans-speaker go-librespot[21506]: time="2026-08-31T22:59:12+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 22:59:12 stefans-speaker go-librespot[21506]: time="2026-08-31T22:59:12+02:00" level=info msg="zeroconf server listening on port 42819"
Aug 31 22:59:13 stefans-speaker go-librespot[21506]: time="2026-08-31T22:59:13+02:00" level=debug msg="obtained new client token: AAGtX3KcfH+QD3MDYMmJl8azAQMgDYFsXJEAZ7H/lWpOpvsCFsEa8pNpk9nrW0T0aSphbMP6BuqiFI9irqQWTSAAyVYpHuHPPGLZGGBvVDcJi1xRHH3A6GtuMejN+JzaYstiZ2TY/1PjvKq6HlaB24ichgrYszD7g9vBokizVi8sfm6/9OehZXdr68KkDRiypI2OmdbWaEu3QLj01CX6vMCd7SvJLS0DPMmqs8uSlpsbsna1/Q6WHC9U"
Aug 31 22:59:13 stefans-speaker volumio[21436]: info: Starting UPNP Browser
Aug 31 22:59:13 stefans-speaker volumio[21436]: info: Loading plugin "alarm-clock"...
Aug 31 22:59:13 stefans-speaker go-librespot[21506]: time="2026-08-31T22:59:13+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Aug 31 22:59:13 stefans-speaker go-librespot[21506]: time="2026-08-31T22:59:13+02:00" level=debug msg="completed keyexchange"
Aug 31 22:59:13 stefans-speaker go-librespot[21506]: time="2026-08-31T22:59:13+02:00" level=debug msg="completed challenge"
Aug 31 22:59:13 stefans-speaker volumio[21436]: info: Loading plugin "airplay_emulation"...
Aug 31 22:59:13 stefans-speaker volumio[21436]: info: Starting Shairport Sync
Aug 31 22:59:13 stefans-speaker volumio[21436]: info: Loading plugin "last_100"...
Aug 31 22:59:13 stefans-speaker volumio[21436]: info: Loading plugin "webradio"...
Aug 31 22:59:13 stefans-speaker volumio[21436]: info: Loading plugin "i2s_dacs"...
Aug 31 22:59:13 stefans-speaker volumio[21436]: info: I2S DAC not set, start Auto-detection
Aug 31 22:59:13 stefans-speaker volumio[21436]: info: Loading plugin "volumiodiscovery"...
Aug 31 22:59:13 stefans-speaker volumio[21436]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Aug 31 22:59:13 stefans-speaker volumio[21436]: *** WARNING *** Please fix your application to use the native API of Avahi!
Aug 31 22:59:13 stefans-speaker volumio[21436]: *** WARNING *** For more information see
Aug 31 22:59:13 stefans-speaker volumio[21436]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Aug 31 22:59:13 stefans-speaker volumio[21436]: *** WARNING *** Please fix your application to use the native API of Avahi!
Aug 31 22:59:13 stefans-speaker volumio[21436]: *** WARNING *** For more information see
Aug 31 22:59:13 stefans-speaker node[21436]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Aug 31 22:59:13 stefans-speaker node[21436]: *** WARNING *** Please fix your application to use the native API of Avahi!
Aug 31 22:59:13 stefans-speaker node[21436]: *** WARNING *** For more information see
Aug 31 22:59:13 stefans-speaker node[21436]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Aug 31 22:59:13 stefans-speaker node[21436]: *** WARNING *** Please fix your application to use the native API of Avahi!
Aug 31 22:59:13 stefans-speaker node[21436]: *** WARNING *** For more information see
Aug 31 22:59:13 stefans-speaker volumio[21436]: info: Applying required configuration parameters for plugin volumiodiscovery
Aug 31 22:59:13 stefans-speaker volumio[21436]: info: Discovery: Started advertising with name: Stefan’s Speaker
Aug 31 22:59:13 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Aug 31 22:59:13 stefans-speaker volumio[21436]: info: Loading plugin "spop"...
Aug 31 22:59:13 stefans-speaker go-librespot[21506]: time="2026-08-31T22:59:13+02:00" level=info msg="authenticated AP" username="31************************cy"
Aug 31 22:59:14 stefans-speaker go-librespot[21506]: time="2026-08-31T22:59:14+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 22:59:14 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:59:14 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 31 22:59:14 stefans-speaker sudo[21481]: pam_unix(sudo:session): session closed for user root
Aug 31 22:59:14 stefans-speaker volumio[21436]: info: Loading plugin "ytcr"...
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: Loading plugin "outputs"...
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: Loading plugin "albumart"...
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: Plugin example_plugin is not enabled
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: Loading plugin "inputs"...
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: Loading plugin "updater_comm"...
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: Plugin mpdemulation is not enabled
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: Loading plugin "rest_api"...
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: Loading plugin "websocket"...
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: Starting Socket.io Server version 1.7.4
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: Loading i18n strings for locale nl
Aug 31 22:59:16 stefans-speaker volumio[21436]: Updating browse sources language
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: CoreCommandRouter::initPlayerControls
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 31 22:59:16 stefans-speaker volumio[21436]: Express server listening on port 3000
Aug 31 22:59:16 stefans-speaker volumio[21436]: [Metrics] WebUI: 8s 1.24ms
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: CoreStateMachine::resetVolumioState
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: CoreStateMachine::getcurrentVolume
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: CoreCommandRouter::volumioRetrievevolume
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: Cannot read play queue from file
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: Volumio Network Manager: Network status updated: 2
Aug 31 22:59:16 stefans-speaker volumio[21518]: Forking 3 albumart workers
Aug 31 22:59:16 stefans-speaker systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service...
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: VolumeController:: Volume=70 Mute =false
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: CoreStateMachine::pushState
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: CorePlayQueue::getTrack 0
Aug 31 22:59:16 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreCommandRouter::volumioPushState
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreStateMachine::updateTrackBlock
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CorePlayQueue::getTrackBlock
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreCommandRouter::volumioRetrievevolume
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreStateMachine::setRepeat null single undefined
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreStateMachine::pushState
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CorePlayQueue::getTrack 0
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreCommandRouter::volumioPushState
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreStateMachine::setRandom null
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreStateMachine::pushState
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CorePlayQueue::getTrack 0
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreCommandRouter::volumioPushState
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: Setting Device type: Raspberry PI
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: VolumeController:: Volume=70 Mute =false
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreStateMachine::pushState
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CorePlayQueue::getTrack 0
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreCommandRouter::volumioPushState
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: Completed loading Core Plugins
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: Preparing to generate the ALSA configuration file
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: Discovery: adding a312d0fa-c0eb-45ad-a25b-79fcdf237e8a
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: Discovery: Found device Stefan’s Speaker
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreCommandRouter::volumioGetState
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CorePlayQueue::getTrack 0
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: Asound.conf file unchanged, so no further update is needed
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: Output device has changed, restarting MPD
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: Output device has changed, restarting Shairport Sync
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:59:17 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6.
Aug 31 22:59:17 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: ___________ START PLUGINS ___________
Aug 31 22:59:17 stefans-speaker sudo[21590]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:59:17 stefans-speaker sudo[21592]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:59:17 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: ControllerMpd::onStart: Initializing MPD
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: Creating MPD Configuration file
Aug 31 22:59:17 stefans-speaker sudo[21590]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Aug 31 22:59:17 stefans-speaker go-librespot[21596]: go-librespot daemon starting...
Aug 31 22:59:17 stefans-speaker sudo[21590]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:59:17 stefans-speaker sudo[21592]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 31 22:59:17 stefans-speaker sudo[21592]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:59:17 stefans-speaker sudo[21590]: pam_unix(sudo:session): session closed for user root
Aug 31 22:59:17 stefans-speaker go-librespot[21598]: time="2026-08-31T22:59:17+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:59:17 stefans-speaker go-librespot[21598]: time="2026-08-31T22:59:17+02:00" level=debug msg="app state loaded"
Aug 31 22:59:17 stefans-speaker go-librespot[21598]: time="2026-08-31T22:59:17+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: [1788209957675] CoreMusicLibrary::Adding element Media Servers
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: UPNP Browser: Client initialized successfully
Aug 31 22:59:17 stefans-speaker sudo[21609]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:59:17 stefans-speaker systemd[1]: Stopping mpd.service - Music Player Daemon...
Aug 31 22:59:17 stefans-speaker sudo[21607]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:59:17 stefans-speaker sudo[21609]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:59:17 stefans-speaker sudo[21607]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Aug 31 22:59:17 stefans-speaker sudo[21609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:59:17 stefans-speaker sudo[21607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:59:17 stefans-speaker sudo[21607]: pam_unix(sudo:session): session closed for user root
Aug 31 22:59:17 stefans-speaker systemd[1]: mpd.service: Deactivated successfully.
Aug 31 22:59:17 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 31 22:59:17 stefans-speaker systemd[1]: mpd.service: Consumed 4.491s CPU time.
Aug 31 22:59:17 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully.
Aug 31 22:59:17 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 31 22:59:17 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: [1788209957893] CoreMusicLibrary::Adding element Last_100
Aug 31 22:59:17 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 31 22:59:17 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 31 22:59:17 stefans-speaker systemd[1]: mpd.service: Deactivated successfully.
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: [1788209957932] CoreMusicLibrary::Adding element Webradio
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 31 22:59:17 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 31 22:59:17 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully.
Aug 31 22:59:17 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 31 22:59:17 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 31 22:59:17 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 31 22:59:17 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 31 22:59:17 stefans-speaker volumio[21436]: info: Initializing BBC Radios
Aug 31 22:59:18 stefans-speaker go-librespot[21598]: time="2026-08-31T22:59:18+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Aug 31 22:59:18 stefans-speaker go-librespot[21598]: time="2026-08-31T22:59:18+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 22:59:18 stefans-speaker go-librespot[21598]: time="2026-08-31T22:59:18+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 22:59:18 stefans-speaker go-librespot[21598]: time="2026-08-31T22:59:18+02:00" level=info msg="zeroconf server listening on port 42511"
Aug 31 22:59:18 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 31 22:59:18 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:59:18 stefans-speaker volumio[21436]: info: Creating Spotify config file
Aug 31 22:59:18 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:18 stefans-speaker go-librespot[21598]: time="2026-08-31T22:59:18+02:00" level=debug msg="obtained new client token: AAFlHJRK1xGZwKfwLI9wHWnRD9itR7BUtDdSeop4lzgbFeYLKx3qODYNtN8pdEYAA9KB4sleOZ0AT6S1Qzp0dnfUiRjmbe/FNZzTIJ3IRPrARRvHkBHnyhl8xwa9gBaxboQIkseCgb9V5iYuFdxXCthSyj2u5U+PGO0gHz5tFst74h/7tFg9dat6y2u+tN2wyma/aYD9a5M5l/Iea/L8K0s8L5lRvK8JLr3iVJdaxRkamQbLljirm/9v"
Aug 31 22:59:18 stefans-speaker go-librespot[21598]: time="2026-08-31T22:59:18+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 22:59:18 stefans-speaker go-librespot[21598]: time="2026-08-31T22:59:18+02:00" level=debug msg="connected to ap-gew4.spotify.com:443"
Aug 31 22:59:18 stefans-speaker go-librespot[21598]: time="2026-08-31T22:59:18+02:00" level=debug msg="completed keyexchange"
Aug 31 22:59:18 stefans-speaker go-librespot[21598]: time="2026-08-31T22:59:18+02:00" level=debug msg="completed challenge"
Aug 31 22:59:18 stefans-speaker sudo[21625]: root : unable to resolve host stefans-speaker: System error
Aug 31 22:59:18 stefans-speaker sudo[21625]: sudo: unable to resolve host stefans-speaker: System error
Aug 31 22:59:18 stefans-speaker sudo[21625]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Aug 31 22:59:18 stefans-speaker sudo[21625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 31 22:59:18 stefans-speaker sudo[21625]: pam_unix(sudo:session): session closed for user root
Aug 31 22:59:18 stefans-speaker go-librespot[21598]: time="2026-08-31T22:59:18+02:00" level=info msg="authenticated AP" username="31************************cy"
Aug 31 22:59:18 stefans-speaker go-librespot[21598]: time="2026-08-31T22:59:18+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 22:59:18 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:59:18 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 31 22:59:19 stefans-speaker systemd[1]: setdatetime-helper.service: Deactivated successfully.
Aug 31 22:59:19 stefans-speaker systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
Aug 31 22:59:19 stefans-speaker systemd[1]: setdatetime-helper.service: Consumed 1.316s CPU time.
Aug 31 22:59:19 stefans-speaker volumio[21534]: Starting albumart workers
Aug 31 22:59:19 stefans-speaker volumio[21536]: Starting albumart workers
Aug 31 22:59:19 stefans-speaker volumio[21436]: info: Volumio Calling Home
Aug 31 22:59:19 stefans-speaker volumio[21535]: Starting albumart workers
Aug 31 22:59:19 stefans-speaker volumio[21436]: info: Discovery: this is already registered, a312d0fa-c0eb-45ad-a25b-79fcdf237e8a
Aug 31 22:59:19 stefans-speaker volumio[21436]: info: Discovery: Found device Stefan’s Speaker
Aug 31 22:59:19 stefans-speaker volumio[21436]: info: CoreCommandRouter::volumioGetState
Aug 31 22:59:19 stefans-speaker volumio[21436]: info: CorePlayQueue::getTrack 0
Aug 31 22:59:19 stefans-speaker volumio[21436]: info: MPD Permissions set
Aug 31 22:59:19 stefans-speaker volumio[21436]: info: MPD Permissions set
Aug 31 22:59:19 stefans-speaker volumio[21436]: info: Volumio called home
Aug 31 22:59:19 stefans-speaker volumio[21436]: info: Spotify config file written
Aug 31 22:59:19 stefans-speaker volumio[21436]: 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 22:59:19 stefans-speaker sudo[21660]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:59:19 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:19 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:19 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:19 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:19 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:19 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:19 stefans-speaker sudo[21660]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Aug 31 22:59:19 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:19 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:19 stefans-speaker sudo[21660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:59:20 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:59:20 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:20 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:20 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:20 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:20 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:20 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:20 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:20 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:20 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:20 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:59:20 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 22:59:20 stefans-speaker volumio[21436]: info: No need to fix Spotify hosts
Aug 31 22:59:20 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:59:20 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:59:20 stefans-speaker go-librespot[21672]: go-librespot daemon starting...
Aug 31 22:59:20 stefans-speaker sudo[21660]: pam_unix(sudo:session): session closed for user root
Aug 31 22:59:20 stefans-speaker go-librespot[21673]: time="2026-08-31T22:59:20+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:59:20 stefans-speaker go-librespot[21673]: time="2026-08-31T22:59:20+02:00" level=debug msg="app state loaded"
Aug 31 22:59:20 stefans-speaker go-librespot[21673]: time="2026-08-31T22:59:20+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 31 22:59:20 stefans-speaker go-librespot[21673]: time="2026-08-31T22:59: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 22:59:20 stefans-speaker go-librespot[21673]: time="2026-08-31T22:59: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 22:59:20 stefans-speaker go-librespot[21673]: time="2026-08-31T22:59: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 22:59:20 stefans-speaker go-librespot[21673]: time="2026-08-31T22:59:20+02:00" level=info msg="zeroconf server listening on port 36063"
Aug 31 22:59:20 stefans-speaker go-librespot[21673]: time="2026-08-31T22:59:20+02:00" level=debug msg="obtained new client token: AAFW5UA7wJEgzKQHtN18N7PB8g7uG6M2ZlEJ6KT1qdtWsnVEzxAPQzidWerhYcEpaVlBpP3By2ORK3EBv1U8F75yIrHzHq62Jj3Sg35fvRg1Y26EB+G3+AnHdWyafiNXgk9VF8vg1Y1GM0vQSAq1KRtaH0lrUrz4LYcvc+HStn2o3oobCLT8lHndCmHEX5WiysZX9Wq1Nu+9Z5mtxQD04zikKivaQyHV0HAtx0qLFXoLxoWIDv9JlPxeizk="
Aug 31 22:59:20 stefans-speaker go-librespot[21673]: time="2026-08-31T22:59:20+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Aug 31 22:59:20 stefans-speaker volumio[21436]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Aug 31 22:59:20 stefans-speaker volumio[21436]: SPOTIFY: BQCfdESIakUrBNfn4hS0vCQU84MMWDxR_DBW4KQwlHfd8KeiBsDP1LQd-yDTtObI7XoZ0mxSHOJ3ZSkZa2M5loSntOSbHVmHeicw4S3ItIGjFVks2ybH0PYWM-HnoSpiFNadRQ-6TwfF80cfDRJl0So_uahN8rKX9Y4A7q_G7f0qFxGi-sKTdp-cJdLuxwaSeqBfeNv6w1t26f_2VbFRlGaGxgLzh6MLmkj0mY6RGeqA_LhKH7wqelFMftDNKh8JaCEENON_36c3cQE-PVyNgfvFEK1KuAc97OJsbBRKH6QXV2l0KtyDMNUfozWnS1M
Aug 31 22:59:20 stefans-speaker volumio[21436]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Aug 31 22:59:20 stefans-speaker volumio[21436]: info: New Spotify access token = BQCfdESIakUrBNfn4hS0vCQU84MMWDxR_DBW4KQwlHfd8KeiBsDP1LQd-yDTtObI7XoZ0mxSHOJ3ZSkZa2M5loSntOSbHVmHeicw4S3ItIGjFVks2ybH0PYWM-HnoSpiFNadRQ-6TwfF80cfDRJl0So_uahN8rKX9Y4A7q_G7f0qFxGi-sKTdp-cJdLuxwaSeqBfeNv6w1t26f_2VbFRlGaGxgLzh6MLmkj0mY6RGeqA_LhKH7wqelFMftDNKh8JaCEENON_36c3cQE-PVyNgfvFEK1KuAc97OJsbBRKH6QXV2l0KtyDMNUfozWnS1M
Aug 31 22:59:20 stefans-speaker volumio[21436]: info: Spotify credentials grant success - running version from March 24, 2019
Aug 31 22:59:20 stefans-speaker go-librespot[21673]: time="2026-08-31T22:59:20+02:00" level=debug msg="completed keyexchange"
Aug 31 22:59:20 stefans-speaker go-librespot[21673]: time="2026-08-31T22:59:20+02:00" level=debug msg="completed challenge"
Aug 31 22:59:20 stefans-speaker volumio[21436]: info: Starting Shairport Sync
Aug 31 22:59:20 stefans-speaker volumio[21436]: info: Starting Shairport Sync
Aug 31 22:59:20 stefans-speaker volumio[21436]: info: Starting Shairport Sync
Aug 31 22:59:20 stefans-speaker go-librespot[21673]: time="2026-08-31T22:59:20+02:00" level=info msg="authenticated AP" username="31************************cy"
Aug 31 22:59:20 stefans-speaker sudo[21693]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:59:20 stefans-speaker sudo[21693]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 31 22:59:21 stefans-speaker sudo[21695]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:59:21 stefans-speaker sudo[21693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:59:21 stefans-speaker sudo[21697]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:59:21 stefans-speaker sudo[21695]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 31 22:59:21 stefans-speaker sudo[21695]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:59:21 stefans-speaker sudo[21697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 31 22:59:21 stefans-speaker sudo[21697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:59:21 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Aug 31 22:59:21 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully.
Aug 31 22:59:21 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 31 22:59:21 stefans-speaker systemd[1]: shairport-sync.service: Consumed 1.837s CPU time.
Aug 31 22:59:21 stefans-speaker go-librespot[21673]: time="2026-08-31T22:59: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 22:59:21 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 31 22:59:21 stefans-speaker sudo[21693]: pam_unix(sudo:session): session closed for user root
Aug 31 22:59:21 stefans-speaker sudo[21697]: pam_unix(sudo:session): session closed for user root
Aug 31 22:59:21 stefans-speaker sudo[21695]: pam_unix(sudo:session): session closed for user root
Aug 31 22:59:21 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:59:21 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 31 22:59:21 stefans-speaker volumio[21436]: info: CoreCommandRouter::volumioGetState
Aug 31 22:59:21 stefans-speaker volumio[21436]: info: CorePlayQueue::getTrack 0
Aug 31 22:59:21 stefans-speaker volumio[21436]: info: Shairport-Sync Started
Aug 31 22:59:21 stefans-speaker volumio[21436]: Error adding Membership: Error: addMembership EINVAL
Aug 31 22:59:21 stefans-speaker volumio[21436]: info: Shairport-Sync Started
Aug 31 22:59:21 stefans-speaker volumio[21436]: info: Shairport-Sync Started
Aug 31 22:59:21 stefans-speaker sudo[21733]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:59:21 stefans-speaker sudo[21733]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Aug 31 22:59:21 stefans-speaker sudo[21733]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:59:21 stefans-speaker sudo[21733]: pam_unix(sudo:session): session closed for user root
Aug 31 22:59:21 stefans-speaker sudo[21735]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:59:21 stefans-speaker sudo[21735]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 31 22:59:21 stefans-speaker sudo[21735]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:59:21 stefans-speaker sudo[21735]: pam_unix(sudo:session): session closed for user root
Aug 31 22:59:21 stefans-speaker volumio[21436]: 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 22:59:21 stefans-speaker volumio[21436]: info: Spotify Successfully logged in
Aug 31 22:59:21 stefans-speaker volumio[21436]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Aug 31 22:59:21 stefans-speaker volumio[21436]: info: [1788209961650] CoreMusicLibrary::Adding element Spotify
Aug 31 22:59:21 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 31 22:59:21 stefans-speaker volumio[21436]: Cannot find translation for source Spotify
Aug 31 22:59:21 stefans-speaker sudo[21739]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:59:21 stefans-speaker sudo[21739]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Aug 31 22:59:21 stefans-speaker sudo[21739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:59:21 stefans-speaker sudo[21739]: pam_unix(sudo:session): session closed for user root
Aug 31 22:59:21 stefans-speaker volumio[21436]: info: Upmpdcli Daemon Started
Aug 31 22:59:21 stefans-speaker volumio[21436]: info: [yt-cast-receiver] DIAL server listening on port 8098
Aug 31 22:59:21 stefans-speaker volumio[21436]: info: CoreCommandRouter::volumioRetrievevolume
Aug 31 22:59:22 stefans-speaker volumio[21436]: info: VolumeController:: Volume=70 Mute =false
Aug 31 22:59:22 stefans-speaker volumio[21436]: info: CoreCommandRouter::volumioGetState
Aug 31 22:59:22 stefans-speaker volumio[21436]: info: CorePlayQueue::getTrack 0
Aug 31 22:59:22 stefans-speaker volumio[21436]: info: CoreStateMachine::pushState
Aug 31 22:59:22 stefans-speaker volumio[21436]: info: CorePlayQueue::getTrack 0
Aug 31 22:59:22 stefans-speaker volumio[21436]: info: CoreCommandRouter::volumioPushState
Aug 31 22:59:22 stefans-speaker mpd[21645]: 2026-08-31T22:59:22 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Aug 31 22:59:22 stefans-speaker systemd[1]: Started mpd.service - Music Player Daemon.
Aug 31 22:59:22 stefans-speaker sudo[21592]: pam_unix(sudo:session): session closed for user root
Aug 31 22:59:22 stefans-speaker sudo[21609]: pam_unix(sudo:session): session closed for user root
Aug 31 22:59:23 stefans-speaker volumio[21436]: error: MPD error: The expression evaluated to a falsy value:
Aug 31 22:59:23 stefans-speaker volumio[21436]: assert.ok(self.idling)
Aug 31 22:59:23 stefans-speaker volumio[21436]: error: The expression evaluated to a falsy value:
Aug 31 22:59:23 stefans-speaker volumio[21436]: assert.ok(self.idling)
Aug 31 22:59:23 stefans-speaker volumio[21436]: info: MPD running with PID21645
Aug 31 22:59:23 stefans-speaker volumio[21436]: ,establishing connection
Aug 31 22:59:23 stefans-speaker volumio[21436]: error: updateQueue error: null
Aug 31 22:59:23 stefans-speaker volumio[21436]: info: Completed starting Core Plugins
Aug 31 22:59:23 stefans-speaker volumio[21436]: info: -------------------------------------------
Aug 31 22:59:23 stefans-speaker volumio[21436]: info: ----- MyVolumio plugins startup ----
Aug 31 22:59:23 stefans-speaker volumio[21436]: info: -------------------------------------------
Aug 31 22:59:23 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Fetching plans data....
Aug 31 22:59:23 stefans-speaker volumio[21436]: error: updateQueue error: null
Aug 31 22:59:23 stefans-speaker volumio[21436]: info: go-librespot daemon successfully initialized
Aug 31 22:59:24 stefans-speaker volumio-remote-updater[679]: [2026-08-31 22:59:24] [connect] Successful connection
Aug 31 22:59:24 stefans-speaker volumio-remote-updater[679]: [2026-08-31 22:59:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788209964 101
Aug 31 22:59:24 stefans-speaker volumio[21436]: 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 22:59:24 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7.
Aug 31 22:59:24 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:59:24 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:59:24 stefans-speaker go-librespot[21747]: go-librespot daemon starting...
Aug 31 22:59:24 stefans-speaker go-librespot[21748]: time="2026-08-31T22:59:24+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:59:24 stefans-speaker go-librespot[21748]: time="2026-08-31T22:59:24+02:00" level=debug msg="app state loaded"
Aug 31 22:59:24 stefans-speaker go-librespot[21748]: time="2026-08-31T22:59:24+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 31 22:59:24 stefans-speaker go-librespot[21748]: time="2026-08-31T22:59:24+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Aug 31 22:59:24 stefans-speaker go-librespot[21748]: time="2026-08-31T22:59:24+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Aug 31 22:59:24 stefans-speaker go-librespot[21748]: time="2026-08-31T22:59:24+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Aug 31 22:59:24 stefans-speaker go-librespot[21748]: time="2026-08-31T22:59:24+02:00" level=info msg="zeroconf server listening on port 33003"
Aug 31 22:59:24 stefans-speaker go-librespot[21748]: time="2026-08-31T22:59:24+02:00" level=debug msg="obtained new client token: AAHDGNglDyEk+ZXucMsgY9fEMdeVodFAeQnZj1ZQAInoKdmsGDrHrXdiJeRYTUCKm65+yHPxS1lvbZLj2w/+4zlwlq7ecU7MK0usM8lIuvvBNEa9ELNZkZ9uqpBY6yeZvOwYRUkmTCvTLFMXA2lTSnAyLZs+/rllx2N59/bGphkeWhMg2oSkYI5iwflejzwh7EjYzi0NH5XMj54zKFmYRXWYsCAkERiVOURrLg6OlXUkUAcamF2muZ4RJzM="
Aug 31 22:59:24 stefans-speaker go-librespot[21748]: time="2026-08-31T22:59:24+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused"
Aug 31 22:59:24 stefans-speaker go-librespot[21748]: time="2026-08-31T22:59:24+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.1.133:443: connect: connection refused"
Aug 31 22:59:24 stefans-speaker go-librespot[21748]: time="2026-08-31T22:59:24+02:00" level=debug msg="connected to ap-gew4.spotify.com:80"
Aug 31 22:59:24 stefans-speaker go-librespot[21748]: time="2026-08-31T22:59:24+02:00" level=debug msg="completed keyexchange"
Aug 31 22:59:24 stefans-speaker go-librespot[21748]: time="2026-08-31T22:59:24+02:00" level=debug msg="completed challenge"
Aug 31 22:59:24 stefans-speaker go-librespot[21748]: time="2026-08-31T22:59:24+02:00" level=info msg="authenticated AP" username="31************************cy"
Aug 31 22:59:25 stefans-speaker go-librespot[21748]: time="2026-08-31T22:59: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 22:59:25 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:59:25 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 31 22:59:25 stefans-speaker sudo[21758]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:59:25 stefans-speaker sudo[21758]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Aug 31 22:59:25 stefans-speaker sudo[21758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:59:25 stefans-speaker sudo[21760]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:59:25 stefans-speaker sudo[21760]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Aug 31 22:59:25 stefans-speaker sudo[21760]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:59:26 stefans-speaker systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service...
Aug 31 22:59:26 stefans-speaker systemd[1]: mpd_monitor.service: Deactivated successfully.
Aug 31 22:59:26 stefans-speaker systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service.
Aug 31 22:59:26 stefans-speaker systemd[1]: Started mpd_monitor.service - MPD Monitor Service.
Aug 31 22:59:26 stefans-speaker sudo[21758]: pam_unix(sudo:session): session closed for user root
Aug 31 22:59:26 stefans-speaker sudo[21760]: pam_unix(sudo:session): session closed for user root
Aug 31 22:59:26 stefans-speaker volumio[21436]: info: Successfully started MPD Monitor
Aug 31 22:59:26 stefans-speaker mpd_monitor.sh[21763]: MPD Monitor Service: Starting MPD Monitor Service
Aug 31 22:59:26 stefans-speaker volumio[21436]: info: Successfully started MPD Monitor
Aug 31 22:59:26 stefans-speaker volumio[21436]: info: Initializing connection to go-librespot Websocket
Aug 31 22:59:26 stefans-speaker volumio[21436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 31 22:59:27 stefans-speaker volumio[21436]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Aug 31 22:59:28 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8.
Aug 31 22:59:28 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:59:28 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:59:28 stefans-speaker go-librespot[21767]: go-librespot daemon starting...
Aug 31 22:59:28 stefans-speaker go-librespot[21768]: time="2026-08-31T22:59:28+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:59:28 stefans-speaker go-librespot[21768]: time="2026-08-31T22:59:28+02:00" level=debug msg="app state loaded"
Aug 31 22:59:28 stefans-speaker go-librespot[21768]: time="2026-08-31T22:59:28+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 31 22:59:28 stefans-speaker go-librespot[21768]: time="2026-08-31T22:59: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 22:59:28 stefans-speaker go-librespot[21768]: time="2026-08-31T22:59: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 22:59:28 stefans-speaker go-librespot[21768]: time="2026-08-31T22:59: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 22:59:28 stefans-speaker go-librespot[21768]: time="2026-08-31T22:59:28+02:00" level=info msg="zeroconf server listening on port 35633"
Aug 31 22:59:28 stefans-speaker go-librespot[21768]: time="2026-08-31T22:59:28+02:00" level=debug msg="obtained new client token: AAESX5NunFwZ+ouqdkthjZSVrMwiv+SSiHEZS4WsWVHEbOlqh/XVRVDoXVGTZGXxBnKTnGY6i99Qw0951coTqa1OnGcCumaziAGj019/LUZTw1ftcrYOTSHXPg+WJ9yx+3GtU3S9ZqdH5LAk+8z6Hh3slwWyN4zGe4ptzX6+CUKLtT8+ySqMfsdXbfAJ1+KYZsbH6vE183F0B7bEBYqS2Yx3+NwoI4l1snfZMDXc6BwPmliUv2BvDR4yD/Q="
Aug 31 22:59:28 stefans-speaker go-librespot[21768]: time="2026-08-31T22:59:28+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Aug 31 22:59:28 stefans-speaker go-librespot[21768]: time="2026-08-31T22:59:28+02:00" level=debug msg="completed keyexchange"
Aug 31 22:59:28 stefans-speaker go-librespot[21768]: time="2026-08-31T22:59:28+02:00" level=debug msg="completed challenge"
Aug 31 22:59:28 stefans-speaker go-librespot[21768]: time="2026-08-31T22:59:28+02:00" level=info msg="authenticated AP" username="31************************cy"
Aug 31 22:59:29 stefans-speaker go-librespot[21768]: time="2026-08-31T22:59: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 22:59:29 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:59:29 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 31 22:59:29 stefans-speaker volumio[21436]: info: Initializing connection to go-librespot Websocket
Aug 31 22:59:29 stefans-speaker volumio[21436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: Adding plugin bluetooth to MyMusic Plugins
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: Adding plugin multiroom to MyMusic Plugins
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: Adding plugin metavolumio to MyMusic Plugins
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: Adding plugin cd_controller to MyMusic Plugins
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: Adding plugin qobuzconnect to MyMusic Plugins
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: Adding plugin smart_inputs to MyMusic Plugins
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: Adding plugin tidalconnect to MyMusic Plugins
Aug 31 22:59:31 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Aug 31 22:59:32 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9.
Aug 31 22:59:32 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:59:32 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:59:32 stefans-speaker go-librespot[21790]: go-librespot daemon starting...
Aug 31 22:59:32 stefans-speaker go-librespot[21791]: time="2026-08-31T22:59:32+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:59:32 stefans-speaker go-librespot[21791]: time="2026-08-31T22:59:32+02:00" level=debug msg="app state loaded"
Aug 31 22:59:32 stefans-speaker go-librespot[21791]: time="2026-08-31T22:59:32+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 31 22:59:32 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Aug 31 22:59:32 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Aug 31 22:59:32 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:59:32 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:59:32 stefans-speaker volumio[21436]: info: Starting MyVolumio Remote Streaming Endpoints
Aug 31 22:59:32 stefans-speaker volumio[21436]: info: MyVolumio login type: Token
Aug 31 22:59:32 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Aug 31 22:59:32 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Aug 31 22:59:32 stefans-speaker go-librespot[21791]: time="2026-08-31T22:59: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 22:59:32 stefans-speaker go-librespot[21791]: time="2026-08-31T22:59: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 22:59:32 stefans-speaker go-librespot[21791]: time="2026-08-31T22:59: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 22:59:32 stefans-speaker go-librespot[21791]: time="2026-08-31T22:59:32+02:00" level=info msg="zeroconf server listening on port 43883"
Aug 31 22:59:32 stefans-speaker go-librespot[21791]: time="2026-08-31T22:59:32+02:00" level=debug msg="obtained new client token: AAHIchvkTe7An+56cA5MUxmHEbv88iSLpducL9zCbd1UxZ05t/KA+7vRnFL3ShTSAZNnS40MEguZ3KQuzIXJ4uUtyI9h+l8KTPvj95hhEJPRIZaJQ6ecUTJnBkH61rSf8n3G0uWGYHO3UXnNMNquGUNIT/lfk35RBbH+5eYMu0aPpZFI8GuSnNC9ZHmK6Tpl3UINKx24tIDcJz35noPlgtbSUgaLucrWeXY+uXPmPTIVCV/qJ72kDK73mXg="
Aug 31 22:59:32 stefans-speaker go-librespot[21791]: time="2026-08-31T22:59:32+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Aug 31 22:59:32 stefans-speaker go-librespot[21791]: time="2026-08-31T22:59:32+02:00" level=debug msg="completed keyexchange"
Aug 31 22:59:32 stefans-speaker go-librespot[21791]: time="2026-08-31T22:59:32+02:00" level=debug msg="completed challenge"
Aug 31 22:59:32 stefans-speaker go-librespot[21791]: time="2026-08-31T22:59:32+02:00" level=info msg="authenticated AP" username="31************************cy"
Aug 31 22:59:33 stefans-speaker go-librespot[21791]: time="2026-08-31T22:59: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 22:59:33 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:59:33 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 31 22:59:33 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Aug 31 22:59:33 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Aug 31 22:59:33 stefans-speaker volumio[21436]: info: Streaming services startup
Aug 31 22:59:33 stefans-speaker volumio[21436]: info: Starting Streaming Daemon
Aug 31 22:59:33 stefans-speaker volumio[21436]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Aug 31 22:59:33 stefans-speaker sudo[21799]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:59:33 stefans-speaker sudo[21799]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Aug 31 22:59:33 stefans-speaker sudo[21799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:59:33 stefans-speaker sudo[21799]: pam_unix(sudo:session): session closed for user root
Aug 31 22:59:33 stefans-speaker volumio[21436]: info: Initializing connection to go-librespot Websocket
Aug 31 22:59:33 stefans-speaker volumio[21436]: error: Cannot start Volumio Streaming Daemon
Aug 31 22:59:33 stefans-speaker volumio[21436]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Aug 31 22:59:33 stefans-speaker volumio[21436]: sudo: unable to resolve host stefans-speaker: System error
Aug 31 22:59:33 stefans-speaker volumio[21436]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Aug 31 22:59:33 stefans-speaker volumio[21436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 31 22:59:34 stefans-speaker volumio[21436]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Aug 31 22:59:35 stefans-speaker volumio[21436]: info: MyVolumio token set successfully
Aug 31 22:59:35 stefans-speaker volumio[21436]: info: MYVOLUMIO: Adding device
Aug 31 22:59:35 stefans-speaker volumio[21436]: info: MYVOLUMIO: Evaluating Server
Aug 31 22:59:36 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10.
Aug 31 22:59:36 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:59:36 stefans-speaker volumio[21436]: info: MyVolumio status changed
Aug 31 22:59:36 stefans-speaker volumio[21436]: info: Streaming services startup
Aug 31 22:59:36 stefans-speaker volumio[21436]: info: Starting Streaming Daemon
Aug 31 22:59:36 stefans-speaker volumio[21436]: info: Removing browser output: myVolumio user plan is not superstar
Aug 31 22:59:36 stefans-speaker volumio[21436]: info: Removing audio output:
Aug 31 22:59:36 stefans-speaker volumio[21436]: info: Stoppping Tunnel 1
Aug 31 22:59:36 stefans-speaker sudo[21810]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:59:36 stefans-speaker sudo[21810]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Aug 31 22:59:36 stefans-speaker sudo[21810]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:59:36 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:59:36 stefans-speaker go-librespot[21808]: go-librespot daemon starting...
Aug 31 22:59:36 stefans-speaker sudo[21812]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:59:36 stefans-speaker sudo[21812]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Aug 31 22:59:36 stefans-speaker sudo[21812]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:59:36 stefans-speaker go-librespot[21818]: time="2026-08-31T22:59:36+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:59:36 stefans-speaker go-librespot[21818]: time="2026-08-31T22:59:36+02:00" level=debug msg="app state loaded"
Aug 31 22:59:36 stefans-speaker go-librespot[21818]: time="2026-08-31T22:59:36+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 31 22:59:36 stefans-speaker sudo[21810]: pam_unix(sudo:session): session closed for user root
Aug 31 22:59:36 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 22:59:36 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 22:59:36 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 22:59:36 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 22:59:36 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 22:59:36 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 22:59:36 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 22:59:36 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 22:59:36 stefans-speaker sudo[21812]: pam_unix(sudo:session): session closed for user root
Aug 31 22:59:36 stefans-speaker volumio[21436]: info: Remote SSH Stopped
Aug 31 22:59:36 stefans-speaker volumio[21436]: error: Cannot start Volumio Streaming Daemon
Aug 31 22:59:36 stefans-speaker volumio[21436]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Aug 31 22:59:36 stefans-speaker volumio[21436]: sudo: unable to resolve host stefans-speaker: System error
Aug 31 22:59:36 stefans-speaker volumio[21436]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Aug 31 22:59:36 stefans-speaker volumio[21436]: info: Initializing connection to go-librespot Websocket
Aug 31 22:59:36 stefans-speaker go-librespot[21818]: time="2026-08-31T22:59:36+02:00" level=debug msg="new websocket client"
Aug 31 22:59:36 stefans-speaker volumio[21436]: info: Connection to go-librespot Websocket established
Aug 31 22:59:36 stefans-speaker go-librespot[21818]: time="2026-08-31T22:59:36+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Aug 31 22:59:36 stefans-speaker go-librespot[21818]: time="2026-08-31T22:59:36+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 22:59:36 stefans-speaker go-librespot[21818]: time="2026-08-31T22:59:36+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 22:59:36 stefans-speaker go-librespot[21818]: time="2026-08-31T22:59:36+02:00" level=info msg="zeroconf server listening on port 33419"
Aug 31 22:59:36 stefans-speaker go-librespot[21818]: time="2026-08-31T22:59:36+02:00" level=debug msg="obtained new client token: AAHBYvrdt6UpyPVq57tiqMll544Y9/Vvav7SKWGO5Bgbfaer0hS+gvM0fPR40RNaEF8REc2ATzn5VKnNhSfTpURrSzGhMrFZoX/dAZq/+IQpeTWfjy2pKB69rrMOJObRJSgt7Px1yN1631pbFcpJpNvrQbzori0E+DFe90+LC0WKDnjCEUQUs8kvUnW0+ehn7kHEzvUEjuyjjNSvr4ENFbeV3IcB9wg+YHWO9WJEg3aTyzxpY1sdZQfbyZ8="
Aug 31 22:59:36 stefans-speaker go-librespot[21818]: time="2026-08-31T22:59:36+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Aug 31 22:59:36 stefans-speaker go-librespot[21818]: time="2026-08-31T22:59:36+02:00" level=debug msg="completed keyexchange"
Aug 31 22:59:36 stefans-speaker go-librespot[21818]: time="2026-08-31T22:59:36+02:00" level=debug msg="completed challenge"
Aug 31 22:59:37 stefans-speaker go-librespot[21818]: time="2026-08-31T22:59:37+02:00" level=info msg="authenticated AP" username="31************************cy"
Aug 31 22:59:37 stefans-speaker go-librespot[21818]: time="2026-08-31T22:59: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 22:59:37 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 31 22:59:37 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 31 22:59:37 stefans-speaker volumio[21436]: info: Connection to go-librespot Websocket closed
Aug 31 22:59:38 stefans-speaker volumio[21436]: info: Setting Geolocation for MyVolumio to eu5
Aug 31 22:59:38 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:59:38 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:59:38 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:59:38 stefans-speaker volumio[21436]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Aug 31 22:59:39 stefans-speaker volumio[21436]: info: Updating MyVolumio device info
Aug 31 22:59:39 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:59:39 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:59:39 stefans-speaker volumio[21436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 22:59:39 stefans-speaker volumio[21436]: info: Getting Spotify volume
Aug 31 22:59:39 stefans-speaker volumio[21436]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 31 22:59:39 stefans-speaker volumio[21436]: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 31 22:59:39 stefans-speaker volumio[21436]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Aug 31 22:59:39 stefans-speaker volumio[21436]: errno: -111,
Aug 31 22:59:39 stefans-speaker volumio[21436]: code: 'ECONNREFUSED',
Aug 31 22:59:39 stefans-speaker volumio[21436]: syscall: 'connect',
Aug 31 22:59:39 stefans-speaker volumio[21436]: address: '127.0.0.1',
Aug 31 22:59:39 stefans-speaker volumio[21436]: port: 9879,
Aug 31 22:59:39 stefans-speaker volumio[21436]: response: undefined
Aug 31 22:59:39 stefans-speaker volumio[21436]: }
Aug 31 22:59:39 stefans-speaker volumio[21436]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 31 22:59:40 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11.
Aug 31 22:59:40 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:59:40 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 31 22:59:40 stefans-speaker go-librespot[21853]: go-librespot daemon starting...
Aug 31 22:59:40 stefans-speaker go-librespot[21854]: time="2026-08-31T22:59:40+02:00" level=info msg="running go-librespot 0.4.0"
Aug 31 22:59:40 stefans-speaker go-librespot[21854]: time="2026-08-31T22:59:40+02:00" level=debug msg="app state loaded"
Aug 31 22:59:40 stefans-speaker go-librespot[21854]: time="2026-08-31T22:59:40+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 31 22:59:40 stefans-speaker sudo[21863]: volumio : unable to resolve host stefans-speaker: System error
Aug 31 22:59:40 stefans-speaker sudo[21863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-31 22:58'
Aug 31 22:59:40 stefans-speaker sudo[21863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 31 22:59:40 stefans-speaker go-librespot[21854]: time="2026-08-31T22:59:40+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Aug 31 22:59:40 stefans-speaker go-librespot[21854]: time="2026-08-31T22:59:40+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 22:59:40 stefans-speaker go-librespot[21854]: time="2026-08-31T22:59:40+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 22:59:40 stefans-speaker go-librespot[21854]: time="2026-08-31T22:59:40+02:00" level=info msg="zeroconf server listening on port 45227"
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"