Dec 15 10:09:00 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Dec 15 10:09:00 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Dec 15 10:09:00 gbvpi-bedroom volumio[13871]: info: Streaming services startup
Dec 15 10:09:00 gbvpi-bedroom volumio[13871]: info: Starting Streaming Daemon
Dec 15 10:09:00 gbvpi-bedroom sudo[14172]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 15 10:09:00 gbvpi-bedroom sudo[14172]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 15 10:09:00 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Dec 15 10:09:00 gbvpi-bedroom sudo[14172]: pam_unix(sudo:session): session closed for user root
Dec 15 10:09:00 gbvpi-bedroom volumio[13871]: error: Cannot start Volumio Streaming Daemon
Dec 15 10:09:00 gbvpi-bedroom volumio[13871]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 15 10:09:00 gbvpi-bedroom volumio[13871]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 15 10:09:00 gbvpi-bedroom volumio[13871]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Dec 15 10:09:00 gbvpi-bedroom volumio[13871]: info: MyVolumio token set successfully
Dec 15 10:09:00 gbvpi-bedroom volumio[13871]: info: MYVOLUMIO: Adding device
Dec 15 10:09:00 gbvpi-bedroom volumio[13871]: info: MYVOLUMIO: Evaluating Server
Dec 15 10:09:01 gbvpi-bedroom volumio[13871]: info: MyVolumio Plan changed: premium
Dec 15 10:09:01 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Subscribed plan changed to premium
Dec 15 10:09:01 gbvpi-bedroom volumio[13871]: info: Removing browser output: myVolumio user plan is not superstar
Dec 15 10:09:01 gbvpi-bedroom volumio[13871]: info: Removing audio output:
Dec 15 10:09:01 gbvpi-bedroom volumio[13871]: info: MYVOLUMIO: Adding device
Dec 15 10:09:01 gbvpi-bedroom volumio[13871]: info: MYVOLUMIO: Evaluating Server
Dec 15 10:09:01 gbvpi-bedroom volumio[13871]: info: Remote config written successfully
Dec 15 10:09:01 gbvpi-bedroom volumio[13871]: info: Starting Tunnel 1
Dec 15 10:09:01 gbvpi-bedroom volumio[13871]: info: Starting Tunnel Connection Checker
Dec 15 10:09:01 gbvpi-bedroom volumio[13871]: info: Completed starting MyVolumio Plugin
Dec 15 10:09:01 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60.
Dec 15 10:09:01 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:01 gbvpi-bedroom volumio[13871]: info: MYVolumio Device enabled
Dec 15 10:09:01 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins...
Dec 15 10:09:01 gbvpi-bedroom volumio[13871]: info: MyVolumio status changed
Dec 15 10:09:01 gbvpi-bedroom volumio[13871]: info: Streaming services startup
Dec 15 10:09:01 gbvpi-bedroom volumio[13871]: info: Starting Streaming Daemon
Dec 15 10:09:01 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:01 gbvpi-bedroom go-librespot[14212]: go-librespot daemon starting...
Dec 15 10:09:01 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid
Dec 15 10:09:01 gbvpi-bedroom sudo[14218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 15 10:09:01 gbvpi-bedroom sudo[14218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 15 10:09:01 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"...
Dec 15 10:09:01 gbvpi-bedroom go-librespot[14219]: time="2025-12-15T10:09:01-05:00" level=info msg="running go-librespot 0.4.0"
Dec 15 10:09:01 gbvpi-bedroom go-librespot[14219]: time="2025-12-15T10:09:01-05:00" level=debug msg="app state loaded"
Dec 15 10:09:01 gbvpi-bedroom go-librespot[14219]: time="2025-12-15T10:09:01-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 15 10:09:01 gbvpi-bedroom sudo[14218]: pam_unix(sudo:session): session closed for user root
Dec 15 10:09:01 gbvpi-bedroom volumio[13871]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart
Dec 15 10:09:01 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Loading plugin "multiroom"...
Dec 15 10:09:01 gbvpi-bedroom go-librespot[14219]: time="2025-12-15T10:09:01-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 15 10:09:01 gbvpi-bedroom go-librespot[14219]: time="2025-12-15T10:09:01-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 15 10:09:01 gbvpi-bedroom go-librespot[14219]: time="2025-12-15T10:09:01-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 15 10:09:01 gbvpi-bedroom go-librespot[14219]: time="2025-12-15T10:09:01-05:00" level=info msg="zeroconf server listening on port 34863"
Dec 15 10:09:01 gbvpi-bedroom go-librespot[14219]: time="2025-12-15T10:09:01-05:00" level=debug msg="obtained new client token: AACeQKDJYDwLuL4qxpzeYXd+rsOWo3dfJ+i2wq7zY3Sq3P+PJ83mtws2UAHZdGZHzv7aMTsfC3jzHz8T9mSG61efgJ6FoAjvpbRMWwpubeclYyiIPErMGIIyofMWmbE9dmiQ9HOrFge5pWKOJbj1ctipix0NOq1GaOctFypfEUTkWQUVNLNN1DUa/XKvbZXO3mDsL3ehrNB/nWvjXxXHrmqJkFlAYui7kocs7W5NOmh8zv8BTmnJChu9Lg=="
Dec 15 10:09:01 gbvpi-bedroom go-librespot[14219]: time="2025-12-15T10:09:01-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 15 10:09:01 gbvpi-bedroom go-librespot[14219]: time="2025-12-15T10:09:01-05:00" level=debug msg="completed keyexchange"
Dec 15 10:09:01 gbvpi-bedroom go-librespot[14219]: time="2025-12-15T10:09:01-05:00" level=debug msg="completed challenge"
Dec 15 10:09:01 gbvpi-bedroom go-librespot[14219]: time="2025-12-15T10:09:01-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 15 10:09:01 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 15 10:09:01 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom
Dec 15 10:09:02 gbvpi-bedroom sudo[14229]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom
Dec 15 10:09:02 gbvpi-bedroom sudo[14229]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 15 10:09:02 gbvpi-bedroom sudo[14229]: pam_unix(sudo:session): session closed for user root
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: MRS: MultiRoom plugin initialized
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: MRS: STOPPING SNAPCLIENT
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: MRS: Snap server stop
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: MRS: STOPPING volumioStreaming
Dec 15 10:09:02 gbvpi-bedroom sudo[14246]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient
Dec 15 10:09:02 gbvpi-bedroom sudo[14246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 15 10:09:02 gbvpi-bedroom sudo[14248]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver
Dec 15 10:09:02 gbvpi-bedroom sudo[14248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 15 10:09:02 gbvpi-bedroom sudo[14250]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming
Dec 15 10:09:02 gbvpi-bedroom sudo[14253]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/*
Dec 15 10:09:02 gbvpi-bedroom sudo[14250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 15 10:09:02 gbvpi-bedroom sudo[14253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"...
Dec 15 10:09:02 gbvpi-bedroom sudo[14253]: pam_unix(sudo:session): session closed for user root
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Loading plugin "manifestui"...
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"...
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"...
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"...
Dec 15 10:09:02 gbvpi-bedroom sudo[14246]: pam_unix(sudo:session): session closed for user root
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Loading plugin "tidal"...
Dec 15 10:09:02 gbvpi-bedroom sudo[14248]: pam_unix(sudo:session): session closed for user root
Dec 15 10:09:02 gbvpi-bedroom sudo[14250]: pam_unix(sudo:session): session closed for user root
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Loading plugin "qobuz"...
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"...
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"...
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: Preparing to generate the ALSA configuration file
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: Reading ALSA contributions from plugins.
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: Setting Geolocation for MyVolumio to us1
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: MRS: Removed streaming files
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: MRS: volumioStreaming STOPPED
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: MRS: SNAPSERVER STOPPED
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: MRS: SNAPCLIENT STOPPED
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: error: Cannot start Volumio Streaming Daemon
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: Asound.conf file written
Dec 15 10:09:02 gbvpi-bedroom sudo[14260]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Dec 15 10:09:02 gbvpi-bedroom sudo[14260]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 15 10:09:02 gbvpi-bedroom sudo[14260]: pam_unix(sudo:session): session closed for user root
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: Output device has changed, restarting MPD
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: Output device has changed, restarting Shairport Sync
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 15 10:09:02 gbvpi-bedroom sudo[14266]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Dec 15 10:09:02 gbvpi-bedroom sudo[14266]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 15 10:09:02 gbvpi-bedroom sudo[14266]: pam_unix(sudo:session): session closed for user root
Dec 15 10:09:02 gbvpi-bedroom sudo[14268]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Dec 15 10:09:02 gbvpi-bedroom sudo[14268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: ------------------------------------ BT MESSAGE: [FUNC] onStart
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready.
Dec 15 10:09:02 gbvpi-bedroom systemd[1]: Stopping mpd.service - Music Player Daemon...
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: MRS: Pushing multiroomSync output for this device
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: MRS: Pushing multiroomSync output
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: Adding audio output:
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: Adding audio output:
Dec 15 10:09:02 gbvpi-bedroom systemd[1]: mpd.service: Deactivated successfully.
Dec 15 10:09:02 gbvpi-bedroom systemd[1]: Stopped mpd.service - Music Player Daemon.
Dec 15 10:09:02 gbvpi-bedroom systemd[1]: mpd.service: Consumed 1.970s CPU time.
Dec 15 10:09:02 gbvpi-bedroom systemd[1]: mpd.socket: Deactivated successfully.
Dec 15 10:09:02 gbvpi-bedroom systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Dec 15 10:09:02 gbvpi-bedroom systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 15 10:09:02 gbvpi-bedroom bluetoothd[763]: Path / reserved for Adv Monitor app :1.105209
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: Adding METAVOLUMIO REST API Endpoints
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: Preparing CD Folders
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: Adding CD REST API Endpoints
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: Starting UDEV Watcher for CD
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: Detecting CD presence with UDEV
Dec 15 10:09:02 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices
Dec 15 10:09:02 gbvpi-bedroom bluetoothd[763]: Adv Monitor app :1.105209 disconnected from D-Bus
Dec 15 10:09:02 gbvpi-bedroom systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Dec 15 10:09:02 gbvpi-bedroom systemd[1]: Starting mpd.service - Music Player Daemon...
Dec 15 10:09:02 gbvpi-bedroom sudo[14285]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Dec 15 10:09:02 gbvpi-bedroom sudo[14285]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Dec 15 10:09:02 gbvpi-bedroom sudo[14285]: pam_unix(sudo:session): session closed for user root
Dec 15 10:09:03 gbvpi-bedroom mpd[14288]: 2025-12-15T10:09:03 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Dec 15 10:09:03 gbvpi-bedroom systemd[1]: Started mpd.service - Music Player Daemon.
Dec 15 10:09:03 gbvpi-bedroom sudo[14268]: pam_unix(sudo:session): session closed for user root
Dec 15 10:09:04 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 61.
Dec 15 10:09:04 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:05 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:05 gbvpi-bedroom go-librespot[14291]: go-librespot daemon starting...
Dec 15 10:09:05 gbvpi-bedroom go-librespot[14292]: time="2025-12-15T10:09:05-05:00" level=info msg="running go-librespot 0.4.0"
Dec 15 10:09:05 gbvpi-bedroom go-librespot[14292]: time="2025-12-15T10:09:05-05:00" level=debug msg="app state loaded"
Dec 15 10:09:05 gbvpi-bedroom go-librespot[14292]: time="2025-12-15T10:09:05-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 15 10:09:05 gbvpi-bedroom go-librespot[14292]: time="2025-12-15T10:09:05-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 15 10:09:05 gbvpi-bedroom go-librespot[14292]: time="2025-12-15T10:09:05-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 15 10:09:05 gbvpi-bedroom go-librespot[14292]: time="2025-12-15T10:09:05-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 15 10:09:05 gbvpi-bedroom go-librespot[14292]: time="2025-12-15T10:09:05-05:00" level=info msg="zeroconf server listening on port 46815"
Dec 15 10:09:05 gbvpi-bedroom go-librespot[14292]: time="2025-12-15T10:09:05-05:00" level=debug msg="obtained new client token: AAD050YSQbYJw/cUYMQ7R9JELjWCa7k+pS+p1VHG8ym3fKTwUKNKCHe0JQUnMHy/flfBYO4iDA9Pdq++vo70kbm4kBFtkvcROUGRqOWCESNuih1vbXlGzFa7zk2P7/ecrxWxfoHW8skeBEI5kba6JNhNGvuNxBll2dRb3hrx5nhK4tK5AB69rdvX+4fZo6gOZgjZ3P/mPBHN3+6qz0McKcgI32EtMILv9pQCFt2KO7+PmBCpS2K8jyXs6Q=="
Dec 15 10:09:05 gbvpi-bedroom go-librespot[14292]: time="2025-12-15T10:09:05-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 15 10:09:05 gbvpi-bedroom go-librespot[14292]: time="2025-12-15T10:09:05-05:00" level=debug msg="completed keyexchange"
Dec 15 10:09:05 gbvpi-bedroom go-librespot[14292]: time="2025-12-15T10:09:05-05:00" level=debug msg="completed challenge"
Dec 15 10:09:05 gbvpi-bedroom go-librespot[14292]: time="2025-12-15T10:09:05-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 15 10:09:05 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 15 10:09:05 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: warn: [cd-plugin] cdspeedctl: device or media not ready
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: Adding inputs REST Endpoints
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: Scanning Audio Inputs
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: Checking against Known Cards name
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: Adding Server instance for streaming
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: error: Hi Res Audio Failed Login: Missing Login Data
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: Adding HIGHRESAUDIO REST API Endpoints
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect
Dec 15 10:09:07 gbvpi-bedroom sudo[14317]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Dec 15 10:09:07 gbvpi-bedroom sudo[14317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: QobuzConnect: Starting Qobuz Connect socket and service
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: Adding TIDAL REST API Endpoints
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: Stopping AccessToken refresher cron for QOBUZ
Dec 15 10:09:07 gbvpi-bedroom systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service...
Dec 15 10:09:07 gbvpi-bedroom sudo[14324]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service
Dec 15 10:09:07 gbvpi-bedroom sudo[14324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 15 10:09:07 gbvpi-bedroom systemd[1]: vtcs.service: Deactivated successfully.
Dec 15 10:09:07 gbvpi-bedroom systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service.
Dec 15 10:09:07 gbvpi-bedroom sudo[14317]: pam_unix(sudo:session): session closed for user root
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: AccessToken refresher cron started for QOBUZ
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: Adding QOBUZ REST API Endpoints
Dec 15 10:09:07 gbvpi-bedroom sudo[14324]: pam_unix(sudo:session): session closed for user root
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: Updating MyVolumio device info
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: MRS: Getting audio outputs on start
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: MRS: Requesting all other devices output
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:09:07 gbvpi-bedroom sudo[14327]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service
Dec 15 10:09:07 gbvpi-bedroom sudo[14327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 15 10:09:07 gbvpi-bedroom systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service...
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: Successfully Added MyVolumio device
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: Setting Geolocation for MyVolumio to us4
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 15 10:09:07 gbvpi-bedroom qobuz-connect[13783]: 20251215 10:09:07.878 [13783.13783] INFO SampleApp: Stopping Local configuration server
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: MPD Permissions set
Dec 15 10:09:07 gbvpi-bedroom sudo[14330]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service
Dec 15 10:09:07 gbvpi-bedroom sudo[14330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: CorePlayQueue::getTrack 0
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 15 10:09:07 gbvpi-bedroom sudo[14330]: pam_unix(sudo:session): session closed for user root
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: info: CorePlayQueue::getTrack 0
Dec 15 10:09:07 gbvpi-bedroom volumio[13871]: error: updateQueue error: null
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioStop
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: info: CoreStateMachine::stop
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: info: Starting Shairport Sync
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: info: MRS: Found cast device: SmartTV-4K-fe72c264393a59c76d38e27b8168114d
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: info: Adding audio output:
Dec 15 10:09:08 gbvpi-bedroom sudo[14346]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service
Dec 15 10:09:08 gbvpi-bedroom sudo[14346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: info: MRS: Found cast device: Google-Home-Mini-f209fa314cb426d40a59bcf66354ddc9
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: info: Adding audio output:
Dec 15 10:09:08 gbvpi-bedroom sudo[14348]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 15 10:09:08 gbvpi-bedroom sudo[14348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 15 10:09:08 gbvpi-bedroom systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Dec 15 10:09:08 gbvpi-bedroom systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel...
Dec 15 10:09:08 gbvpi-bedroom autossh[13765]: received signal to exit (15)
Dec 15 10:09:08 gbvpi-bedroom systemd[1]: sshtunnel.service: Deactivated successfully.
Dec 15 10:09:08 gbvpi-bedroom systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel.
Dec 15 10:09:08 gbvpi-bedroom systemd[1]: shairport-sync.service: Deactivated successfully.
Dec 15 10:09:08 gbvpi-bedroom systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Dec 15 10:09:08 gbvpi-bedroom systemd[1]: shairport-sync.service: Consumed 1.608s CPU time.
Dec 15 10:09:08 gbvpi-bedroom systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Dec 15 10:09:08 gbvpi-bedroom sudo[14348]: pam_unix(sudo:session): session closed for user root
Dec 15 10:09:08 gbvpi-bedroom systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel.
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: info: Shairport-Sync Started
Dec 15 10:09:08 gbvpi-bedroom sudo[14346]: pam_unix(sudo:session): session closed for user root
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: info: Remote SSH Started
Dec 15 10:09:08 gbvpi-bedroom autossh[14353]: port set to 0, monitoring disabled
Dec 15 10:09:08 gbvpi-bedroom autossh[14353]: starting ssh (count 1)
Dec 15 10:09:08 gbvpi-bedroom autossh[14353]: ssh child pid is 14356
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: info: Successfully Updated MyVolumio device
Dec 15 10:09:08 gbvpi-bedroom volumiossh-tunnel[14356]: Warning: Permanently added '[us1.myvolumio.org]:2222' (RSA) to the list of known hosts.
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.66712a116082b2e6ca4ffe3109a843e6.state.status'
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: info: Successfully Added MyVolumio device
Dec 15 10:09:08 gbvpi-bedroom systemd[1]: qobuz-connect.service: Deactivated successfully.
Dec 15 10:09:08 gbvpi-bedroom systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service.
Dec 15 10:09:08 gbvpi-bedroom systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service.
Dec 15 10:09:08 gbvpi-bedroom sudo[14327]: pam_unix(sudo:session): session closed for user root
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: info: Executing endpoint qc_getconfig
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Dec 15 10:09:08 gbvpi-bedroom qobuz-connect[14371]: 20251215 10:09:08.575 [14371.14371] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object]
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: info: QobuzConnect: QOBUZ Connect daemon connected
Dec 15 10:09:08 gbvpi-bedroom qobuz-connect[14371]: 20251215 10:09:08.578 [14371.14371] INFO VolumeManager: [0xda4588]: Setting new playback volume: 75
Dec 15 10:09:08 gbvpi-bedroom qobuz-connect[14371]: 20251215 10:09:08.579 [14371.14371] INFO VolumeManager: [0xda4588]: Setting new mute state: 0
Dec 15 10:09:08 gbvpi-bedroom qobuz-connect[14371]: 20251215 10:09:08.579 [14371.14371] INFO QobuzConnect: [0xda4f58]: Client initialized!
Dec 15 10:09:08 gbvpi-bedroom qobuz-connect[14371]: 20251215 10:09:08.579 [14371.14371] INFO SampleApp: Starting Avahi advertising, name: GBVPi-Bedroom, service name: _qobuz-connect._tcp
Dec 15 10:09:08 gbvpi-bedroom qobuz-connect[14371]: 20251215 10:09:08.590 [14371.14371] INFO LocalConfigManager: [0xda3e68]: Starting Local Configuration server
Dec 15 10:09:08 gbvpi-bedroom qobuz-connect[14371]: 20251215 10:09:08.590 [14371.14371] INFO SampleApp: Starting Local configuration server
Dec 15 10:09:08 gbvpi-bedroom qobuz-connect[14371]: 20251215 10:09:08.590 [14371.14371] INFO SampleApp: Connected to UNIX socket client 0xd8e818
Dec 15 10:09:08 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 62.
Dec 15 10:09:08 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:08 gbvpi-bedroom qobuz-connect[14371]: 20251215 10:09:08.725 [14371.14371] INFO SampleApp: Playback volume changed: 75
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:09:08 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:08 gbvpi-bedroom go-librespot[14377]: go-librespot daemon starting...
Dec 15 10:09:08 gbvpi-bedroom go-librespot[14378]: time="2025-12-15T10:09:08-05:00" level=info msg="running go-librespot 0.4.0"
Dec 15 10:09:08 gbvpi-bedroom go-librespot[14378]: time="2025-12-15T10:09:08-05:00" level=debug msg="app state loaded"
Dec 15 10:09:08 gbvpi-bedroom go-librespot[14378]: time="2025-12-15T10:09:08-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: info: Updating MyVolumio device info
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 15 10:09:08 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 15 10:09:08 gbvpi-bedroom go-librespot[14378]: time="2025-12-15T10:09:08-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 15 10:09:08 gbvpi-bedroom go-librespot[14378]: time="2025-12-15T10:09:08-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 15 10:09:08 gbvpi-bedroom go-librespot[14378]: time="2025-12-15T10:09:08-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 15 10:09:08 gbvpi-bedroom go-librespot[14378]: time="2025-12-15T10:09:08-05:00" level=info msg="zeroconf server listening on port 45327"
Dec 15 10:09:09 gbvpi-bedroom go-librespot[14378]: time="2025-12-15T10:09:09-05:00" level=debug msg="obtained new client token: AAChZJlC2I+sAfP/LDJUWWFKetgX0ePnCARHsfxU1SIvs0pvsFKq9yIgFvF/KhMtwizUBsbvx+8HHQ1FdE0IJHuDLgTnJ7Nwi2mYkopB6PRI7PwE6cRlo1YHKjY554Xp4MUOzzKE/Emir//hXIlH1lObJR77Fs8Hdw57SxFVNe7G9yiVRoz++Ts6uXNChVQepQ04fM5DqtzeSGYlwCYlwkZAF7qKq8/Pq1iDBZmwX6FrBRQ/wYtElQw="
Dec 15 10:09:09 gbvpi-bedroom volumio[13871]: info: Successfully Updated MyVolumio device
Dec 15 10:09:09 gbvpi-bedroom go-librespot[14378]: time="2025-12-15T10:09:09-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 15 10:09:09 gbvpi-bedroom go-librespot[14378]: time="2025-12-15T10:09:09-05:00" level=debug msg="completed keyexchange"
Dec 15 10:09:09 gbvpi-bedroom go-librespot[14378]: time="2025-12-15T10:09:09-05:00" level=debug msg="completed challenge"
Dec 15 10:09:09 gbvpi-bedroom go-librespot[14378]: time="2025-12-15T10:09:09-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 15 10:09:09 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 15 10:09:09 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 15 10:09:10 gbvpi-bedroom volumio[13871]: info: TidalConnect service stoped!
Dec 15 10:09:10 gbvpi-bedroom volumio[13871]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Dec 15 10:09:10 gbvpi-bedroom volumio[13871]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect
Dec 15 10:09:10 gbvpi-bedroom sudo[14394]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Dec 15 10:09:10 gbvpi-bedroom sudo[14394]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 15 10:09:10 gbvpi-bedroom sudo[14398]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Dec 15 10:09:10 gbvpi-bedroom sudo[14398]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 15 10:09:10 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:09:10 gbvpi-bedroom volumio[13871]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:09:10 gbvpi-bedroom systemd[1]: Started vtcs.service - Volumio Tidal Connect Service.
Dec 15 10:09:10 gbvpi-bedroom sudo[14394]: pam_unix(sudo:session): session closed for user root
Dec 15 10:09:10 gbvpi-bedroom systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service...
Dec 15 10:09:10 gbvpi-bedroom systemd[1]: mpd_monitor.service: Deactivated successfully.
Dec 15 10:09:10 gbvpi-bedroom systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service.
Dec 15 10:09:10 gbvpi-bedroom systemd[1]: Started mpd_monitor.service - MPD Monitor Service.
Dec 15 10:09:10 gbvpi-bedroom mpd_monitor.sh[14402]: MPD Monitor Service: Starting MPD Monitor Service
Dec 15 10:09:10 gbvpi-bedroom sudo[14398]: pam_unix(sudo:session): session closed for user root
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: Successfully started MPD Monitor
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: Executing endpoint tc_getconfig
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig
Dec 15 10:09:11 gbvpi-bedroom vtcs[14400]: STARTING TidalConnect services, version: 1.5.2.56
Dec 15 10:09:11 gbvpi-bedroom vtcs[14400]: STARTED TidalConnect services.
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: Executing endpoint tc_connect
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: Connecting to TidalConnect
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::servicePushState
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreStateMachine::pushState
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioPushState
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: MRS: Pushing multiroomSync output update for this device
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: MRS: Pushing multiroomSync output
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CorePlayQueue::getTrack 0
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false}
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: verbose: CURRENT POSITION 0
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreStateMachine::syncState stateService stop
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreStateMachine::syncState currentStatus stop
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreStateMachine::pushState
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioPushState
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: MRS: Pushing multiroomSync output update for this device
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: MRS: Pushing multiroomSync output
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: No code
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreStateMachine::pushState
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioPushState
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: MRS: Pushing multiroomSync output update for this device
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: MRS: Pushing multiroomSync output
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::servicePushState
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreStateMachine::pushState
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioPushState
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: MRS: Pushing multiroomSync output update for this device
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: MRS: Pushing multiroomSync output
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CorePlayQueue::getTrack 0
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false}
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: verbose: CURRENT POSITION 0
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreStateMachine::syncState stateService stop
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreStateMachine::syncState currentStatus stop
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreStateMachine::pushState
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioPushState
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: MRS: Pushing multiroomSync output update for this device
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: MRS: Pushing multiroomSync output
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: No code
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreStateMachine::pushState
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioPushState
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: MRS: Pushing multiroomSync output update for this device
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: MRS: Pushing multiroomSync output
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.66712a116082b2e6ca4ffe3109a843e6.state.status'
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.66712a116082b2e6ca4ffe3109a843e6.state.status'
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.66712a116082b2e6ca4ffe3109a843e6.state.status'
Dec 15 10:09:11 gbvpi-bedroom volumio[13871]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.66712a116082b2e6ca4ffe3109a843e6.state.status'
Dec 15 10:09:12 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 63.
Dec 15 10:09:12 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:12 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:12 gbvpi-bedroom go-librespot[14420]: go-librespot daemon starting...
Dec 15 10:09:12 gbvpi-bedroom go-librespot[14421]: time="2025-12-15T10:09:12-05:00" level=info msg="running go-librespot 0.4.0"
Dec 15 10:09:12 gbvpi-bedroom go-librespot[14421]: time="2025-12-15T10:09:12-05:00" level=debug msg="app state loaded"
Dec 15 10:09:12 gbvpi-bedroom go-librespot[14421]: time="2025-12-15T10:09:12-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 15 10:09:12 gbvpi-bedroom go-librespot[14421]: time="2025-12-15T10:09:12-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 15 10:09:12 gbvpi-bedroom go-librespot[14421]: time="2025-12-15T10:09:12-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 15 10:09:12 gbvpi-bedroom go-librespot[14421]: time="2025-12-15T10:09:12-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 15 10:09:12 gbvpi-bedroom go-librespot[14421]: time="2025-12-15T10:09:12-05:00" level=info msg="zeroconf server listening on port 41897"
Dec 15 10:09:12 gbvpi-bedroom go-librespot[14421]: time="2025-12-15T10:09:12-05:00" level=debug msg="obtained new client token: AAAEACQa+VdcIAQFMBI3o4a0NbHMRDU7ewtMzSsffaleb/L6S09YpAoNMcKKOtvRN6+RKVFDFPn0S6r0vUnQD6Qqa132L8seAH1DZG1woXVIV62ZQMZNukhaxJokLBLemEKLqSlewJx+A72mVZJIt7Y9+bjWxlzP0saKBBfqqfZv+uGYCmBFS1rbprLxW8n2SFum4FLZrWIrQyYao7RxrmS3Az2YRguorxzzzNz01LxPu32G2d7ehoLSPg=="
Dec 15 10:09:12 gbvpi-bedroom go-librespot[14421]: time="2025-12-15T10:09:12-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 15 10:09:13 gbvpi-bedroom go-librespot[14421]: time="2025-12-15T10:09:13-05:00" level=debug msg="completed keyexchange"
Dec 15 10:09:13 gbvpi-bedroom go-librespot[14421]: time="2025-12-15T10:09:13-05:00" level=debug msg="completed challenge"
Dec 15 10:09:13 gbvpi-bedroom go-librespot[14421]: time="2025-12-15T10:09:13-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 15 10:09:13 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 15 10:09:13 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 15 10:09:13 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:09:13 gbvpi-bedroom volumio[13871]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:09:13 gbvpi-bedroom volumio[13871]: info: TidalConnect service started!
Dec 15 10:09:13 gbvpi-bedroom volumio[13871]: [Metrics] CommandRouter: 29s 419.59ms
Dec 15 10:09:13 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumiosetStartupVolume
Dec 15 10:09:13 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 15 10:09:13 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 15 10:09:13 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::Close All Modals sent
Dec 15 10:09:13 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::Close All Modals sent
Dec 15 10:09:14 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 15 10:09:14 gbvpi-bedroom volumio[13871]: 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 15 10:09:14 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:09:14 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Dec 15 10:09:14 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Dec 15 10:09:14 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Dec 15 10:09:16 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 64.
Dec 15 10:09:16 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:16 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:16 gbvpi-bedroom go-librespot[14446]: go-librespot daemon starting...
Dec 15 10:09:16 gbvpi-bedroom go-librespot[14447]: time="2025-12-15T10:09:16-05:00" level=info msg="running go-librespot 0.4.0"
Dec 15 10:09:16 gbvpi-bedroom go-librespot[14447]: time="2025-12-15T10:09:16-05:00" level=debug msg="app state loaded"
Dec 15 10:09:16 gbvpi-bedroom go-librespot[14447]: time="2025-12-15T10:09:16-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 15 10:09:16 gbvpi-bedroom go-librespot[14447]: time="2025-12-15T10:09:16-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 15 10:09:16 gbvpi-bedroom go-librespot[14447]: time="2025-12-15T10:09:16-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 15 10:09:16 gbvpi-bedroom go-librespot[14447]: time="2025-12-15T10:09:16-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 15 10:09:16 gbvpi-bedroom go-librespot[14447]: time="2025-12-15T10:09:16-05:00" level=info msg="zeroconf server listening on port 45587"
Dec 15 10:09:16 gbvpi-bedroom go-librespot[14447]: time="2025-12-15T10:09:16-05:00" level=debug msg="obtained new client token: AAD6sbBzRpkzFFe6fsQ9pNGxbXTldvjsKOLaNeBxh+WGZy+7epAHtNV0IjaSseYZFZO+1ZOb95NmBzA7JRtYLIUeTI7XW5EEKTXWYvLE1I2qkY4RDOFDmSCRaIwOlgq7iMH9mwLPxBSOvy3aTRnGzhVfH5NJ+xZEuJe/3rTsSsJGtw2M14wa8iWFxgYxgjrl7WI0o/IVNgSOlCklJnfw8hc/eIvlnvAk91SBTs2kAeO83gqLTxVsMqLC1w=="
Dec 15 10:09:16 gbvpi-bedroom go-librespot[14447]: time="2025-12-15T10:09:16-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 15 10:09:16 gbvpi-bedroom go-librespot[14447]: time="2025-12-15T10:09:16-05:00" level=debug msg="completed keyexchange"
Dec 15 10:09:16 gbvpi-bedroom go-librespot[14447]: time="2025-12-15T10:09:16-05:00" level=debug msg="completed challenge"
Dec 15 10:09:16 gbvpi-bedroom go-librespot[14447]: time="2025-12-15T10:09:16-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 15 10:09:16 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 15 10:09:16 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 15 10:09:16 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:09:16 gbvpi-bedroom volumio[13871]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:09:17 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:09:17 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:09:17 gbvpi-bedroom volumio[13871]: info: Listing playlists
Dec 15 10:09:17 gbvpi-bedroom volumio[13871]: info: Listing playlists
Dec 15 10:09:17 gbvpi-bedroom volumio[13871]: info: Listing playlists
Dec 15 10:09:17 gbvpi-bedroom volumio[13871]: info: Listing playlists
Dec 15 10:09:19 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 15 10:09:19 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 15 10:09:19 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 15 10:09:19 gbvpi-bedroom volumio-remote-updater[775]: Test mode enabled
Dec 15 10:09:19 gbvpi-bedroom volumio-remote-updater[775]: Alpha mode disabled
Dec 15 10:09:19 gbvpi-bedroom volumio-remote-updater[775]: Alpha legacy test mode disabled
Dec 15 10:09:19 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Dec 15 10:09:19 gbvpi-bedroom volumio[13871]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"CAUTION
\n\n- This build may break your network
\n
\nFIX
\n\n- Wireless and network handling
\n
\n","title":"Update v4.080","updateavailable":true}
Dec 15 10:09:19 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Dec 15 10:09:19 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 15 10:09:19 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 15 10:09:19 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Dec 15 10:09:19 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Dec 15 10:09:19 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Dec 15 10:09:19 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 15 10:09:19 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 15 10:09:19 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:09:19 gbvpi-bedroom volumio[13871]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:09:19 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 65.
Dec 15 10:09:19 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:20 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:20 gbvpi-bedroom go-librespot[14459]: go-librespot daemon starting...
Dec 15 10:09:20 gbvpi-bedroom go-librespot[14460]: time="2025-12-15T10:09:20-05:00" level=info msg="running go-librespot 0.4.0"
Dec 15 10:09:20 gbvpi-bedroom go-librespot[14460]: time="2025-12-15T10:09:20-05:00" level=debug msg="app state loaded"
Dec 15 10:09:20 gbvpi-bedroom go-librespot[14460]: time="2025-12-15T10:09:20-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 15 10:09:20 gbvpi-bedroom go-librespot[14460]: time="2025-12-15T10:09:20-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 15 10:09:20 gbvpi-bedroom go-librespot[14460]: time="2025-12-15T10:09:20-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 15 10:09:20 gbvpi-bedroom go-librespot[14460]: time="2025-12-15T10:09:20-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 15 10:09:20 gbvpi-bedroom go-librespot[14460]: time="2025-12-15T10:09:20-05:00" level=info msg="zeroconf server listening on port 44459"
Dec 15 10:09:20 gbvpi-bedroom go-librespot[14460]: time="2025-12-15T10:09:20-05:00" level=debug msg="obtained new client token: AABxkRu7EGnsBdN8Qy3gtmjhqryvfmhFqKnnqEeX/dxQn6ld2mqnH3WLBJsD8aEN1SUt0Wy0GBrwAgfqiunO2V2i1H0ZtzJgFpXjoDBsqoM8UNvNUWAI5ily8ZbkQnGT+WAwDWcECWn7HkDbQWh44Q/b/6BC5MuMlsSiuiXwB7u57d5g3I2znVMWpScse8/ATR//kCq2duZFgtausb8JiIn3cimUP8fPgR4dFWoc9DYNHklOxrw7HNKlRw=="
Dec 15 10:09:20 gbvpi-bedroom go-librespot[14460]: time="2025-12-15T10:09:20-05:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.255.62:4070: connect: connection refused"
Dec 15 10:09:20 gbvpi-bedroom go-librespot[14460]: time="2025-12-15T10:09:20-05:00" level=debug msg="connected to ap-gue1.spotify.com:443"
Dec 15 10:09:20 gbvpi-bedroom go-librespot[14460]: time="2025-12-15T10:09:20-05:00" level=debug msg="completed keyexchange"
Dec 15 10:09:20 gbvpi-bedroom go-librespot[14460]: time="2025-12-15T10:09:20-05:00" level=debug msg="completed challenge"
Dec 15 10:09:20 gbvpi-bedroom go-librespot[14460]: time="2025-12-15T10:09:20-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 15 10:09:20 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 15 10:09:20 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 15 10:09:20 gbvpi-bedroom volumio[13871]: info: BOOT COMPLETED
Dec 15 10:09:22 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:09:22 gbvpi-bedroom volumio[13871]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:09:23 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 66.
Dec 15 10:09:23 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:23 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:23 gbvpi-bedroom go-librespot[14469]: go-librespot daemon starting...
Dec 15 10:09:23 gbvpi-bedroom go-librespot[14470]: time="2025-12-15T10:09:23-05:00" level=info msg="running go-librespot 0.4.0"
Dec 15 10:09:23 gbvpi-bedroom go-librespot[14470]: time="2025-12-15T10:09:23-05:00" level=debug msg="app state loaded"
Dec 15 10:09:23 gbvpi-bedroom go-librespot[14470]: time="2025-12-15T10:09:23-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 15 10:09:24 gbvpi-bedroom go-librespot[14470]: time="2025-12-15T10:09:24-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 15 10:09:24 gbvpi-bedroom go-librespot[14470]: time="2025-12-15T10:09:24-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 15 10:09:24 gbvpi-bedroom go-librespot[14470]: time="2025-12-15T10:09:24-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 15 10:09:24 gbvpi-bedroom go-librespot[14470]: time="2025-12-15T10:09:24-05:00" level=info msg="zeroconf server listening on port 36559"
Dec 15 10:09:24 gbvpi-bedroom go-librespot[14470]: time="2025-12-15T10:09:24-05:00" level=debug msg="obtained new client token: AAAzK4ulu/nnVdLMBWWq02kLFGDihfcPOfZj7SgVFPORqzWUkQZQ3OF5wuSP3lbdENkRu4HTlPPzGNmeGDyHuFbGPFvkYULg+A5gv5mU87J5zPt/GwFnYXT0T1ndgHNMac36AChIp72OICH9GtbpcrCJy5u/tuX48d9iZs41YS9HolKNHbG5L5NRRddsiJSkzLd95dbi302Adw7NIKaAhbTCVpdd8vpSE714D7vi+uq5V4FwPwAI0TE="
Dec 15 10:09:24 gbvpi-bedroom go-librespot[14470]: time="2025-12-15T10:09:24-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 15 10:09:24 gbvpi-bedroom go-librespot[14470]: time="2025-12-15T10:09:24-05:00" level=debug msg="completed keyexchange"
Dec 15 10:09:24 gbvpi-bedroom go-librespot[14470]: time="2025-12-15T10:09:24-05:00" level=debug msg="completed challenge"
Dec 15 10:09:24 gbvpi-bedroom go-librespot[14470]: time="2025-12-15T10:09:24-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 15 10:09:24 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 15 10:09:24 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 15 10:09:25 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:09:25 gbvpi-bedroom volumio[13871]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:09:27 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:09:27 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:09:27 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 67.
Dec 15 10:09:27 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:27 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:27 gbvpi-bedroom go-librespot[14491]: go-librespot daemon starting...
Dec 15 10:09:27 gbvpi-bedroom go-librespot[14492]: time="2025-12-15T10:09:27-05:00" level=info msg="running go-librespot 0.4.0"
Dec 15 10:09:27 gbvpi-bedroom go-librespot[14492]: time="2025-12-15T10:09:27-05:00" level=debug msg="app state loaded"
Dec 15 10:09:27 gbvpi-bedroom go-librespot[14492]: time="2025-12-15T10:09:27-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 15 10:09:27 gbvpi-bedroom go-librespot[14492]: time="2025-12-15T10:09:27-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 15 10:09:27 gbvpi-bedroom go-librespot[14492]: time="2025-12-15T10:09:27-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 15 10:09:27 gbvpi-bedroom go-librespot[14492]: time="2025-12-15T10:09:27-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 15 10:09:27 gbvpi-bedroom go-librespot[14492]: time="2025-12-15T10:09:27-05:00" level=info msg="zeroconf server listening on port 45745"
Dec 15 10:09:27 gbvpi-bedroom go-librespot[14492]: time="2025-12-15T10:09:27-05:00" level=debug msg="obtained new client token: AAAGmpYy25G+F8gMGE7yHU8M4JXnX7sA4ZAB/YJcCSOkf+Qslfo5s4e79UJxlQlw0FNCEYRluZhl/u1NvRb4IjF/lkT9LU3kar0Uf9OpGsvUC/E0ttmL6nq190bKE88KZCXCGQV4k6AeKl1inGJcqWZHwNOmS+zLOq3hFb6KMMyy6prNSehxORjF+okcZa/QG1xkIQpwQ74+8sE3Hv9q45/DSbF31EzvJOx5cFQlq/4xwHf6v5UWue/bpA=="
Dec 15 10:09:28 gbvpi-bedroom go-librespot[14492]: time="2025-12-15T10:09:28-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 15 10:09:28 gbvpi-bedroom go-librespot[14492]: time="2025-12-15T10:09:28-05:00" level=debug msg="completed keyexchange"
Dec 15 10:09:28 gbvpi-bedroom go-librespot[14492]: time="2025-12-15T10:09:28-05:00" level=debug msg="completed challenge"
Dec 15 10:09:28 gbvpi-bedroom go-librespot[14492]: time="2025-12-15T10:09:28-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 15 10:09:28 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 15 10:09:28 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 15 10:09:28 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:09:28 gbvpi-bedroom volumio[13871]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:09:31 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 68.
Dec 15 10:09:31 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:31 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:31 gbvpi-bedroom go-librespot[14502]: go-librespot daemon starting...
Dec 15 10:09:31 gbvpi-bedroom go-librespot[14503]: time="2025-12-15T10:09:31-05:00" level=info msg="running go-librespot 0.4.0"
Dec 15 10:09:31 gbvpi-bedroom go-librespot[14503]: time="2025-12-15T10:09:31-05:00" level=debug msg="app state loaded"
Dec 15 10:09:31 gbvpi-bedroom go-librespot[14503]: time="2025-12-15T10:09:31-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 15 10:09:31 gbvpi-bedroom go-librespot[14503]: time="2025-12-15T10:09:31-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 15 10:09:31 gbvpi-bedroom go-librespot[14503]: time="2025-12-15T10:09:31-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 15 10:09:31 gbvpi-bedroom go-librespot[14503]: time="2025-12-15T10:09:31-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 15 10:09:31 gbvpi-bedroom go-librespot[14503]: time="2025-12-15T10:09:31-05:00" level=info msg="zeroconf server listening on port 37821"
Dec 15 10:09:31 gbvpi-bedroom go-librespot[14503]: time="2025-12-15T10:09:31-05:00" level=debug msg="obtained new client token: AAAqbuN2MuVB+UpaNM381UeXIBmA9GTSKYZw3iH6bKd5vgE60kc7yY5ZJjA6AU0bx170G4HFt//sTU8X0AGpiELbFIJlmWfjqByu9LkKEfUy04i1hfX1lX3Z63w2KFQiCeWBMKLtDtvfrTck5TeTcRMeOlzPD8LaboVC76kaVV5VMjze3l3dHT6utX6NoXSj3jVgH89Pjrb3MRLPng7BN1r2vzieSNxLH/mhfwYxGxRzw6AlRg0MGtuTOA=="
Dec 15 10:09:31 gbvpi-bedroom go-librespot[14503]: time="2025-12-15T10:09:31-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 15 10:09:31 gbvpi-bedroom go-librespot[14503]: time="2025-12-15T10:09:31-05:00" level=debug msg="completed keyexchange"
Dec 15 10:09:31 gbvpi-bedroom go-librespot[14503]: time="2025-12-15T10:09:31-05:00" level=debug msg="completed challenge"
Dec 15 10:09:31 gbvpi-bedroom go-librespot[14503]: time="2025-12-15T10:09:31-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 15 10:09:31 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 15 10:09:31 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 15 10:09:31 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:09:31 gbvpi-bedroom volumio[13871]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:09:34 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:09:34 gbvpi-bedroom volumio[13871]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:09:34 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 69.
Dec 15 10:09:34 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:35 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:35 gbvpi-bedroom go-librespot[14512]: go-librespot daemon starting...
Dec 15 10:09:35 gbvpi-bedroom go-librespot[14513]: time="2025-12-15T10:09:35-05:00" level=info msg="running go-librespot 0.4.0"
Dec 15 10:09:35 gbvpi-bedroom go-librespot[14513]: time="2025-12-15T10:09:35-05:00" level=debug msg="app state loaded"
Dec 15 10:09:35 gbvpi-bedroom go-librespot[14513]: time="2025-12-15T10:09:35-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 15 10:09:35 gbvpi-bedroom go-librespot[14513]: time="2025-12-15T10:09:35-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 15 10:09:35 gbvpi-bedroom go-librespot[14513]: time="2025-12-15T10:09:35-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 15 10:09:35 gbvpi-bedroom go-librespot[14513]: time="2025-12-15T10:09:35-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 15 10:09:35 gbvpi-bedroom go-librespot[14513]: time="2025-12-15T10:09:35-05:00" level=info msg="zeroconf server listening on port 42389"
Dec 15 10:09:35 gbvpi-bedroom go-librespot[14513]: time="2025-12-15T10:09:35-05:00" level=debug msg="obtained new client token: AADeUVGiI1ZOLJWu7vn2ezD++bKN4v05WsM3yeh52gP1Cv/dBbYpfItLLuKALLkS7C2X44kv1u6wxcU6jkseDunkvMMUTgxN0OYvOrFreZx8AjmgzEMsB8F5EZH3Nvv199I8/mTqhc3u1/CcroztJKQkqxI/aHO1tq4StNB6MO+gdDkUgzI0CDUAqT5CT1lyBp4nYY4L0ZciajqdFZmoJMWjkJGYuB1IhG/+DNqvxmyr7CiWzZj/9WZCmA=="
Dec 15 10:09:35 gbvpi-bedroom go-librespot[14513]: time="2025-12-15T10:09:35-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 15 10:09:35 gbvpi-bedroom go-librespot[14513]: time="2025-12-15T10:09:35-05:00" level=debug msg="completed keyexchange"
Dec 15 10:09:35 gbvpi-bedroom go-librespot[14513]: time="2025-12-15T10:09:35-05:00" level=debug msg="completed challenge"
Dec 15 10:09:35 gbvpi-bedroom go-librespot[14513]: time="2025-12-15T10:09:35-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 15 10:09:35 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 15 10:09:35 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 15 10:09:37 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:09:37 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:09:37 gbvpi-bedroom volumio[13871]: info: Listing playlists
Dec 15 10:09:37 gbvpi-bedroom volumio[13871]: info: Listing playlists
Dec 15 10:09:37 gbvpi-bedroom volumio[13871]: info: Listing playlists
Dec 15 10:09:37 gbvpi-bedroom volumio[13871]: info: Listing playlists
Dec 15 10:09:37 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:09:37 gbvpi-bedroom volumio[13871]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:09:38 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 70.
Dec 15 10:09:38 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:38 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:38 gbvpi-bedroom go-librespot[14534]: go-librespot daemon starting...
Dec 15 10:09:38 gbvpi-bedroom go-librespot[14535]: time="2025-12-15T10:09:38-05:00" level=info msg="running go-librespot 0.4.0"
Dec 15 10:09:38 gbvpi-bedroom go-librespot[14535]: time="2025-12-15T10:09:38-05:00" level=debug msg="app state loaded"
Dec 15 10:09:38 gbvpi-bedroom go-librespot[14535]: time="2025-12-15T10:09:38-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 15 10:09:38 gbvpi-bedroom go-librespot[14535]: time="2025-12-15T10:09:38-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 15 10:09:38 gbvpi-bedroom go-librespot[14535]: time="2025-12-15T10:09:38-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 15 10:09:38 gbvpi-bedroom go-librespot[14535]: time="2025-12-15T10:09:38-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 15 10:09:38 gbvpi-bedroom go-librespot[14535]: time="2025-12-15T10:09:38-05:00" level=info msg="zeroconf server listening on port 33205"
Dec 15 10:09:39 gbvpi-bedroom go-librespot[14535]: time="2025-12-15T10:09:39-05:00" level=debug msg="obtained new client token: AABv2HyPPbt17cV/49inVw9YgcF+cNrXvoVOLAS/6e5xJrVJZUyv/nuYJL7KtFb8HLQBJ3Mvfrdcl3gAPTUou0utett/LWLq2klhTV+5J0hjxJHv0U93m1U4EC0R8nTtXYPWBiWHBZJ8kDZqPnMT3D90zm3LMkrnLnefS9vLiDEqKa+dFGIBQFsI2d1kLMF8PhwoBAEnznLvUawpc5bZid6FwTBY8RH2nKIquJCJ7cbSmndQWt/+bUU="
Dec 15 10:09:39 gbvpi-bedroom go-librespot[14535]: time="2025-12-15T10:09:39-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 15 10:09:39 gbvpi-bedroom go-librespot[14535]: time="2025-12-15T10:09:39-05:00" level=debug msg="completed keyexchange"
Dec 15 10:09:39 gbvpi-bedroom go-librespot[14535]: time="2025-12-15T10:09:39-05:00" level=debug msg="completed challenge"
Dec 15 10:09:39 gbvpi-bedroom go-librespot[14535]: time="2025-12-15T10:09:39-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 15 10:09:39 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 15 10:09:39 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 15 10:09:40 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:09:40 gbvpi-bedroom volumio[13871]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:09:42 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 71.
Dec 15 10:09:42 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:42 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:42 gbvpi-bedroom go-librespot[14542]: go-librespot daemon starting...
Dec 15 10:09:42 gbvpi-bedroom go-librespot[14543]: time="2025-12-15T10:09:42-05:00" level=info msg="running go-librespot 0.4.0"
Dec 15 10:09:42 gbvpi-bedroom go-librespot[14543]: time="2025-12-15T10:09:42-05:00" level=debug msg="app state loaded"
Dec 15 10:09:42 gbvpi-bedroom go-librespot[14543]: time="2025-12-15T10:09:42-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 15 10:09:42 gbvpi-bedroom go-librespot[14543]: time="2025-12-15T10:09:42-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 15 10:09:42 gbvpi-bedroom go-librespot[14543]: time="2025-12-15T10:09:42-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 15 10:09:42 gbvpi-bedroom go-librespot[14543]: time="2025-12-15T10:09:42-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 15 10:09:42 gbvpi-bedroom go-librespot[14543]: time="2025-12-15T10:09:42-05:00" level=info msg="zeroconf server listening on port 45577"
Dec 15 10:09:42 gbvpi-bedroom go-librespot[14543]: time="2025-12-15T10:09:42-05:00" level=debug msg="obtained new client token: AAB7rp0SntnLEkUCmYhm9Qxhhmqf8LkmZ38jcAJA441cy63gYGN2eLIT8Huo37ulhs6M/rq4OeeqgnX/8QFm5FN+BgF8xy1+f1E41WgTjkohABKxKjS2weaIm86dGohf1g2NxWENd9BqI0+nEGvlJe6PnhCUyh28m0XfpuodP4Pi/NeolVv+k5PNl7J9I0RGC5Yk/3D76qYva8nOM9n8Wzqi7WOxcwibWcK7WYE8oLhISHB4+r13SqbbwA=="
Dec 15 10:09:42 gbvpi-bedroom go-librespot[14543]: time="2025-12-15T10:09:42-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 15 10:09:42 gbvpi-bedroom go-librespot[14543]: time="2025-12-15T10:09:42-05:00" level=debug msg="completed keyexchange"
Dec 15 10:09:42 gbvpi-bedroom go-librespot[14543]: time="2025-12-15T10:09:42-05:00" level=debug msg="completed challenge"
Dec 15 10:09:43 gbvpi-bedroom go-librespot[14543]: time="2025-12-15T10:09:43-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 15 10:09:43 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 15 10:09:43 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 15 10:09:43 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:09:43 gbvpi-bedroom volumio[13871]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:09:46 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 72.
Dec 15 10:09:46 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:46 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:46 gbvpi-bedroom go-librespot[14565]: go-librespot daemon starting...
Dec 15 10:09:46 gbvpi-bedroom go-librespot[14566]: time="2025-12-15T10:09:46-05:00" level=info msg="running go-librespot 0.4.0"
Dec 15 10:09:46 gbvpi-bedroom go-librespot[14566]: time="2025-12-15T10:09:46-05:00" level=debug msg="app state loaded"
Dec 15 10:09:46 gbvpi-bedroom go-librespot[14566]: time="2025-12-15T10:09:46-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 15 10:09:46 gbvpi-bedroom go-librespot[14566]: time="2025-12-15T10:09:46-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 15 10:09:46 gbvpi-bedroom go-librespot[14566]: time="2025-12-15T10:09:46-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 15 10:09:46 gbvpi-bedroom go-librespot[14566]: time="2025-12-15T10:09:46-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 15 10:09:46 gbvpi-bedroom go-librespot[14566]: time="2025-12-15T10:09:46-05:00" level=info msg="zeroconf server listening on port 46061"
Dec 15 10:09:46 gbvpi-bedroom go-librespot[14566]: time="2025-12-15T10:09:46-05:00" level=debug msg="obtained new client token: AAB2iCxt1ks3E1+LxtJl07VnV89KY8nglGWjz+W49y0gNUUHMZhdL1BcU5AIG2r6qTIXZK/tdzhX7lW3ILBqPSNuUXxdmI/TK7pER6k+06zuM7VIMWEdVlAu3t0q40COIfS+OOR5Ujh/+VionboWg/vVB+NQ90OaBFYkc2ctd1LPG7iZCPch6rTWt3n4zt75hnqoUXtukdmwLmuKwpYgb7atM4HhlM+Lb/LCsSwnOgJNbT4H9chXpGfODQ=="
Dec 15 10:09:46 gbvpi-bedroom go-librespot[14566]: time="2025-12-15T10:09:46-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 15 10:09:46 gbvpi-bedroom go-librespot[14566]: time="2025-12-15T10:09:46-05:00" level=debug msg="completed keyexchange"
Dec 15 10:09:46 gbvpi-bedroom go-librespot[14566]: time="2025-12-15T10:09:46-05:00" level=debug msg="completed challenge"
Dec 15 10:09:46 gbvpi-bedroom go-librespot[14566]: time="2025-12-15T10:09:46-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 15 10:09:46 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 15 10:09:46 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 15 10:09:46 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:09:46 gbvpi-bedroom volumio[13871]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:09:47 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:09:47 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:09:49 gbvpi-bedroom volumio[13871]: info: UPDATER: Scheduling automatic update
Dec 15 10:09:49 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Dec 15 10:09:49 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Dec 15 10:09:49 gbvpi-bedroom volumio[13871]: info: UPDATER: Auto update will take place at: Tue Dec 16 2025 04:05:13 GMT-0500 (Eastern Standard Time)
Dec 15 10:09:49 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 73.
Dec 15 10:09:49 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:49 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:09:49 gbvpi-bedroom volumio[13871]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:09:50 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:50 gbvpi-bedroom go-librespot[14573]: go-librespot daemon starting...
Dec 15 10:09:50 gbvpi-bedroom go-librespot[14574]: time="2025-12-15T10:09:50-05:00" level=info msg="running go-librespot 0.4.0"
Dec 15 10:09:50 gbvpi-bedroom go-librespot[14574]: time="2025-12-15T10:09:50-05:00" level=debug msg="app state loaded"
Dec 15 10:09:50 gbvpi-bedroom go-librespot[14574]: time="2025-12-15T10:09:50-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 15 10:09:50 gbvpi-bedroom go-librespot[14574]: time="2025-12-15T10:09:50-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 15 10:09:50 gbvpi-bedroom go-librespot[14574]: time="2025-12-15T10:09:50-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 15 10:09:50 gbvpi-bedroom go-librespot[14574]: time="2025-12-15T10:09:50-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 15 10:09:50 gbvpi-bedroom go-librespot[14574]: time="2025-12-15T10:09:50-05:00" level=info msg="zeroconf server listening on port 46473"
Dec 15 10:09:50 gbvpi-bedroom go-librespot[14574]: time="2025-12-15T10:09:50-05:00" level=debug msg="obtained new client token: AAAGBS43WFK4ZNqBBLo8VRwd0n31kmd5agm9+3JO7bpkxirIynEb+BqrgUQ0C3Ge78gh5V+BM363t8ErPs+g1qgitFYmTCKZ59jwZgyCAhwQjNzqoZoVBwdMpuVfuAUvRzkLxW06lG3dDRq4+NGOWHpR75K4DQ6IMPzdjKN1/ihwZbNhGJQzo1ghb8/Vw6cw/s8joy3mllbOO2C9XsjfC0PKcvM7TnTAh2CyzSDMJwEnfRal5pngDGt6TQ=="
Dec 15 10:09:50 gbvpi-bedroom go-librespot[14574]: time="2025-12-15T10:09:50-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 15 10:09:50 gbvpi-bedroom go-librespot[14574]: time="2025-12-15T10:09:50-05:00" level=debug msg="completed keyexchange"
Dec 15 10:09:50 gbvpi-bedroom go-librespot[14574]: time="2025-12-15T10:09:50-05:00" level=debug msg="completed challenge"
Dec 15 10:09:50 gbvpi-bedroom go-librespot[14574]: time="2025-12-15T10:09:50-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 15 10:09:50 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 15 10:09:50 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 15 10:09:52 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:09:52 gbvpi-bedroom volumio[13871]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:09:53 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 74.
Dec 15 10:09:53 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:53 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:53 gbvpi-bedroom go-librespot[14585]: go-librespot daemon starting...
Dec 15 10:09:53 gbvpi-bedroom go-librespot[14586]: time="2025-12-15T10:09:53-05:00" level=info msg="running go-librespot 0.4.0"
Dec 15 10:09:53 gbvpi-bedroom go-librespot[14586]: time="2025-12-15T10:09:53-05:00" level=debug msg="app state loaded"
Dec 15 10:09:53 gbvpi-bedroom go-librespot[14586]: time="2025-12-15T10:09:53-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 15 10:09:53 gbvpi-bedroom go-librespot[14586]: time="2025-12-15T10:09:53-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 15 10:09:53 gbvpi-bedroom go-librespot[14586]: time="2025-12-15T10:09:53-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 15 10:09:53 gbvpi-bedroom go-librespot[14586]: time="2025-12-15T10:09:53-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 15 10:09:53 gbvpi-bedroom go-librespot[14586]: time="2025-12-15T10:09:53-05:00" level=info msg="zeroconf server listening on port 39191"
Dec 15 10:09:54 gbvpi-bedroom go-librespot[14586]: time="2025-12-15T10:09:54-05:00" level=debug msg="obtained new client token: AADjIBP1VQVhlA1nD6B8RckGburbr1MM60tyKljIjYjf7Ub7Nc0lKmbgQWFRHh1uzL7y4X39IRKAoQLdEtNas3nZQAmw3UKPGthBPpbAUladvS5B0Hgwhy5nljx4u0Zo+OWtOgLw6OmTCz/0NOQFd9rRXs0tKukiymQHafttQWq201KO5wNBdgMkhhaqVhB7YUkmh70QlGdQ9b3oqSveZiynzjroqjKXo+Nnne7RFCzNKqbl7hh1ubk="
Dec 15 10:09:54 gbvpi-bedroom go-librespot[14586]: time="2025-12-15T10:09:54-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 15 10:09:54 gbvpi-bedroom go-librespot[14586]: time="2025-12-15T10:09:54-05:00" level=debug msg="completed keyexchange"
Dec 15 10:09:54 gbvpi-bedroom go-librespot[14586]: time="2025-12-15T10:09:54-05:00" level=debug msg="completed challenge"
Dec 15 10:09:54 gbvpi-bedroom go-librespot[14586]: time="2025-12-15T10:09:54-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 15 10:09:54 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 15 10:09:54 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 15 10:09:55 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:09:55 gbvpi-bedroom volumio[13871]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:09:57 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:09:57 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:09:57 gbvpi-bedroom volumio[13871]: info: Listing playlists
Dec 15 10:09:57 gbvpi-bedroom volumio[13871]: info: Listing playlists
Dec 15 10:09:57 gbvpi-bedroom volumio[13871]: info: Listing playlists
Dec 15 10:09:57 gbvpi-bedroom volumio[13871]: info: Listing playlists
Dec 15 10:09:57 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 75.
Dec 15 10:09:57 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:57 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:09:57 gbvpi-bedroom go-librespot[14607]: go-librespot daemon starting...
Dec 15 10:09:57 gbvpi-bedroom go-librespot[14608]: time="2025-12-15T10:09:57-05:00" level=info msg="running go-librespot 0.4.0"
Dec 15 10:09:57 gbvpi-bedroom go-librespot[14608]: time="2025-12-15T10:09:57-05:00" level=debug msg="app state loaded"
Dec 15 10:09:57 gbvpi-bedroom go-librespot[14608]: time="2025-12-15T10:09:57-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 15 10:09:57 gbvpi-bedroom go-librespot[14608]: time="2025-12-15T10:09:57-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 15 10:09:57 gbvpi-bedroom go-librespot[14608]: time="2025-12-15T10:09:57-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 15 10:09:57 gbvpi-bedroom go-librespot[14608]: time="2025-12-15T10:09:57-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 15 10:09:57 gbvpi-bedroom go-librespot[14608]: time="2025-12-15T10:09:57-05:00" level=info msg="zeroconf server listening on port 38585"
Dec 15 10:09:57 gbvpi-bedroom go-librespot[14608]: time="2025-12-15T10:09:57-05:00" level=debug msg="obtained new client token: AAAzVZHI/h5bV+p7bnBkBWfyOocj5auLZ//zdPEEhksKHNJk73a/FoZYuE4btgT4x2t2GJU+HrmjjWp4oKOldhKmmIKj3n50twBRQOClCArUJh2/JCFG21hEjwnZJqfqrnHQs3zm2ym6M6Y2R7z8ar6xjG3DhJJV1huW/Rwhtze651WhXxuX6BCkJPeCSqySY3+SH0JC4K+0mbQ/MHMsm4NAG63RgHtrzx2WqruGnS1xPRI+HCQc+xQEpA=="
Dec 15 10:09:57 gbvpi-bedroom go-librespot[14608]: time="2025-12-15T10:09:57-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 15 10:09:57 gbvpi-bedroom go-librespot[14608]: time="2025-12-15T10:09:57-05:00" level=debug msg="completed keyexchange"
Dec 15 10:09:57 gbvpi-bedroom go-librespot[14608]: time="2025-12-15T10:09:57-05:00" level=debug msg="completed challenge"
Dec 15 10:09:57 gbvpi-bedroom go-librespot[14608]: time="2025-12-15T10:09:57-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 15 10:09:57 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 15 10:09:57 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 15 10:09:58 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:09:58 gbvpi-bedroom volumio[13871]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:10:01 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 76.
Dec 15 10:10:01 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:10:01 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:10:01 gbvpi-bedroom go-librespot[14615]: go-librespot daemon starting...
Dec 15 10:10:01 gbvpi-bedroom go-librespot[14616]: time="2025-12-15T10:10:01-05:00" level=info msg="running go-librespot 0.4.0"
Dec 15 10:10:01 gbvpi-bedroom go-librespot[14616]: time="2025-12-15T10:10:01-05:00" level=debug msg="app state loaded"
Dec 15 10:10:01 gbvpi-bedroom go-librespot[14616]: time="2025-12-15T10:10:01-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 15 10:10:01 gbvpi-bedroom go-librespot[14616]: time="2025-12-15T10:10:01-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 15 10:10:01 gbvpi-bedroom go-librespot[14616]: time="2025-12-15T10:10:01-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 15 10:10:01 gbvpi-bedroom go-librespot[14616]: time="2025-12-15T10:10:01-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 15 10:10:01 gbvpi-bedroom go-librespot[14616]: time="2025-12-15T10:10:01-05:00" level=info msg="zeroconf server listening on port 45031"
Dec 15 10:10:01 gbvpi-bedroom go-librespot[14616]: time="2025-12-15T10:10:01-05:00" level=debug msg="obtained new client token: AADEe5zL14i4Kkk2PQmZAJn0+84n+yk93YunXsFsh1Fabry187DHpZMqISNHRNA9T9OtYEUF5uSQik7+0ay6gSfbvuQBaQr+wqGlqWJrt3cEUYG1u2+/cNqkCDY4HdNnPSQIRiF3f7yIxMeMAObZPexzbnKBxL57kRKf1dFDUcqdWS66X4eI1N6DjdtvaU56Kw0VUxKoCyEHMIsv1hK6JaHsLG9GmG9dPWqdAL7CT9n1jabw1EbniRkSow=="
Dec 15 10:10:01 gbvpi-bedroom go-librespot[14616]: time="2025-12-15T10:10:01-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 15 10:10:01 gbvpi-bedroom go-librespot[14616]: time="2025-12-15T10:10:01-05:00" level=debug msg="completed keyexchange"
Dec 15 10:10:01 gbvpi-bedroom go-librespot[14616]: time="2025-12-15T10:10:01-05:00" level=debug msg="completed challenge"
Dec 15 10:10:01 gbvpi-bedroom go-librespot[14616]: time="2025-12-15T10:10:01-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 15 10:10:01 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 15 10:10:01 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 15 10:10:01 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:10:01 gbvpi-bedroom volumio[13871]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:10:04 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 77.
Dec 15 10:10:04 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:10:04 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:10:04 gbvpi-bedroom volumio[13871]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:10:05 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:10:05 gbvpi-bedroom go-librespot[14623]: go-librespot daemon starting...
Dec 15 10:10:05 gbvpi-bedroom go-librespot[14624]: time="2025-12-15T10:10:05-05:00" level=info msg="running go-librespot 0.4.0"
Dec 15 10:10:05 gbvpi-bedroom go-librespot[14624]: time="2025-12-15T10:10:05-05:00" level=debug msg="app state loaded"
Dec 15 10:10:05 gbvpi-bedroom go-librespot[14624]: time="2025-12-15T10:10:05-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 15 10:10:05 gbvpi-bedroom go-librespot[14624]: time="2025-12-15T10:10:05-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 15 10:10:05 gbvpi-bedroom go-librespot[14624]: time="2025-12-15T10:10:05-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 15 10:10:05 gbvpi-bedroom go-librespot[14624]: time="2025-12-15T10:10:05-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 15 10:10:05 gbvpi-bedroom go-librespot[14624]: time="2025-12-15T10:10:05-05:00" level=info msg="zeroconf server listening on port 46717"
Dec 15 10:10:05 gbvpi-bedroom go-librespot[14624]: time="2025-12-15T10:10:05-05:00" level=debug msg="obtained new client token: AAAyGXWEaIX9MDXF7ITLciQUDsyHfEVVDXpmVO84FKdsVncfYwwGc9V/kYGhzabhBkScXt/eduXWthzt1fF+5QKgj6RYFDJcNQm1M3AjA/RtON4fFnMo0F4uFqjCmgfBZ5saKKPyDVarmdrzqtWoAAQLeZWaYHMdmEwqs+xrZ4EYelD9LeM7nuQyqcfPqGg6aTqt9/pr2i4UT69XCicGlADouCon3JEZDHZ7Hing/a4xQJkEjHGKQ4UDsg=="
Dec 15 10:10:05 gbvpi-bedroom go-librespot[14624]: time="2025-12-15T10:10:05-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 15 10:10:05 gbvpi-bedroom go-librespot[14624]: time="2025-12-15T10:10:05-05:00" level=debug msg="completed keyexchange"
Dec 15 10:10:05 gbvpi-bedroom go-librespot[14624]: time="2025-12-15T10:10:05-05:00" level=debug msg="completed challenge"
Dec 15 10:10:05 gbvpi-bedroom go-librespot[14624]: time="2025-12-15T10:10:05-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 15 10:10:05 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 15 10:10:05 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 15 10:10:07 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:10:07 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:10:07 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:10:07 gbvpi-bedroom volumio[13871]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:10:08 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 78.
Dec 15 10:10:08 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:10:08 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:10:08 gbvpi-bedroom go-librespot[14645]: go-librespot daemon starting...
Dec 15 10:10:08 gbvpi-bedroom go-librespot[14646]: time="2025-12-15T10:10:08-05:00" level=info msg="running go-librespot 0.4.0"
Dec 15 10:10:08 gbvpi-bedroom go-librespot[14646]: time="2025-12-15T10:10:08-05:00" level=debug msg="app state loaded"
Dec 15 10:10:08 gbvpi-bedroom go-librespot[14646]: time="2025-12-15T10:10:08-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 15 10:10:08 gbvpi-bedroom go-librespot[14646]: time="2025-12-15T10:10:08-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 15 10:10:08 gbvpi-bedroom go-librespot[14646]: time="2025-12-15T10:10:08-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 15 10:10:08 gbvpi-bedroom go-librespot[14646]: time="2025-12-15T10:10:08-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 15 10:10:08 gbvpi-bedroom go-librespot[14646]: time="2025-12-15T10:10:08-05:00" level=info msg="zeroconf server listening on port 45577"
Dec 15 10:10:09 gbvpi-bedroom go-librespot[14646]: time="2025-12-15T10:10:09-05:00" level=debug msg="obtained new client token: AADKjmNcD8NtZ6yqm4RtymSmepma1ksdfptj5mDE13Igd/316Qo64ukfydwRYmX+bltrk9SjUuac7dPhAKO+TYQsND8/fZSHodGq2TYV6RPu8ScotCbzqSGjiALxPJHNjGU6ujjBWqQCXSL0mW52pBE1qsTX1sErVoEsGM+JR53Zs66LP7gceRl7t2Y7it2UkH9q/MmTErI0gHa2Yx+LuOj2eKCu4CNVaOkrJaSUXmcaIJlEmUMNSy0="
Dec 15 10:10:09 gbvpi-bedroom go-librespot[14646]: time="2025-12-15T10:10:09-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 15 10:10:09 gbvpi-bedroom go-librespot[14646]: time="2025-12-15T10:10:09-05:00" level=debug msg="completed keyexchange"
Dec 15 10:10:09 gbvpi-bedroom go-librespot[14646]: time="2025-12-15T10:10:09-05:00" level=debug msg="completed challenge"
Dec 15 10:10:09 gbvpi-bedroom go-librespot[14646]: time="2025-12-15T10:10:09-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 15 10:10:09 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 15 10:10:09 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 15 10:10:10 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:10:10 gbvpi-bedroom volumio[13871]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:10:12 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 79.
Dec 15 10:10:12 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:10:12 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:10:12 gbvpi-bedroom go-librespot[14657]: go-librespot daemon starting...
Dec 15 10:10:12 gbvpi-bedroom go-librespot[14659]: time="2025-12-15T10:10:12-05:00" level=info msg="running go-librespot 0.4.0"
Dec 15 10:10:12 gbvpi-bedroom go-librespot[14659]: time="2025-12-15T10:10:12-05:00" level=debug msg="app state loaded"
Dec 15 10:10:12 gbvpi-bedroom go-librespot[14659]: time="2025-12-15T10:10:12-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 15 10:10:12 gbvpi-bedroom go-librespot[14659]: time="2025-12-15T10:10:12-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 15 10:10:12 gbvpi-bedroom go-librespot[14659]: time="2025-12-15T10:10:12-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 15 10:10:12 gbvpi-bedroom go-librespot[14659]: time="2025-12-15T10:10:12-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 15 10:10:12 gbvpi-bedroom go-librespot[14659]: time="2025-12-15T10:10:12-05:00" level=info msg="zeroconf server listening on port 41803"
Dec 15 10:10:13 gbvpi-bedroom go-librespot[14659]: time="2025-12-15T10:10:13-05:00" level=debug msg="obtained new client token: AAAThXvMwBuqFu8KImQ8N66NmnaKfG2UwbAybxHE5om8atNTic+hOeMGDTDMtst+LVskFjIFNZZUdO+PP/IQsxA4FMSWGaZ4DkaAFN4LB0jdLnsXd4g/J4T0VKVfBi+7j9WcB97fYOw1m+U3esjUtrmaMgM8tA1H4sicNELUVeOB8eqIGN2OR65KMZJxgPH82th8sBdQWtMJoWSF4ieTK6psaqfQbU46fdj/gkeZjllZTXzQhTPx8+w="
Dec 15 10:10:13 gbvpi-bedroom go-librespot[14659]: time="2025-12-15T10:10:13-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 15 10:10:13 gbvpi-bedroom go-librespot[14659]: time="2025-12-15T10:10:13-05:00" level=debug msg="completed keyexchange"
Dec 15 10:10:13 gbvpi-bedroom go-librespot[14659]: time="2025-12-15T10:10:13-05:00" level=debug msg="completed challenge"
Dec 15 10:10:13 gbvpi-bedroom go-librespot[14659]: time="2025-12-15T10:10:13-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 15 10:10:13 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 15 10:10:13 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 15 10:10:14 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:10:14 gbvpi-bedroom volumio[13871]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:10:16 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80.
Dec 15 10:10:16 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:10:16 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:10:16 gbvpi-bedroom go-librespot[14680]: go-librespot daemon starting...
Dec 15 10:10:16 gbvpi-bedroom go-librespot[14681]: time="2025-12-15T10:10:16-05:00" level=info msg="running go-librespot 0.4.0"
Dec 15 10:10:16 gbvpi-bedroom go-librespot[14681]: time="2025-12-15T10:10:16-05:00" level=debug msg="app state loaded"
Dec 15 10:10:16 gbvpi-bedroom go-librespot[14681]: time="2025-12-15T10:10:16-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 15 10:10:16 gbvpi-bedroom go-librespot[14681]: time="2025-12-15T10:10:16-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 15 10:10:16 gbvpi-bedroom go-librespot[14681]: time="2025-12-15T10:10:16-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 15 10:10:16 gbvpi-bedroom go-librespot[14681]: time="2025-12-15T10:10:16-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 15 10:10:16 gbvpi-bedroom go-librespot[14681]: time="2025-12-15T10:10:16-05:00" level=info msg="zeroconf server listening on port 44805"
Dec 15 10:10:16 gbvpi-bedroom go-librespot[14681]: time="2025-12-15T10:10:16-05:00" level=debug msg="obtained new client token: AAC1awzo9mJ09/dUnAUBEsJHmTtOz0YbWkhZzD9hk1i0b8S0W1EVku8dTNnBdl7zgmDaBt4l1WLkRHW5gSi2CCOfcraomywXzVkET2BhNf1c2Oqokgyi+aFsqVd+NlwTssAfHIcXS/lhtGSwHkpV5jhVTXl+TV25ZK9tUI8p+7IDkcBN5M9izhTdMdeS/55OqvaSEKfq7kwssB3k1U+kjioQ26s6DMUVP9hsZHacQrxmFZBKQ4OiFUOGVQ=="
Dec 15 10:10:16 gbvpi-bedroom go-librespot[14681]: time="2025-12-15T10:10:16-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 15 10:10:16 gbvpi-bedroom go-librespot[14681]: time="2025-12-15T10:10:16-05:00" level=debug msg="completed keyexchange"
Dec 15 10:10:16 gbvpi-bedroom go-librespot[14681]: time="2025-12-15T10:10:16-05:00" level=debug msg="completed challenge"
Dec 15 10:10:17 gbvpi-bedroom volumio[13871]: info: Initializing connection to go-librespot Websocket
Dec 15 10:10:17 gbvpi-bedroom go-librespot[14681]: time="2025-12-15T10:10:17-05:00" level=debug msg="new websocket client"
Dec 15 10:10:17 gbvpi-bedroom volumio[13871]: info: Connection to go-librespot Websocket established
Dec 15 10:10:17 gbvpi-bedroom go-librespot[14681]: time="2025-12-15T10:10:17-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 15 10:10:17 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 15 10:10:17 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 15 10:10:17 gbvpi-bedroom volumio[13871]: info: Connection to go-librespot Websocket closed
Dec 15 10:10:17 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:10:17 gbvpi-bedroom volumio[13871]: info: CoreCommandRouter::volumioGetState
Dec 15 10:10:17 gbvpi-bedroom volumio[13871]: info: Listing playlists
Dec 15 10:10:17 gbvpi-bedroom volumio[13871]: info: Listing playlists
Dec 15 10:10:17 gbvpi-bedroom volumio[13871]: info: Listing playlists
Dec 15 10:10:17 gbvpi-bedroom volumio[13871]: info: Listing playlists
Dec 15 10:10:20 gbvpi-bedroom volumio[13871]: info: Getting Spotify volume
Dec 15 10:10:20 gbvpi-bedroom volumio[13871]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 15 10:10:20 gbvpi-bedroom volumio[13871]: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 15 10:10:20 gbvpi-bedroom volumio[13871]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Dec 15 10:10:20 gbvpi-bedroom volumio[13871]: errno: -111,
Dec 15 10:10:20 gbvpi-bedroom volumio[13871]: code: 'ECONNREFUSED',
Dec 15 10:10:20 gbvpi-bedroom volumio[13871]: syscall: 'connect',
Dec 15 10:10:20 gbvpi-bedroom volumio[13871]: address: '127.0.0.1',
Dec 15 10:10:20 gbvpi-bedroom volumio[13871]: port: 9879,
Dec 15 10:10:20 gbvpi-bedroom volumio[13871]: response: undefined
Dec 15 10:10:20 gbvpi-bedroom volumio[13871]: }
Dec 15 10:10:20 gbvpi-bedroom volumio[13871]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 15 10:10:20 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 81.
Dec 15 10:10:20 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:10:20 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 15 10:10:20 gbvpi-bedroom go-librespot[14701]: go-librespot daemon starting...
Dec 15 10:10:20 gbvpi-bedroom go-librespot[14702]: time="2025-12-15T10:10:20-05:00" level=info msg="running go-librespot 0.4.0"
Dec 15 10:10:20 gbvpi-bedroom go-librespot[14702]: time="2025-12-15T10:10:20-05:00" level=debug msg="app state loaded"
Dec 15 10:10:20 gbvpi-bedroom go-librespot[14702]: time="2025-12-15T10:10:20-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 15 10:10:20 gbvpi-bedroom sudo[14711]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-15 10:09'
Dec 15 10:10:20 gbvpi-bedroom sudo[14711]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)"
NAME="Raspbian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="6a0bffa825dc2af90b21170949f5219ba5ab5701"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed Dec 10 09:27:23 UTC 2025"
VOLUMIO_VERSION="4.075"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="4bd01f93a654c7068f4553fbd69478e2"