Dec 09 10:18:00 northpi-soundstation sudo[14158]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Dec 09 10:18:00 northpi-soundstation sudo[14158]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 10:18:00 northpi-soundstation sudo[14158]: pam_unix(sudo:session): session closed for user root
Dec 09 10:18:00 northpi-soundstation sudo[14160]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 09 10:18:00 northpi-soundstation sudo[14160]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 10:18:00 northpi-soundstation sudo[14160]: pam_unix(sudo:session): session closed for user root
Dec 09 10:18:00 northpi-soundstation sudo[14164]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Dec 09 10:18:00 northpi-soundstation sudo[14164]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 10:18:00 northpi-soundstation sudo[14164]: pam_unix(sudo:session): session closed for user root
Dec 09 10:18:00 northpi-soundstation volumio[13861]: info: Upmpdcli Daemon Started
Dec 09 10:18:01 northpi-soundstation volumio[13861]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Dec 09 10:18:01 northpi-soundstation volumio[13861]: info: Initializing connection to go-librespot Websocket
Dec 09 10:18:01 northpi-soundstation volumio[13861]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 10:18:02 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6.
Dec 09 10:18:02 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:02 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:02 northpi-soundstation go-librespot[14166]: go-librespot daemon starting...
Dec 09 10:18:02 northpi-soundstation go-librespot[14167]: time="2025-12-09T10:18:02-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 10:18:02 northpi-soundstation go-librespot[14167]: time="2025-12-09T10:18:02-05:00" level=debug msg="app state loaded"
Dec 09 10:18:02 northpi-soundstation go-librespot[14167]: time="2025-12-09T10:18:02-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 10:18:02 northpi-soundstation go-librespot[14167]: time="2025-12-09T10:18:02-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 09 10:18:02 northpi-soundstation go-librespot[14167]: time="2025-12-09T10:18:02-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 09 10:18:02 northpi-soundstation go-librespot[14167]: time="2025-12-09T10:18:02-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 09 10:18:02 northpi-soundstation go-librespot[14167]: time="2025-12-09T10:18:02-05:00" level=info msg="zeroconf server listening on port 37559"
Dec 09 10:18:03 northpi-soundstation go-librespot[14167]: time="2025-12-09T10:18:03-05:00" level=debug msg="obtained new client token: AABdZmdlDPy2F3cLbYbsIU0y6PtJxhCeeerBczsWOk1eLzHqLVHzLASruU/EetQayflkD858U9rNm+X9c7SxQLLarqV8kWmiac/UpNjItEnsV+/0SNX2WLgAokNmH9ryFe2no/F/xnDavYxnOSxwRwjHNBBb9LZlyKbyJUGzKkyRYUitzgs1Wc/acjwKkvYnFIHwdwZo5xFyav0QLE7ErzoKOK9vYu4cMii1G1GXQHC47VXBGFV68FE="
Dec 09 10:18:03 northpi-soundstation go-librespot[14167]: time="2025-12-09T10:18:03-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 10:18:03 northpi-soundstation go-librespot[14167]: time="2025-12-09T10:18:03-05:00" level=debug msg="completed keyexchange"
Dec 09 10:18:03 northpi-soundstation go-librespot[14167]: time="2025-12-09T10:18:03-05:00" level=debug msg="completed challenge"
Dec 09 10:18:03 northpi-soundstation go-librespot[14167]: time="2025-12-09T10:18: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 09 10:18:03 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 10:18:03 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 10:18:04 northpi-soundstation systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 3.
Dec 09 10:18:04 northpi-soundstation systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service.
Dec 09 10:18:04 northpi-soundstation systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service.
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: No valid Plugin REST Endpoint
Dec 09 10:18:04 northpi-soundstation qobuz-connect[14174]: 20251209 10:18:04.284 [14174.14174] ERROR SampleApp: api request was not successful
Dec 09 10:18:04 northpi-soundstation qobuz-connect[14174]: Error: Application ID was not specified!
Dec 09 10:18:04 northpi-soundstation qobuz-connect[14174]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...]
Dec 09 10:18:04 northpi-soundstation qobuz-connect[14174]: -m|--manufacturer: Manufacturer of the device
Dec 09 10:18:04 northpi-soundstation qobuz-connect[14174]: -n|--deviceName: Device name
Dec 09 10:18:04 northpi-soundstation qobuz-connect[14174]: -l|--model: Device's model name
Dec 09 10:18:04 northpi-soundstation qobuz-connect[14174]: -s|--serialNumber: Device's serial number
Dec 09 10:18:04 northpi-soundstation qobuz-connect[14174]: -o|--outputDevice: ALSA PCM device to play audio to
Dec 09 10:18:04 northpi-soundstation qobuz-connect[14174]: -p|--port: Port to run the local configuration HTTP server on
Dec 09 10:18:04 northpi-soundstation qobuz-connect[14174]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3]
Dec 09 10:18:04 northpi-soundstation qobuz-connect[14174]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control
Dec 09 10:18:04 northpi-soundstation qobuz-connect[14174]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control
Dec 09 10:18:04 northpi-soundstation qobuz-connect[14174]: -h|--help: Print this help
Dec 09 10:18:04 northpi-soundstation systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 10:18:04 northpi-soundstation systemd[1]: qobuz-connect.service: Failed with result 'exit-code'.
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: Adding plugin bluetooth to MyMusic Plugins
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: Adding plugin multiroom to MyMusic Plugins
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: Adding plugin metavolumio to MyMusic Plugins
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: Adding plugin cd_controller to MyMusic Plugins
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: Adding plugin qobuzconnect to MyMusic Plugins
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: Adding plugin smart_inputs to MyMusic Plugins
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: Adding plugin tidalconnect to MyMusic Plugins
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: Starting MyVolumio Remote Streaming Endpoints
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: MyVolumio login type: Token
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Dec 09 10:18:04 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Dec 09 10:18:05 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Dec 09 10:18:05 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Dec 09 10:18:05 northpi-soundstation volumio[13861]: info: Streaming services startup
Dec 09 10:18:05 northpi-soundstation volumio[13861]: info: Starting Streaming Daemon
Dec 09 10:18:05 northpi-soundstation sudo[14176]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 09 10:18:05 northpi-soundstation sudo[14176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 10:18:05 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Dec 09 10:18:05 northpi-soundstation sudo[14176]: pam_unix(sudo:session): session closed for user root
Dec 09 10:18:05 northpi-soundstation volumio[13861]: info: Initializing connection to go-librespot Websocket
Dec 09 10:18:05 northpi-soundstation volumio[13861]: error: Cannot start Volumio Streaming Daemon
Dec 09 10:18:05 northpi-soundstation volumio[13861]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 09 10:18:05 northpi-soundstation volumio[13861]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 09 10:18:05 northpi-soundstation volumio[13861]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 10:18:05 northpi-soundstation volumio[13861]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Dec 09 10:18:06 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7.
Dec 09 10:18:06 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:06 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:06 northpi-soundstation go-librespot[14185]: go-librespot daemon starting...
Dec 09 10:18:06 northpi-soundstation volumio[13861]: info: MyVolumio token set successfully
Dec 09 10:18:06 northpi-soundstation volumio[13861]: info: MYVOLUMIO: Adding device
Dec 09 10:18:06 northpi-soundstation volumio[13861]: info: MYVOLUMIO: Evaluating Server
Dec 09 10:18:06 northpi-soundstation go-librespot[14187]: time="2025-12-09T10:18:06-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 10:18:06 northpi-soundstation go-librespot[14187]: time="2025-12-09T10:18:06-05:00" level=debug msg="app state loaded"
Dec 09 10:18:06 northpi-soundstation go-librespot[14187]: time="2025-12-09T10:18:06-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 10:18:06 northpi-soundstation go-librespot[14187]: time="2025-12-09T10:18:06-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 09 10:18:06 northpi-soundstation go-librespot[14187]: time="2025-12-09T10:18:06-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 09 10:18:06 northpi-soundstation go-librespot[14187]: time="2025-12-09T10:18:06-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 09 10:18:06 northpi-soundstation go-librespot[14187]: time="2025-12-09T10:18:06-05:00" level=info msg="zeroconf server listening on port 42435"
Dec 09 10:18:06 northpi-soundstation volumio[13861]: info: MyVolumio Plan changed: premium
Dec 09 10:18:06 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Subscribed plan changed to premium
Dec 09 10:18:06 northpi-soundstation volumio[13861]: info: Removing browser output: myVolumio user plan is not superstar
Dec 09 10:18:06 northpi-soundstation volumio[13861]: info: Removing audio output:
Dec 09 10:18:06 northpi-soundstation volumio[13861]: info: MYVOLUMIO: Adding device
Dec 09 10:18:06 northpi-soundstation volumio[13861]: info: MYVOLUMIO: Evaluating Server
Dec 09 10:18:06 northpi-soundstation volumio[13861]: info: Remote config written successfully
Dec 09 10:18:06 northpi-soundstation volumio[13861]: info: Starting Tunnel 1
Dec 09 10:18:06 northpi-soundstation volumio[13861]: info: Starting Tunnel Connection Checker
Dec 09 10:18:06 northpi-soundstation volumio[13861]: info: Completed starting MyVolumio Plugin
Dec 09 10:18:06 northpi-soundstation go-librespot[14187]: time="2025-12-09T10:18:06-05:00" level=debug msg="obtained new client token: AAC8TV0KXNyOX2XzSYyUZOWQdW33fyMonsOYAg5SFSugrIQBY1oxxFQeWGamUC1dtRi0JxwGc2S8qhjj99vTZR8VXqxHVCwRG6dwkKEd9L4tcOqzqypFxIpSC3hV6PVmfUK+MOhnFVAQBD33zjnTSb33HppMRCmHFbYs4QtZ8RZet3SQ1raYtGDEdILH7b4ikts5/qfSVAxq+daBwjOQc0YfdxP5Nqf6vMCP3iL0tV7X3PdRDqM5ggtyLQ=="
Dec 09 10:18:06 northpi-soundstation go-librespot[14187]: time="2025-12-09T10:18:06-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 10:18:06 northpi-soundstation go-librespot[14187]: time="2025-12-09T10:18:06-05:00" level=debug msg="completed keyexchange"
Dec 09 10:18:06 northpi-soundstation go-librespot[14187]: time="2025-12-09T10:18:06-05:00" level=debug msg="completed challenge"
Dec 09 10:18:06 northpi-soundstation go-librespot[14187]: time="2025-12-09T10:18:06-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 09 10:18:06 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 10:18:06 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 10:18:07 northpi-soundstation volumio[13861]: info: MYVolumio Device enabled
Dec 09 10:18:07 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins...
Dec 09 10:18:07 northpi-soundstation volumio[13861]: info: MyVolumio status changed
Dec 09 10:18:07 northpi-soundstation volumio[13861]: info: Streaming services startup
Dec 09 10:18:07 northpi-soundstation volumio[13861]: info: Starting Streaming Daemon
Dec 09 10:18:07 northpi-soundstation sudo[14232]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 09 10:18:07 northpi-soundstation sudo[14232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 10:18:07 northpi-soundstation volumio[13861]: info: Setting Geolocation for MyVolumio to us1
Dec 09 10:18:07 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 10:18:07 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 10:18:07 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 10:18:07 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid
Dec 09 10:18:07 northpi-soundstation sudo[14232]: pam_unix(sudo:session): session closed for user root
Dec 09 10:18:07 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"...
Dec 09 10:18:07 northpi-soundstation volumio[13861]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart
Dec 09 10:18:07 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Loading plugin "multiroom"...
Dec 09 10:18:07 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom
Dec 09 10:18:07 northpi-soundstation sudo[14236]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom
Dec 09 10:18:07 northpi-soundstation sudo[14236]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 10:18:07 northpi-soundstation sudo[14236]: pam_unix(sudo:session): session closed for user root
Dec 09 10:18:07 northpi-soundstation volumio[13861]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists
Dec 09 10:18:07 northpi-soundstation volumio[13861]: info: MRS: MultiRoom plugin initialized
Dec 09 10:18:07 northpi-soundstation volumio[13861]: info: MRS: STOPPING SNAPCLIENT
Dec 09 10:18:07 northpi-soundstation volumio[13861]: info: MRS: Snap server stop
Dec 09 10:18:07 northpi-soundstation volumio[13861]: info: MRS: STOPPING volumioStreaming
Dec 09 10:18:07 northpi-soundstation sudo[14253]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient
Dec 09 10:18:08 northpi-soundstation sudo[14253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"...
Dec 09 10:18:08 northpi-soundstation sudo[14256]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver
Dec 09 10:18:08 northpi-soundstation sudo[14256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 10:18:08 northpi-soundstation sudo[14258]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming
Dec 09 10:18:08 northpi-soundstation sudo[14258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Loading plugin "manifestui"...
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"...
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"...
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"...
Dec 09 10:18:08 northpi-soundstation sudo[14253]: pam_unix(sudo:session): session closed for user root
Dec 09 10:18:08 northpi-soundstation sudo[14260]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/*
Dec 09 10:18:08 northpi-soundstation sudo[14260]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 10:18:08 northpi-soundstation sudo[14260]: pam_unix(sudo:session): session closed for user root
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Loading plugin "tidal"...
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Loading plugin "qobuz"...
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"...
Dec 09 10:18:08 northpi-soundstation sudo[14256]: pam_unix(sudo:session): session closed for user root
Dec 09 10:18:08 northpi-soundstation sudo[14258]: pam_unix(sudo:session): session closed for user root
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"...
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: Preparing to generate the ALSA configuration file
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: Updating MyVolumio device info
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: Setting Geolocation for MyVolumio to us1
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: Successfully Added MyVolumio device
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: MRS: Removed streaming files
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: MRS: volumioStreaming STOPPED
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: MRS: SNAPSERVER STOPPED
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: MRS: SNAPCLIENT STOPPED
Dec 09 10:18:08 northpi-soundstation volumio[13861]: error: Cannot start Volumio Streaming Daemon
Dec 09 10:18:08 northpi-soundstation volumio[13861]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 09 10:18:08 northpi-soundstation volumio[13861]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: Asound.conf file unchanged, so no further update is needed
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: Output device has changed, restarting MPD
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: Output device has changed, restarting Shairport Sync
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 10:18:08 northpi-soundstation sudo[14266]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Dec 09 10:18:08 northpi-soundstation sudo[14266]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 10:18:08 northpi-soundstation sudo[14266]: pam_unix(sudo:session): session closed for user root
Dec 09 10:18:08 northpi-soundstation sudo[14270]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Dec 09 10:18:08 northpi-soundstation sudo[14270]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth
Dec 09 10:18:08 northpi-soundstation volumio[13861]: ------------------------------------ BT MESSAGE: [FUNC] onStart
Dec 09 10:18:08 northpi-soundstation volumio[13861]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service
Dec 09 10:18:08 northpi-soundstation volumio[13861]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp
Dec 09 10:18:08 northpi-soundstation volumio[13861]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready.
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus
Dec 09 10:18:08 northpi-soundstation systemd[1]: Stopping mpd.service - Music Player Daemon...
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: MRS: Pushing multiroomSync output for this device
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: MRS: Pushing multiroomSync output
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: Adding audio output:
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: Adding audio output:
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: Adding METAVOLUMIO REST API Endpoints
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: Preparing CD Folders
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: Adding CD REST API Endpoints
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: Starting UDEV Watcher for CD
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: Detecting CD presence with UDEV
Dec 09 10:18:08 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices
Dec 09 10:18:08 northpi-soundstation systemd[1]: mpd.service: Deactivated successfully.
Dec 09 10:18:08 northpi-soundstation systemd[1]: Stopped mpd.service - Music Player Daemon.
Dec 09 10:18:08 northpi-soundstation systemd[1]: mpd.service: Consumed 1.759s CPU time.
Dec 09 10:18:08 northpi-soundstation systemd[1]: mpd.socket: Deactivated successfully.
Dec 09 10:18:08 northpi-soundstation systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Dec 09 10:18:08 northpi-soundstation systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Dec 09 10:18:08 northpi-soundstation bluetoothd[806]: Path / reserved for Adv Monitor app :1.71148
Dec 09 10:18:08 northpi-soundstation bluetoothd[806]: Adv Monitor app :1.71148 disconnected from D-Bus
Dec 09 10:18:08 northpi-soundstation systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Dec 09 10:18:08 northpi-soundstation systemd[1]: Starting mpd.service - Music Player Daemon...
Dec 09 10:18:08 northpi-soundstation sudo[14286]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Dec 09 10:18:08 northpi-soundstation sudo[14286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Dec 09 10:18:08 northpi-soundstation sudo[14286]: pam_unix(sudo:session): session closed for user root
Dec 09 10:18:09 northpi-soundstation systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 4.
Dec 09 10:18:09 northpi-soundstation systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service.
Dec 09 10:18:09 northpi-soundstation systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service.
Dec 09 10:18:09 northpi-soundstation mpd[14288]: 2025-12-09T10:18:09 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Dec 09 10:18:09 northpi-soundstation systemd[1]: Started mpd.service - Music Player Daemon.
Dec 09 10:18:09 northpi-soundstation sudo[14270]: pam_unix(sudo:session): session closed for user root
Dec 09 10:18:10 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8.
Dec 09 10:18:10 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:10 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:10 northpi-soundstation go-librespot[14292]: go-librespot daemon starting...
Dec 09 10:18:10 northpi-soundstation go-librespot[14293]: time="2025-12-09T10:18:10-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 10:18:10 northpi-soundstation go-librespot[14293]: time="2025-12-09T10:18:10-05:00" level=debug msg="app state loaded"
Dec 09 10:18:10 northpi-soundstation go-librespot[14293]: time="2025-12-09T10:18:10-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 10:18:10 northpi-soundstation go-librespot[14293]: time="2025-12-09T10:18:10-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 09 10:18:10 northpi-soundstation go-librespot[14293]: time="2025-12-09T10:18:10-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 09 10:18:10 northpi-soundstation go-librespot[14293]: time="2025-12-09T10:18:10-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 09 10:18:10 northpi-soundstation go-librespot[14293]: time="2025-12-09T10:18:10-05:00" level=info msg="zeroconf server listening on port 45053"
Dec 09 10:18:10 northpi-soundstation go-librespot[14293]: time="2025-12-09T10:18:10-05:00" level=debug msg="obtained new client token: AAB87MAYPGr2bnX3wMpkOPazEiHxoae9jkENZ61f/bSQbxk9BZjatxV+fYEYpRcuxl4ajtje6vSrmMwlSnHO5+basxYmsUQHuht+NKmsQ8+uPDr+kNJ842PEu3XAaaek8H/7FU4B6Z7QW0dJSRvlLKC57WpKK/XgpaE7qDZ/cylMMJGVgYsGFCiNqLB5NofLa1/RN4fBJOIsz/J8KcWcyqDWpFh1jFDOKB6ie7UTkA/m45RNHAcZMTYeow=="
Dec 09 10:18:10 northpi-soundstation go-librespot[14293]: time="2025-12-09T10:18:10-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 10:18:11 northpi-soundstation go-librespot[14293]: time="2025-12-09T10:18:11-05:00" level=debug msg="completed keyexchange"
Dec 09 10:18:11 northpi-soundstation go-librespot[14293]: time="2025-12-09T10:18:11-05:00" level=debug msg="completed challenge"
Dec 09 10:18:12 northpi-soundstation go-librespot[14293]: time="2025-12-09T10:18:12-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 09 10:18:12 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 10:18:12 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 10:18:13 northpi-soundstation volumio[13861]: warn: [cd-plugin] cdspeedctl: device or media not ready
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: Adding inputs REST Endpoints
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: Scanning Audio Inputs
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: Checking against Known Cards name
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: Adding Server instance for streaming
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio
Dec 09 10:18:13 northpi-soundstation volumio[13861]: error: Hi Res Audio Failed Login: Missing Login Data
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: Adding HIGHRESAUDIO REST API Endpoints
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect
Dec 09 10:18:13 northpi-soundstation sudo[14318]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: QobuzConnect: Starting Qobuz Connect socket and service
Dec 09 10:18:13 northpi-soundstation sudo[14318]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: Adding TIDAL REST API Endpoints
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: Stopping AccessToken refresher cron for QOBUZ
Dec 09 10:18:13 northpi-soundstation sudo[14325]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service
Dec 09 10:18:13 northpi-soundstation sudo[14325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 10:18:13 northpi-soundstation sudo[14318]: pam_unix(sudo:session): session closed for user root
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: AccessToken refresher cron started for QOBUZ
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: Adding QOBUZ REST API Endpoints
Dec 09 10:18:13 northpi-soundstation sudo[14325]: pam_unix(sudo:session): session closed for user root
Dec 09 10:18:13 northpi-soundstation sudo[14328]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service
Dec 09 10:18:13 northpi-soundstation sudo[14328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: Initializing connection to go-librespot Websocket
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: MRS: Getting audio outputs on start
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: MRS: Requesting all other devices output
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: Updating MyVolumio device info
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 10:18:13 northpi-soundstation systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service...
Dec 09 10:18:13 northpi-soundstation systemd[1]: qobuz-connect.service: Deactivated successfully.
Dec 09 10:18:13 northpi-soundstation systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service.
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: Successfully Added MyVolumio device
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: Successfully Updated MyVolumio device
Dec 09 10:18:13 northpi-soundstation volumio[13861]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on
Dec 09 10:18:13 northpi-soundstation systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service.
Dec 09 10:18:13 northpi-soundstation sudo[14328]: pam_unix(sudo:session): session closed for user root
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: MPD Permissions set
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: Executing endpoint qc_getconfig
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 10:18:13 northpi-soundstation sudo[14333]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service
Dec 09 10:18:13 northpi-soundstation sudo[14333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 10:18:13 northpi-soundstation sudo[14333]: pam_unix(sudo:session): session closed for user root
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 10:18:13 northpi-soundstation volumio[13861]: error: updateQueue error: null
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: Executing endpoint qc_getconfig
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Dec 09 10:18:13 northpi-soundstation qobuz-connect[14331]: 20251209 10:18:13.780 [14331.14331] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Dec 09 10:18:13 northpi-soundstation qobuz-connect[14331]: 20251209 10:18:13.784 [14331.14331] INFO VolumeManager: [0x229598]: Setting new playback volume: 75
Dec 09 10:18:13 northpi-soundstation qobuz-connect[14331]: 20251209 10:18:13.784 [14331.14331] INFO VolumeManager: [0x229598]: Setting new mute state: 0
Dec 09 10:18:13 northpi-soundstation qobuz-connect[14331]: 20251209 10:18:13.784 [14331.14331] INFO QobuzConnect: [0x229f68]: Client initialized!
Dec 09 10:18:13 northpi-soundstation qobuz-connect[14331]: 20251209 10:18:13.784 [14331.14331] INFO SampleApp: Starting Avahi advertising, name: NorthPi-Soundstation, service name: _qobuz-connect._tcp
Dec 09 10:18:13 northpi-soundstation volumio[13861]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: CoreCommandRouter::volumioStop
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: CoreStateMachine::stop
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 09 10:18:13 northpi-soundstation volumio[13861]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object]
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: QobuzConnect: QOBUZ Connect daemon connected
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: Starting Shairport Sync
Dec 09 10:18:13 northpi-soundstation sudo[14352]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service
Dec 09 10:18:13 northpi-soundstation qobuz-connect[14331]: 20251209 10:18:13.809 [14331.14331] INFO LocalConfigManager: [0x228e78]: Starting Local Configuration server
Dec 09 10:18:13 northpi-soundstation qobuz-connect[14331]: 20251209 10:18:13.809 [14331.14331] INFO SampleApp: Starting Local configuration server
Dec 09 10:18:13 northpi-soundstation qobuz-connect[14331]: 20251209 10:18:13.809 [14331.14331] INFO SampleApp: Connected to UNIX socket client 0x213818
Dec 09 10:18:13 northpi-soundstation sudo[14352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: Successfully Updated MyVolumio device
Dec 09 10:18:13 northpi-soundstation sudo[14356]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 09 10:18:13 northpi-soundstation sudo[14356]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 10:18:13 northpi-soundstation autossh[9217]: received signal to exit (15)
Dec 09 10:18:13 northpi-soundstation systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel...
Dec 09 10:18:13 northpi-soundstation systemd[1]: sshtunnel.service: Deactivated successfully.
Dec 09 10:18:13 northpi-soundstation systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel.
Dec 09 10:18:13 northpi-soundstation qobuz-connect[14331]: 20251209 10:18:13.929 [14331.14331] INFO SampleApp: Playback volume changed: 75
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: CoreCommandRouter::volumioGetState
Dec 09 10:18:13 northpi-soundstation systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel.
Dec 09 10:18:13 northpi-soundstation sudo[14352]: pam_unix(sudo:session): session closed for user root
Dec 09 10:18:13 northpi-soundstation autossh[14360]: port set to 0, monitoring disabled
Dec 09 10:18:13 northpi-soundstation volumio[13861]: info: Remote SSH Started
Dec 09 10:18:13 northpi-soundstation autossh[14360]: starting ssh (count 1)
Dec 09 10:18:13 northpi-soundstation autossh[14360]: ssh child pid is 14363
Dec 09 10:18:13 northpi-soundstation systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Dec 09 10:18:13 northpi-soundstation systemd[1]: shairport-sync.service: Deactivated successfully.
Dec 09 10:18:13 northpi-soundstation systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Dec 09 10:18:13 northpi-soundstation systemd[1]: shairport-sync.service: Consumed 1.619s CPU time.
Dec 09 10:18:13 northpi-soundstation systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Dec 09 10:18:13 northpi-soundstation sudo[14356]: pam_unix(sudo:session): session closed for user root
Dec 09 10:18:14 northpi-soundstation volumio[13861]: info: Shairport-Sync Started
Dec 09 10:18:14 northpi-soundstation volumio[13861]: 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 09 10:18:14 northpi-soundstation volumiossh-tunnel[14363]: Warning: Permanently added '[us1.myvolumio.org]:2222' (RSA) to the list of known hosts.
Dec 09 10:18:14 northpi-soundstation volumio[13861]: info: CoreCommandRouter::volumioGetState
Dec 09 10:18:14 northpi-soundstation volumio[13861]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.0b008618efe21bea1cc98e8c917fa0a8.state.status'
Dec 09 10:18:15 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9.
Dec 09 10:18:15 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:15 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:15 northpi-soundstation go-librespot[14379]: go-librespot daemon starting...
Dec 09 10:18:15 northpi-soundstation go-librespot[14380]: time="2025-12-09T10:18:15-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 10:18:15 northpi-soundstation go-librespot[14380]: time="2025-12-09T10:18:15-05:00" level=debug msg="app state loaded"
Dec 09 10:18:15 northpi-soundstation go-librespot[14380]: time="2025-12-09T10:18:15-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 10:18:15 northpi-soundstation go-librespot[14380]: time="2025-12-09T10:18:15-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 09 10:18:15 northpi-soundstation go-librespot[14380]: time="2025-12-09T10:18:15-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 09 10:18:15 northpi-soundstation go-librespot[14380]: time="2025-12-09T10:18:15-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 09 10:18:15 northpi-soundstation go-librespot[14380]: time="2025-12-09T10:18:15-05:00" level=info msg="zeroconf server listening on port 35319"
Dec 09 10:18:15 northpi-soundstation go-librespot[14380]: time="2025-12-09T10:18:15-05:00" level=debug msg="obtained new client token: AABtvZX5gYQ8sOFYZb93ChWQKz9E3VtMxCHd4c3uDtF0VOMXrKn7k+FHdENZCI7mwaL+NhX79ME+ikN1Qt9oKWU4P5pOT/uPSeoSTGBczf5SK4CrxVlY/j8h1eFvQYmNfHTEcs8vscCnlEhgU6gSjeU3iF63Itd8BfeXxnQue9S7RX8N/dQhwch/3BGw1FoS1vi39BbWgGvbZsDek+K4LgN3rbez7WkvigyzZw8Q8uh4IL56BqEpHo6XQw=="
Dec 09 10:18:15 northpi-soundstation go-librespot[14380]: time="2025-12-09T10:18:15-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 10:18:15 northpi-soundstation go-librespot[14380]: time="2025-12-09T10:18:15-05:00" level=debug msg="completed keyexchange"
Dec 09 10:18:15 northpi-soundstation go-librespot[14380]: time="2025-12-09T10:18:15-05:00" level=debug msg="completed challenge"
Dec 09 10:18:15 northpi-soundstation go-librespot[14380]: time="2025-12-09T10:18:15-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 09 10:18:15 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 10:18:15 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: TidalConnect service stoped!
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect
Dec 09 10:18:16 northpi-soundstation sudo[14396]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Dec 09 10:18:16 northpi-soundstation sudo[14396]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 10:18:16 northpi-soundstation sudo[14400]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Dec 09 10:18:16 northpi-soundstation sudo[14400]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: Initializing connection to go-librespot Websocket
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 10:18:16 northpi-soundstation systemd[1]: Started vtcs.service - Volumio Tidal Connect Service.
Dec 09 10:18:16 northpi-soundstation sudo[14396]: pam_unix(sudo:session): session closed for user root
Dec 09 10:18:16 northpi-soundstation systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service...
Dec 09 10:18:16 northpi-soundstation systemd[1]: mpd_monitor.service: Deactivated successfully.
Dec 09 10:18:16 northpi-soundstation systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service.
Dec 09 10:18:16 northpi-soundstation systemd[1]: Started mpd_monitor.service - MPD Monitor Service.
Dec 09 10:18:16 northpi-soundstation mpd_monitor.sh[14404]: MPD Monitor Service: Starting MPD Monitor Service
Dec 09 10:18:16 northpi-soundstation sudo[14400]: pam_unix(sudo:session): session closed for user root
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: Successfully started MPD Monitor
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: Executing endpoint tc_getconfig
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig
Dec 09 10:18:16 northpi-soundstation vtcs[14402]: STARTING TidalConnect services, version: 1.5.2.56
Dec 09 10:18:16 northpi-soundstation vtcs[14402]: STARTED TidalConnect services.
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: Executing endpoint tc_connect
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: Connecting to TidalConnect
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreCommandRouter::servicePushState
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreStateMachine::pushState
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreCommandRouter::volumioPushState
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: MRS: Pushing multiroomSync output update for this device
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: MRS: Pushing multiroomSync output
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreCommandRouter::volumioGetState
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CorePlayQueue::getTrack 0
Dec 09 10:18:16 northpi-soundstation volumio[13861]: 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 09 10:18:16 northpi-soundstation volumio[13861]: verbose: CURRENT POSITION 0
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreStateMachine::syncState stateService stop
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreStateMachine::syncState currentStatus stop
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreStateMachine::pushState
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreCommandRouter::volumioPushState
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: MRS: Pushing multiroomSync output update for this device
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: MRS: Pushing multiroomSync output
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreCommandRouter::volumioGetState
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: No code
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreStateMachine::pushState
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreCommandRouter::volumioPushState
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: MRS: Pushing multiroomSync output update for this device
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: MRS: Pushing multiroomSync output
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreCommandRouter::volumioGetState
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreCommandRouter::servicePushState
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreStateMachine::pushState
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreCommandRouter::volumioPushState
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: MRS: Pushing multiroomSync output update for this device
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: MRS: Pushing multiroomSync output
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreCommandRouter::volumioGetState
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CorePlayQueue::getTrack 0
Dec 09 10:18:16 northpi-soundstation volumio[13861]: 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 09 10:18:16 northpi-soundstation volumio[13861]: verbose: CURRENT POSITION 0
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreStateMachine::syncState stateService stop
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreStateMachine::syncState currentStatus stop
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreStateMachine::pushState
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreCommandRouter::volumioPushState
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: MRS: Pushing multiroomSync output update for this device
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: MRS: Pushing multiroomSync output
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreCommandRouter::volumioGetState
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: No code
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreStateMachine::pushState
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreCommandRouter::volumioPushState
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: MRS: Pushing multiroomSync output update for this device
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: MRS: Pushing multiroomSync output
Dec 09 10:18:16 northpi-soundstation volumio[13861]: info: CoreCommandRouter::volumioGetState
Dec 09 10:18:16 northpi-soundstation volumio[13861]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.0b008618efe21bea1cc98e8c917fa0a8.state.status'
Dec 09 10:18:16 northpi-soundstation volumio[13861]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.0b008618efe21bea1cc98e8c917fa0a8.state.status'
Dec 09 10:18:16 northpi-soundstation volumio[13861]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.0b008618efe21bea1cc98e8c917fa0a8.state.status'
Dec 09 10:18:16 northpi-soundstation volumio[13861]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.0b008618efe21bea1cc98e8c917fa0a8.state.status'
Dec 09 10:18:18 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10.
Dec 09 10:18:18 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:18 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:18 northpi-soundstation go-librespot[14422]: go-librespot daemon starting...
Dec 09 10:18:19 northpi-soundstation go-librespot[14423]: time="2025-12-09T10:18:19-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 10:18:19 northpi-soundstation go-librespot[14423]: time="2025-12-09T10:18:19-05:00" level=debug msg="app state loaded"
Dec 09 10:18:19 northpi-soundstation go-librespot[14423]: time="2025-12-09T10:18:19-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 10:18:19 northpi-soundstation go-librespot[14423]: time="2025-12-09T10:18:19-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 09 10:18:19 northpi-soundstation go-librespot[14423]: time="2025-12-09T10:18:19-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 09 10:18:19 northpi-soundstation go-librespot[14423]: time="2025-12-09T10:18:19-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 09 10:18:19 northpi-soundstation go-librespot[14423]: time="2025-12-09T10:18:19-05:00" level=info msg="zeroconf server listening on port 33483"
Dec 09 10:18:19 northpi-soundstation go-librespot[14423]: time="2025-12-09T10:18:19-05:00" level=debug msg="obtained new client token: AABSHOvbw1XHxX48QyBYoFm/JN6wjbBGEHE00pbco3/TPdfccJ148kHg3y268N1pKofePTkYnN3RsFXWzHwirdVLX9DMN/gyf/ZAqzZrnBkgbD4xQZ2M2NUKCxjOFF9qLTX0+KnhtXg2p7y2ln792m1L/Le9W5XyqEH8NisC/zQtAb6htj/D/acrJxop+Arov2yP/sEv4nfbScMBnaMd+UBV8qOeuuJRGAyMhzc5ihOkNse2noiEuFWUjg=="
Dec 09 10:18:19 northpi-soundstation go-librespot[14423]: time="2025-12-09T10:18:19-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 10:18:19 northpi-soundstation go-librespot[14423]: time="2025-12-09T10:18:19-05:00" level=debug msg="completed keyexchange"
Dec 09 10:18:19 northpi-soundstation go-librespot[14423]: time="2025-12-09T10:18:19-05:00" level=debug msg="completed challenge"
Dec 09 10:18:19 northpi-soundstation go-librespot[14423]: time="2025-12-09T10:18:19-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 09 10:18:19 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 10:18:19 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 10:18:19 northpi-soundstation volumio[13861]: info: Initializing connection to go-librespot Websocket
Dec 09 10:18:19 northpi-soundstation volumio[13861]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 10:18:19 northpi-soundstation volumio[13861]: info: TidalConnect service started!
Dec 09 10:18:19 northpi-soundstation volumio[13861]: [Metrics] CommandRouter: 30s 522.90ms
Dec 09 10:18:19 northpi-soundstation volumio[13861]: info: CoreCommandRouter::volumiosetStartupVolume
Dec 09 10:18:19 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 10:18:19 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 10:18:19 northpi-soundstation volumio[13861]: info: CoreCommandRouter::Close All Modals sent
Dec 09 10:18:19 northpi-soundstation volumio[13861]: info: CoreCommandRouter::Close All Modals sent
Dec 09 10:18:19 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 09 10:18:19 northpi-soundstation volumio[13861]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Dec 09 10:18:19 northpi-soundstation volumio[13861]: info: CoreCommandRouter::volumioGetState
Dec 09 10:18:20 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Dec 09 10:18:20 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Dec 09 10:18:20 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Dec 09 10:18:22 northpi-soundstation volumio[13861]: info: Initializing connection to go-librespot Websocket
Dec 09 10:18:22 northpi-soundstation volumio[13861]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 10:18:22 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11.
Dec 09 10:18:22 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:22 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:22 northpi-soundstation go-librespot[14449]: go-librespot daemon starting...
Dec 09 10:18:22 northpi-soundstation go-librespot[14450]: time="2025-12-09T10:18:22-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 10:18:22 northpi-soundstation go-librespot[14450]: time="2025-12-09T10:18:22-05:00" level=debug msg="app state loaded"
Dec 09 10:18:22 northpi-soundstation go-librespot[14450]: time="2025-12-09T10:18:22-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 10:18:23 northpi-soundstation go-librespot[14450]: time="2025-12-09T10:18:23-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 09 10:18:23 northpi-soundstation go-librespot[14450]: time="2025-12-09T10:18:23-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 09 10:18:23 northpi-soundstation go-librespot[14450]: time="2025-12-09T10:18:23-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 09 10:18:23 northpi-soundstation go-librespot[14450]: time="2025-12-09T10:18:23-05:00" level=info msg="zeroconf server listening on port 45335"
Dec 09 10:18:23 northpi-soundstation go-librespot[14450]: time="2025-12-09T10:18:23-05:00" level=debug msg="obtained new client token: AABWm3sR/k2AIsKIbYQVpcz/S/Dc0+RXfdZoAe7SVkLmjzonkSfb1asixl61tuU3RiMToNV33zxDz86JOgJfXb93Dw6YSYKkpK022kWQCLCUS4w2+52AQQV0JFOmRLmZXHGOqtDrzdQQZ7qDEVVl/xUJojeIhbZ3l2Uxt5H6eLeYiPbXp3szUdtjRaFsooPwKT401UqKyfAQt4DIwzKX32nFKLDR2NRbkixJwdq1TeGPGCvLUmYJYyw="
Dec 09 10:18:23 northpi-soundstation go-librespot[14450]: time="2025-12-09T10:18:23-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 09 10:18:23 northpi-soundstation go-librespot[14450]: time="2025-12-09T10:18:23-05:00" level=debug msg="connected to ap-gue1.spotify.com:443"
Dec 09 10:18:23 northpi-soundstation go-librespot[14450]: time="2025-12-09T10:18:23-05:00" level=debug msg="completed keyexchange"
Dec 09 10:18:23 northpi-soundstation go-librespot[14450]: time="2025-12-09T10:18:23-05:00" level=debug msg="completed challenge"
Dec 09 10:18:23 northpi-soundstation go-librespot[14450]: time="2025-12-09T10:18:23-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 09 10:18:23 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 10:18:23 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 10:18:24 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 09 10:18:24 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 10:18:24 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 09 10:18:24 northpi-soundstation volumio-remote-updater[5170]: Test mode enabled
Dec 09 10:18:24 northpi-soundstation volumio-remote-updater[5170]: Alpha mode disabled
Dec 09 10:18:24 northpi-soundstation volumio-remote-updater[5170]: Alpha legacy test mode disabled
Dec 09 10:18:24 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Dec 09 10:18:24 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 10:18:24 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 10:18:24 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Dec 09 10:18:24 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Dec 09 10:18:24 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Dec 09 10:18:24 northpi-soundstation volumio[13861]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 09 10:18:24 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 09 10:18:25 northpi-soundstation kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 13
Dec 09 10:18:25 northpi-soundstation kernel: xhci_hcd 0000:01:00.0: @0000000428d14870 00000000 00000000 0e000000 020e8001
Dec 09 10:18:25 northpi-soundstation volumio[13861]: 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 09 10:18:25 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Dec 09 10:18:25 northpi-soundstation volumio[13861]: info: Initializing connection to go-librespot Websocket
Dec 09 10:18:25 northpi-soundstation volumio[13861]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 10:18:26 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12.
Dec 09 10:18:26 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:26 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:26 northpi-soundstation go-librespot[14462]: go-librespot daemon starting...
Dec 09 10:18:26 northpi-soundstation go-librespot[14463]: time="2025-12-09T10:18:26-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 10:18:26 northpi-soundstation go-librespot[14463]: time="2025-12-09T10:18:26-05:00" level=debug msg="app state loaded"
Dec 09 10:18:26 northpi-soundstation go-librespot[14463]: time="2025-12-09T10:18:26-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 10:18:26 northpi-soundstation go-librespot[14463]: time="2025-12-09T10:18:26-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 09 10:18:26 northpi-soundstation go-librespot[14463]: time="2025-12-09T10:18:26-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 09 10:18:26 northpi-soundstation go-librespot[14463]: time="2025-12-09T10:18:26-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 09 10:18:26 northpi-soundstation go-librespot[14463]: time="2025-12-09T10:18:26-05:00" level=info msg="zeroconf server listening on port 35027"
Dec 09 10:18:26 northpi-soundstation volumio[13861]: info: BOOT COMPLETED
Dec 09 10:18:26 northpi-soundstation go-librespot[14463]: time="2025-12-09T10:18:26-05:00" level=debug msg="obtained new client token: AADYxDgT5Mz2VOv60FThOhqW+IhZAyrekVFwHhD6fygxWb2RPVoe9GVQVAJ9YfMfQD+buz85xc4QrzuH99TvEjElPoUfIixDszCfmmE//7JeMOdWRCi6B8OLEdSVhrX+jfH67KJQ/6hQfcvc76v130KtGxpsYE7bIIVYA5zry/TDY4gNuQ9l0zd2HkUBO95c2CudiiYRY3Nd3aOoDS61Jpr/NM8iYYLyx4oNfUungJ75rjCT05JwfNgjzA=="
Dec 09 10:18:26 northpi-soundstation go-librespot[14463]: time="2025-12-09T10:18:26-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 10:18:26 northpi-soundstation go-librespot[14463]: time="2025-12-09T10:18:26-05:00" level=debug msg="completed keyexchange"
Dec 09 10:18:26 northpi-soundstation go-librespot[14463]: time="2025-12-09T10:18:26-05:00" level=debug msg="completed challenge"
Dec 09 10:18:26 northpi-soundstation go-librespot[14463]: time="2025-12-09T10:18:26-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 09 10:18:26 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 10:18:26 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 10:18:28 northpi-soundstation volumio[13861]: info: Initializing connection to go-librespot Websocket
Dec 09 10:18:28 northpi-soundstation volumio[13861]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 10:18:30 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13.
Dec 09 10:18:30 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:30 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:30 northpi-soundstation go-librespot[14470]: go-librespot daemon starting...
Dec 09 10:18:30 northpi-soundstation go-librespot[14471]: time="2025-12-09T10:18:30-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 10:18:30 northpi-soundstation go-librespot[14471]: time="2025-12-09T10:18:30-05:00" level=debug msg="app state loaded"
Dec 09 10:18:30 northpi-soundstation go-librespot[14471]: time="2025-12-09T10:18:30-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 10:18:30 northpi-soundstation go-librespot[14471]: time="2025-12-09T10:18:30-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 09 10:18:30 northpi-soundstation go-librespot[14471]: time="2025-12-09T10:18:30-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 09 10:18:30 northpi-soundstation go-librespot[14471]: time="2025-12-09T10:18:30-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 09 10:18:30 northpi-soundstation go-librespot[14471]: time="2025-12-09T10:18:30-05:00" level=info msg="zeroconf server listening on port 40045"
Dec 09 10:18:30 northpi-soundstation go-librespot[14471]: time="2025-12-09T10:18:30-05:00" level=debug msg="obtained new client token: AACnCIPgLSqFpejk7uUmh0WelMCzgkDudNW9WE0QFGUtAUMNSh7V+oYRRVMZ4mddD83Ns78khfYz/8D2JxigKVXf5fdkWorGDTweschnDRE0pACyuMOlwk0aoxHtfVtnltUbsMdUwOdrtSBUgs7Ktqd+FxwmbN8sM+lHgfnm1Uqz6ICauU3JX+GMd83O5ErvZkgSX4lm+Q6n5pSA0e4G6E+Ar6QnmKexAL6qofA3tQonIy3aEBO7wjFORw=="
Dec 09 10:18:30 northpi-soundstation go-librespot[14471]: time="2025-12-09T10:18:30-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 10:18:30 northpi-soundstation go-librespot[14471]: time="2025-12-09T10:18:30-05:00" level=debug msg="completed keyexchange"
Dec 09 10:18:30 northpi-soundstation go-librespot[14471]: time="2025-12-09T10:18:30-05:00" level=debug msg="completed challenge"
Dec 09 10:18:30 northpi-soundstation go-librespot[14471]: time="2025-12-09T10:18:30-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 09 10:18:30 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 10:18:30 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 10:18:31 northpi-soundstation volumio[13861]: info: Initializing connection to go-librespot Websocket
Dec 09 10:18:31 northpi-soundstation volumio[13861]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 10:18:33 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14.
Dec 09 10:18:33 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:33 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:33 northpi-soundstation go-librespot[14492]: go-librespot daemon starting...
Dec 09 10:18:33 northpi-soundstation go-librespot[14493]: time="2025-12-09T10:18:33-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 10:18:33 northpi-soundstation go-librespot[14493]: time="2025-12-09T10:18:33-05:00" level=debug msg="app state loaded"
Dec 09 10:18:33 northpi-soundstation go-librespot[14493]: time="2025-12-09T10:18:33-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 10:18:34 northpi-soundstation go-librespot[14493]: time="2025-12-09T10:18:34-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 09 10:18:34 northpi-soundstation go-librespot[14493]: time="2025-12-09T10:18:34-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 09 10:18:34 northpi-soundstation go-librespot[14493]: time="2025-12-09T10:18:34-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 09 10:18:34 northpi-soundstation go-librespot[14493]: time="2025-12-09T10:18:34-05:00" level=info msg="zeroconf server listening on port 46561"
Dec 09 10:18:34 northpi-soundstation go-librespot[14493]: time="2025-12-09T10:18:34-05:00" level=debug msg="obtained new client token: AAB1PS1Its8sJf2qIupqExLQlWcWps3ykjfNwIT41Zcyc1b4d1bYy+udIOEjQYPRqBJAMrlUNMFZNuaQrMgjMGh+TNPutwRxE8zVDUS29Hgw9Xc+dTbYdZEgz0u4kHKdo1A8SfUCPcDG7fvedMZ2Umw3uTipWaV8vMttJ91icDyW6nEHM1NCnjCZvHSnxyiF9kDfFyT4vIa4qu3TnkJXMp4S6/D8i26eJzt4t0qRVdyvaj05l30xrMM="
Dec 09 10:18:34 northpi-soundstation go-librespot[14493]: time="2025-12-09T10:18:34-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 09 10:18:34 northpi-soundstation go-librespot[14493]: time="2025-12-09T10:18:34-05:00" level=debug msg="connected to ap-gue1.spotify.com:443"
Dec 09 10:18:34 northpi-soundstation go-librespot[14493]: time="2025-12-09T10:18:34-05:00" level=debug msg="completed keyexchange"
Dec 09 10:18:34 northpi-soundstation go-librespot[14493]: time="2025-12-09T10:18:34-05:00" level=debug msg="completed challenge"
Dec 09 10:18:34 northpi-soundstation go-librespot[14493]: time="2025-12-09T10:18:34-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 09 10:18:34 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 10:18:34 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 10:18:34 northpi-soundstation volumio[13861]: info: Initializing connection to go-librespot Websocket
Dec 09 10:18:34 northpi-soundstation volumio[13861]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 10:18:37 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15.
Dec 09 10:18:37 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:37 northpi-soundstation volumio[13861]: info: Initializing connection to go-librespot Websocket
Dec 09 10:18:37 northpi-soundstation volumio[13861]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 10:18:37 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:37 northpi-soundstation go-librespot[14503]: go-librespot daemon starting...
Dec 09 10:18:37 northpi-soundstation go-librespot[14504]: time="2025-12-09T10:18:37-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 10:18:37 northpi-soundstation go-librespot[14504]: time="2025-12-09T10:18:37-05:00" level=debug msg="app state loaded"
Dec 09 10:18:37 northpi-soundstation go-librespot[14504]: time="2025-12-09T10:18:37-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 10:18:37 northpi-soundstation go-librespot[14504]: time="2025-12-09T10:18:37-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 09 10:18:37 northpi-soundstation go-librespot[14504]: time="2025-12-09T10:18:37-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 09 10:18:37 northpi-soundstation go-librespot[14504]: time="2025-12-09T10:18:37-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 09 10:18:37 northpi-soundstation go-librespot[14504]: time="2025-12-09T10:18:37-05:00" level=info msg="zeroconf server listening on port 46155"
Dec 09 10:18:38 northpi-soundstation go-librespot[14504]: time="2025-12-09T10:18:38-05:00" level=debug msg="obtained new client token: AACxr53yrKjgIEumg1ZSAtqHl4lHlnXpqnEUA32JJySrfGUqOgmxVLCOg2+z1Dp0jBlE3YXUVQ56x7+BOMQgDz26Z5fznx+CU3pXFGJxUjFabvBt1j1ck4LciIRkqPlo0fwuV3Qf7J6y5GgGtuXW3oct2BeIglDX5TtU6jxAkv76igXq6SzX+3oc2c6/RV4CXk6CRNjyS1ScaaJXY7fpHHPG/QALnjo3nK/pfNKk9tFQtDS2ZaUoaps="
Dec 09 10:18:38 northpi-soundstation go-librespot[14504]: time="2025-12-09T10:18:38-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 10:18:38 northpi-soundstation go-librespot[14504]: time="2025-12-09T10:18:38-05:00" level=debug msg="completed keyexchange"
Dec 09 10:18:38 northpi-soundstation go-librespot[14504]: time="2025-12-09T10:18:38-05:00" level=debug msg="completed challenge"
Dec 09 10:18:38 northpi-soundstation go-librespot[14504]: time="2025-12-09T10:18:38-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 09 10:18:38 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 10:18:38 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 10:18:40 northpi-soundstation volumio[13861]: info: Initializing connection to go-librespot Websocket
Dec 09 10:18:40 northpi-soundstation volumio[13861]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 10:18:41 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16.
Dec 09 10:18:41 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:41 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:41 northpi-soundstation go-librespot[14525]: go-librespot daemon starting...
Dec 09 10:18:41 northpi-soundstation go-librespot[14526]: time="2025-12-09T10:18:41-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 10:18:41 northpi-soundstation go-librespot[14526]: time="2025-12-09T10:18:41-05:00" level=debug msg="app state loaded"
Dec 09 10:18:41 northpi-soundstation go-librespot[14526]: time="2025-12-09T10:18:41-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 10:18:41 northpi-soundstation go-librespot[14526]: time="2025-12-09T10:18:41-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 09 10:18:41 northpi-soundstation go-librespot[14526]: time="2025-12-09T10:18:41-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 09 10:18:41 northpi-soundstation go-librespot[14526]: time="2025-12-09T10:18:41-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 09 10:18:41 northpi-soundstation go-librespot[14526]: time="2025-12-09T10:18:41-05:00" level=info msg="zeroconf server listening on port 33497"
Dec 09 10:18:41 northpi-soundstation go-librespot[14526]: time="2025-12-09T10:18:41-05:00" level=debug msg="obtained new client token: AABOAlXRmDusww1Ou+uyMlX9CnUclIJM1hbmFbaIffHvHlnpkAx+/I5ZulB/KKk1fEAktlhKxBg3SUrxg0g/G57tj2v6zddA9eTXZ2FtZthhSReM17k3kTvplTm/UKpwcnWclj4z0hq93dpWvEHCn4WHeD1WrTnPiz9JbWRuOTglRRbCAIINzGkmOj9yBvrC4Mv1yaEyhJMavDysuzYjDmPEbZhQcH/YA20naWuhbqht45bcIJut2o4TQQ=="
Dec 09 10:18:41 northpi-soundstation go-librespot[14526]: time="2025-12-09T10:18:41-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 10:18:41 northpi-soundstation go-librespot[14526]: time="2025-12-09T10:18:41-05:00" level=debug msg="completed keyexchange"
Dec 09 10:18:41 northpi-soundstation go-librespot[14526]: time="2025-12-09T10:18:41-05:00" level=debug msg="completed challenge"
Dec 09 10:18:41 northpi-soundstation go-librespot[14526]: time="2025-12-09T10:18:41-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 09 10:18:41 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 10:18:41 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 10:18:43 northpi-soundstation volumio[13861]: info: Initializing connection to go-librespot Websocket
Dec 09 10:18:43 northpi-soundstation volumio[13861]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 10:18:45 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17.
Dec 09 10:18:45 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:45 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:45 northpi-soundstation go-librespot[14534]: go-librespot daemon starting...
Dec 09 10:18:45 northpi-soundstation go-librespot[14535]: time="2025-12-09T10:18:45-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 10:18:45 northpi-soundstation go-librespot[14535]: time="2025-12-09T10:18:45-05:00" level=debug msg="app state loaded"
Dec 09 10:18:45 northpi-soundstation go-librespot[14535]: time="2025-12-09T10:18:45-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 10:18:45 northpi-soundstation go-librespot[14535]: time="2025-12-09T10:18:45-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 09 10:18:45 northpi-soundstation go-librespot[14535]: time="2025-12-09T10:18:45-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 09 10:18:45 northpi-soundstation go-librespot[14535]: time="2025-12-09T10:18:45-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 09 10:18:45 northpi-soundstation go-librespot[14535]: time="2025-12-09T10:18:45-05:00" level=info msg="zeroconf server listening on port 39171"
Dec 09 10:18:45 northpi-soundstation go-librespot[14535]: time="2025-12-09T10:18:45-05:00" level=debug msg="obtained new client token: AAAHhti9/6DC74W2TnUy9LdSDdRy27PHsfdRQ3EX9UsRsTsPJ+R/E5LSTb9Pd5maWJcxDW+TGMNGY1u2K2r+xHwOvWyopj9TYDctnL7YpIBOKsWcpNyZUpG0cU+nsWlfwlcR1pnpifGSOu1rifeHiTIBr4Bk360Br4zKld4iZzqsgWOOdMt2JHCmwGCIiVbkE46h7UzpzybbrUljtpBCOCy4fOmlKJvb0l6rL2O4IlwWLvWTSM8C98exfA=="
Dec 09 10:18:45 northpi-soundstation go-librespot[14535]: time="2025-12-09T10:18:45-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 10:18:45 northpi-soundstation go-librespot[14535]: time="2025-12-09T10:18:45-05:00" level=debug msg="completed keyexchange"
Dec 09 10:18:45 northpi-soundstation go-librespot[14535]: time="2025-12-09T10:18:45-05:00" level=debug msg="completed challenge"
Dec 09 10:18:45 northpi-soundstation go-librespot[14535]: time="2025-12-09T10:18:45-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 09 10:18:45 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 10:18:45 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 10:18:46 northpi-soundstation volumio[13861]: info: Initializing connection to go-librespot Websocket
Dec 09 10:18:46 northpi-soundstation volumio[13861]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 10:18:48 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18.
Dec 09 10:18:48 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:48 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:48 northpi-soundstation go-librespot[14543]: go-librespot daemon starting...
Dec 09 10:18:49 northpi-soundstation go-librespot[14544]: time="2025-12-09T10:18:49-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 10:18:49 northpi-soundstation go-librespot[14544]: time="2025-12-09T10:18:49-05:00" level=debug msg="app state loaded"
Dec 09 10:18:49 northpi-soundstation go-librespot[14544]: time="2025-12-09T10:18:49-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 10:18:49 northpi-soundstation go-librespot[14544]: time="2025-12-09T10:18:49-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 09 10:18:49 northpi-soundstation go-librespot[14544]: time="2025-12-09T10:18:49-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 09 10:18:49 northpi-soundstation go-librespot[14544]: time="2025-12-09T10:18:49-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 09 10:18:49 northpi-soundstation go-librespot[14544]: time="2025-12-09T10:18:49-05:00" level=info msg="zeroconf server listening on port 46795"
Dec 09 10:18:49 northpi-soundstation go-librespot[14544]: time="2025-12-09T10:18:49-05:00" level=debug msg="obtained new client token: AAADoA0ED35+j/qWWqiJmxbRHCuo2rVDXa2uavRukmoXd1KOItni9Y+MYhepkrD1R2hTXjTp5PU/gzJDbNmNGOMRARabiwqYk5J7sdJZT5hnBTD0w/GG487Xw3J4XFgf3rFFoGNI/7kNb+oohLHcMVWMZMmP47gYPNIEUX1o4SbqhLxzJb9IBWqWznLq8oxcTBVw+uEoR71C04/DgxN8ChPcSstqRRTF96VlSJtt9Mxbzg2NhDYt1Xhi+Q=="
Dec 09 10:18:49 northpi-soundstation go-librespot[14544]: time="2025-12-09T10:18:49-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 10:18:49 northpi-soundstation go-librespot[14544]: time="2025-12-09T10:18:49-05:00" level=debug msg="completed keyexchange"
Dec 09 10:18:49 northpi-soundstation go-librespot[14544]: time="2025-12-09T10:18:49-05:00" level=debug msg="completed challenge"
Dec 09 10:18:49 northpi-soundstation go-librespot[14544]: time="2025-12-09T10:18:49-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 09 10:18:49 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 10:18:49 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 10:18:49 northpi-soundstation volumio[13861]: info: Initializing connection to go-librespot Websocket
Dec 09 10:18:49 northpi-soundstation volumio[13861]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 10:18:52 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19.
Dec 09 10:18:52 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:52 northpi-soundstation volumio[13861]: info: Initializing connection to go-librespot Websocket
Dec 09 10:18:52 northpi-soundstation volumio[13861]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 10:18:52 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:52 northpi-soundstation go-librespot[14565]: go-librespot daemon starting...
Dec 09 10:18:52 northpi-soundstation go-librespot[14566]: time="2025-12-09T10:18:52-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 10:18:52 northpi-soundstation go-librespot[14566]: time="2025-12-09T10:18:52-05:00" level=debug msg="app state loaded"
Dec 09 10:18:52 northpi-soundstation go-librespot[14566]: time="2025-12-09T10:18:52-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 10:18:52 northpi-soundstation go-librespot[14566]: time="2025-12-09T10:18:52-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 09 10:18:52 northpi-soundstation go-librespot[14566]: time="2025-12-09T10:18:52-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 09 10:18:52 northpi-soundstation go-librespot[14566]: time="2025-12-09T10:18:52-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 09 10:18:52 northpi-soundstation go-librespot[14566]: time="2025-12-09T10:18:52-05:00" level=info msg="zeroconf server listening on port 37751"
Dec 09 10:18:53 northpi-soundstation go-librespot[14566]: time="2025-12-09T10:18:53-05:00" level=debug msg="obtained new client token: AAAn0rS9rWR2pB9XycOrxpGv1hLvP1hPKN6gL3vbD2dZWNFPAIMVN3zlq04maPXDlN25MRqYDAs1Vl55VNgrz77QKWwr36I6MXP953aZQvLPIv2qUXKnes4ER/LRiTKp3yAHiNdyOLgKFc92c38gTj/oCEb68tPGYbJpZ7+ohAvSN5g2PxHcDEr9HYM0ARxE72eeGpIyeoJgxhczW5jpMAnKMDNaXszQTmqYvfjsTZvnraEE0qcjOBh8oA=="
Dec 09 10:18:53 northpi-soundstation go-librespot[14566]: time="2025-12-09T10:18:53-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 10:18:53 northpi-soundstation go-librespot[14566]: time="2025-12-09T10:18:53-05:00" level=debug msg="completed keyexchange"
Dec 09 10:18:53 northpi-soundstation go-librespot[14566]: time="2025-12-09T10:18:53-05:00" level=debug msg="completed challenge"
Dec 09 10:18:53 northpi-soundstation go-librespot[14566]: time="2025-12-09T10:18:53-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 09 10:18:53 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 10:18:53 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 10:18:54 northpi-soundstation volumio[13861]: info: UPDATER: Scheduling automatic update
Dec 09 10:18:54 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Dec 09 10:18:54 northpi-soundstation volumio[13861]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Dec 09 10:18:54 northpi-soundstation volumio[13861]: info: UPDATER: Auto update will take place at: Wed Dec 10 2025 03:22:18 GMT-0500 (Eastern Standard Time)
Dec 09 10:18:55 northpi-soundstation volumio[13861]: info: Initializing connection to go-librespot Websocket
Dec 09 10:18:55 northpi-soundstation volumio[13861]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 10:18:56 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20.
Dec 09 10:18:56 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:56 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:18:56 northpi-soundstation go-librespot[14573]: go-librespot daemon starting...
Dec 09 10:18:56 northpi-soundstation go-librespot[14574]: time="2025-12-09T10:18:56-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 10:18:56 northpi-soundstation go-librespot[14574]: time="2025-12-09T10:18:56-05:00" level=debug msg="app state loaded"
Dec 09 10:18:56 northpi-soundstation go-librespot[14574]: time="2025-12-09T10:18:56-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 10:18:56 northpi-soundstation go-librespot[14574]: time="2025-12-09T10:18:56-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 09 10:18:56 northpi-soundstation go-librespot[14574]: time="2025-12-09T10:18:56-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 09 10:18:56 northpi-soundstation go-librespot[14574]: time="2025-12-09T10:18:56-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 09 10:18:56 northpi-soundstation go-librespot[14574]: time="2025-12-09T10:18:56-05:00" level=info msg="zeroconf server listening on port 38589"
Dec 09 10:18:56 northpi-soundstation go-librespot[14574]: time="2025-12-09T10:18:56-05:00" level=debug msg="obtained new client token: AAAE189xLBrP/iYvmG0JhzfVPH1XR0BR3srEFfiuPumSgjVkulK90aZy6lfdd79lgLgu+ofQrNMOQeN0n0ktZTr9EhNim23rGmfKWOxqH90WpRfz14E+FCXVLbopcO4LITEAjfTM7apubcuAUv0DDCVk0fd+SX9VC5Hf4TrppV/BexTVzTabhGWLgY8lCJA0zbRGTqviQIAP7uZVWFGq1pqXj47EXUT+8ESx8ZV15zVz7t+q+BcyN0KK2w=="
Dec 09 10:18:56 northpi-soundstation go-librespot[14574]: time="2025-12-09T10:18:56-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 10:18:56 northpi-soundstation go-librespot[14574]: time="2025-12-09T10:18:56-05:00" level=debug msg="completed keyexchange"
Dec 09 10:18:56 northpi-soundstation go-librespot[14574]: time="2025-12-09T10:18:56-05:00" level=debug msg="completed challenge"
Dec 09 10:18:56 northpi-soundstation go-librespot[14574]: time="2025-12-09T10:18:56-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 09 10:18:56 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 10:18:56 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 10:18:58 northpi-soundstation volumio[13861]: info: Initializing connection to go-librespot Websocket
Dec 09 10:18:58 northpi-soundstation volumio[13861]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 10:19:00 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21.
Dec 09 10:19:00 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:19:00 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:19:00 northpi-soundstation go-librespot[14584]: go-librespot daemon starting...
Dec 09 10:19:00 northpi-soundstation go-librespot[14585]: time="2025-12-09T10:19:00-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 10:19:00 northpi-soundstation go-librespot[14585]: time="2025-12-09T10:19:00-05:00" level=debug msg="app state loaded"
Dec 09 10:19:00 northpi-soundstation go-librespot[14585]: time="2025-12-09T10:19:00-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 10:19:00 northpi-soundstation go-librespot[14585]: time="2025-12-09T10:19:00-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 09 10:19:00 northpi-soundstation go-librespot[14585]: time="2025-12-09T10:19:00-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 09 10:19:00 northpi-soundstation go-librespot[14585]: time="2025-12-09T10:19:00-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 09 10:19:00 northpi-soundstation go-librespot[14585]: time="2025-12-09T10:19:00-05:00" level=info msg="zeroconf server listening on port 39205"
Dec 09 10:19:00 northpi-soundstation go-librespot[14585]: time="2025-12-09T10:19:00-05:00" level=debug msg="obtained new client token: AAAqUsutR6gyucRNRrAvC6/e8kmql9/58pOxTyRNo40bxzGfOzqX4Dxe68wJBk0QZNfUebfSwBWkXM5xZjX0MqOJ4E3nZeN+U43ahEfPisc097TwcBwc7uLvbXECCkyB1ZcLmLxdB+n7kOvIGdRniMkWPG8Gk3FQ4m3/+NAjKQVkntjRsyaJk3FQjPio2GL4xf+9sdvVUoTWfba8FvWSpW4ccviWk1m6jrZgGMvTU3Vy62VuJBfp2VJX/g=="
Dec 09 10:19:00 northpi-soundstation go-librespot[14585]: time="2025-12-09T10:19:00-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 10:19:00 northpi-soundstation go-librespot[14585]: time="2025-12-09T10:19:00-05:00" level=debug msg="completed keyexchange"
Dec 09 10:19:00 northpi-soundstation go-librespot[14585]: time="2025-12-09T10:19:00-05:00" level=debug msg="completed challenge"
Dec 09 10:19:00 northpi-soundstation go-librespot[14585]: time="2025-12-09T10:19: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 09 10:19:00 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 10:19:00 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 10:19:01 northpi-soundstation volumio[13861]: info: Initializing connection to go-librespot Websocket
Dec 09 10:19:01 northpi-soundstation volumio[13861]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 10:19:03 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22.
Dec 09 10:19:03 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:19:03 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 10:19:03 northpi-soundstation go-librespot[14606]: go-librespot daemon starting...
Dec 09 10:19:03 northpi-soundstation go-librespot[14607]: time="2025-12-09T10:19:03-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 10:19:03 northpi-soundstation go-librespot[14607]: time="2025-12-09T10:19:03-05:00" level=debug msg="app state loaded"
Dec 09 10:19:03 northpi-soundstation go-librespot[14607]: time="2025-12-09T10:19:03-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 10:19:04 northpi-soundstation go-librespot[14607]: time="2025-12-09T10:19:04-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 09 10:19:04 northpi-soundstation go-librespot[14607]: time="2025-12-09T10:19:04-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 09 10:19:04 northpi-soundstation go-librespot[14607]: time="2025-12-09T10:19:04-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 09 10:19:04 northpi-soundstation go-librespot[14607]: time="2025-12-09T10:19:04-05:00" level=info msg="zeroconf server listening on port 45351"
Dec 09 10:19:04 northpi-soundstation go-librespot[14607]: time="2025-12-09T10:19:04-05:00" level=debug msg="obtained new client token: AADE/JeCq5eJl4Md9OWqotZAdV1BEdrsSBvI/GNj8PBJsfCa7Ho2rexhkWhpkyFr/gAFSyzOBiaPhMfHCNK9RN2XXg4VlH0tz/ZRZsg83YcLeNPRwAdJWjZ1a5k1hzl3oBldBNJuDtdX/OASHDNCPgt5TTSqoi809a22ZBofwSEL12MmfDLNVUxXT+jSrT2p9OhW+M75cvLtmy9hvbXtIarEwT6uZTFZ5rtWJI+vTpDFjwqWQlMoTKo="
Dec 09 10:19:04 northpi-soundstation go-librespot[14607]: time="2025-12-09T10:19:04-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 10:19:04 northpi-soundstation volumio[13861]: info: Initializing connection to go-librespot Websocket
Dec 09 10:19:04 northpi-soundstation go-librespot[14607]: time="2025-12-09T10:19:04-05:00" level=debug msg="new websocket client"
Dec 09 10:19:04 northpi-soundstation volumio[13861]: info: Connection to go-librespot Websocket established
Dec 09 10:19:05 northpi-soundstation go-librespot[14607]: time="2025-12-09T10:19:05-05:00" level=debug msg="completed keyexchange"
Dec 09 10:19:05 northpi-soundstation go-librespot[14607]: time="2025-12-09T10:19:05-05:00" level=debug msg="completed challenge"
Dec 09 10:19:05 northpi-soundstation go-librespot[14607]: time="2025-12-09T10:19:05-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 09 10:19:05 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 10:19:05 northpi-soundstation volumio[13861]: info: Connection to go-librespot Websocket closed
Dec 09 10:19:05 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 10:19:07 northpi-soundstation volumio[13861]: info: Getting Spotify volume
Dec 09 10:19:07 northpi-soundstation volumio[13861]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 09 10:19:07 northpi-soundstation volumio[13861]: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 10:19:07 northpi-soundstation volumio[13861]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Dec 09 10:19:07 northpi-soundstation volumio[13861]: errno: -111,
Dec 09 10:19:07 northpi-soundstation volumio[13861]: code: 'ECONNREFUSED',
Dec 09 10:19:07 northpi-soundstation volumio[13861]: syscall: 'connect',
Dec 09 10:19:07 northpi-soundstation volumio[13861]: address: '127.0.0.1',
Dec 09 10:19:07 northpi-soundstation volumio[13861]: port: 9879,
Dec 09 10:19:07 northpi-soundstation volumio[13861]: response: undefined
Dec 09 10:19:07 northpi-soundstation volumio[13861]: }
Dec 09 10:19:07 northpi-soundstation volumio[13861]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 09 10:19:08 northpi-soundstation sudo[14629]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-09 10:18'
Dec 09 10:19:08 northpi-soundstation sudo[14629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)"
NAME="Raspbian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="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"