-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Fri 2024-09-06 00:23:56 UTC. -- Sep 06 00:22:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:22:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Sep 06 00:22:01 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:22:01 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:22:01 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:01 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:22:01 volumio go-librespot[2361]: Librespot-go daemon starting... Sep 06 00:22:01 volumio go-librespot[2361]: time="2024-09-06T00:22:01Z" level=info msg="generated new device id: ecc9b82aaf926571f716b65a62803f1c1693e24f" Sep 06 00:22:01 volumio go-librespot[2361]: time="2024-09-06T00:22:01Z" level=debug msg="stored credentials not found" Sep 06 00:22:02 volumio go-librespot[2361]: time="2024-09-06T00:22:02Z" 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]" Sep 06 00:22:02 volumio go-librespot[2361]: time="2024-09-06T00:22:02Z" 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]" Sep 06 00:22:02 volumio go-librespot[2361]: time="2024-09-06T00:22:02Z" 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]" Sep 06 00:22:02 volumio go-librespot[2361]: time="2024-09-06T00:22:02Z" level=debug msg="zeroconf server listening on port 41969" Sep 06 00:22:02 volumio go-librespot[2361]: time="2024-09-06T00:22:02Z" level=debug msg="obtained new client token: AACLvFIT3Y0dGTJffcP1nZO0XCDGvBVXZWcerXO8VEEdRUkgZ6xaMuSSPs7ITbnL4DNE8ki3ho9YVHLyEHzal77hwK2i7L/JPUO2fO6bdS1n6iqdeaYnOfUVaoamQLdNzY4hYfK3JwZbHzd2+rP44bN/CjwfLzgJT0hLMNgz3WhBh4meWl+uj9GTIkHbck4D/7sEybudbWNXu5RHUJA9G3zfJok43y4G+Gknoi1JvI/W7YTmlnoafQ==" Sep 06 00:22:02 volumio go-librespot[2361]: time="2024-09-06T00:22:02Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:22:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:22:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:22:02 volumio volumio[983]: info: Preload queue cleared Sep 06 00:22:04 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:22:04 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:05 volumio volumio[983]: info: Preload queue cleared Sep 06 00:22:05 volumio volumio[983]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 06 00:22:05 volumio volumio[983]: info: CoreStateMachine::ClearQueue Sep 06 00:22:05 volumio volumio[983]: info: CoreStateMachine::stop Sep 06 00:22:05 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:05 volumio volumio[983]: info: CorePlayQueue::clearPlayQueue Sep 06 00:22:05 volumio volumio[983]: info: CorePlayQueue::saveQueue Sep 06 00:22:05 volumio volumio[983]: info: CoreCommandRouter::volumioPushQueue Sep 06 00:22:05 volumio volumio[983]: info: CoreStateMachine::addQueueItems Sep 06 00:22:05 volumio volumio[983]: info: CorePlayQueue::addQueueItems Sep 06 00:22:05 volumio volumio[983]: info: Preload queue cleared Sep 06 00:22:05 volumio volumio[983]: info: Adding Item to queue: spotify:album:0fDQ95grFC5hOYKrcPSUWw Sep 06 00:22:05 volumio volumio[983]: info: Exploding uri spotify:album:0fDQ95grFC5hOYKrcPSUWw in service spop Sep 06 00:22:05 volumio volumio[983]: SPOTIFY: EXPLODING URI:spotify:album:0fDQ95grFC5hOYKrcPSUWw Sep 06 00:22:05 volumio volumio[983]: info: CoreCommandRouter::volumioPushQueue Sep 06 00:22:05 volumio volumio[983]: info: CorePlayQueue::saveQueue Sep 06 00:22:05 volumio volumio[983]: info: CoreStateMachine::updateTrackBlock Sep 06 00:22:05 volumio volumio[983]: info: CorePlayQueue::getTrackBlock Sep 06 00:22:05 volumio volumio[983]: info: CoreCommandRouter::volumioPlay Sep 06 00:22:05 volumio volumio[983]: info: CoreStateMachine::play index 0 Sep 06 00:22:05 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:05 volumio volumio[983]: info: CoreStateMachine::stop Sep 06 00:22:05 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:05 volumio volumio[983]: info: CoreStateMachine::play index undefined Sep 06 00:22:05 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:05 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Sep 06 00:22:05 volumio volumio[983]: info: CoreStateMachine::startPlaybackTimer Sep 06 00:22:05 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Sep 06 00:22:05 volumio volumio[983]: info: [1725582125538] ControllerSpotify::clearAddPlayTrack Sep 06 00:22:05 volumio volumio[983]: info: Sending Spotify command with payload to local API: /player/play Sep 06 00:22:05 volumio volumio[983]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:22:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Sep 06 00:22:05 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:22:05 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:22:05 volumio go-librespot[2370]: Librespot-go daemon starting... Sep 06 00:22:05 volumio go-librespot[2370]: time="2024-09-06T00:22:05Z" level=info msg="generated new device id: 67e72697a80389cc5bc68ecafa3453e2fdf6fbb6" Sep 06 00:22:05 volumio go-librespot[2370]: time="2024-09-06T00:22:05Z" level=debug msg="stored credentials not found" Sep 06 00:22:05 volumio go-librespot[2370]: time="2024-09-06T00:22:05Z" 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]" Sep 06 00:22:05 volumio go-librespot[2370]: time="2024-09-06T00:22:05Z" 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]" Sep 06 00:22:05 volumio go-librespot[2370]: time="2024-09-06T00:22:05Z" 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]" Sep 06 00:22:05 volumio go-librespot[2370]: time="2024-09-06T00:22:05Z" level=debug msg="zeroconf server listening on port 45581" Sep 06 00:22:05 volumio go-librespot[2370]: time="2024-09-06T00:22:05Z" level=debug msg="obtained new client token: AAD4m8jjJduxAccNhxQg8pbv/rVlwZzUlGmIV889IhKg5WyoRIi//yur0VVgX2pKSoA7EtN68nFuzceP9X2gZiR64m3oMwXD/6lGXKDYw1bYptclxyUvO+s+ZBXYugtkyWkjtBgr8YVeF41JpFBie157pSV5eYemZJFVlst3gcR9iNDlh/nxapDM0o1iGl4Kh1OSBmt1IP0fBeBtLAhoTt5dfIoKzIVhGA2nB9r8Eh6MAHGY7R2j6LAT" Sep 06 00:22:06 volumio go-librespot[2370]: time="2024-09-06T00:22:06Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:22:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:22:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:22:07 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:22:07 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:07 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Sep 06 00:22:07 volumio volumio[983]: info: In handleBrowseUri, curUri=spotify:album:0fDQ95grFC5hOYKrcPSUWw Sep 06 00:22:08 volumio volumio[983]: info: Preload queue cleared Sep 06 00:22:08 volumio volumio[983]: info: Preloading song: spotify:track:5gwKOGdsJrXRihS4YNafpp Sep 06 00:22:08 volumio volumio[983]: info: Preloading song: spotify:track:3twFQfFRk4L7pCDQhPgkZL Sep 06 00:22:08 volumio volumio[983]: info: Preloading song: spotify:track:44Resf6o7RZsOky2sDpRKm Sep 06 00:22:08 volumio volumio[983]: info: Preloading song: spotify:track:46m1Ukc5okavdP4ZCA3cpa Sep 06 00:22:08 volumio volumio[983]: info: Preloading song: spotify:track:1erhVgJZqDcxG91DorrPnV Sep 06 00:22:08 volumio volumio[983]: info: Preloading song: spotify:track:01TR3fIvV0Ny06qcVdozQF Sep 06 00:22:08 volumio volumio[983]: info: Preloading song: spotify:track:3bBl5fYTIClDbM3RPucsvF Sep 06 00:22:08 volumio volumio[983]: info: Exploding uri spotify:track:5gwKOGdsJrXRihS4YNafpp in service spop Sep 06 00:22:08 volumio volumio[983]: SPOTIFY: EXPLODING URI:spotify:track:5gwKOGdsJrXRihS4YNafpp Sep 06 00:22:08 volumio volumio[983]: info: Exploding uri spotify:track:3twFQfFRk4L7pCDQhPgkZL in service spop Sep 06 00:22:08 volumio volumio[983]: SPOTIFY: EXPLODING URI:spotify:track:3twFQfFRk4L7pCDQhPgkZL Sep 06 00:22:08 volumio volumio[983]: info: No valid Plugin REST Endpoint: getSimilarAlbums Sep 06 00:22:08 volumio volumio[983]: info: Exploding uri spotify:track:44Resf6o7RZsOky2sDpRKm in service spop Sep 06 00:22:08 volumio volumio[983]: SPOTIFY: EXPLODING URI:spotify:track:44Resf6o7RZsOky2sDpRKm Sep 06 00:22:08 volumio volumio[983]: info: Exploding uri spotify:track:46m1Ukc5okavdP4ZCA3cpa in service spop Sep 06 00:22:08 volumio volumio[983]: SPOTIFY: EXPLODING URI:spotify:track:46m1Ukc5okavdP4ZCA3cpa Sep 06 00:22:08 volumio volumio[983]: info: Exploding uri spotify:track:1erhVgJZqDcxG91DorrPnV in service spop Sep 06 00:22:08 volumio volumio[983]: SPOTIFY: EXPLODING URI:spotify:track:1erhVgJZqDcxG91DorrPnV Sep 06 00:22:08 volumio volumio[983]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3twFQfFRk4L7pCDQhPgkZL","service":"spop","name":"Piano Concerto No. 2 In G Minor, Op. 16: 2. Scherzo (Vivace)","artist":"Sergei Prokofiev","album":"Prokofiev: Piano Concerto No. 2 in G minor, Op.16, Ravel: Piano Concerto in G major","type":"song","duration":137,"albumart":"https://i.scdn.co/image/ab67616d0000b2738ae86d4dde1a6a34cee0cb72","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 00:22:08 volumio volumio[983]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5gwKOGdsJrXRihS4YNafpp","service":"spop","name":"Piano Concerto No. 2 in G minor, Op. 16: 1. Andantino","artist":"Sergei Prokofiev","album":"Prokofiev: Piano Concerto No. 2 in G minor, Op.16, Ravel: Piano Concerto in G major","type":"song","duration":671,"albumart":"https://i.scdn.co/image/ab67616d0000b2738ae86d4dde1a6a34cee0cb72","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 00:22:08 volumio volumio[983]: info: Exploding uri spotify:track:01TR3fIvV0Ny06qcVdozQF in service spop Sep 06 00:22:08 volumio volumio[983]: SPOTIFY: EXPLODING URI:spotify:track:01TR3fIvV0Ny06qcVdozQF Sep 06 00:22:08 volumio volumio[983]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:44Resf6o7RZsOky2sDpRKm","service":"spop","name":"Piano Concerto No. 2 in G minor, Op. 16: 3. Intermezzo (Allegro moderato)","artist":"Sergei Prokofiev","album":"Prokofiev: Piano Concerto No. 2 in G minor, Op.16, Ravel: Piano Concerto in G major","type":"song","duration":341,"albumart":"https://i.scdn.co/image/ab67616d0000b2738ae86d4dde1a6a34cee0cb72","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 00:22:08 volumio volumio[983]: info: Exploding uri spotify:track:3bBl5fYTIClDbM3RPucsvF in service spop Sep 06 00:22:08 volumio volumio[983]: SPOTIFY: EXPLODING URI:spotify:track:3bBl5fYTIClDbM3RPucsvF Sep 06 00:22:09 volumio volumio[983]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:46m1Ukc5okavdP4ZCA3cpa","service":"spop","name":"Piano Concerto No. 2 in G minor, Op. 16: 4. Finale (Allegro tempestoso)","artist":"Sergei Prokofiev","album":"Prokofiev: Piano Concerto No. 2 in G minor, Op.16, Ravel: Piano Concerto in G major","type":"song","duration":662,"albumart":"https://i.scdn.co/image/ab67616d0000b2738ae86d4dde1a6a34cee0cb72","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 00:22:09 volumio volumio[983]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:01TR3fIvV0Ny06qcVdozQF","service":"spop","name":"Piano Concerto in G Major, M. 83: 2. Adagio assai","artist":"Maurice Ravel","album":"Prokofiev: Piano Concerto No. 2 in G minor, Op.16, Ravel: Piano Concerto in G major","type":"song","duration":521,"albumart":"https://i.scdn.co/image/ab67616d0000b2738ae86d4dde1a6a34cee0cb72","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 00:22:09 volumio volumio[983]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1erhVgJZqDcxG91DorrPnV","service":"spop","name":"Piano Concerto in G Major, M. 83: 1. Allegramente","artist":"Maurice Ravel","album":"Prokofiev: Piano Concerto No. 2 in G minor, Op.16, Ravel: Piano Concerto in G major","type":"song","duration":495,"albumart":"https://i.scdn.co/image/ab67616d0000b2738ae86d4dde1a6a34cee0cb72","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 00:22:09 volumio volumio[983]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3bBl5fYTIClDbM3RPucsvF","service":"spop","name":"Piano Concerto in G Major, M. 83: 3. Presto","artist":"Maurice Ravel","album":"Prokofiev: Piano Concerto No. 2 in G minor, Op.16, Ravel: Piano Concerto in G major","type":"song","duration":232,"albumart":"https://i.scdn.co/image/ab67616d0000b2738ae86d4dde1a6a34cee0cb72","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 00:22:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:22:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Sep 06 00:22:09 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:22:09 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:22:09 volumio go-librespot[2378]: Librespot-go daemon starting... Sep 06 00:22:09 volumio go-librespot[2378]: time="2024-09-06T00:22:09Z" level=info msg="generated new device id: 7bb48a3fd9efe3e19869d07f2ad49d28523817ac" Sep 06 00:22:09 volumio go-librespot[2378]: time="2024-09-06T00:22:09Z" level=debug msg="stored credentials not found" Sep 06 00:22:09 volumio go-librespot[2378]: time="2024-09-06T00:22:09Z" 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]" Sep 06 00:22:09 volumio go-librespot[2378]: time="2024-09-06T00:22:09Z" 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]" Sep 06 00:22:09 volumio go-librespot[2378]: time="2024-09-06T00:22:09Z" 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]" Sep 06 00:22:09 volumio go-librespot[2378]: time="2024-09-06T00:22:09Z" level=debug msg="zeroconf server listening on port 41549" Sep 06 00:22:09 volumio go-librespot[2378]: time="2024-09-06T00:22:09Z" level=debug msg="obtained new client token: AAB0OZm8eRsP9JsT+Z5465YuXBM4Wgj9Qb/YrV+/SOKTaFwucPVbs4PYn1RdMnDLBl5R08vD0NhzIjV/li9cS/YtSfeekECbx0OrkiIY/jMdqfio6MXiR5+aePzqR77aTrgydUM5l66xCd/qvIlvKMzAjojXYQLaEMWf++GeB1pr9w+OHMXpTOpIAvwx9FxG3DMEtaiyHarbbQWW45Yq+h8dz4aZCJPxSbr9vj/C+JUwqz/ZYAJe8NQc" Sep 06 00:22:09 volumio go-librespot[2378]: time="2024-09-06T00:22:09Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:22:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:22:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:22:10 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:22:10 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:11 volumio ntpd[787]: Soliciting pool server 2803:bc40:8160::3 Sep 06 00:22:11 volumio volumio[983]: info: Preload queue cleared Sep 06 00:22:11 volumio volumio[983]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 06 00:22:11 volumio volumio[983]: info: CoreStateMachine::ClearQueue Sep 06 00:22:11 volumio volumio[983]: info: CoreStateMachine::stop Sep 06 00:22:11 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:11 volumio volumio[983]: info: CorePlayQueue::clearPlayQueue Sep 06 00:22:11 volumio volumio[983]: info: CorePlayQueue::saveQueue Sep 06 00:22:11 volumio volumio[983]: info: CoreCommandRouter::volumioPushQueue Sep 06 00:22:11 volumio volumio[983]: info: CoreStateMachine::addQueueItems Sep 06 00:22:11 volumio volumio[983]: info: CorePlayQueue::addQueueItems Sep 06 00:22:11 volumio volumio[983]: info: Preload queue cleared Sep 06 00:22:11 volumio volumio[983]: info: Adding Item to queue: spotify:album:0fDQ95grFC5hOYKrcPSUWw Sep 06 00:22:11 volumio volumio[983]: info: Using cached record of: spotify:album:0fDQ95grFC5hOYKrcPSUWw Sep 06 00:22:11 volumio volumio[983]: info: CoreCommandRouter::volumioPushQueue Sep 06 00:22:11 volumio volumio[983]: info: CorePlayQueue::saveQueue Sep 06 00:22:11 volumio volumio[983]: info: CoreStateMachine::updateTrackBlock Sep 06 00:22:11 volumio volumio[983]: info: CorePlayQueue::getTrackBlock Sep 06 00:22:11 volumio volumio[983]: info: CoreCommandRouter::volumioPlay Sep 06 00:22:11 volumio volumio[983]: info: CoreStateMachine::play index 0 Sep 06 00:22:11 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:11 volumio volumio[983]: info: CoreStateMachine::stop Sep 06 00:22:11 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:11 volumio volumio[983]: info: CoreStateMachine::play index undefined Sep 06 00:22:11 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:11 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Sep 06 00:22:11 volumio volumio[983]: info: CoreStateMachine::startPlaybackTimer Sep 06 00:22:11 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Sep 06 00:22:11 volumio volumio[983]: info: [1725582131223] ControllerSpotify::clearAddPlayTrack Sep 06 00:22:11 volumio volumio[983]: info: Sending Spotify command with payload to local API: /player/play Sep 06 00:22:11 volumio volumio[983]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:12 volumio volumio[983]: info: Preload queue cleared Sep 06 00:22:12 volumio volumio[983]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 06 00:22:12 volumio volumio[983]: info: CoreStateMachine::ClearQueue Sep 06 00:22:12 volumio volumio[983]: info: CoreStateMachine::stop Sep 06 00:22:12 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:12 volumio volumio[983]: info: CorePlayQueue::clearPlayQueue Sep 06 00:22:12 volumio volumio[983]: info: CorePlayQueue::saveQueue Sep 06 00:22:12 volumio volumio[983]: info: CoreCommandRouter::volumioPushQueue Sep 06 00:22:12 volumio volumio[983]: info: CoreStateMachine::addQueueItems Sep 06 00:22:12 volumio volumio[983]: info: CorePlayQueue::addQueueItems Sep 06 00:22:12 volumio volumio[983]: info: Preload queue cleared Sep 06 00:22:12 volumio volumio[983]: info: Adding Item to queue: spotify:album:0fDQ95grFC5hOYKrcPSUWw Sep 06 00:22:12 volumio volumio[983]: info: Using cached record of: spotify:album:0fDQ95grFC5hOYKrcPSUWw Sep 06 00:22:12 volumio volumio[983]: info: CoreCommandRouter::volumioPushQueue Sep 06 00:22:12 volumio volumio[983]: info: CorePlayQueue::saveQueue Sep 06 00:22:12 volumio volumio[983]: info: CoreStateMachine::updateTrackBlock Sep 06 00:22:12 volumio volumio[983]: info: CorePlayQueue::getTrackBlock Sep 06 00:22:12 volumio volumio[983]: info: CoreCommandRouter::volumioPlay Sep 06 00:22:12 volumio volumio[983]: info: CoreStateMachine::play index 0 Sep 06 00:22:12 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:12 volumio volumio[983]: info: CoreStateMachine::stop Sep 06 00:22:12 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:12 volumio volumio[983]: info: CoreStateMachine::play index undefined Sep 06 00:22:12 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:12 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Sep 06 00:22:12 volumio volumio[983]: info: CoreStateMachine::startPlaybackTimer Sep 06 00:22:12 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Sep 06 00:22:12 volumio volumio[983]: info: [1725582132111] ControllerSpotify::clearAddPlayTrack Sep 06 00:22:12 volumio volumio[983]: info: Sending Spotify command with payload to local API: /player/play Sep 06 00:22:12 volumio volumio[983]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:22:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Sep 06 00:22:13 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:22:13 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:22:13 volumio go-librespot[2386]: Librespot-go daemon starting... Sep 06 00:22:13 volumio go-librespot[2386]: time="2024-09-06T00:22:13Z" level=info msg="generated new device id: f7ebd1369851dfaddeddf957752bd0ec28c0ba10" Sep 06 00:22:13 volumio go-librespot[2386]: time="2024-09-06T00:22:13Z" level=debug msg="stored credentials not found" Sep 06 00:22:13 volumio go-librespot[2386]: time="2024-09-06T00:22:13Z" 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]" Sep 06 00:22:13 volumio go-librespot[2386]: time="2024-09-06T00:22:13Z" 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]" Sep 06 00:22:13 volumio go-librespot[2386]: time="2024-09-06T00:22:13Z" 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]" Sep 06 00:22:13 volumio go-librespot[2386]: time="2024-09-06T00:22:13Z" level=debug msg="zeroconf server listening on port 39195" Sep 06 00:22:13 volumio go-librespot[2386]: time="2024-09-06T00:22:13Z" level=debug msg="obtained new client token: AAC+xUspW3C2ruiYTmBlo4zQOJYT28l1aUAhODUTad7He4s7bKYFrgZxoF2eh1pE+sNwkS+1J68EhZQYxPfg9QU/lJhbI5sPMvFI95kvJ1xg9pny0KpnBu0w1SdaQXOXfEzYgWVmo99xIyIJC0JBGfyz0g8s5Wu3a3gts5OfZz293jDMExUFgCSHTiiqM81Oin5OSia0P96Ppn2LQhV/LDazP9tjZd5fH0UAQXnQVttHIlPtW21d35ks" Sep 06 00:22:13 volumio go-librespot[2386]: time="2024-09-06T00:22:13Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:22:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:22:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:22:13 volumio volumio[983]: info: Preload queue cleared Sep 06 00:22:13 volumio volumio[983]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 06 00:22:13 volumio volumio[983]: info: CoreStateMachine::ClearQueue Sep 06 00:22:13 volumio volumio[983]: info: CoreStateMachine::stop Sep 06 00:22:13 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:13 volumio volumio[983]: info: CorePlayQueue::clearPlayQueue Sep 06 00:22:13 volumio volumio[983]: info: CorePlayQueue::saveQueue Sep 06 00:22:13 volumio volumio[983]: info: CoreCommandRouter::volumioPushQueue Sep 06 00:22:13 volumio volumio[983]: info: CoreStateMachine::addQueueItems Sep 06 00:22:13 volumio volumio[983]: info: CorePlayQueue::addQueueItems Sep 06 00:22:13 volumio volumio[983]: info: Preload queue cleared Sep 06 00:22:13 volumio volumio[983]: info: Adding Item to queue: spotify:album:0fDQ95grFC5hOYKrcPSUWw Sep 06 00:22:13 volumio volumio[983]: info: Using cached record of: spotify:album:0fDQ95grFC5hOYKrcPSUWw Sep 06 00:22:13 volumio volumio[983]: info: CoreCommandRouter::volumioPushQueue Sep 06 00:22:13 volumio volumio[983]: info: CorePlayQueue::saveQueue Sep 06 00:22:13 volumio volumio[983]: info: CoreStateMachine::updateTrackBlock Sep 06 00:22:13 volumio volumio[983]: info: CorePlayQueue::getTrackBlock Sep 06 00:22:13 volumio volumio[983]: info: CoreCommandRouter::volumioPlay Sep 06 00:22:13 volumio volumio[983]: info: CoreStateMachine::play index 0 Sep 06 00:22:13 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:13 volumio volumio[983]: info: CoreStateMachine::stop Sep 06 00:22:13 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:13 volumio volumio[983]: info: CoreStateMachine::play index undefined Sep 06 00:22:13 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:13 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Sep 06 00:22:13 volumio volumio[983]: info: CoreStateMachine::startPlaybackTimer Sep 06 00:22:13 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Sep 06 00:22:13 volumio volumio[983]: info: [1725582133696] ControllerSpotify::clearAddPlayTrack Sep 06 00:22:13 volumio volumio[983]: info: Sending Spotify command with payload to local API: /player/play Sep 06 00:22:13 volumio volumio[983]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:13 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:22:13 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:13 volumio volumio[983]: info: Preload queue cleared Sep 06 00:22:13 volumio volumio[983]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 06 00:22:13 volumio volumio[983]: info: CoreStateMachine::ClearQueue Sep 06 00:22:13 volumio volumio[983]: info: CoreStateMachine::stop Sep 06 00:22:13 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:13 volumio volumio[983]: info: CorePlayQueue::clearPlayQueue Sep 06 00:22:13 volumio volumio[983]: info: CorePlayQueue::saveQueue Sep 06 00:22:13 volumio volumio[983]: info: CoreCommandRouter::volumioPushQueue Sep 06 00:22:13 volumio volumio[983]: info: CoreStateMachine::addQueueItems Sep 06 00:22:13 volumio volumio[983]: info: CorePlayQueue::addQueueItems Sep 06 00:22:13 volumio volumio[983]: info: Preload queue cleared Sep 06 00:22:13 volumio volumio[983]: info: Adding Item to queue: spotify:album:0fDQ95grFC5hOYKrcPSUWw Sep 06 00:22:13 volumio volumio[983]: info: Using cached record of: spotify:album:0fDQ95grFC5hOYKrcPSUWw Sep 06 00:22:13 volumio volumio[983]: info: CoreCommandRouter::volumioPushQueue Sep 06 00:22:13 volumio volumio[983]: info: CorePlayQueue::saveQueue Sep 06 00:22:13 volumio volumio[983]: info: CoreStateMachine::updateTrackBlock Sep 06 00:22:13 volumio volumio[983]: info: CorePlayQueue::getTrackBlock Sep 06 00:22:13 volumio volumio[983]: info: CoreCommandRouter::volumioPlay Sep 06 00:22:13 volumio volumio[983]: info: CoreStateMachine::play index 0 Sep 06 00:22:13 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:13 volumio volumio[983]: info: CoreStateMachine::stop Sep 06 00:22:13 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:13 volumio volumio[983]: info: CoreStateMachine::play index undefined Sep 06 00:22:13 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:13 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Sep 06 00:22:13 volumio volumio[983]: info: CoreStateMachine::startPlaybackTimer Sep 06 00:22:13 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Sep 06 00:22:13 volumio volumio[983]: info: [1725582133968] ControllerSpotify::clearAddPlayTrack Sep 06 00:22:13 volumio volumio[983]: info: Sending Spotify command with payload to local API: /player/play Sep 06 00:22:13 volumio volumio[983]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:14 volumio volumio[983]: info: Preload queue cleared Sep 06 00:22:14 volumio volumio[983]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::ClearQueue Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::stop Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:14 volumio volumio[983]: info: CorePlayQueue::clearPlayQueue Sep 06 00:22:14 volumio volumio[983]: info: CorePlayQueue::saveQueue Sep 06 00:22:14 volumio volumio[983]: info: CoreCommandRouter::volumioPushQueue Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::addQueueItems Sep 06 00:22:14 volumio volumio[983]: info: CorePlayQueue::addQueueItems Sep 06 00:22:14 volumio volumio[983]: info: Preload queue cleared Sep 06 00:22:14 volumio volumio[983]: info: Adding Item to queue: spotify:album:0fDQ95grFC5hOYKrcPSUWw Sep 06 00:22:14 volumio volumio[983]: info: Using cached record of: spotify:album:0fDQ95grFC5hOYKrcPSUWw Sep 06 00:22:14 volumio volumio[983]: info: CoreCommandRouter::volumioPushQueue Sep 06 00:22:14 volumio volumio[983]: info: CorePlayQueue::saveQueue Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::updateTrackBlock Sep 06 00:22:14 volumio volumio[983]: info: CorePlayQueue::getTrackBlock Sep 06 00:22:14 volumio volumio[983]: info: CoreCommandRouter::volumioPlay Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::play index 0 Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::stop Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::play index undefined Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:14 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::startPlaybackTimer Sep 06 00:22:14 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Sep 06 00:22:14 volumio volumio[983]: info: [1725582134256] ControllerSpotify::clearAddPlayTrack Sep 06 00:22:14 volumio volumio[983]: info: Sending Spotify command with payload to local API: /player/play Sep 06 00:22:14 volumio volumio[983]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:14 volumio volumio[983]: info: Preload queue cleared Sep 06 00:22:14 volumio volumio[983]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::ClearQueue Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::stop Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:14 volumio volumio[983]: info: CorePlayQueue::clearPlayQueue Sep 06 00:22:14 volumio volumio[983]: info: CorePlayQueue::saveQueue Sep 06 00:22:14 volumio volumio[983]: info: CoreCommandRouter::volumioPushQueue Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::addQueueItems Sep 06 00:22:14 volumio volumio[983]: info: CorePlayQueue::addQueueItems Sep 06 00:22:14 volumio volumio[983]: info: Preload queue cleared Sep 06 00:22:14 volumio volumio[983]: info: Adding Item to queue: spotify:album:0fDQ95grFC5hOYKrcPSUWw Sep 06 00:22:14 volumio volumio[983]: info: Using cached record of: spotify:album:0fDQ95grFC5hOYKrcPSUWw Sep 06 00:22:14 volumio volumio[983]: info: CoreCommandRouter::volumioPushQueue Sep 06 00:22:14 volumio volumio[983]: info: CorePlayQueue::saveQueue Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::updateTrackBlock Sep 06 00:22:14 volumio volumio[983]: info: CorePlayQueue::getTrackBlock Sep 06 00:22:14 volumio volumio[983]: info: CoreCommandRouter::volumioPlay Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::play index 0 Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::stop Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::play index undefined Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:14 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::startPlaybackTimer Sep 06 00:22:14 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Sep 06 00:22:14 volumio volumio[983]: info: [1725582134521] ControllerSpotify::clearAddPlayTrack Sep 06 00:22:14 volumio volumio[983]: info: Sending Spotify command with payload to local API: /player/play Sep 06 00:22:14 volumio volumio[983]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:14 volumio volumio[983]: info: Preload queue cleared Sep 06 00:22:14 volumio volumio[983]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::ClearQueue Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::stop Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:14 volumio volumio[983]: info: CorePlayQueue::clearPlayQueue Sep 06 00:22:14 volumio volumio[983]: info: CorePlayQueue::saveQueue Sep 06 00:22:14 volumio volumio[983]: info: CoreCommandRouter::volumioPushQueue Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::addQueueItems Sep 06 00:22:14 volumio volumio[983]: info: CorePlayQueue::addQueueItems Sep 06 00:22:14 volumio volumio[983]: info: Preload queue cleared Sep 06 00:22:14 volumio volumio[983]: info: Adding Item to queue: spotify:album:0fDQ95grFC5hOYKrcPSUWw Sep 06 00:22:14 volumio volumio[983]: info: Using cached record of: spotify:album:0fDQ95grFC5hOYKrcPSUWw Sep 06 00:22:14 volumio volumio[983]: info: CoreCommandRouter::volumioPushQueue Sep 06 00:22:14 volumio volumio[983]: info: CorePlayQueue::saveQueue Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::updateTrackBlock Sep 06 00:22:14 volumio volumio[983]: info: CorePlayQueue::getTrackBlock Sep 06 00:22:14 volumio volumio[983]: info: CoreCommandRouter::volumioPlay Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::play index 0 Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::stop Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::play index undefined Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 00:22:14 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Sep 06 00:22:14 volumio volumio[983]: info: CoreStateMachine::startPlaybackTimer Sep 06 00:22:14 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Sep 06 00:22:14 volumio volumio[983]: info: [1725582134798] ControllerSpotify::clearAddPlayTrack Sep 06 00:22:14 volumio volumio[983]: info: Sending Spotify command with payload to local API: /player/play Sep 06 00:22:14 volumio volumio[983]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:22:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Sep 06 00:22:16 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:22:16 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:22:16 volumio go-librespot[2395]: Librespot-go daemon starting... Sep 06 00:22:16 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:22:16 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:16 volumio go-librespot[2395]: time="2024-09-06T00:22:16Z" level=info msg="generated new device id: 1f6a92e54a68e295aa2cf9c898924c23573b7723" Sep 06 00:22:16 volumio go-librespot[2395]: time="2024-09-06T00:22:16Z" level=debug msg="stored credentials not found" Sep 06 00:22:17 volumio go-librespot[2395]: time="2024-09-06T00:22:17Z" 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]" Sep 06 00:22:17 volumio go-librespot[2395]: time="2024-09-06T00:22:17Z" 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]" Sep 06 00:22:17 volumio go-librespot[2395]: time="2024-09-06T00:22:17Z" 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]" Sep 06 00:22:17 volumio go-librespot[2395]: time="2024-09-06T00:22:17Z" level=debug msg="zeroconf server listening on port 40855" Sep 06 00:22:17 volumio go-librespot[2395]: time="2024-09-06T00:22:17Z" level=debug msg="obtained new client token: AAD69LhbFy5M/EJpp3aqMZ8vPa8zeAf/O4hI1eFhBlDNgrYp8jolRji/DY0INM2j61VFsaNAozTwxBTG7zPX8AdbTnU7HY+7p76jFInExxRjiVhxdLc2G9yAsnRNYneW5QxF9TH7j4TwyhYsKMSNb75nS1Y8HrO6TbwVk1eRqDnl+sfpLaBn+ktiVLjtaoxkWdjl/deAO4amdg/Lk+RG4FLByEQ8p6LVG3uLGg3V4OpoMK798/CCbQ==" Sep 06 00:22:17 volumio go-librespot[2395]: time="2024-09-06T00:22:17Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:22:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:22:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:22:19 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:22:19 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:22:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Sep 06 00:22:20 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:22:20 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:22:20 volumio go-librespot[2403]: Librespot-go daemon starting... Sep 06 00:22:20 volumio go-librespot[2403]: time="2024-09-06T00:22:20Z" level=info msg="generated new device id: 4cefdf3342c28e00ec99e54d73905ce44a1d6412" Sep 06 00:22:20 volumio go-librespot[2403]: time="2024-09-06T00:22:20Z" level=debug msg="stored credentials not found" Sep 06 00:22:20 volumio go-librespot[2403]: time="2024-09-06T00:22:20Z" 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]" Sep 06 00:22:20 volumio go-librespot[2403]: time="2024-09-06T00:22:20Z" 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]" Sep 06 00:22:20 volumio go-librespot[2403]: time="2024-09-06T00:22:20Z" 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]" Sep 06 00:22:20 volumio go-librespot[2403]: time="2024-09-06T00:22:20Z" level=debug msg="zeroconf server listening on port 44395" Sep 06 00:22:20 volumio go-librespot[2403]: time="2024-09-06T00:22:20Z" level=debug msg="obtained new client token: AABZlEvpYNE+0FVVP8kf0Yf2esUTVqFD6HDjMyRSCHCs6yiHe1E7btUYCWibGQTqr98UKqWwvLCCUQ7O3CZyMHV+JLi1H8XB/e3jc0BcgaE+zgwffvj7DHNCrXPpH7HiDyZMIXbOZkT0pya3XcP1N+ZVN4hvrFXAVmzXRKTmOYCPNLkXGAOBmjXswbfzMc0BWG2lj3a13ygisZhybcymB7O6qfCYfN+yCBAwLVT3/4DRAEpmcV03mV4Y" Sep 06 00:22:21 volumio go-librespot[2403]: time="2024-09-06T00:22:21Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:22:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:22:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:22:22 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:22:22 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:22:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Sep 06 00:22:24 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:22:24 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:22:24 volumio go-librespot[2411]: Librespot-go daemon starting... Sep 06 00:22:24 volumio go-librespot[2411]: time="2024-09-06T00:22:24Z" level=info msg="generated new device id: e8d29e7a69b2203540454ad49a3825cd4a37bc84" Sep 06 00:22:24 volumio go-librespot[2411]: time="2024-09-06T00:22:24Z" level=debug msg="stored credentials not found" Sep 06 00:22:24 volumio go-librespot[2411]: time="2024-09-06T00:22:24Z" 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]" Sep 06 00:22:24 volumio go-librespot[2411]: time="2024-09-06T00:22:24Z" 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]" Sep 06 00:22:24 volumio go-librespot[2411]: time="2024-09-06T00:22:24Z" 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]" Sep 06 00:22:24 volumio go-librespot[2411]: time="2024-09-06T00:22:24Z" level=debug msg="zeroconf server listening on port 40445" Sep 06 00:22:24 volumio go-librespot[2411]: time="2024-09-06T00:22:24Z" level=debug msg="obtained new client token: AAA+YquUKjmxduqlORbb/B5q+u1aJIOYdWMlu2mQ45P+obk8pCSF0VpCXkJo6ku5dq5XQdRJM2nrcL2Y2LZRmOTDoLEU3ZXVXm8Lw9lQd8PlVY2Fc4jSUyHHtpyEU0dmUvzJDS3p1+hAeN0gebOpcKShuCszdDOnSAZ8HvpPB1FPs0HPutnvIZ7tHaC8MrQ/zOXjEcqZ4+HYKQrH3Z5Ou2iZ8em8YuZf7umtaiBNa4WreUIwaHSr2m2Y" Sep 06 00:22:24 volumio go-librespot[2411]: time="2024-09-06T00:22:24Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:22:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:22:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:22:25 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:22:25 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:28 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:22:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Sep 06 00:22:28 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:22:28 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:22:28 volumio go-librespot[2421]: Librespot-go daemon starting... Sep 06 00:22:28 volumio go-librespot[2421]: time="2024-09-06T00:22:28Z" level=info msg="generated new device id: b717bf12d6f99194143375cdb6f14604c7bc36bd" Sep 06 00:22:28 volumio go-librespot[2421]: time="2024-09-06T00:22:28Z" level=debug msg="stored credentials not found" Sep 06 00:22:28 volumio go-librespot[2421]: time="2024-09-06T00:22:28Z" 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]" Sep 06 00:22:28 volumio go-librespot[2421]: time="2024-09-06T00:22:28Z" 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]" Sep 06 00:22:28 volumio go-librespot[2421]: time="2024-09-06T00:22:28Z" 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]" Sep 06 00:22:28 volumio go-librespot[2421]: time="2024-09-06T00:22:28Z" level=debug msg="zeroconf server listening on port 35773" Sep 06 00:22:28 volumio go-librespot[2421]: time="2024-09-06T00:22:28Z" level=debug msg="obtained new client token: AAC4u6p333NGzvzqublBQHm9qub0gwt8FocFkGmoW2d6x9T6S1bRognb7q/Vy3PHVhbXM6ci84H/tWqoHdJw95/c5Ri9N+tpTpLlVPCHYgZvuxPT3iIxqKkwx/UtndldzodP2OAxPwxdYVX6ESTxvK1b669Pn+9JXjDhwEu5iD32uMiqWjLwncz6jkgtJuvHC03PQQHqpzzIpic36oEdGDr+TON65M656w0CKxfvZLtWIF9f3V+7Vts5" Sep 06 00:22:28 volumio go-librespot[2421]: time="2024-09-06T00:22:28Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:22:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:22:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:22:28 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:22:28 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:30 volumio volumio[983]: info: Tunnel connection is inactive, restarting it Sep 06 00:22:30 volumio volumio[983]: info: Starting Tunnel 1 Sep 06 00:22:30 volumio volumio[983]: info: Starting Tunnel Connection Checker Sep 06 00:22:30 volumio sudo[2436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Sep 06 00:22:30 volumio sudo[2436]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 00:22:30 volumio autossh[1917]: received signal to exit (15) Sep 06 00:22:30 volumio systemd[1]: Stopping MyVolumio SSH Tunnel... Sep 06 00:22:30 volumio systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Sep 06 00:22:30 volumio systemd[1]: sshtunnel.service: Succeeded. Sep 06 00:22:30 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Sep 06 00:22:30 volumio systemd[1]: Started MyVolumio SSH Tunnel. Sep 06 00:22:30 volumio sudo[2436]: pam_unix(sudo:session): session closed for user root Sep 06 00:22:30 volumio volumio[983]: info: Remote SSH Started Sep 06 00:22:30 volumio autossh[2440]: port set to 0, monitoring disabled Sep 06 00:22:30 volumio autossh[2440]: starting ssh (count 1) Sep 06 00:22:30 volumio autossh[2440]: ssh child pid is 2443 Sep 06 00:22:30 volumio volumio[983]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Sep 06 00:22:30 volumio volumio[983]: info: CoreCommandRouter::volumioGetState Sep 06 00:22:30 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Sep 06 00:22:30 volumio volumiossh-tunnel[2438]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Sep 06 00:22:31 volumio volumiossh-tunnel[2438]: Warning: remote port forwarding failed for listen port 8091 Sep 06 00:22:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:22:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Sep 06 00:22:31 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:22:31 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:22:31 volumio go-librespot[2444]: Librespot-go daemon starting... Sep 06 00:22:31 volumio go-librespot[2444]: time="2024-09-06T00:22:31Z" level=info msg="generated new device id: a3d16ad705108a4dc630d83a3c710cc9c6a661d7" Sep 06 00:22:31 volumio go-librespot[2444]: time="2024-09-06T00:22:31Z" level=debug msg="stored credentials not found" Sep 06 00:22:31 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:22:31 volumio go-librespot[2444]: time="2024-09-06T00:22:31Z" level=debug msg="new websocket client" Sep 06 00:22:31 volumio volumio[983]: info: Connection to go-librespot Websocket established Sep 06 00:22:32 volumio go-librespot[2444]: time="2024-09-06T00:22:32Z" 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]" Sep 06 00:22:32 volumio go-librespot[2444]: time="2024-09-06T00:22:32Z" 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]" Sep 06 00:22:32 volumio go-librespot[2444]: time="2024-09-06T00:22:32Z" 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]" Sep 06 00:22:32 volumio go-librespot[2444]: time="2024-09-06T00:22:32Z" level=debug msg="zeroconf server listening on port 35311" Sep 06 00:22:32 volumio go-librespot[2444]: time="2024-09-06T00:22:32Z" level=debug msg="obtained new client token: AADa+ougQ2/cE1iE/G+7Q8ahuUmTL4xyyrkmsYM+DOTmCl8tJZI+/h3L8rxfj9U9HkU3f1epQwUl1yUO43DnlUcZOmcZfPsRlMtYuY6r4otx639VtnjVwT+8c+nYO7sQMzT2pD/7k+uwv8GMIeR/fkU8Q3mEmdsXFY9ePra/zkCgYo4aB72vfpBBGA2LF5D4gZoZ7vgUez27RDkL5oZQxZnSXw5fUtbIkylQNyJ/UQUxU3yJ3ZATeA==" Sep 06 00:22:32 volumio go-librespot[2444]: time="2024-09-06T00:22:32Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:22:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:22:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:22:32 volumio volumio[983]: info: Connection to go-librespot Websocket closed Sep 06 00:22:34 volumio volumio[983]: info: Getting Spotify volume Sep 06 00:22:34 volumio volumio[983]: (node:983) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:34 volumio volumio[983]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Sep 06 00:22:34 volumio volumio[983]: (node:983) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 8) Sep 06 00:22:34 volumio volumio[983]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Sep 06 00:22:34 volumio volumio[983]: info: CoreCommandRouter::volumioGetState Sep 06 00:22:34 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Sep 06 00:22:35 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:22:35 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:35 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:22:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Sep 06 00:22:35 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:22:35 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:22:35 volumio go-librespot[2457]: Librespot-go daemon starting... Sep 06 00:22:35 volumio go-librespot[2457]: time="2024-09-06T00:22:35Z" level=info msg="generated new device id: 5b4b66777326205cb0c93c3ea489e73e8c323590" Sep 06 00:22:35 volumio go-librespot[2457]: time="2024-09-06T00:22:35Z" level=debug msg="stored credentials not found" Sep 06 00:22:35 volumio go-librespot[2457]: time="2024-09-06T00:22:35Z" 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]" Sep 06 00:22:35 volumio go-librespot[2457]: time="2024-09-06T00:22:35Z" 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]" Sep 06 00:22:35 volumio go-librespot[2457]: time="2024-09-06T00:22:35Z" 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]" Sep 06 00:22:35 volumio go-librespot[2457]: time="2024-09-06T00:22:35Z" level=debug msg="zeroconf server listening on port 44673" Sep 06 00:22:36 volumio go-librespot[2457]: time="2024-09-06T00:22:36Z" level=debug msg="obtained new client token: AABAji/vjBiWVRqBNai0D4bkDAUyuKP54+JB9j6qJzczJpAA4PrGDZBUUDdC/lnG/kcdZQEGIdCEl5kOO0EEoDNwyjNCZcBOyIVTQ52GyzhBj3O1mRcX0IVN/VGRTKndJO7CxlHL1ClWarbP0G6GG8mREUCH6NolhAeLHCjWN3RgEH9kS70WVkL0YGsb3IEP8wMq3/J/9o0/h+YfZdLk8U7G+PMnGiTKoxCVSiGKFmYJ8Tzy8xKDqw==" Sep 06 00:22:36 volumio go-librespot[2457]: time="2024-09-06T00:22:36Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:22:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:22:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:22:38 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:22:38 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:22:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Sep 06 00:22:39 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:22:39 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:22:39 volumio go-librespot[2465]: Librespot-go daemon starting... Sep 06 00:22:39 volumio go-librespot[2465]: time="2024-09-06T00:22:39Z" level=info msg="generated new device id: a683a7b3ca264328345e29944e06f68f74cc0700" Sep 06 00:22:39 volumio go-librespot[2465]: time="2024-09-06T00:22:39Z" level=debug msg="stored credentials not found" Sep 06 00:22:39 volumio go-librespot[2465]: time="2024-09-06T00:22:39Z" 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]" Sep 06 00:22:39 volumio go-librespot[2465]: time="2024-09-06T00:22:39Z" 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]" Sep 06 00:22:39 volumio go-librespot[2465]: time="2024-09-06T00:22:39Z" 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]" Sep 06 00:22:39 volumio go-librespot[2465]: time="2024-09-06T00:22:39Z" level=debug msg="zeroconf server listening on port 33331" Sep 06 00:22:39 volumio go-librespot[2465]: time="2024-09-06T00:22:39Z" level=debug msg="obtained new client token: AAANdzILMXJYIIazhHy4JUUrXRXezy22OxIitsruRfr1HMLTppYvXAMMmCIk73XO0VWLDfOdc8zYR9ZK+EiO2MNXR3SiH0wqb5JEFjxEqJ1AWrYcVdgPDuiRSJMu6QvyY4uVxJj8keBK66Sy2N6SBBerrvfXFWwts+Nm7DqQWWbQxCUts0WVOFJkbtMXYH6tY8BUo5B4y1zNq2SOe7PkTaRz+y2qQMK4EHA0nDDy6xoiH6VcNHXTvq8d" Sep 06 00:22:39 volumio go-librespot[2465]: time="2024-09-06T00:22:39Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:22:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:22:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:22:41 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:22:41 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:43 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:22:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Sep 06 00:22:43 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:22:43 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:22:43 volumio go-librespot[2511]: Librespot-go daemon starting... Sep 06 00:22:43 volumio go-librespot[2511]: time="2024-09-06T00:22:43Z" level=info msg="generated new device id: c150f01bf7d9ad61533a7fa32229a4fc22f93f53" Sep 06 00:22:43 volumio go-librespot[2511]: time="2024-09-06T00:22:43Z" level=debug msg="stored credentials not found" Sep 06 00:22:43 volumio go-librespot[2511]: time="2024-09-06T00:22:43Z" 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]" Sep 06 00:22:43 volumio go-librespot[2511]: time="2024-09-06T00:22:43Z" 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]" Sep 06 00:22:43 volumio go-librespot[2511]: time="2024-09-06T00:22:43Z" 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]" Sep 06 00:22:43 volumio go-librespot[2511]: time="2024-09-06T00:22:43Z" level=debug msg="zeroconf server listening on port 36503" Sep 06 00:22:43 volumio go-librespot[2511]: time="2024-09-06T00:22:43Z" level=debug msg="obtained new client token: AABw7xGc3FknpiHoN6EmR5aV89unJ+JCLEkNzLnkz6+YzgVNdrvg+SlF1PKMkC2h4itpoVVco8XEfJcjSeZB6SjDFeZW0RtR4TCYhAHvx2+2ZWV8e6wLT4t1r9YCX4CLWfd4tScNDSdO32/EbPtmR2Zl5Q0J5Dv0dP1vXQNw6WxrJ0zAHRRUgEKNDw0/KmOAzlIz7O5Lr14pzvkGVtNtCdiZb493TLx8H8BpyuaXevaJ2ppj3ES2I8fK" Sep 06 00:22:43 volumio go-librespot[2511]: time="2024-09-06T00:22:43Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:22:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:22:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:22:44 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:22:44 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:22:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Sep 06 00:22:46 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:22:46 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:22:46 volumio go-librespot[2521]: Librespot-go daemon starting... Sep 06 00:22:46 volumio go-librespot[2521]: time="2024-09-06T00:22:46Z" level=info msg="generated new device id: 93ec5dc28274905070a868a04ed124e91fe675ae" Sep 06 00:22:46 volumio go-librespot[2521]: time="2024-09-06T00:22:46Z" level=debug msg="stored credentials not found" Sep 06 00:22:47 volumio go-librespot[2521]: time="2024-09-06T00:22:47Z" 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]" Sep 06 00:22:47 volumio go-librespot[2521]: time="2024-09-06T00:22:47Z" 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]" Sep 06 00:22:47 volumio go-librespot[2521]: time="2024-09-06T00:22:47Z" 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]" Sep 06 00:22:47 volumio go-librespot[2521]: time="2024-09-06T00:22:47Z" level=debug msg="zeroconf server listening on port 33139" Sep 06 00:22:47 volumio go-librespot[2521]: time="2024-09-06T00:22:47Z" level=debug msg="obtained new client token: AABAoSUxP9M21RK4L/jkHp8TydP09SCbM4YKotVH+bM7Iq3WhHfNwIPlHr9hJ97mI5mxGtsXJiQ0w1vIJ+GNpunxVz3o6AMS990rPjYsYShx72TmutDzP2ESFjAHzohlTOZBAn66SoCAW0fe8o8uaSj2CwCNTRJJYn6yM9jCysxUibrByA5v+7ycmA51mui5VDqB27nGz6XP0RbKzN+uq1PidyQ4jfRB/t5pXOGo6OQZw8IX1/16WQ==" Sep 06 00:22:47 volumio go-librespot[2521]: time="2024-09-06T00:22:47Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:22:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:22:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:22:47 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:22:47 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:50 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:22:50 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:22:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Sep 06 00:22:50 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:22:50 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:22:50 volumio go-librespot[2529]: Librespot-go daemon starting... Sep 06 00:22:50 volumio go-librespot[2529]: time="2024-09-06T00:22:50Z" level=info msg="generated new device id: 9757227d4e47fe484fb2d87ae906fb1b6ba6974b" Sep 06 00:22:50 volumio go-librespot[2529]: time="2024-09-06T00:22:50Z" level=debug msg="stored credentials not found" Sep 06 00:22:50 volumio go-librespot[2529]: time="2024-09-06T00:22:50Z" 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]" Sep 06 00:22:50 volumio go-librespot[2529]: time="2024-09-06T00:22:50Z" 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]" Sep 06 00:22:50 volumio go-librespot[2529]: time="2024-09-06T00:22:50Z" 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]" Sep 06 00:22:50 volumio go-librespot[2529]: time="2024-09-06T00:22:50Z" level=debug msg="zeroconf server listening on port 34247" Sep 06 00:22:51 volumio go-librespot[2529]: time="2024-09-06T00:22:51Z" level=debug msg="obtained new client token: AACjDeb2mUvmU4OfVB1YwfPrzxHPJ0E0LiznrJPOzH1Aax4fJgbCDX3z43SxTTMvOItDXCLs3i3xhYmUIsPgf+si4/u1QwXsuLhqCSqwh3KoN4wdZnpv6R7R1WdO8TB6ARqiWmJ201YgpCnVNyaeWHp00PrKk2ufD3q+bPvE224OKkltj3wCmQITyVzcw51JzovyU4tITeuAx7SjGs3dkRLx3G1vY7HJR4HYAQxig7KgSsDXKX6tdEdQ" Sep 06 00:22:51 volumio go-librespot[2529]: time="2024-09-06T00:22:51Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:22:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:22:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:22:53 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:22:53 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:54 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:22:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Sep 06 00:22:54 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:22:54 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:22:54 volumio go-librespot[2537]: Librespot-go daemon starting... Sep 06 00:22:54 volumio go-librespot[2537]: time="2024-09-06T00:22:54Z" level=info msg="generated new device id: 4c37dd9aa7afa26706ca303b66d1eaa8e2d92acc" Sep 06 00:22:54 volumio go-librespot[2537]: time="2024-09-06T00:22:54Z" level=debug msg="stored credentials not found" Sep 06 00:22:54 volumio go-librespot[2537]: time="2024-09-06T00:22:54Z" 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]" Sep 06 00:22:54 volumio go-librespot[2537]: time="2024-09-06T00:22:54Z" 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]" Sep 06 00:22:54 volumio go-librespot[2537]: time="2024-09-06T00:22:54Z" 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]" Sep 06 00:22:54 volumio go-librespot[2537]: time="2024-09-06T00:22:54Z" level=debug msg="zeroconf server listening on port 35373" Sep 06 00:22:54 volumio go-librespot[2537]: time="2024-09-06T00:22:54Z" level=debug msg="obtained new client token: AACBYGtVH95yA7RaQQ2a2UeEU3qrDjmPPAGDbG/R/RSqI+RaATEh8w1oRaYZre3ymlyHUwzsWeoP+0gHIRGxoViMVUW/BhXn4KdG4hUUn8rHBiwzGiONC5JlKUbsdvMWYxKJhKEqmUx6Lch/1c0pdkV+AIWFA6VzGoecJ5T/hGBFcrqx1nIX6kNS1TYDOUuJAFJIkADzFqj8jG37jRxJUCmTTmpnTHRHqyNA5GLdVPfuzVLKFPIlfRLA" Sep 06 00:22:54 volumio go-librespot[2537]: time="2024-09-06T00:22:54Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:22:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:22:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:22:56 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:22:56 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:22:58 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:22:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Sep 06 00:22:58 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:22:58 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:22:58 volumio go-librespot[2545]: Librespot-go daemon starting... Sep 06 00:22:58 volumio go-librespot[2545]: time="2024-09-06T00:22:58Z" level=info msg="generated new device id: ba2a7370d7f060748a245ac0f17801aec78e473c" Sep 06 00:22:58 volumio go-librespot[2545]: time="2024-09-06T00:22:58Z" level=debug msg="stored credentials not found" Sep 06 00:22:58 volumio go-librespot[2545]: time="2024-09-06T00:22:58Z" 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]" Sep 06 00:22:58 volumio go-librespot[2545]: time="2024-09-06T00:22:58Z" 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]" Sep 06 00:22:58 volumio go-librespot[2545]: time="2024-09-06T00:22:58Z" 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]" Sep 06 00:22:58 volumio go-librespot[2545]: time="2024-09-06T00:22:58Z" level=debug msg="zeroconf server listening on port 46669" Sep 06 00:22:58 volumio go-librespot[2545]: time="2024-09-06T00:22:58Z" level=debug msg="obtained new client token: AAD2BmljlV0WI0nTa44FjA9QWH0xCAjYWHo83UjSBYOhEfI6xJz7WSS77m/OAx51O+h+z5kaVJAhtmiqg9NFmyrBi96oakvYmMyNbSaPHb4vpVA3WaylmuVVBJS4UCyFCp53ZfkiApVklqb53kOuPtflEx2LCeo9y3oDuluUn7DoAvr6r+vk7uv+qtSH3uaX4PKq0dWYCobBLtuTgfM5ngeA8Z/lbErk+742CIFqW/qHIaSHpfRppcT6" Sep 06 00:22:58 volumio go-librespot[2545]: time="2024-09-06T00:22:58Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:22:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:22:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:22:59 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:22:59 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:23:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:23:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Sep 06 00:23:01 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:23:01 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:23:01 volumio go-librespot[2553]: Librespot-go daemon starting... Sep 06 00:23:01 volumio go-librespot[2553]: time="2024-09-06T00:23:01Z" level=info msg="generated new device id: 80f4eaa78c2068327628f8df412f9e70ce925ea0" Sep 06 00:23:01 volumio go-librespot[2553]: time="2024-09-06T00:23:01Z" level=debug msg="stored credentials not found" Sep 06 00:23:02 volumio go-librespot[2553]: time="2024-09-06T00:23:02Z" 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]" Sep 06 00:23:02 volumio go-librespot[2553]: time="2024-09-06T00:23:02Z" 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]" Sep 06 00:23:02 volumio go-librespot[2553]: time="2024-09-06T00:23:02Z" 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]" Sep 06 00:23:02 volumio go-librespot[2553]: time="2024-09-06T00:23:02Z" level=debug msg="zeroconf server listening on port 42029" Sep 06 00:23:02 volumio go-librespot[2553]: time="2024-09-06T00:23:02Z" level=debug msg="obtained new client token: AAAbAGdC1jGJsb6Ui9NCf+SBjTdk83LIHTcmdHgIrdpuy+UXhVLC2OO+tPHFKpNLw0R2VGGwplDGObZLKbYbI+ea9evyEpUXjfhZnMXAxzBjulKjXzFW0ukZBM0AcKWUJXbd/xjFTzzJmhHrFhqSJ+DBOZWE+H0NvCJmVrPi3Wvo/B2CYqOdWSjOf35WzoPn/jk5LQh5cioC+C4MeathM59WXSG2WoIfRN6NTWcVyKflEM55A/qgtA==" Sep 06 00:23:02 volumio go-librespot[2553]: time="2024-09-06T00:23:02Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:23:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:23:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:23:02 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:23:02 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:23:05 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:23:05 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:23:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:23:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Sep 06 00:23:05 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:23:05 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:23:05 volumio go-librespot[2562]: Librespot-go daemon starting... Sep 06 00:23:05 volumio go-librespot[2562]: time="2024-09-06T00:23:05Z" level=info msg="generated new device id: e3866f1a74e6089453dbb68419c276cb033eb50d" Sep 06 00:23:05 volumio go-librespot[2562]: time="2024-09-06T00:23:05Z" level=debug msg="stored credentials not found" Sep 06 00:23:05 volumio go-librespot[2562]: time="2024-09-06T00:23:05Z" 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]" Sep 06 00:23:05 volumio go-librespot[2562]: time="2024-09-06T00:23:05Z" 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]" Sep 06 00:23:05 volumio go-librespot[2562]: time="2024-09-06T00:23:05Z" 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]" Sep 06 00:23:05 volumio go-librespot[2562]: time="2024-09-06T00:23:05Z" level=debug msg="zeroconf server listening on port 33723" Sep 06 00:23:05 volumio go-librespot[2562]: time="2024-09-06T00:23:05Z" level=debug msg="obtained new client token: AACQ/KdKBywoAKkoo7IVlY6U6NieoS+g/pUtlgpffdPYOws5EZmV5etp/HOleh1YyzBKPtnFIc8wnkysnqU1mYsxl7BBs3qtCla+t/fRAikO2NHTQA/9w4Lik6QxekWhe4/yu5Ykez0aCv2FLpJjjZkutX5m8OHGPaOh1PNoMyKWF5RSZda9ffkXALfOIz0l7DdGOOQRA5AjZI0XBJ6W3xdZRYJZcHekJNnvBeLoEYaHOxg0ydjytjyn" Sep 06 00:23:06 volumio go-librespot[2562]: time="2024-09-06T00:23:06Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:23:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:23:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:23:08 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:23:08 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:23:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:23:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Sep 06 00:23:09 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:23:09 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:23:09 volumio go-librespot[2570]: Librespot-go daemon starting... Sep 06 00:23:09 volumio go-librespot[2570]: time="2024-09-06T00:23:09Z" level=info msg="generated new device id: dc770d192a78837d706ea74562a5ccc800566bde" Sep 06 00:23:09 volumio go-librespot[2570]: time="2024-09-06T00:23:09Z" level=debug msg="stored credentials not found" Sep 06 00:23:09 volumio go-librespot[2570]: time="2024-09-06T00:23:09Z" 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]" Sep 06 00:23:09 volumio go-librespot[2570]: time="2024-09-06T00:23:09Z" 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]" Sep 06 00:23:09 volumio go-librespot[2570]: time="2024-09-06T00:23:09Z" 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]" Sep 06 00:23:09 volumio go-librespot[2570]: time="2024-09-06T00:23:09Z" level=debug msg="zeroconf server listening on port 34595" Sep 06 00:23:09 volumio go-librespot[2570]: time="2024-09-06T00:23:09Z" level=debug msg="obtained new client token: AAARRaT9rceF+bZW46guvp64lsfgnL5nOceIf0+dr7xqxFyNzjvQQX6bi+DqsETQ5z0LCLYnyP+fVPYik0BrCv3LkVToL+inowhd958+TTu/R3qYL9VGPlFWjohjL9l5hGu1zuu5xCkjlipn8m9krxLSyI9xXXCJZMxrZfAED+XKvpR7j6E0TQ9vJ0WgtdFeNAXWdWHSDP5ubF8RXFjJoUGxQ/DWBusJYpJ4gUjUyDv92bSQ5PJaFbYv" Sep 06 00:23:09 volumio go-librespot[2570]: time="2024-09-06T00:23:09Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:23:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:23:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:23:11 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:23:11 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:23:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:23:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Sep 06 00:23:13 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:23:13 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:23:13 volumio go-librespot[2578]: Librespot-go daemon starting... Sep 06 00:23:13 volumio go-librespot[2578]: time="2024-09-06T00:23:13Z" level=info msg="generated new device id: 53f57991380bd98ebb34c9953fa323ff758d0d21" Sep 06 00:23:13 volumio go-librespot[2578]: time="2024-09-06T00:23:13Z" level=debug msg="stored credentials not found" Sep 06 00:23:13 volumio go-librespot[2578]: time="2024-09-06T00:23:13Z" 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]" Sep 06 00:23:13 volumio go-librespot[2578]: time="2024-09-06T00:23:13Z" 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]" Sep 06 00:23:13 volumio go-librespot[2578]: time="2024-09-06T00:23:13Z" 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]" Sep 06 00:23:13 volumio go-librespot[2578]: time="2024-09-06T00:23:13Z" level=debug msg="zeroconf server listening on port 38201" Sep 06 00:23:13 volumio go-librespot[2578]: time="2024-09-06T00:23:13Z" level=debug msg="obtained new client token: AADwi6fmFp4s73mu6vVGKuDF3fa/671D648cgQB4ZtYMz8dJf/l7v/eE0bKk2jMhMkXSEgt/jRwN7CpddVVAvI6ax+DYhpiIeVfd95dyM18q10DZkO9/il+zY4zMbwIRa/4K/XBRm8ZpK8Bvm5oSSzgrtlFH27+KNt+98EoM9l7K/RJUnzTkKKbwCkrCH3fqO4Jui1jVpt+VOJc22MgoqWiasGMI2juFeulek6B69i3V9Yu61lIzIE2O" Sep 06 00:23:13 volumio go-librespot[2578]: time="2024-09-06T00:23:13Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:23:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:23:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:23:14 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:23:14 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:23:15 volumio ntpd[787]: Soliciting pool server 2803:bc40:8160::3 Sep 06 00:23:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:23:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Sep 06 00:23:16 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:23:16 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:23:16 volumio go-librespot[2586]: Librespot-go daemon starting... Sep 06 00:23:16 volumio go-librespot[2586]: time="2024-09-06T00:23:16Z" level=info msg="generated new device id: 78cc59b47d477766b1179eabf58a460dfb232df7" Sep 06 00:23:16 volumio go-librespot[2586]: time="2024-09-06T00:23:16Z" level=debug msg="stored credentials not found" Sep 06 00:23:17 volumio go-librespot[2586]: time="2024-09-06T00:23:17Z" 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]" Sep 06 00:23:17 volumio go-librespot[2586]: time="2024-09-06T00:23:17Z" 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]" Sep 06 00:23:17 volumio go-librespot[2586]: time="2024-09-06T00:23:17Z" 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]" Sep 06 00:23:17 volumio go-librespot[2586]: time="2024-09-06T00:23:17Z" level=debug msg="zeroconf server listening on port 36179" Sep 06 00:23:17 volumio go-librespot[2586]: time="2024-09-06T00:23:17Z" level=debug msg="obtained new client token: AADURmLFxldDT+HrOxnnJgp7/nnLthyeP2Q3hyYNCcJ3SfTiA6aoR3j0AnbvLIhd2Md6BtQkNg/BnQca8I/ha1XDAjb2dIpT3KO5NLfkbuI2rs+Qq12S+HWl3/JBAsAxjouWB+qOgjnKCbQqkyaRrY0YAwgdq6L1X9VDt9N7I0qxE4Y+fcGPpFjXLYL7TM35Osd08HWsz6/YyswfLChG0XwXQABJBGQTfxD7Eh0ybKSAVFtlh8A9bQ==" Sep 06 00:23:17 volumio go-librespot[2586]: time="2024-09-06T00:23:17Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:23:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:23:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:23:17 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:23:17 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:23:20 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:23:20 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:23:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:23:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Sep 06 00:23:20 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:23:20 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:23:20 volumio go-librespot[2594]: Librespot-go daemon starting... Sep 06 00:23:20 volumio go-librespot[2594]: time="2024-09-06T00:23:20Z" level=info msg="generated new device id: 5ca989fc5edbb17a99bce1032641a773991ec11a" Sep 06 00:23:20 volumio go-librespot[2594]: time="2024-09-06T00:23:20Z" level=debug msg="stored credentials not found" Sep 06 00:23:20 volumio go-librespot[2594]: time="2024-09-06T00:23:20Z" 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]" Sep 06 00:23:20 volumio go-librespot[2594]: time="2024-09-06T00:23:20Z" 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]" Sep 06 00:23:20 volumio go-librespot[2594]: time="2024-09-06T00:23:20Z" 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]" Sep 06 00:23:20 volumio go-librespot[2594]: time="2024-09-06T00:23:20Z" level=debug msg="zeroconf server listening on port 32867" Sep 06 00:23:21 volumio go-librespot[2594]: time="2024-09-06T00:23:21Z" level=debug msg="obtained new client token: AADjmSr36tjxveUnKgTjDoophIZpUQy1eIU/Wp3iYuL3CWPE6PfZv/0c/c33LgKNmjCVEgZHQgF4CZPkaFBoBu3gO672iB8Fx2Joim5weGr60xQgdU4wf3/vF5an7VADtIg5diOwcbuDBck0Ylbpj/j+Fv6Iny9OCj7djuxncDpwWVhGkqzw0dB4KOkDgmP8dEteNVA+IIk/ubpFF27BxjejdGl6ZbfPZJ6Ef09uQgQ6tYca5OLuIA==" Sep 06 00:23:21 volumio go-librespot[2594]: time="2024-09-06T00:23:21Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:23:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:23:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:23:23 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:23:23 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:23:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:23:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Sep 06 00:23:24 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:23:24 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:23:24 volumio go-librespot[2602]: Librespot-go daemon starting... Sep 06 00:23:24 volumio go-librespot[2602]: time="2024-09-06T00:23:24Z" level=info msg="generated new device id: 79f5b29a108a75ccbba8108759b91be0343bc86c" Sep 06 00:23:24 volumio go-librespot[2602]: time="2024-09-06T00:23:24Z" level=debug msg="stored credentials not found" Sep 06 00:23:24 volumio go-librespot[2602]: time="2024-09-06T00:23:24Z" 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]" Sep 06 00:23:24 volumio go-librespot[2602]: time="2024-09-06T00:23:24Z" 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]" Sep 06 00:23:24 volumio go-librespot[2602]: time="2024-09-06T00:23:24Z" 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]" Sep 06 00:23:24 volumio go-librespot[2602]: time="2024-09-06T00:23:24Z" level=debug msg="zeroconf server listening on port 36711" Sep 06 00:23:24 volumio go-librespot[2602]: time="2024-09-06T00:23:24Z" level=debug msg="obtained new client token: AAA8JlvWqvxQU0YBKwCiGVB18jKgqIzFTE6iOgLmbLkWzV0+OKw/sldrwMzOgTj1GsQvCXEIZSmCPfBxAHVu/3m5yLsmvTwxM8ZrUOYZv7ZIU6Mxu8krJk14GHzqRXe2kTJ5f8yJ0FTYqiEHCi/2VSNdrge0yMhA354Ico/tfCoS2Dvk7ANcGVApdiomyngLqEx8y2mt1Mze26QPCMAMKiKwL/XzYT5Zs0lopamD3obOiyqHJUZVVd2Y" Sep 06 00:23:24 volumio go-librespot[2602]: time="2024-09-06T00:23:24Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:23:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:23:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:23:26 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:23:26 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:23:28 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:23:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Sep 06 00:23:28 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:23:28 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:23:28 volumio go-librespot[2610]: Librespot-go daemon starting... Sep 06 00:23:28 volumio go-librespot[2610]: time="2024-09-06T00:23:28Z" level=info msg="generated new device id: a2e8147a0d1702f9a463fa90a2bf74fa9e45f668" Sep 06 00:23:28 volumio go-librespot[2610]: time="2024-09-06T00:23:28Z" level=debug msg="stored credentials not found" Sep 06 00:23:28 volumio go-librespot[2610]: time="2024-09-06T00:23:28Z" 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]" Sep 06 00:23:28 volumio go-librespot[2610]: time="2024-09-06T00:23:28Z" 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]" Sep 06 00:23:28 volumio go-librespot[2610]: time="2024-09-06T00:23:28Z" 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]" Sep 06 00:23:28 volumio go-librespot[2610]: time="2024-09-06T00:23:28Z" level=debug msg="zeroconf server listening on port 44275" Sep 06 00:23:28 volumio go-librespot[2610]: time="2024-09-06T00:23:28Z" level=debug msg="obtained new client token: AACkb7ZDnjW8vabw62DdryfKnxTD5PJjCrB9jL2jTzM9/magS0+/gfDfcez4Yl6/OxJr9AExQYl4nGLdl0cj/r1wFmuJS9AVXkbb2NxDac1P10kmoETe2iBkVfGSOqOHpFvoL4dPz4QuNng+q0/ing6ESU9LItFqUV6U76BSq04eNB1D9gcwZvO7GTYizV+3epqFacp1nMxQcfXAvMwFMmboTwJuRHP1mxYTkHJ93Gu+sSVyPwR9H961" Sep 06 00:23:28 volumio go-librespot[2610]: time="2024-09-06T00:23:28Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:23:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:23:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:23:29 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:23:29 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:23:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:23:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Sep 06 00:23:31 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:23:31 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:23:31 volumio go-librespot[2619]: Librespot-go daemon starting... Sep 06 00:23:31 volumio go-librespot[2619]: time="2024-09-06T00:23:31Z" level=info msg="generated new device id: 3586bf8172217324a97585df0e93324c6cf2c48a" Sep 06 00:23:31 volumio go-librespot[2619]: time="2024-09-06T00:23:31Z" level=debug msg="stored credentials not found" Sep 06 00:23:32 volumio go-librespot[2619]: time="2024-09-06T00:23:32Z" 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]" Sep 06 00:23:32 volumio go-librespot[2619]: time="2024-09-06T00:23:32Z" 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]" Sep 06 00:23:32 volumio go-librespot[2619]: time="2024-09-06T00:23:32Z" 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]" Sep 06 00:23:32 volumio go-librespot[2619]: time="2024-09-06T00:23:32Z" level=debug msg="zeroconf server listening on port 34327" Sep 06 00:23:32 volumio go-librespot[2619]: time="2024-09-06T00:23:32Z" level=debug msg="obtained new client token: AADSRMaiiAybKcKZYUl7jXEcnSsTCNRN0uLeHD5rQzIw5YJXKnFJU1Ujt2CrGVyrerSIYIU2OXTKodMKTAvqQi62RmY+qPxJvgu1yKNi6gBh0cLcGUXIzLQ9vIyoP+5Rp2iMjG0q4AjOIuUEJ/CWJjhQvP1l7rSfgLjRpIuNAza4ooEjq+hg6Fgpjesba2wbOG0TB2tUMeBNzqYdZGdsKTkYQzsQ3M60FG/ztGGiQEt90HkXlTilug==" Sep 06 00:23:32 volumio go-librespot[2619]: time="2024-09-06T00:23:32Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:23:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:23:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:23:32 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:23:32 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:23:35 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:23:35 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:23:35 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:23:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Sep 06 00:23:35 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:23:35 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:23:35 volumio go-librespot[2629]: Librespot-go daemon starting... Sep 06 00:23:35 volumio go-librespot[2629]: time="2024-09-06T00:23:35Z" level=info msg="generated new device id: 2d9c8c7d7c2e3056b6583e2d7a6981615095f8a2" Sep 06 00:23:35 volumio go-librespot[2629]: time="2024-09-06T00:23:35Z" level=debug msg="stored credentials not found" Sep 06 00:23:35 volumio go-librespot[2629]: time="2024-09-06T00:23:35Z" 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]" Sep 06 00:23:35 volumio go-librespot[2629]: time="2024-09-06T00:23:35Z" 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]" Sep 06 00:23:35 volumio go-librespot[2629]: time="2024-09-06T00:23:35Z" 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]" Sep 06 00:23:35 volumio go-librespot[2629]: time="2024-09-06T00:23:35Z" level=debug msg="zeroconf server listening on port 43163" Sep 06 00:23:38 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:23:38 volumio go-librespot[2629]: time="2024-09-06T00:23:38Z" level=debug msg="new websocket client" Sep 06 00:23:38 volumio volumio[983]: info: Connection to go-librespot Websocket established Sep 06 00:23:40 volumio go-librespot[2629]: time="2024-09-06T00:23:40Z" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: invalid status code from clienttoken: 502" Sep 06 00:23:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:23:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:23:40 volumio volumio[983]: info: Connection to go-librespot Websocket closed Sep 06 00:23:41 volumio volumio[983]: info: Getting Spotify volume Sep 06 00:23:41 volumio volumio[983]: (node:983) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:23:41 volumio volumio[983]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Sep 06 00:23:41 volumio volumio[983]: (node:983) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 9) Sep 06 00:23:41 volumio volumio[983]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Sep 06 00:23:41 volumio volumio[983]: info: CoreCommandRouter::volumioGetState Sep 06 00:23:41 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Sep 06 00:23:43 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:23:43 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:23:43 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:23:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Sep 06 00:23:43 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:23:43 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:23:43 volumio go-librespot[2675]: Librespot-go daemon starting... Sep 06 00:23:43 volumio go-librespot[2675]: time="2024-09-06T00:23:43Z" level=info msg="generated new device id: abe431b33a51aab1019b2269f21bec5fa928559d" Sep 06 00:23:43 volumio go-librespot[2675]: time="2024-09-06T00:23:43Z" level=debug msg="stored credentials not found" Sep 06 00:23:43 volumio go-librespot[2675]: time="2024-09-06T00:23:43Z" 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]" Sep 06 00:23:43 volumio go-librespot[2675]: time="2024-09-06T00:23:43Z" 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]" Sep 06 00:23:43 volumio go-librespot[2675]: time="2024-09-06T00:23:43Z" 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]" Sep 06 00:23:43 volumio go-librespot[2675]: time="2024-09-06T00:23:43Z" level=debug msg="zeroconf server listening on port 39643" Sep 06 00:23:44 volumio go-librespot[2675]: time="2024-09-06T00:23:44Z" level=debug msg="obtained new client token: AADUYzTx2wfV/0SUxvLm1UlyvZO5RfAn64TSsjfOZG3+k8T3G6aXUB1UzVdPl1nhidK0UXUJ7yrwWeKO48djMURSmVL6MrLG3E+wXKfltcn8vMBUjujmggki0GUqgRzg/O0Q/WyrUBZ+9IhSXB71ucFHkzBMeRb4+eGejTSL/7zLGWic+fw8fUtEjtoz2lfA4PITuZjqNEd6NsEWof3RcnSuHYhbeIZnFK2GLqUQa/4ONPjrWPTHybeo" Sep 06 00:23:44 volumio go-librespot[2675]: time="2024-09-06T00:23:44Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:23:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:23:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:23:46 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:23:46 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:23:47 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:23:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Sep 06 00:23:47 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:23:47 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:23:47 volumio go-librespot[2683]: Librespot-go daemon starting... Sep 06 00:23:47 volumio go-librespot[2683]: time="2024-09-06T00:23:47Z" level=info msg="generated new device id: 17377e57812e6a425d8eb1fc4f5e92b36fd6a66c" Sep 06 00:23:47 volumio go-librespot[2683]: time="2024-09-06T00:23:47Z" level=debug msg="stored credentials not found" Sep 06 00:23:47 volumio go-librespot[2683]: time="2024-09-06T00:23:47Z" 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]" Sep 06 00:23:47 volumio go-librespot[2683]: time="2024-09-06T00:23:47Z" 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]" Sep 06 00:23:47 volumio go-librespot[2683]: time="2024-09-06T00:23:47Z" 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]" Sep 06 00:23:47 volumio go-librespot[2683]: time="2024-09-06T00:23:47Z" level=debug msg="zeroconf server listening on port 42099" Sep 06 00:23:47 volumio go-librespot[2683]: time="2024-09-06T00:23:47Z" level=debug msg="obtained new client token: AADFdv6/Me7XBgUBEasBLocbsDP9gJG3k2bvx8t/ZIQWRULjEX2ttDAGFif5HKVP5W8AuiJgufPydlKnOMP7nwy/ffxvSrtb8Ck+nVVPQVZA53O8eeuVfPNepQkKrtQbFyR9wQzMS2wey6pr3Lzasgr8MLAnBb5oyla8c9cjBrp3QGOIFD1vPPj3MT1I+wZJwZKqOkPeFG6jQeJ8bbAU05LoBBMT5xXlmwLk83+4kac09ubZ+h3kB4+p" Sep 06 00:23:47 volumio go-librespot[2683]: time="2024-09-06T00:23:47Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:23:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:23:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:23:49 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:23:49 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:23:51 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:23:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36. Sep 06 00:23:51 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:23:51 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:23:51 volumio go-librespot[2692]: Librespot-go daemon starting... Sep 06 00:23:51 volumio go-librespot[2692]: time="2024-09-06T00:23:51Z" level=info msg="generated new device id: 89b13dc84075dc5c432ab67cfad1b9ddcb03dcdc" Sep 06 00:23:51 volumio go-librespot[2692]: time="2024-09-06T00:23:51Z" level=debug msg="stored credentials not found" Sep 06 00:23:51 volumio go-librespot[2692]: time="2024-09-06T00:23:51Z" 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]" Sep 06 00:23:51 volumio go-librespot[2692]: time="2024-09-06T00:23:51Z" 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]" Sep 06 00:23:51 volumio go-librespot[2692]: time="2024-09-06T00:23:51Z" 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]" Sep 06 00:23:51 volumio go-librespot[2692]: time="2024-09-06T00:23:51Z" level=debug msg="zeroconf server listening on port 40999" Sep 06 00:23:51 volumio go-librespot[2692]: time="2024-09-06T00:23:51Z" level=debug msg="obtained new client token: AACrjS57w15HFzQp9rOeAKNtQP3a3BRzZd6RdtABd2WMDbRfjMz+IJLOPmvbDwB3EFoP4Lw1a4EAoEgtp8av7WIGPjXTowje/atPCgyfagoElPJ/r+gPw0IvPTUZkJEa4EreZ9goiPPz8WrV1Iu22aKaTih0fzF8bfAajSehUHbj2qw0Z/x3L1kt65GLqM4Q5CHOO+4kEnrx6RovfMTKUJFCMffRpPUOfHmbdz9UaTWytn/C2umP525j" Sep 06 00:23:51 volumio go-librespot[2692]: time="2024-09-06T00:23:51Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:23:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:23:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:23:52 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 06 00:23:52 volumio volumio[983]: info: Preload queue cleared Sep 06 00:23:52 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:23:52 volumio volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 06 00:23:53 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 06 00:23:53 volumio volumio[983]: info: Preload queue cleared Sep 06 00:23:54 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 06 00:23:55 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 06 00:23:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37. Sep 06 00:23:55 volumio systemd[1]: Stopped go-librespot Daemon. Sep 06 00:23:55 volumio systemd[1]: Started go-librespot Daemon. Sep 06 00:23:55 volumio go-librespot[2700]: Librespot-go daemon starting... Sep 06 00:23:55 volumio go-librespot[2700]: time="2024-09-06T00:23:55Z" level=info msg="generated new device id: 549164c69e88b9ef304959f0a2706fb4e7110968" Sep 06 00:23:55 volumio go-librespot[2700]: time="2024-09-06T00:23:55Z" level=debug msg="stored credentials not found" Sep 06 00:23:55 volumio volumio[983]: info: Preload queue cleared Sep 06 00:23:55 volumio go-librespot[2700]: time="2024-09-06T00:23:55Z" 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]" Sep 06 00:23:55 volumio go-librespot[2700]: time="2024-09-06T00:23:55Z" 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]" Sep 06 00:23:55 volumio go-librespot[2700]: time="2024-09-06T00:23:55Z" 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]" Sep 06 00:23:55 volumio go-librespot[2700]: time="2024-09-06T00:23:55Z" level=debug msg="zeroconf server listening on port 44699" Sep 06 00:23:55 volumio volumio[983]: info: Initializing connection to go-librespot Websocket Sep 06 00:23:55 volumio go-librespot[2700]: time="2024-09-06T00:23:55Z" level=debug msg="new websocket client" Sep 06 00:23:55 volumio volumio[983]: info: Connection to go-librespot Websocket established Sep 06 00:23:55 volumio go-librespot[2700]: time="2024-09-06T00:23:55Z" level=debug msg="obtained new client token: AACza1Clb8p6hg6DRIUDbSpLDedSPoMcWtWM6QyGHQl2TU0rYAN0BEQ+6HCtDOdGxt8sAz5tJ3MxiSrqJujaTm7hj9tJmNatJOg03i6P7EQLntw2b4p6NmfsIfFwmuQu8YPEHe3rKxZT/FeQCaAswUGmk4NFfI4/EPdsc+2GWEPATZGG7XqiVFYVlm7IREwuuKA6KDTznEagzuW1xc6ItfAH8IddbK8l9R+bvOVM0t1old+tuxwjKzhz" Sep 06 00:23:55 volumio go-librespot[2700]: time="2024-09-06T00:23:55Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection refused" Sep 06 00:23:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 06 00:23:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 06 00:23:55 volumio volumio[983]: info: Connection to go-librespot Websocket closed Sep 06 00:23:56 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 06 00:23:56 volumio volumio[983]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 00:23:56 volumio volumio[983]: TypeError: Cannot read property 'length' of undefined Sep 06 00:23:56 volumio volumio[983]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Sep 06 00:23:56 volumio volumio[983]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Sep 06 00:23:56 volumio volumio[983]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Sep 06 00:23:56 volumio volumio[983]: at Parser.emit (events.js:315:20) Sep 06 00:23:56 volumio volumio[983]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Sep 06 00:23:56 volumio volumio[983]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Sep 06 00:23:56 volumio volumio[983]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Sep 06 00:23:56 volumio volumio[983]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Sep 06 00:23:56 volumio volumio[983]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Sep 06 00:23:56 volumio volumio[983]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Sep 06 00:23:56 volumio volumio[983]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Sep 06 00:23:56 volumio volumio[983]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Sep 06 00:23:56 volumio volumio[983]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Sep 06 00:23:56 volumio volumio[983]: at IncomingMessage.emit (events.js:327:22) Sep 06 00:23:56 volumio volumio[983]: at endReadableNT (internal/streams/readable.js:1327:12) Sep 06 00:23:56 volumio volumio[983]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 06 00:23:56 volumio volumio[983]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 00:23:56 volumio sudo[2717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-06 00:22 Sep 06 00:23:56 volumio sudo[2717]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"