Dec 07 16:41:00 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4.
Dec 07 16:41:00 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:00 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:00 northpi-soundstation go-librespot[30992]: go-librespot daemon starting...
Dec 07 16:41:00 northpi-soundstation go-librespot[30993]: time="2025-12-07T16:41:00-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 16:41:00 northpi-soundstation go-librespot[30993]: time="2025-12-07T16:41:00-05:00" level=debug msg="app state loaded"
Dec 07 16:41:00 northpi-soundstation go-librespot[30993]: time="2025-12-07T16:41:00-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 16:41:00 northpi-soundstation go-librespot[30993]: time="2025-12-07T16:41: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 07 16:41:00 northpi-soundstation go-librespot[30993]: time="2025-12-07T16:41: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 07 16:41:00 northpi-soundstation go-librespot[30993]: time="2025-12-07T16:41: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 07 16:41:00 northpi-soundstation go-librespot[30993]: time="2025-12-07T16:41:00-05:00" level=info msg="zeroconf server listening on port 39583"
Dec 07 16:41:01 northpi-soundstation go-librespot[30993]: time="2025-12-07T16:41:01-05:00" level=debug msg="obtained new client token: AAA+EOSiObMZVEPOMwNz04DQOmfLIQNxuyHO/b/dGoWXJJpJo5MGpPS8iPjdZT02H3Q6+RncA5p3xHaPo0/7ud3455frnSQyCdlS2SjgflU5xiuxcrv9lPpDRXXYFPP1624NzTyfmpQuhfcGkAKUiEgUeuppIgX0hHMnpWPFfMVViGe9OYjbbxhZ9LVHKiiS+g+YwfA39NyM9IHI7WhTjy4Qv/P0QaoElNjZrle2UuaXodWw+2n8CgY5xQ=="
Dec 07 16:41:01 northpi-soundstation go-librespot[30993]: time="2025-12-07T16:41:01-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 16:41:01 northpi-soundstation go-librespot[30993]: time="2025-12-07T16:41:01-05:00" level=debug msg="completed keyexchange"
Dec 07 16:41:01 northpi-soundstation go-librespot[30993]: time="2025-12-07T16:41:01-05:00" level=debug msg="completed challenge"
Dec 07 16:41:01 northpi-soundstation go-librespot[30993]: time="2025-12-07T16:41: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 07 16:41:01 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 16:41:01 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 16:41:03 northpi-soundstation volumio[30549]: warn: [cd-plugin] cdspeedctl: device or media not ready
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: Adding inputs REST Endpoints
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: Scanning Audio Inputs
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: Checking against Known Cards name
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: Adding Server instance for streaming
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio
Dec 07 16:41:03 northpi-soundstation volumio[30549]: error: Hi Res Audio Failed Login: Missing Login Data
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: Adding HIGHRESAUDIO REST API Endpoints
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: QobuzConnect: Starting Qobuz Connect socket and service
Dec 07 16:41:03 northpi-soundstation sudo[31018]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Dec 07 16:41:03 northpi-soundstation sudo[31018]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: Adding TIDAL REST API Endpoints
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: Stopping AccessToken refresher cron for QOBUZ
Dec 07 16:41:03 northpi-soundstation sudo[31025]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service
Dec 07 16:41:03 northpi-soundstation sudo[31025]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: AccessToken refresher cron started for QOBUZ
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: Adding QOBUZ REST API Endpoints
Dec 07 16:41:03 northpi-soundstation systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service...
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:41:03 northpi-soundstation systemd[1]: vtcs.service: Killing process 29500 (vtcs) with signal SIGKILL.
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: Updating MyVolumio device info
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 07 16:41:03 northpi-soundstation systemd[1]: vtcs.service: Deactivated successfully.
Dec 07 16:41:03 northpi-soundstation systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service.
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: MRS: Getting audio outputs on start
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: MRS: Requesting all other devices output
Dec 07 16:41:03 northpi-soundstation sudo[31025]: pam_unix(sudo:session): session closed for user root
Dec 07 16:41:03 northpi-soundstation sudo[31018]: pam_unix(sudo:session): session closed for user root
Dec 07 16:41:03 northpi-soundstation sudo[31027]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service
Dec 07 16:41:03 northpi-soundstation sudo[31027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: Successfully Updated MyVolumio device
Dec 07 16:41:03 northpi-soundstation volumio[30549]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: MPD Permissions set
Dec 07 16:41:03 northpi-soundstation qobuz-connect[29464]: 20251207 16:41:03.668 [29464.29464] INFO SampleApp: Stopping Local configuration server
Dec 07 16:41:03 northpi-soundstation systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service...
Dec 07 16:41:03 northpi-soundstation sudo[31031]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:41:03 northpi-soundstation sudo[31031]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioGetState
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: CorePlayQueue::getTrack 0
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 16:41:03 northpi-soundstation sudo[31031]: pam_unix(sudo:session): session closed for user root
Dec 07 16:41:03 northpi-soundstation volumio[30549]: error: updateQueue error: null
Dec 07 16:41:03 northpi-soundstation volumio[30549]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioStop
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: CoreStateMachine::stop
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 07 16:41:03 northpi-soundstation volumio[30549]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: Starting Shairport Sync
Dec 07 16:41:03 northpi-soundstation sudo[31045]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 07 16:41:03 northpi-soundstation sudo[31045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 16:41:03 northpi-soundstation sudo[31047]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service
Dec 07 16:41:03 northpi-soundstation sudo[31047]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 16:41:03 northpi-soundstation systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Dec 07 16:41:03 northpi-soundstation systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel...
Dec 07 16:41:03 northpi-soundstation autossh[29446]: received signal to exit (15)
Dec 07 16:41:03 northpi-soundstation systemd[1]: sshtunnel.service: Deactivated successfully.
Dec 07 16:41:03 northpi-soundstation systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel.
Dec 07 16:41:03 northpi-soundstation systemd[1]: shairport-sync.service: Deactivated successfully.
Dec 07 16:41:03 northpi-soundstation systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Dec 07 16:41:03 northpi-soundstation systemd[1]: shairport-sync.service: Consumed 1.588s CPU time.
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: Successfully Updated MyVolumio device
Dec 07 16:41:03 northpi-soundstation systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Dec 07 16:41:03 northpi-soundstation systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel.
Dec 07 16:41:03 northpi-soundstation sudo[31045]: pam_unix(sudo:session): session closed for user root
Dec 07 16:41:03 northpi-soundstation autossh[31052]: port set to 0, monitoring disabled
Dec 07 16:41:03 northpi-soundstation autossh[31052]: starting ssh (count 1)
Dec 07 16:41:03 northpi-soundstation autossh[31052]: ssh child pid is 31055
Dec 07 16:41:03 northpi-soundstation sudo[31047]: pam_unix(sudo:session): session closed for user root
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: Successfully Added MyVolumio device
Dec 07 16:41:03 northpi-soundstation volumio[30549]: info: Remote SSH Started
Dec 07 16:41:04 northpi-soundstation volumiossh-tunnel[31055]: Warning: Permanently added '[us4.myvolumio.org]:2222' (ED25519) to the list of known hosts.
Dec 07 16:41:04 northpi-soundstation volumio[30549]: info: Shairport-Sync Started
Dec 07 16:41:04 northpi-soundstation volumio[30549]: 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 07 16:41:04 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5.
Dec 07 16:41:04 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioGetState
Dec 07 16:41:04 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:04 northpi-soundstation volumio[30549]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.0b008618efe21bea1cc98e8c917fa0a8.state.status'
Dec 07 16:41:04 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:04 northpi-soundstation go-librespot[31070]: go-librespot daemon starting...
Dec 07 16:41:04 northpi-soundstation go-librespot[31071]: time="2025-12-07T16:41:04-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 16:41:04 northpi-soundstation go-librespot[31071]: time="2025-12-07T16:41:04-05:00" level=debug msg="app state loaded"
Dec 07 16:41:04 northpi-soundstation go-librespot[31071]: time="2025-12-07T16:41:04-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 16:41:04 northpi-soundstation go-librespot[31071]: time="2025-12-07T16:41: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-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 07 16:41:04 northpi-soundstation go-librespot[31071]: time="2025-12-07T16:41:04-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 07 16:41:04 northpi-soundstation go-librespot[31071]: time="2025-12-07T16:41:04-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 07 16:41:04 northpi-soundstation go-librespot[31071]: time="2025-12-07T16:41:04-05:00" level=info msg="zeroconf server listening on port 43057"
Dec 07 16:41:04 northpi-soundstation go-librespot[31071]: time="2025-12-07T16:41:04-05:00" level=debug msg="obtained new client token: AADCFPOQ1y6DULCtJ5h0FPqLqmesGHSsE7eaxDvxmB1EBIVmG3qAS3B+cxHASPsJuk/LvwTSiPU+wElPd1SZVELERWvwkmwfWpS1A9MNvVmSxCCpXmMbGbLxxN8mLhIgzjzcJIntoTynnhvq5WCISOTxgAWhvOldFTPFCStXq2pESk+I2zhdbJClhDvA2twYSlbXT3D+gWhZ1qDb+1QLJuDTDQ+MyyuONvsh3/CroIk8Q8GkWi0JWoWRbQ=="
Dec 07 16:41:04 northpi-soundstation go-librespot[31071]: time="2025-12-07T16:41:04-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 16:41:04 northpi-soundstation go-librespot[31071]: time="2025-12-07T16:41:04-05:00" level=debug msg="completed keyexchange"
Dec 07 16:41:04 northpi-soundstation go-librespot[31071]: time="2025-12-07T16:41:04-05:00" level=debug msg="completed challenge"
Dec 07 16:41:04 northpi-soundstation go-librespot[31071]: time="2025-12-07T16:41: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 07 16:41:04 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 16:41:04 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 16:41:05 northpi-soundstation systemd[1]: qobuz-connect.service: Deactivated successfully.
Dec 07 16:41:05 northpi-soundstation systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service.
Dec 07 16:41:05 northpi-soundstation sudo[31027]: pam_unix(sudo:session): session closed for user root
Dec 07 16:41:05 northpi-soundstation systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service.
Dec 07 16:41:05 northpi-soundstation volumio[30549]: info: Executing endpoint qc_getconfig
Dec 07 16:41:05 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Dec 07 16:41:05 northpi-soundstation qobuz-connect[31078]: 20251207 16:41:05.217 [31078.31078] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Dec 07 16:41:05 northpi-soundstation qobuz-connect[31078]: 20251207 16:41:05.221 [31078.31078] INFO VolumeManager: [0x211598]: Setting new playback volume: 75
Dec 07 16:41:05 northpi-soundstation qobuz-connect[31078]: 20251207 16:41:05.221 [31078.31078] INFO VolumeManager: [0x211598]: Setting new mute state: 0
Dec 07 16:41:05 northpi-soundstation qobuz-connect[31078]: 20251207 16:41:05.221 [31078.31078] INFO QobuzConnect: [0x211f68]: Client initialized!
Dec 07 16:41:05 northpi-soundstation qobuz-connect[31078]: 20251207 16:41:05.221 [31078.31078] INFO SampleApp: Starting Avahi advertising, name: NorthPi-Soundstation, service name: _qobuz-connect._tcp
Dec 07 16:41:05 northpi-soundstation volumio[30549]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object]
Dec 07 16:41:05 northpi-soundstation volumio[30549]: info: QobuzConnect: QOBUZ Connect daemon connected
Dec 07 16:41:05 northpi-soundstation qobuz-connect[31078]: 20251207 16:41:05.236 [31078.31078] INFO LocalConfigManager: [0x210e78]: Starting Local Configuration server
Dec 07 16:41:05 northpi-soundstation qobuz-connect[31078]: 20251207 16:41:05.236 [31078.31078] INFO SampleApp: Starting Local configuration server
Dec 07 16:41:05 northpi-soundstation qobuz-connect[31078]: 20251207 16:41:05.236 [31078.31078] INFO SampleApp: Connected to UNIX socket client 0x1fb818
Dec 07 16:41:05 northpi-soundstation qobuz-connect[31078]: 20251207 16:41:05.367 [31078.31078] INFO SampleApp: Playback volume changed: 75
Dec 07 16:41:05 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioGetState
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: TidalConnect service stoped!
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect
Dec 07 16:41:06 northpi-soundstation sudo[31094]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Dec 07 16:41:06 northpi-soundstation sudo[31094]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:41:06 northpi-soundstation sudo[31096]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Dec 07 16:41:06 northpi-soundstation sudo[31096]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 16:41:06 northpi-soundstation systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service...
Dec 07 16:41:06 northpi-soundstation systemd[1]: Started vtcs.service - Volumio Tidal Connect Service.
Dec 07 16:41:06 northpi-soundstation systemd[1]: mpd_monitor.service: Deactivated successfully.
Dec 07 16:41:06 northpi-soundstation systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service.
Dec 07 16:41:06 northpi-soundstation sudo[31094]: pam_unix(sudo:session): session closed for user root
Dec 07 16:41:06 northpi-soundstation systemd[1]: Started mpd_monitor.service - MPD Monitor Service.
Dec 07 16:41:06 northpi-soundstation sudo[31096]: pam_unix(sudo:session): session closed for user root
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: Successfully started MPD Monitor
Dec 07 16:41:06 northpi-soundstation mpd_monitor.sh[31101]: MPD Monitor Service: Starting MPD Monitor Service
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: Executing endpoint tc_getconfig
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig
Dec 07 16:41:06 northpi-soundstation vtcs[31100]: STARTING TidalConnect services, version: 1.5.2.56
Dec 07 16:41:06 northpi-soundstation vtcs[31100]: STARTED TidalConnect services.
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: Executing endpoint tc_connect
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: Connecting to TidalConnect
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreCommandRouter::servicePushState
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreStateMachine::pushState
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioPushState
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: MRS: Pushing multiroomSync output update for this device
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: MRS: Pushing multiroomSync output
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioGetState
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CorePlayQueue::getTrack 0
Dec 07 16:41:06 northpi-soundstation volumio[30549]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false}
Dec 07 16:41:06 northpi-soundstation volumio[30549]: verbose: CURRENT POSITION 0
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreStateMachine::syncState stateService stop
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreStateMachine::syncState currentStatus stop
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreStateMachine::pushState
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioPushState
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: MRS: Pushing multiroomSync output update for this device
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: MRS: Pushing multiroomSync output
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioGetState
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: No code
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreStateMachine::pushState
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioPushState
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: MRS: Pushing multiroomSync output update for this device
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: MRS: Pushing multiroomSync output
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioGetState
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreCommandRouter::servicePushState
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreStateMachine::pushState
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioPushState
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: MRS: Pushing multiroomSync output update for this device
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: MRS: Pushing multiroomSync output
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioGetState
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CorePlayQueue::getTrack 0
Dec 07 16:41:06 northpi-soundstation volumio[30549]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false}
Dec 07 16:41:06 northpi-soundstation volumio[30549]: verbose: CURRENT POSITION 0
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreStateMachine::syncState stateService stop
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreStateMachine::syncState currentStatus stop
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreStateMachine::pushState
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioPushState
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: MRS: Pushing multiroomSync output update for this device
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: MRS: Pushing multiroomSync output
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioGetState
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: No code
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreStateMachine::pushState
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioPushState
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: MRS: Pushing multiroomSync output update for this device
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: MRS: Pushing multiroomSync output
Dec 07 16:41:06 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioGetState
Dec 07 16:41:07 northpi-soundstation volumio[30549]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.0b008618efe21bea1cc98e8c917fa0a8.state.status'
Dec 07 16:41:07 northpi-soundstation volumio[30549]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.0b008618efe21bea1cc98e8c917fa0a8.state.status'
Dec 07 16:41:07 northpi-soundstation volumio[30549]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.0b008618efe21bea1cc98e8c917fa0a8.state.status'
Dec 07 16:41:07 northpi-soundstation volumio[30549]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KgLfU6XUUlR7svHZAvNpgZIq9af1.0b008618efe21bea1cc98e8c917fa0a8.state.status'
Dec 07 16:41:07 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6.
Dec 07 16:41:07 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:07 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:07 northpi-soundstation go-librespot[31120]: go-librespot daemon starting...
Dec 07 16:41:07 northpi-soundstation go-librespot[31121]: time="2025-12-07T16:41:07-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 16:41:07 northpi-soundstation go-librespot[31121]: time="2025-12-07T16:41:07-05:00" level=debug msg="app state loaded"
Dec 07 16:41:07 northpi-soundstation go-librespot[31121]: time="2025-12-07T16:41:07-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 16:41:08 northpi-soundstation go-librespot[31121]: time="2025-12-07T16:41: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 07 16:41:08 northpi-soundstation go-librespot[31121]: time="2025-12-07T16:41:08-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 07 16:41:08 northpi-soundstation go-librespot[31121]: time="2025-12-07T16:41:08-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 07 16:41:08 northpi-soundstation go-librespot[31121]: time="2025-12-07T16:41:08-05:00" level=info msg="zeroconf server listening on port 40769"
Dec 07 16:41:08 northpi-soundstation go-librespot[31121]: time="2025-12-07T16:41:08-05:00" level=debug msg="obtained new client token: AABIzlV1abCTY/xBRGqrmsUjm9S5QfSMBtTQkuTGCvBFtih8s8CPDfYKOWgnqYNxv2A/xqH5lVXHHWj9J4rm6OkoxHateNwxBN4V+J2efLGdVnAREadmkYAaRq4FwCuQJuCzXtUtXcnNvZcHba6xhvp5bfg98f4BFmQy3UTJzfa4EZaFMB4OB8UNr2NjG2eHWSw3V5+u/y0emAcdI7FvrUKCwo75NGssi43BWU77U6lkXvayeLl+Kay/jQ=="
Dec 07 16:41:08 northpi-soundstation go-librespot[31121]: time="2025-12-07T16:41:08-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 16:41:08 northpi-soundstation go-librespot[31121]: time="2025-12-07T16:41:08-05:00" level=debug msg="completed keyexchange"
Dec 07 16:41:08 northpi-soundstation go-librespot[31121]: time="2025-12-07T16:41:08-05:00" level=debug msg="completed challenge"
Dec 07 16:41:08 northpi-soundstation go-librespot[31121]: time="2025-12-07T16:41: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 07 16:41:08 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 16:41:08 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 16:41:08 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioGetState
Dec 07 16:41:08 northpi-soundstation volumio[30549]: info: Listing playlists
Dec 07 16:41:08 northpi-soundstation volumio[30549]: info: Listing playlists
Dec 07 16:41:09 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:41:09 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:41:09 northpi-soundstation volumio[30549]: info: TidalConnect service started!
Dec 07 16:41:09 northpi-soundstation volumio[30549]: [Metrics] CommandRouter: 30s 16.34ms
Dec 07 16:41:09 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumiosetStartupVolume
Dec 07 16:41:09 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 16:41:09 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 07 16:41:09 northpi-soundstation volumio[30549]: info: CoreCommandRouter::Close All Modals sent
Dec 07 16:41:09 northpi-soundstation volumio[30549]: info: CoreCommandRouter::Close All Modals sent
Dec 07 16:41:10 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 07 16:41:10 northpi-soundstation volumio[30549]: 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 07 16:41:10 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioGetState
Dec 07 16:41:10 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Dec 07 16:41:10 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Dec 07 16:41:10 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Dec 07 16:41:11 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7.
Dec 07 16:41:11 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:11 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:11 northpi-soundstation go-librespot[31147]: go-librespot daemon starting...
Dec 07 16:41:11 northpi-soundstation go-librespot[31148]: time="2025-12-07T16:41:11-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 16:41:11 northpi-soundstation go-librespot[31148]: time="2025-12-07T16:41:11-05:00" level=debug msg="app state loaded"
Dec 07 16:41:11 northpi-soundstation go-librespot[31148]: time="2025-12-07T16:41:11-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 16:41:11 northpi-soundstation go-librespot[31148]: time="2025-12-07T16:41: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-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 07 16:41:11 northpi-soundstation go-librespot[31148]: time="2025-12-07T16:41:11-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 07 16:41:11 northpi-soundstation go-librespot[31148]: time="2025-12-07T16:41:11-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 07 16:41:11 northpi-soundstation go-librespot[31148]: time="2025-12-07T16:41:11-05:00" level=info msg="zeroconf server listening on port 44335"
Dec 07 16:41:12 northpi-soundstation go-librespot[31148]: time="2025-12-07T16:41:12-05:00" level=debug msg="obtained new client token: AABZ6D3cuEE0Vlu197PKzxCJ+wJQOxva/DhOtW8h+ToXDu4I8RPJ7r33P3BiDt55AkoUXCctyB/zgRhmJR/l3iJPsulG+8vCZNb+bLi/Hyzju116+ElWZkqInLZ8ueQhA132dFzmLaN97ukQkzxvejbembPxYIrIaq46se3OlJF8KnpF9NVcCGKfNB08Wrag/kVDxv4m4UhFGVLoJvxzz3NACz/1BcoSleT2djuOBA4HH5SEjtAbSUOfDA=="
Dec 07 16:41:12 northpi-soundstation go-librespot[31148]: time="2025-12-07T16:41:12-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 16:41:12 northpi-soundstation go-librespot[31148]: time="2025-12-07T16:41:12-05:00" level=debug msg="completed keyexchange"
Dec 07 16:41:12 northpi-soundstation go-librespot[31148]: time="2025-12-07T16:41:12-05:00" level=debug msg="completed challenge"
Dec 07 16:41:12 northpi-soundstation go-librespot[31148]: time="2025-12-07T16:41:12-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 07 16:41:12 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 16:41:12 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 16:41:12 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:41:12 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:41:14 northpi-soundstation kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 13
Dec 07 16:41:14 northpi-soundstation kernel: xhci_hcd 0000:01:00.0: @0000000428d22990 00000000 00000000 0e000000 020e8000
Dec 07 16:41:15 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 07 16:41:15 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 07 16:41:15 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 07 16:41:15 northpi-soundstation volumio-remote-updater[7772]: Test mode enabled
Dec 07 16:41:15 northpi-soundstation volumio-remote-updater[7772]: Alpha mode disabled
Dec 07 16:41:15 northpi-soundstation volumio-remote-updater[7772]: Alpha legacy test mode disabled
Dec 07 16:41:15 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Dec 07 16:41:15 northpi-soundstation volumio[30549]: verbose: New Socket.io Connection to 192.168.1.28:3000 from 192.168.1.97 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Dec 07 16:41:15 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 16:41:15 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 07 16:41:15 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Dec 07 16:41:15 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Dec 07 16:41:15 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Dec 07 16:41:15 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 07 16:41:15 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 07 16:41:15 northpi-soundstation volumio[30549]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"ANNOUNCEMENT
\n\n- Production line
\n
\nFIX
\n\n- Nodejs 20.5.1 version pinning with static package fallback
\n
\n","title":"Update v4.073","updateavailable":true}
Dec 07 16:41:15 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Dec 07 16:41:15 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8.
Dec 07 16:41:15 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:15 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:41:15 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:41:15 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:15 northpi-soundstation go-librespot[31160]: go-librespot daemon starting...
Dec 07 16:41:15 northpi-soundstation go-librespot[31161]: time="2025-12-07T16:41:15-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 16:41:15 northpi-soundstation go-librespot[31161]: time="2025-12-07T16:41:15-05:00" level=debug msg="app state loaded"
Dec 07 16:41:15 northpi-soundstation go-librespot[31161]: time="2025-12-07T16:41:15-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 16:41:15 northpi-soundstation go-librespot[31161]: time="2025-12-07T16:41:15-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 07 16:41:15 northpi-soundstation go-librespot[31161]: time="2025-12-07T16:41:15-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 07 16:41:15 northpi-soundstation go-librespot[31161]: time="2025-12-07T16:41:15-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 07 16:41:15 northpi-soundstation go-librespot[31161]: time="2025-12-07T16:41:15-05:00" level=info msg="zeroconf server listening on port 44027"
Dec 07 16:41:16 northpi-soundstation go-librespot[31161]: time="2025-12-07T16:41:16-05:00" level=debug msg="obtained new client token: AABTrTx8UkOULgpOAwuDAHQGCnoP6FmB5yjSgohxXL5EaKzZOu9inC8e7iYDrezJ4qqS4qtyWMEvehTQKk77wx2R/ATdZhK/0pav6t46S6g8uO/n37y8aQ0NbEUrqRv1CLsT635GvX5nVqjFNfOQm6FsOCqfzqbHCv5XkAxMswBphkWpDxhFdDdYoPSaj3olg7bu1ChTeLFhQZ6zQT3IC2XjB0xSCECWGb58NLMgGFxSVZh3VB4fx7E="
Dec 07 16:41:16 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket
Dec 07 16:41:16 northpi-soundstation go-librespot[31161]: time="2025-12-07T16:41:16-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 16:41:16 northpi-soundstation go-librespot[31161]: time="2025-12-07T16:41:16-05:00" level=debug msg="completed keyexchange"
Dec 07 16:41:16 northpi-soundstation go-librespot[31161]: time="2025-12-07T16:41:16-05:00" level=debug msg="completed challenge"
Dec 07 16:41:16 northpi-soundstation go-librespot[31161]: time="2025-12-07T16:41:16-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 07 16:41:16 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioGetState
Dec 07 16:41:16 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 16:41:16 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 16:41:16 northpi-soundstation volumio[30549]: info: BOOT COMPLETED
Dec 07 16:41:18 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:41:18 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:41:18 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioGetState
Dec 07 16:41:19 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9.
Dec 07 16:41:19 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:19 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:19 northpi-soundstation go-librespot[31168]: go-librespot daemon starting...
Dec 07 16:41:19 northpi-soundstation go-librespot[31169]: time="2025-12-07T16:41:19-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 16:41:19 northpi-soundstation go-librespot[31169]: time="2025-12-07T16:41:19-05:00" level=debug msg="app state loaded"
Dec 07 16:41:19 northpi-soundstation go-librespot[31169]: time="2025-12-07T16:41:19-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 16:41:19 northpi-soundstation go-librespot[31169]: time="2025-12-07T16:41:19-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 07 16:41:19 northpi-soundstation go-librespot[31169]: time="2025-12-07T16:41:19-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 07 16:41:19 northpi-soundstation go-librespot[31169]: time="2025-12-07T16:41:19-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 07 16:41:19 northpi-soundstation go-librespot[31169]: time="2025-12-07T16:41:19-05:00" level=info msg="zeroconf server listening on port 36559"
Dec 07 16:41:19 northpi-soundstation go-librespot[31169]: time="2025-12-07T16:41:19-05:00" level=debug msg="obtained new client token: AAAqEhHE6nKnrDoZwnusAxZVDK23jv8bLSxGNJPYEFWkNg/cvkqA0np2kpb84jPDjrKg3Mdhh5vvD/+k+xYtG+tT8dyFirxVqi9mKY2UU9wzfjtwYV28avAeu5BLkliN43zYh6ioxOa7Y9CSUIqHd1JkxZZyW7sRyc0x+izKAL7BZ2dYeofdRcAAC5pcmNGGMNrlWZSCakv+1EvmBlpYyvpkJOryV/SUdqvOSonL2OrY++PDaxdA7BZOBQ=="
Dec 07 16:41:19 northpi-soundstation go-librespot[31169]: time="2025-12-07T16:41:19-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 16:41:19 northpi-soundstation go-librespot[31169]: time="2025-12-07T16:41:19-05:00" level=debug msg="completed keyexchange"
Dec 07 16:41:19 northpi-soundstation go-librespot[31169]: time="2025-12-07T16:41:19-05:00" level=debug msg="completed challenge"
Dec 07 16:41:19 northpi-soundstation go-librespot[31169]: time="2025-12-07T16:41:19-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 07 16:41:19 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 16:41:19 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 16:41:21 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:41:21 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:41:23 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10.
Dec 07 16:41:23 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:23 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:23 northpi-soundstation go-librespot[31190]: go-librespot daemon starting...
Dec 07 16:41:23 northpi-soundstation go-librespot[31191]: time="2025-12-07T16:41:23-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 16:41:23 northpi-soundstation go-librespot[31191]: time="2025-12-07T16:41:23-05:00" level=debug msg="app state loaded"
Dec 07 16:41:23 northpi-soundstation go-librespot[31191]: time="2025-12-07T16:41:23-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 16:41:23 northpi-soundstation go-librespot[31191]: time="2025-12-07T16:41:23-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 07 16:41:23 northpi-soundstation go-librespot[31191]: time="2025-12-07T16:41:23-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 07 16:41:23 northpi-soundstation go-librespot[31191]: time="2025-12-07T16:41:23-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 07 16:41:23 northpi-soundstation go-librespot[31191]: time="2025-12-07T16:41:23-05:00" level=info msg="zeroconf server listening on port 35105"
Dec 07 16:41:23 northpi-soundstation go-librespot[31191]: time="2025-12-07T16:41:23-05:00" level=debug msg="obtained new client token: AAB16CqRnMtXk1oq12ngUiGRjCXHVFlkytlw+4yw64ngMBSfe03H2tOq5BSL/pXJUoJJbcNN2GlPv7BonJayewfbly4umw/iG+r6Qa1Xb4HEA2iES1VLiNxhSzEBB/tmBGCV2tsu8ZIAe193k1LEXLamxpMVDQz71wg1veK1G2mCGqWw9OPlkJQ6qzpub0pOCUFLMHufldzSV9udNheu7jViwtQ+1kWA4LPot7JLdATX7xj+i+fFMRdkSQ=="
Dec 07 16:41:23 northpi-soundstation go-librespot[31191]: time="2025-12-07T16:41:23-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 16:41:23 northpi-soundstation go-librespot[31191]: time="2025-12-07T16:41:23-05:00" level=debug msg="completed keyexchange"
Dec 07 16:41:23 northpi-soundstation go-librespot[31191]: time="2025-12-07T16:41:23-05:00" level=debug msg="completed challenge"
Dec 07 16:41:23 northpi-soundstation go-librespot[31191]: time="2025-12-07T16:41:23-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 07 16:41:23 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 16:41:23 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 16:41:24 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:41:24 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:41:26 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11.
Dec 07 16:41:26 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:26 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:26 northpi-soundstation go-librespot[31201]: go-librespot daemon starting...
Dec 07 16:41:26 northpi-soundstation go-librespot[31202]: time="2025-12-07T16:41:26-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 16:41:26 northpi-soundstation go-librespot[31202]: time="2025-12-07T16:41:26-05:00" level=debug msg="app state loaded"
Dec 07 16:41:26 northpi-soundstation go-librespot[31202]: time="2025-12-07T16:41:26-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 16:41:27 northpi-soundstation go-librespot[31202]: time="2025-12-07T16:41:27-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 07 16:41:27 northpi-soundstation go-librespot[31202]: time="2025-12-07T16:41:27-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 07 16:41:27 northpi-soundstation go-librespot[31202]: time="2025-12-07T16:41:27-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 07 16:41:27 northpi-soundstation go-librespot[31202]: time="2025-12-07T16:41:27-05:00" level=info msg="zeroconf server listening on port 32789"
Dec 07 16:41:27 northpi-soundstation go-librespot[31202]: time="2025-12-07T16:41:27-05:00" level=debug msg="obtained new client token: AAD0RJvLvXRjGzKCivobU6r/lfVtG1P5zMd89Le+gy4qAypLh+bV2uvLJbUSwYzn8PSvTkV3HnRcU0A8c1MjBmmJwyy258kSGYjKeO9hHf7tJD9ttq/hDdVNog/h/flUt2ZEY0KPm8E9J3bU/GxYEliA2S1wJMFVTsFpYxMEYRvf9yLN8z71RT4xB6IzOTQc59nZj6rIuvWkSlOHfuI+jwP7ez4BXJ2J/fLnddjCVifd11qhgJNniGs="
Dec 07 16:41:27 northpi-soundstation go-librespot[31202]: time="2025-12-07T16:41:27-05:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.255.62:4070: connect: connection refused"
Dec 07 16:41:27 northpi-soundstation go-librespot[31202]: time="2025-12-07T16:41:27-05:00" level=debug msg="connected to ap-gue1.spotify.com:443"
Dec 07 16:41:27 northpi-soundstation go-librespot[31202]: time="2025-12-07T16:41:27-05:00" level=debug msg="completed keyexchange"
Dec 07 16:41:27 northpi-soundstation go-librespot[31202]: time="2025-12-07T16:41:27-05:00" level=debug msg="completed challenge"
Dec 07 16:41:27 northpi-soundstation go-librespot[31202]: time="2025-12-07T16:41:27-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 07 16:41:27 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 16:41:27 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 16:41:27 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:41:27 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:41:28 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioGetState
Dec 07 16:41:28 northpi-soundstation volumio[30549]: info: Listing playlists
Dec 07 16:41:28 northpi-soundstation volumio[30549]: info: Listing playlists
Dec 07 16:41:30 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12.
Dec 07 16:41:30 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:30 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:41:30 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:41:30 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:30 northpi-soundstation go-librespot[31209]: go-librespot daemon starting...
Dec 07 16:41:30 northpi-soundstation go-librespot[31210]: time="2025-12-07T16:41:30-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 16:41:30 northpi-soundstation go-librespot[31210]: time="2025-12-07T16:41:30-05:00" level=debug msg="app state loaded"
Dec 07 16:41:30 northpi-soundstation go-librespot[31210]: time="2025-12-07T16:41:30-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 16:41:30 northpi-soundstation go-librespot[31210]: time="2025-12-07T16:41:30-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 07 16:41:30 northpi-soundstation go-librespot[31210]: time="2025-12-07T16:41:30-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 07 16:41:30 northpi-soundstation go-librespot[31210]: time="2025-12-07T16:41:30-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 07 16:41:30 northpi-soundstation go-librespot[31210]: time="2025-12-07T16:41:30-05:00" level=info msg="zeroconf server listening on port 34657"
Dec 07 16:41:31 northpi-soundstation go-librespot[31210]: time="2025-12-07T16:41:31-05:00" level=debug msg="obtained new client token: AAAG5hRjjC28dHKkZsjk8+fd1dSbyHtTUgJdQ30fyztI2CA6gXS65jILDfZI0ZNkVG6oMhkHw7DAdpUG3z64BsB4u7xpjI/u+5OW37QsSMj8gQ5DMB/mT+9LbFbO82naUHXOMBdNjrC1S/aI12pEldLgTKc77ncB6LHrNIQmRTl8xWqEJS5jMgu6XUtYMQGeERVTMFAiCmO6u2/yk4xuGK8arJIM9uBLACvQMwHoezuu+FD/gbu7FOU="
Dec 07 16:41:31 northpi-soundstation go-librespot[31210]: time="2025-12-07T16:41:31-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 16:41:31 northpi-soundstation go-librespot[31210]: time="2025-12-07T16:41:31-05:00" level=debug msg="completed keyexchange"
Dec 07 16:41:31 northpi-soundstation go-librespot[31210]: time="2025-12-07T16:41:31-05:00" level=debug msg="completed challenge"
Dec 07 16:41:31 northpi-soundstation go-librespot[31210]: time="2025-12-07T16:41:31-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 07 16:41:31 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 16:41:31 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 16:41:33 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:41:33 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:41:34 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13.
Dec 07 16:41:34 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:34 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:34 northpi-soundstation go-librespot[31232]: go-librespot daemon starting...
Dec 07 16:41:34 northpi-soundstation go-librespot[31233]: time="2025-12-07T16:41:34-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 16:41:34 northpi-soundstation go-librespot[31233]: time="2025-12-07T16:41:34-05:00" level=debug msg="app state loaded"
Dec 07 16:41:34 northpi-soundstation go-librespot[31233]: time="2025-12-07T16:41:34-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 16:41:34 northpi-soundstation go-librespot[31233]: time="2025-12-07T16:41:34-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 07 16:41:34 northpi-soundstation go-librespot[31233]: time="2025-12-07T16:41:34-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 07 16:41:34 northpi-soundstation go-librespot[31233]: time="2025-12-07T16:41:34-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 07 16:41:34 northpi-soundstation go-librespot[31233]: time="2025-12-07T16:41:34-05:00" level=info msg="zeroconf server listening on port 38751"
Dec 07 16:41:34 northpi-soundstation go-librespot[31233]: time="2025-12-07T16:41:34-05:00" level=debug msg="obtained new client token: AAAmel4dK2f9VzhJrqTNYdR4NPuqokUjcQcjW8h7Ba+Th+Os1StoHcXR6zJvJzHfMrnkEtbFi8h3IlWFAqUiCaT3x6LGfK7KKgHzW+leb1gAKWG2kvxEC+N9CBonjyeEHYDOJ6lSnliSEJbcKjjEFL4idhnuGywTzpc2/Oj0FPCE0V41Y1Rm/HLNWPxdkqAh2hq6aaeejFD06MpLdinwrvxmNMgNWuB7eV/FJ67Asqbz+YWNYalf8qPnZw=="
Dec 07 16:41:34 northpi-soundstation go-librespot[31233]: time="2025-12-07T16:41:34-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 16:41:34 northpi-soundstation go-librespot[31233]: time="2025-12-07T16:41:34-05:00" level=debug msg="completed keyexchange"
Dec 07 16:41:34 northpi-soundstation go-librespot[31233]: time="2025-12-07T16:41:34-05:00" level=debug msg="completed challenge"
Dec 07 16:41:34 northpi-soundstation go-librespot[31233]: time="2025-12-07T16:41:34-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 07 16:41:34 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 16:41:34 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 16:41:36 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:41:36 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:41:38 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14.
Dec 07 16:41:38 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:38 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:38 northpi-soundstation go-librespot[31240]: go-librespot daemon starting...
Dec 07 16:41:38 northpi-soundstation go-librespot[31241]: time="2025-12-07T16:41:38-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 16:41:38 northpi-soundstation go-librespot[31241]: time="2025-12-07T16:41:38-05:00" level=debug msg="app state loaded"
Dec 07 16:41:38 northpi-soundstation go-librespot[31241]: time="2025-12-07T16:41:38-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 16:41:38 northpi-soundstation go-librespot[31241]: time="2025-12-07T16:41:38-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 07 16:41:38 northpi-soundstation go-librespot[31241]: time="2025-12-07T16:41:38-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 07 16:41:38 northpi-soundstation go-librespot[31241]: time="2025-12-07T16:41:38-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 07 16:41:38 northpi-soundstation go-librespot[31241]: time="2025-12-07T16:41:38-05:00" level=info msg="zeroconf server listening on port 43175"
Dec 07 16:41:38 northpi-soundstation go-librespot[31241]: time="2025-12-07T16:41:38-05:00" level=debug msg="obtained new client token: AAA4sI4yoi8Euoda0mgky6eF0pAjGB0dPWpiu+/AM+A/2gPFH1sFyvNplsTQaem5TglIE8zCKvAXPOBI//XNARMUARfIQFIxr8z5+gHMpZw8gd/kldFSKYiWWM4QvNreSXIQbImliLjRwXWys/stZaE3hDMPAFCSOWfG66PUYh3i46tjeGInKRSxizJnRKyM35+16g983uO620P7nv5wATXR5+7mvtZJFAYFTKoQLqpwz62WD4PypZZDqg=="
Dec 07 16:41:38 northpi-soundstation go-librespot[31241]: time="2025-12-07T16:41:38-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 16:41:38 northpi-soundstation go-librespot[31241]: time="2025-12-07T16:41:38-05:00" level=debug msg="completed keyexchange"
Dec 07 16:41:38 northpi-soundstation go-librespot[31241]: time="2025-12-07T16:41:38-05:00" level=debug msg="completed challenge"
Dec 07 16:41:38 northpi-soundstation go-librespot[31241]: time="2025-12-07T16:41:38-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 07 16:41:38 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 16:41:38 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 16:41:38 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioGetState
Dec 07 16:41:39 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:41:39 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:41:41 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15.
Dec 07 16:41:41 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:41 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:41 northpi-soundstation go-librespot[31263]: go-librespot daemon starting...
Dec 07 16:41:41 northpi-soundstation go-librespot[31264]: time="2025-12-07T16:41:41-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 16:41:41 northpi-soundstation go-librespot[31264]: time="2025-12-07T16:41:41-05:00" level=debug msg="app state loaded"
Dec 07 16:41:41 northpi-soundstation go-librespot[31264]: time="2025-12-07T16:41:41-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 16:41:41 northpi-soundstation go-librespot[31264]: time="2025-12-07T16:41:41-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 07 16:41:41 northpi-soundstation go-librespot[31264]: time="2025-12-07T16:41:41-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 07 16:41:41 northpi-soundstation go-librespot[31264]: time="2025-12-07T16:41:41-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 07 16:41:41 northpi-soundstation go-librespot[31264]: time="2025-12-07T16:41:41-05:00" level=info msg="zeroconf server listening on port 33203"
Dec 07 16:41:42 northpi-soundstation go-librespot[31264]: time="2025-12-07T16:41:42-05:00" level=debug msg="obtained new client token: AAARBp+1rRWl1QjjAQzTyQDhrXd5w4UwTo6Tgx8EL2zGwXi8F/rWwRkzilSB8pDTqaLstJEhdFZFwsZ5YqwuADIhoBH2KKiQoLGn5jENFUHsief/LPHkNoSwxTfMZ1Q9psN40LJ34EX+5K7DpbFDmEdWquHfIbsiu6VBHNO3CXveL4Rni/LtuUoJajQ+picM6eN+4SHjGxh2mdS2FqrKSbbODqeg3s92m4l70WGkbZR0K/lFjpw/YWBxtA=="
Dec 07 16:41:42 northpi-soundstation go-librespot[31264]: time="2025-12-07T16:41:42-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 16:41:42 northpi-soundstation go-librespot[31264]: time="2025-12-07T16:41:42-05:00" level=debug msg="completed keyexchange"
Dec 07 16:41:42 northpi-soundstation go-librespot[31264]: time="2025-12-07T16:41:42-05:00" level=debug msg="completed challenge"
Dec 07 16:41:42 northpi-soundstation go-librespot[31264]: time="2025-12-07T16:41:42-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 07 16:41:42 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 16:41:42 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 16:41:42 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:41:42 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:41:45 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16.
Dec 07 16:41:45 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:45 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:45 northpi-soundstation go-librespot[31272]: go-librespot daemon starting...
Dec 07 16:41:45 northpi-soundstation go-librespot[31273]: time="2025-12-07T16:41:45-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 16:41:45 northpi-soundstation go-librespot[31273]: time="2025-12-07T16:41:45-05:00" level=debug msg="app state loaded"
Dec 07 16:41:45 northpi-soundstation go-librespot[31273]: time="2025-12-07T16:41:45-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 16:41:45 northpi-soundstation volumio[30549]: info: UPDATER: Scheduling automatic update
Dec 07 16:41:45 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Dec 07 16:41:45 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Dec 07 16:41:45 northpi-soundstation volumio[30549]: info: UPDATER: Auto update will take place at: Mon Dec 08 2025 05:39:20 GMT-0500 (Eastern Standard Time)
Dec 07 16:41:45 northpi-soundstation go-librespot[31273]: time="2025-12-07T16:41:45-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 07 16:41:45 northpi-soundstation go-librespot[31273]: time="2025-12-07T16:41:45-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 07 16:41:45 northpi-soundstation go-librespot[31273]: time="2025-12-07T16:41:45-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 07 16:41:45 northpi-soundstation go-librespot[31273]: time="2025-12-07T16:41:45-05:00" level=info msg="zeroconf server listening on port 34825"
Dec 07 16:41:45 northpi-soundstation go-librespot[31273]: time="2025-12-07T16:41:45-05:00" level=debug msg="obtained new client token: AABkynjyEe4ibNvBc09uz+u9czSvLfiym2Gw2H6FBMk9qtACQqwFVLYAkKyd8mwQc/DSkSvZy9VL5HxWv/3O8ioRysL9EA1eal9un7iLDDUcKNrTa/YwwGY7EkDuJslG1UPmonN1ggbixN9Ia3cVM61IFZCaRL8yFYyt25jp2OsEjSho1YG5ipj0+0HwU5CnBbaX/xrm3a2vVL5o3NKNtgDXCHMrmgMEztraW/f6xLUu4TbiXxBOmvDj+A=="
Dec 07 16:41:45 northpi-soundstation go-librespot[31273]: time="2025-12-07T16:41:45-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 16:41:45 northpi-soundstation go-librespot[31273]: time="2025-12-07T16:41:45-05:00" level=debug msg="completed keyexchange"
Dec 07 16:41:45 northpi-soundstation go-librespot[31273]: time="2025-12-07T16:41:45-05:00" level=debug msg="completed challenge"
Dec 07 16:41:45 northpi-soundstation go-librespot[31273]: time="2025-12-07T16:41:45-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 07 16:41:45 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 16:41:45 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 16:41:45 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:41:45 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:41:48 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:41:48 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:41:48 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioGetState
Dec 07 16:41:48 northpi-soundstation volumio[30549]: info: Listing playlists
Dec 07 16:41:48 northpi-soundstation volumio[30549]: info: Listing playlists
Dec 07 16:41:48 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17.
Dec 07 16:41:48 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:48 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:48 northpi-soundstation go-librespot[31285]: go-librespot daemon starting...
Dec 07 16:41:48 northpi-soundstation go-librespot[31286]: time="2025-12-07T16:41:48-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 16:41:48 northpi-soundstation go-librespot[31286]: time="2025-12-07T16:41:48-05:00" level=debug msg="app state loaded"
Dec 07 16:41:48 northpi-soundstation go-librespot[31286]: time="2025-12-07T16:41:48-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 16:41:49 northpi-soundstation go-librespot[31286]: time="2025-12-07T16:41:49-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 07 16:41:49 northpi-soundstation go-librespot[31286]: time="2025-12-07T16:41:49-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 07 16:41:49 northpi-soundstation go-librespot[31286]: time="2025-12-07T16:41:49-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 07 16:41:49 northpi-soundstation go-librespot[31286]: time="2025-12-07T16:41:49-05:00" level=info msg="zeroconf server listening on port 35487"
Dec 07 16:41:49 northpi-soundstation go-librespot[31286]: time="2025-12-07T16:41:49-05:00" level=debug msg="obtained new client token: AADlE5Kb5FOoJzJBgkrtn6JnEDc3IVDXtTjGxo63Mv6SYzclIQbXiSkfw06SBagrQat4EAsDeAWtoeObElZfHnST3uLayKsx8/bcBscgXGRYDgZ7UzwXLbiUNDLDs/T6j5GrOf8tJ/JvSaggjCSTErGwwXRo+pu0dPHHaY148KszbKIDvLFgdwls3+C2V9/SusCaERRKGyaFz15ENUNFraRJVZY41HhdrxErhhW+8Lwqu/i9XF4i160="
Dec 07 16:41:49 northpi-soundstation go-librespot[31286]: time="2025-12-07T16:41:49-05:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.255.62:4070: connect: connection refused"
Dec 07 16:41:49 northpi-soundstation go-librespot[31286]: time="2025-12-07T16:41:49-05:00" level=debug msg="connected to ap-gue1.spotify.com:443"
Dec 07 16:41:49 northpi-soundstation go-librespot[31286]: time="2025-12-07T16:41:49-05:00" level=debug msg="completed keyexchange"
Dec 07 16:41:49 northpi-soundstation go-librespot[31286]: time="2025-12-07T16:41:49-05:00" level=debug msg="completed challenge"
Dec 07 16:41:49 northpi-soundstation go-librespot[31286]: time="2025-12-07T16:41:49-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 07 16:41:49 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 16:41:49 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 16:41:51 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:41:51 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:41:52 northpi-soundstation volumio[30549]: verbose: New Socket.io Connection to 192.168.1.28:3000 from 192.168.1.97 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Dec 07 16:41:52 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18.
Dec 07 16:41:52 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:52 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:52 northpi-soundstation go-librespot[31307]: go-librespot daemon starting...
Dec 07 16:41:52 northpi-soundstation volumio[30549]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket
Dec 07 16:41:52 northpi-soundstation go-librespot[31308]: time="2025-12-07T16:41:52-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 16:41:52 northpi-soundstation go-librespot[31308]: time="2025-12-07T16:41:52-05:00" level=debug msg="app state loaded"
Dec 07 16:41:52 northpi-soundstation go-librespot[31308]: time="2025-12-07T16:41:52-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 16:41:52 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioGetState
Dec 07 16:41:52 northpi-soundstation go-librespot[31308]: time="2025-12-07T16:41:52-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 07 16:41:52 northpi-soundstation go-librespot[31308]: time="2025-12-07T16:41:52-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 07 16:41:52 northpi-soundstation go-librespot[31308]: time="2025-12-07T16:41:52-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 07 16:41:52 northpi-soundstation go-librespot[31308]: time="2025-12-07T16:41:52-05:00" level=info msg="zeroconf server listening on port 34165"
Dec 07 16:41:53 northpi-soundstation go-librespot[31308]: time="2025-12-07T16:41:53-05:00" level=debug msg="obtained new client token: AADfGwu6qRtmAZoORnRoiahHsA4JrNk8g2/pDKp4GDrLe/z63WgkxHbZVN8zxzuIUk+rG41WelwLBuMOcDRyl3FyLeBzNEavaLN8iAAE0G0fpeUAAeYeHLSkqfJQdGbjac7mhtL/FG7iEl+4FGzepnAhdamfUj+chxMSGfNEXnR8RDFJF4hZbJyCnCA62QNv1wvqMHXZd0/3uO5FsxouqERoj9dZJiS3s30Cdp1qpH7NtoKI8974"
Dec 07 16:41:53 northpi-soundstation go-librespot[31308]: time="2025-12-07T16:41:53-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 16:41:53 northpi-soundstation go-librespot[31308]: time="2025-12-07T16:41:53-05:00" level=debug msg="completed keyexchange"
Dec 07 16:41:53 northpi-soundstation go-librespot[31308]: time="2025-12-07T16:41:53-05:00" level=debug msg="completed challenge"
Dec 07 16:41:53 northpi-soundstation go-librespot[31308]: time="2025-12-07T16:41:53-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 07 16:41:53 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 16:41:53 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 16:41:54 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:41:54 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:41:56 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19.
Dec 07 16:41:56 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:56 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:56 northpi-soundstation go-librespot[31315]: go-librespot daemon starting...
Dec 07 16:41:56 northpi-soundstation go-librespot[31316]: time="2025-12-07T16:41:56-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 16:41:56 northpi-soundstation go-librespot[31316]: time="2025-12-07T16:41:56-05:00" level=debug msg="app state loaded"
Dec 07 16:41:56 northpi-soundstation go-librespot[31316]: time="2025-12-07T16:41:56-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 16:41:56 northpi-soundstation go-librespot[31316]: time="2025-12-07T16:41:56-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 07 16:41:56 northpi-soundstation go-librespot[31316]: time="2025-12-07T16:41:56-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 07 16:41:56 northpi-soundstation go-librespot[31316]: time="2025-12-07T16:41:56-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 07 16:41:56 northpi-soundstation go-librespot[31316]: time="2025-12-07T16:41:56-05:00" level=info msg="zeroconf server listening on port 36447"
Dec 07 16:41:56 northpi-soundstation go-librespot[31316]: time="2025-12-07T16:41:56-05:00" level=debug msg="obtained new client token: AABRB+yoxHBriMb8x9qQIONGD9Y2DDPQKV4PLP6RuNQD2S37Yl65STKHwtbY3S5imaLQwo7BLYZaBEevbc92/uZJ3NhXUCcensJr5VBWQRYd0HqeMpIyRDnDT0emZ4PAFe/HJlNvp9CezYq8698Vic8rkYMLPbxmluJP6sM9+W4rwuwNVedOgGAFjYdOhldWhJwzfkYNul0hoT+hMs8+cX9Hs5H3lZ0DH8ydWnlUF2aQE56URCnglttq/A=="
Dec 07 16:41:56 northpi-soundstation go-librespot[31316]: time="2025-12-07T16:41:56-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 16:41:56 northpi-soundstation go-librespot[31316]: time="2025-12-07T16:41:56-05:00" level=debug msg="completed keyexchange"
Dec 07 16:41:56 northpi-soundstation go-librespot[31316]: time="2025-12-07T16:41:56-05:00" level=debug msg="completed challenge"
Dec 07 16:41:56 northpi-soundstation go-librespot[31316]: time="2025-12-07T16:41:56-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 07 16:41:56 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 16:41:56 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 16:41:57 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:41:57 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:41:58 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioGetState
Dec 07 16:41:59 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20.
Dec 07 16:41:59 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:59 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:41:59 northpi-soundstation go-librespot[31323]: go-librespot daemon starting...
Dec 07 16:41:59 northpi-soundstation go-librespot[31324]: time="2025-12-07T16:41:59-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 16:41:59 northpi-soundstation go-librespot[31324]: time="2025-12-07T16:41:59-05:00" level=debug msg="app state loaded"
Dec 07 16:41:59 northpi-soundstation go-librespot[31324]: time="2025-12-07T16:41:59-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 16:42:00 northpi-soundstation go-librespot[31324]: time="2025-12-07T16:42:00-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 07 16:42:00 northpi-soundstation go-librespot[31324]: time="2025-12-07T16:42:00-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 07 16:42:00 northpi-soundstation go-librespot[31324]: time="2025-12-07T16:42:00-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 07 16:42:00 northpi-soundstation go-librespot[31324]: time="2025-12-07T16:42:00-05:00" level=info msg="zeroconf server listening on port 42667"
Dec 07 16:42:00 northpi-soundstation go-librespot[31324]: time="2025-12-07T16:42:00-05:00" level=debug msg="obtained new client token: AAAHnhRUoavHT3l6rXcQFERpyjVNoRZeVaNLzAH5bqO6qVwpgBBS4a94yvr4NnkD05L35UHd/F9FQTXsPETTlvsuoC7zGUZaVof+U/BucZgxnYIJDtRiCF3fDiH+imDbbs2AlgFwwnVjKtzlWq58ip/CjqOlWGxgiU2J7E1bdaQ8SQSHabyFZ3qE5eRd0AU5Gey7pGH75vb4+Fa9aSx+HJutjf0/RV3842k/eun7gHVokefyhhO/DiQ="
Dec 07 16:42:00 northpi-soundstation go-librespot[31324]: time="2025-12-07T16:42:00-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 16:42:00 northpi-soundstation go-librespot[31324]: time="2025-12-07T16:42:00-05:00" level=debug msg="completed keyexchange"
Dec 07 16:42:00 northpi-soundstation go-librespot[31324]: time="2025-12-07T16:42:00-05:00" level=debug msg="completed challenge"
Dec 07 16:42:00 northpi-soundstation go-librespot[31324]: time="2025-12-07T16:42:00-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 07 16:42:00 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 16:42:00 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 16:42:00 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:42:00 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:42:03 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21.
Dec 07 16:42:03 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:42:03 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:42:03 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:42:03 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:42:03 northpi-soundstation go-librespot[31346]: go-librespot daemon starting...
Dec 07 16:42:03 northpi-soundstation go-librespot[31347]: time="2025-12-07T16:42:03-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 16:42:03 northpi-soundstation go-librespot[31347]: time="2025-12-07T16:42:03-05:00" level=debug msg="app state loaded"
Dec 07 16:42:03 northpi-soundstation go-librespot[31347]: time="2025-12-07T16:42:03-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 16:42:03 northpi-soundstation go-librespot[31347]: time="2025-12-07T16:42: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-gew1.spotify.com:80]"
Dec 07 16:42:03 northpi-soundstation go-librespot[31347]: time="2025-12-07T16:42:03-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 07 16:42:03 northpi-soundstation go-librespot[31347]: time="2025-12-07T16:42:03-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 07 16:42:03 northpi-soundstation go-librespot[31347]: time="2025-12-07T16:42:03-05:00" level=info msg="zeroconf server listening on port 33083"
Dec 07 16:42:04 northpi-soundstation go-librespot[31347]: time="2025-12-07T16:42:04-05:00" level=debug msg="obtained new client token: AAABa4ZcuUxB3BzLMx54mO6Kfu+rL+qElE0yYnyXMrYKbUTBFfy8t9fq1FIrVE8T1j6bxJjKUWbfOiS+1tA6wAsekFf2VlM11zkVlDORmZqGakULQImhElRk6ZGqsd6jp//+KxO4xVcrDHJUrcr+QmffCd8iaGIX0uMJV/B4OOSDkoxjmq0NlEm02u/K8SEMJeYOggNgrq/wbEKmd9t0ypL990QrSFTAnzaup13DN9VVmJYfTbg9YBw="
Dec 07 16:42:04 northpi-soundstation go-librespot[31347]: time="2025-12-07T16:42:04-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 16:42:04 northpi-soundstation go-librespot[31347]: time="2025-12-07T16:42:04-05:00" level=debug msg="completed keyexchange"
Dec 07 16:42:04 northpi-soundstation go-librespot[31347]: time="2025-12-07T16:42:04-05:00" level=debug msg="completed challenge"
Dec 07 16:42:04 northpi-soundstation go-librespot[31347]: time="2025-12-07T16:42: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 07 16:42:04 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 16:42:04 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 16:42:06 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:42:06 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:42:07 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22.
Dec 07 16:42:07 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:42:07 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:42:07 northpi-soundstation go-librespot[31357]: go-librespot daemon starting...
Dec 07 16:42:07 northpi-soundstation go-librespot[31358]: time="2025-12-07T16:42:07-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 16:42:07 northpi-soundstation go-librespot[31358]: time="2025-12-07T16:42:07-05:00" level=debug msg="app state loaded"
Dec 07 16:42:07 northpi-soundstation go-librespot[31358]: time="2025-12-07T16:42:07-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 16:42:07 northpi-soundstation go-librespot[31358]: time="2025-12-07T16:42:07-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 07 16:42:07 northpi-soundstation go-librespot[31358]: time="2025-12-07T16:42:07-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 07 16:42:07 northpi-soundstation go-librespot[31358]: time="2025-12-07T16:42:07-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 07 16:42:07 northpi-soundstation go-librespot[31358]: time="2025-12-07T16:42:07-05:00" level=info msg="zeroconf server listening on port 36673"
Dec 07 16:42:07 northpi-soundstation go-librespot[31358]: time="2025-12-07T16:42:07-05:00" level=debug msg="obtained new client token: AADa0L41UP2LjqIcRth6NiG09pjgDF1NmIBLdj3TpS8Uix+Z4mU6/OZ+At4WjdYZbsQ2m4EEKha3b3nJv1QO2i82HEUUpAOOCV6cVLDSd1ROUczBGdr8vAVY+sF9mcss+LVKptIDood7vXCvYtMnvt+YjRX3Z9G0J+2i4J97yGtuAosWiHb3xhcCee0UfoaZ5L7BgNKJtLmKyz8lY7qyfAOlEZ8T329bMGUUwEww+xBd8oeIaPU58/K7Ag=="
Dec 07 16:42:07 northpi-soundstation go-librespot[31358]: time="2025-12-07T16:42:07-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 16:42:07 northpi-soundstation go-librespot[31358]: time="2025-12-07T16:42:07-05:00" level=debug msg="completed keyexchange"
Dec 07 16:42:07 northpi-soundstation go-librespot[31358]: time="2025-12-07T16:42:07-05:00" level=debug msg="completed challenge"
Dec 07 16:42:07 northpi-soundstation go-librespot[31358]: time="2025-12-07T16:42:07-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 07 16:42:07 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 16:42:07 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 16:42:08 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioGetState
Dec 07 16:42:08 northpi-soundstation volumio[30549]: info: Listing playlists
Dec 07 16:42:08 northpi-soundstation volumio[30549]: info: Listing playlists
Dec 07 16:42:09 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:42:09 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:42:10 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23.
Dec 07 16:42:10 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:42:10 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:42:11 northpi-soundstation go-librespot[31366]: go-librespot daemon starting...
Dec 07 16:42:11 northpi-soundstation go-librespot[31367]: time="2025-12-07T16:42:11-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 16:42:11 northpi-soundstation go-librespot[31367]: time="2025-12-07T16:42:11-05:00" level=debug msg="app state loaded"
Dec 07 16:42:11 northpi-soundstation go-librespot[31367]: time="2025-12-07T16:42:11-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 16:42:11 northpi-soundstation go-librespot[31367]: time="2025-12-07T16:42: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-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 07 16:42:11 northpi-soundstation go-librespot[31367]: time="2025-12-07T16:42:11-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 07 16:42:11 northpi-soundstation go-librespot[31367]: time="2025-12-07T16:42:11-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 07 16:42:11 northpi-soundstation go-librespot[31367]: time="2025-12-07T16:42:11-05:00" level=info msg="zeroconf server listening on port 38835"
Dec 07 16:42:11 northpi-soundstation go-librespot[31367]: time="2025-12-07T16:42:11-05:00" level=debug msg="obtained new client token: AACMpbiNCFznKmaksYLML70VxeieXVfllapd9ZZ4AWVCh2HWRqVG3T7QvkdSjeMsP+4zwjaycjqfzOTvj8hL2/GQl7Hs5JGDE09Uvl+rHS1MSgCUQdcyV/drnI8fuxFXCHEK1i8ydOE0wQDTHWdBLEoHatUFsvcoMWxX+qRxtN2lNtVwnTtEh1Ob8xXI5cxaGgLhJN/Hkc7pnkFHeygklMAGE59c85Cl/sWwLNj+jOG3B+a13QS+N60nPA=="
Dec 07 16:42:11 northpi-soundstation go-librespot[31367]: time="2025-12-07T16:42:11-05:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.255.62:4070: connect: connection refused"
Dec 07 16:42:11 northpi-soundstation go-librespot[31367]: time="2025-12-07T16:42:11-05:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.255.62:443: connect: connection refused"
Dec 07 16:42:11 northpi-soundstation go-librespot[31367]: time="2025-12-07T16:42:11-05:00" level=debug msg="connected to ap-gue1.spotify.com:80"
Dec 07 16:42:11 northpi-soundstation go-librespot[31367]: time="2025-12-07T16:42:11-05:00" level=debug msg="completed keyexchange"
Dec 07 16:42:11 northpi-soundstation go-librespot[31367]: time="2025-12-07T16:42:11-05:00" level=debug msg="completed challenge"
Dec 07 16:42:11 northpi-soundstation go-librespot[31367]: time="2025-12-07T16:42:11-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 07 16:42:11 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 16:42:11 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 16:42:12 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:42:12 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:42:14 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24.
Dec 07 16:42:14 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:42:14 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:42:14 northpi-soundstation go-librespot[31388]: go-librespot daemon starting...
Dec 07 16:42:14 northpi-soundstation go-librespot[31389]: time="2025-12-07T16:42:14-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 16:42:14 northpi-soundstation go-librespot[31389]: time="2025-12-07T16:42:14-05:00" level=debug msg="app state loaded"
Dec 07 16:42:14 northpi-soundstation go-librespot[31389]: time="2025-12-07T16:42:14-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 16:42:14 northpi-soundstation go-librespot[31389]: time="2025-12-07T16:42:14-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 07 16:42:14 northpi-soundstation go-librespot[31389]: time="2025-12-07T16:42:14-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 07 16:42:14 northpi-soundstation go-librespot[31389]: time="2025-12-07T16:42:14-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 07 16:42:14 northpi-soundstation go-librespot[31389]: time="2025-12-07T16:42:14-05:00" level=info msg="zeroconf server listening on port 44765"
Dec 07 16:42:15 northpi-soundstation go-librespot[31389]: time="2025-12-07T16:42:15-05:00" level=debug msg="obtained new client token: AAAQGbyNY36F4hY5D5SKAtc9tBdPA3GersoTCSKh7rE+5/tkoDk8P8CyuJoODzSQ2B54oqxjWwjLL5xB8QMhB7S+cvjqaUmWmrTEA9IDgRv3Er1mxbAyyvWYmzmQLecn0b6XFpmPN9p9axtxezbRk3JH4BwlkFo2Lg8DBE5Bq7lldsbm8wrHsl/yxDlBAPdR8w54DO5BMa/YkDcnDw7HlP+eiw2ruPj2xp2JYF64jN6oOvdgedjURbBZnw=="
Dec 07 16:42:15 northpi-soundstation go-librespot[31389]: time="2025-12-07T16:42:15-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 16:42:15 northpi-soundstation go-librespot[31389]: time="2025-12-07T16:42:15-05:00" level=debug msg="completed keyexchange"
Dec 07 16:42:15 northpi-soundstation go-librespot[31389]: time="2025-12-07T16:42:15-05:00" level=debug msg="completed challenge"
Dec 07 16:42:15 northpi-soundstation go-librespot[31389]: time="2025-12-07T16:42: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 07 16:42:15 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 16:42:15 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 16:42:15 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:42:15 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:42:18 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25.
Dec 07 16:42:18 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:42:18 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:42:18 northpi-soundstation go-librespot[31398]: go-librespot daemon starting...
Dec 07 16:42:18 northpi-soundstation go-librespot[31399]: time="2025-12-07T16:42:18-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 16:42:18 northpi-soundstation go-librespot[31399]: time="2025-12-07T16:42:18-05:00" level=debug msg="app state loaded"
Dec 07 16:42:18 northpi-soundstation go-librespot[31399]: time="2025-12-07T16:42:18-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 16:42:18 northpi-soundstation go-librespot[31399]: time="2025-12-07T16:42: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-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 07 16:42:18 northpi-soundstation go-librespot[31399]: time="2025-12-07T16:42:18-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 07 16:42:18 northpi-soundstation go-librespot[31399]: time="2025-12-07T16:42:18-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 07 16:42:18 northpi-soundstation go-librespot[31399]: time="2025-12-07T16:42:18-05:00" level=info msg="zeroconf server listening on port 46391"
Dec 07 16:42:18 northpi-soundstation go-librespot[31399]: time="2025-12-07T16:42:18-05:00" level=debug msg="obtained new client token: AACA7XpDUjHUb0Mxx2JWgtb39SEvJYa18o84G6SJsv8kjkPSSRyohcEcu8UeqRakL2sMBwJ6UTacBYPUA8aHKj9+iArO2uLGr3RB2XqaOOzBlFwOrSsZoduHmOYTtUCG4q3I3Lg6r7Ig5U2azX5ua/CYwQ/kffqnTMSGr4RELDhasl1MmikBKllcZLxl/aprAW8qtAMFAMk7yyHLVqppWd7S1OPdwxpSwjSrnlhpgr4DmSxjDtKE1U8Bwg=="
Dec 07 16:42:18 northpi-soundstation go-librespot[31399]: time="2025-12-07T16:42:18-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 16:42:18 northpi-soundstation go-librespot[31399]: time="2025-12-07T16:42:18-05:00" level=debug msg="completed keyexchange"
Dec 07 16:42:18 northpi-soundstation go-librespot[31399]: time="2025-12-07T16:42:18-05:00" level=debug msg="completed challenge"
Dec 07 16:42:18 northpi-soundstation go-librespot[31399]: time="2025-12-07T16:42:18-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 07 16:42:18 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 16:42:18 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 16:42:18 northpi-soundstation volumio[30549]: info: CoreCommandRouter::volumioGetState
Dec 07 16:42:18 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:42:18 northpi-soundstation volumio[30549]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:42:21 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26.
Dec 07 16:42:21 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:42:21 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:42:21 northpi-soundstation go-librespot[31421]: go-librespot daemon starting...
Dec 07 16:42:21 northpi-soundstation go-librespot[31422]: time="2025-12-07T16:42:21-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 16:42:21 northpi-soundstation go-librespot[31422]: time="2025-12-07T16:42:21-05:00" level=debug msg="app state loaded"
Dec 07 16:42:21 northpi-soundstation go-librespot[31422]: time="2025-12-07T16:42:21-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 07 16:42:21 northpi-soundstation volumio[30549]: info: Initializing connection to go-librespot Websocket
Dec 07 16:42:21 northpi-soundstation go-librespot[31422]: time="2025-12-07T16:42:21-05:00" level=debug msg="new websocket client"
Dec 07 16:42:21 northpi-soundstation volumio[30549]: info: Connection to go-librespot Websocket established
Dec 07 16:42:21 northpi-soundstation go-librespot[31422]: time="2025-12-07T16:42:21-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 07 16:42:21 northpi-soundstation go-librespot[31422]: time="2025-12-07T16:42:21-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 07 16:42:21 northpi-soundstation go-librespot[31422]: time="2025-12-07T16:42:21-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 07 16:42:21 northpi-soundstation go-librespot[31422]: time="2025-12-07T16:42:21-05:00" level=info msg="zeroconf server listening on port 37277"
Dec 07 16:42:22 northpi-soundstation go-librespot[31422]: time="2025-12-07T16:42:22-05:00" level=debug msg="obtained new client token: AAD7e0VtBTmv0Glme+oS4UsqMZTeIzyEpPs9aI5atfigG33s1mfgovREyYVmAaEdvzT/9w5AYdKR53W+EZ/AU362aswq6BgqUHKNRoGCgx6fGwHO5EQ+64+DyOjIaBTeg7agSa6SFOmpHUl4DrtsgC1rKiB6A0xerz69fP1qSL633CC0S9XQHNyo1UHBoxuXP+GvDy7xe8e3Q16HbyY+6gHOREDqkpZaYZIQ0WuEfo2t7fSf0lvCEKMOxA=="
Dec 07 16:42:22 northpi-soundstation go-librespot[31422]: time="2025-12-07T16:42:22-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Dec 07 16:42:22 northpi-soundstation go-librespot[31422]: time="2025-12-07T16:42:22-05:00" level=debug msg="completed keyexchange"
Dec 07 16:42:22 northpi-soundstation go-librespot[31422]: time="2025-12-07T16:42:22-05:00" level=debug msg="completed challenge"
Dec 07 16:42:22 northpi-soundstation go-librespot[31422]: time="2025-12-07T16:42:22-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Dec 07 16:42:22 northpi-soundstation systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 07 16:42:22 northpi-soundstation systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 07 16:42:22 northpi-soundstation volumio[30549]: info: Connection to go-librespot Websocket closed
Dec 07 16:42:24 northpi-soundstation volumio[30549]: info: Getting Spotify volume
Dec 07 16:42:24 northpi-soundstation volumio[30549]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 07 16:42:24 northpi-soundstation volumio[30549]: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 07 16:42:24 northpi-soundstation volumio[30549]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Dec 07 16:42:24 northpi-soundstation volumio[30549]: errno: -111,
Dec 07 16:42:24 northpi-soundstation volumio[30549]: code: 'ECONNREFUSED',
Dec 07 16:42:24 northpi-soundstation volumio[30549]: syscall: 'connect',
Dec 07 16:42:24 northpi-soundstation volumio[30549]: address: '127.0.0.1',
Dec 07 16:42:24 northpi-soundstation volumio[30549]: port: 9879,
Dec 07 16:42:24 northpi-soundstation volumio[30549]: response: undefined
Dec 07 16:42:24 northpi-soundstation volumio[30549]: }
Dec 07 16:42:24 northpi-soundstation volumio[30549]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 07 16:42:25 northpi-soundstation systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27.
Dec 07 16:42:25 northpi-soundstation systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:42:25 northpi-soundstation sudo[31444]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-07 16:41'
Dec 07 16:42:25 northpi-soundstation sudo[31444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 07 16:42:25 northpi-soundstation systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 07 16:42:25 northpi-soundstation go-librespot[31441]: go-librespot daemon starting...
Dec 07 16:42:25 northpi-soundstation go-librespot[31446]: time="2025-12-07T16:42:25-05:00" level=info msg="running go-librespot 0.3.2"
Dec 07 16:42:25 northpi-soundstation go-librespot[31446]: time="2025-12-07T16:42:25-05:00" level=debug msg="app state loaded"
Dec 07 16:42:25 northpi-soundstation go-librespot[31446]: time="2025-12-07T16:42:25-05:00" level=info msg="api server listening on 127.0.0.1:9879"
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"