Dec 06 18:39:00 volumio volumio[18234]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Dec 06 18:39:00 volumio volumio[18234]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Dec 06 18:39:00 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 18:39:00 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 18:39:00 volumio volumio[18234]: info: Starting MyVolumio Remote Streaming Endpoints
Dec 06 18:39:00 volumio volumio[18234]: info: MyVolumio login type: Token
Dec 06 18:39:00 volumio volumio[18234]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Dec 06 18:39:00 volumio volumio[18234]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Dec 06 18:39:01 volumio volumio[18234]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Dec 06 18:39:01 volumio volumio[18234]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Dec 06 18:39:01 volumio volumio[18234]: info: Streaming services startup
Dec 06 18:39:01 volumio volumio[18234]: info: Starting Streaming Daemon
Dec 06 18:39:01 volumio sudo[18724]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 06 18:39:01 volumio sudo[18724]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 06 18:39:01 volumio volumio[18234]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Dec 06 18:39:01 volumio sudo[18724]: pam_unix(sudo:session): session closed for user root
Dec 06 18:39:01 volumio volumio[18234]: info: Initializing connection to go-librespot Websocket
Dec 06 18:39:01 volumio volumio[18234]: error: Cannot start Volumio Streaming Daemon
Dec 06 18:39:01 volumio volumio[18234]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 06 18:39:01 volumio volumio[18234]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 06 18:39:01 volumio volumio[18234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 18:39:01 volumio volumio[18234]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Dec 06 18:39:02 volumio volumio[18234]: info: MyVolumio token set successfully
Dec 06 18:39:02 volumio volumio[18234]: info: MYVOLUMIO: Adding device
Dec 06 18:39:02 volumio volumio[18234]: info: MYVOLUMIO: Evaluating Server
Dec 06 18:39:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 105.
Dec 06 18:39:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:02 volumio go-librespot[18738]: go-librespot daemon starting...
Dec 06 18:39:02 volumio go-librespot[18745]: time="2025-12-06T18:39:02-06:00" level=info msg="running go-librespot 0.4.0"
Dec 06 18:39:02 volumio go-librespot[18745]: time="2025-12-06T18:39:02-06:00" level=debug msg="app state loaded"
Dec 06 18:39:02 volumio go-librespot[18745]: time="2025-12-06T18:39:02-06:00" level=debug msg="stored credentials not found"
Dec 06 18:39:02 volumio go-librespot[18745]: time="2025-12-06T18:39:02-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 18:39:02 volumio volumio[18234]: info: MyVolumio status changed
Dec 06 18:39:02 volumio volumio[18234]: info: Streaming services startup
Dec 06 18:39:02 volumio volumio[18234]: info: Starting Streaming Daemon
Dec 06 18:39:02 volumio volumio[18234]: info: Removing browser output: myVolumio user plan is not superstar
Dec 06 18:39:02 volumio volumio[18234]: info: Removing audio output:
Dec 06 18:39:02 volumio volumio[18234]: info: Stoppping Tunnel 1
Dec 06 18:39:02 volumio sudo[18760]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 06 18:39:02 volumio sudo[18760]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 06 18:39:02 volumio sudo[18762]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Dec 06 18:39:02 volumio sudo[18762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 06 18:39:02 volumio sudo[18760]: pam_unix(sudo:session): session closed for user root
Dec 06 18:39:02 volumio volumio[18234]: error: Cannot start Volumio Streaming Daemon
Dec 06 18:39:02 volumio volumio[18234]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 06 18:39:02 volumio volumio[18234]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 06 18:39:02 volumio 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.
Dec 06 18:39:02 volumio 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.
Dec 06 18:39:02 volumio 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.
Dec 06 18:39:02 volumio 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.
Dec 06 18:39:02 volumio 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.
Dec 06 18:39:02 volumio 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.
Dec 06 18:39:02 volumio 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.
Dec 06 18:39:02 volumio 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.
Dec 06 18:39:02 volumio sudo[18762]: pam_unix(sudo:session): session closed for user root
Dec 06 18:39:02 volumio volumio[18234]: info: Remote SSH Stopped
Dec 06 18:39:02 volumio volumio[18234]: info: Setting Geolocation for MyVolumio to us4
Dec 06 18:39:02 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 18:39:02 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 18:39:02 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 18:39:02 volumio go-librespot[18745]: time="2025-12-06T18:39:02-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 06 18:39:02 volumio go-librespot[18745]: time="2025-12-06T18:39:02-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 06 18:39:02 volumio go-librespot[18745]: time="2025-12-06T18:39:02-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 06 18:39:02 volumio go-librespot[18745]: time="2025-12-06T18:39:02-06:00" level=info msg="zeroconf server listening on port 42413"
Dec 06 18:39:02 volumio go-librespot[18745]: time="2025-12-06T18:39:02-06:00" level=debug msg="obtained new client token: AACzEFbDX9LEnXPVvMET9Y+ZO7RK0W1ly1eyZZsYR3fUuetPcIwllVo6dVyHMxa1Npa9MNJbvqWekTS6Tm/NZ3IqXbVurZdJxA0H2HhoeIgFIjRQ9tMJG+DelRlJYGbY1Ydl5PPpWzQRI15VQM0J82iVHl3LQcdR9I+DxNHeX66lTZ8nViLS2thVIEEecKeiq31OYZY5e1cEInLfJCvZd55SydTDhW5kYJ7rcQZnp98bG0OYZwklMBbL/A=="
Dec 06 18:39:02 volumio go-librespot[18745]: time="2025-12-06T18:39:02-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 06 18:39:02 volumio volumio[18234]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Dec 06 18:39:03 volumio go-librespot[18745]: time="2025-12-06T18:39:03-06:00" level=debug msg="completed keyexchange"
Dec 06 18:39:03 volumio go-librespot[18745]: time="2025-12-06T18:39:03-06:00" level=debug msg="completed challenge"
Dec 06 18:39:03 volumio go-librespot[18745]: time="2025-12-06T18:39:03-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 06 18:39:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 18:39:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 18:39:03 volumio volumio[18234]: info: Updating MyVolumio device info
Dec 06 18:39:03 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 18:39:03 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 18:39:03 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 18:39:03 volumio volumio[18234]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Dec 06 18:39:04 volumio volumio[18234]: info: Initializing connection to go-librespot Websocket
Dec 06 18:39:04 volumio volumio[18234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 18:39:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 106.
Dec 06 18:39:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:06 volumio go-librespot[18765]: go-librespot daemon starting...
Dec 06 18:39:06 volumio go-librespot[18766]: time="2025-12-06T18:39:06-06:00" level=info msg="running go-librespot 0.4.0"
Dec 06 18:39:06 volumio go-librespot[18766]: time="2025-12-06T18:39:06-06:00" level=debug msg="app state loaded"
Dec 06 18:39:06 volumio go-librespot[18766]: time="2025-12-06T18:39:06-06:00" level=debug msg="stored credentials not found"
Dec 06 18:39:06 volumio go-librespot[18766]: time="2025-12-06T18:39:06-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 18:39:06 volumio go-librespot[18766]: time="2025-12-06T18:39:06-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 06 18:39:06 volumio go-librespot[18766]: time="2025-12-06T18:39:06-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 06 18:39:06 volumio go-librespot[18766]: time="2025-12-06T18:39:06-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 06 18:39:06 volumio go-librespot[18766]: time="2025-12-06T18:39:06-06:00" level=info msg="zeroconf server listening on port 35711"
Dec 06 18:39:06 volumio go-librespot[18766]: time="2025-12-06T18:39:06-06:00" level=debug msg="obtained new client token: AACjEHW/iCuAEtWoQx6i1PO0KdDUc5Qnf6v2HJADJ4G1CRG5UMuy2qhuZvFHeU3tnE8h61EJiqHzsHuMp1Hq8CTUzu2GuVJNnD7IIjRr9mLLvFc1WOPSCyThg9yf4j7OQDslIZ+ZdtFJcKv6aHSCRwlSFKciSPkmqQtrlcy02kkSJoPZgwE/p1Xtlso+ashlREMfbFEtRxd5HltJ/BZnOuYjgb24UD3h3DddWwVbzJ+t7WUqBR5DzzXfYw=="
Dec 06 18:39:06 volumio go-librespot[18766]: time="2025-12-06T18:39:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 06 18:39:06 volumio go-librespot[18766]: time="2025-12-06T18:39:06-06:00" level=debug msg="completed keyexchange"
Dec 06 18:39:06 volumio go-librespot[18766]: time="2025-12-06T18:39:06-06:00" level=debug msg="completed challenge"
Dec 06 18:39:06 volumio go-librespot[18766]: time="2025-12-06T18:39:06-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 06 18:39:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 18:39:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 18:39:06 volumio volumio[18234]: info: MYVOLUMIO: Adding device
Dec 06 18:39:06 volumio volumio[18234]: info: MYVOLUMIO: Evaluating Server
Dec 06 18:39:07 volumio volumio[18234]: info: Setting Geolocation for MyVolumio to us1
Dec 06 18:39:07 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 18:39:07 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 18:39:07 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 18:39:07 volumio volumio[18234]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Dec 06 18:39:07 volumio volumio[18234]: info: Initializing connection to go-librespot Websocket
Dec 06 18:39:07 volumio volumio[18234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 18:39:08 volumio volumio[18234]: info: Updating MyVolumio device info
Dec 06 18:39:08 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 18:39:08 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 18:39:08 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 18:39:08 volumio volumio[18234]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Dec 06 18:39:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 107.
Dec 06 18:39:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:09 volumio go-librespot[18791]: go-librespot daemon starting...
Dec 06 18:39:10 volumio go-librespot[18800]: time="2025-12-06T18:39:10-06:00" level=info msg="running go-librespot 0.4.0"
Dec 06 18:39:10 volumio go-librespot[18800]: time="2025-12-06T18:39:10-06:00" level=debug msg="app state loaded"
Dec 06 18:39:10 volumio go-librespot[18800]: time="2025-12-06T18:39:10-06:00" level=debug msg="stored credentials not found"
Dec 06 18:39:10 volumio go-librespot[18800]: time="2025-12-06T18:39:10-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 18:39:10 volumio go-librespot[18800]: time="2025-12-06T18:39:10-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 06 18:39:10 volumio go-librespot[18800]: time="2025-12-06T18:39:10-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 06 18:39:10 volumio go-librespot[18800]: time="2025-12-06T18:39:10-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 06 18:39:10 volumio go-librespot[18800]: time="2025-12-06T18:39:10-06:00" level=info msg="zeroconf server listening on port 39509"
Dec 06 18:39:10 volumio go-librespot[18800]: time="2025-12-06T18:39:10-06:00" level=debug msg="obtained new client token: AACH5/r0VkGumUE4Zfb/bCb3vfFu/Mfu7Fw47dD5nvBr7y7EStLVbix+rpqozcStIx8k5+9gdn2NVQ+7P/P6piHYn21HUD92xMJ/G3sYVNZx9J6g21nlRg941jqV1ouSHtKKFSTQhLmj13WvwT6pyiUDfs/Z09+NfRtV4H5s03OV3DRu60qpULTXMNjF4njgl8auz+yYw6os8LjSzJUa6LZ/JUIl4Xf5wLEOmuRzxaUvslh07y+cMVLl2A=="
Dec 06 18:39:10 volumio go-librespot[18800]: time="2025-12-06T18:39:10-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 06 18:39:10 volumio go-librespot[18800]: time="2025-12-06T18:39:10-06:00" level=debug msg="completed keyexchange"
Dec 06 18:39:10 volumio go-librespot[18800]: time="2025-12-06T18:39:10-06:00" level=debug msg="completed challenge"
Dec 06 18:39:10 volumio go-librespot[18800]: time="2025-12-06T18:39:10-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 06 18:39:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 18:39:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 18:39:10 volumio volumio[18234]: info: Initializing connection to go-librespot Websocket
Dec 06 18:39:10 volumio volumio[18234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 18:39:12 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 06 18:39:12 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 18:39:12 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 06 18:39:12 volumio volumio-remote-updater[2353]: Test mode disabled
Dec 06 18:39:12 volumio volumio-remote-updater[2353]: Alpha mode disabled
Dec 06 18:39:12 volumio volumio-remote-updater[2353]: Alpha legacy test mode disabled
Dec 06 18:39:12 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Dec 06 18:39:13 volumio volumio[18234]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"ANNOUNCEMENT
\n\n- Production line
\n
\nFIX
\n\n- Nodejs 20.5.1 version pinning with static package fallback
\n
\n","title":"Update v4.073","updateavailable":true}
Dec 06 18:39:13 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Dec 06 18:39:13 volumio volumio[18234]: info: Initializing connection to go-librespot Websocket
Dec 06 18:39:13 volumio volumio[18234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 18:39:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 108.
Dec 06 18:39:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:13 volumio go-librespot[18820]: go-librespot daemon starting...
Dec 06 18:39:13 volumio go-librespot[18821]: time="2025-12-06T18:39:13-06:00" level=info msg="running go-librespot 0.4.0"
Dec 06 18:39:13 volumio go-librespot[18821]: time="2025-12-06T18:39:13-06:00" level=debug msg="app state loaded"
Dec 06 18:39:13 volumio go-librespot[18821]: time="2025-12-06T18:39:13-06:00" level=debug msg="stored credentials not found"
Dec 06 18:39:13 volumio go-librespot[18821]: time="2025-12-06T18:39:13-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 18:39:13 volumio go-librespot[18821]: time="2025-12-06T18:39:13-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 06 18:39:13 volumio go-librespot[18821]: time="2025-12-06T18:39:13-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 06 18:39:13 volumio go-librespot[18821]: time="2025-12-06T18:39:13-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 06 18:39:13 volumio go-librespot[18821]: time="2025-12-06T18:39:13-06:00" level=info msg="zeroconf server listening on port 35243"
Dec 06 18:39:14 volumio go-librespot[18821]: time="2025-12-06T18:39:14-06:00" level=debug msg="obtained new client token: AACCjAnkbxlUMRabo9CU5ziI9XA69yBiff4jOYs3/rHCf/UoT/yWPp4LFiilKsXbZfu0BuUmFOgTTIY0axvXq1VILmo7ZRk/fSJgC2SeHx6jF3A09rFOEU+iEgE874sKDdceOJmMf82WKZQN/Vrl4ACdhOYep13Yuz/qjOnedHyvECekaSKk0h3OUnlqdTETLtAs2fFeJFTp3Ckk6uCwjIEgVQje2YLPYtboh4sIB+Vj6n8XYd2xg6k="
Dec 06 18:39:14 volumio go-librespot[18821]: time="2025-12-06T18:39:14-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 06 18:39:14 volumio go-librespot[18821]: time="2025-12-06T18:39:14-06:00" level=debug msg="completed keyexchange"
Dec 06 18:39:14 volumio go-librespot[18821]: time="2025-12-06T18:39:14-06:00" level=debug msg="completed challenge"
Dec 06 18:39:14 volumio go-librespot[18821]: time="2025-12-06T18:39:14-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 06 18:39:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 18:39:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 18:39:15 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 06 18:39:15 volumio volumio[18234]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Dec 06 18:39:15 volumio volumio[18234]: info: CoreCommandRouter::volumioGetState
Dec 06 18:39:15 volumio volumio[18234]: info: CorePlayQueue::getTrack 0
Dec 06 18:39:16 volumio volumio[18234]: info: Initializing connection to go-librespot Websocket
Dec 06 18:39:16 volumio volumio[18234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 18:39:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 109.
Dec 06 18:39:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:17 volumio go-librespot[18828]: go-librespot daemon starting...
Dec 06 18:39:17 volumio go-librespot[18829]: time="2025-12-06T18:39:17-06:00" level=info msg="running go-librespot 0.4.0"
Dec 06 18:39:17 volumio go-librespot[18829]: time="2025-12-06T18:39:17-06:00" level=debug msg="app state loaded"
Dec 06 18:39:17 volumio go-librespot[18829]: time="2025-12-06T18:39:17-06:00" level=debug msg="stored credentials not found"
Dec 06 18:39:17 volumio go-librespot[18829]: time="2025-12-06T18:39:17-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 18:39:17 volumio go-librespot[18829]: time="2025-12-06T18:39:17-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 06 18:39:17 volumio go-librespot[18829]: time="2025-12-06T18:39:17-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 06 18:39:17 volumio go-librespot[18829]: time="2025-12-06T18:39:17-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 06 18:39:17 volumio go-librespot[18829]: time="2025-12-06T18:39:17-06:00" level=info msg="zeroconf server listening on port 42305"
Dec 06 18:39:17 volumio go-librespot[18829]: time="2025-12-06T18:39:17-06:00" level=debug msg="obtained new client token: AABAuKw20Zb/P0IqZ+PQ206qlChi6V07wLUXn20PSdSFhuWOCyPCAx5NDo3uVvXut3AbfbMU92hHpIaIKvGU/VOtIh9oPJIhzV3OZY25tatv1qa0Yn+roUu1inv3ZjVTfp24fsSyIi0fIHM4ISYUDK2rfe8umtLcYyUXhqyR8XZRLRJpk9cOesAXbXH0jmenn88LbdHIm84GC2nmECh1sXs90YyPmJSzzqt+/XXGWJv0qKCULEfqG9RT0g=="
Dec 06 18:39:17 volumio go-librespot[18829]: time="2025-12-06T18:39:17-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused"
Dec 06 18:39:17 volumio go-librespot[18829]: time="2025-12-06T18:39:17-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Dec 06 18:39:17 volumio go-librespot[18829]: time="2025-12-06T18:39:17-06:00" level=debug msg="completed keyexchange"
Dec 06 18:39:17 volumio go-librespot[18829]: time="2025-12-06T18:39:17-06:00" level=debug msg="completed challenge"
Dec 06 18:39:18 volumio go-librespot[18829]: time="2025-12-06T18:39:18-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 06 18:39:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 18:39:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 18:39:19 volumio volumio[18234]: info: Initializing connection to go-librespot Websocket
Dec 06 18:39:19 volumio volumio[18234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 18:39:20 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 18:39:20 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 18:39:20 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Dec 06 18:39:20 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Dec 06 18:39:20 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Dec 06 18:39:20 volumio volumio[18234]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 06 18:39:20 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 06 18:39:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 110.
Dec 06 18:39:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:21 volumio go-librespot[18851]: go-librespot daemon starting...
Dec 06 18:39:21 volumio go-librespot[18852]: time="2025-12-06T18:39:21-06:00" level=info msg="running go-librespot 0.4.0"
Dec 06 18:39:21 volumio go-librespot[18852]: time="2025-12-06T18:39:21-06:00" level=debug msg="app state loaded"
Dec 06 18:39:21 volumio go-librespot[18852]: time="2025-12-06T18:39:21-06:00" level=debug msg="stored credentials not found"
Dec 06 18:39:21 volumio go-librespot[18852]: time="2025-12-06T18:39:21-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 18:39:21 volumio volumio[18234]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Dec 06 18:39:21 volumio volumio[18234]: info: Completed starting MyVolumio Plugin
Dec 06 18:39:21 volumio volumio[18234]: [Metrics] CommandRouter: 53s 825.29ms
Dec 06 18:39:21 volumio volumio[18234]: info: CoreCommandRouter::volumiosetStartupVolume
Dec 06 18:39:21 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 18:39:21 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 18:39:21 volumio volumio[18234]: info: CoreCommandRouter::Close All Modals sent
Dec 06 18:39:21 volumio volumio[18234]: info: CoreCommandRouter::Close All Modals sent
Dec 06 18:39:21 volumio go-librespot[18852]: time="2025-12-06T18:39:21-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 06 18:39:21 volumio go-librespot[18852]: time="2025-12-06T18:39:21-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 06 18:39:21 volumio go-librespot[18852]: time="2025-12-06T18:39:21-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 06 18:39:21 volumio go-librespot[18852]: time="2025-12-06T18:39:21-06:00" level=info msg="zeroconf server listening on port 43333"
Dec 06 18:39:21 volumio go-librespot[18852]: time="2025-12-06T18:39:21-06:00" level=debug msg="obtained new client token: AACMMnSd4J9rPD0+Y32Z8J/K3veTqtlbGTgM6SerUEEBoTQVa+TtHpDerjSlNvw5toE7RY+ACQ2fXiUlATck93D0+nphkCwfL1p+4xOR1s+ABjLUiF2xRcPNlLVBJdtaP6RJcpBfJKsLw+q0T8C3NylWewOAI4k3p83Dkz19NjU01mkRbVSJJYKnqoShmu+4t8i9/Rh6WK20NsB+xXnhBno8qPOw1o6p3055RKpiBsHfpU/nNkzPby/Q8w=="
Dec 06 18:39:21 volumio go-librespot[18852]: time="2025-12-06T18:39:21-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 06 18:39:21 volumio go-librespot[18852]: time="2025-12-06T18:39:21-06:00" level=debug msg="completed keyexchange"
Dec 06 18:39:21 volumio go-librespot[18852]: time="2025-12-06T18:39:21-06:00" level=debug msg="completed challenge"
Dec 06 18:39:21 volumio go-librespot[18852]: time="2025-12-06T18:39:21-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 06 18:39:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 18:39:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 18:39:22 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Dec 06 18:39:22 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Dec 06 18:39:22 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Dec 06 18:39:22 volumio volumio[18234]: info: Initializing connection to go-librespot Websocket
Dec 06 18:39:22 volumio volumio[18234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 18:39:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 111.
Dec 06 18:39:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:24 volumio go-librespot[18865]: go-librespot daemon starting...
Dec 06 18:39:25 volumio go-librespot[18866]: time="2025-12-06T18:39:25-06:00" level=info msg="running go-librespot 0.4.0"
Dec 06 18:39:25 volumio go-librespot[18866]: time="2025-12-06T18:39:25-06:00" level=debug msg="app state loaded"
Dec 06 18:39:25 volumio go-librespot[18866]: time="2025-12-06T18:39:25-06:00" level=debug msg="stored credentials not found"
Dec 06 18:39:25 volumio go-librespot[18866]: time="2025-12-06T18:39:25-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 18:39:25 volumio go-librespot[18866]: time="2025-12-06T18:39:25-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 06 18:39:25 volumio go-librespot[18866]: time="2025-12-06T18:39:25-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 06 18:39:25 volumio go-librespot[18866]: time="2025-12-06T18:39:25-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 06 18:39:25 volumio go-librespot[18866]: time="2025-12-06T18:39:25-06:00" level=info msg="zeroconf server listening on port 37757"
Dec 06 18:39:25 volumio go-librespot[18866]: time="2025-12-06T18:39:25-06:00" level=debug msg="obtained new client token: AABktjoyDtnKpidv5u7pzbnFMwt5U01ScxFlkbqnzRaJhn/dkdzhWktIqLJoDlXdINj51FUcVmm73q8KbZvOgWyM0FIleUPmh6r/PLGF7jMkf41ZO1vKNdVAArEKuh/PEVZ87ROgYzTtFOgudbaBpQLcc6bA7HFxuO9yycH56RyHvTtNRjWJPJ9qKX2sBBAT7OGleBUZtg383Fz8+anxC4Y1KUB1/2XBzoJBQJgeNFysoeB+SWkxvoePRQ=="
Dec 06 18:39:25 volumio go-librespot[18866]: time="2025-12-06T18:39:25-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 06 18:39:25 volumio go-librespot[18866]: time="2025-12-06T18:39:25-06:00" level=debug msg="completed keyexchange"
Dec 06 18:39:25 volumio go-librespot[18866]: time="2025-12-06T18:39:25-06:00" level=debug msg="completed challenge"
Dec 06 18:39:25 volumio go-librespot[18866]: time="2025-12-06T18:39:25-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 06 18:39:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 18:39:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 18:39:25 volumio volumio[18234]: info: Initializing connection to go-librespot Websocket
Dec 06 18:39:25 volumio volumio[18234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 18:39:28 volumio volumio[18234]: info: BOOT COMPLETED
Dec 06 18:39:28 volumio volumio[18234]: info: Initializing connection to go-librespot Websocket
Dec 06 18:39:28 volumio volumio[18234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 18:39:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 112.
Dec 06 18:39:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:28 volumio go-librespot[18873]: go-librespot daemon starting...
Dec 06 18:39:28 volumio go-librespot[18874]: time="2025-12-06T18:39:28-06:00" level=info msg="running go-librespot 0.4.0"
Dec 06 18:39:28 volumio go-librespot[18874]: time="2025-12-06T18:39:28-06:00" level=debug msg="app state loaded"
Dec 06 18:39:28 volumio go-librespot[18874]: time="2025-12-06T18:39:28-06:00" level=debug msg="stored credentials not found"
Dec 06 18:39:28 volumio go-librespot[18874]: time="2025-12-06T18:39:28-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 18:39:29 volumio go-librespot[18874]: time="2025-12-06T18:39:29-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 06 18:39:29 volumio go-librespot[18874]: time="2025-12-06T18:39:29-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 06 18:39:29 volumio go-librespot[18874]: time="2025-12-06T18:39:29-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 06 18:39:29 volumio go-librespot[18874]: time="2025-12-06T18:39:29-06:00" level=info msg="zeroconf server listening on port 42205"
Dec 06 18:39:29 volumio go-librespot[18874]: time="2025-12-06T18:39:29-06:00" level=debug msg="obtained new client token: AAD/JFGElTsKGaeHZLx8iXz8HaZ/OEAtbWyD1qKwFiuEKgodAWzQyPDwC/Ir9//QfvbeaQyUO61OYLkSXnpYPmVVQWtX56XIGUM5cJNzdyuQi9lM4ej9KCup40CIeQqkanFb1OWnt8yUcYOBHOXaqxxXOOWyPpqGCnVMUTS4OL+1F++yr4z8vgp6MbrmmKbeG3O0qFkkMphjr4oDFlmVOeqMbRpycSra2sHNDqU1oudFYY34t45yNHY="
Dec 06 18:39:29 volumio go-librespot[18874]: time="2025-12-06T18:39:29-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 06 18:39:29 volumio go-librespot[18874]: time="2025-12-06T18:39:29-06:00" level=debug msg="completed keyexchange"
Dec 06 18:39:29 volumio go-librespot[18874]: time="2025-12-06T18:39:29-06:00" level=debug msg="completed challenge"
Dec 06 18:39:29 volumio go-librespot[18874]: time="2025-12-06T18:39:29-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 06 18:39:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 18:39:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 18:39:31 volumio volumio[18234]: info: Initializing connection to go-librespot Websocket
Dec 06 18:39:31 volumio volumio[18234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 18:39:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 113.
Dec 06 18:39:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:32 volumio go-librespot[18898]: go-librespot daemon starting...
Dec 06 18:39:32 volumio go-librespot[18899]: time="2025-12-06T18:39:32-06:00" level=info msg="running go-librespot 0.4.0"
Dec 06 18:39:32 volumio go-librespot[18899]: time="2025-12-06T18:39:32-06:00" level=debug msg="app state loaded"
Dec 06 18:39:32 volumio go-librespot[18899]: time="2025-12-06T18:39:32-06:00" level=debug msg="stored credentials not found"
Dec 06 18:39:32 volumio go-librespot[18899]: time="2025-12-06T18:39:32-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 18:39:32 volumio go-librespot[18899]: time="2025-12-06T18:39:32-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 06 18:39:32 volumio go-librespot[18899]: time="2025-12-06T18:39:32-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 06 18:39:32 volumio go-librespot[18899]: time="2025-12-06T18:39:32-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 06 18:39:32 volumio go-librespot[18899]: time="2025-12-06T18:39:32-06:00" level=info msg="zeroconf server listening on port 37999"
Dec 06 18:39:32 volumio go-librespot[18899]: time="2025-12-06T18:39:32-06:00" level=debug msg="obtained new client token: AADgQUJ/oqfbE8CQojVkShGDQDbja6kmNNty3+1VdmrShMvGpoRDhMuMnd6Q5YblRN+36A53Dn7KfuzjCTsIs5Sgmj65FUSkpPpnNHyOIY44lAbL6BwG2e8XfosuKO6FAzKPZe2saDKM8IBI0tl6RCyLYCd7e2Q3/e2K+x9IqYi0BVoGgQ9sKPJwMQRAHhQtFgdC7VXoc5OML5unFnLnJUjr649mSQMfCKO1mrchQ0Dpt++hlpGo+b75iQ=="
Dec 06 18:39:32 volumio go-librespot[18899]: time="2025-12-06T18:39:32-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 06 18:39:32 volumio go-librespot[18899]: time="2025-12-06T18:39:32-06:00" level=debug msg="completed keyexchange"
Dec 06 18:39:32 volumio go-librespot[18899]: time="2025-12-06T18:39:32-06:00" level=debug msg="completed challenge"
Dec 06 18:39:32 volumio go-librespot[18899]: time="2025-12-06T18:39:32-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 06 18:39:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 18:39:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 18:39:34 volumio volumio[18234]: info: Initializing connection to go-librespot Websocket
Dec 06 18:39:34 volumio volumio[18234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 18:39:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 114.
Dec 06 18:39:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:36 volumio go-librespot[18906]: go-librespot daemon starting...
Dec 06 18:39:36 volumio go-librespot[18907]: time="2025-12-06T18:39:36-06:00" level=info msg="running go-librespot 0.4.0"
Dec 06 18:39:36 volumio go-librespot[18907]: time="2025-12-06T18:39:36-06:00" level=debug msg="app state loaded"
Dec 06 18:39:36 volumio go-librespot[18907]: time="2025-12-06T18:39:36-06:00" level=debug msg="stored credentials not found"
Dec 06 18:39:36 volumio go-librespot[18907]: time="2025-12-06T18:39:36-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 18:39:36 volumio go-librespot[18907]: time="2025-12-06T18:39:36-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 06 18:39:36 volumio go-librespot[18907]: time="2025-12-06T18:39:36-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 06 18:39:36 volumio go-librespot[18907]: time="2025-12-06T18:39:36-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 06 18:39:36 volumio go-librespot[18907]: time="2025-12-06T18:39:36-06:00" level=info msg="zeroconf server listening on port 37167"
Dec 06 18:39:36 volumio go-librespot[18907]: time="2025-12-06T18:39:36-06:00" level=debug msg="obtained new client token: AAAzXSbFj/zAsLwUerKbloAAr8kJh/dhS7GA4vutCbxOftdAEXicedhaoxpuIobWQQK9NPNv803WTiP3Kjn628X8RXQyKt/ZLKDOWzMWCxUa7FhdUhDxDX/5d+W50vs2AfFYD0vytnpofkGT94SRY3dPv0+Oj99Ae5eMDWNFNI4JEtCRitIkbaj+YuSBTfFUwHSbm/JbLr2Mbj2Rjomhsasm+WUgQp0ynvpXdeJHW0nlN4hlfOkJnX86qw=="
Dec 06 18:39:36 volumio go-librespot[18907]: time="2025-12-06T18:39:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 06 18:39:36 volumio go-librespot[18907]: time="2025-12-06T18:39:36-06:00" level=debug msg="completed keyexchange"
Dec 06 18:39:36 volumio go-librespot[18907]: time="2025-12-06T18:39:36-06:00" level=debug msg="completed challenge"
Dec 06 18:39:36 volumio go-librespot[18907]: time="2025-12-06T18:39:36-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 06 18:39:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 18:39:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 18:39:37 volumio volumio[18234]: info: Initializing connection to go-librespot Websocket
Dec 06 18:39:37 volumio volumio[18234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 18:39:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 115.
Dec 06 18:39:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:39 volumio go-librespot[18914]: go-librespot daemon starting...
Dec 06 18:39:40 volumio go-librespot[18915]: time="2025-12-06T18:39:40-06:00" level=info msg="running go-librespot 0.4.0"
Dec 06 18:39:40 volumio go-librespot[18915]: time="2025-12-06T18:39:40-06:00" level=debug msg="app state loaded"
Dec 06 18:39:40 volumio go-librespot[18915]: time="2025-12-06T18:39:40-06:00" level=debug msg="stored credentials not found"
Dec 06 18:39:40 volumio go-librespot[18915]: time="2025-12-06T18:39:40-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 18:39:40 volumio go-librespot[18915]: time="2025-12-06T18:39:40-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 06 18:39:40 volumio go-librespot[18915]: time="2025-12-06T18:39:40-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 06 18:39:40 volumio go-librespot[18915]: time="2025-12-06T18:39:40-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 06 18:39:40 volumio go-librespot[18915]: time="2025-12-06T18:39:40-06:00" level=info msg="zeroconf server listening on port 40903"
Dec 06 18:39:40 volumio go-librespot[18915]: time="2025-12-06T18:39:40-06:00" level=debug msg="obtained new client token: AAD5gdgqxig5+djckw3JqLOALF0SVt/WO0EXbCVl6M2szAlPmqq/jm0svSH7By2xMrQai3okMp4NGTWVejm8+PJSmFXeD7Yc8mgu1lTpxtut9gXEhtTwadJFe8u2dHl2XvhhcosrLx6ScxPqrcoB0cHQ1KQCOoGjmiiCT+c+H49rW80pD1hLynC2y0CnUJG/aG3Snyxu5l9vwRpmazKFtYjx+cdta0XBuJ8zACyG1VyZVptBLIJkaiq5ug=="
Dec 06 18:39:40 volumio go-librespot[18915]: time="2025-12-06T18:39:40-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 06 18:39:40 volumio go-librespot[18915]: time="2025-12-06T18:39:40-06:00" level=debug msg="completed keyexchange"
Dec 06 18:39:40 volumio go-librespot[18915]: time="2025-12-06T18:39:40-06:00" level=debug msg="completed challenge"
Dec 06 18:39:40 volumio go-librespot[18915]: time="2025-12-06T18:39:40-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 06 18:39:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 18:39:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 18:39:40 volumio volumio[18234]: info: Initializing connection to go-librespot Websocket
Dec 06 18:39:40 volumio volumio[18234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 18:39:42 volumio volumio[18234]: info: UPDATER: Scheduling automatic update
Dec 06 18:39:42 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Dec 06 18:39:42 volumio volumio[18234]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Dec 06 18:39:42 volumio volumio[18234]: info: UPDATER: Auto update will take place at: Sun Dec 07 2025 03:32:12 GMT-0600 (Central Standard Time)
Dec 06 18:39:43 volumio volumio[18234]: info: Initializing connection to go-librespot Websocket
Dec 06 18:39:43 volumio volumio[18234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 18:39:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 116.
Dec 06 18:39:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:43 volumio go-librespot[18937]: go-librespot daemon starting...
Dec 06 18:39:43 volumio go-librespot[18938]: time="2025-12-06T18:39:43-06:00" level=info msg="running go-librespot 0.4.0"
Dec 06 18:39:43 volumio go-librespot[18938]: time="2025-12-06T18:39:43-06:00" level=debug msg="app state loaded"
Dec 06 18:39:43 volumio go-librespot[18938]: time="2025-12-06T18:39:43-06:00" level=debug msg="stored credentials not found"
Dec 06 18:39:43 volumio go-librespot[18938]: time="2025-12-06T18:39:43-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 18:39:43 volumio go-librespot[18938]: time="2025-12-06T18:39:43-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 06 18:39:43 volumio go-librespot[18938]: time="2025-12-06T18:39:43-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 06 18:39:43 volumio go-librespot[18938]: time="2025-12-06T18:39:43-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 06 18:39:44 volumio go-librespot[18938]: time="2025-12-06T18:39:44-06:00" level=info msg="zeroconf server listening on port 37829"
Dec 06 18:39:44 volumio go-librespot[18938]: time="2025-12-06T18:39:44-06:00" level=debug msg="obtained new client token: AABwxye4ofehQeDUcWlkvxc5uTXZd7olyyGLTRkFxXsTs6A5KWwdlIcfIEc1NDFTOlu7jhk58YzBUNxjQiX51VyHe/XFJ4sZOqpjDGq3eXYj8etiWudXxg1zwRfBMiA8A4ru6tIMZn71X3Wlhft7toXX/1rg74EH2ARktXDkWc9eQTXEf/EMl5cbBvGMgLMhR1NA5A0j/janEgCjrbmoFUixMnNstqB3aQtkyUcQXb478wysz9Shgr0="
Dec 06 18:39:44 volumio go-librespot[18938]: time="2025-12-06T18:39:44-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 06 18:39:44 volumio go-librespot[18938]: time="2025-12-06T18:39:44-06:00" level=debug msg="completed keyexchange"
Dec 06 18:39:44 volumio go-librespot[18938]: time="2025-12-06T18:39:44-06:00" level=debug msg="completed challenge"
Dec 06 18:39:44 volumio go-librespot[18938]: time="2025-12-06T18:39:44-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 06 18:39:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 18:39:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 18:39:46 volumio volumio[18234]: info: Initializing connection to go-librespot Websocket
Dec 06 18:39:46 volumio volumio[18234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 18:39:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117.
Dec 06 18:39:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:47 volumio go-librespot[18945]: go-librespot daemon starting...
Dec 06 18:39:47 volumio go-librespot[18946]: time="2025-12-06T18:39:47-06:00" level=info msg="running go-librespot 0.4.0"
Dec 06 18:39:47 volumio go-librespot[18946]: time="2025-12-06T18:39:47-06:00" level=debug msg="app state loaded"
Dec 06 18:39:47 volumio go-librespot[18946]: time="2025-12-06T18:39:47-06:00" level=debug msg="stored credentials not found"
Dec 06 18:39:47 volumio go-librespot[18946]: time="2025-12-06T18:39:47-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 18:39:47 volumio go-librespot[18946]: time="2025-12-06T18:39:47-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 06 18:39:47 volumio go-librespot[18946]: time="2025-12-06T18:39:47-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 06 18:39:47 volumio go-librespot[18946]: time="2025-12-06T18:39:47-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 06 18:39:47 volumio go-librespot[18946]: time="2025-12-06T18:39:47-06:00" level=info msg="zeroconf server listening on port 39279"
Dec 06 18:39:47 volumio go-librespot[18946]: time="2025-12-06T18:39:47-06:00" level=debug msg="obtained new client token: AAAq2jzWo4pMskx5rdehhhir60O5YP1Nwo6boxwnjphh8N+Tz9o92U3vEAwsFABXnBvPqcyBNeKeOWPM56F9A7Gd1EPm4ojuDwm6X6qeMZpXKjn+U5ISsLJ9xRer/nyDdb0XkTKnSK73VpVpMVOFx/JhwjT6JFDqS2WE12us82l1TKj9Ga5Aw0+IQvupkruJPZ+BLSgvuZzl8tid3Qs9oNIIGv5eYVbE28r7Cr7shZVLLmi+lpl+U7CGjg=="
Dec 06 18:39:47 volumio go-librespot[18946]: time="2025-12-06T18:39:47-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused"
Dec 06 18:39:47 volumio go-librespot[18946]: time="2025-12-06T18:39:47-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Dec 06 18:39:47 volumio go-librespot[18946]: time="2025-12-06T18:39:47-06:00" level=debug msg="completed keyexchange"
Dec 06 18:39:47 volumio go-librespot[18946]: time="2025-12-06T18:39:47-06:00" level=debug msg="completed challenge"
Dec 06 18:39:48 volumio go-librespot[18946]: time="2025-12-06T18:39:48-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 06 18:39:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 18:39:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 18:39:49 volumio volumio[18234]: info: Initializing connection to go-librespot Websocket
Dec 06 18:39:49 volumio volumio[18234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 18:39:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 118.
Dec 06 18:39:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:51 volumio go-librespot[18967]: go-librespot daemon starting...
Dec 06 18:39:51 volumio go-librespot[18968]: time="2025-12-06T18:39:51-06:00" level=info msg="running go-librespot 0.4.0"
Dec 06 18:39:51 volumio go-librespot[18968]: time="2025-12-06T18:39:51-06:00" level=debug msg="app state loaded"
Dec 06 18:39:51 volumio go-librespot[18968]: time="2025-12-06T18:39:51-06:00" level=debug msg="stored credentials not found"
Dec 06 18:39:51 volumio go-librespot[18968]: time="2025-12-06T18:39:51-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 18:39:51 volumio go-librespot[18968]: time="2025-12-06T18:39:51-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 06 18:39:51 volumio go-librespot[18968]: time="2025-12-06T18:39:51-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 06 18:39:51 volumio go-librespot[18968]: time="2025-12-06T18:39:51-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 06 18:39:51 volumio go-librespot[18968]: time="2025-12-06T18:39:51-06:00" level=info msg="zeroconf server listening on port 42573"
Dec 06 18:39:51 volumio go-librespot[18968]: time="2025-12-06T18:39:51-06:00" level=debug msg="obtained new client token: AADfHVRtzuua4Dprfg93aUPwxBcMh5g5dL3iFEIBmT9mH64tOv6V9n6F50Ih9ueAlPbZhagQwKQ9nn8fx6VxYbxiF9J3xrM2zR1gb/TMmZc3pif9+zvLAA/PKRL7zqhKVFz4xoakuQaa55l7DnM7uIc18U1a3rMyQX2PIV+53tebusDMVVI1IY5pYKi1JKjy9XzJsPV6XeLVesKrHZXVjuGe5lbNHP0uuodzC+FT+HcnJ+JwLMWOlheL5A=="
Dec 06 18:39:51 volumio go-librespot[18968]: time="2025-12-06T18:39:51-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 06 18:39:51 volumio go-librespot[18968]: time="2025-12-06T18:39:51-06:00" level=debug msg="completed keyexchange"
Dec 06 18:39:51 volumio go-librespot[18968]: time="2025-12-06T18:39:51-06:00" level=debug msg="completed challenge"
Dec 06 18:39:51 volumio go-librespot[18968]: time="2025-12-06T18:39:51-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 06 18:39:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 18:39:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 18:39:52 volumio volumio[18234]: info: Initializing connection to go-librespot Websocket
Dec 06 18:39:52 volumio volumio[18234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 18:39:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 119.
Dec 06 18:39:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:54 volumio go-librespot[18979]: go-librespot daemon starting...
Dec 06 18:39:55 volumio go-librespot[18980]: time="2025-12-06T18:39:55-06:00" level=info msg="running go-librespot 0.4.0"
Dec 06 18:39:55 volumio go-librespot[18980]: time="2025-12-06T18:39:55-06:00" level=debug msg="app state loaded"
Dec 06 18:39:55 volumio go-librespot[18980]: time="2025-12-06T18:39:55-06:00" level=debug msg="stored credentials not found"
Dec 06 18:39:55 volumio go-librespot[18980]: time="2025-12-06T18:39:55-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 18:39:55 volumio go-librespot[18980]: time="2025-12-06T18:39:55-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 06 18:39:55 volumio go-librespot[18980]: time="2025-12-06T18:39:55-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 06 18:39:55 volumio go-librespot[18980]: time="2025-12-06T18:39:55-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 06 18:39:55 volumio go-librespot[18980]: time="2025-12-06T18:39:55-06:00" level=info msg="zeroconf server listening on port 44179"
Dec 06 18:39:55 volumio go-librespot[18980]: time="2025-12-06T18:39:55-06:00" level=debug msg="obtained new client token: AABMY7G+Q5mj0tSOjUBUBMQqWl4BVQXqbHmquu7g6GGj7YQRwxwCdwEMBHcczlbD/zrIixQi4Pi1npO8wYeqJnOefJB+jGZT2Z6++2QcShGsRGtGsclBZ2i9fTTf+Hi8g93pc66f/f26TVmYqnqAa7Us2Obfg6oMRrBdSas7GYZ9d4/T9gDr8AxcLydHYQucLbDtRvO8ccUhmThMcx+4DxypsA+5/JA9RgKXKXiDM4yseae+RWT93TlABQ=="
Dec 06 18:39:55 volumio go-librespot[18980]: time="2025-12-06T18:39:55-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 06 18:39:55 volumio go-librespot[18980]: time="2025-12-06T18:39:55-06:00" level=debug msg="completed keyexchange"
Dec 06 18:39:55 volumio go-librespot[18980]: time="2025-12-06T18:39:55-06:00" level=debug msg="completed challenge"
Dec 06 18:39:55 volumio go-librespot[18980]: time="2025-12-06T18:39:55-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 06 18:39:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 18:39:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 18:39:55 volumio volumio[18234]: info: Initializing connection to go-librespot Websocket
Dec 06 18:39:55 volumio volumio[18234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 18:39:58 volumio volumio[18234]: info: Initializing connection to go-librespot Websocket
Dec 06 18:39:58 volumio volumio[18234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 18:39:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 120.
Dec 06 18:39:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:39:58 volumio go-librespot[18987]: go-librespot daemon starting...
Dec 06 18:39:58 volumio go-librespot[18988]: time="2025-12-06T18:39:58-06:00" level=info msg="running go-librespot 0.4.0"
Dec 06 18:39:58 volumio go-librespot[18988]: time="2025-12-06T18:39:58-06:00" level=debug msg="app state loaded"
Dec 06 18:39:58 volumio go-librespot[18988]: time="2025-12-06T18:39:58-06:00" level=debug msg="stored credentials not found"
Dec 06 18:39:58 volumio go-librespot[18988]: time="2025-12-06T18:39:58-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 18:39:58 volumio go-librespot[18988]: time="2025-12-06T18:39:58-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 06 18:39:58 volumio go-librespot[18988]: time="2025-12-06T18:39:58-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 06 18:39:58 volumio go-librespot[18988]: time="2025-12-06T18:39:58-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 06 18:39:58 volumio go-librespot[18988]: time="2025-12-06T18:39:58-06:00" level=info msg="zeroconf server listening on port 42715"
Dec 06 18:39:59 volumio go-librespot[18988]: time="2025-12-06T18:39:59-06:00" level=debug msg="obtained new client token: AADpn071zygzBKqGJQ3HdZtg+3YmZhcvLjn+rRb8hF3K8owaCPtDaFdC+0OK8AS8a+ewTc4POd5H8xb8Di1pRQvXAX+ZZu8CZVke9S9aHRr46skGbP2O//3Gvr14zrMUmTbjP9WDS04sK8v+10a4is1DRFsio2C188Yo9IqvTskVqWUskNvirMcABjPvpJrTBjYzQV9ZWwRqVfbY0bUU8gN2Se7ZHDIZaIX1jTieFMv0q0jX61pUOek="
Dec 06 18:39:59 volumio go-librespot[18988]: time="2025-12-06T18:39:59-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 06 18:39:59 volumio go-librespot[18988]: time="2025-12-06T18:39:59-06:00" level=debug msg="completed keyexchange"
Dec 06 18:39:59 volumio go-librespot[18988]: time="2025-12-06T18:39:59-06:00" level=debug msg="completed challenge"
Dec 06 18:39:59 volumio go-librespot[18988]: time="2025-12-06T18:39:59-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 06 18:39:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 18:39:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 18:40:01 volumio volumio[18234]: info: Initializing connection to go-librespot Websocket
Dec 06 18:40:01 volumio volumio[18234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 18:40:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 121.
Dec 06 18:40:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:40:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:40:02 volumio go-librespot[19010]: go-librespot daemon starting...
Dec 06 18:40:02 volumio go-librespot[19011]: time="2025-12-06T18:40:02-06:00" level=info msg="running go-librespot 0.4.0"
Dec 06 18:40:02 volumio go-librespot[19011]: time="2025-12-06T18:40:02-06:00" level=debug msg="app state loaded"
Dec 06 18:40:02 volumio go-librespot[19011]: time="2025-12-06T18:40:02-06:00" level=debug msg="stored credentials not found"
Dec 06 18:40:02 volumio go-librespot[19011]: time="2025-12-06T18:40:02-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 18:40:02 volumio go-librespot[19011]: time="2025-12-06T18:40:02-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 06 18:40:02 volumio go-librespot[19011]: time="2025-12-06T18:40:02-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 06 18:40:02 volumio go-librespot[19011]: time="2025-12-06T18:40:02-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 06 18:40:02 volumio go-librespot[19011]: time="2025-12-06T18:40:02-06:00" level=info msg="zeroconf server listening on port 40029"
Dec 06 18:40:02 volumio go-librespot[19011]: time="2025-12-06T18:40:02-06:00" level=debug msg="obtained new client token: AAAW106Ww2EJgVESR1ZpjQGWU5pybIMH6lqrWRUcZ7XNXXEXhx8E2kdSjcJOOJZ/aqHspoannfx9jQUfjTcEEY1peGXd16oZfVyMfKNDYiS1Cgp96OKz1w/A6p23mMBklYa1/3Nua/+qwtuTpXM0ZVybo63a1fu3ffxlc2Kh60ZmuXEAU91JT+8B7ceW+yj5G8iERaZnIoJjdl38/aViDJl8cHKHmsI+fuiGX36OByZqNwEtg8lGa1XhJA=="
Dec 06 18:40:02 volumio go-librespot[19011]: time="2025-12-06T18:40:02-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 06 18:40:02 volumio go-librespot[19011]: time="2025-12-06T18:40:02-06:00" level=debug msg="completed keyexchange"
Dec 06 18:40:02 volumio go-librespot[19011]: time="2025-12-06T18:40:02-06:00" level=debug msg="completed challenge"
Dec 06 18:40:02 volumio go-librespot[19011]: time="2025-12-06T18:40:02-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 06 18:40:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 18:40:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 18:40:04 volumio volumio[18234]: info: Initializing connection to go-librespot Websocket
Dec 06 18:40:04 volumio volumio[18234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 18:40:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 122.
Dec 06 18:40:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:40:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:40:06 volumio go-librespot[19019]: go-librespot daemon starting...
Dec 06 18:40:06 volumio go-librespot[19020]: time="2025-12-06T18:40:06-06:00" level=info msg="running go-librespot 0.4.0"
Dec 06 18:40:06 volumio go-librespot[19020]: time="2025-12-06T18:40:06-06:00" level=debug msg="app state loaded"
Dec 06 18:40:06 volumio go-librespot[19020]: time="2025-12-06T18:40:06-06:00" level=debug msg="stored credentials not found"
Dec 06 18:40:06 volumio go-librespot[19020]: time="2025-12-06T18:40:06-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 18:40:06 volumio go-librespot[19020]: time="2025-12-06T18:40:06-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 06 18:40:06 volumio go-librespot[19020]: time="2025-12-06T18:40:06-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 06 18:40:06 volumio go-librespot[19020]: time="2025-12-06T18:40:06-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 06 18:40:06 volumio go-librespot[19020]: time="2025-12-06T18:40:06-06:00" level=info msg="zeroconf server listening on port 37201"
Dec 06 18:40:06 volumio go-librespot[19020]: time="2025-12-06T18:40:06-06:00" level=debug msg="obtained new client token: AABDO/vLOtt48wlj+dBlXTKaFPBqJ5KicHgD0+8SrFni7NXveCf/1SO49pfaRhc+jRpqgqPWIjyNl/DqBI+3pXB2+cHDb3sIYo+03BMyde+PM5TgoX/0NrIVOlw9sP+hIn+21ULlVZHQu06vr6d7g0OhgQPV7FpsVjREaAu8WtXFOMGfktTe+GgAYB54YLyW7A82z6Fd2DfN4wlkmmeanYLR+EL+oVt3/NvsGmJ/ByXv38XgNjvzVKGd5w=="
Dec 06 18:40:06 volumio go-librespot[19020]: time="2025-12-06T18:40:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 06 18:40:06 volumio go-librespot[19020]: time="2025-12-06T18:40:06-06:00" level=debug msg="completed keyexchange"
Dec 06 18:40:06 volumio go-librespot[19020]: time="2025-12-06T18:40:06-06:00" level=debug msg="completed challenge"
Dec 06 18:40:06 volumio go-librespot[19020]: time="2025-12-06T18:40:06-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 06 18:40:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 18:40:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 18:40:07 volumio volumio[18234]: info: Initializing connection to go-librespot Websocket
Dec 06 18:40:07 volumio volumio[18234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 18:40:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 123.
Dec 06 18:40:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:40:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:40:10 volumio go-librespot[19041]: go-librespot daemon starting...
Dec 06 18:40:10 volumio go-librespot[19042]: time="2025-12-06T18:40:10-06:00" level=info msg="running go-librespot 0.4.0"
Dec 06 18:40:10 volumio go-librespot[19042]: time="2025-12-06T18:40:10-06:00" level=debug msg="app state loaded"
Dec 06 18:40:10 volumio go-librespot[19042]: time="2025-12-06T18:40:10-06:00" level=debug msg="stored credentials not found"
Dec 06 18:40:10 volumio go-librespot[19042]: time="2025-12-06T18:40:10-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 18:40:10 volumio go-librespot[19042]: time="2025-12-06T18:40:10-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 06 18:40:10 volumio go-librespot[19042]: time="2025-12-06T18:40:10-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 06 18:40:10 volumio go-librespot[19042]: time="2025-12-06T18:40:10-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 06 18:40:10 volumio go-librespot[19042]: time="2025-12-06T18:40:10-06:00" level=info msg="zeroconf server listening on port 33125"
Dec 06 18:40:10 volumio go-librespot[19042]: time="2025-12-06T18:40:10-06:00" level=debug msg="obtained new client token: AADbgyEMLxhRfYNxfQnBUK26OpeYPa7GYi+cGmDC3FgxQeBwniYFBlQffgurp2mcQR9ViX2Xw6+e0PjsLsUipWCbyCPUDmFTDW9OYBvSbt51rRYEvhw17VOOOcB5bM0T+fzC97UKMcskgXwEHKxvlNYgYgLnx182egESIluBjg1N6M2f8CzQ6Un47nqJXz3gLosRoPJo69tkbHmGN0GXaSoWNwlIdpRWK39HBDbVr5BwKSn9UFhSeT9v5g=="
Dec 06 18:40:10 volumio volumio[18234]: info: Initializing connection to go-librespot Websocket
Dec 06 18:40:10 volumio go-librespot[19042]: time="2025-12-06T18:40:10-06:00" level=debug msg="new websocket client"
Dec 06 18:40:10 volumio volumio[18234]: info: Connection to go-librespot Websocket established
Dec 06 18:40:10 volumio go-librespot[19042]: time="2025-12-06T18:40:10-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 06 18:40:10 volumio go-librespot[19042]: time="2025-12-06T18:40:10-06:00" level=debug msg="completed keyexchange"
Dec 06 18:40:10 volumio go-librespot[19042]: time="2025-12-06T18:40:10-06:00" level=debug msg="completed challenge"
Dec 06 18:40:10 volumio go-librespot[19042]: time="2025-12-06T18:40:10-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 06 18:40:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 18:40:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 18:40:10 volumio volumio[18234]: info: Connection to go-librespot Websocket closed
Dec 06 18:40:13 volumio volumio[18234]: info: Getting Spotify volume
Dec 06 18:40:13 volumio volumio[18234]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 06 18:40:13 volumio volumio[18234]: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 18:40:13 volumio volumio[18234]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Dec 06 18:40:13 volumio volumio[18234]: errno: -111,
Dec 06 18:40:13 volumio volumio[18234]: code: 'ECONNREFUSED',
Dec 06 18:40:13 volumio volumio[18234]: syscall: 'connect',
Dec 06 18:40:13 volumio volumio[18234]: address: '127.0.0.1',
Dec 06 18:40:13 volumio volumio[18234]: port: 9879,
Dec 06 18:40:13 volumio volumio[18234]: response: undefined
Dec 06 18:40:13 volumio volumio[18234]: }
Dec 06 18:40:13 volumio volumio[18234]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 06 18:40:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 124.
Dec 06 18:40:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:40:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 18:40:14 volumio go-librespot[19064]: go-librespot daemon starting...
Dec 06 18:40:14 volumio go-librespot[19065]: time="2025-12-06T18:40:14-06:00" level=info msg="running go-librespot 0.4.0"
Dec 06 18:40:14 volumio go-librespot[19065]: time="2025-12-06T18:40:14-06:00" level=debug msg="app state loaded"
Dec 06 18:40:14 volumio go-librespot[19065]: time="2025-12-06T18:40:14-06:00" level=debug msg="stored credentials not found"
Dec 06 18:40:14 volumio go-librespot[19065]: time="2025-12-06T18:40:14-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 18:40:14 volumio go-librespot[19065]: time="2025-12-06T18:40:14-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 06 18:40:14 volumio go-librespot[19065]: time="2025-12-06T18:40:14-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 06 18:40:14 volumio go-librespot[19065]: time="2025-12-06T18:40:14-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 06 18:40:14 volumio go-librespot[19065]: time="2025-12-06T18:40:14-06:00" level=info msg="zeroconf server listening on port 46171"
Dec 06 18:40:14 volumio go-librespot[19065]: time="2025-12-06T18:40:14-06:00" level=debug msg="obtained new client token: AABI1AOn7odRHcv3HrlKV7by/7oJzQ7OLCCTV+vJQLKZjvsm/TupxgKf6+i3sKMbMzYUyLV/gGDMqet6hWMAoirS62nh6lptM8KCCubfzrtgJt76ngSfXOaYfMDVdKNkYwa8GtrKqUm41yWSnAXcOVbWCU0FVO8dDoO9hKGY12yvdKx3/C6tTMjFd79w68dgBqto8/CUgKUkmnggrfWAjd58aaZ0cWd9uEVz+15Fihkg+v9De6+GFMf8ow=="
Dec 06 18:40:14 volumio go-librespot[19065]: time="2025-12-06T18:40:14-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 06 18:40:14 volumio sudo[19075]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-06 18:39'
Dec 06 18:40:14 volumio sudo[19075]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 06 18:40:14 volumio go-librespot[19065]: time="2025-12-06T18:40:14-06:00" level=debug msg="completed keyexchange"
Dec 06 18:40:14 volumio go-librespot[19065]: time="2025-12-06T18:40:14-06:00" level=debug msg="completed challenge"
Dec 06 18:40:14 volumio go-librespot[19065]: time="2025-12-06T18:40:14-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 06 18:40:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 18:40:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
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="ac7dd5922ede329d1459d48b226ddc71f5209a2c"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="3441cddd43405c36fe444484553f10f2f5bc830b"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Nov 13 11:04:27 UTC 2025"
VOLUMIO_VERSION="4.069"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="7da8d6d9f8baf8621af4ec2af5a00f00"