Dec 07 01:31:00 northpi-soundstation go-librespot[7902]: time="2025-12-07T01:31:00-05:00" level=debug msg="obtained new client token: AADe7ulGBOhz77bBNbR9v7uii0eZzlzKd8HnfudSqEvl9ZGoZuHVz2V/iPFZMPbpdG4ZfNR+Luu5Qfet41tlvDYqvKSI5Y63g/GrYUqFK7fUGTJd8dFZhj+cleUHVFIylZFoy47ffNWzZ6lVKPY8qorYPIxRrMhkFnrveeZyDb+bmtjZ9HcwLygRNpQtHIXXspeDuW0OoO29qx90AKnEZX/Hnj8T3VlmPEZJTCC3+Og+St4a4AjQhg8="
Dec 07 01:31:00 northpi-soundstation systemd[1]: Started mpd_monitor.service - MPD Monitor Service.
Dec 07 01:31:00 northpi-soundstation sudo[7910]: pam_unix(sudo:session): session closed for user root
Dec 07 01:31:00 northpi-soundstation volumio[7638]: info: Successfully started MPD Monitor
Dec 07 01:31:00 northpi-soundstation mpd_monitor.sh[7915]: MPD Monitor Service: Starting MPD Monitor Service
Dec 07 01:31:00 northpi-soundstation go-librespot[7902]: time="2025-12-07T01:31:00-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 01:31:00 northpi-soundstation systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service...
Dec 07 01:31:00 northpi-soundstation systemd[1]: mpd_monitor.service: Deactivated successfully.
Dec 07 01:31:00 northpi-soundstation systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service.
Dec 07 01:31:00 northpi-soundstation systemd[1]: Started mpd_monitor.service - MPD Monitor Service.
Dec 07 01:31:00 northpi-soundstation sudo[7912]: pam_unix(sudo:session): session closed for user root
Dec 07 01:31:00 northpi-soundstation volumio[7638]: info: Successfully started MPD Monitor
Dec 07 01:31:00 northpi-soundstation mpd_monitor.sh[7918]: MPD Monitor Service: Starting MPD Monitor Service
Dec 07 01:31:00 northpi-soundstation go-librespot[7902]: time="2025-12-07T01:31:00-05:00" level=debug msg="completed keyexchange"
Dec 07 01:31:00 northpi-soundstation go-librespot[7902]: time="2025-12-07T01:31:00-05:00" level=debug msg="completed challenge"
Dec 07 01:31:00 northpi-soundstation go-librespot[7902]: time="2025-12-07T01:31:00-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 07 01:31:00 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 01:31:00 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 01:31:00 northpi-soundstation systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 2.
Dec 07 01:31:00 northpi-soundstation systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service.
Dec 07 01:31:00 northpi-soundstation systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service.
Dec 07 01:31:01 northpi-soundstation volumio[7638]: info: No valid Plugin REST Endpoint
Dec 07 01:31:01 northpi-soundstation qobuz-connect[7922]: 20251207 01:31:01.002 [7922.7922] ERROR SampleApp: api request was not successful
Dec 07 01:31:01 northpi-soundstation qobuz-connect[7922]: Error: Application ID was not specified!
Dec 07 01:31:01 northpi-soundstation qobuz-connect[7922]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...]
Dec 07 01:31:01 northpi-soundstation qobuz-connect[7922]: -m|--manufacturer: Manufacturer of the device
Dec 07 01:31:01 northpi-soundstation qobuz-connect[7922]: -n|--deviceName: Device name
Dec 07 01:31:01 northpi-soundstation qobuz-connect[7922]: -l|--model: Device's model name
Dec 07 01:31:01 northpi-soundstation qobuz-connect[7922]: -s|--serialNumber: Device's serial number
Dec 07 01:31:01 northpi-soundstation qobuz-connect[7922]: -o|--outputDevice: ALSA PCM device to play audio to
Dec 07 01:31:01 northpi-soundstation qobuz-connect[7922]: -p|--port: Port to run the local configuration HTTP server on
Dec 07 01:31:01 northpi-soundstation qobuz-connect[7922]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3]
Dec 07 01:31:01 northpi-soundstation qobuz-connect[7922]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control
Dec 07 01:31:01 northpi-soundstation qobuz-connect[7922]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control
Dec 07 01:31:01 northpi-soundstation qobuz-connect[7922]: -h|--help: Print this help
Dec 07 01:31:01 northpi-soundstation systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 01:31:01 northpi-soundstation systemd[1]: qobuz-connect.service: Failed with result 'exit-code'.
Dec 07 01:31:01 northpi-soundstation sudo[7939]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Dec 07 01:31:01 northpi-soundstation sudo[7939]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 01:31:01 northpi-soundstation sudo[7940]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 07 01:31:01 northpi-soundstation sudo[7940]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 01:31:01 northpi-soundstation sudo[7939]: pam_unix(sudo:session): session closed for user root
Dec 07 01:31:01 northpi-soundstation sudo[7940]: pam_unix(sudo:session): session closed for user root
Dec 07 01:31:01 northpi-soundstation sudo[7944]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Dec 07 01:31:01 northpi-soundstation sudo[7944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 01:31:01 northpi-soundstation sudo[7944]: pam_unix(sudo:session): session closed for user root
Dec 07 01:31:01 northpi-soundstation volumio[7638]: info: Upmpdcli Daemon Started
Dec 07 01:31:01 northpi-soundstation volumio[7638]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Dec 07 01:31:02 northpi-soundstation volumio[7638]: info: Initializing connection to go-librespot Websocket
Dec 07 01:31:02 northpi-soundstation volumio[7638]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 01:31:02 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioGetState
Dec 07 01:31:02 northpi-soundstation volumio[7638]: info: CorePlayQueue::getTrack 0
Dec 07 01:31:03 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 254.
Dec 07 01:31:03 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:03 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:03 northpi-soundstation go-librespot[7946]: go-librespot daemon starting...
Dec 07 01:31:03 northpi-soundstation go-librespot[7947]: time="2025-12-07T01:31:03-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 01:31:03 northpi-soundstation go-librespot[7947]: time="2025-12-07T01:31:03-05:00" level=debug msg="app state loaded"
Dec 07 01:31:03 northpi-soundstation go-librespot[7947]: time="2025-12-07T01:31:03-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 01:31:03 northpi-soundstation go-librespot[7947]: time="2025-12-07T01:31: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-gae2.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 07 01:31:03 northpi-soundstation go-librespot[7947]: time="2025-12-07T01:31:03-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 07 01:31:03 northpi-soundstation go-librespot[7947]: time="2025-12-07T01:31:03-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 07 01:31:03 northpi-soundstation go-librespot[7947]: time="2025-12-07T01:31:03-05:00" level=info msg="zeroconf server listening on port 42431"
Dec 07 01:31:03 northpi-soundstation go-librespot[7947]: time="2025-12-07T01:31:03-05:00" level=debug msg="obtained new client token: AADlxqtCSPvtcLWdm5tJ6m7EIwyoqAousR6obHWDvfRN2YkYrDY1uiQe+ndMAL50jYlDRqaS8S18kifc3jKBV0oQ0E0vYLcJ1M0Kn64XmSyr81e/TEUq19EQc6vpA055pUi6T7Rr1W13KG/mUv8kEKp7HWM4USpKrMi9IA5ZYmhV4bKgjBY+Rb5oSs3d/dOffg5a1Xn3DAomCfTqfboTpAOBo5IA+nSic47KKN/eiIlPdth14hLfTN/ewg=="
Dec 07 01:31:03 northpi-soundstation go-librespot[7947]: time="2025-12-07T01:31:03-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 01:31:03 northpi-soundstation go-librespot[7947]: time="2025-12-07T01:31:03-05:00" level=debug msg="completed keyexchange"
Dec 07 01:31:03 northpi-soundstation go-librespot[7947]: time="2025-12-07T01:31:03-05:00" level=debug msg="completed challenge"
Dec 07 01:31:03 northpi-soundstation go-librespot[7947]: time="2025-12-07T01:31: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 07 01:31:03 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 01:31:03 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: Initializing connection to go-librespot Websocket
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: Adding plugin bluetooth to MyMusic Plugins
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: Adding plugin multiroom to MyMusic Plugins
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: Adding plugin metavolumio to MyMusic Plugins
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: Adding plugin cd_controller to MyMusic Plugins
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: Adding plugin qobuzconnect to MyMusic Plugins
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: Adding plugin smart_inputs to MyMusic Plugins
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: Adding plugin tidalconnect to MyMusic Plugins
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: Starting MyVolumio Remote Streaming Endpoints
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: MyVolumio login type: Token
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Dec 07 01:31:05 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Dec 07 01:31:06 northpi-soundstation systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 3.
Dec 07 01:31:06 northpi-soundstation systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service.
Dec 07 01:31:06 northpi-soundstation systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service.
Dec 07 01:31:06 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Dec 07 01:31:06 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Dec 07 01:31:06 northpi-soundstation volumio[7638]: info: Streaming services startup
Dec 07 01:31:06 northpi-soundstation volumio[7638]: info: Starting Streaming Daemon
Dec 07 01:31:06 northpi-soundstation sudo[7956]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 07 01:31:06 northpi-soundstation sudo[7956]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 01:31:06 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Dec 07 01:31:06 northpi-soundstation sudo[7956]: pam_unix(sudo:session): session closed for user root
Dec 07 01:31:06 northpi-soundstation volumio[7638]: error: Cannot start Volumio Streaming Daemon
Dec 07 01:31:06 northpi-soundstation volumio[7638]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 07 01:31:06 northpi-soundstation volumio[7638]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 07 01:31:06 northpi-soundstation volumio[7638]: info: No valid Plugin REST Endpoint
Dec 07 01:31:06 northpi-soundstation qobuz-connect[7954]: 20251207 01:31:06.363 [7954.7954] ERROR SampleApp: api request was not successful
Dec 07 01:31:06 northpi-soundstation qobuz-connect[7954]: Error: Application ID was not specified!
Dec 07 01:31:06 northpi-soundstation qobuz-connect[7954]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...]
Dec 07 01:31:06 northpi-soundstation qobuz-connect[7954]: -m|--manufacturer: Manufacturer of the device
Dec 07 01:31:06 northpi-soundstation qobuz-connect[7954]: -n|--deviceName: Device name
Dec 07 01:31:06 northpi-soundstation qobuz-connect[7954]: -l|--model: Device's model name
Dec 07 01:31:06 northpi-soundstation qobuz-connect[7954]: -s|--serialNumber: Device's serial number
Dec 07 01:31:06 northpi-soundstation qobuz-connect[7954]: -o|--outputDevice: ALSA PCM device to play audio to
Dec 07 01:31:06 northpi-soundstation qobuz-connect[7954]: -p|--port: Port to run the local configuration HTTP server on
Dec 07 01:31:06 northpi-soundstation qobuz-connect[7954]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3]
Dec 07 01:31:06 northpi-soundstation qobuz-connect[7954]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control
Dec 07 01:31:06 northpi-soundstation qobuz-connect[7954]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control
Dec 07 01:31:06 northpi-soundstation qobuz-connect[7954]: -h|--help: Print this help
Dec 07 01:31:06 northpi-soundstation systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 01:31:06 northpi-soundstation systemd[1]: qobuz-connect.service: Failed with result 'exit-code'.
Dec 07 01:31:06 northpi-soundstation volumio[7638]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Dec 07 01:31:06 northpi-soundstation volumio[7638]: info: MyVolumio token set successfully
Dec 07 01:31:06 northpi-soundstation volumio[7638]: info: MYVOLUMIO: Adding device
Dec 07 01:31:06 northpi-soundstation volumio[7638]: info: MYVOLUMIO: Evaluating Server
Dec 07 01:31:06 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 255.
Dec 07 01:31:06 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:06 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:06 northpi-soundstation go-librespot[7966]: go-librespot daemon starting...
Dec 07 01:31:07 northpi-soundstation go-librespot[7971]: time="2025-12-07T01:31:07-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 01:31:07 northpi-soundstation go-librespot[7971]: time="2025-12-07T01:31:07-05:00" level=debug msg="app state loaded"
Dec 07 01:31:07 northpi-soundstation go-librespot[7971]: time="2025-12-07T01:31:07-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 01:31:07 northpi-soundstation volumio[7638]: info: MyVolumio Plan changed: premium
Dec 07 01:31:07 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Subscribed plan changed to premium
Dec 07 01:31:07 northpi-soundstation volumio[7638]: info: Removing browser output: myVolumio user plan is not superstar
Dec 07 01:31:07 northpi-soundstation volumio[7638]: info: Removing audio output:
Dec 07 01:31:07 northpi-soundstation volumio[7638]: info: MYVOLUMIO: Adding device
Dec 07 01:31:07 northpi-soundstation volumio[7638]: info: MYVOLUMIO: Evaluating Server
Dec 07 01:31:07 northpi-soundstation volumio[7638]: info: Remote config written successfully
Dec 07 01:31:07 northpi-soundstation volumio[7638]: info: Starting Tunnel 1
Dec 07 01:31:07 northpi-soundstation volumio[7638]: info: Starting Tunnel Connection Checker
Dec 07 01:31:07 northpi-soundstation volumio[7638]: info: Completed starting MyVolumio Plugin
Dec 07 01:31:07 northpi-soundstation go-librespot[7971]: time="2025-12-07T01:31:07-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 07 01:31:07 northpi-soundstation go-librespot[7971]: time="2025-12-07T01:31:07-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 07 01:31:07 northpi-soundstation go-librespot[7971]: time="2025-12-07T01:31:07-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 07 01:31:07 northpi-soundstation go-librespot[7971]: time="2025-12-07T01:31:07-05:00" level=info msg="zeroconf server listening on port 38287"
Dec 07 01:31:07 northpi-soundstation go-librespot[7971]: time="2025-12-07T01:31:07-05:00" level=debug msg="obtained new client token: AADrQV/VjVybO/xUsTNMoKHG54hIINFgKJl4TabLFajLOVcAaSenriXCSHHNRRWLO7DUkKrCDUSRh2IOrWV+khKzmN+t9y4Dv5Qc6HyCbyZEgZUT2HJXhvGUoAXu8APILjGjyXnbNaA5m9jO2fwdGFnLR92CtEwkh+tBBWdAsHvYN8678ZKlvrwJpy39Ob0dsIkHJnpL4H7kdblSDXozztph/6yFKSnkPhYTcL+byFRo36GJgg5MkkGKEg=="
Dec 07 01:31:07 northpi-soundstation go-librespot[7971]: time="2025-12-07T01:31:07-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 01:31:07 northpi-soundstation volumio[7638]: info: MYVolumio Device enabled
Dec 07 01:31:07 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins...
Dec 07 01:31:07 northpi-soundstation volumio[7638]: info: MyVolumio status changed
Dec 07 01:31:07 northpi-soundstation volumio[7638]: info: Streaming services startup
Dec 07 01:31:07 northpi-soundstation volumio[7638]: info: Starting Streaming Daemon
Dec 07 01:31:07 northpi-soundstation sudo[8011]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 07 01:31:07 northpi-soundstation sudo[8011]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 01:31:07 northpi-soundstation go-librespot[7971]: time="2025-12-07T01:31:07-05:00" level=debug msg="completed keyexchange"
Dec 07 01:31:07 northpi-soundstation volumio[7638]: info: Setting Geolocation for MyVolumio to us4
Dec 07 01:31:07 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 07 01:31:07 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 07 01:31:07 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 07 01:31:07 northpi-soundstation go-librespot[7971]: time="2025-12-07T01:31:07-05:00" level=debug msg="completed challenge"
Dec 07 01:31:07 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid
Dec 07 01:31:07 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"...
Dec 07 01:31:07 northpi-soundstation sudo[8011]: pam_unix(sudo:session): session closed for user root
Dec 07 01:31:07 northpi-soundstation go-librespot[7971]: time="2025-12-07T01:31:07-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 07 01:31:07 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 01:31:07 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 01:31:07 northpi-soundstation volumio[7638]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart
Dec 07 01:31:07 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Loading plugin "multiroom"...
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom
Dec 07 01:31:08 northpi-soundstation sudo[8015]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom
Dec 07 01:31:08 northpi-soundstation sudo[8015]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 01:31:08 northpi-soundstation sudo[8015]: pam_unix(sudo:session): session closed for user root
Dec 07 01:31:08 northpi-soundstation volumio[7638]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: MRS: MultiRoom plugin initialized
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: MRS: STOPPING SNAPCLIENT
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: MRS: Snap server stop
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: MRS: STOPPING volumioStreaming
Dec 07 01:31:08 northpi-soundstation sudo[8034]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver
Dec 07 01:31:08 northpi-soundstation sudo[8033]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient
Dec 07 01:31:08 northpi-soundstation sudo[8034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"...
Dec 07 01:31:08 northpi-soundstation sudo[8033]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 01:31:08 northpi-soundstation sudo[8036]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming
Dec 07 01:31:08 northpi-soundstation sudo[8036]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Loading plugin "manifestui"...
Dec 07 01:31:08 northpi-soundstation sudo[8039]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/*
Dec 07 01:31:08 northpi-soundstation sudo[8039]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"...
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"...
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"...
Dec 07 01:31:08 northpi-soundstation sudo[8034]: pam_unix(sudo:session): session closed for user root
Dec 07 01:31:08 northpi-soundstation sudo[8039]: pam_unix(sudo:session): session closed for user root
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Loading plugin "tidal"...
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Loading plugin "qobuz"...
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"...
Dec 07 01:31:08 northpi-soundstation sudo[8033]: pam_unix(sudo:session): session closed for user root
Dec 07 01:31:08 northpi-soundstation sudo[8036]: pam_unix(sudo:session): session closed for user root
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"...
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: Preparing to generate the ALSA configuration file
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: Updating MyVolumio device info
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: Initializing connection to go-librespot Websocket
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: Setting Geolocation for MyVolumio to us1
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: Successfully Added MyVolumio device
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: MRS: Removed streaming files
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: MRS: volumioStreaming STOPPED
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: MRS: SNAPSERVER STOPPED
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: MRS: SNAPCLIENT STOPPED
Dec 07 01:31:08 northpi-soundstation volumio[7638]: error: Cannot start Volumio Streaming Daemon
Dec 07 01:31:08 northpi-soundstation volumio[7638]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 07 01:31:08 northpi-soundstation volumio[7638]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: Asound.conf file unchanged, so no further update is needed
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: Output device has changed, restarting MPD
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: Output device has changed, restarting Shairport Sync
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 07 01:31:08 northpi-soundstation sudo[8044]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Dec 07 01:31:08 northpi-soundstation sudo[8044]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 01:31:08 northpi-soundstation sudo[8044]: pam_unix(sudo:session): session closed for user root
Dec 07 01:31:08 northpi-soundstation sudo[8046]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Dec 07 01:31:08 northpi-soundstation sudo[8046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth
Dec 07 01:31:08 northpi-soundstation volumio[7638]: ------------------------------------ BT MESSAGE: [FUNC] onStart
Dec 07 01:31:08 northpi-soundstation volumio[7638]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service
Dec 07 01:31:08 northpi-soundstation systemd[1]: Stopping mpd.service - Music Player Daemon...
Dec 07 01:31:08 northpi-soundstation volumio[7638]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp
Dec 07 01:31:08 northpi-soundstation volumio[7638]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready.
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: MRS: Pushing multiroomSync output for this device
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: MRS: Pushing multiroomSync output
Dec 07 01:31:08 northpi-soundstation systemd[1]: mpd.service: Deactivated successfully.
Dec 07 01:31:08 northpi-soundstation systemd[1]: Stopped mpd.service - Music Player Daemon.
Dec 07 01:31:08 northpi-soundstation systemd[1]: mpd.service: Consumed 1.697s CPU time.
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: Adding audio output:
Dec 07 01:31:08 northpi-soundstation systemd[1]: mpd.socket: Deactivated successfully.
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: Adding audio output:
Dec 07 01:31:08 northpi-soundstation systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Dec 07 01:31:08 northpi-soundstation systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Dec 07 01:31:08 northpi-soundstation bluetoothd[806]: Path / reserved for Adv Monitor app :1.33435
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 01:31:08 northpi-soundstation bluetoothd[806]: Adv Monitor app :1.33435 disconnected from D-Bus
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: Adding METAVOLUMIO REST API Endpoints
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: Preparing CD Folders
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: Adding CD REST API Endpoints
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: Starting UDEV Watcher for CD
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: Detecting CD presence with UDEV
Dec 07 01:31:08 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices
Dec 07 01:31:08 northpi-soundstation systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Dec 07 01:31:08 northpi-soundstation systemd[1]: Starting mpd.service - Music Player Daemon...
Dec 07 01:31:08 northpi-soundstation sudo[8064]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Dec 07 01:31:08 northpi-soundstation sudo[8064]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Dec 07 01:31:08 northpi-soundstation sudo[8064]: pam_unix(sudo:session): session closed for user root
Dec 07 01:31:09 northpi-soundstation mpd[8066]: 2025-12-07T01:31:09 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Dec 07 01:31:09 northpi-soundstation systemd[1]: Started mpd.service - Music Player Daemon.
Dec 07 01:31:09 northpi-soundstation sudo[8046]: pam_unix(sudo:session): session closed for user root
Dec 07 01:31:10 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 256.
Dec 07 01:31:10 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:10 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:10 northpi-soundstation go-librespot[8069]: go-librespot daemon starting...
Dec 07 01:31:10 northpi-soundstation go-librespot[8070]: time="2025-12-07T01:31:10-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 01:31:10 northpi-soundstation go-librespot[8070]: time="2025-12-07T01:31:10-05:00" level=debug msg="app state loaded"
Dec 07 01:31:10 northpi-soundstation go-librespot[8070]: time="2025-12-07T01:31:10-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 01:31:10 northpi-soundstation go-librespot[8070]: time="2025-12-07T01:31: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 07 01:31:10 northpi-soundstation go-librespot[8070]: time="2025-12-07T01:31:10-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 07 01:31:10 northpi-soundstation go-librespot[8070]: time="2025-12-07T01:31:10-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 07 01:31:10 northpi-soundstation go-librespot[8070]: time="2025-12-07T01:31:10-05:00" level=info msg="zeroconf server listening on port 37127"
Dec 07 01:31:11 northpi-soundstation go-librespot[8070]: time="2025-12-07T01:31:11-05:00" level=debug msg="obtained new client token: AACQZO7+v1Hl5YLc4meCZ/T58NLSw715IHcxqYCOeKOsZeSbF2vjQLssR2pCYZRbYiXAKCqbyaDYkfXGBUZvj7MFz0MZIYsYyHXAYzgteKQBAqTjF2XjXLwjv6G4JvIXLSbT9HqZWW+gLb3Fv50IJR31EY9ms8BPhBiBk/yr7h3ISeqkwFoCdPqw9Q0PrDQUz/obcP8GH4JaYbGXsLqp6D9S7Vr5zEpb1Uny6V7UnbOqzW++iSFcIxn6bQ=="
Dec 07 01:31:11 northpi-soundstation go-librespot[8070]: time="2025-12-07T01:31:11-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 07 01:31:11 northpi-soundstation go-librespot[8070]: time="2025-12-07T01:31:11-05:00" level=debug msg="connected to ap-gue1.spotify.com:443"
Dec 07 01:31:11 northpi-soundstation go-librespot[8070]: time="2025-12-07T01:31:11-05:00" level=debug msg="completed keyexchange"
Dec 07 01:31:11 northpi-soundstation go-librespot[8070]: time="2025-12-07T01:31:11-05:00" level=debug msg="completed challenge"
Dec 07 01:31:11 northpi-soundstation go-librespot[8070]: time="2025-12-07T01:31:11-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 07 01:31:11 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 01:31:11 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 01:31:11 northpi-soundstation systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 4.
Dec 07 01:31:11 northpi-soundstation systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service.
Dec 07 01:31:11 northpi-soundstation systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service.
Dec 07 01:31:13 northpi-soundstation volumio[7638]: warn: [cd-plugin] cdspeedctl: device or media not ready
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: Adding inputs REST Endpoints
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: Scanning Audio Inputs
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: Checking against Known Cards name
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: Adding Server instance for streaming
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio
Dec 07 01:31:13 northpi-soundstation volumio[7638]: error: Hi Res Audio Failed Login: Missing Login Data
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: Adding HIGHRESAUDIO REST API Endpoints
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect
Dec 07 01:31:13 northpi-soundstation sudo[8096]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Dec 07 01:31:13 northpi-soundstation sudo[8096]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: QobuzConnect: Starting Qobuz Connect socket and service
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: Adding TIDAL REST API Endpoints
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: Stopping AccessToken refresher cron for QOBUZ
Dec 07 01:31:13 northpi-soundstation sudo[8103]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service
Dec 07 01:31:13 northpi-soundstation sudo[8103]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 01:31:13 northpi-soundstation sudo[8096]: pam_unix(sudo:session): session closed for user root
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: AccessToken refresher cron started for QOBUZ
Dec 07 01:31:13 northpi-soundstation sudo[8103]: pam_unix(sudo:session): session closed for user root
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: Adding QOBUZ REST API Endpoints
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: Updating MyVolumio device info
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: MRS: Getting audio outputs on start
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: MRS: Requesting all other devices output
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: Initializing connection to go-librespot Websocket
Dec 07 01:31:13 northpi-soundstation sudo[8106]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service
Dec 07 01:31:13 northpi-soundstation sudo[8106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 01:31:13 northpi-soundstation systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service...
Dec 07 01:31:13 northpi-soundstation systemd[1]: qobuz-connect.service: Deactivated successfully.
Dec 07 01:31:13 northpi-soundstation systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service.
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: Successfully Updated MyVolumio device
Dec 07 01:31:13 northpi-soundstation volumio[7638]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: MPD Permissions set
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 01:31:13 northpi-soundstation sudo[8110]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service
Dec 07 01:31:13 northpi-soundstation sudo[8110]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: Executing endpoint qc_getconfig
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Dec 07 01:31:13 northpi-soundstation systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service.
Dec 07 01:31:13 northpi-soundstation sudo[8106]: pam_unix(sudo:session): session closed for user root
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 01:31:13 northpi-soundstation sudo[8110]: pam_unix(sudo:session): session closed for user root
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 07 01:31:13 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 01:31:14 northpi-soundstation volumio[7638]: error: updateQueue error: null
Dec 07 01:31:14 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioGetState
Dec 07 01:31:14 northpi-soundstation volumio[7638]: info: CorePlayQueue::getTrack 0
Dec 07 01:31:14 northpi-soundstation volumio[7638]: info: Successfully Added MyVolumio device
Dec 07 01:31:14 northpi-soundstation volumio[7638]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully
Dec 07 01:31:14 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioStop
Dec 07 01:31:14 northpi-soundstation volumio[7638]: info: CoreStateMachine::stop
Dec 07 01:31:14 northpi-soundstation volumio[7638]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 07 01:31:14 northpi-soundstation volumio[7638]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart
Dec 07 01:31:14 northpi-soundstation volumio[7638]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 07 01:31:14 northpi-soundstation volumio[7638]: info: Successfully Updated MyVolumio device
Dec 07 01:31:14 northpi-soundstation volumio[7638]: info: Executing endpoint qc_getconfig
Dec 07 01:31:14 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Dec 07 01:31:14 northpi-soundstation qobuz-connect[8108]: 20251207 01:31:14.104 [8108.8108] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Dec 07 01:31:14 northpi-soundstation qobuz-connect[8108]: 20251207 01:31:14.108 [8108.8108] INFO VolumeManager: [0x22c598]: Setting new playback volume: 75
Dec 07 01:31:14 northpi-soundstation qobuz-connect[8108]: 20251207 01:31:14.108 [8108.8108] INFO VolumeManager: [0x22c598]: Setting new mute state: 0
Dec 07 01:31:14 northpi-soundstation qobuz-connect[8108]: 20251207 01:31:14.108 [8108.8108] INFO QobuzConnect: [0x22cf68]: Client initialized!
Dec 07 01:31:14 northpi-soundstation qobuz-connect[8108]: 20251207 01:31:14.108 [8108.8108] INFO SampleApp: Starting Avahi advertising, name: NorthPi-Soundstation, service name: _qobuz-connect._tcp
Dec 07 01:31:14 northpi-soundstation volumio[7638]: info: Starting Shairport Sync
Dec 07 01:31:14 northpi-soundstation volumio[7638]: info: Listing playlists
Dec 07 01:31:14 northpi-soundstation qobuz-connect[8108]: 20251207 01:31:14.127 [8108.8108] INFO LocalConfigManager: [0x22be78]: Starting Local Configuration server
Dec 07 01:31:14 northpi-soundstation qobuz-connect[8108]: 20251207 01:31:14.127 [8108.8108] INFO SampleApp: Starting Local configuration server
Dec 07 01:31:14 northpi-soundstation qobuz-connect[8108]: 20251207 01:31:14.127 [8108.8108] INFO SampleApp: Connected to UNIX socket client 0x216818
Dec 07 01:31:14 northpi-soundstation volumio[7638]: info: Listing playlists
Dec 07 01:31:14 northpi-soundstation sudo[8129]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service
Dec 07 01:31:14 northpi-soundstation sudo[8129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 01:31:14 northpi-soundstation sudo[8131]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 07 01:31:14 northpi-soundstation sudo[8131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 01:31:14 northpi-soundstation volumio[7638]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object]
Dec 07 01:31:14 northpi-soundstation volumio[7638]: info: QobuzConnect: QOBUZ Connect daemon connected
Dec 07 01:31:14 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 257.
Dec 07 01:31:14 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:14 northpi-soundstation qobuz-connect[8108]: 20251207 01:31:14.254 [8108.8108] INFO SampleApp: Playback volume changed: 75
Dec 07 01:31:14 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioGetState
Dec 07 01:31:14 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:14 northpi-soundstation go-librespot[8136]: go-librespot daemon starting...
Dec 07 01:31:14 northpi-soundstation systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Dec 07 01:31:14 northpi-soundstation systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel...
Dec 07 01:31:14 northpi-soundstation autossh[6930]: received signal to exit (15)
Dec 07 01:31:14 northpi-soundstation systemd[1]: sshtunnel.service: Deactivated successfully.
Dec 07 01:31:14 northpi-soundstation systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel.
Dec 07 01:31:14 northpi-soundstation systemd[1]: shairport-sync.service: Deactivated successfully.
Dec 07 01:31:14 northpi-soundstation systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Dec 07 01:31:14 northpi-soundstation systemd[1]: shairport-sync.service: Consumed 1.608s CPU time.
Dec 07 01:31:14 northpi-soundstation systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Dec 07 01:31:14 northpi-soundstation sudo[8131]: pam_unix(sudo:session): session closed for user root
Dec 07 01:31:14 northpi-soundstation go-librespot[8137]: time="2025-12-07T01:31:14-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 01:31:14 northpi-soundstation go-librespot[8137]: time="2025-12-07T01:31:14-05:00" level=debug msg="app state loaded"
Dec 07 01:31:14 northpi-soundstation go-librespot[8137]: time="2025-12-07T01:31:14-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 01:31:14 northpi-soundstation systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel.
Dec 07 01:31:14 northpi-soundstation volumio[7638]: info: Shairport-Sync Started
Dec 07 01:31:14 northpi-soundstation sudo[8129]: pam_unix(sudo:session): session closed for user root
Dec 07 01:31:14 northpi-soundstation volumio[7638]: info: Remote SSH Started
Dec 07 01:31:14 northpi-soundstation autossh[8145]: port set to 0, monitoring disabled
Dec 07 01:31:14 northpi-soundstation autossh[8145]: starting ssh (count 1)
Dec 07 01:31:14 northpi-soundstation autossh[8145]: ssh child pid is 8148
Dec 07 01:31:14 northpi-soundstation volumio[7638]: 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 07 01:31:14 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioGetState
Dec 07 01:31:14 northpi-soundstation volumiossh-tunnel[8148]: Warning: Permanently added '[us1.myvolumio.org]:2222' (RSA) to the list of known hosts.
Dec 07 01:31:14 northpi-soundstation go-librespot[8137]: time="2025-12-07T01:31:14-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 07 01:31:14 northpi-soundstation go-librespot[8137]: time="2025-12-07T01:31:14-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 07 01:31:14 northpi-soundstation go-librespot[8137]: time="2025-12-07T01:31:14-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 07 01:31:14 northpi-soundstation volumio[7638]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.0b008618efe21bea1cc98e8c917fa0a8.state.status'
Dec 07 01:31:14 northpi-soundstation go-librespot[8137]: time="2025-12-07T01:31:14-05:00" level=info msg="zeroconf server listening on port 33147"
Dec 07 01:31:14 northpi-soundstation go-librespot[8137]: time="2025-12-07T01:31:14-05:00" level=debug msg="obtained new client token: AACToWk0wCPYBt9dECbEG2vFOFPcXoLLBfaYltlIxdKxgibSIVvmGQFOprZNH3cDohRdkgxiQi9xXXg8SQcLnhg5aygMCz9eSrVJ26lmbhelsJv8TFrMUPni7QM/e4ncCOttVyvZgh41jSqusTAV2NZ1CcwbKgROEbmI9Cl9miyrPMaLZ4QrZ7pOseMogIu+8CU4V83DMVDRKTGVYxmmQKSf2kGdM845hBe3v+7pBTjWYKeZtQfBehuabQ=="
Dec 07 01:31:14 northpi-soundstation go-librespot[8137]: time="2025-12-07T01:31:14-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 01:31:14 northpi-soundstation go-librespot[8137]: time="2025-12-07T01:31:14-05:00" level=debug msg="completed keyexchange"
Dec 07 01:31:14 northpi-soundstation go-librespot[8137]: time="2025-12-07T01:31:14-05:00" level=debug msg="completed challenge"
Dec 07 01:31:14 northpi-soundstation go-librespot[8137]: time="2025-12-07T01:31: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 07 01:31:14 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 01:31:14 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 01:31:16 northpi-soundstation volumio[7638]: info: TidalConnect service stoped!
Dec 07 01:31:16 northpi-soundstation volumio[7638]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Dec 07 01:31:16 northpi-soundstation volumio[7638]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect
Dec 07 01:31:16 northpi-soundstation sudo[8174]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Dec 07 01:31:16 northpi-soundstation sudo[8174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 01:31:16 northpi-soundstation sudo[8178]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Dec 07 01:31:16 northpi-soundstation sudo[8178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 01:31:16 northpi-soundstation volumio[7638]: info: Initializing connection to go-librespot Websocket
Dec 07 01:31:16 northpi-soundstation volumio[7638]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 01:31:16 northpi-soundstation systemd[1]: Started vtcs.service - Volumio Tidal Connect Service.
Dec 07 01:31:16 northpi-soundstation sudo[8174]: pam_unix(sudo:session): session closed for user root
Dec 07 01:31:16 northpi-soundstation systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service...
Dec 07 01:31:16 northpi-soundstation systemd[1]: mpd_monitor.service: Deactivated successfully.
Dec 07 01:31:16 northpi-soundstation systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service.
Dec 07 01:31:16 northpi-soundstation systemd[1]: Started mpd_monitor.service - MPD Monitor Service.
Dec 07 01:31:16 northpi-soundstation sudo[8178]: pam_unix(sudo:session): session closed for user root
Dec 07 01:31:16 northpi-soundstation volumio[7638]: info: Successfully started MPD Monitor
Dec 07 01:31:16 northpi-soundstation mpd_monitor.sh[8182]: MPD Monitor Service: Starting MPD Monitor Service
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: Executing endpoint tc_getconfig
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig
Dec 07 01:31:17 northpi-soundstation vtcs[8180]: STARTING TidalConnect services, version: 1.5.2.56
Dec 07 01:31:17 northpi-soundstation vtcs[8180]: STARTED TidalConnect services.
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: Executing endpoint tc_connect
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: Connecting to TidalConnect
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreCommandRouter::servicePushState
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreStateMachine::pushState
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioPushState
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: MRS: Pushing multiroomSync output update for this device
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: MRS: Pushing multiroomSync output
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioGetState
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CorePlayQueue::getTrack 0
Dec 07 01:31:17 northpi-soundstation volumio[7638]: 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 07 01:31:17 northpi-soundstation volumio[7638]: verbose: CURRENT POSITION 0
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreStateMachine::syncState stateService stop
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreStateMachine::syncState currentStatus stop
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreStateMachine::pushState
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioPushState
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: MRS: Pushing multiroomSync output update for this device
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: MRS: Pushing multiroomSync output
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioGetState
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: No code
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreStateMachine::pushState
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioPushState
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: MRS: Pushing multiroomSync output update for this device
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: MRS: Pushing multiroomSync output
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioGetState
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreCommandRouter::servicePushState
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreStateMachine::pushState
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioPushState
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: MRS: Pushing multiroomSync output update for this device
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: MRS: Pushing multiroomSync output
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioGetState
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CorePlayQueue::getTrack 0
Dec 07 01:31:17 northpi-soundstation volumio[7638]: 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 07 01:31:17 northpi-soundstation volumio[7638]: verbose: CURRENT POSITION 0
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreStateMachine::syncState stateService stop
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreStateMachine::syncState currentStatus stop
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreStateMachine::pushState
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioPushState
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: MRS: Pushing multiroomSync output update for this device
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: MRS: Pushing multiroomSync output
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioGetState
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: No code
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreStateMachine::pushState
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioPushState
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: MRS: Pushing multiroomSync output update for this device
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: MRS: Pushing multiroomSync output
Dec 07 01:31:17 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioGetState
Dec 07 01:31:17 northpi-soundstation volumio[7638]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.0b008618efe21bea1cc98e8c917fa0a8.state.status'
Dec 07 01:31:17 northpi-soundstation volumio[7638]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.0b008618efe21bea1cc98e8c917fa0a8.state.status'
Dec 07 01:31:17 northpi-soundstation volumio[7638]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.0b008618efe21bea1cc98e8c917fa0a8.state.status'
Dec 07 01:31:17 northpi-soundstation volumio[7638]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.0b008618efe21bea1cc98e8c917fa0a8.state.status'
Dec 07 01:31:17 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 258.
Dec 07 01:31:17 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:17 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:17 northpi-soundstation go-librespot[8200]: go-librespot daemon starting...
Dec 07 01:31:17 northpi-soundstation go-librespot[8201]: time="2025-12-07T01:31:17-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 01:31:17 northpi-soundstation go-librespot[8201]: time="2025-12-07T01:31:17-05:00" level=debug msg="app state loaded"
Dec 07 01:31:17 northpi-soundstation go-librespot[8201]: time="2025-12-07T01:31:17-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 01:31:18 northpi-soundstation go-librespot[8201]: time="2025-12-07T01:31:18-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 07 01:31:18 northpi-soundstation go-librespot[8201]: time="2025-12-07T01:31:18-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 07 01:31:18 northpi-soundstation go-librespot[8201]: time="2025-12-07T01:31:18-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 07 01:31:18 northpi-soundstation go-librespot[8201]: time="2025-12-07T01:31:18-05:00" level=info msg="zeroconf server listening on port 33829"
Dec 07 01:31:18 northpi-soundstation go-librespot[8201]: time="2025-12-07T01:31:18-05:00" level=debug msg="obtained new client token: AAAUmWEuH40N7R4++MciqQOOJOMbCx6DZUp4iGpOrh78GZXngdt8kZNOoIjwJzpVfsoI7FAr7cZRcRGV2niWr9muJOWMfM9eB6iuANkQVBMl7btjkW/TlKqsq0hG7g5HlO/H6sTmXzej/nxoeZaiVfUF/LkMpRTui7r9FqHPbEnwCBwL0X6FN6mUrURK70fGSlDeAnqd80zV9pVOL0f/D6BZmWlS5OYAhUoVR6RWGcEwTeOW5/IBRVI="
Dec 07 01:31:18 northpi-soundstation go-librespot[8201]: time="2025-12-07T01:31:18-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 01:31:18 northpi-soundstation go-librespot[8201]: time="2025-12-07T01:31:18-05:00" level=debug msg="completed keyexchange"
Dec 07 01:31:18 northpi-soundstation go-librespot[8201]: time="2025-12-07T01:31:18-05:00" level=debug msg="completed challenge"
Dec 07 01:31:18 northpi-soundstation go-librespot[8201]: time="2025-12-07T01:31:18-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 07 01:31:18 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 01:31:18 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 01:31:19 northpi-soundstation volumio[7638]: info: Initializing connection to go-librespot Websocket
Dec 07 01:31:19 northpi-soundstation volumio[7638]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 01:31:19 northpi-soundstation volumio[7638]: verbose: New Socket.io Connection to 192.168.1.28:3000 from 192.168.1.97 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Dec 07 01:31:19 northpi-soundstation volumio[7638]: info: TidalConnect service started!
Dec 07 01:31:19 northpi-soundstation volumio[7638]: [Metrics] CommandRouter: 29s 921.70ms
Dec 07 01:31:19 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumiosetStartupVolume
Dec 07 01:31:19 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 01:31:19 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 07 01:31:19 northpi-soundstation volumio[7638]: info: CoreCommandRouter::Close All Modals sent
Dec 07 01:31:19 northpi-soundstation volumio[7638]: info: CoreCommandRouter::Close All Modals sent
Dec 07 01:31:20 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket
Dec 07 01:31:20 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioGetState
Dec 07 01:31:20 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 07 01:31:20 northpi-soundstation volumio[7638]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Dec 07 01:31:20 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioGetState
Dec 07 01:31:20 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Dec 07 01:31:20 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Dec 07 01:31:20 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Dec 07 01:31:21 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 259.
Dec 07 01:31:21 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:21 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:21 northpi-soundstation go-librespot[8227]: go-librespot daemon starting...
Dec 07 01:31:21 northpi-soundstation go-librespot[8228]: time="2025-12-07T01:31:21-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 01:31:21 northpi-soundstation go-librespot[8228]: time="2025-12-07T01:31:21-05:00" level=debug msg="app state loaded"
Dec 07 01:31:21 northpi-soundstation go-librespot[8228]: time="2025-12-07T01:31:21-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 01:31:21 northpi-soundstation go-librespot[8228]: time="2025-12-07T01:31: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 07 01:31:21 northpi-soundstation go-librespot[8228]: time="2025-12-07T01:31:21-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 07 01:31:21 northpi-soundstation go-librespot[8228]: time="2025-12-07T01:31:21-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 07 01:31:21 northpi-soundstation go-librespot[8228]: time="2025-12-07T01:31:21-05:00" level=info msg="zeroconf server listening on port 44717"
Dec 07 01:31:22 northpi-soundstation go-librespot[8228]: time="2025-12-07T01:31:22-05:00" level=debug msg="obtained new client token: AADRVN0K0/h99XGKRyU42kkFS8eXDf1znKWxS9R4zs9l/nSA8BqdvLREv4xx3m4n8bACTPIAnxK1lnk+WSArBWfiuwff21fWV1FGrhp7JX+Xa3SxqWpS0YL6gDWJ5tiZIvp7+3aWC0VqoBfk2CA1Q5/X9VXAgoEpimmyu9k0C+RhHd+OxGCjgZzE1PaOuyAlr+3ymQh7xWB4skIx5rxnd7HBHzxd8su5U3dI+z+RQ13T5uYNhk3kIRQQNQ=="
Dec 07 01:31:22 northpi-soundstation go-librespot[8228]: time="2025-12-07T01:31:22-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 01:31:22 northpi-soundstation go-librespot[8228]: time="2025-12-07T01:31:22-05:00" level=debug msg="completed keyexchange"
Dec 07 01:31:22 northpi-soundstation go-librespot[8228]: time="2025-12-07T01:31:22-05:00" level=debug msg="completed challenge"
Dec 07 01:31:22 northpi-soundstation go-librespot[8228]: time="2025-12-07T01:31:22-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 07 01:31:22 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 01:31:22 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 01:31:22 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioGetState
Dec 07 01:31:22 northpi-soundstation volumio[7638]: info: Initializing connection to go-librespot Websocket
Dec 07 01:31:22 northpi-soundstation volumio[7638]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 01:31:25 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 260.
Dec 07 01:31:25 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:25 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:25 northpi-soundstation go-librespot[8235]: go-librespot daemon starting...
Dec 07 01:31:25 northpi-soundstation go-librespot[8236]: time="2025-12-07T01:31:25-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 01:31:25 northpi-soundstation go-librespot[8236]: time="2025-12-07T01:31:25-05:00" level=debug msg="app state loaded"
Dec 07 01:31:25 northpi-soundstation go-librespot[8236]: time="2025-12-07T01:31:25-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 01:31:25 northpi-soundstation kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 13
Dec 07 01:31:25 northpi-soundstation kernel: xhci_hcd 0000:01:00.0: @0000000428d1e890 00000000 00000000 0e000000 020e8001
Dec 07 01:31:25 northpi-soundstation go-librespot[8236]: time="2025-12-07T01:31:25-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 07 01:31:25 northpi-soundstation go-librespot[8236]: time="2025-12-07T01:31:25-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 07 01:31:25 northpi-soundstation go-librespot[8236]: time="2025-12-07T01:31:25-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 07 01:31:25 northpi-soundstation go-librespot[8236]: time="2025-12-07T01:31:25-05:00" level=info msg="zeroconf server listening on port 42783"
Dec 07 01:31:25 northpi-soundstation go-librespot[8236]: time="2025-12-07T01:31:25-05:00" level=debug msg="obtained new client token: AABO/3KJ1qUnC9bNT5e3dywQjcdtsEU+ADutJxLEbJ2tHb66HcW5yNuoCa//i52/iQGmpLabnoIGzGjFZI+iIUfgGjJ8TmxiLRNnU3xSqeEoEl5l4dKxWtPw/cL3yhxgLu2/vu+b8oWByRAmDdXrr39p6VYFyz695SLNkRK99jei8Kpu1WoT+AwLGdBWT6kRohKi/pQJN8LjcSJgeUS8SLyo7OG1vA7zmtLjWZJ9nsj+nibmitUl4sMOPw=="
Dec 07 01:31:25 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 07 01:31:25 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 07 01:31:25 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 07 01:31:25 northpi-soundstation go-librespot[8236]: time="2025-12-07T01:31:25-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 07 01:31:25 northpi-soundstation volumio-remote-updater[7772]: Test mode enabled
Dec 07 01:31:25 northpi-soundstation volumio-remote-updater[7772]: Alpha mode disabled
Dec 07 01:31:25 northpi-soundstation volumio-remote-updater[7772]: Alpha legacy test mode disabled
Dec 07 01:31:25 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Dec 07 01:31:25 northpi-soundstation go-librespot[8236]: time="2025-12-07T01:31:25-05:00" level=debug msg="connected to ap-gue1.spotify.com:443"
Dec 07 01:31:25 northpi-soundstation go-librespot[8236]: time="2025-12-07T01:31:25-05:00" level=debug msg="completed keyexchange"
Dec 07 01:31:25 northpi-soundstation go-librespot[8236]: time="2025-12-07T01:31:25-05:00" level=debug msg="completed challenge"
Dec 07 01:31:25 northpi-soundstation go-librespot[8236]: time="2025-12-07T01:31:25-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 07 01:31:25 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 01:31:25 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 01:31:25 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 01:31:25 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 01:31:25 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Dec 07 01:31:25 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Dec 07 01:31:25 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Dec 07 01:31:25 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 07 01:31:25 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 07 01:31:25 northpi-soundstation volumio[7638]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"ANNOUNCEMENT
\n\n- Production line
\n
\nFIX
\n\n- Nodejs 20.5.1 version pinning with static package fallback
\n
\n","title":"Update v4.073","updateavailable":true}
Dec 07 01:31:25 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Dec 07 01:31:25 northpi-soundstation volumio[7638]: info: Initializing connection to go-librespot Websocket
Dec 07 01:31:25 northpi-soundstation volumio[7638]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 01:31:26 northpi-soundstation volumio[7638]: info: BOOT COMPLETED
Dec 07 01:31:28 northpi-soundstation volumio[7638]: info: Initializing connection to go-librespot Websocket
Dec 07 01:31:28 northpi-soundstation volumio[7638]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 01:31:28 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 261.
Dec 07 01:31:28 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:28 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:28 northpi-soundstation go-librespot[8248]: go-librespot daemon starting...
Dec 07 01:31:28 northpi-soundstation go-librespot[8249]: time="2025-12-07T01:31:28-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 01:31:28 northpi-soundstation go-librespot[8249]: time="2025-12-07T01:31:28-05:00" level=debug msg="app state loaded"
Dec 07 01:31:28 northpi-soundstation go-librespot[8249]: time="2025-12-07T01:31:28-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 01:31:29 northpi-soundstation go-librespot[8249]: time="2025-12-07T01:31:29-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 07 01:31:29 northpi-soundstation go-librespot[8249]: time="2025-12-07T01:31:29-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 07 01:31:29 northpi-soundstation go-librespot[8249]: time="2025-12-07T01:31:29-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 07 01:31:29 northpi-soundstation go-librespot[8249]: time="2025-12-07T01:31:29-05:00" level=info msg="zeroconf server listening on port 45193"
Dec 07 01:31:29 northpi-soundstation go-librespot[8249]: time="2025-12-07T01:31:29-05:00" level=debug msg="obtained new client token: AAB9CPzlWWTwKPB9JVlF83C6+UjLXgbsoqDVZoQ5QdEukrxp44DcwTQIjubwtduPYEUwgp0NJDccJo2tYaYOupKyv59aOnarh80aBL7n3R2KEljvDdEgvshSPclkD8H8S47PJ0mgJ2KRIix3wYL61prZrzAidgjY7sCi6OFcjt+TlQfApdGUvX/jG0N3cFCsVgk6b9MbyQ1iJ6kpDZBg1Mhb702qKYdJ0cjyHdA5gK6NUUJvsKRmkHs="
Dec 07 01:31:29 northpi-soundstation go-librespot[8249]: time="2025-12-07T01:31:29-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 01:31:29 northpi-soundstation go-librespot[8249]: time="2025-12-07T01:31:29-05:00" level=debug msg="completed keyexchange"
Dec 07 01:31:29 northpi-soundstation go-librespot[8249]: time="2025-12-07T01:31:29-05:00" level=debug msg="completed challenge"
Dec 07 01:31:29 northpi-soundstation go-librespot[8249]: time="2025-12-07T01:31:29-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 07 01:31:29 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 01:31:29 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 01:31:31 northpi-soundstation volumio[7638]: info: Initializing connection to go-librespot Websocket
Dec 07 01:31:31 northpi-soundstation volumio[7638]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 01:31:32 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 262.
Dec 07 01:31:32 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:32 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:32 northpi-soundstation go-librespot[8270]: go-librespot daemon starting...
Dec 07 01:31:32 northpi-soundstation go-librespot[8271]: time="2025-12-07T01:31:32-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 01:31:32 northpi-soundstation go-librespot[8271]: time="2025-12-07T01:31:32-05:00" level=debug msg="app state loaded"
Dec 07 01:31:32 northpi-soundstation go-librespot[8271]: time="2025-12-07T01:31:32-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 01:31:32 northpi-soundstation go-librespot[8271]: time="2025-12-07T01:31:32-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 07 01:31:32 northpi-soundstation go-librespot[8271]: time="2025-12-07T01:31:32-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 07 01:31:32 northpi-soundstation go-librespot[8271]: time="2025-12-07T01:31:32-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 07 01:31:32 northpi-soundstation go-librespot[8271]: time="2025-12-07T01:31:32-05:00" level=info msg="zeroconf server listening on port 38287"
Dec 07 01:31:32 northpi-soundstation go-librespot[8271]: time="2025-12-07T01:31:32-05:00" level=debug msg="obtained new client token: AAA2VrXj0kjscRcFON8HZMuR9ntIh4izSWLyZYxOgbbS9qVkuNIY6F5haonIDMDePiDu6md/6edc3OO3lIjLXTXP8gsxv9IvMI8KWA4UiCbvHP2dPnyv/cbyLD4rqrIg0eRWI/k+NC4ZqWOCXmyY67wJZ1mAKyfqZvZDE0TTjI0LuWs7DL1hMYt1w4NE5oB2r4szudvpMjKAcFYdKLBpW5pjARqk+3qFTOfvzjOkkJ2/pgDbROvp0p+/LQ=="
Dec 07 01:31:32 northpi-soundstation go-librespot[8271]: time="2025-12-07T01:31:32-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 01:31:32 northpi-soundstation go-librespot[8271]: time="2025-12-07T01:31:32-05:00" level=debug msg="completed keyexchange"
Dec 07 01:31:32 northpi-soundstation go-librespot[8271]: time="2025-12-07T01:31:32-05:00" level=debug msg="completed challenge"
Dec 07 01:31:32 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioGetState
Dec 07 01:31:32 northpi-soundstation go-librespot[8271]: time="2025-12-07T01:31: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 07 01:31:32 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 01:31:32 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 01:31:32 northpi-soundstation volumio[7638]: info: Listing playlists
Dec 07 01:31:32 northpi-soundstation volumio[7638]: info: Listing playlists
Dec 07 01:31:34 northpi-soundstation volumio[7638]: info: Initializing connection to go-librespot Websocket
Dec 07 01:31:34 northpi-soundstation volumio[7638]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 01:31:35 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 263.
Dec 07 01:31:35 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:35 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:35 northpi-soundstation go-librespot[8278]: go-librespot daemon starting...
Dec 07 01:31:35 northpi-soundstation go-librespot[8279]: time="2025-12-07T01:31:35-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 01:31:35 northpi-soundstation go-librespot[8279]: time="2025-12-07T01:31:35-05:00" level=debug msg="app state loaded"
Dec 07 01:31:35 northpi-soundstation go-librespot[8279]: time="2025-12-07T01:31:35-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 01:31:36 northpi-soundstation go-librespot[8279]: time="2025-12-07T01:31:36-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 07 01:31:36 northpi-soundstation go-librespot[8279]: time="2025-12-07T01:31:36-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 07 01:31:36 northpi-soundstation go-librespot[8279]: time="2025-12-07T01:31:36-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 07 01:31:36 northpi-soundstation go-librespot[8279]: time="2025-12-07T01:31:36-05:00" level=info msg="zeroconf server listening on port 40549"
Dec 07 01:31:36 northpi-soundstation go-librespot[8279]: time="2025-12-07T01:31:36-05:00" level=debug msg="obtained new client token: AABZvGl+w0Ubljl6xL0CWX4MSC7VGwhrya2HFkHoiQY9qHVgarkG89NLf+JS7I4P1eCvCRIy/Y6Aai3Dw1Nq/qT4e7tOclsQ/CvOIbG18egsWyDPmM4MCIW3Sj56L0aYBxm6DRnzD3szLQqinmwvJqmqWGkGVCqNBRFclwT1x4HwsFo/IomnrzTZX2zA4JcVwepEhE8Oo/egr2qoHXcIoSMwOL6B3Hqm9ZsFjEMqwd3g6sJkOJYtVQk="
Dec 07 01:31:36 northpi-soundstation go-librespot[8279]: time="2025-12-07T01:31:36-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 01:31:36 northpi-soundstation go-librespot[8279]: time="2025-12-07T01:31:36-05:00" level=debug msg="completed keyexchange"
Dec 07 01:31:36 northpi-soundstation go-librespot[8279]: time="2025-12-07T01:31:36-05:00" level=debug msg="completed challenge"
Dec 07 01:31:36 northpi-soundstation go-librespot[8279]: time="2025-12-07T01:31:36-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 07 01:31:36 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 01:31:36 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 01:31:37 northpi-soundstation volumio[7638]: info: Initializing connection to go-librespot Websocket
Dec 07 01:31:37 northpi-soundstation volumio[7638]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 01:31:39 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 264.
Dec 07 01:31:39 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:39 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:39 northpi-soundstation go-librespot[8289]: go-librespot daemon starting...
Dec 07 01:31:39 northpi-soundstation go-librespot[8290]: time="2025-12-07T01:31:39-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 01:31:39 northpi-soundstation go-librespot[8290]: time="2025-12-07T01:31:39-05:00" level=debug msg="app state loaded"
Dec 07 01:31:39 northpi-soundstation go-librespot[8290]: time="2025-12-07T01:31:39-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 01:31:39 northpi-soundstation go-librespot[8290]: time="2025-12-07T01:31: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-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 07 01:31:39 northpi-soundstation go-librespot[8290]: time="2025-12-07T01:31:39-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 07 01:31:39 northpi-soundstation go-librespot[8290]: time="2025-12-07T01:31:39-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 07 01:31:39 northpi-soundstation go-librespot[8290]: time="2025-12-07T01:31:39-05:00" level=info msg="zeroconf server listening on port 42387"
Dec 07 01:31:39 northpi-soundstation go-librespot[8290]: time="2025-12-07T01:31:39-05:00" level=debug msg="obtained new client token: AADXIVfHQP2fEdMNVQkp1MbPbFyjIfkx/KWpJN+HJTGaWAJkTtr62tyRfRIjSidWIDVLbOoXeQpWR2V5sv0S+PYh1kFKUjnNUBLB3SpsleShmplj7WY44v0RE8F/GpnOeN6GTJnRPMcrtNdqtkBiBVdvuzRUQy/I6GGTkRDvVl6CUeQfU55BkqP+ralEm3gLp1LGciZahV554yqYAJsx+jmyiZnp2aL8tIN5DEDVX6rwTuILceDe5Y5/KA=="
Dec 07 01:31:39 northpi-soundstation go-librespot[8290]: time="2025-12-07T01:31:39-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 01:31:39 northpi-soundstation go-librespot[8290]: time="2025-12-07T01:31:39-05:00" level=debug msg="completed keyexchange"
Dec 07 01:31:39 northpi-soundstation go-librespot[8290]: time="2025-12-07T01:31:39-05:00" level=debug msg="completed challenge"
Dec 07 01:31:39 northpi-soundstation go-librespot[8290]: time="2025-12-07T01:31: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 07 01:31:39 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 01:31:39 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 01:31:40 northpi-soundstation volumio[7638]: info: Initializing connection to go-librespot Websocket
Dec 07 01:31:40 northpi-soundstation volumio[7638]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 01:31:42 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioGetState
Dec 07 01:31:43 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 265.
Dec 07 01:31:43 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:43 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:43 northpi-soundstation go-librespot[8311]: go-librespot daemon starting...
Dec 07 01:31:43 northpi-soundstation go-librespot[8312]: time="2025-12-07T01:31:43-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 01:31:43 northpi-soundstation go-librespot[8312]: time="2025-12-07T01:31:43-05:00" level=debug msg="app state loaded"
Dec 07 01:31:43 northpi-soundstation go-librespot[8312]: time="2025-12-07T01:31:43-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 01:31:43 northpi-soundstation go-librespot[8312]: time="2025-12-07T01:31:43-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 07 01:31:43 northpi-soundstation go-librespot[8312]: time="2025-12-07T01:31:43-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 07 01:31:43 northpi-soundstation go-librespot[8312]: time="2025-12-07T01:31:43-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 07 01:31:43 northpi-soundstation go-librespot[8312]: time="2025-12-07T01:31:43-05:00" level=info msg="zeroconf server listening on port 43601"
Dec 07 01:31:43 northpi-soundstation go-librespot[8312]: time="2025-12-07T01:31:43-05:00" level=debug msg="obtained new client token: AAB3R94WJzu61JNLtDBzPj5B79A9L68B/2sskZ78z7KnB9uIngg2TpSC3z1zN05x367JpgzDkfpLr3z2oh7Jk992DyOt+8sgdEgwe2ri/tn84pLbVesh9rFGVYnHnTinle+TaN6Aa8vLPqkTWiSGczX3WZkfMAUm0ZEd1wRYpqTfOITwydeisOqyHOZoIbtG4EF8waXzR0MClivWfrTjhm41Wl2H6ZKtXpY6ftmoXujIyUBtgnQHpAe/fw=="
Dec 07 01:31:43 northpi-soundstation go-librespot[8312]: time="2025-12-07T01:31:43-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 01:31:43 northpi-soundstation go-librespot[8312]: time="2025-12-07T01:31:43-05:00" level=debug msg="completed keyexchange"
Dec 07 01:31:43 northpi-soundstation go-librespot[8312]: time="2025-12-07T01:31:43-05:00" level=debug msg="completed challenge"
Dec 07 01:31:43 northpi-soundstation go-librespot[8312]: time="2025-12-07T01:31: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 07 01:31:43 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 01:31:43 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 01:31:43 northpi-soundstation volumio[7638]: info: Initializing connection to go-librespot Websocket
Dec 07 01:31:43 northpi-soundstation volumio[7638]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 01:31:46 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 266.
Dec 07 01:31:46 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:46 northpi-soundstation volumio[7638]: info: Initializing connection to go-librespot Websocket
Dec 07 01:31:46 northpi-soundstation volumio[7638]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 01:31:46 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:46 northpi-soundstation go-librespot[8320]: go-librespot daemon starting...
Dec 07 01:31:46 northpi-soundstation go-librespot[8321]: time="2025-12-07T01:31:46-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 01:31:46 northpi-soundstation go-librespot[8321]: time="2025-12-07T01:31:46-05:00" level=debug msg="app state loaded"
Dec 07 01:31:46 northpi-soundstation go-librespot[8321]: time="2025-12-07T01:31:46-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 01:31:47 northpi-soundstation go-librespot[8321]: time="2025-12-07T01:31:47-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 07 01:31:47 northpi-soundstation go-librespot[8321]: time="2025-12-07T01:31:47-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 07 01:31:47 northpi-soundstation go-librespot[8321]: time="2025-12-07T01:31:47-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 07 01:31:47 northpi-soundstation go-librespot[8321]: time="2025-12-07T01:31:47-05:00" level=info msg="zeroconf server listening on port 42057"
Dec 07 01:31:47 northpi-soundstation go-librespot[8321]: time="2025-12-07T01:31:47-05:00" level=debug msg="obtained new client token: AADSve/nr8CEWap4VwBJS2nz8W6+sl+BQQMnDlix1wQvCQP2D2vqxOGROwM6ShJtirhI2wk48JC0gffNQ/7E2B+PL+7ZveSIjJ+wsUOWqA0YJcRk3o7jQHeLLERnN1WnCNxMPqz+dJpJk77LEsflWFT7F6ZyjA8tGUq+lkor75odHm5bM1vhLsi2pNKKduPf/ChXnD8bFGSdtiOYPR2SMBQ4k5K67SoE5W2u+NbcvWOHt3DLgsBfsTk="
Dec 07 01:31:47 northpi-soundstation go-librespot[8321]: time="2025-12-07T01:31:47-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 07 01:31:47 northpi-soundstation go-librespot[8321]: time="2025-12-07T01:31:47-05:00" level=debug msg="connected to ap-gue1.spotify.com:443"
Dec 07 01:31:47 northpi-soundstation go-librespot[8321]: time="2025-12-07T01:31:47-05:00" level=debug msg="completed keyexchange"
Dec 07 01:31:47 northpi-soundstation go-librespot[8321]: time="2025-12-07T01:31:47-05:00" level=debug msg="completed challenge"
Dec 07 01:31:47 northpi-soundstation go-librespot[8321]: time="2025-12-07T01:31:47-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 07 01:31:47 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 01:31:47 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 01:31:48 northpi-soundstation volumio[7638]: verbose: New Socket.io Connection to 192.168.1.28:3000 from 192.168.1.97 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Dec 07 01:31:49 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket
Dec 07 01:31:49 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioGetState
Dec 07 01:31:49 northpi-soundstation volumio[7638]: info: Initializing connection to go-librespot Websocket
Dec 07 01:31:49 northpi-soundstation volumio[7638]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 01:31:50 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 267.
Dec 07 01:31:50 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:50 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:50 northpi-soundstation go-librespot[8328]: go-librespot daemon starting...
Dec 07 01:31:50 northpi-soundstation go-librespot[8329]: time="2025-12-07T01:31:50-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 01:31:50 northpi-soundstation go-librespot[8329]: time="2025-12-07T01:31:50-05:00" level=debug msg="app state loaded"
Dec 07 01:31:50 northpi-soundstation go-librespot[8329]: time="2025-12-07T01:31:50-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 01:31:50 northpi-soundstation go-librespot[8329]: time="2025-12-07T01:31:50-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 07 01:31:50 northpi-soundstation go-librespot[8329]: time="2025-12-07T01:31:50-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 07 01:31:50 northpi-soundstation go-librespot[8329]: time="2025-12-07T01:31:50-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 07 01:31:50 northpi-soundstation go-librespot[8329]: time="2025-12-07T01:31:50-05:00" level=info msg="zeroconf server listening on port 33615"
Dec 07 01:31:50 northpi-soundstation go-librespot[8329]: time="2025-12-07T01:31:50-05:00" level=debug msg="obtained new client token: AABpo5baN6pJ7jwiRDv4x7qQqK6BzdzAbwUsa/4xqUBhnadmIzninddTFLm+9eYLA34b1/YjBSN3C4fHeymGSp/7Fdz3sp83XlhrAZyrQLRf8dR7MGmkPVw9mbD4BnRoDmWlJPdxSRmXwVBH0z+hzG/2NenvZgoYcPjwEiRcedENCg/8/EQXDnzIDsx+Gx0rSysnx+N3XbJ0yVeCpoPWXExne1taA/vTxlnL8VuuKxbu+8lTluah8C9F8w=="
Dec 07 01:31:51 northpi-soundstation go-librespot[8329]: time="2025-12-07T01:31:51-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 01:31:51 northpi-soundstation go-librespot[8329]: time="2025-12-07T01:31:51-05:00" level=debug msg="completed keyexchange"
Dec 07 01:31:51 northpi-soundstation go-librespot[8329]: time="2025-12-07T01:31:51-05:00" level=debug msg="completed challenge"
Dec 07 01:31:51 northpi-soundstation go-librespot[8329]: time="2025-12-07T01:31:51-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 07 01:31:51 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 01:31:51 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 01:31:52 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioGetState
Dec 07 01:31:52 northpi-soundstation volumio[7638]: info: Listing playlists
Dec 07 01:31:52 northpi-soundstation volumio[7638]: info: Listing playlists
Dec 07 01:31:52 northpi-soundstation volumio[7638]: info: Initializing connection to go-librespot Websocket
Dec 07 01:31:52 northpi-soundstation volumio[7638]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 01:31:54 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 268.
Dec 07 01:31:54 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:54 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:54 northpi-soundstation go-librespot[8350]: go-librespot daemon starting...
Dec 07 01:31:54 northpi-soundstation go-librespot[8351]: time="2025-12-07T01:31:54-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 01:31:54 northpi-soundstation go-librespot[8351]: time="2025-12-07T01:31:54-05:00" level=debug msg="app state loaded"
Dec 07 01:31:54 northpi-soundstation go-librespot[8351]: time="2025-12-07T01:31:54-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 01:31:54 northpi-soundstation go-librespot[8351]: time="2025-12-07T01:31:54-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 07 01:31:54 northpi-soundstation go-librespot[8351]: time="2025-12-07T01:31:54-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 07 01:31:54 northpi-soundstation go-librespot[8351]: time="2025-12-07T01:31:54-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 07 01:31:54 northpi-soundstation go-librespot[8351]: time="2025-12-07T01:31:54-05:00" level=info msg="zeroconf server listening on port 40937"
Dec 07 01:31:54 northpi-soundstation go-librespot[8351]: time="2025-12-07T01:31:54-05:00" level=debug msg="obtained new client token: AABiSXQ82HVxPI5Tcx/KvtJ2c+1/n3TA6vdY2OAJG3zreTMz4tv5wW/xeuxiDPTW1FjXagVWZqdbgrdSszuDrul1q1diQeWlFkEIiPgBVah/cbfYOqYytppc/AkaGNKfVO2+v3daYEnD7M8EvqFfODgbkaA5GrXziETkGctM8D/yiTFUCWVvtw2AlLQVWSlH4HAvfXo9OvrXLUIlf+woNJKsadAaTceWN/JQo84I3Kho6FVEE5sMU57Gjw=="
Dec 07 01:31:54 northpi-soundstation go-librespot[8351]: time="2025-12-07T01:31:54-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 01:31:54 northpi-soundstation go-librespot[8351]: time="2025-12-07T01:31:54-05:00" level=debug msg="completed keyexchange"
Dec 07 01:31:54 northpi-soundstation go-librespot[8351]: time="2025-12-07T01:31:54-05:00" level=debug msg="completed challenge"
Dec 07 01:31:54 northpi-soundstation go-librespot[8351]: time="2025-12-07T01:31:54-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 07 01:31:54 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 01:31:54 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 01:31:55 northpi-soundstation volumio[7638]: info: UPDATER: Scheduling automatic update
Dec 07 01:31:55 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Dec 07 01:31:55 northpi-soundstation volumio[7638]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Dec 07 01:31:55 northpi-soundstation volumio[7638]: info: UPDATER: Auto update will take place at: Mon Dec 08 2025 03:35:34 GMT-0500 (Eastern Standard Time)
Dec 07 01:31:55 northpi-soundstation volumio[7638]: info: Initializing connection to go-librespot Websocket
Dec 07 01:31:55 northpi-soundstation volumio[7638]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 01:31:57 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 269.
Dec 07 01:31:57 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:57 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:31:57 northpi-soundstation go-librespot[8362]: go-librespot daemon starting...
Dec 07 01:31:58 northpi-soundstation go-librespot[8363]: time="2025-12-07T01:31:58-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 01:31:58 northpi-soundstation go-librespot[8363]: time="2025-12-07T01:31:58-05:00" level=debug msg="app state loaded"
Dec 07 01:31:58 northpi-soundstation go-librespot[8363]: time="2025-12-07T01:31:58-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 01:31:58 northpi-soundstation go-librespot[8363]: time="2025-12-07T01:31:58-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 07 01:31:58 northpi-soundstation go-librespot[8363]: time="2025-12-07T01:31:58-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 07 01:31:58 northpi-soundstation go-librespot[8363]: time="2025-12-07T01:31:58-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 07 01:31:58 northpi-soundstation go-librespot[8363]: time="2025-12-07T01:31:58-05:00" level=info msg="zeroconf server listening on port 41407"
Dec 07 01:31:58 northpi-soundstation go-librespot[8363]: time="2025-12-07T01:31:58-05:00" level=debug msg="obtained new client token: AACF8Vpk0HSv93hRyikyMamDxYdxbT5kNfGPIoLC+oBbExjD/5R9F6ZIBZRh5lfGUtr4FmR9cDmQrymAKor/RlGqr+Cs57EYSQmlORjqAEHd0jYDHOX7or70KQYYSA36jn/E9GJcpRmUI3FL8Kg7tiOUPwEbXFc/rc/N/vCJKAZeGPElqfnJbhp8VNaZBX5/vqU17nNlq+MCVzdIyqyU4FVuINnru1osEpQR5KKZZ9auvGS9xP4K9XH1rg=="
Dec 07 01:31:58 northpi-soundstation go-librespot[8363]: time="2025-12-07T01:31:58-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 01:31:58 northpi-soundstation go-librespot[8363]: time="2025-12-07T01:31:58-05:00" level=debug msg="completed keyexchange"
Dec 07 01:31:58 northpi-soundstation go-librespot[8363]: time="2025-12-07T01:31:58-05:00" level=debug msg="completed challenge"
Dec 07 01:31:58 northpi-soundstation go-librespot[8363]: time="2025-12-07T01:31:58-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 07 01:31:58 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 01:31:58 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 01:31:58 northpi-soundstation volumio[7638]: info: Initializing connection to go-librespot Websocket
Dec 07 01:31:58 northpi-soundstation volumio[7638]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 01:32:01 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 270.
Dec 07 01:32:01 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:32:01 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 01:32:01 northpi-soundstation go-librespot[8384]: go-librespot daemon starting...
Dec 07 01:32:01 northpi-soundstation go-librespot[8385]: time="2025-12-07T01:32:01-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 01:32:01 northpi-soundstation go-librespot[8385]: time="2025-12-07T01:32:01-05:00" level=debug msg="app state loaded"
Dec 07 01:32:01 northpi-soundstation go-librespot[8385]: time="2025-12-07T01:32:01-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 01:32:01 northpi-soundstation go-librespot[8385]: time="2025-12-07T01:32:01-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 07 01:32:01 northpi-soundstation go-librespot[8385]: time="2025-12-07T01:32:01-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 07 01:32:01 northpi-soundstation go-librespot[8385]: time="2025-12-07T01:32:01-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 07 01:32:01 northpi-soundstation go-librespot[8385]: time="2025-12-07T01:32:01-05:00" level=info msg="zeroconf server listening on port 45769"
Dec 07 01:32:01 northpi-soundstation volumio[7638]: info: Initializing connection to go-librespot Websocket
Dec 07 01:32:01 northpi-soundstation go-librespot[8385]: time="2025-12-07T01:32:01-05:00" level=debug msg="new websocket client"
Dec 07 01:32:01 northpi-soundstation volumio[7638]: info: Connection to go-librespot Websocket established
Dec 07 01:32:02 northpi-soundstation go-librespot[8385]: time="2025-12-07T01:32:02-05:00" level=debug msg="obtained new client token: AAAWqHG86iynRAv+upJHYL6ThGhoqhFav5Dc1ePXpU1Hon8X/g4C6ClWFeGZ7Xs0/ectEcR9ybN89JQbLWHKCGGzV1TBwpvLKPzjXdNQwWiSe20pW/MB+xMzdsaqIggd5+WYUcwQTpxtdoJuzEnejc3UWPBAK8bscJvE6tcnHNeFBIKvZli5UDvRn1eAah0my0M83/MX99ELTeYijnv0N7a9isY6SFZnp/KmczZys773ANBRgy+3"
Dec 07 01:32:02 northpi-soundstation go-librespot[8385]: time="2025-12-07T01:32:02-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 01:32:02 northpi-soundstation go-librespot[8385]: time="2025-12-07T01:32:02-05:00" level=debug msg="completed keyexchange"
Dec 07 01:32:02 northpi-soundstation go-librespot[8385]: time="2025-12-07T01:32:02-05:00" level=debug msg="completed challenge"
Dec 07 01:32:02 northpi-soundstation go-librespot[8385]: time="2025-12-07T01:32:02-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 07 01:32:02 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 01:32:02 northpi-soundstation volumio[7638]: info: Connection to go-librespot Websocket closed
Dec 07 01:32:02 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 01:32:02 northpi-soundstation volumio[7638]: info: CoreCommandRouter::volumioGetState
Dec 07 01:32:04 northpi-soundstation volumio[7638]: info: Getting Spotify volume
Dec 07 01:32:04 northpi-soundstation volumio[7638]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 07 01:32:04 northpi-soundstation volumio[7638]: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 01:32:04 northpi-soundstation volumio[7638]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Dec 07 01:32:04 northpi-soundstation volumio[7638]: errno: -111,
Dec 07 01:32:04 northpi-soundstation volumio[7638]: code: 'ECONNREFUSED',
Dec 07 01:32:04 northpi-soundstation volumio[7638]: syscall: 'connect',
Dec 07 01:32:04 northpi-soundstation volumio[7638]: address: '127.0.0.1',
Dec 07 01:32:04 northpi-soundstation volumio[7638]: port: 9879,
Dec 07 01:32:04 northpi-soundstation volumio[7638]: response: undefined
Dec 07 01:32:04 northpi-soundstation volumio[7638]: }
Dec 07 01:32:04 northpi-soundstation volumio[7638]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 07 01:32:05 northpi-soundstation sudo[8406]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-07 01:31'
Dec 07 01:32:05 northpi-soundstation sudo[8406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 01:32:05 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 271.
Dec 07 01:32:05 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
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="3a3cfe435f8bb2bc94a920662e91a4ef35bc8e7c"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue Dec 2 11:51:19 UTC 2025"
VOLUMIO_VERSION="4.072"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="e5001581b220b096097cf8f46a54d4f1"