Dec 09 04:56:00 northpi-soundstation systemd[1]: go-librespot-daemon.service: Deactivated successfully.
Dec 09 04:56:00 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:00 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 04:56:00 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 04:56:00 northpi-soundstation volumio[31960]: info: No need to fix Spotify hosts
Dec 09 04:56:00 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:00 northpi-soundstation go-librespot[32175]: go-librespot daemon starting...
Dec 09 04:56:00 northpi-soundstation sudo[32167]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:00 northpi-soundstation go-librespot[32180]: time="2025-12-09T04:56:00-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 04:56:00 northpi-soundstation go-librespot[32180]: time="2025-12-09T04:56:00-05:00" level=debug msg="app state loaded"
Dec 09 04:56:00 northpi-soundstation go-librespot[32180]: time="2025-12-09T04:56:00-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 04:56:00 northpi-soundstation volumio[31960]: info: [jellyfin-poller] Polled https://jellyfin.lukeaurio.net: online
Dec 09 04:56:00 northpi-soundstation volumio[31960]: info: Volumio called home
Dec 09 04:56:00 northpi-soundstation volumio[31960]: info: Starting Shairport Sync
Dec 09 04:56:00 northpi-soundstation volumio[31960]: info: Starting Shairport Sync
Dec 09 04:56:00 northpi-soundstation volumio[31960]: info: Starting Shairport Sync
Dec 09 04:56:00 northpi-soundstation sudo[32191]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 09 04:56:00 northpi-soundstation sudo[32191]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:00 northpi-soundstation sudo[32192]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 09 04:56:00 northpi-soundstation sudo[32189]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 09 04:56:00 northpi-soundstation sudo[32192]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:00 northpi-soundstation sudo[32189]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:00 northpi-soundstation go-librespot[32180]: time="2025-12-09T04:56: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-gew1.spotify.com:80]"
Dec 09 04:56:00 northpi-soundstation go-librespot[32180]: time="2025-12-09T04:56:00-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 04:56:00 northpi-soundstation go-librespot[32180]: time="2025-12-09T04:56:00-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 04:56:00 northpi-soundstation go-librespot[32180]: time="2025-12-09T04:56:00-05:00" level=info msg="zeroconf server listening on port 40633"
Dec 09 04:56:00 northpi-soundstation systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Dec 09 04:56:00 northpi-soundstation systemd[1]: shairport-sync.service: Deactivated successfully.
Dec 09 04:56:00 northpi-soundstation systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Dec 09 04:56:00 northpi-soundstation systemd[1]: shairport-sync.service: Consumed 3.097s CPU time.
Dec 09 04:56:00 northpi-soundstation go-librespot[32180]: time="2025-12-09T04:56:00-05:00" level=debug msg="obtained new client token: AABX5BCYCEcUG4L76zO0LS5su2wE9hH5BFZIkL7X9kbAFaH4cTrbXeG5Kqp4BEura4shQF550AwB6AJzndABW0b1BnJbte6P9i6i4N04sZZOSl0lT5xRTLizOJ/ONGNFOHEJDK3jJwr30nviPraImafVs4wm5lcWx6x7xmFzOehmA7D2HA5z4Lac6a0disHfxanRBV+AC5xwT3YHd3MsWT6rX0Zw9ak9SEyBpDoA5WlLhhGSO+eAm4igtw=="
Dec 09 04:56:00 northpi-soundstation volumio[31960]: info: An error occurred while refreshing Spotify Token Error: Bad Request
Dec 09 04:56:00 northpi-soundstation systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Dec 09 04:56:00 northpi-soundstation volumio[31960]: info: CoreCommandRouter::volumioGetState
Dec 09 04:56:00 northpi-soundstation sudo[32192]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:00 northpi-soundstation sudo[32191]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:00 northpi-soundstation sudo[32189]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:00 northpi-soundstation volumio[31960]: info: CorePlayQueue::getTrack 0
Dec 09 04:56:00 northpi-soundstation go-librespot[32180]: time="2025-12-09T04:56:00-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 04:56:00 northpi-soundstation volumio[31960]: info: Shairport-Sync Started
Dec 09 04:56:00 northpi-soundstation volumio[31960]: Error adding Membership: Error: addMembership EINVAL
Dec 09 04:56:00 northpi-soundstation volumio[31960]: info: Shairport-Sync Started
Dec 09 04:56:00 northpi-soundstation volumio[31960]: info: Shairport-Sync Started
Dec 09 04:56:00 northpi-soundstation go-librespot[32180]: time="2025-12-09T04:56:00-05:00" level=debug msg="connected to ap-gue1.spotify.com:443"
Dec 09 04:56:00 northpi-soundstation go-librespot[32180]: time="2025-12-09T04:56:00-05:00" level=debug msg="completed keyexchange"
Dec 09 04:56:00 northpi-soundstation go-librespot[32180]: time="2025-12-09T04:56:00-05:00" level=debug msg="completed challenge"
Dec 09 04:56:00 northpi-soundstation mpd[32132]: 2025-12-09T04:56:00 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Dec 09 04:56:00 northpi-soundstation systemd[1]: Started mpd.service - Music Player Daemon.
Dec 09 04:56:00 northpi-soundstation sudo[32097]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:00 northpi-soundstation sudo[32107]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:00 northpi-soundstation go-librespot[32180]: time="2025-12-09T04:56: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 04:56:00 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 04:56:00 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 04:56:00 northpi-soundstation volumio[31960]: info: Completed starting Core Plugins
Dec 09 04:56:00 northpi-soundstation volumio[31960]: info: -------------------------------------------
Dec 09 04:56:00 northpi-soundstation volumio[31960]: info: ----- MyVolumio plugins startup ----
Dec 09 04:56:00 northpi-soundstation volumio[31960]: info: -------------------------------------------
Dec 09 04:56:00 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Fetching plans data....
Dec 09 04:56:00 northpi-soundstation volumio[31960]: error: MPD error: The expression evaluated to a falsy value:
Dec 09 04:56:00 northpi-soundstation volumio[31960]: assert.ok(self.idling)
Dec 09 04:56:00 northpi-soundstation volumio[31960]: error: The expression evaluated to a falsy value:
Dec 09 04:56:00 northpi-soundstation volumio[31960]: assert.ok(self.idling)
Dec 09 04:56:00 northpi-soundstation volumio[31960]: error: updateQueue error: null
Dec 09 04:56:00 northpi-soundstation volumio[31960]: info: MPD running with PID32132
Dec 09 04:56:00 northpi-soundstation volumio[31960]: ,establishing connection
Dec 09 04:56:00 northpi-soundstation volumio[31960]: error: updateQueue error: null
Dec 09 04:56:03 northpi-soundstation volumio[31960]: info: go-librespot daemon successfully initialized
Dec 09 04:56:03 northpi-soundstation sudo[32221]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Dec 09 04:56:03 northpi-soundstation sudo[32221]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:03 northpi-soundstation sudo[32223]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Dec 09 04:56:03 northpi-soundstation sudo[32223]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:03 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1.
Dec 09 04:56:03 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:03 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:03 northpi-soundstation go-librespot[32226]: go-librespot daemon starting...
Dec 09 04:56:03 northpi-soundstation systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service...
Dec 09 04:56:03 northpi-soundstation systemd[1]: mpd_monitor.service: Deactivated successfully.
Dec 09 04:56:03 northpi-soundstation systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service.
Dec 09 04:56:03 northpi-soundstation systemd[1]: Started mpd_monitor.service - MPD Monitor Service.
Dec 09 04:56:03 northpi-soundstation go-librespot[32227]: time="2025-12-09T04:56:03-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 04:56:03 northpi-soundstation go-librespot[32227]: time="2025-12-09T04:56:03-05:00" level=debug msg="app state loaded"
Dec 09 04:56:03 northpi-soundstation sudo[32223]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:03 northpi-soundstation go-librespot[32227]: time="2025-12-09T04:56:03-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 04:56:03 northpi-soundstation sudo[32221]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:03 northpi-soundstation volumio[31960]: info: Successfully started MPD Monitor
Dec 09 04:56:03 northpi-soundstation volumio[31960]: info: Successfully started MPD Monitor
Dec 09 04:56:03 northpi-soundstation mpd_monitor.sh[32234]: MPD Monitor Service: Starting MPD Monitor Service
Dec 09 04:56:03 northpi-soundstation go-librespot[32227]: time="2025-12-09T04:56:03-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 09 04:56:03 northpi-soundstation go-librespot[32227]: time="2025-12-09T04:56:03-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 09 04:56:03 northpi-soundstation go-librespot[32227]: time="2025-12-09T04:56:03-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 09 04:56:03 northpi-soundstation go-librespot[32227]: time="2025-12-09T04:56:03-05:00" level=info msg="zeroconf server listening on port 41725"
Dec 09 04:56:04 northpi-soundstation go-librespot[32227]: time="2025-12-09T04:56:04-05:00" level=debug msg="obtained new client token: AADZ47zGoqHgAyfpa85DeZufbYM8u9meWUj+7Z176y87Jl3l5f/eq3KhSEy6eOUJkT5Fs6GIhLUMPllVi856697SGI8GDPpULyTeYQp1spCVsBWqZT0JzS2RQfq+Nk8lX8iG17aKZnAovb+YNJtskQfzgG+vmigbowklx9U6eL182Tv4RxsgknyU4Y063GHaSPIa2DXfhmcCa53+AaItg27ymQChwYyBwu/1ir0Qv+JQwndHnyCOQv4="
Dec 09 04:56:04 northpi-soundstation go-librespot[32227]: time="2025-12-09T04:56:04-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 04:56:04 northpi-soundstation go-librespot[32227]: time="2025-12-09T04:56:04-05:00" level=debug msg="completed keyexchange"
Dec 09 04:56:04 northpi-soundstation go-librespot[32227]: time="2025-12-09T04:56:04-05:00" level=debug msg="completed challenge"
Dec 09 04:56:04 northpi-soundstation go-librespot[32227]: time="2025-12-09T04:56:04-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 04:56:04 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 04:56:04 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 04:56:05 northpi-soundstation sudo[32255]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Dec 09 04:56:05 northpi-soundstation sudo[32255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:05 northpi-soundstation sudo[32255]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:05 northpi-soundstation sudo[32257]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 09 04:56:05 northpi-soundstation sudo[32257]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:05 northpi-soundstation sudo[32257]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:05 northpi-soundstation sudo[32261]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Dec 09 04:56:05 northpi-soundstation sudo[32261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:05 northpi-soundstation sudo[32261]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:05 northpi-soundstation volumio[31960]: info: Upmpdcli Daemon Started
Dec 09 04:56:05 northpi-soundstation volumio[31960]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Dec 09 04:56:06 northpi-soundstation volumio[31960]: info: Initializing connection to go-librespot Websocket
Dec 09 04:56:06 northpi-soundstation volumio[31960]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 04:56:07 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2.
Dec 09 04:56:07 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:07 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:07 northpi-soundstation go-librespot[32263]: go-librespot daemon starting...
Dec 09 04:56:07 northpi-soundstation go-librespot[32264]: time="2025-12-09T04:56:07-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 04:56:07 northpi-soundstation go-librespot[32264]: time="2025-12-09T04:56:07-05:00" level=debug msg="app state loaded"
Dec 09 04:56:07 northpi-soundstation go-librespot[32264]: time="2025-12-09T04:56:07-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 04:56:07 northpi-soundstation go-librespot[32264]: time="2025-12-09T04:56:07-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 09 04:56:07 northpi-soundstation go-librespot[32264]: time="2025-12-09T04:56:07-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 04:56:07 northpi-soundstation go-librespot[32264]: time="2025-12-09T04:56:07-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 04:56:07 northpi-soundstation go-librespot[32264]: time="2025-12-09T04:56:07-05:00" level=info msg="zeroconf server listening on port 44279"
Dec 09 04:56:07 northpi-soundstation go-librespot[32264]: time="2025-12-09T04:56:07-05:00" level=debug msg="obtained new client token: AADMlR6XJSZ4TAzT59rSL40Ws6ZkkdmSwWKGVKUS1ZMY9s0Uc9b58x2g4o9cZBtp+8dU30Qvpec2yE3zV0S3XzMFTMSqQuOHZtT8CK61XpiUlc0V1Sy7XQyhdy/nPsaNKcR2wttMrtrrl+fMTayBBkY26qihnZJXmcovS6KtX20XFtJ1W0llMNwPp5Da2DviTJtkjOTgkQ8QjI98If99qg+ZPm6xpAuE9/9+qyQnzW7XpXEIk/Rv4UR43A=="
Dec 09 04:56:07 northpi-soundstation go-librespot[32264]: time="2025-12-09T04:56:07-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 04:56:07 northpi-soundstation go-librespot[32264]: time="2025-12-09T04:56:07-05:00" level=debug msg="completed keyexchange"
Dec 09 04:56:07 northpi-soundstation go-librespot[32264]: time="2025-12-09T04:56:07-05:00" level=debug msg="completed challenge"
Dec 09 04:56:07 northpi-soundstation go-librespot[32264]: time="2025-12-09T04:56:07-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 09 04:56:07 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 04:56:07 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: Adding plugin bluetooth to MyMusic Plugins
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: Adding plugin multiroom to MyMusic Plugins
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: Adding plugin metavolumio to MyMusic Plugins
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: Adding plugin cd_controller to MyMusic Plugins
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: Adding plugin qobuzconnect to MyMusic Plugins
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: Adding plugin smart_inputs to MyMusic Plugins
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: Adding plugin tidalconnect to MyMusic Plugins
Dec 09 04:56:08 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Dec 09 04:56:09 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Dec 09 04:56:09 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Dec 09 04:56:09 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 04:56:09 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 04:56:09 northpi-soundstation volumio[31960]: info: Starting MyVolumio Remote Streaming Endpoints
Dec 09 04:56:09 northpi-soundstation volumio[31960]: info: MyVolumio login type: Token
Dec 09 04:56:09 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Dec 09 04:56:09 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Dec 09 04:56:09 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Dec 09 04:56:09 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Dec 09 04:56:09 northpi-soundstation volumio[31960]: info: Streaming services startup
Dec 09 04:56:09 northpi-soundstation volumio[31960]: info: Starting Streaming Daemon
Dec 09 04:56:09 northpi-soundstation sudo[32272]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 09 04:56:09 northpi-soundstation sudo[32272]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:10 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Dec 09 04:56:10 northpi-soundstation sudo[32272]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:10 northpi-soundstation volumio[31960]: info: Initializing connection to go-librespot Websocket
Dec 09 04:56:10 northpi-soundstation volumio[31960]: error: Cannot start Volumio Streaming Daemon
Dec 09 04:56:10 northpi-soundstation volumio[31960]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 09 04:56:10 northpi-soundstation volumio[31960]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 09 04:56:10 northpi-soundstation volumio[31960]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 04:56:10 northpi-soundstation volumio[31960]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Dec 09 04:56:10 northpi-soundstation volumio[31960]: info: MyVolumio token set successfully
Dec 09 04:56:10 northpi-soundstation volumio[31960]: info: MYVOLUMIO: Adding device
Dec 09 04:56:10 northpi-soundstation volumio[31960]: info: MYVOLUMIO: Evaluating Server
Dec 09 04:56:10 northpi-soundstation volumio[31960]: info: MyVolumio Plan changed: premium
Dec 09 04:56:10 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Subscribed plan changed to premium
Dec 09 04:56:10 northpi-soundstation volumio[31960]: info: Removing browser output: myVolumio user plan is not superstar
Dec 09 04:56:10 northpi-soundstation volumio[31960]: info: Removing audio output:
Dec 09 04:56:10 northpi-soundstation volumio[31960]: info: MYVOLUMIO: Adding device
Dec 09 04:56:10 northpi-soundstation volumio[31960]: info: MYVOLUMIO: Evaluating Server
Dec 09 04:56:10 northpi-soundstation volumio[31960]: info: Remote config written successfully
Dec 09 04:56:10 northpi-soundstation volumio[31960]: info: Starting Tunnel 1
Dec 09 04:56:10 northpi-soundstation volumio[31960]: info: Starting Tunnel Connection Checker
Dec 09 04:56:10 northpi-soundstation volumio[31960]: info: Completed starting MyVolumio Plugin
Dec 09 04:56:11 northpi-soundstation volumio[31960]: info: MYVolumio Device enabled
Dec 09 04:56:11 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins...
Dec 09 04:56:11 northpi-soundstation volumio[31960]: info: MyVolumio status changed
Dec 09 04:56:11 northpi-soundstation volumio[31960]: info: Streaming services startup
Dec 09 04:56:11 northpi-soundstation volumio[31960]: info: Starting Streaming Daemon
Dec 09 04:56:11 northpi-soundstation sudo[32319]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 09 04:56:11 northpi-soundstation sudo[32319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:11 northpi-soundstation volumio[31960]: info: Setting Geolocation for MyVolumio to us1
Dec 09 04:56:11 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 04:56:11 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 04:56:11 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 04:56:11 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid
Dec 09 04:56:11 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3.
Dec 09 04:56:11 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"...
Dec 09 04:56:11 northpi-soundstation sudo[32319]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:11 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:11 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:11 northpi-soundstation go-librespot[32321]: go-librespot daemon starting...
Dec 09 04:56:11 northpi-soundstation volumio[31960]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart
Dec 09 04:56:11 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Loading plugin "multiroom"...
Dec 09 04:56:11 northpi-soundstation go-librespot[32322]: time="2025-12-09T04:56:11-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 04:56:11 northpi-soundstation go-librespot[32322]: time="2025-12-09T04:56:11-05:00" level=debug msg="app state loaded"
Dec 09 04:56:11 northpi-soundstation go-librespot[32322]: time="2025-12-09T04:56:11-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 04:56:11 northpi-soundstation go-librespot[32322]: time="2025-12-09T04:56:11-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 04:56:11 northpi-soundstation go-librespot[32322]: time="2025-12-09T04:56:11-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 04:56:11 northpi-soundstation go-librespot[32322]: time="2025-12-09T04:56:11-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 04:56:11 northpi-soundstation go-librespot[32322]: time="2025-12-09T04:56:11-05:00" level=info msg="zeroconf server listening on port 42999"
Dec 09 04:56:11 northpi-soundstation go-librespot[32322]: time="2025-12-09T04:56:11-05:00" level=debug msg="obtained new client token: AADA6Umlu3+YH3wWvIU1rKomGAK6x42GPFb2xUyIefYarzCQFgoEWSLR8vJrFbpMlIBHeHidISvHCUJdwZgns7w2NbGKNUtSIpN+slfA6zKBEQRSvftgqlEwFHU3t288bDaV93in43jnsONi2U1NEGM8bNETvbPHcQE/jBvcIdUY49DW1JcBEgSRbSKZglszIKtLv4EtQ7W5M9OLHkStueDRhVnT7RCL2UAYI44ot5NR6GGSUAf1khUFEg=="
Dec 09 04:56:11 northpi-soundstation go-librespot[32322]: time="2025-12-09T04:56:11-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 04:56:11 northpi-soundstation go-librespot[32322]: time="2025-12-09T04:56:11-05:00" level=debug msg="completed keyexchange"
Dec 09 04:56:11 northpi-soundstation go-librespot[32322]: time="2025-12-09T04:56:11-05:00" level=debug msg="completed challenge"
Dec 09 04:56:11 northpi-soundstation go-librespot[32322]: time="2025-12-09T04:56:11-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 09 04:56:11 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 04:56:11 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 04:56:11 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom
Dec 09 04:56:11 northpi-soundstation sudo[32331]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom
Dec 09 04:56:11 northpi-soundstation sudo[32331]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:11 northpi-soundstation sudo[32331]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:11 northpi-soundstation volumio[31960]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists
Dec 09 04:56:11 northpi-soundstation volumio[31960]: info: MRS: MultiRoom plugin initialized
Dec 09 04:56:11 northpi-soundstation volumio[31960]: info: MRS: STOPPING SNAPCLIENT
Dec 09 04:56:11 northpi-soundstation volumio[31960]: info: MRS: Snap server stop
Dec 09 04:56:11 northpi-soundstation volumio[31960]: info: MRS: STOPPING volumioStreaming
Dec 09 04:56:11 northpi-soundstation sudo[32348]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient
Dec 09 04:56:11 northpi-soundstation sudo[32348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:11 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"...
Dec 09 04:56:11 northpi-soundstation sudo[32351]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver
Dec 09 04:56:11 northpi-soundstation sudo[32351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:11 northpi-soundstation sudo[32354]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming
Dec 09 04:56:11 northpi-soundstation sudo[32354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Loading plugin "manifestui"...
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"...
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"...
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"...
Dec 09 04:56:12 northpi-soundstation sudo[32355]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/*
Dec 09 04:56:12 northpi-soundstation sudo[32355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:12 northpi-soundstation sudo[32355]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Loading plugin "tidal"...
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Loading plugin "qobuz"...
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"...
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"...
Dec 09 04:56:12 northpi-soundstation sudo[32348]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: Preparing to generate the ALSA configuration file
Dec 09 04:56:12 northpi-soundstation sudo[32351]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:12 northpi-soundstation sudo[32354]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: Updating MyVolumio device info
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: Reading ALSA contributions from plugins.
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: Setting Geolocation for MyVolumio to us1
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: Successfully Added MyVolumio device
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: MRS: Removed streaming files
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: MRS: volumioStreaming STOPPED
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: MRS: SNAPSERVER STOPPED
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: MRS: SNAPCLIENT STOPPED
Dec 09 04:56:12 northpi-soundstation volumio[31960]: error: Cannot start Volumio Streaming Daemon
Dec 09 04:56:12 northpi-soundstation volumio[31960]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 09 04:56:12 northpi-soundstation volumio[31960]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: Asound.conf file written
Dec 09 04:56:12 northpi-soundstation sudo[32361]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Dec 09 04:56:12 northpi-soundstation sudo[32361]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:12 northpi-soundstation sudo[32361]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:12 northpi-soundstation volumio[31960]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2
Dec 09 04:56:12 northpi-soundstation volumio[31960]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: Output device has changed, restarting MPD
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: Output device has changed, restarting Shairport Sync
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 04:56:12 northpi-soundstation sudo[32367]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Dec 09 04:56:12 northpi-soundstation sudo[32367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:12 northpi-soundstation sudo[32369]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Dec 09 04:56:12 northpi-soundstation sudo[32367]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 09 04:56:12 northpi-soundstation sudo[32369]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth
Dec 09 04:56:12 northpi-soundstation volumio[31960]: ------------------------------------ BT MESSAGE: [FUNC] onStart
Dec 09 04:56:12 northpi-soundstation volumio[31960]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service
Dec 09 04:56:12 northpi-soundstation volumio[31960]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp
Dec 09 04:56:12 northpi-soundstation volumio[31960]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready.
Dec 09 04:56:12 northpi-soundstation systemd[1]: Stopping mpd.service - Music Player Daemon...
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: MRS: Pushing multiroomSync output for this device
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: MRS: Pushing multiroomSync output
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: Adding audio output:
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: Adding audio output:
Dec 09 04:56:12 northpi-soundstation systemd[1]: mpd.service: Deactivated successfully.
Dec 09 04:56:12 northpi-soundstation systemd[1]: Stopped mpd.service - Music Player Daemon.
Dec 09 04:56:12 northpi-soundstation systemd[1]: mpd.service: Consumed 1.788s CPU time.
Dec 09 04:56:12 northpi-soundstation systemd[1]: mpd.socket: Deactivated successfully.
Dec 09 04:56:12 northpi-soundstation systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Dec 09 04:56:12 northpi-soundstation systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 04:56:12 northpi-soundstation bluetoothd[806]: Path / reserved for Adv Monitor app :1.67250
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: Adding METAVOLUMIO REST API Endpoints
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: Preparing CD Folders
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: Adding CD REST API Endpoints
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: Starting UDEV Watcher for CD
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: Detecting CD presence with UDEV
Dec 09 04:56:12 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices
Dec 09 04:56:12 northpi-soundstation bluetoothd[806]: Adv Monitor app :1.67250 disconnected from D-Bus
Dec 09 04:56:12 northpi-soundstation systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Dec 09 04:56:12 northpi-soundstation systemd[1]: Starting mpd.service - Music Player Daemon...
Dec 09 04:56:12 northpi-soundstation sudo[32387]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Dec 09 04:56:12 northpi-soundstation sudo[32387]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Dec 09 04:56:12 northpi-soundstation sudo[32387]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:13 northpi-soundstation mpd[32389]: 2025-12-09T04:56:13 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Dec 09 04:56:13 northpi-soundstation systemd[1]: Started mpd.service - Music Player Daemon.
Dec 09 04:56:13 northpi-soundstation sudo[32369]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:14 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4.
Dec 09 04:56:14 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:14 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:14 northpi-soundstation go-librespot[32392]: go-librespot daemon starting...
Dec 09 04:56:14 northpi-soundstation go-librespot[32393]: time="2025-12-09T04:56:14-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 04:56:14 northpi-soundstation go-librespot[32393]: time="2025-12-09T04:56:14-05:00" level=debug msg="app state loaded"
Dec 09 04:56:14 northpi-soundstation go-librespot[32393]: time="2025-12-09T04:56:14-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 04:56:14 northpi-soundstation go-librespot[32393]: time="2025-12-09T04:56:14-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 09 04:56:14 northpi-soundstation go-librespot[32393]: time="2025-12-09T04:56:14-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 04:56:14 northpi-soundstation go-librespot[32393]: time="2025-12-09T04:56:14-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 04:56:14 northpi-soundstation go-librespot[32393]: time="2025-12-09T04:56:14-05:00" level=info msg="zeroconf server listening on port 33751"
Dec 09 04:56:14 northpi-soundstation go-librespot[32393]: time="2025-12-09T04:56:14-05:00" level=debug msg="obtained new client token: AAADXweGQ54WOlDGsYCy/s45maBR6NDvxO0i/oKvrXYxTC1E6HPs4z3dfdos9cjFh8g0iXhKsecbLMnKzxyStgeaws87esFMZTyJ0OEkoPn0iZOjUbnVDzDTUSm7RyQYD9ThAPcBmUEOS2KgQKFuCJvRkFM1uy7KBXlnN8KOPO1NcGRVm4tHn6Ys7SWYzAbn3HPmpMWVgPDQ1Gx6UVh4lezc1jqNQEBMpXT9HFTxZD7GQ/zEPFR9lnjQhw=="
Dec 09 04:56:15 northpi-soundstation go-librespot[32393]: time="2025-12-09T04:56:15-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 04:56:15 northpi-soundstation go-librespot[32393]: time="2025-12-09T04:56:15-05:00" level=debug msg="completed keyexchange"
Dec 09 04:56:15 northpi-soundstation go-librespot[32393]: time="2025-12-09T04:56:15-05:00" level=debug msg="completed challenge"
Dec 09 04:56:15 northpi-soundstation go-librespot[32393]: time="2025-12-09T04:56: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 04:56:15 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 04:56:15 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 04:56:17 northpi-soundstation volumio[31960]: warn: [cd-plugin] cdspeedctl: device or media not ready
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: Adding inputs REST Endpoints
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: Scanning Audio Inputs
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: Checking against Known Cards name
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: Adding Server instance for streaming
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio
Dec 09 04:56:17 northpi-soundstation volumio[31960]: error: Hi Res Audio Failed Login: Missing Login Data
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: Adding HIGHRESAUDIO REST API Endpoints
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: QobuzConnect: Starting Qobuz Connect socket and service
Dec 09 04:56:17 northpi-soundstation sudo[32418]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Dec 09 04:56:17 northpi-soundstation sudo[32418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: Adding TIDAL REST API Endpoints
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: Stopping AccessToken refresher cron for QOBUZ
Dec 09 04:56:17 northpi-soundstation sudo[32427]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service
Dec 09 04:56:17 northpi-soundstation systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service...
Dec 09 04:56:17 northpi-soundstation sudo[32427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:17 northpi-soundstation systemd[1]: vtcs.service: Deactivated successfully.
Dec 09 04:56:17 northpi-soundstation systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service.
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: AccessToken refresher cron started for QOBUZ
Dec 09 04:56:17 northpi-soundstation sudo[32418]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: Adding QOBUZ REST API Endpoints
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: Initializing connection to go-librespot Websocket
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: Updating MyVolumio device info
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 04:56:17 northpi-soundstation sudo[32427]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: MRS: Getting audio outputs on start
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: MRS: Requesting all other devices output
Dec 09 04:56:17 northpi-soundstation sudo[32430]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service
Dec 09 04:56:17 northpi-soundstation sudo[32430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: Successfully Updated MyVolumio device
Dec 09 04:56:17 northpi-soundstation volumio[31960]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on
Dec 09 04:56:17 northpi-soundstation systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service...
Dec 09 04:56:17 northpi-soundstation qobuz-connect[30875]: 20251209 04:56:17.620 [30875.30875] INFO SampleApp: Stopping Local configuration server
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: MPD Permissions set
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 04:56:17 northpi-soundstation sudo[32434]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service
Dec 09 04:56:17 northpi-soundstation sudo[32434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 04:56:17 northpi-soundstation sudo[32434]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:17 northpi-soundstation volumio[31960]: error: updateQueue error: null
Dec 09 04:56:17 northpi-soundstation volumio[31960]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: CoreCommandRouter::volumioStop
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: CoreStateMachine::stop
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 09 04:56:17 northpi-soundstation volumio[31960]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: Starting Shairport Sync
Dec 09 04:56:17 northpi-soundstation sudo[32447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 09 04:56:17 northpi-soundstation sudo[32447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:17 northpi-soundstation systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Dec 09 04:56:17 northpi-soundstation systemd[1]: shairport-sync.service: Deactivated successfully.
Dec 09 04:56:17 northpi-soundstation systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Dec 09 04:56:17 northpi-soundstation systemd[1]: shairport-sync.service: Consumed 1.578s CPU time.
Dec 09 04:56:17 northpi-soundstation sudo[32450]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service
Dec 09 04:56:17 northpi-soundstation sudo[32450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:17 northpi-soundstation systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Dec 09 04:56:17 northpi-soundstation sudo[32447]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: Shairport-Sync Started
Dec 09 04:56:17 northpi-soundstation autossh[30857]: received signal to exit (15)
Dec 09 04:56:17 northpi-soundstation systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel...
Dec 09 04:56:17 northpi-soundstation systemd[1]: sshtunnel.service: Deactivated successfully.
Dec 09 04:56:17 northpi-soundstation systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel.
Dec 09 04:56:17 northpi-soundstation systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel.
Dec 09 04:56:17 northpi-soundstation sudo[32450]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:17 northpi-soundstation volumio[31960]: info: Remote SSH Started
Dec 09 04:56:17 northpi-soundstation autossh[32467]: port set to 0, monitoring disabled
Dec 09 04:56:17 northpi-soundstation autossh[32467]: starting ssh (count 1)
Dec 09 04:56:17 northpi-soundstation autossh[32467]: ssh child pid is 32472
Dec 09 04:56:18 northpi-soundstation volumiossh-tunnel[32472]: Warning: Permanently added '[us1.myvolumio.org]:2222' (RSA) to the list of known hosts.
Dec 09 04:56:18 northpi-soundstation volumio[31960]: 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 04:56:18 northpi-soundstation volumio[31960]: info: Successfully Added MyVolumio device
Dec 09 04:56:18 northpi-soundstation volumio[31960]: info: CoreCommandRouter::volumioGetState
Dec 09 04:56:18 northpi-soundstation volumio[31960]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.0b008618efe21bea1cc98e8c917fa0a8.state.status'
Dec 09 04:56:18 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5.
Dec 09 04:56:18 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:18 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:18 northpi-soundstation go-librespot[32473]: go-librespot daemon starting...
Dec 09 04:56:18 northpi-soundstation go-librespot[32474]: time="2025-12-09T04:56:18-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 04:56:18 northpi-soundstation go-librespot[32474]: time="2025-12-09T04:56:18-05:00" level=debug msg="app state loaded"
Dec 09 04:56:18 northpi-soundstation go-librespot[32474]: time="2025-12-09T04:56:18-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 04:56:18 northpi-soundstation systemd[1]: qobuz-connect.service: Deactivated successfully.
Dec 09 04:56:18 northpi-soundstation systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service.
Dec 09 04:56:18 northpi-soundstation systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service.
Dec 09 04:56:18 northpi-soundstation sudo[32430]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:18 northpi-soundstation volumio[31960]: info: Executing endpoint qc_getconfig
Dec 09 04:56:18 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Dec 09 04:56:18 northpi-soundstation go-librespot[32474]: time="2025-12-09T04:56:18-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 09 04:56:18 northpi-soundstation go-librespot[32474]: time="2025-12-09T04:56:18-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 09 04:56:18 northpi-soundstation go-librespot[32474]: time="2025-12-09T04:56:18-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 09 04:56:18 northpi-soundstation qobuz-connect[32480]: 20251209 04:56:18.516 [32480.32480] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Dec 09 04:56:18 northpi-soundstation qobuz-connect[32480]: 20251209 04:56:18.520 [32480.32480] INFO VolumeManager: [0x112e598]: Setting new playback volume: 75
Dec 09 04:56:18 northpi-soundstation go-librespot[32474]: time="2025-12-09T04:56:18-05:00" level=info msg="zeroconf server listening on port 38435"
Dec 09 04:56:18 northpi-soundstation qobuz-connect[32480]: 20251209 04:56:18.522 [32480.32480] INFO VolumeManager: [0x112e598]: Setting new mute state: 0
Dec 09 04:56:18 northpi-soundstation qobuz-connect[32480]: 20251209 04:56:18.522 [32480.32480] INFO QobuzConnect: [0x112ef68]: Client initialized!
Dec 09 04:56:18 northpi-soundstation qobuz-connect[32480]: 20251209 04:56:18.522 [32480.32480] INFO SampleApp: Starting Avahi advertising, name: NorthPi-Soundstation, service name: _qobuz-connect._tcp
Dec 09 04:56:18 northpi-soundstation volumio[31960]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object]
Dec 09 04:56:18 northpi-soundstation volumio[31960]: info: QobuzConnect: QOBUZ Connect daemon connected
Dec 09 04:56:18 northpi-soundstation qobuz-connect[32480]: 20251209 04:56:18.538 [32480.32480] INFO LocalConfigManager: [0x112de78]: Starting Local Configuration server
Dec 09 04:56:18 northpi-soundstation qobuz-connect[32480]: 20251209 04:56:18.538 [32480.32480] INFO SampleApp: Starting Local configuration server
Dec 09 04:56:18 northpi-soundstation qobuz-connect[32480]: 20251209 04:56:18.539 [32480.32480] INFO SampleApp: Connected to UNIX socket client 0x1118818
Dec 09 04:56:18 northpi-soundstation go-librespot[32474]: time="2025-12-09T04:56:18-05:00" level=debug msg="obtained new client token: AAB9Znh4r+M07FyP0vaKT294HmvZ9KIRMusnasGPI5j1YSI9vtvaRjH3H6kUUDTpe4UXYrkPwnV+OegA8YMGGSl5dv8FkvQllaojBP1VRJN+T4Hep1TBMsOkqFuqfCdcUF7DZG1QxPzGOn3gfpZ1Sw1ilDoXPg0XLCfmBep8LpFA0+gVhCu78gpqfOCL2HzkzruJmU1wpQRQPz5G2G7R3k3oy4c8ft6e8iMoKRuHzaQeJ+bOa+tbucdTdw=="
Dec 09 04:56:18 northpi-soundstation qobuz-connect[32480]: 20251209 04:56:18.667 [32480.32480] INFO SampleApp: Playback volume changed: 75
Dec 09 04:56:18 northpi-soundstation volumio[31960]: info: CoreCommandRouter::volumioGetState
Dec 09 04:56:18 northpi-soundstation go-librespot[32474]: time="2025-12-09T04:56:18-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 04:56:18 northpi-soundstation go-librespot[32474]: time="2025-12-09T04:56:18-05:00" level=debug msg="completed keyexchange"
Dec 09 04:56:18 northpi-soundstation go-librespot[32474]: time="2025-12-09T04:56:18-05:00" level=debug msg="completed challenge"
Dec 09 04:56:18 northpi-soundstation go-librespot[32474]: time="2025-12-09T04:56:18-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 09 04:56:18 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 04:56:18 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 04:56:18 northpi-soundstation volumio[31960]: info: Successfully Updated MyVolumio device
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: TidalConnect service stoped!
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect
Dec 09 04:56:20 northpi-soundstation sudo[32497]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Dec 09 04:56:20 northpi-soundstation sudo[32497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: Initializing connection to go-librespot Websocket
Dec 09 04:56:20 northpi-soundstation sudo[32500]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Dec 09 04:56:20 northpi-soundstation sudo[32500]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 04:56:20 northpi-soundstation systemd[1]: Started vtcs.service - Volumio Tidal Connect Service.
Dec 09 04:56:20 northpi-soundstation sudo[32497]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:20 northpi-soundstation systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service...
Dec 09 04:56:20 northpi-soundstation systemd[1]: mpd_monitor.service: Deactivated successfully.
Dec 09 04:56:20 northpi-soundstation systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service.
Dec 09 04:56:20 northpi-soundstation systemd[1]: Started mpd_monitor.service - MPD Monitor Service.
Dec 09 04:56:20 northpi-soundstation sudo[32500]: pam_unix(sudo:session): session closed for user root
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: Successfully started MPD Monitor
Dec 09 04:56:20 northpi-soundstation mpd_monitor.sh[32505]: MPD Monitor Service: Starting MPD Monitor Service
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: Executing endpoint tc_getconfig
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig
Dec 09 04:56:20 northpi-soundstation vtcs[32503]: STARTING TidalConnect services, version: 1.5.2.56
Dec 09 04:56:20 northpi-soundstation vtcs[32503]: STARTED TidalConnect services.
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: Executing endpoint tc_connect
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: Connecting to TidalConnect
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreCommandRouter::servicePushState
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreStateMachine::pushState
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreCommandRouter::volumioPushState
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: MRS: Pushing multiroomSync output update for this device
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: MRS: Pushing multiroomSync output
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreCommandRouter::volumioGetState
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CorePlayQueue::getTrack 0
Dec 09 04:56:20 northpi-soundstation volumio[31960]: 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 04:56:20 northpi-soundstation volumio[31960]: verbose: CURRENT POSITION 0
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreStateMachine::syncState stateService stop
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreStateMachine::syncState currentStatus stop
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreStateMachine::pushState
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreCommandRouter::volumioPushState
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: MRS: Pushing multiroomSync output update for this device
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: MRS: Pushing multiroomSync output
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreCommandRouter::volumioGetState
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: No code
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreStateMachine::pushState
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreCommandRouter::volumioPushState
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: MRS: Pushing multiroomSync output update for this device
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: MRS: Pushing multiroomSync output
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreCommandRouter::volumioGetState
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreCommandRouter::servicePushState
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreStateMachine::pushState
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreCommandRouter::volumioPushState
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: MRS: Pushing multiroomSync output update for this device
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: MRS: Pushing multiroomSync output
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreCommandRouter::volumioGetState
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CorePlayQueue::getTrack 0
Dec 09 04:56:20 northpi-soundstation volumio[31960]: 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 04:56:20 northpi-soundstation volumio[31960]: verbose: CURRENT POSITION 0
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreStateMachine::syncState stateService stop
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreStateMachine::syncState currentStatus stop
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreStateMachine::pushState
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreCommandRouter::volumioPushState
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: MRS: Pushing multiroomSync output update for this device
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: MRS: Pushing multiroomSync output
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreCommandRouter::volumioGetState
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: No code
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreStateMachine::pushState
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreCommandRouter::volumioPushState
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: MRS: Pushing multiroomSync output update for this device
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: MRS: Pushing multiroomSync output
Dec 09 04:56:20 northpi-soundstation volumio[31960]: info: CoreCommandRouter::volumioGetState
Dec 09 04:56:20 northpi-soundstation volumio[31960]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.0b008618efe21bea1cc98e8c917fa0a8.state.status'
Dec 09 04:56:20 northpi-soundstation volumio[31960]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.0b008618efe21bea1cc98e8c917fa0a8.state.status'
Dec 09 04:56:20 northpi-soundstation volumio[31960]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.0b008618efe21bea1cc98e8c917fa0a8.state.status'
Dec 09 04:56:20 northpi-soundstation volumio[31960]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.0b008618efe21bea1cc98e8c917fa0a8.state.status'
Dec 09 04:56:21 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6.
Dec 09 04:56:21 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:21 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:21 northpi-soundstation go-librespot[32523]: go-librespot daemon starting...
Dec 09 04:56:21 northpi-soundstation go-librespot[32524]: time="2025-12-09T04:56:21-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 04:56:21 northpi-soundstation go-librespot[32524]: time="2025-12-09T04:56:21-05:00" level=debug msg="app state loaded"
Dec 09 04:56:22 northpi-soundstation go-librespot[32524]: time="2025-12-09T04:56:22-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 04:56:22 northpi-soundstation go-librespot[32524]: time="2025-12-09T04:56:22-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 04:56:22 northpi-soundstation go-librespot[32524]: time="2025-12-09T04:56:22-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 04:56:22 northpi-soundstation go-librespot[32524]: time="2025-12-09T04:56:22-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 04:56:22 northpi-soundstation go-librespot[32524]: time="2025-12-09T04:56:22-05:00" level=info msg="zeroconf server listening on port 37643"
Dec 09 04:56:22 northpi-soundstation go-librespot[32524]: time="2025-12-09T04:56:22-05:00" level=debug msg="obtained new client token: AADNaYB/ZTWd4To8zMhREH7wWoBwCkn2B6KXV+/qTKClQYJgcNRVwny7RRjLI8TZZp9GXsQ0uWHkpNEesfoSpPdah9hDyF+f3JmVfgHbzwnBtvRhtPeEcid988gk3EycInFU7jq4rA/tHnS8FZFGhcALF9myF+1r3bzCR4bdZGMd2r/l3ADyCW5ElRiAbSZPD3npKRx0mLEBUljf531mDYnCok3ptX9fAPR2YmGgJoJ9luHJ4nKHOPM="
Dec 09 04:56:22 northpi-soundstation go-librespot[32524]: time="2025-12-09T04:56:22-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 04:56:22 northpi-soundstation go-librespot[32524]: time="2025-12-09T04:56:22-05:00" level=debug msg="completed keyexchange"
Dec 09 04:56:22 northpi-soundstation go-librespot[32524]: time="2025-12-09T04:56:22-05:00" level=debug msg="completed challenge"
Dec 09 04:56:22 northpi-soundstation go-librespot[32524]: time="2025-12-09T04:56:22-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 09 04:56:22 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 04:56:22 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 04:56:23 northpi-soundstation volumio[31960]: info: Initializing connection to go-librespot Websocket
Dec 09 04:56:23 northpi-soundstation volumio[31960]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 04:56:23 northpi-soundstation volumio[31960]: info: TidalConnect service started!
Dec 09 04:56:23 northpi-soundstation volumio[31960]: [Metrics] CommandRouter: 29s 767.31ms
Dec 09 04:56:23 northpi-soundstation volumio[31960]: info: CoreCommandRouter::volumiosetStartupVolume
Dec 09 04:56:23 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 04:56:23 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 04:56:23 northpi-soundstation volumio[31960]: info: CoreCommandRouter::Close All Modals sent
Dec 09 04:56:23 northpi-soundstation volumio[31960]: info: CoreCommandRouter::Close All Modals sent
Dec 09 04:56:24 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 09 04:56:24 northpi-soundstation volumio[31960]: 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 04:56:24 northpi-soundstation volumio[31960]: info: CoreCommandRouter::volumioGetState
Dec 09 04:56:24 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Dec 09 04:56:24 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Dec 09 04:56:24 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Dec 09 04:56:25 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7.
Dec 09 04:56:25 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:25 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:25 northpi-soundstation go-librespot[32551]: go-librespot daemon starting...
Dec 09 04:56:25 northpi-soundstation go-librespot[32552]: time="2025-12-09T04:56:25-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 04:56:25 northpi-soundstation go-librespot[32552]: time="2025-12-09T04:56:25-05:00" level=debug msg="app state loaded"
Dec 09 04:56:25 northpi-soundstation go-librespot[32552]: time="2025-12-09T04:56:25-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 04:56:25 northpi-soundstation go-librespot[32552]: time="2025-12-09T04:56:25-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 09 04:56:25 northpi-soundstation go-librespot[32552]: time="2025-12-09T04:56:25-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 09 04:56:25 northpi-soundstation go-librespot[32552]: time="2025-12-09T04:56:25-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 09 04:56:25 northpi-soundstation go-librespot[32552]: time="2025-12-09T04:56:25-05:00" level=info msg="zeroconf server listening on port 43271"
Dec 09 04:56:25 northpi-soundstation go-librespot[32552]: time="2025-12-09T04:56:25-05:00" level=debug msg="obtained new client token: AAA6ncROGxZgj2Ci6V1R+494iAQG64Fz1095gCC6tY/RECYrqSRBCXaM3N5zIuRgvMzc5dLKGOUa9rgnNdQoTIqw4nChnDvRixj1zA3lCxyyRPM5XuODGCpSiBGXUPAbd4TKpmUP2jFkkkBEbYKrilT+qhSbvlFR0NPZFEYZgJAFrQZzyVeJ7kwFh2EL4tNU3jcX94fXgZKTuHWKRxkI2j3K2TYi77KWz21AyFcQrcTRlfCUy2ndSaHiiA=="
Dec 09 04:56:25 northpi-soundstation go-librespot[32552]: time="2025-12-09T04:56:25-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 04:56:25 northpi-soundstation go-librespot[32552]: time="2025-12-09T04:56:25-05:00" level=debug msg="completed keyexchange"
Dec 09 04:56:25 northpi-soundstation go-librespot[32552]: time="2025-12-09T04:56:25-05:00" level=debug msg="completed challenge"
Dec 09 04:56:25 northpi-soundstation go-librespot[32552]: time="2025-12-09T04:56:25-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 09 04:56:25 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 04:56:25 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 04:56:26 northpi-soundstation volumio[31960]: info: Initializing connection to go-librespot Websocket
Dec 09 04:56:26 northpi-soundstation volumio[31960]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 04:56:28 northpi-soundstation kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 13
Dec 09 04:56:28 northpi-soundstation kernel: xhci_hcd 0000:01:00.0: @0000000428d18270 00000000 00000000 0e000000 020e8001
Dec 09 04:56:28 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8.
Dec 09 04:56:28 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:28 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:28 northpi-soundstation go-librespot[32559]: go-librespot daemon starting...
Dec 09 04:56:29 northpi-soundstation go-librespot[32560]: time="2025-12-09T04:56:29-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 04:56:29 northpi-soundstation go-librespot[32560]: time="2025-12-09T04:56:29-05:00" level=debug msg="app state loaded"
Dec 09 04:56:29 northpi-soundstation go-librespot[32560]: time="2025-12-09T04:56:29-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 04:56:29 northpi-soundstation go-librespot[32560]: time="2025-12-09T04:56:29-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 09 04:56:29 northpi-soundstation go-librespot[32560]: time="2025-12-09T04:56:29-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 04:56:29 northpi-soundstation go-librespot[32560]: time="2025-12-09T04:56:29-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 04:56:29 northpi-soundstation go-librespot[32560]: time="2025-12-09T04:56:29-05:00" level=info msg="zeroconf server listening on port 38869"
Dec 09 04:56:29 northpi-soundstation go-librespot[32560]: time="2025-12-09T04:56:29-05:00" level=debug msg="obtained new client token: AABHI401vzpnSKXAmmFhVPDlPszoJMtxza/qb5TeoThFFxm1dHuNWyYx8Ky/KgCXj2qluHlWnDVUVvc2ut3zmN1B+lIpbeTjuWRC2HkNxPyxlLh10+JfoOFvwdZxHUViAzT1PZN0enFCdhgdEGzcWB/k0cYiUFGCju8ilIB3iZopUdreqbvC0bCCXo90DFI0WKkIr/bKSPT2iLu91wp/TMWafVHx1zaf4ZkbwK+tlKGRQh+069ZCKRw="
Dec 09 04:56:29 northpi-soundstation go-librespot[32560]: time="2025-12-09T04:56:29-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 04:56:29 northpi-soundstation go-librespot[32560]: time="2025-12-09T04:56:29-05:00" level=debug msg="completed keyexchange"
Dec 09 04:56:29 northpi-soundstation go-librespot[32560]: time="2025-12-09T04:56:29-05:00" level=debug msg="completed challenge"
Dec 09 04:56:29 northpi-soundstation go-librespot[32560]: time="2025-12-09T04:56:29-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 09 04:56:29 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 04:56:29 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 04:56:29 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 04:56:29 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 04:56:29 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Dec 09 04:56:29 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Dec 09 04:56:29 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Dec 09 04:56:29 northpi-soundstation volumio[31960]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 09 04:56:29 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 09 04:56:29 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 09 04:56:29 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 04:56:29 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 09 04:56:29 northpi-soundstation volumio-remote-updater[5170]: Test mode enabled
Dec 09 04:56:29 northpi-soundstation volumio-remote-updater[5170]: Alpha mode disabled
Dec 09 04:56:29 northpi-soundstation volumio-remote-updater[5170]: Alpha legacy test mode disabled
Dec 09 04:56:29 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Dec 09 04:56:29 northpi-soundstation volumio[31960]: info: Initializing connection to go-librespot Websocket
Dec 09 04:56:29 northpi-soundstation volumio[31960]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 04:56:29 northpi-soundstation volumio[31960]: 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 04:56:29 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Dec 09 04:56:30 northpi-soundstation volumio[31960]: info: BOOT COMPLETED
Dec 09 04:56:32 northpi-soundstation volumio[31960]: info: Initializing connection to go-librespot Websocket
Dec 09 04:56:32 northpi-soundstation volumio[31960]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 04:56:32 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9.
Dec 09 04:56:32 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:32 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:32 northpi-soundstation go-librespot[32571]: go-librespot daemon starting...
Dec 09 04:56:32 northpi-soundstation go-librespot[32572]: time="2025-12-09T04:56:32-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 04:56:32 northpi-soundstation go-librespot[32572]: time="2025-12-09T04:56:32-05:00" level=debug msg="app state loaded"
Dec 09 04:56:32 northpi-soundstation go-librespot[32572]: time="2025-12-09T04:56:32-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 04:56:32 northpi-soundstation go-librespot[32572]: time="2025-12-09T04:56:32-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 09 04:56:32 northpi-soundstation go-librespot[32572]: time="2025-12-09T04:56:32-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 04:56:32 northpi-soundstation go-librespot[32572]: time="2025-12-09T04:56:32-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 04:56:32 northpi-soundstation go-librespot[32572]: time="2025-12-09T04:56:32-05:00" level=info msg="zeroconf server listening on port 34637"
Dec 09 04:56:33 northpi-soundstation go-librespot[32572]: time="2025-12-09T04:56:33-05:00" level=debug msg="obtained new client token: AACe/SbsKfpre7S8e65uLkqv5q2Fy51Tsxefaz9mxF/lIr73Ti8X48SnASzbdE5tlj7s7kzZUlDHJsk3uRMjQTc7jRoN3rgopvjZf9llD93BYZd+n+HXGNyCqh98mg5IJcmpm+q0yIe69FtgKNY/pLpfZqQ9jJIbNNIX/VppG+Hzp/tc7qsgD2qFvOOcmH4taQY3Cq4CtdZEvx8YOWCRL+QPRW7Ua0QV+fb/IszCQusy4ao//O2+vhg="
Dec 09 04:56:33 northpi-soundstation go-librespot[32572]: time="2025-12-09T04:56:33-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 04:56:33 northpi-soundstation go-librespot[32572]: time="2025-12-09T04:56:33-05:00" level=debug msg="completed keyexchange"
Dec 09 04:56:33 northpi-soundstation go-librespot[32572]: time="2025-12-09T04:56:33-05:00" level=debug msg="completed challenge"
Dec 09 04:56:33 northpi-soundstation go-librespot[32572]: time="2025-12-09T04:56:33-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 04:56:33 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 04:56:33 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 04:56:35 northpi-soundstation volumio[31960]: info: Initializing connection to go-librespot Websocket
Dec 09 04:56:35 northpi-soundstation volumio[31960]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 04:56:36 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10.
Dec 09 04:56:36 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:36 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:36 northpi-soundstation go-librespot[32593]: go-librespot daemon starting...
Dec 09 04:56:36 northpi-soundstation go-librespot[32594]: time="2025-12-09T04:56:36-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 04:56:36 northpi-soundstation go-librespot[32594]: time="2025-12-09T04:56:36-05:00" level=debug msg="app state loaded"
Dec 09 04:56:36 northpi-soundstation go-librespot[32594]: time="2025-12-09T04:56:36-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 04:56:36 northpi-soundstation go-librespot[32594]: time="2025-12-09T04:56:36-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 09 04:56:36 northpi-soundstation go-librespot[32594]: time="2025-12-09T04:56:36-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 04:56:36 northpi-soundstation go-librespot[32594]: time="2025-12-09T04:56:36-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 04:56:36 northpi-soundstation go-librespot[32594]: time="2025-12-09T04:56:36-05:00" level=info msg="zeroconf server listening on port 40399"
Dec 09 04:56:36 northpi-soundstation go-librespot[32594]: time="2025-12-09T04:56:36-05:00" level=debug msg="obtained new client token: AABLu9YjB8bv7Sa8p210B9vWCACNNaEmYvXwtmFs6ezzXPobBb+tR/tmfkCnc0kSVV2GKhPp5Uz6fUFiBZ+tZzYA3jwK4/mjxqcHOeCVaHTbmwDTZSayHrUxkLRLoP2gm1ynr5EreklVycTZ4cI32/X4lJ89dsvZUT7nCJqSAnPDgBdYa6m2enowwAPVo2f+bA4z1KdaBzTCY3LaG0mGnMLyYKrTzpTLtCOe7Q9vsdVh9BIElYrAvn9vJA=="
Dec 09 04:56:36 northpi-soundstation go-librespot[32594]: time="2025-12-09T04:56:36-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 04:56:36 northpi-soundstation go-librespot[32594]: time="2025-12-09T04:56:36-05:00" level=debug msg="completed keyexchange"
Dec 09 04:56:36 northpi-soundstation go-librespot[32594]: time="2025-12-09T04:56:36-05:00" level=debug msg="completed challenge"
Dec 09 04:56:36 northpi-soundstation go-librespot[32594]: time="2025-12-09T04:56:36-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 09 04:56:36 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 04:56:36 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 04:56:38 northpi-soundstation volumio[31960]: info: Initializing connection to go-librespot Websocket
Dec 09 04:56:38 northpi-soundstation volumio[31960]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 04:56:39 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11.
Dec 09 04:56:39 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:40 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:40 northpi-soundstation go-librespot[32601]: go-librespot daemon starting...
Dec 09 04:56:40 northpi-soundstation go-librespot[32602]: time="2025-12-09T04:56:40-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 04:56:40 northpi-soundstation go-librespot[32602]: time="2025-12-09T04:56:40-05:00" level=debug msg="app state loaded"
Dec 09 04:56:40 northpi-soundstation go-librespot[32602]: time="2025-12-09T04:56:40-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 04:56:40 northpi-soundstation go-librespot[32602]: time="2025-12-09T04:56:40-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 04:56:40 northpi-soundstation go-librespot[32602]: time="2025-12-09T04:56:40-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 04:56:40 northpi-soundstation go-librespot[32602]: time="2025-12-09T04:56:40-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 04:56:40 northpi-soundstation go-librespot[32602]: time="2025-12-09T04:56:40-05:00" level=info msg="zeroconf server listening on port 37313"
Dec 09 04:56:40 northpi-soundstation go-librespot[32602]: time="2025-12-09T04:56:40-05:00" level=debug msg="obtained new client token: AABDZ1/SljyscRpKzWfD2SPfAWN7VSiDub1iBfl2ySi4BhUCMxQ9JbJJD6Zi2AfU4fh8Rbyi/O6kCd4X0FqAs2da0X2OeyZQ0BbaC+ZyU/+q/hJ9TSLuSwhxDL9mo4NOiOtrDEMzly/CNCAELO8TJ9isfejR23Wyy2bnXrmgxpKa/+aWODdae8ws/Iqtd/q91JpKLKZUMi94GvKt4nA5W7P1AwO3sdi1fyxwfipEJR7NGWGoXihHhDk="
Dec 09 04:56:40 northpi-soundstation go-librespot[32602]: time="2025-12-09T04:56:40-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 04:56:40 northpi-soundstation go-librespot[32602]: time="2025-12-09T04:56:40-05:00" level=debug msg="completed keyexchange"
Dec 09 04:56:40 northpi-soundstation go-librespot[32602]: time="2025-12-09T04:56:40-05:00" level=debug msg="completed challenge"
Dec 09 04:56:40 northpi-soundstation go-librespot[32602]: time="2025-12-09T04:56:40-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 04:56:40 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 04:56:40 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 04:56:41 northpi-soundstation volumio[31960]: info: Initializing connection to go-librespot Websocket
Dec 09 04:56:41 northpi-soundstation volumio[31960]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 04:56:43 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12.
Dec 09 04:56:43 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:43 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:43 northpi-soundstation go-librespot[32614]: go-librespot daemon starting...
Dec 09 04:56:43 northpi-soundstation go-librespot[32615]: time="2025-12-09T04:56:43-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 04:56:43 northpi-soundstation go-librespot[32615]: time="2025-12-09T04:56:43-05:00" level=debug msg="app state loaded"
Dec 09 04:56:43 northpi-soundstation go-librespot[32615]: time="2025-12-09T04:56:43-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 04:56:43 northpi-soundstation go-librespot[32615]: time="2025-12-09T04:56:43-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 09 04:56:43 northpi-soundstation go-librespot[32615]: time="2025-12-09T04:56:43-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 04:56:43 northpi-soundstation go-librespot[32615]: time="2025-12-09T04:56:43-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 04:56:43 northpi-soundstation go-librespot[32615]: time="2025-12-09T04:56:43-05:00" level=info msg="zeroconf server listening on port 38003"
Dec 09 04:56:44 northpi-soundstation go-librespot[32615]: time="2025-12-09T04:56:44-05:00" level=debug msg="obtained new client token: AABClIn1IBP4mSR70J5RMmQT3Y6d+pTnOf035XGxg7Ewo5n7EyrVJIv/5KLDOQRITiFQwgJzhziq3sMCyCb7AD46RRsyZsBAwnFjSvs4loPQBCw+cmh2U2jdBY6CqXFD1Z+mVO0vzR0H5ZpoucOnFaK/9AaL2NWbott+Mt1Ttr93dhbcTQfFTp0BV+Fqf0Bw/GxA4fbkF7yWeRMHXXaz5Eo/PKUDGtOBW8fmCdCV0abs3xn+GE1u4tpMRQ=="
Dec 09 04:56:44 northpi-soundstation go-librespot[32615]: time="2025-12-09T04:56:44-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 04:56:44 northpi-soundstation go-librespot[32615]: time="2025-12-09T04:56:44-05:00" level=debug msg="completed keyexchange"
Dec 09 04:56:44 northpi-soundstation go-librespot[32615]: time="2025-12-09T04:56:44-05:00" level=debug msg="completed challenge"
Dec 09 04:56:44 northpi-soundstation go-librespot[32615]: time="2025-12-09T04:56:44-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 04:56:44 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 04:56:44 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 04:56:44 northpi-soundstation volumio[31960]: info: Initializing connection to go-librespot Websocket
Dec 09 04:56:44 northpi-soundstation volumio[31960]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 04:56:47 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13.
Dec 09 04:56:47 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:47 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:47 northpi-soundstation go-librespot[32637]: go-librespot daemon starting...
Dec 09 04:56:47 northpi-soundstation go-librespot[32638]: time="2025-12-09T04:56:47-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 04:56:47 northpi-soundstation go-librespot[32638]: time="2025-12-09T04:56:47-05:00" level=debug msg="app state loaded"
Dec 09 04:56:47 northpi-soundstation go-librespot[32638]: time="2025-12-09T04:56:47-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 04:56:47 northpi-soundstation go-librespot[32638]: time="2025-12-09T04:56:47-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 09 04:56:47 northpi-soundstation go-librespot[32638]: time="2025-12-09T04:56:47-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 04:56:47 northpi-soundstation go-librespot[32638]: time="2025-12-09T04:56:47-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 04:56:47 northpi-soundstation go-librespot[32638]: time="2025-12-09T04:56:47-05:00" level=info msg="zeroconf server listening on port 42683"
Dec 09 04:56:47 northpi-soundstation go-librespot[32638]: time="2025-12-09T04:56:47-05:00" level=debug msg="obtained new client token: AADDWsARNpbYjW4TNpgQN/lI1iePElZ4GDOR9SBIboFOeHAStgbguVCQEdJA4JsG0lpFy+Daa5SKuOdJltOqn833qYoApfhTYElOxuYWtlu88cK7sRBLoOO8i2+YAHhu5cyVAymH3fOofqEuaD/+V+U5dC2d7/3fY6fxGcr1n97WllsFDOMCIjf9jR1utA2Jf0u/bKyvQECzAkM1Ot7nnZqS3X7lXwSdBuxTEo6BjDkjAm7buXIm97g62Q=="
Dec 09 04:56:47 northpi-soundstation go-librespot[32638]: time="2025-12-09T04:56:47-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 04:56:47 northpi-soundstation go-librespot[32638]: time="2025-12-09T04:56:47-05:00" level=debug msg="completed keyexchange"
Dec 09 04:56:47 northpi-soundstation go-librespot[32638]: time="2025-12-09T04:56:47-05:00" level=debug msg="completed challenge"
Dec 09 04:56:47 northpi-soundstation go-librespot[32638]: time="2025-12-09T04:56:47-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 09 04:56:47 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 04:56:47 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 04:56:47 northpi-soundstation volumio[31960]: info: Initializing connection to go-librespot Websocket
Dec 09 04:56:47 northpi-soundstation volumio[31960]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 04:56:50 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14.
Dec 09 04:56:50 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:50 northpi-soundstation volumio[31960]: info: Initializing connection to go-librespot Websocket
Dec 09 04:56:50 northpi-soundstation volumio[31960]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 04:56:50 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:50 northpi-soundstation go-librespot[32646]: go-librespot daemon starting...
Dec 09 04:56:50 northpi-soundstation go-librespot[32647]: time="2025-12-09T04:56:50-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 04:56:50 northpi-soundstation go-librespot[32647]: time="2025-12-09T04:56:50-05:00" level=debug msg="app state loaded"
Dec 09 04:56:50 northpi-soundstation go-librespot[32647]: time="2025-12-09T04:56:50-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 04:56:50 northpi-soundstation go-librespot[32647]: time="2025-12-09T04:56:50-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 09 04:56:50 northpi-soundstation go-librespot[32647]: time="2025-12-09T04:56:50-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 04:56:50 northpi-soundstation go-librespot[32647]: time="2025-12-09T04:56:50-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 04:56:50 northpi-soundstation go-librespot[32647]: time="2025-12-09T04:56:50-05:00" level=info msg="zeroconf server listening on port 45481"
Dec 09 04:56:51 northpi-soundstation go-librespot[32647]: time="2025-12-09T04:56:51-05:00" level=debug msg="obtained new client token: AAAu9u2VGNpe/7HJkWzCpvIeKJxFdci+VJ3+AME0E2z0flu8qJ+9KZ7f5hDGZsGLFOTFyM9yMc76KLow+l0+yI0RidMssu1GlBCHtfWSFDdGVDZH7Rm6pTST8EgahfPyWVVOnNx851CbuUBNciHzLeEQiB4N582lzSHxroJG4FhiEEwxsLoEWQ4qp24Gy2iN8dO+B2/UNSR6R+Q/BF7/IWxEN9FwrRP8Ud5kQd/qiu8kTWyk24YFqhY4Pg=="
Dec 09 04:56:51 northpi-soundstation go-librespot[32647]: time="2025-12-09T04:56:51-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 04:56:51 northpi-soundstation go-librespot[32647]: time="2025-12-09T04:56:51-05:00" level=debug msg="completed keyexchange"
Dec 09 04:56:51 northpi-soundstation go-librespot[32647]: time="2025-12-09T04:56:51-05:00" level=debug msg="completed challenge"
Dec 09 04:56:51 northpi-soundstation go-librespot[32647]: time="2025-12-09T04:56:51-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 09 04:56:51 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 04:56:51 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 04:56:53 northpi-soundstation volumio[31960]: info: Initializing connection to go-librespot Websocket
Dec 09 04:56:53 northpi-soundstation volumio[31960]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 04:56:54 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15.
Dec 09 04:56:54 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:54 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:54 northpi-soundstation go-librespot[32654]: go-librespot daemon starting...
Dec 09 04:56:54 northpi-soundstation go-librespot[32655]: time="2025-12-09T04:56:54-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 04:56:54 northpi-soundstation go-librespot[32655]: time="2025-12-09T04:56:54-05:00" level=debug msg="app state loaded"
Dec 09 04:56:54 northpi-soundstation go-librespot[32655]: time="2025-12-09T04:56:54-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 04:56:54 northpi-soundstation go-librespot[32655]: time="2025-12-09T04:56:54-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 09 04:56:54 northpi-soundstation go-librespot[32655]: time="2025-12-09T04:56:54-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 09 04:56:54 northpi-soundstation go-librespot[32655]: time="2025-12-09T04:56:54-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 09 04:56:54 northpi-soundstation go-librespot[32655]: time="2025-12-09T04:56:54-05:00" level=info msg="zeroconf server listening on port 43253"
Dec 09 04:56:54 northpi-soundstation go-librespot[32655]: time="2025-12-09T04:56:54-05:00" level=debug msg="obtained new client token: AACHlfuwmwlKvuhFue8mRymDhz0fMgGmmeY0aUy7Cw4Y7zeBdsO8qA4qpn88Y3dp5heETRBwn2rxAVrd/jaEpbXrMqTRbwlI5QO629OaT17RfW64sW8e/36suC5mu+TjdE9XltJcdmcA4kLe/58FPePhQyaHLkJrpUO6DxR17DHWsrfRHtgSOOw//jdPf9Pfx5v+rSlfeTGFS5GEXus16ryohUxR7D/KY4YHqH7WNAdC7hH81gKxWayoAQ=="
Dec 09 04:56:54 northpi-soundstation go-librespot[32655]: time="2025-12-09T04:56:54-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 04:56:54 northpi-soundstation go-librespot[32655]: time="2025-12-09T04:56:54-05:00" level=debug msg="completed keyexchange"
Dec 09 04:56:54 northpi-soundstation go-librespot[32655]: time="2025-12-09T04:56:54-05:00" level=debug msg="completed challenge"
Dec 09 04:56:54 northpi-soundstation go-librespot[32655]: time="2025-12-09T04:56:54-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 09 04:56:54 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 04:56:54 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 04:56:56 northpi-soundstation volumio[31960]: info: Initializing connection to go-librespot Websocket
Dec 09 04:56:56 northpi-soundstation volumio[31960]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 04:56:57 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16.
Dec 09 04:56:57 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:57 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:56:57 northpi-soundstation go-librespot[32676]: go-librespot daemon starting...
Dec 09 04:56:57 northpi-soundstation go-librespot[32677]: time="2025-12-09T04:56:57-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 04:56:57 northpi-soundstation go-librespot[32677]: time="2025-12-09T04:56:57-05:00" level=debug msg="app state loaded"
Dec 09 04:56:57 northpi-soundstation go-librespot[32677]: time="2025-12-09T04:56:57-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 04:56:57 northpi-soundstation go-librespot[32677]: time="2025-12-09T04:56:57-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 09 04:56:57 northpi-soundstation go-librespot[32677]: time="2025-12-09T04:56:57-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 09 04:56:57 northpi-soundstation go-librespot[32677]: time="2025-12-09T04:56:57-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 09 04:56:57 northpi-soundstation go-librespot[32677]: time="2025-12-09T04:56:57-05:00" level=info msg="zeroconf server listening on port 44503"
Dec 09 04:56:57 northpi-soundstation go-librespot[32677]: time="2025-12-09T04:56:57-05:00" level=debug msg="obtained new client token: AADLCqY//oNdVyR+wm5321VPy5vALjsGTMz0zE7+KbHnpOtbMEi0m/AD17df+/f3qcAi9rkIB/9J8/VWLgn6MzW3C0qOC9tutoVJOJYtrX/XKwO9MoUJBJVLSXfp8WXQPS8wfIuMIP2rhhy4c6LgrpWB4tAnnbzzziD/xn2+FfQJqLyY13Jh6RThVqpA+nzqxgGTX2oOe0FX15A0JxLGetgrdsFgtDm+tGeTqThTxz3a6KQ77TGhABHrxg=="
Dec 09 04:56:58 northpi-soundstation go-librespot[32677]: time="2025-12-09T04:56:58-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 04:56:58 northpi-soundstation go-librespot[32677]: time="2025-12-09T04:56:58-05:00" level=debug msg="completed keyexchange"
Dec 09 04:56:58 northpi-soundstation go-librespot[32677]: time="2025-12-09T04:56:58-05:00" level=debug msg="completed challenge"
Dec 09 04:56:58 northpi-soundstation go-librespot[32677]: time="2025-12-09T04:56:58-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 09 04:56:58 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 04:56:58 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 04:56:59 northpi-soundstation volumio[31960]: info: UPDATER: Scheduling automatic update
Dec 09 04:56:59 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Dec 09 04:56:59 northpi-soundstation volumio[31960]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Dec 09 04:56:59 northpi-soundstation volumio[31960]: info: UPDATER: Auto update will take place at: Wed Dec 10 2025 04:42:40 GMT-0500 (Eastern Standard Time)
Dec 09 04:56:59 northpi-soundstation volumio[31960]: info: Initializing connection to go-librespot Websocket
Dec 09 04:56:59 northpi-soundstation volumio[31960]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 04:57:01 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17.
Dec 09 04:57:01 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:57:01 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:57:01 northpi-soundstation go-librespot[32688]: go-librespot daemon starting...
Dec 09 04:57:01 northpi-soundstation go-librespot[32689]: time="2025-12-09T04:57:01-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 04:57:01 northpi-soundstation go-librespot[32689]: time="2025-12-09T04:57:01-05:00" level=debug msg="app state loaded"
Dec 09 04:57:01 northpi-soundstation go-librespot[32689]: time="2025-12-09T04:57:01-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 04:57:01 northpi-soundstation go-librespot[32689]: time="2025-12-09T04:57:01-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 09 04:57:01 northpi-soundstation go-librespot[32689]: time="2025-12-09T04:57:01-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 04:57:01 northpi-soundstation go-librespot[32689]: time="2025-12-09T04:57:01-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 04:57:01 northpi-soundstation go-librespot[32689]: time="2025-12-09T04:57:01-05:00" level=info msg="zeroconf server listening on port 37993"
Dec 09 04:57:01 northpi-soundstation go-librespot[32689]: time="2025-12-09T04:57:01-05:00" level=debug msg="obtained new client token: AABPgVSAiAvREba+R1I/xM0mOa6zENiZkWF3+FxZKWda+9N9Xm00pUKp/2ZPwyV68BYcsD9HZ2yV2yWcDIwFqtiA9WlI76qD8BRlZWvv68ujIVy7isVYjMTobwHZZZ3BFgcSGasdhpcfIaBkgLDeh5z94wThDBe4IIDGZ7qBkGBv48XMM/nzeebKj8t1YkA5G1uvGjcSKsdZjfg1uVhM5popeC3xUP3geKMHH88CN2TcJnNrxg6UZwbwMg=="
Dec 09 04:57:01 northpi-soundstation go-librespot[32689]: time="2025-12-09T04:57:01-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 04:57:01 northpi-soundstation go-librespot[32689]: time="2025-12-09T04:57:01-05:00" level=debug msg="completed keyexchange"
Dec 09 04:57:01 northpi-soundstation go-librespot[32689]: time="2025-12-09T04:57:01-05:00" level=debug msg="completed challenge"
Dec 09 04:57:01 northpi-soundstation go-librespot[32689]: time="2025-12-09T04:57:01-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 09 04:57:01 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 04:57:01 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 04:57:02 northpi-soundstation volumio[31960]: info: Initializing connection to go-librespot Websocket
Dec 09 04:57:02 northpi-soundstation volumio[31960]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 04:57:04 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18.
Dec 09 04:57:04 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:57:04 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:57:04 northpi-soundstation go-librespot[32698]: go-librespot daemon starting...
Dec 09 04:57:04 northpi-soundstation go-librespot[32699]: time="2025-12-09T04:57:04-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 04:57:04 northpi-soundstation go-librespot[32699]: time="2025-12-09T04:57:04-05:00" level=debug msg="app state loaded"
Dec 09 04:57:04 northpi-soundstation go-librespot[32699]: time="2025-12-09T04:57:04-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 04:57:04 northpi-soundstation go-librespot[32699]: time="2025-12-09T04:57: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-gew1.spotify.com:80]"
Dec 09 04:57:04 northpi-soundstation go-librespot[32699]: time="2025-12-09T04:57:04-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 04:57:04 northpi-soundstation go-librespot[32699]: time="2025-12-09T04:57:04-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 04:57:04 northpi-soundstation go-librespot[32699]: time="2025-12-09T04:57:04-05:00" level=info msg="zeroconf server listening on port 39311"
Dec 09 04:57:04 northpi-soundstation go-librespot[32699]: time="2025-12-09T04:57:04-05:00" level=debug msg="obtained new client token: AACVK5GEQow61nydmFSlffIso3v7acAkVMDEPPhDxQh8XSC4w51LXFKjlgCUcGPOs51tHPtErhTCQ4Xgu4Ngtttg+wMz6rynIUbuWYk7y8wyZfnj+Iz93gBIpzrGgJSw5+q3Cse3d3Ky2iaPyaoubPJ90h88v2LMPciy7DLfvYN/JGIU5R8X/NFLWgTxpm2cZd5A2Imt/F5zerqaOcHrl7C9sF5WhS73XAKghLv177wbEkDAqsmFCTNWgw=="
Dec 09 04:57:05 northpi-soundstation go-librespot[32699]: time="2025-12-09T04:57:05-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 04:57:05 northpi-soundstation go-librespot[32699]: time="2025-12-09T04:57:05-05:00" level=debug msg="completed keyexchange"
Dec 09 04:57:05 northpi-soundstation go-librespot[32699]: time="2025-12-09T04:57:05-05:00" level=debug msg="completed challenge"
Dec 09 04:57:05 northpi-soundstation go-librespot[32699]: time="2025-12-09T04:57: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 04:57:05 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 04:57:05 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 04:57:05 northpi-soundstation volumio[31960]: info: Initializing connection to go-librespot Websocket
Dec 09 04:57:05 northpi-soundstation volumio[31960]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 04:57:08 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19.
Dec 09 04:57:08 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:57:08 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:57:08 northpi-soundstation go-librespot[32721]: go-librespot daemon starting...
Dec 09 04:57:08 northpi-soundstation go-librespot[32722]: time="2025-12-09T04:57:08-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 04:57:08 northpi-soundstation go-librespot[32722]: time="2025-12-09T04:57:08-05:00" level=debug msg="app state loaded"
Dec 09 04:57:08 northpi-soundstation go-librespot[32722]: time="2025-12-09T04:57:08-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 04:57:08 northpi-soundstation go-librespot[32722]: time="2025-12-09T04:57:08-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 09 04:57:08 northpi-soundstation go-librespot[32722]: time="2025-12-09T04:57:08-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 04:57:08 northpi-soundstation go-librespot[32722]: time="2025-12-09T04:57:08-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 04:57:08 northpi-soundstation go-librespot[32722]: time="2025-12-09T04:57:08-05:00" level=info msg="zeroconf server listening on port 35621"
Dec 09 04:57:08 northpi-soundstation go-librespot[32722]: time="2025-12-09T04:57:08-05:00" level=debug msg="obtained new client token: AABlz69Gs81M4rgamwm9fVSALvqxRZJCxD1ZJf8++Q+tyH7WCMSVXpktD8BTuSi6ry3qe6HBDOPSqj1UKOajjIl1lbgE5DHlTQhjwbY345tkT5JTy5sml1rRmurkaX8zc+qrI4A3/+tVxxCwDNRtM26uvnIeV0HkUSk8YOtqE4oqPD16jpsY+4LPLSUIaPCVRQhjJ9D7L1cHDWEbqWPo0rE1w71gQRj+5msGN4EwDob521gF9VcBCLdsqg=="
Dec 09 04:57:08 northpi-soundstation go-librespot[32722]: time="2025-12-09T04:57:08-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 09 04:57:08 northpi-soundstation go-librespot[32722]: time="2025-12-09T04:57:08-05:00" level=debug msg="completed keyexchange"
Dec 09 04:57:08 northpi-soundstation go-librespot[32722]: time="2025-12-09T04:57:08-05:00" level=debug msg="completed challenge"
Dec 09 04:57:08 northpi-soundstation volumio[31960]: info: Initializing connection to go-librespot Websocket
Dec 09 04:57:08 northpi-soundstation go-librespot[32722]: time="2025-12-09T04:57:08-05:00" level=debug msg="new websocket client"
Dec 09 04:57:08 northpi-soundstation volumio[31960]: info: Connection to go-librespot Websocket established
Dec 09 04:57:08 northpi-soundstation go-librespot[32722]: time="2025-12-09T04:57:08-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 04:57:08 northpi-soundstation volumio[31960]: info: Connection to go-librespot Websocket closed
Dec 09 04:57:08 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 09 04:57:08 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 09 04:57:11 northpi-soundstation volumio[31960]: info: Getting Spotify volume
Dec 09 04:57:11 northpi-soundstation volumio[31960]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 09 04:57:11 northpi-soundstation volumio[31960]: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 09 04:57:11 northpi-soundstation volumio[31960]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Dec 09 04:57:11 northpi-soundstation volumio[31960]: errno: -111,
Dec 09 04:57:11 northpi-soundstation volumio[31960]: code: 'ECONNREFUSED',
Dec 09 04:57:11 northpi-soundstation volumio[31960]: syscall: 'connect',
Dec 09 04:57:11 northpi-soundstation volumio[31960]: address: '127.0.0.1',
Dec 09 04:57:11 northpi-soundstation volumio[31960]: port: 9879,
Dec 09 04:57:11 northpi-soundstation volumio[31960]: response: undefined
Dec 09 04:57:11 northpi-soundstation volumio[31960]: }
Dec 09 04:57:11 northpi-soundstation volumio[31960]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 09 04:57:11 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20.
Dec 09 04:57:11 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:57:11 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 09 04:57:11 northpi-soundstation go-librespot[32741]: go-librespot daemon starting...
Dec 09 04:57:12 northpi-soundstation go-librespot[32742]: time="2025-12-09T04:57:12-05:00" level=info msg="running go-librespot 0.3.2"
Dec 09 04:57:12 northpi-soundstation go-librespot[32742]: time="2025-12-09T04:57:12-05:00" level=debug msg="app state loaded"
Dec 09 04:57:12 northpi-soundstation go-librespot[32742]: time="2025-12-09T04:57:12-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 09 04:57:12 northpi-soundstation sudo[32751]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-09 04:56'
Dec 09 04:57:12 northpi-soundstation sudo[32751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 04:57:12 northpi-soundstation go-librespot[32742]: time="2025-12-09T04:57:12-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 09 04:57:12 northpi-soundstation go-librespot[32742]: time="2025-12-09T04:57:12-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 04:57:12 northpi-soundstation go-librespot[32742]: time="2025-12-09T04:57:12-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 04:57:12 northpi-soundstation go-librespot[32742]: time="2025-12-09T04:57:12-05:00" level=info msg="zeroconf server listening on port 42723"
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"