Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom
Dec 16 02:33:00 gbvpi-bedroom sudo[23267]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom
Dec 16 02:33:00 gbvpi-bedroom sudo[23267]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 16 02:33:00 gbvpi-bedroom sudo[23267]: pam_unix(sudo:session): session closed for user root
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: MRS: MultiRoom plugin initialized
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: MRS: STOPPING SNAPCLIENT
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: MRS: Snap server stop
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: MRS: STOPPING volumioStreaming
Dec 16 02:33:00 gbvpi-bedroom sudo[23284]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient
Dec 16 02:33:00 gbvpi-bedroom sudo[23284]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 16 02:33:00 gbvpi-bedroom sudo[23286]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver
Dec 16 02:33:00 gbvpi-bedroom sudo[23286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"...
Dec 16 02:33:00 gbvpi-bedroom sudo[23288]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming
Dec 16 02:33:00 gbvpi-bedroom sudo[23288]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: [MyVolumio PluginManager] Loading plugin "manifestui"...
Dec 16 02:33:00 gbvpi-bedroom sudo[23292]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/*
Dec 16 02:33:00 gbvpi-bedroom sudo[23292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 16 02:33:00 gbvpi-bedroom sudo[23292]: pam_unix(sudo:session): session closed for user root
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"...
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"...
Dec 16 02:33:00 gbvpi-bedroom sudo[23286]: pam_unix(sudo:session): session closed for user root
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"...
Dec 16 02:33:00 gbvpi-bedroom sudo[23284]: pam_unix(sudo:session): session closed for user root
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: [MyVolumio PluginManager] Loading plugin "tidal"...
Dec 16 02:33:00 gbvpi-bedroom sudo[23288]: pam_unix(sudo:session): session closed for user root
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: [MyVolumio PluginManager] Loading plugin "qobuz"...
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"...
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"...
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: Preparing to generate the ALSA configuration file
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: Setting Geolocation for MyVolumio to us4
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: Setting Geolocation for MyVolumio to us1
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: Reading ALSA contributions from plugins.
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: MRS: Removed streaming files
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: MRS: volumioStreaming STOPPED
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: MRS: SNAPSERVER STOPPED
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: MRS: SNAPCLIENT STOPPED
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: error: Cannot start Volumio Streaming Daemon
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: Asound.conf file written
Dec 16 02:33:00 gbvpi-bedroom sudo[23297]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Dec 16 02:33:00 gbvpi-bedroom sudo[23297]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 16 02:33:00 gbvpi-bedroom sudo[23297]: pam_unix(sudo:session): session closed for user root
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: Output device has changed, restarting MPD
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: Output device has changed, restarting Shairport Sync
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 16 02:33:00 gbvpi-bedroom sudo[23303]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Dec 16 02:33:00 gbvpi-bedroom sudo[23303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 16 02:33:00 gbvpi-bedroom sudo[23303]: pam_unix(sudo:session): session closed for user root
Dec 16 02:33:00 gbvpi-bedroom sudo[23305]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Dec 16 02:33:00 gbvpi-bedroom sudo[23305]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: ------------------------------------ BT MESSAGE: [FUNC] onStart
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service
Dec 16 02:33:00 gbvpi-bedroom systemd[1]: Stopping mpd.service - Music Player Daemon...
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready.
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: MRS: Pushing multiroomSync output for this device
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: MRS: Pushing multiroomSync output
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: Adding audio output:
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: Adding audio output:
Dec 16 02:33:00 gbvpi-bedroom systemd[1]: mpd.service: Deactivated successfully.
Dec 16 02:33:00 gbvpi-bedroom systemd[1]: Stopped mpd.service - Music Player Daemon.
Dec 16 02:33:00 gbvpi-bedroom systemd[1]: mpd.service: Consumed 1.853s CPU time.
Dec 16 02:33:00 gbvpi-bedroom systemd[1]: mpd.socket: Deactivated successfully.
Dec 16 02:33:00 gbvpi-bedroom systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Dec 16 02:33:00 gbvpi-bedroom systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio
Dec 16 02:33:00 gbvpi-bedroom bluetoothd[763]: Path / reserved for Adv Monitor app :1.117514
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: Adding METAVOLUMIO REST API Endpoints
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: Preparing CD Folders
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: Adding CD REST API Endpoints
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: Starting UDEV Watcher for CD
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: Detecting CD presence with UDEV
Dec 16 02:33:00 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices
Dec 16 02:33:00 gbvpi-bedroom bluetoothd[763]: Adv Monitor app :1.117514 disconnected from D-Bus
Dec 16 02:33:00 gbvpi-bedroom systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Dec 16 02:33:00 gbvpi-bedroom systemd[1]: Starting mpd.service - Music Player Daemon...
Dec 16 02:33:00 gbvpi-bedroom sudo[23323]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Dec 16 02:33:00 gbvpi-bedroom sudo[23323]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Dec 16 02:33:00 gbvpi-bedroom sudo[23323]: pam_unix(sudo:session): session closed for user root
Dec 16 02:33:01 gbvpi-bedroom mpd[23325]: 2025-12-16T02:33:01 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Dec 16 02:33:01 gbvpi-bedroom systemd[1]: Started mpd.service - Music Player Daemon.
Dec 16 02:33:01 gbvpi-bedroom sudo[23305]: pam_unix(sudo:session): session closed for user root
Dec 16 02:33:02 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4.
Dec 16 02:33:02 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:02 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:02 gbvpi-bedroom go-librespot[23329]: go-librespot daemon starting...
Dec 16 02:33:02 gbvpi-bedroom go-librespot[23330]: time="2025-12-16T02:33:02-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:33:02 gbvpi-bedroom go-librespot[23330]: time="2025-12-16T02:33:02-05:00" level=debug msg="app state loaded"
Dec 16 02:33:02 gbvpi-bedroom go-librespot[23330]: time="2025-12-16T02:33:02-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:33:02 gbvpi-bedroom go-librespot[23330]: time="2025-12-16T02:33:02-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 16 02:33:02 gbvpi-bedroom go-librespot[23330]: time="2025-12-16T02:33:02-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 16 02:33:02 gbvpi-bedroom go-librespot[23330]: time="2025-12-16T02:33:02-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 16 02:33:02 gbvpi-bedroom go-librespot[23330]: time="2025-12-16T02:33:02-05:00" level=info msg="zeroconf server listening on port 32971"
Dec 16 02:33:03 gbvpi-bedroom go-librespot[23330]: time="2025-12-16T02:33:03-05:00" level=debug msg="obtained new client token: AACB4tnnv3Fucq1Tp7QPvCed4M09b0c092cfD31LEmhS0ZrXKTsd3RSl3YVsLM9abRhHWZqTBVdENThODOiI9X8gLErt8lh5Ddx5ncWjWBYsfN9Vb5UWYwbV2X3oRcxyXQRKgI6JwPnB3Pmnli4/US1ueZpFKwBawcUMT1dQbLift59VEi8JtZlNbz6KDR0fMI9AQKTtsCMmu3Xmwye98tuRCCe9oN+g9s48RXlknuCra6im/6+racg="
Dec 16 02:33:03 gbvpi-bedroom go-librespot[23330]: time="2025-12-16T02:33:03-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:33:03 gbvpi-bedroom go-librespot[23330]: time="2025-12-16T02:33:03-05:00" level=debug msg="completed keyexchange"
Dec 16 02:33:03 gbvpi-bedroom go-librespot[23330]: time="2025-12-16T02:33:03-05:00" level=debug msg="completed challenge"
Dec 16 02:33:03 gbvpi-bedroom go-librespot[23330]: time="2025-12-16T02:33:03-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 16 02:33:03 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:33:03 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: warn: [cd-plugin] cdspeedctl: device or media not ready
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: Adding inputs REST Endpoints
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: Scanning Audio Inputs
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: Checking against Known Cards name
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: Adding Server instance for streaming
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: error: Hi Res Audio Failed Login: Missing Login Data
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: Adding HIGHRESAUDIO REST API Endpoints
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect
Dec 16 02:33:05 gbvpi-bedroom sudo[23355]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: QobuzConnect: Starting Qobuz Connect socket and service
Dec 16 02:33:05 gbvpi-bedroom sudo[23355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: Adding TIDAL REST API Endpoints
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: Stopping AccessToken refresher cron for QOBUZ
Dec 16 02:33:05 gbvpi-bedroom sudo[23362]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service
Dec 16 02:33:05 gbvpi-bedroom sudo[23362]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 16 02:33:05 gbvpi-bedroom systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service...
Dec 16 02:33:05 gbvpi-bedroom systemd[1]: vtcs.service: Deactivated successfully.
Dec 16 02:33:05 gbvpi-bedroom systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service.
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: AccessToken refresher cron started for QOBUZ
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: Adding QOBUZ REST API Endpoints
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: Updating MyVolumio device info
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 16 02:33:05 gbvpi-bedroom sudo[23362]: pam_unix(sudo:session): session closed for user root
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 16 02:33:05 gbvpi-bedroom sudo[23355]: pam_unix(sudo:session): session closed for user root
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: Updating MyVolumio device info
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: MRS: Getting audio outputs on start
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: MRS: Requesting all other devices output
Dec 16 02:33:05 gbvpi-bedroom sudo[23364]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service
Dec 16 02:33:05 gbvpi-bedroom sudo[23364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 16 02:33:05 gbvpi-bedroom systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service...
Dec 16 02:33:05 gbvpi-bedroom qobuz-connect[21774]: 20251216 02:33:05.876 [21774.21774] INFO SampleApp: Stopping Local configuration server
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: Successfully Added MyVolumio device
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: Successfully Added MyVolumio device
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: MPD Permissions set
Dec 16 02:33:05 gbvpi-bedroom sudo[23368]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service
Dec 16 02:33:05 gbvpi-bedroom sudo[23368]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: CorePlayQueue::getTrack 0
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 16 02:33:05 gbvpi-bedroom sudo[23368]: pam_unix(sudo:session): session closed for user root
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: CorePlayQueue::getTrack 0
Dec 16 02:33:05 gbvpi-bedroom systemd[1]: qobuz-connect.service: Deactivated successfully.
Dec 16 02:33:05 gbvpi-bedroom systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service.
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: error: updateQueue error: null
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: Listing playlists
Dec 16 02:33:05 gbvpi-bedroom volumio[22906]: info: Listing playlists
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioStop
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: info: CoreStateMachine::stop
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: info: Listing playlists
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: info: Listing playlists
Dec 16 02:33:06 gbvpi-bedroom systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service.
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: info: Starting Shairport Sync
Dec 16 02:33:06 gbvpi-bedroom sudo[23364]: pam_unix(sudo:session): session closed for user root
Dec 16 02:33:06 gbvpi-bedroom sudo[23385]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service
Dec 16 02:33:06 gbvpi-bedroom sudo[23385]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: info: MRS: Found cast device: Google-Home-Mini-f209fa314cb426d40a59bcf66354ddc9
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: info: Adding audio output:
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: info: MRS: Found cast device: SmartTV-4K-fe72c264393a59c76d38e27b8168114d
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: info: Adding audio output:
Dec 16 02:33:06 gbvpi-bedroom sudo[23389]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 16 02:33:06 gbvpi-bedroom sudo[23389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 16 02:33:06 gbvpi-bedroom autossh[21747]: received signal to exit (15)
Dec 16 02:33:06 gbvpi-bedroom systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel...
Dec 16 02:33:06 gbvpi-bedroom systemd[1]: sshtunnel.service: Deactivated successfully.
Dec 16 02:33:06 gbvpi-bedroom systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel.
Dec 16 02:33:06 gbvpi-bedroom systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel.
Dec 16 02:33:06 gbvpi-bedroom sudo[23385]: pam_unix(sudo:session): session closed for user root
Dec 16 02:33:06 gbvpi-bedroom systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Dec 16 02:33:06 gbvpi-bedroom systemd[1]: shairport-sync.service: Deactivated successfully.
Dec 16 02:33:06 gbvpi-bedroom systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Dec 16 02:33:06 gbvpi-bedroom systemd[1]: shairport-sync.service: Consumed 1.598s CPU time.
Dec 16 02:33:06 gbvpi-bedroom autossh[23392]: port set to 0, monitoring disabled
Dec 16 02:33:06 gbvpi-bedroom autossh[23392]: starting ssh (count 1)
Dec 16 02:33:06 gbvpi-bedroom autossh[23392]: ssh child pid is 23395
Dec 16 02:33:06 gbvpi-bedroom systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: info: Executing endpoint qc_getconfig
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Dec 16 02:33:06 gbvpi-bedroom sudo[23389]: pam_unix(sudo:session): session closed for user root
Dec 16 02:33:06 gbvpi-bedroom qobuz-connect[23383]: 20251216 02:33:06.138 [23383.23383] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: info: Successfully Updated MyVolumio device
Dec 16 02:33:06 gbvpi-bedroom qobuz-connect[23383]: 20251216 02:33:06.142 [23383.23383] INFO VolumeManager: [0x15c2588]: Setting new playback volume: 75
Dec 16 02:33:06 gbvpi-bedroom qobuz-connect[23383]: 20251216 02:33:06.142 [23383.23383] INFO VolumeManager: [0x15c2588]: Setting new mute state: 0
Dec 16 02:33:06 gbvpi-bedroom qobuz-connect[23383]: 20251216 02:33:06.142 [23383.23383] INFO QobuzConnect: [0x15c2f58]: Client initialized!
Dec 16 02:33:06 gbvpi-bedroom qobuz-connect[23383]: 20251216 02:33:06.142 [23383.23383] INFO SampleApp: Starting Avahi advertising, name: GBVPi-Bedroom, service name: _qobuz-connect._tcp
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: info: Shairport-Sync Started
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: info: Remote SSH Started
Dec 16 02:33:06 gbvpi-bedroom qobuz-connect[23383]: 20251216 02:33:06.163 [23383.23383] INFO LocalConfigManager: [0x15c1e68]: Starting Local Configuration server
Dec 16 02:33:06 gbvpi-bedroom qobuz-connect[23383]: 20251216 02:33:06.163 [23383.23383] INFO SampleApp: Starting Local configuration server
Dec 16 02:33:06 gbvpi-bedroom qobuz-connect[23383]: 20251216 02:33:06.164 [23383.23383] INFO SampleApp: Connected to UNIX socket client 0x15ac818
Dec 16 02:33:06 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5.
Dec 16 02:33:06 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object]
Dec 16 02:33:06 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:06 gbvpi-bedroom go-librespot[23416]: go-librespot daemon starting...
Dec 16 02:33:06 gbvpi-bedroom volumiossh-tunnel[23395]: Warning: Permanently added '[us1.myvolumio.org]:2222' (RSA) to the list of known hosts.
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: info: QobuzConnect: QOBUZ Connect daemon connected
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: 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 16 02:33:06 gbvpi-bedroom qobuz-connect[23383]: 20251216 02:33:06.288 [23383.23383] INFO SampleApp: Playback volume changed: 75
Dec 16 02:33:06 gbvpi-bedroom go-librespot[23417]: time="2025-12-16T02:33:06-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:33:06 gbvpi-bedroom go-librespot[23417]: time="2025-12-16T02:33:06-05:00" level=debug msg="app state loaded"
Dec 16 02:33:06 gbvpi-bedroom go-librespot[23417]: time="2025-12-16T02:33:06-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.66712a116082b2e6ca4ffe3109a843e6.state.status'
Dec 16 02:33:06 gbvpi-bedroom go-librespot[23417]: time="2025-12-16T02:33:06-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 16 02:33:06 gbvpi-bedroom go-librespot[23417]: time="2025-12-16T02:33:06-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 16 02:33:06 gbvpi-bedroom go-librespot[23417]: time="2025-12-16T02:33:06-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 16 02:33:06 gbvpi-bedroom go-librespot[23417]: time="2025-12-16T02:33:06-05:00" level=info msg="zeroconf server listening on port 37883"
Dec 16 02:33:06 gbvpi-bedroom go-librespot[23417]: time="2025-12-16T02:33:06-05:00" level=debug msg="obtained new client token: AACjh8bJD8j3IgTT3yUJWfnd0BjFfuUC4FP6P2g8y00/mNBeCBFcNiKaGo3Q2WX78bmpAkiXiwaqaoctAKlUp+H7SnqR1hiHRSVzco9NPzraCBMo/dFWVRaTt++hdcsij9CnmVf5zpeoOtQYzAsmj3XlFo4tUuP8+xEtll9Oef3DdHaHRZ/mjV42FnLSwmuh0sHL6/O9DNNg18Ni8zfAB95vvTqWEgPdBdtmehK1zkTQ84swyOyBbonRpQ=="
Dec 16 02:33:06 gbvpi-bedroom go-librespot[23417]: time="2025-12-16T02:33:06-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:33:06 gbvpi-bedroom go-librespot[23417]: time="2025-12-16T02:33:06-05:00" level=debug msg="completed keyexchange"
Dec 16 02:33:06 gbvpi-bedroom go-librespot[23417]: time="2025-12-16T02:33:06-05:00" level=debug msg="completed challenge"
Dec 16 02:33:06 gbvpi-bedroom go-librespot[23417]: time="2025-12-16T02:33:06-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 16 02:33:06 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:33:06 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:33:06 gbvpi-bedroom volumio[22906]: info: Successfully Updated MyVolumio device
Dec 16 02:33:08 gbvpi-bedroom volumio[22906]: info: TidalConnect service stoped!
Dec 16 02:33:08 gbvpi-bedroom volumio[22906]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Dec 16 02:33:08 gbvpi-bedroom volumio[22906]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect
Dec 16 02:33:08 gbvpi-bedroom sudo[23434]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Dec 16 02:33:08 gbvpi-bedroom sudo[23434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 16 02:33:08 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:33:08 gbvpi-bedroom sudo[23437]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Dec 16 02:33:08 gbvpi-bedroom sudo[23437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 16 02:33:08 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:33:08 gbvpi-bedroom systemd[1]: Started vtcs.service - Volumio Tidal Connect Service.
Dec 16 02:33:08 gbvpi-bedroom sudo[23434]: pam_unix(sudo:session): session closed for user root
Dec 16 02:33:08 gbvpi-bedroom systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service...
Dec 16 02:33:08 gbvpi-bedroom systemd[1]: mpd_monitor.service: Deactivated successfully.
Dec 16 02:33:08 gbvpi-bedroom systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service.
Dec 16 02:33:08 gbvpi-bedroom systemd[1]: Started mpd_monitor.service - MPD Monitor Service.
Dec 16 02:33:08 gbvpi-bedroom sudo[23437]: pam_unix(sudo:session): session closed for user root
Dec 16 02:33:08 gbvpi-bedroom volumio[22906]: info: Successfully started MPD Monitor
Dec 16 02:33:08 gbvpi-bedroom mpd_monitor.sh[23442]: MPD Monitor Service: Starting MPD Monitor Service
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: Executing endpoint tc_getconfig
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig
Dec 16 02:33:09 gbvpi-bedroom vtcs[23440]: STARTING TidalConnect services, version: 1.5.2.56
Dec 16 02:33:09 gbvpi-bedroom vtcs[23440]: STARTED TidalConnect services.
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: Executing endpoint tc_connect
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: Connecting to TidalConnect
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::servicePushState
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreStateMachine::pushState
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioPushState
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: MRS: Pushing multiroomSync output update for this device
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: MRS: Pushing multiroomSync output
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CorePlayQueue::getTrack 0
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: 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 16 02:33:09 gbvpi-bedroom volumio[22906]: verbose: CURRENT POSITION 0
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreStateMachine::syncState stateService stop
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreStateMachine::syncState currentStatus stop
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreStateMachine::pushState
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioPushState
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: MRS: Pushing multiroomSync output update for this device
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: MRS: Pushing multiroomSync output
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: No code
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreStateMachine::pushState
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioPushState
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: MRS: Pushing multiroomSync output update for this device
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: MRS: Pushing multiroomSync output
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::servicePushState
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreStateMachine::pushState
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioPushState
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: MRS: Pushing multiroomSync output update for this device
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: MRS: Pushing multiroomSync output
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CorePlayQueue::getTrack 0
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: 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 16 02:33:09 gbvpi-bedroom volumio[22906]: verbose: CURRENT POSITION 0
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreStateMachine::syncState stateService stop
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreStateMachine::syncState currentStatus stop
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreStateMachine::pushState
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioPushState
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: MRS: Pushing multiroomSync output update for this device
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: MRS: Pushing multiroomSync output
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: No code
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreStateMachine::pushState
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioPushState
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: MRS: Pushing multiroomSync output update for this device
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: MRS: Pushing multiroomSync output
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.66712a116082b2e6ca4ffe3109a843e6.state.status'
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.66712a116082b2e6ca4ffe3109a843e6.state.status'
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.66712a116082b2e6ca4ffe3109a843e6.state.status'
Dec 16 02:33:09 gbvpi-bedroom volumio[22906]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.66712a116082b2e6ca4ffe3109a843e6.state.status'
Dec 16 02:33:09 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6.
Dec 16 02:33:09 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:09 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:09 gbvpi-bedroom go-librespot[23460]: go-librespot daemon starting...
Dec 16 02:33:09 gbvpi-bedroom go-librespot[23461]: time="2025-12-16T02:33:09-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:33:09 gbvpi-bedroom go-librespot[23461]: time="2025-12-16T02:33:09-05:00" level=debug msg="app state loaded"
Dec 16 02:33:09 gbvpi-bedroom go-librespot[23461]: time="2025-12-16T02:33:09-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:33:09 gbvpi-bedroom go-librespot[23461]: time="2025-12-16T02:33:09-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 16 02:33:09 gbvpi-bedroom go-librespot[23461]: time="2025-12-16T02:33:09-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 16 02:33:09 gbvpi-bedroom go-librespot[23461]: time="2025-12-16T02:33:09-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 16 02:33:09 gbvpi-bedroom go-librespot[23461]: time="2025-12-16T02:33:09-05:00" level=info msg="zeroconf server listening on port 36419"
Dec 16 02:33:10 gbvpi-bedroom go-librespot[23461]: time="2025-12-16T02:33:10-05:00" level=debug msg="obtained new client token: AAC4OptFF+rY0CUxlJgLKVVozZPSWw61ozditL1OKOWKJvhW6uBU9Lv7cGVCgM5ZsKJUp9pP/Uvwb5u9PXLfZKSpxNuAM9ND9zBW94ADR03VmugUa8TQc2puNPH3+geO0nXW+jZRUcix++p9D8qvcVP/hxMEfT9F75pHy/V/V95yNyrpUeKkCCQ5KfFRAAGaImGkYM7HUzX1w91u3/0Z+OWIJyq0w7euY4qHn9zRgq18OKuy98dVV/M="
Dec 16 02:33:10 gbvpi-bedroom go-librespot[23461]: time="2025-12-16T02:33:10-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:33:10 gbvpi-bedroom go-librespot[23461]: time="2025-12-16T02:33:10-05:00" level=debug msg="completed keyexchange"
Dec 16 02:33:10 gbvpi-bedroom go-librespot[23461]: time="2025-12-16T02:33:10-05:00" level=debug msg="completed challenge"
Dec 16 02:33:10 gbvpi-bedroom go-librespot[23461]: time="2025-12-16T02:33:10-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 16 02:33:10 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:33:10 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:33:11 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:33:11 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:33:11 gbvpi-bedroom volumio[22906]: info: TidalConnect service started!
Dec 16 02:33:11 gbvpi-bedroom volumio[22906]: [Metrics] CommandRouter: 29s 145.66ms
Dec 16 02:33:11 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumiosetStartupVolume
Dec 16 02:33:11 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 16 02:33:11 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 16 02:33:11 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::Close All Modals sent
Dec 16 02:33:11 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::Close All Modals sent
Dec 16 02:33:12 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 16 02:33:12 gbvpi-bedroom volumio[22906]: 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 16 02:33:12 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:33:12 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Dec 16 02:33:12 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Dec 16 02:33:12 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Dec 16 02:33:13 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:33:13 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:33:13 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7.
Dec 16 02:33:13 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:13 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:13 gbvpi-bedroom go-librespot[23471]: go-librespot daemon starting...
Dec 16 02:33:13 gbvpi-bedroom go-librespot[23472]: time="2025-12-16T02:33:13-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:33:13 gbvpi-bedroom go-librespot[23472]: time="2025-12-16T02:33:13-05:00" level=debug msg="app state loaded"
Dec 16 02:33:13 gbvpi-bedroom go-librespot[23472]: time="2025-12-16T02:33:13-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:33:13 gbvpi-bedroom go-librespot[23472]: time="2025-12-16T02:33:13-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 16 02:33:13 gbvpi-bedroom go-librespot[23472]: time="2025-12-16T02:33:13-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 16 02:33:13 gbvpi-bedroom go-librespot[23472]: time="2025-12-16T02:33:13-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 16 02:33:13 gbvpi-bedroom go-librespot[23472]: time="2025-12-16T02:33:13-05:00" level=info msg="zeroconf server listening on port 42513"
Dec 16 02:33:13 gbvpi-bedroom go-librespot[23472]: time="2025-12-16T02:33:13-05:00" level=debug msg="obtained new client token: AAD3qgKnDnQrsCGo3oq21Wkflx8YivN1dh0KBs6t/lLrArnn/MAp40wpbctK0Qst91mmOzwWpufsjq+8saP6YN5KNOasdg25NEcVazLVQFeNlaSv39lrUXZgQJ1zyeASlVyiMJDo4PbTvFxYti3nkkl5MFKAKYPlP5KUjjcFEsFMzesewNDCAC04hQ1BgOAqhWbPo23qqqonesf/jbWOZskQDu5hz6TA+MmwQNLhNM76rjDVGyAOTyERVw=="
Dec 16 02:33:13 gbvpi-bedroom go-librespot[23472]: time="2025-12-16T02:33:13-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:33:13 gbvpi-bedroom go-librespot[23472]: time="2025-12-16T02:33:13-05:00" level=debug msg="completed keyexchange"
Dec 16 02:33:13 gbvpi-bedroom go-librespot[23472]: time="2025-12-16T02:33:13-05:00" level=debug msg="completed challenge"
Dec 16 02:33:13 gbvpi-bedroom go-librespot[23472]: time="2025-12-16T02:33: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 16 02:33:13 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:33:13 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:33:14 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:33:14 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:33:16 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8.
Dec 16 02:33:16 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:17 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:17 gbvpi-bedroom go-librespot[23493]: go-librespot daemon starting...
Dec 16 02:33:17 gbvpi-bedroom go-librespot[23494]: time="2025-12-16T02:33:17-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:33:17 gbvpi-bedroom go-librespot[23494]: time="2025-12-16T02:33:17-05:00" level=debug msg="app state loaded"
Dec 16 02:33:17 gbvpi-bedroom go-librespot[23494]: time="2025-12-16T02:33:17-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:33:17 gbvpi-bedroom go-librespot[23494]: time="2025-12-16T02:33:17-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 16 02:33:17 gbvpi-bedroom go-librespot[23494]: time="2025-12-16T02:33:17-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 16 02:33:17 gbvpi-bedroom go-librespot[23494]: time="2025-12-16T02:33:17-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 16 02:33:17 gbvpi-bedroom go-librespot[23494]: time="2025-12-16T02:33:17-05:00" level=info msg="zeroconf server listening on port 38131"
Dec 16 02:33:17 gbvpi-bedroom go-librespot[23494]: time="2025-12-16T02:33:17-05:00" level=debug msg="obtained new client token: AADAIBT4TkRjOeXLS4r0aStc6hv20HSZhy7dOEADu/6F0ZfIkbvwf+WWTaaaiGo6MG0QjNasxiD/SnQja2hIMDHQd4dVxu4mROSTTFbpeMcHnqjBabaTbPbRS70KxD9uExaxNKrdPV8g+LK4XxOJ3hAOxhxudtMuw12quMD2DhwcB9emTjsxd29Ls8gQmboO0/i36EDARcagbwNLz43gC82YpOcKPKoMlSsVhSvkG3ymwoCPlI41DFH0tQ=="
Dec 16 02:33:17 gbvpi-bedroom go-librespot[23494]: time="2025-12-16T02:33:17-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:33:17 gbvpi-bedroom go-librespot[23494]: time="2025-12-16T02:33:17-05:00" level=debug msg="completed keyexchange"
Dec 16 02:33:17 gbvpi-bedroom go-librespot[23494]: time="2025-12-16T02:33:17-05:00" level=debug msg="completed challenge"
Dec 16 02:33:17 gbvpi-bedroom go-librespot[23494]: time="2025-12-16T02:33: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 16 02:33:17 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:33:17 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:33:17 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 16 02:33:17 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 16 02:33:17 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 16 02:33:17 gbvpi-bedroom volumio-remote-updater[775]: Test mode enabled
Dec 16 02:33:17 gbvpi-bedroom volumio-remote-updater[775]: Alpha mode disabled
Dec 16 02:33:17 gbvpi-bedroom volumio-remote-updater[775]: Alpha legacy test mode disabled
Dec 16 02:33:17 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Dec 16 02:33:17 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 16 02:33:17 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 16 02:33:17 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Dec 16 02:33:17 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Dec 16 02:33:17 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Dec 16 02:33:17 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 16 02:33:17 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 16 02:33:17 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:33:17 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:33:17 gbvpi-bedroom volumio[22906]: 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 16 02:33:17 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Dec 16 02:33:18 gbvpi-bedroom volumio[22906]: info: BOOT COMPLETED
Dec 16 02:33:20 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9.
Dec 16 02:33:20 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:20 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:20 gbvpi-bedroom go-librespot[23505]: go-librespot daemon starting...
Dec 16 02:33:20 gbvpi-bedroom go-librespot[23506]: time="2025-12-16T02:33:20-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:33:20 gbvpi-bedroom go-librespot[23506]: time="2025-12-16T02:33:20-05:00" level=debug msg="app state loaded"
Dec 16 02:33:20 gbvpi-bedroom go-librespot[23506]: time="2025-12-16T02:33:20-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:33:20 gbvpi-bedroom go-librespot[23506]: time="2025-12-16T02:33: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-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 16 02:33:20 gbvpi-bedroom go-librespot[23506]: time="2025-12-16T02:33:20-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 16 02:33:20 gbvpi-bedroom go-librespot[23506]: time="2025-12-16T02:33:20-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 16 02:33:20 gbvpi-bedroom go-librespot[23506]: time="2025-12-16T02:33:20-05:00" level=info msg="zeroconf server listening on port 42901"
Dec 16 02:33:20 gbvpi-bedroom go-librespot[23506]: time="2025-12-16T02:33:20-05:00" level=debug msg="obtained new client token: AAD5i79zxIzRFetQlGRDs5HyWO7DNuZi2N2sswwwDmrd3VCLO/0iSShp13899cCjS4OjECYsKQdC+sypinckeHaw137d2atuwJqiOofY9v3U8a6diSpfsNlhvgF7kT2+zR7mMCYxfjvHbxcFAKha5EsR/TgU7j/88NdSbehrFVS96mnpCmTr3WksTalyC28liuCqdo29tH7hzPLuWj+LsooD41+/FWPJtQlU01KjPQ7+/exa6vwe4K/Clw=="
Dec 16 02:33:20 gbvpi-bedroom go-librespot[23506]: time="2025-12-16T02:33:20-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:33:20 gbvpi-bedroom go-librespot[23506]: time="2025-12-16T02:33:20-05:00" level=debug msg="completed keyexchange"
Dec 16 02:33:20 gbvpi-bedroom go-librespot[23506]: time="2025-12-16T02:33:20-05:00" level=debug msg="completed challenge"
Dec 16 02:33:20 gbvpi-bedroom go-librespot[23506]: time="2025-12-16T02:33: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 16 02:33:20 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:33:20 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:33:20 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:33:20 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:33:23 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:33:23 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:33:23 gbvpi-bedroom volumio[22906]: info: Listing playlists
Dec 16 02:33:23 gbvpi-bedroom volumio[22906]: info: Listing playlists
Dec 16 02:33:23 gbvpi-bedroom volumio[22906]: info: Listing playlists
Dec 16 02:33:23 gbvpi-bedroom volumio[22906]: info: Listing playlists
Dec 16 02:33:23 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:33:23 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:33:23 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10.
Dec 16 02:33:23 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:24 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:24 gbvpi-bedroom go-librespot[23513]: go-librespot daemon starting...
Dec 16 02:33:24 gbvpi-bedroom go-librespot[23514]: time="2025-12-16T02:33:24-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:33:24 gbvpi-bedroom go-librespot[23514]: time="2025-12-16T02:33:24-05:00" level=debug msg="app state loaded"
Dec 16 02:33:24 gbvpi-bedroom go-librespot[23514]: time="2025-12-16T02:33:24-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:33:24 gbvpi-bedroom go-librespot[23514]: time="2025-12-16T02:33: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-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 16 02:33:24 gbvpi-bedroom go-librespot[23514]: time="2025-12-16T02:33:24-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 16 02:33:24 gbvpi-bedroom go-librespot[23514]: time="2025-12-16T02:33:24-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 16 02:33:24 gbvpi-bedroom go-librespot[23514]: time="2025-12-16T02:33:24-05:00" level=info msg="zeroconf server listening on port 46657"
Dec 16 02:33:24 gbvpi-bedroom go-librespot[23514]: time="2025-12-16T02:33:24-05:00" level=debug msg="obtained new client token: AABaK+OcMPFAAdTxSrFc8k6yPP2uCqOvIWR6PSkTcEasDYnoPA0GF4XOUL4l1tU4RcJq/hFrHFZ9cBR4lYYDvCxsiyfeqdlXT1Be4sMZoKuZ+xan+PuKnSjE/EspcyJxQzPV8nYbm7SKDX6m1sCbbtDNxu9+0IO/Bai/JNDu19kdqgrg2VbfELcA4g7TKtIQawSXFSu2K383/ZrgDz0GbQX8CtkCcPpLuAlheYrX6JiFkcuggWZ4xyE="
Dec 16 02:33:24 gbvpi-bedroom go-librespot[23514]: time="2025-12-16T02:33:24-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:33:24 gbvpi-bedroom go-librespot[23514]: time="2025-12-16T02:33:24-05:00" level=debug msg="completed keyexchange"
Dec 16 02:33:24 gbvpi-bedroom go-librespot[23514]: time="2025-12-16T02:33:24-05:00" level=debug msg="completed challenge"
Dec 16 02:33:24 gbvpi-bedroom go-librespot[23514]: time="2025-12-16T02:33: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 16 02:33:24 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:33:24 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:33:26 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:33:26 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:33:27 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11.
Dec 16 02:33:27 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:27 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:27 gbvpi-bedroom go-librespot[23535]: go-librespot daemon starting...
Dec 16 02:33:27 gbvpi-bedroom go-librespot[23536]: time="2025-12-16T02:33:27-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:33:27 gbvpi-bedroom go-librespot[23536]: time="2025-12-16T02:33:27-05:00" level=debug msg="app state loaded"
Dec 16 02:33:27 gbvpi-bedroom go-librespot[23536]: time="2025-12-16T02:33:27-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:33:27 gbvpi-bedroom go-librespot[23536]: time="2025-12-16T02:33: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 16 02:33:27 gbvpi-bedroom go-librespot[23536]: time="2025-12-16T02:33: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 16 02:33:27 gbvpi-bedroom go-librespot[23536]: time="2025-12-16T02:33: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 16 02:33:27 gbvpi-bedroom go-librespot[23536]: time="2025-12-16T02:33:27-05:00" level=info msg="zeroconf server listening on port 42825"
Dec 16 02:33:27 gbvpi-bedroom go-librespot[23536]: time="2025-12-16T02:33:27-05:00" level=debug msg="obtained new client token: AADMKkcckLiSOPbeJXHhNAg/owPVofP2mPyTgDV73ZhQw31mLJuItGOdXCBRduOb/4cgc0GxVwOqKb93iO30/W0ysxjXycSXDdHZbJpPF+ABtvfU7domuTVun1m7lPxphAIU61j+js2G65KmUNDu8wkgOiY1J3xQvwDw/gBv+9BUwni7xmzCkKxv4y7FdOo3Re+z2HtRhqClKrvjV8ER9RhIxcC7mx0wgE1JY4CeqMLXNPQVa7xZcRr0rQ=="
Dec 16 02:33:27 gbvpi-bedroom go-librespot[23536]: time="2025-12-16T02:33:27-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:33:27 gbvpi-bedroom go-librespot[23536]: time="2025-12-16T02:33:27-05:00" level=debug msg="completed keyexchange"
Dec 16 02:33:27 gbvpi-bedroom go-librespot[23536]: time="2025-12-16T02:33:27-05:00" level=debug msg="completed challenge"
Dec 16 02:33:27 gbvpi-bedroom go-librespot[23536]: time="2025-12-16T02:33:27-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 16 02:33:27 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:33:27 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:33:29 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:33:29 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:33:30 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12.
Dec 16 02:33:30 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:31 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:31 gbvpi-bedroom go-librespot[23546]: go-librespot daemon starting...
Dec 16 02:33:31 gbvpi-bedroom go-librespot[23547]: time="2025-12-16T02:33:31-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:33:31 gbvpi-bedroom go-librespot[23547]: time="2025-12-16T02:33:31-05:00" level=debug msg="app state loaded"
Dec 16 02:33:31 gbvpi-bedroom go-librespot[23547]: time="2025-12-16T02:33:31-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:33:31 gbvpi-bedroom go-librespot[23547]: time="2025-12-16T02:33: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-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 16 02:33:31 gbvpi-bedroom go-librespot[23547]: time="2025-12-16T02:33:31-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 16 02:33:31 gbvpi-bedroom go-librespot[23547]: time="2025-12-16T02:33:31-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 16 02:33:31 gbvpi-bedroom go-librespot[23547]: time="2025-12-16T02:33:31-05:00" level=info msg="zeroconf server listening on port 46443"
Dec 16 02:33:31 gbvpi-bedroom go-librespot[23547]: time="2025-12-16T02:33:31-05:00" level=debug msg="obtained new client token: AAAKQhaUrBxQg2RWHGVPzKKz3fB/e2awMg+FVOBqCeAWKavateBDfv4UxPUk9AyaUiTt/Rqqs1WGb+/Z8Dy5unOoOeYu8SQxzMsKeHA8AqvEdAFU/cAiXKk8D/onWiTfwRxirBrxAODwGPHeqyFG9PY37hIwX/NJKpf81eKfT1GijQscG14OwNbXgYoSZWR2uVxMAOx5Wad89Ro12WmQTWl5WbhPea8O1oHtoBmpr9fcjlEgv6x1AVooTA=="
Dec 16 02:33:31 gbvpi-bedroom go-librespot[23547]: time="2025-12-16T02:33:31-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:33:31 gbvpi-bedroom go-librespot[23547]: time="2025-12-16T02:33:31-05:00" level=debug msg="completed keyexchange"
Dec 16 02:33:31 gbvpi-bedroom go-librespot[23547]: time="2025-12-16T02:33:31-05:00" level=debug msg="completed challenge"
Dec 16 02:33:31 gbvpi-bedroom go-librespot[23547]: time="2025-12-16T02:33: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 16 02:33:31 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:33:31 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:33:32 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:33:32 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:33:33 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:33:33 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:33:34 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13.
Dec 16 02:33:34 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:34 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:34 gbvpi-bedroom go-librespot[23569]: go-librespot daemon starting...
Dec 16 02:33:34 gbvpi-bedroom go-librespot[23570]: time="2025-12-16T02:33:34-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:33:34 gbvpi-bedroom go-librespot[23570]: time="2025-12-16T02:33:34-05:00" level=debug msg="app state loaded"
Dec 16 02:33:34 gbvpi-bedroom go-librespot[23570]: time="2025-12-16T02:33:34-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:33:34 gbvpi-bedroom go-librespot[23570]: time="2025-12-16T02:33:34-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 16 02:33:34 gbvpi-bedroom go-librespot[23570]: time="2025-12-16T02:33:34-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 16 02:33:34 gbvpi-bedroom go-librespot[23570]: time="2025-12-16T02:33:34-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 16 02:33:34 gbvpi-bedroom go-librespot[23570]: time="2025-12-16T02:33:34-05:00" level=info msg="zeroconf server listening on port 41941"
Dec 16 02:33:34 gbvpi-bedroom go-librespot[23570]: time="2025-12-16T02:33:34-05:00" level=debug msg="obtained new client token: AABUUgk1mb28YjIn37m0cWNxVfHD1nWiak1BmvJtQHxEWR1TG3trlGZd/kPRIOo9LaIFGQFum4xv3h02aXnTJwFj5wK/KLj1Y5cSVqqHyhXi7/wBA+RujCVy78bd5AP7t5vxWouNp74auyS+xO+NyzMh3vEM7eN4mD3fXN8Mk29UTHYjPwpgEthLAwBebRLIosi2BTZMTHooEY1xhLjPiDiix05DwSWRNJPt0E1QFcfEEdeqU2NZIKwRgQ=="
Dec 16 02:33:34 gbvpi-bedroom go-librespot[23570]: time="2025-12-16T02:33:34-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:33:34 gbvpi-bedroom go-librespot[23570]: time="2025-12-16T02:33:34-05:00" level=debug msg="completed keyexchange"
Dec 16 02:33:34 gbvpi-bedroom go-librespot[23570]: time="2025-12-16T02:33:34-05:00" level=debug msg="completed challenge"
Dec 16 02:33:34 gbvpi-bedroom go-librespot[23570]: time="2025-12-16T02:33:34-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 16 02:33:34 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:33:34 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:33:35 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:33:35 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:33:37 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14.
Dec 16 02:33:37 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:38 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:38 gbvpi-bedroom go-librespot[23577]: go-librespot daemon starting...
Dec 16 02:33:38 gbvpi-bedroom go-librespot[23578]: time="2025-12-16T02:33:38-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:33:38 gbvpi-bedroom go-librespot[23578]: time="2025-12-16T02:33:38-05:00" level=debug msg="app state loaded"
Dec 16 02:33:38 gbvpi-bedroom go-librespot[23578]: time="2025-12-16T02:33:38-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:33:38 gbvpi-bedroom go-librespot[23578]: time="2025-12-16T02:33: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-gew1.spotify.com:80]"
Dec 16 02:33:38 gbvpi-bedroom go-librespot[23578]: time="2025-12-16T02:33:38-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 16 02:33:38 gbvpi-bedroom go-librespot[23578]: time="2025-12-16T02:33:38-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 16 02:33:38 gbvpi-bedroom go-librespot[23578]: time="2025-12-16T02:33:38-05:00" level=info msg="zeroconf server listening on port 40617"
Dec 16 02:33:38 gbvpi-bedroom go-librespot[23578]: time="2025-12-16T02:33:38-05:00" level=debug msg="obtained new client token: AACsMjMSOCDnajlc6kzzbWDbme/ct0zv7HkR+M3XIrg+bTPxHWtZxVfIUgpan1hU9kGIoq216csJ0Q1vFfP/nizEfb1YUSF0KEVHt9iA1fOQ19JJcTh3YR8rCe+xgqWfRL7+b3bLi0itLNlN03E1h50hg1ptr+XFW/dFE453Ccq1moNPtejheFvAlkcFnf4ho1nOfh651A7g0KqkiSpM8+q8Adcf9pKM09PpzMi30tz97Nr8BklH8JNRkA=="
Dec 16 02:33:38 gbvpi-bedroom go-librespot[23578]: time="2025-12-16T02:33:38-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:33:38 gbvpi-bedroom go-librespot[23578]: time="2025-12-16T02:33:38-05:00" level=debug msg="completed keyexchange"
Dec 16 02:33:38 gbvpi-bedroom go-librespot[23578]: time="2025-12-16T02:33:38-05:00" level=debug msg="completed challenge"
Dec 16 02:33:38 gbvpi-bedroom go-librespot[23578]: time="2025-12-16T02:33:38-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 16 02:33:38 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:33:38 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:33:38 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:33:38 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:33:41 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15.
Dec 16 02:33:41 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:41 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:41 gbvpi-bedroom go-librespot[23585]: go-librespot daemon starting...
Dec 16 02:33:41 gbvpi-bedroom go-librespot[23586]: time="2025-12-16T02:33:41-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:33:41 gbvpi-bedroom go-librespot[23586]: time="2025-12-16T02:33:41-05:00" level=debug msg="app state loaded"
Dec 16 02:33:41 gbvpi-bedroom go-librespot[23586]: time="2025-12-16T02:33:41-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:33:41 gbvpi-bedroom go-librespot[23586]: time="2025-12-16T02:33:41-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 16 02:33:41 gbvpi-bedroom go-librespot[23586]: time="2025-12-16T02:33:41-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 16 02:33:41 gbvpi-bedroom go-librespot[23586]: time="2025-12-16T02:33:41-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 16 02:33:41 gbvpi-bedroom go-librespot[23586]: time="2025-12-16T02:33:41-05:00" level=info msg="zeroconf server listening on port 33217"
Dec 16 02:33:41 gbvpi-bedroom go-librespot[23586]: time="2025-12-16T02:33:41-05:00" level=debug msg="obtained new client token: AAAe5j/g2Lm38X6VF3059qMDrt//OYHIJhp/G/ZRcWc+RkqHV9xOx6ysc6xGI0doNQmNds96jvqmYGU/cyUBNA/HOqNGTXVOMN18glf8EDoBG4H7eVczlgE3k/s1+C0DixFb8WQZ2dqtbL/VwxJMFvsZpoxDxEB2luKcMiRXK47a+DZe1V7nevD4NER8yWMIphh2f9YAucDan8ExC7WdHl4BTYyuOGwKnmWjmswos6H+/nQaO3hTYaKILQ=="
Dec 16 02:33:41 gbvpi-bedroom go-librespot[23586]: time="2025-12-16T02:33:41-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:33:41 gbvpi-bedroom go-librespot[23586]: time="2025-12-16T02:33:41-05:00" level=debug msg="completed keyexchange"
Dec 16 02:33:41 gbvpi-bedroom go-librespot[23586]: time="2025-12-16T02:33:41-05:00" level=debug msg="completed challenge"
Dec 16 02:33:41 gbvpi-bedroom go-librespot[23586]: time="2025-12-16T02:33:41-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 16 02:33:41 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:33:41 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:33:41 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:33:41 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:33:43 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:33:43 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:33:43 gbvpi-bedroom volumio[22906]: info: Listing playlists
Dec 16 02:33:43 gbvpi-bedroom volumio[22906]: info: Listing playlists
Dec 16 02:33:43 gbvpi-bedroom volumio[22906]: info: Listing playlists
Dec 16 02:33:43 gbvpi-bedroom volumio[22906]: info: Listing playlists
Dec 16 02:33:44 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:33:44 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:33:44 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16.
Dec 16 02:33:44 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:45 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:45 gbvpi-bedroom go-librespot[23607]: go-librespot daemon starting...
Dec 16 02:33:45 gbvpi-bedroom go-librespot[23608]: time="2025-12-16T02:33:45-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:33:45 gbvpi-bedroom go-librespot[23608]: time="2025-12-16T02:33:45-05:00" level=debug msg="app state loaded"
Dec 16 02:33:45 gbvpi-bedroom go-librespot[23608]: time="2025-12-16T02:33:45-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:33:45 gbvpi-bedroom go-librespot[23608]: time="2025-12-16T02:33:45-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 16 02:33:45 gbvpi-bedroom go-librespot[23608]: time="2025-12-16T02:33:45-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 16 02:33:45 gbvpi-bedroom go-librespot[23608]: time="2025-12-16T02:33:45-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 16 02:33:45 gbvpi-bedroom go-librespot[23608]: time="2025-12-16T02:33:45-05:00" level=info msg="zeroconf server listening on port 43875"
Dec 16 02:33:45 gbvpi-bedroom go-librespot[23608]: time="2025-12-16T02:33:45-05:00" level=debug msg="obtained new client token: AADCm8Zu6E2J35y9BWhP80E8+25hsoNE5Wvs4uHDtCHXbbrhN93l9sCv0OHNA7PWMbQA9/JsaDD+EUkzTcko/G0BWVVPDzW+m/kLJZWQSD9ubvPTA65DT+b+N9c0N+74/IclO3AS3IKgcvLgJL8qO56BMn6DvbojN4bVwm1tAyu2QVFQiDvj7RWD9WWmYNx2hcLCyvUVPWl4gLJjctmzhd35qCV8m/w2RdoIIJUUOV+0oBwqeX36LqFj0A=="
Dec 16 02:33:45 gbvpi-bedroom go-librespot[23608]: time="2025-12-16T02:33:45-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:33:45 gbvpi-bedroom go-librespot[23608]: time="2025-12-16T02:33:45-05:00" level=debug msg="completed keyexchange"
Dec 16 02:33:45 gbvpi-bedroom go-librespot[23608]: time="2025-12-16T02:33:45-05:00" level=debug msg="completed challenge"
Dec 16 02:33:45 gbvpi-bedroom go-librespot[23608]: time="2025-12-16T02:33:45-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 16 02:33:45 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:33:45 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:33:47 gbvpi-bedroom volumio[22906]: info: UPDATER: Scheduling automatic update
Dec 16 02:33:47 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Dec 16 02:33:47 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Dec 16 02:33:47 gbvpi-bedroom volumio[22906]: info: UPDATER: Auto update will take place at: Wed Dec 17 2025 03:20:55 GMT-0500 (Eastern Standard Time)
Dec 16 02:33:47 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:33:47 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:33:48 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17.
Dec 16 02:33:48 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:48 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:48 gbvpi-bedroom go-librespot[23615]: go-librespot daemon starting...
Dec 16 02:33:48 gbvpi-bedroom go-librespot[23616]: time="2025-12-16T02:33:48-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:33:48 gbvpi-bedroom go-librespot[23616]: time="2025-12-16T02:33:48-05:00" level=debug msg="app state loaded"
Dec 16 02:33:48 gbvpi-bedroom go-librespot[23616]: time="2025-12-16T02:33:48-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:33:48 gbvpi-bedroom go-librespot[23616]: time="2025-12-16T02:33:48-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 16 02:33:48 gbvpi-bedroom go-librespot[23616]: time="2025-12-16T02:33:48-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 16 02:33:48 gbvpi-bedroom go-librespot[23616]: time="2025-12-16T02:33:48-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 16 02:33:48 gbvpi-bedroom go-librespot[23616]: time="2025-12-16T02:33:48-05:00" level=info msg="zeroconf server listening on port 42513"
Dec 16 02:33:49 gbvpi-bedroom go-librespot[23616]: time="2025-12-16T02:33:49-05:00" level=debug msg="obtained new client token: AACXXNq6iQKCR37C6FPcidmilzufjok2Hests2d/lrfFMq87Qt7wwQ8z/3EFe4f7TgIUzj8NNSHXrZMBYK4Zkp9ByP/QkTICPgTuTp+p9a3WwSYcwbtz9X1FLk3+akH7xs6nEAOxroqm4IRiKeJ5xTiiSTYmkkcyNdOTiucFVpKzSYype4p3+iLAH751BzXku1TZZKrAHKqWyrnloBXtP78xMyFP87/T8yqSrYusTIWxQy5GXbOuTmA="
Dec 16 02:33:49 gbvpi-bedroom go-librespot[23616]: time="2025-12-16T02:33:49-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:33:49 gbvpi-bedroom go-librespot[23616]: time="2025-12-16T02:33:49-05:00" level=debug msg="completed keyexchange"
Dec 16 02:33:49 gbvpi-bedroom go-librespot[23616]: time="2025-12-16T02:33:49-05:00" level=debug msg="completed challenge"
Dec 16 02:33:49 gbvpi-bedroom go-librespot[23616]: time="2025-12-16T02:33:49-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 16 02:33:49 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:33:49 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:33:50 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:33:50 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:33:52 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18.
Dec 16 02:33:52 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:52 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:52 gbvpi-bedroom go-librespot[23627]: go-librespot daemon starting...
Dec 16 02:33:52 gbvpi-bedroom go-librespot[23628]: time="2025-12-16T02:33:52-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:33:52 gbvpi-bedroom go-librespot[23628]: time="2025-12-16T02:33:52-05:00" level=debug msg="app state loaded"
Dec 16 02:33:52 gbvpi-bedroom go-librespot[23628]: time="2025-12-16T02:33:52-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:33:52 gbvpi-bedroom go-librespot[23628]: time="2025-12-16T02:33:52-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 16 02:33:52 gbvpi-bedroom go-librespot[23628]: time="2025-12-16T02:33:52-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 16 02:33:52 gbvpi-bedroom go-librespot[23628]: time="2025-12-16T02:33:52-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 16 02:33:52 gbvpi-bedroom go-librespot[23628]: time="2025-12-16T02:33:52-05:00" level=info msg="zeroconf server listening on port 39865"
Dec 16 02:33:52 gbvpi-bedroom go-librespot[23628]: time="2025-12-16T02:33:52-05:00" level=debug msg="obtained new client token: AADq0697NNCkO38WHjOVjlLEWFRRraYIlHumm+EWqfnYkD3gfZPbK/Rmlb0Qd9d6nS5CY2DFtpsgCEwvBAVEpIi0FbX3Eo93Lbdn6Vhf7qyL394cSUOnBZWnwZ5AA8WgQLM/RqQinMKvE00jbGFl/dNbCV9PyGY8WOPWwKDYFVcU24V3EGeGW9oYz029VcImnNp0MGxPpTxZs5C/H+G8VhQC2MbgpAuMuR4rIgc3azf6jzvYo6Z7EXMztw=="
Dec 16 02:33:52 gbvpi-bedroom go-librespot[23628]: time="2025-12-16T02:33:52-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:33:52 gbvpi-bedroom go-librespot[23628]: time="2025-12-16T02:33:52-05:00" level=debug msg="completed keyexchange"
Dec 16 02:33:52 gbvpi-bedroom go-librespot[23628]: time="2025-12-16T02:33:52-05:00" level=debug msg="completed challenge"
Dec 16 02:33:52 gbvpi-bedroom go-librespot[23628]: time="2025-12-16T02:33:52-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 16 02:33:52 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:33:52 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:33:53 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:33:53 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:33:53 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:33:53 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:33:55 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19.
Dec 16 02:33:55 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:56 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:56 gbvpi-bedroom go-librespot[23650]: go-librespot daemon starting...
Dec 16 02:33:56 gbvpi-bedroom go-librespot[23651]: time="2025-12-16T02:33:56-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:33:56 gbvpi-bedroom go-librespot[23651]: time="2025-12-16T02:33:56-05:00" level=debug msg="app state loaded"
Dec 16 02:33:56 gbvpi-bedroom go-librespot[23651]: time="2025-12-16T02:33:56-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:33:56 gbvpi-bedroom go-librespot[23651]: time="2025-12-16T02:33:56-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 16 02:33:56 gbvpi-bedroom go-librespot[23651]: time="2025-12-16T02:33:56-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 16 02:33:56 gbvpi-bedroom go-librespot[23651]: time="2025-12-16T02:33:56-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 16 02:33:56 gbvpi-bedroom go-librespot[23651]: time="2025-12-16T02:33:56-05:00" level=info msg="zeroconf server listening on port 35353"
Dec 16 02:33:56 gbvpi-bedroom go-librespot[23651]: time="2025-12-16T02:33:56-05:00" level=debug msg="obtained new client token: AACyoTI8wpzbEoLmH7YPsx/DGtb12V73ocD7LB8RF0g2Uqta5G9DDrK+0hKTMxmmlTSO46nLZOgQ5jeGxJcthYzuBPFhihw84Ds1GP99Y4kJ/ObbWC9+8yhkrpYKAtzLcNpznPBBL8o6V7Dlwk26OdjFH3qlc0THwMwtnDnzYJoklTV9F12KfQg+OLZmeP1Yd0jF73u6lr9xCrXcOsirRItzu9C5KnIWvC2NvT+MoGeGcNTbZ41Yjg5OHw=="
Dec 16 02:33:56 gbvpi-bedroom go-librespot[23651]: time="2025-12-16T02:33:56-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:33:56 gbvpi-bedroom go-librespot[23651]: time="2025-12-16T02:33:56-05:00" level=debug msg="completed keyexchange"
Dec 16 02:33:56 gbvpi-bedroom go-librespot[23651]: time="2025-12-16T02:33:56-05:00" level=debug msg="completed challenge"
Dec 16 02:33:56 gbvpi-bedroom go-librespot[23651]: time="2025-12-16T02:33:56-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 16 02:33:56 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:33:56 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:33:56 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:33:56 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:33:59 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20.
Dec 16 02:33:59 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:59 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:33:59 gbvpi-bedroom go-librespot[23658]: go-librespot daemon starting...
Dec 16 02:33:59 gbvpi-bedroom go-librespot[23659]: time="2025-12-16T02:33:59-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:33:59 gbvpi-bedroom go-librespot[23659]: time="2025-12-16T02:33:59-05:00" level=debug msg="app state loaded"
Dec 16 02:33:59 gbvpi-bedroom go-librespot[23659]: time="2025-12-16T02:33:59-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:33:59 gbvpi-bedroom go-librespot[23659]: time="2025-12-16T02:33:59-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 16 02:33:59 gbvpi-bedroom go-librespot[23659]: time="2025-12-16T02:33:59-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 16 02:33:59 gbvpi-bedroom go-librespot[23659]: time="2025-12-16T02:33:59-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 16 02:33:59 gbvpi-bedroom go-librespot[23659]: time="2025-12-16T02:33:59-05:00" level=info msg="zeroconf server listening on port 44277"
Dec 16 02:33:59 gbvpi-bedroom go-librespot[23659]: time="2025-12-16T02:33:59-05:00" level=debug msg="obtained new client token: AAAUba2tQOWU5wXD9RnV9BRB5kdd/x4BgfkWuYZvTdWlB2k7u7YNP4XPw6yOPm1wCm/YMl2SK8yvD7gMMzGoy5iDSnnrl5bPtM6e3eLxU40ojefU4vM/5UcG3bVl5lhyArsYxRPx/gmoHqhB+bK40OKvJJt2pnjpYPsROLSeN38EhR7+VBV+DONCp5ygyb7BduJlqR7GSgrZm1qJLP5Hx+Vm7kGBvDfa6LHVwRPNvTDoiqKx3YL08x2Cgw=="
Dec 16 02:33:59 gbvpi-bedroom go-librespot[23659]: time="2025-12-16T02:33:59-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:33:59 gbvpi-bedroom go-librespot[23659]: time="2025-12-16T02:33:59-05:00" level=debug msg="completed keyexchange"
Dec 16 02:33:59 gbvpi-bedroom go-librespot[23659]: time="2025-12-16T02:33:59-05:00" level=debug msg="completed challenge"
Dec 16 02:33:59 gbvpi-bedroom go-librespot[23659]: time="2025-12-16T02:33:59-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 16 02:33:59 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:33:59 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:33:59 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:33:59 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:34:02 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21.
Dec 16 02:34:02 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:02 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:34:02 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:34:03 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:03 gbvpi-bedroom go-librespot[23666]: go-librespot daemon starting...
Dec 16 02:34:03 gbvpi-bedroom go-librespot[23667]: time="2025-12-16T02:34:03-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:34:03 gbvpi-bedroom go-librespot[23667]: time="2025-12-16T02:34:03-05:00" level=debug msg="app state loaded"
Dec 16 02:34:03 gbvpi-bedroom go-librespot[23667]: time="2025-12-16T02:34:03-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:34:03 gbvpi-bedroom go-librespot[23667]: time="2025-12-16T02:34:03-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 16 02:34:03 gbvpi-bedroom go-librespot[23667]: time="2025-12-16T02:34:03-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 16 02:34:03 gbvpi-bedroom go-librespot[23667]: time="2025-12-16T02:34:03-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 16 02:34:03 gbvpi-bedroom go-librespot[23667]: time="2025-12-16T02:34:03-05:00" level=info msg="zeroconf server listening on port 33663"
Dec 16 02:34:03 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:34:03 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:34:03 gbvpi-bedroom volumio[22906]: info: Listing playlists
Dec 16 02:34:03 gbvpi-bedroom volumio[22906]: info: Listing playlists
Dec 16 02:34:03 gbvpi-bedroom volumio[22906]: info: Listing playlists
Dec 16 02:34:03 gbvpi-bedroom volumio[22906]: info: Listing playlists
Dec 16 02:34:03 gbvpi-bedroom go-librespot[23667]: time="2025-12-16T02:34:03-05:00" level=debug msg="obtained new client token: AAAymMwEAMUHTDjz4JO1Lzic8J2zT8srn9V0jwSD/XnfksDEDHRH6t2CaXetT4+q0MbdfPmZ9f89Krxlm0CILJPalnklIkaSPNEuRm2yVrFeO/cJPfMbMDrrgG/hNNFPG+7DY2ydjuyEwwoe2EkYXOjnxmhtqfgwOEGjEVTC36eddWpw3IHtDiEjxvwxEeEW/LaQRosukUZgIUN81dDYpHaNZRQtm/aSzjnKDfxfSy2FhIMiAxJSZpaS1g=="
Dec 16 02:34:03 gbvpi-bedroom go-librespot[23667]: time="2025-12-16T02:34:03-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:34:03 gbvpi-bedroom go-librespot[23667]: time="2025-12-16T02:34:03-05:00" level=debug msg="completed keyexchange"
Dec 16 02:34:03 gbvpi-bedroom go-librespot[23667]: time="2025-12-16T02:34:03-05:00" level=debug msg="completed challenge"
Dec 16 02:34:03 gbvpi-bedroom go-librespot[23667]: time="2025-12-16T02:34:03-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 16 02:34:03 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:34:03 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:34:05 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:34:05 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:34:06 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22.
Dec 16 02:34:06 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:06 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:06 gbvpi-bedroom go-librespot[23690]: go-librespot daemon starting...
Dec 16 02:34:06 gbvpi-bedroom go-librespot[23691]: time="2025-12-16T02:34:06-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:34:06 gbvpi-bedroom go-librespot[23691]: time="2025-12-16T02:34:06-05:00" level=debug msg="app state loaded"
Dec 16 02:34:06 gbvpi-bedroom go-librespot[23691]: time="2025-12-16T02:34:06-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:34:06 gbvpi-bedroom go-librespot[23691]: time="2025-12-16T02:34:06-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 16 02:34:06 gbvpi-bedroom go-librespot[23691]: time="2025-12-16T02:34:06-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 16 02:34:06 gbvpi-bedroom go-librespot[23691]: time="2025-12-16T02:34:06-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 16 02:34:06 gbvpi-bedroom go-librespot[23691]: time="2025-12-16T02:34:06-05:00" level=info msg="zeroconf server listening on port 40661"
Dec 16 02:34:06 gbvpi-bedroom go-librespot[23691]: time="2025-12-16T02:34:06-05:00" level=debug msg="obtained new client token: AAD8UUNJ0pPmr5GtyYI4odSIgdMeg52Cw3bux4MfADkabInRgML3o8mP2McO8aPJihCuO77iwYcd6DzILHf/mBWK+7S/7Xk6rVMLtsllbqsAc8TuTeG6gO+bJY6hkUy5M3Zv9y1i6jJvL59ZAsnl12WtFEQoqBlU3mCEMDG0kNicFdPD9KjU+xKqe7lChmqbt+/58kWSnZI6SBwmO8BFrgs5IOiPVRs/iOYAAAK/8/3QrFBxUs72nc+4xg=="
Dec 16 02:34:06 gbvpi-bedroom go-librespot[23691]: time="2025-12-16T02:34:06-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:34:06 gbvpi-bedroom go-librespot[23691]: time="2025-12-16T02:34:06-05:00" level=debug msg="completed keyexchange"
Dec 16 02:34:06 gbvpi-bedroom go-librespot[23691]: time="2025-12-16T02:34:06-05:00" level=debug msg="completed challenge"
Dec 16 02:34:06 gbvpi-bedroom go-librespot[23691]: time="2025-12-16T02:34:06-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 16 02:34:06 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:34:06 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:34:08 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:34:08 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:34:09 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23.
Dec 16 02:34:09 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:10 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:10 gbvpi-bedroom go-librespot[23701]: go-librespot daemon starting...
Dec 16 02:34:10 gbvpi-bedroom go-librespot[23702]: time="2025-12-16T02:34:10-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:34:10 gbvpi-bedroom go-librespot[23702]: time="2025-12-16T02:34:10-05:00" level=debug msg="app state loaded"
Dec 16 02:34:10 gbvpi-bedroom go-librespot[23702]: time="2025-12-16T02:34:10-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:34:10 gbvpi-bedroom go-librespot[23702]: time="2025-12-16T02:34:10-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 16 02:34:10 gbvpi-bedroom go-librespot[23702]: time="2025-12-16T02:34:10-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 16 02:34:10 gbvpi-bedroom go-librespot[23702]: time="2025-12-16T02:34:10-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 16 02:34:10 gbvpi-bedroom go-librespot[23702]: time="2025-12-16T02:34:10-05:00" level=info msg="zeroconf server listening on port 41225"
Dec 16 02:34:10 gbvpi-bedroom go-librespot[23702]: time="2025-12-16T02:34:10-05:00" level=debug msg="obtained new client token: AAAeg7WjMidP8jAWPBUpJMgdmmpNquJJC7EWhd+Xk8I1NwvmM+7bJf9VlPpt8N720FlzlZEDKxipRQS6++fEv8Dyut8KkJRHM9hUczPUtQE4HeQMc0d0KGFqcBoe5wim/ZncvCCNGB3Bd8R6BO3GQJ2dfrYClsaSo74iBG6Z2VspyTCQSz1ixdlQrE6tcyl0+Tm099DPHumBOo3b3rTZT14JpeDAEurObX5huEWz26qI4/RHKZO8o1dI3A=="
Dec 16 02:34:10 gbvpi-bedroom go-librespot[23702]: time="2025-12-16T02:34:10-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:34:10 gbvpi-bedroom go-librespot[23702]: time="2025-12-16T02:34:10-05:00" level=debug msg="completed keyexchange"
Dec 16 02:34:10 gbvpi-bedroom go-librespot[23702]: time="2025-12-16T02:34:10-05:00" level=debug msg="completed challenge"
Dec 16 02:34:10 gbvpi-bedroom go-librespot[23702]: time="2025-12-16T02:34:10-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 16 02:34:10 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:34:10 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:34:11 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:34:11 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:34:13 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:34:13 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:34:13 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24.
Dec 16 02:34:13 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:13 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:13 gbvpi-bedroom go-librespot[23710]: go-librespot daemon starting...
Dec 16 02:34:13 gbvpi-bedroom go-librespot[23711]: time="2025-12-16T02:34:13-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:34:13 gbvpi-bedroom go-librespot[23711]: time="2025-12-16T02:34:13-05:00" level=debug msg="app state loaded"
Dec 16 02:34:13 gbvpi-bedroom go-librespot[23711]: time="2025-12-16T02:34:13-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:34:13 gbvpi-bedroom go-librespot[23711]: time="2025-12-16T02:34:13-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 16 02:34:13 gbvpi-bedroom go-librespot[23711]: time="2025-12-16T02:34:13-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 16 02:34:13 gbvpi-bedroom go-librespot[23711]: time="2025-12-16T02:34:13-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 16 02:34:13 gbvpi-bedroom go-librespot[23711]: time="2025-12-16T02:34:13-05:00" level=info msg="zeroconf server listening on port 40931"
Dec 16 02:34:14 gbvpi-bedroom go-librespot[23711]: time="2025-12-16T02:34:14-05:00" level=debug msg="obtained new client token: AAB2fbLRvlJV+cZU0nSrmMCEpDKfKlAiPlOvhfEZ8M3la2Op+OskHeBCLK4oqIY6ocHx1CBixqof6aVVWkI2mC9z4j4I0wBX7H/V9t2U/2sznUXeYte3XIRS9bqNq2/luL2hmhwvuU3uE9xR5UpM3Q8GznFbvUZW9CSku0daeBbkWdHi09vE8vPGknqjBlEwvZUJaPb3uuKfvTQ1X4BAYzMyxuTZ4xXm6rnDH9H5Q/iz4I5eQFh6CxI="
Dec 16 02:34:14 gbvpi-bedroom go-librespot[23711]: time="2025-12-16T02:34:14-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:34:14 gbvpi-bedroom go-librespot[23711]: time="2025-12-16T02:34:14-05:00" level=debug msg="completed keyexchange"
Dec 16 02:34:14 gbvpi-bedroom go-librespot[23711]: time="2025-12-16T02:34:14-05:00" level=debug msg="completed challenge"
Dec 16 02:34:14 gbvpi-bedroom go-librespot[23711]: time="2025-12-16T02:34:14-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 16 02:34:14 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:34:14 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:34:14 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:34:14 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:34:17 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25.
Dec 16 02:34:17 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:17 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:17 gbvpi-bedroom go-librespot[23732]: go-librespot daemon starting...
Dec 16 02:34:17 gbvpi-bedroom go-librespot[23733]: time="2025-12-16T02:34:17-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:34:17 gbvpi-bedroom go-librespot[23733]: time="2025-12-16T02:34:17-05:00" level=debug msg="app state loaded"
Dec 16 02:34:17 gbvpi-bedroom go-librespot[23733]: time="2025-12-16T02:34:17-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:34:17 gbvpi-bedroom go-librespot[23733]: time="2025-12-16T02:34:17-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 16 02:34:17 gbvpi-bedroom go-librespot[23733]: time="2025-12-16T02:34:17-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 16 02:34:17 gbvpi-bedroom go-librespot[23733]: time="2025-12-16T02:34:17-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 16 02:34:17 gbvpi-bedroom go-librespot[23733]: time="2025-12-16T02:34:17-05:00" level=info msg="zeroconf server listening on port 38511"
Dec 16 02:34:17 gbvpi-bedroom go-librespot[23733]: time="2025-12-16T02:34:17-05:00" level=debug msg="obtained new client token: AAA/qOC1y55fneb+vtrekMbV/rJ+wM9Pdg61k33CgtYhHXGBMTY31uP5sdO/gXpwF9DATxGSAeTK68Lz4rfR455HWJhCqgQHeLHfcGr546ExSXMTKbMWqLPEpOnCImqEjKmu7XOAK7AHk4V2gY5pcmDhprhyyPEQ8IRyjVcrMCcNiFdUkm8DZRjF/eeTGQ4THd5LVb6dnIpKPQct+gW16O1oHYQzhQQZqNBeknb71OcV5Wqtkoxx5bsqBg=="
Dec 16 02:34:17 gbvpi-bedroom go-librespot[23733]: time="2025-12-16T02:34:17-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 16 02:34:17 gbvpi-bedroom go-librespot[23733]: time="2025-12-16T02:34:17-05:00" level=debug msg="connected to ap-gue1.spotify.com:443"
Dec 16 02:34:17 gbvpi-bedroom go-librespot[23733]: time="2025-12-16T02:34:17-05:00" level=debug msg="completed keyexchange"
Dec 16 02:34:17 gbvpi-bedroom go-librespot[23733]: time="2025-12-16T02:34:17-05:00" level=debug msg="completed challenge"
Dec 16 02:34:17 gbvpi-bedroom go-librespot[23733]: time="2025-12-16T02:34: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 16 02:34:17 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:34:17 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:34:17 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:34:17 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:34:20 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26.
Dec 16 02:34:20 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:21 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:34:21 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:34:21 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:21 gbvpi-bedroom go-librespot[23740]: go-librespot daemon starting...
Dec 16 02:34:21 gbvpi-bedroom go-librespot[23741]: time="2025-12-16T02:34:21-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:34:21 gbvpi-bedroom go-librespot[23741]: time="2025-12-16T02:34:21-05:00" level=debug msg="app state loaded"
Dec 16 02:34:21 gbvpi-bedroom go-librespot[23741]: time="2025-12-16T02:34:21-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:34:21 gbvpi-bedroom go-librespot[23741]: time="2025-12-16T02:34:21-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 16 02:34:21 gbvpi-bedroom go-librespot[23741]: time="2025-12-16T02:34:21-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 16 02:34:21 gbvpi-bedroom go-librespot[23741]: time="2025-12-16T02:34:21-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 16 02:34:21 gbvpi-bedroom go-librespot[23741]: time="2025-12-16T02:34:21-05:00" level=info msg="zeroconf server listening on port 40873"
Dec 16 02:34:21 gbvpi-bedroom go-librespot[23741]: time="2025-12-16T02:34:21-05:00" level=debug msg="obtained new client token: AAAiJKr2tKa1c/GUO6MmicVYyCDc7Y1Jx9+2QCgo1p0StlugTzZrXGtLa5ermWUpakGZTTlXq+ksVh4MJbI/bgj4LJ/HAYu/qhR6XUqN+FLHauXdp/YHlUifXz9ECBywJTJFEypZ07P95nj2BTJu8+yy8pqdhNwIYCKqhTKUA3ABp4my5S/it5+88/OFTtSdqfr8fJJs9Z1QCVV92/wYSLwTzhT9FZEzuwRrhG2CwV4SH9+UGqGyy6DVUQ=="
Dec 16 02:34:21 gbvpi-bedroom go-librespot[23741]: time="2025-12-16T02:34:21-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:34:21 gbvpi-bedroom go-librespot[23741]: time="2025-12-16T02:34:21-05:00" level=debug msg="completed keyexchange"
Dec 16 02:34:21 gbvpi-bedroom go-librespot[23741]: time="2025-12-16T02:34:21-05:00" level=debug msg="completed challenge"
Dec 16 02:34:21 gbvpi-bedroom go-librespot[23741]: time="2025-12-16T02:34:21-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 16 02:34:21 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:34:21 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:34:23 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:34:23 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:34:23 gbvpi-bedroom volumio[22906]: info: Listing playlists
Dec 16 02:34:23 gbvpi-bedroom volumio[22906]: info: Listing playlists
Dec 16 02:34:23 gbvpi-bedroom volumio[22906]: info: Listing playlists
Dec 16 02:34:23 gbvpi-bedroom volumio[22906]: info: Listing playlists
Dec 16 02:34:24 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:34:24 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:34:24 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27.
Dec 16 02:34:24 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:24 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:24 gbvpi-bedroom go-librespot[23762]: go-librespot daemon starting...
Dec 16 02:34:24 gbvpi-bedroom go-librespot[23763]: time="2025-12-16T02:34:24-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:34:24 gbvpi-bedroom go-librespot[23763]: time="2025-12-16T02:34:24-05:00" level=debug msg="app state loaded"
Dec 16 02:34:24 gbvpi-bedroom go-librespot[23763]: time="2025-12-16T02:34:24-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:34:24 gbvpi-bedroom go-librespot[23763]: time="2025-12-16T02:34: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-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 16 02:34:24 gbvpi-bedroom go-librespot[23763]: time="2025-12-16T02:34:24-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 16 02:34:24 gbvpi-bedroom go-librespot[23763]: time="2025-12-16T02:34:24-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 16 02:34:24 gbvpi-bedroom go-librespot[23763]: time="2025-12-16T02:34:24-05:00" level=info msg="zeroconf server listening on port 35587"
Dec 16 02:34:24 gbvpi-bedroom go-librespot[23763]: time="2025-12-16T02:34:24-05:00" level=debug msg="obtained new client token: AABl1L38exf4jfk/LxQkakhvFmVc7fPUWjKsHSN7Auht7PAsoW6MhDFKEuH2x+RLsMRt8yb4NWj8KHsQs6lJPJaqs71hGbuQlxBV+ofa9MU7PHxKNWO+fUQM4V46QPVPc36tLLSX1owX8BcdJVCauwUnPowM8LnIUTxjCA5NHrEhFvKtwjsqAYQx49iOtr3g4F3mx+krBTTA2S/UMG8AgKr9C4gMyb2ko3Ti29TX8F9fEkOCCMHEo0TPyQ=="
Dec 16 02:34:24 gbvpi-bedroom go-librespot[23763]: time="2025-12-16T02:34:24-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:34:24 gbvpi-bedroom go-librespot[23763]: time="2025-12-16T02:34:24-05:00" level=debug msg="completed keyexchange"
Dec 16 02:34:24 gbvpi-bedroom go-librespot[23763]: time="2025-12-16T02:34:24-05:00" level=debug msg="completed challenge"
Dec 16 02:34:24 gbvpi-bedroom go-librespot[23763]: time="2025-12-16T02:34: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 16 02:34:24 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:34:24 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:34:27 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:34:27 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:34:27 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28.
Dec 16 02:34:27 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:28 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:28 gbvpi-bedroom go-librespot[23772]: go-librespot daemon starting...
Dec 16 02:34:28 gbvpi-bedroom go-librespot[23773]: time="2025-12-16T02:34:28-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:34:28 gbvpi-bedroom go-librespot[23773]: time="2025-12-16T02:34:28-05:00" level=debug msg="app state loaded"
Dec 16 02:34:28 gbvpi-bedroom go-librespot[23773]: time="2025-12-16T02:34:28-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:34:28 gbvpi-bedroom go-librespot[23773]: time="2025-12-16T02:34:28-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 16 02:34:28 gbvpi-bedroom go-librespot[23773]: time="2025-12-16T02:34:28-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 16 02:34:28 gbvpi-bedroom go-librespot[23773]: time="2025-12-16T02:34:28-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 16 02:34:28 gbvpi-bedroom go-librespot[23773]: time="2025-12-16T02:34:28-05:00" level=info msg="zeroconf server listening on port 33313"
Dec 16 02:34:28 gbvpi-bedroom go-librespot[23773]: time="2025-12-16T02:34:28-05:00" level=debug msg="obtained new client token: AAA+A4O7DtN7gyHHuXYvwgyt0eEgAmg6RAJjlS0O53r5y2HYLVM0JKNmJ6uMg2EfjkgTAmwqR1PXG1AXyO13vlo2Nbf18t13GUXBXdxqAfHEPaAEjXB3WWY8bhULtIY9oSOYkg4q8ufI9lF/mPrLM0aeFYtmjnigCxl3Em5z9pbv10k0j+VJ4fdr23jz47G7pHg5bxdu6LBmJpdbTeqBel27wavI+I3PWM1IHkyR7clMQdBZ5qavG8bekg=="
Dec 16 02:34:28 gbvpi-bedroom go-librespot[23773]: time="2025-12-16T02:34:28-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:34:28 gbvpi-bedroom go-librespot[23773]: time="2025-12-16T02:34:28-05:00" level=debug msg="completed keyexchange"
Dec 16 02:34:28 gbvpi-bedroom go-librespot[23773]: time="2025-12-16T02:34:28-05:00" level=debug msg="completed challenge"
Dec 16 02:34:28 gbvpi-bedroom go-librespot[23773]: time="2025-12-16T02:34: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 16 02:34:28 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:34:28 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:34:30 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:34:30 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:34:31 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29.
Dec 16 02:34:31 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:31 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:31 gbvpi-bedroom go-librespot[23783]: go-librespot daemon starting...
Dec 16 02:34:31 gbvpi-bedroom go-librespot[23784]: time="2025-12-16T02:34:31-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:34:31 gbvpi-bedroom go-librespot[23784]: time="2025-12-16T02:34:31-05:00" level=debug msg="app state loaded"
Dec 16 02:34:31 gbvpi-bedroom go-librespot[23784]: time="2025-12-16T02:34:31-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:34:31 gbvpi-bedroom go-librespot[23784]: time="2025-12-16T02:34: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-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 16 02:34:31 gbvpi-bedroom go-librespot[23784]: time="2025-12-16T02:34:31-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 16 02:34:31 gbvpi-bedroom go-librespot[23784]: time="2025-12-16T02:34:31-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 16 02:34:31 gbvpi-bedroom go-librespot[23784]: time="2025-12-16T02:34:31-05:00" level=info msg="zeroconf server listening on port 39023"
Dec 16 02:34:32 gbvpi-bedroom go-librespot[23784]: time="2025-12-16T02:34:32-05:00" level=debug msg="obtained new client token: AABl+hF7O0j3aAYi7u84/npqBVFAxz6GDdDvgLb+ezqW3WiOt5at4JnrqES3xfJYfICwVIofxbfoOz084z0p7sMz0E30b4IpGzp2Ra35yMeBDR3sGaQsIVU+b3mjwLsCSOaHYJMpeElBFO52+iRxmxFEdDGYc4eFfE8ejFLTx8ZyTrT0k6rPc0q9u4Exb/KJYQbN6GG31phg9x2B2DhVOpCd27qkZsfBIeGiTjX6cdZGWwDjpKxGZTo="
Dec 16 02:34:32 gbvpi-bedroom go-librespot[23784]: time="2025-12-16T02:34:32-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:34:32 gbvpi-bedroom go-librespot[23784]: time="2025-12-16T02:34:32-05:00" level=debug msg="completed keyexchange"
Dec 16 02:34:32 gbvpi-bedroom go-librespot[23784]: time="2025-12-16T02:34:32-05:00" level=debug msg="completed challenge"
Dec 16 02:34:32 gbvpi-bedroom go-librespot[23784]: time="2025-12-16T02:34:32-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 16 02:34:32 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:34:32 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:34:33 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:34:33 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:34:33 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:34:33 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:34:35 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30.
Dec 16 02:34:35 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:35 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:35 gbvpi-bedroom go-librespot[23805]: go-librespot daemon starting...
Dec 16 02:34:35 gbvpi-bedroom go-librespot[23806]: time="2025-12-16T02:34:35-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:34:35 gbvpi-bedroom go-librespot[23806]: time="2025-12-16T02:34:35-05:00" level=debug msg="app state loaded"
Dec 16 02:34:35 gbvpi-bedroom go-librespot[23806]: time="2025-12-16T02:34:35-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:34:35 gbvpi-bedroom go-librespot[23806]: time="2025-12-16T02:34: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-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 16 02:34:35 gbvpi-bedroom go-librespot[23806]: time="2025-12-16T02:34:35-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 16 02:34:35 gbvpi-bedroom go-librespot[23806]: time="2025-12-16T02:34:35-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 16 02:34:35 gbvpi-bedroom go-librespot[23806]: time="2025-12-16T02:34:35-05:00" level=info msg="zeroconf server listening on port 42697"
Dec 16 02:34:35 gbvpi-bedroom go-librespot[23806]: time="2025-12-16T02:34:35-05:00" level=debug msg="obtained new client token: AAAkUsM8lMpMegSEPRsLpGujCIc0fxbYrA/86VhbU6O5NsTOHQrrEJX++Rf3NZOtjNEtfmlin8mXCrjOoFVlP1Kzj1NOqmiRkvPKcGJ9RlI3o7wzJop6UM6W4ywMaIvoIe4b+AYqgsMObcyC+gMBsZGpKtq6zgaoBGp09nuUrAfBFgKF3ZWvK2AUBf0n7h6GMU/sqh4GstkV4hmjdd+PLk0ei+QtqPspUADL1v2wwDohL/uzWZI6hlXARA=="
Dec 16 02:34:35 gbvpi-bedroom go-librespot[23806]: time="2025-12-16T02:34:35-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:34:35 gbvpi-bedroom go-librespot[23806]: time="2025-12-16T02:34:35-05:00" level=debug msg="completed keyexchange"
Dec 16 02:34:35 gbvpi-bedroom go-librespot[23806]: time="2025-12-16T02:34:35-05:00" level=debug msg="completed challenge"
Dec 16 02:34:35 gbvpi-bedroom go-librespot[23806]: time="2025-12-16T02:34: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 16 02:34:35 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:34:35 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:34:36 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:34:36 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:34:38 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31.
Dec 16 02:34:38 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:39 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:34:39 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:34:39 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:39 gbvpi-bedroom go-librespot[23813]: go-librespot daemon starting...
Dec 16 02:34:39 gbvpi-bedroom go-librespot[23814]: time="2025-12-16T02:34:39-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:34:39 gbvpi-bedroom go-librespot[23814]: time="2025-12-16T02:34:39-05:00" level=debug msg="app state loaded"
Dec 16 02:34:39 gbvpi-bedroom go-librespot[23814]: time="2025-12-16T02:34:39-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:34:39 gbvpi-bedroom go-librespot[23814]: time="2025-12-16T02:34:39-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 16 02:34:39 gbvpi-bedroom go-librespot[23814]: time="2025-12-16T02:34:39-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 16 02:34:39 gbvpi-bedroom go-librespot[23814]: time="2025-12-16T02:34:39-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 16 02:34:39 gbvpi-bedroom go-librespot[23814]: time="2025-12-16T02:34:39-05:00" level=info msg="zeroconf server listening on port 34905"
Dec 16 02:34:39 gbvpi-bedroom go-librespot[23814]: time="2025-12-16T02:34:39-05:00" level=debug msg="obtained new client token: AADWoOycppg3sv3L1HCrPNekg3jPcH4/YEBF31CcufnJt9lNXVP+sj0+pufv5wLXB0FHpMWHR04s3AvSoHWCI3YlqtdozGJ7gJXj+ayZmsxJp6+mR467gfadORAIklRBUU4ily5ImidY8lsAhOwBWeBAlJ4f6ILY9QxCtEzxy8RgRQA5y1jbte3LGO56N5EhhMYRHQzwPkNmhetDpenA7MdAhk6r9SNIi1Isag1TytSDQG+SEkDyZThVzw=="
Dec 16 02:34:39 gbvpi-bedroom go-librespot[23814]: time="2025-12-16T02:34:39-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 16 02:34:39 gbvpi-bedroom go-librespot[23814]: time="2025-12-16T02:34:39-05:00" level=debug msg="connected to ap-gue1.spotify.com:443"
Dec 16 02:34:39 gbvpi-bedroom go-librespot[23814]: time="2025-12-16T02:34:39-05:00" level=debug msg="completed keyexchange"
Dec 16 02:34:39 gbvpi-bedroom go-librespot[23814]: time="2025-12-16T02:34:39-05:00" level=debug msg="completed challenge"
Dec 16 02:34:39 gbvpi-bedroom go-librespot[23814]: time="2025-12-16T02:34: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 16 02:34:39 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:34:39 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:34:42 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:34:42 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:34:42 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32.
Dec 16 02:34:42 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:42 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:42 gbvpi-bedroom go-librespot[23823]: go-librespot daemon starting...
Dec 16 02:34:42 gbvpi-bedroom go-librespot[23824]: time="2025-12-16T02:34:42-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:34:42 gbvpi-bedroom go-librespot[23824]: time="2025-12-16T02:34:42-05:00" level=debug msg="app state loaded"
Dec 16 02:34:42 gbvpi-bedroom go-librespot[23824]: time="2025-12-16T02:34:42-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:34:42 gbvpi-bedroom go-librespot[23824]: time="2025-12-16T02:34: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-gew4.spotify.com:80]"
Dec 16 02:34:42 gbvpi-bedroom go-librespot[23824]: time="2025-12-16T02:34:42-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 16 02:34:42 gbvpi-bedroom go-librespot[23824]: time="2025-12-16T02:34:42-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 16 02:34:42 gbvpi-bedroom go-librespot[23824]: time="2025-12-16T02:34:42-05:00" level=info msg="zeroconf server listening on port 36771"
Dec 16 02:34:43 gbvpi-bedroom go-librespot[23824]: time="2025-12-16T02:34:43-05:00" level=debug msg="obtained new client token: AABlD9UcC8egACiTYCUELFX/QeAXHZIxeNjZZaGyv4RN258BKFwXF4gip2uDpznGRLp1Ba0b6Gbxyf6zks+jCEOpFkotmJw2CLdbuQAOKfYlZY1MHp6rD/oeLT6e6bzihfijr26QmJ7JO7E35Mm5/ls+KTZRf1N0spagean9WK9g4x1UH4PSp95baBaxHmbdClKoC0BV6H6dMaDLxIybTCESi96vxHXH4q8537AQ9Q6m18ENvyUEvOA="
Dec 16 02:34:43 gbvpi-bedroom go-librespot[23824]: time="2025-12-16T02:34:43-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:34:43 gbvpi-bedroom go-librespot[23824]: time="2025-12-16T02:34:43-05:00" level=debug msg="completed keyexchange"
Dec 16 02:34:43 gbvpi-bedroom go-librespot[23824]: time="2025-12-16T02:34:43-05:00" level=debug msg="completed challenge"
Dec 16 02:34:43 gbvpi-bedroom go-librespot[23824]: time="2025-12-16T02:34: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 16 02:34:43 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:34:43 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:34:43 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:34:43 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:34:43 gbvpi-bedroom volumio[22906]: info: Listing playlists
Dec 16 02:34:43 gbvpi-bedroom volumio[22906]: info: Listing playlists
Dec 16 02:34:43 gbvpi-bedroom volumio[22906]: info: Listing playlists
Dec 16 02:34:43 gbvpi-bedroom volumio[22906]: info: Listing playlists
Dec 16 02:34:45 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:34:45 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:34:46 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33.
Dec 16 02:34:46 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:46 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:46 gbvpi-bedroom go-librespot[23845]: go-librespot daemon starting...
Dec 16 02:34:46 gbvpi-bedroom go-librespot[23846]: time="2025-12-16T02:34:46-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:34:46 gbvpi-bedroom go-librespot[23846]: time="2025-12-16T02:34:46-05:00" level=debug msg="app state loaded"
Dec 16 02:34:46 gbvpi-bedroom go-librespot[23846]: time="2025-12-16T02:34:46-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:34:46 gbvpi-bedroom go-librespot[23846]: time="2025-12-16T02:34: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 16 02:34:46 gbvpi-bedroom go-librespot[23846]: time="2025-12-16T02:34:46-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 16 02:34:46 gbvpi-bedroom go-librespot[23846]: time="2025-12-16T02:34:46-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 16 02:34:46 gbvpi-bedroom go-librespot[23846]: time="2025-12-16T02:34:46-05:00" level=info msg="zeroconf server listening on port 44723"
Dec 16 02:34:46 gbvpi-bedroom go-librespot[23846]: time="2025-12-16T02:34:46-05:00" level=debug msg="obtained new client token: AABz73TWZxPHUzLDR9iQ44/KkcX1ELlCuZla0XvDD9Fql2owZXqyEzsZZHVrmw3cT4jrXcbKZtf4sUdOdeCwGDfPlA3v6BSIibIK/SlEy7v2AbE+/3ze2eOo8WO5kwVrppKp9kp2PurrEfqtQgSg3kogtO3Rp4UWeJA1rpMTsDLTaVhxePz/9JeqbGhiCHea/niiiGjyTxXpNuAFGEznnr/hPlvrpFpaVAvv8Kd/nzZSOML3sv6KcF+36Q=="
Dec 16 02:34:46 gbvpi-bedroom go-librespot[23846]: time="2025-12-16T02:34:46-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:34:46 gbvpi-bedroom go-librespot[23846]: time="2025-12-16T02:34:46-05:00" level=debug msg="completed keyexchange"
Dec 16 02:34:46 gbvpi-bedroom go-librespot[23846]: time="2025-12-16T02:34:46-05:00" level=debug msg="completed challenge"
Dec 16 02:34:46 gbvpi-bedroom go-librespot[23846]: time="2025-12-16T02:34: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 16 02:34:46 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:34:46 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:34:48 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:34:48 gbvpi-bedroom volumio[22906]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:34:49 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34.
Dec 16 02:34:49 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:49 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 16 02:34:49 gbvpi-bedroom go-librespot[23856]: go-librespot daemon starting...
Dec 16 02:34:49 gbvpi-bedroom go-librespot[23857]: time="2025-12-16T02:34:49-05:00" level=info msg="running go-librespot 0.4.0"
Dec 16 02:34:49 gbvpi-bedroom go-librespot[23857]: time="2025-12-16T02:34:49-05:00" level=debug msg="app state loaded"
Dec 16 02:34:49 gbvpi-bedroom go-librespot[23857]: time="2025-12-16T02:34:49-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 16 02:34:49 gbvpi-bedroom go-librespot[23857]: time="2025-12-16T02:34:49-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 16 02:34:49 gbvpi-bedroom go-librespot[23857]: time="2025-12-16T02:34:49-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 16 02:34:49 gbvpi-bedroom go-librespot[23857]: time="2025-12-16T02:34:49-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 16 02:34:50 gbvpi-bedroom go-librespot[23857]: time="2025-12-16T02:34:50-05:00" level=info msg="zeroconf server listening on port 45609"
Dec 16 02:34:50 gbvpi-bedroom go-librespot[23857]: time="2025-12-16T02:34:50-05:00" level=debug msg="obtained new client token: AAAuRq1Ak6HU0Qjw8t8NNB/z+JVqn87aumvQaoOk5KsQYeworDMHVt0883SauJOkQcE7COLNxwKdgMnfzKx7hYE4mpifvOslsV4r1Nj6XOJX23Ri9OW+eyZTRU5vl/Xcwd07O43NYTgS/5l0IwEKO2PB5YPfMdxyCBGsEOCI6qiIjdKbw120zfIr3AT7Q5K1CVg+1CcUsIluNfdEKQo4tnu6eqPL7jYjCSjks7ryBoGGSDuiykDtL88="
Dec 16 02:34:50 gbvpi-bedroom go-librespot[23857]: time="2025-12-16T02:34:50-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 16 02:34:51 gbvpi-bedroom volumio[22906]: info: Initializing connection to go-librespot Websocket
Dec 16 02:34:51 gbvpi-bedroom go-librespot[23857]: time="2025-12-16T02:34:51-05:00" level=debug msg="new websocket client"
Dec 16 02:34:51 gbvpi-bedroom volumio[22906]: info: Connection to go-librespot Websocket established
Dec 16 02:34:52 gbvpi-bedroom go-librespot[23857]: time="2025-12-16T02:34:52-05:00" level=debug msg="completed keyexchange"
Dec 16 02:34:52 gbvpi-bedroom go-librespot[23857]: time="2025-12-16T02:34:52-05:00" level=debug msg="completed challenge"
Dec 16 02:34:52 gbvpi-bedroom go-librespot[23857]: time="2025-12-16T02:34:52-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 16 02:34:52 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 02:34:52 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 16 02:34:52 gbvpi-bedroom volumio[22906]: info: Connection to go-librespot Websocket closed
Dec 16 02:34:53 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:34:53 gbvpi-bedroom volumio[22906]: info: CoreCommandRouter::volumioGetState
Dec 16 02:34:54 gbvpi-bedroom volumio[22906]: info: Getting Spotify volume
Dec 16 02:34:54 gbvpi-bedroom volumio[22906]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 16 02:34:54 gbvpi-bedroom volumio[22906]: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 16 02:34:54 gbvpi-bedroom volumio[22906]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Dec 16 02:34:54 gbvpi-bedroom volumio[22906]: errno: -111,
Dec 16 02:34:54 gbvpi-bedroom volumio[22906]: code: 'ECONNREFUSED',
Dec 16 02:34:54 gbvpi-bedroom volumio[22906]: syscall: 'connect',
Dec 16 02:34:54 gbvpi-bedroom volumio[22906]: address: '127.0.0.1',
Dec 16 02:34:54 gbvpi-bedroom volumio[22906]: port: 9879,
Dec 16 02:34:54 gbvpi-bedroom volumio[22906]: response: undefined
Dec 16 02:34:54 gbvpi-bedroom volumio[22906]: }
Dec 16 02:34:54 gbvpi-bedroom volumio[22906]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 16 02:34:54 gbvpi-bedroom sudo[23893]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-16 02:33'
Dec 16 02:34:54 gbvpi-bedroom sudo[23893]: 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"