-- Logs begin at Thu 2019-02-14 12:11:58 EET, end at Sat 2025-07-05 15:59:15 EEST. --
Jul 05 15:58:01 volumio volumio[1221]: info: Initializing connection to go-librespot Websocket
Jul 05 15:58:01 volumio volumio[1221]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jul 05 15:58:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Jul 05 15:58:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 471.
Jul 05 15:58:01 volumio systemd[1]: Stopped go-librespot Daemon.
Jul 05 15:58:01 volumio systemd[1]: Started go-librespot Daemon.
Jul 05 15:58:01 volumio go-librespot[15930]: go-librespot daemon starting...
Jul 05 15:58:01 volumio go-librespot[15930]: time="2025-07-05T15:58:01+03:00" level=info msg="running go-librespot 0.2.0"
Jul 05 15:58:01 volumio go-librespot[15930]: time="2025-07-05T15:58:01+03:00" level=debug msg="app state loaded"
Jul 05 15:58:01 volumio go-librespot[15930]: time="2025-07-05T15:58:01+03:00" level=debug msg="stored credentials not found"
Jul 05 15:58:01 volumio go-librespot[15930]: time="2025-07-05T15:58:01+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Jul 05 15:58:01 volumio go-librespot[15930]: time="2025-07-05T15:58:01+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Jul 05 15:58:01 volumio go-librespot[15930]: time="2025-07-05T15:58:01+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Jul 05 15:58:01 volumio go-librespot[15930]: time="2025-07-05T15:58:01+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Jul 05 15:58:01 volumio go-librespot[15930]: time="2025-07-05T15:58:01+03:00" level=info msg="zeroconf server listening on port 45051"
Jul 05 15:58:01 volumio go-librespot[15930]: time="2025-07-05T15:58:01+03:00" level=debug msg="obtained new client token: AACXcE1Yp98Q3mFqi8SInbNVo+r8SrWXh8QjB3MRWZ9sFyor+427+Kbyoyftf//euGymxfzcgrRs1rct5zmIbgUU0E/QSeMH16WVzns4u6IKrK31tq//yjoui/Cb4FyUl+4AyGux3tky9rrcncqqr0xtwMksNOwM1mtIQn36BQmbJe4/2tkSjJqDGLBCqWUOcQ1K0k+qJBSJxlXtemMiAnGVcuJsv98syKVOOabAU55JQ3Ou67GRKNLRzfWs"
Jul 05 15:58:01 volumio go-librespot[15930]: time="2025-07-05T15:58:01+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Jul 05 15:58:01 volumio go-librespot[15930]: time="2025-07-05T15:58:01+03:00" level=debug msg="completed keyexchange"
Jul 05 15:58:01 volumio go-librespot[15930]: time="2025-07-05T15:58:01+03:00" level=debug msg="completed challenge"
Jul 05 15:58:02 volumio go-librespot[15930]: time="2025-07-05T15:58:02+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jul 05 15:58:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jul 05 15:58:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jul 05 15:58:03 volumio volumio-remote-updater[645]: PROGRESS: 30, STATUS: "Downloading new update", ETA: "4m"
Jul 05 15:58:03 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Jul 05 15:58:03 volumio volumio[1221]: updateProgress
Jul 05 15:58:03 volumio volumio[1221]: {
Jul 05 15:58:03 volumio volumio[1221]: downloadSpeed: '',
Jul 05 15:58:03 volumio volumio[1221]: eta: '4m',
Jul 05 15:58:03 volumio volumio[1221]: progress: 30,
Jul 05 15:58:03 volumio volumio[1221]: status: 'Загрузка нового обновления'
Jul 05 15:58:03 volumio volumio[1221]: }
Jul 05 15:58:04 volumio wpa_supplicant[938]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=74:da:88:37:89:92 status_code=16
Jul 05 15:58:04 volumio wpa_supplicant[938]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="OpenWRT" auth_failures=26 duration=162 reason=CONN_FAILED
Jul 05 15:58:04 volumio volumio[1221]: info: Initializing connection to go-librespot Websocket
Jul 05 15:58:04 volumio volumio[1221]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jul 05 15:58:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Jul 05 15:58:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 472.
Jul 05 15:58:05 volumio systemd[1]: Stopped go-librespot Daemon.
Jul 05 15:58:05 volumio systemd[1]: Started go-librespot Daemon.
Jul 05 15:58:05 volumio go-librespot[15969]: go-librespot daemon starting...
Jul 05 15:58:05 volumio go-librespot[15969]: time="2025-07-05T15:58:05+03:00" level=info msg="running go-librespot 0.2.0"
Jul 05 15:58:05 volumio go-librespot[15969]: time="2025-07-05T15:58:05+03:00" level=debug msg="app state loaded"
Jul 05 15:58:05 volumio go-librespot[15969]: time="2025-07-05T15:58:05+03:00" level=debug msg="stored credentials not found"
Jul 05 15:58:05 volumio go-librespot[15969]: time="2025-07-05T15:58:05+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Jul 05 15:58:05 volumio go-librespot[15969]: time="2025-07-05T15:58:05+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]"
Jul 05 15:58:05 volumio go-librespot[15969]: time="2025-07-05T15:58:05+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Jul 05 15:58:05 volumio go-librespot[15969]: time="2025-07-05T15:58:05+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Jul 05 15:58:05 volumio go-librespot[15969]: time="2025-07-05T15:58:05+03:00" level=info msg="zeroconf server listening on port 43335"
Jul 05 15:58:05 volumio go-librespot[15969]: time="2025-07-05T15:58:05+03:00" level=debug msg="obtained new client token: AABrx7LsscmVZdwBlWa0TKB9d2rj4ZdUPMjUMBXbmG0RVNRwpFy2ijVgVjfvukMGZFd77GCZno9uzEh2duBC3vG3s+9L02BlFSVJBz4T8LyLzHxfdU56Td2HjneMyJRgrdF+CuXCaU2MC/aVts/6mQ6ylWBh7G3dEhnGcgEvNvj8SpZntjuNaol4nklAPysPBoQpHCLxy/BAJJVFostBXm2fpis2q6zS1vQxlm6Y65fvIPUBGH7mvVTG+YxV"
Jul 05 15:58:05 volumio go-librespot[15969]: time="2025-07-05T15:58:05+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Jul 05 15:58:05 volumio go-librespot[15969]: time="2025-07-05T15:58:05+03:00" level=debug msg="completed keyexchange"
Jul 05 15:58:05 volumio go-librespot[15969]: time="2025-07-05T15:58:05+03:00" level=debug msg="completed challenge"
Jul 05 15:58:05 volumio go-librespot[15969]: time="2025-07-05T15:58:05+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jul 05 15:58:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jul 05 15:58:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jul 05 15:58:07 volumio volumio[1221]: info: Initializing connection to go-librespot Websocket
Jul 05 15:58:07 volumio volumio[1221]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jul 05 15:58:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Jul 05 15:58:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 473.
Jul 05 15:58:08 volumio systemd[1]: Stopped go-librespot Daemon.
Jul 05 15:58:08 volumio systemd[1]: Started go-librespot Daemon.
Jul 05 15:58:08 volumio go-librespot[15978]: go-librespot daemon starting...
Jul 05 15:58:08 volumio go-librespot[15978]: time="2025-07-05T15:58:08+03:00" level=info msg="running go-librespot 0.2.0"
Jul 05 15:58:08 volumio go-librespot[15978]: time="2025-07-05T15:58:08+03:00" level=debug msg="app state loaded"
Jul 05 15:58:08 volumio go-librespot[15978]: time="2025-07-05T15:58:08+03:00" level=debug msg="stored credentials not found"
Jul 05 15:58:08 volumio go-librespot[15978]: time="2025-07-05T15:58:08+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Jul 05 15:58:09 volumio go-librespot[15978]: time="2025-07-05T15:58:09+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]"
Jul 05 15:58:09 volumio go-librespot[15978]: time="2025-07-05T15:58:09+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Jul 05 15:58:09 volumio go-librespot[15978]: time="2025-07-05T15:58:09+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Jul 05 15:58:09 volumio go-librespot[15978]: time="2025-07-05T15:58:09+03:00" level=info msg="zeroconf server listening on port 41857"
Jul 05 15:58:09 volumio go-librespot[15978]: time="2025-07-05T15:58:09+03:00" level=debug msg="obtained new client token: AACli/FXmKaGeZZBXNKwuYGM0SpDHw7QG4WQvirLeh9hL8NelYIMyhvMzSQle59jUOTWK//ABGkKfVzbWb+zBQxdm7s9HdigqW0IIrU+bXEjtRE0AFY15GIbtH2kquHHRsM8GDbZcgBFMfpPfKeG7fcVlznQbEDAa8/Kx2I4JVJP0SD9xtwKkwaUeYumtOLdRaAQpxx/en6vypLwvmTfYrFM/AQE+Ap8CwIfN/Y90BrWFD9bADT3TQMzfOll"
Jul 05 15:58:09 volumio go-librespot[15978]: time="2025-07-05T15:58:09+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Jul 05 15:58:09 volumio go-librespot[15978]: time="2025-07-05T15:58:09+03:00" level=debug msg="completed keyexchange"
Jul 05 15:58:09 volumio go-librespot[15978]: time="2025-07-05T15:58:09+03:00" level=debug msg="completed challenge"
Jul 05 15:58:09 volumio go-librespot[15978]: time="2025-07-05T15:58:09+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jul 05 15:58:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jul 05 15:58:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jul 05 15:58:10 volumio volumio[1221]: info: Initializing connection to go-librespot Websocket
Jul 05 15:58:10 volumio volumio[1221]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jul 05 15:58:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Jul 05 15:58:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 474.
Jul 05 15:58:12 volumio systemd[1]: Stopped go-librespot Daemon.
Jul 05 15:58:12 volumio systemd[1]: Started go-librespot Daemon.
Jul 05 15:58:12 volumio go-librespot[15986]: go-librespot daemon starting...
Jul 05 15:58:12 volumio go-librespot[15986]: time="2025-07-05T15:58:12+03:00" level=info msg="running go-librespot 0.2.0"
Jul 05 15:58:12 volumio go-librespot[15986]: time="2025-07-05T15:58:12+03:00" level=debug msg="app state loaded"
Jul 05 15:58:12 volumio go-librespot[15986]: time="2025-07-05T15:58:12+03:00" level=debug msg="stored credentials not found"
Jul 05 15:58:12 volumio go-librespot[15986]: time="2025-07-05T15:58:12+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Jul 05 15:58:12 volumio go-librespot[15986]: time="2025-07-05T15:58:12+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Jul 05 15:58:12 volumio go-librespot[15986]: time="2025-07-05T15:58:12+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Jul 05 15:58:12 volumio go-librespot[15986]: time="2025-07-05T15:58:12+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Jul 05 15:58:12 volumio go-librespot[15986]: time="2025-07-05T15:58:12+03:00" level=info msg="zeroconf server listening on port 42467"
Jul 05 15:58:13 volumio go-librespot[15986]: time="2025-07-05T15:58:13+03:00" level=debug msg="obtained new client token: AACgGhpDP4dvotdYECj7H2zIEM0ix1/L418qYMSwdRGZMIN0RzqFos/6qAwEvmzeDpe+sEC/mOTUCo5ssvatgZtrTXy9982JXyfd3od5fB+oV1UyzcWpxgZLMbOfszRrzh+TvNFcO2/+PYTO6KJKFn1dbUeYNkyxvNrs4dKwgrYtXLXKmH9ZqrCuwsMtO2VDGxNK6VoVcOyBCTj3+QXyNlgW7gHpzKeWDCdRePyxAvGFXil672fUoMQeWA=="
Jul 05 15:58:13 volumio go-librespot[15986]: time="2025-07-05T15:58:13+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Jul 05 15:58:13 volumio go-librespot[15986]: time="2025-07-05T15:58:13+03:00" level=debug msg="completed keyexchange"
Jul 05 15:58:13 volumio go-librespot[15986]: time="2025-07-05T15:58:13+03:00" level=debug msg="completed challenge"
Jul 05 15:58:13 volumio go-librespot[15986]: time="2025-07-05T15:58:13+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jul 05 15:58:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jul 05 15:58:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jul 05 15:58:13 volumio volumio[1221]: info: Initializing connection to go-librespot Websocket
Jul 05 15:58:13 volumio volumio[1221]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jul 05 15:58:16 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 05 15:58:16 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 05 15:58:16 volumio volumio[1221]: info: Discovery: Getting this device information
Jul 05 15:58:16 volumio volumio[1221]: info: CoreCommandRouter::volumioGetState
Jul 05 15:58:16 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 05 15:58:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Jul 05 15:58:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 475.
Jul 05 15:58:16 volumio systemd[1]: Stopped go-librespot Daemon.
Jul 05 15:58:16 volumio volumio[1221]: info: Initializing connection to go-librespot Websocket
Jul 05 15:58:16 volumio volumio[1221]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jul 05 15:58:16 volumio systemd[1]: Started go-librespot Daemon.
Jul 05 15:58:16 volumio go-librespot[16011]: go-librespot daemon starting...
Jul 05 15:58:16 volumio go-librespot[16011]: time="2025-07-05T15:58:16+03:00" level=info msg="running go-librespot 0.2.0"
Jul 05 15:58:16 volumio go-librespot[16011]: time="2025-07-05T15:58:16+03:00" level=debug msg="app state loaded"
Jul 05 15:58:16 volumio go-librespot[16011]: time="2025-07-05T15:58:16+03:00" level=debug msg="stored credentials not found"
Jul 05 15:58:16 volumio go-librespot[16011]: time="2025-07-05T15:58:16+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Jul 05 15:58:16 volumio go-librespot[16011]: time="2025-07-05T15:58:16+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]"
Jul 05 15:58:16 volumio go-librespot[16011]: time="2025-07-05T15:58:16+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Jul 05 15:58:16 volumio go-librespot[16011]: time="2025-07-05T15:58:16+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Jul 05 15:58:16 volumio go-librespot[16011]: time="2025-07-05T15:58:16+03:00" level=info msg="zeroconf server listening on port 37033"
Jul 05 15:58:16 volumio go-librespot[16011]: time="2025-07-05T15:58:16+03:00" level=debug msg="obtained new client token: AADhsnQfnXUH5iio7wsvzIgCvJt0ZnfOIxmbuTtJILj/73dmsAZyvCJB9gAreB/6arIVbd/P/BSk/W7AvEbB/fMNtBtEUMxNIA2CSV8GqRikXUUU/yX8sNkTQcD/kmKDTb92qeRKTngxUqVQOAe4cuEWJPI2ZJfmT11P2zz2jVhTC8J/vDYJ1P2rYYeVlujwbmQxCOQSQeo8x+YQvsSOdXbpqIDDYVE9Ut0FpOIkNVQ4XKs8UzxzzPEWP1PC"
Jul 05 15:58:16 volumio go-librespot[16011]: time="2025-07-05T15:58:16+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Jul 05 15:58:16 volumio go-librespot[16011]: time="2025-07-05T15:58:16+03:00" level=debug msg="completed keyexchange"
Jul 05 15:58:16 volumio go-librespot[16011]: time="2025-07-05T15:58:16+03:00" level=debug msg="completed challenge"
Jul 05 15:58:17 volumio go-librespot[16011]: time="2025-07-05T15:58:17+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jul 05 15:58:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jul 05 15:58:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jul 05 15:58:19 volumio volumio[1221]: info: Initializing connection to go-librespot Websocket
Jul 05 15:58:19 volumio volumio[1221]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jul 05 15:58:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Jul 05 15:58:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 476.
Jul 05 15:58:20 volumio systemd[1]: Stopped go-librespot Daemon.
Jul 05 15:58:20 volumio systemd[1]: Started go-librespot Daemon.
Jul 05 15:58:20 volumio go-librespot[16021]: go-librespot daemon starting...
Jul 05 15:58:20 volumio go-librespot[16021]: time="2025-07-05T15:58:20+03:00" level=info msg="running go-librespot 0.2.0"
Jul 05 15:58:20 volumio go-librespot[16021]: time="2025-07-05T15:58:20+03:00" level=debug msg="app state loaded"
Jul 05 15:58:20 volumio go-librespot[16021]: time="2025-07-05T15:58:20+03:00" level=debug msg="stored credentials not found"
Jul 05 15:58:20 volumio go-librespot[16021]: time="2025-07-05T15:58:20+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Jul 05 15:58:20 volumio go-librespot[16021]: time="2025-07-05T15:58:20+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Jul 05 15:58:20 volumio go-librespot[16021]: time="2025-07-05T15:58:20+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Jul 05 15:58:20 volumio go-librespot[16021]: time="2025-07-05T15:58:20+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Jul 05 15:58:20 volumio go-librespot[16021]: time="2025-07-05T15:58:20+03:00" level=info msg="zeroconf server listening on port 45323"
Jul 05 15:58:20 volumio go-librespot[16021]: time="2025-07-05T15:58:20+03:00" level=debug msg="obtained new client token: AADbfrxYEOW9qcvo+e8jahjL0H54xb56QPnD0SMNSp+due6Bc4VJpbu8tN44yT9onUYbMAXaPWF9hyrdUT/QOBRJDZKA/iqFWWROJrw2k6nFMcblGqEgcxqhNiDd5P0wRBogc6vS4Tcq1VBIAqLiN/O/tXZdwF+mp5JFrUxZ9FUq6axgYYcs+U8IXa2MnG/0UidoJEjtqfEHUUBTih0ouvhjJIMzuHnNPK4S+zqd5CusNhDYe7LHCCJJtpv6"
Jul 05 15:58:20 volumio go-librespot[16021]: time="2025-07-05T15:58:20+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Jul 05 15:58:20 volumio go-librespot[16021]: time="2025-07-05T15:58:20+03:00" level=debug msg="completed keyexchange"
Jul 05 15:58:20 volumio go-librespot[16021]: time="2025-07-05T15:58:20+03:00" level=debug msg="completed challenge"
Jul 05 15:58:20 volumio go-librespot[16021]: time="2025-07-05T15:58:20+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jul 05 15:58:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jul 05 15:58:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jul 05 15:58:21 volumio wpa_supplicant[938]: wlan0: Failed to initiate sched scan
Jul 05 15:58:22 volumio volumio[1221]: info: Initializing connection to go-librespot Websocket
Jul 05 15:58:22 volumio volumio[1221]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jul 05 15:58:23 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Jul 05 15:58:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 477.
Jul 05 15:58:23 volumio systemd[1]: Stopped go-librespot Daemon.
Jul 05 15:58:23 volumio systemd[1]: Started go-librespot Daemon.
Jul 05 15:58:23 volumio go-librespot[16030]: go-librespot daemon starting...
Jul 05 15:58:23 volumio go-librespot[16030]: time="2025-07-05T15:58:23+03:00" level=info msg="running go-librespot 0.2.0"
Jul 05 15:58:23 volumio go-librespot[16030]: time="2025-07-05T15:58:23+03:00" level=debug msg="app state loaded"
Jul 05 15:58:23 volumio go-librespot[16030]: time="2025-07-05T15:58:23+03:00" level=debug msg="stored credentials not found"
Jul 05 15:58:23 volumio go-librespot[16030]: time="2025-07-05T15:58:23+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Jul 05 15:58:24 volumio go-librespot[16030]: time="2025-07-05T15:58:24+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Jul 05 15:58:24 volumio go-librespot[16030]: time="2025-07-05T15:58:24+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Jul 05 15:58:24 volumio go-librespot[16030]: time="2025-07-05T15:58:24+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Jul 05 15:58:24 volumio go-librespot[16030]: time="2025-07-05T15:58:24+03:00" level=info msg="zeroconf server listening on port 43907"
Jul 05 15:58:24 volumio go-librespot[16030]: time="2025-07-05T15:58:24+03:00" level=debug msg="obtained new client token: AADDzMptBsqs4mKUctHssQ8KaTk93HsHX+0Ae6PCEw2ZHW7Y4rkPJjOQxfKknvti+Wvd+LaKGO3ulL/D9Kb4cZiD5Ub/dhvBG2lxCmTmyeModtJi+WFMHTkG12DUFbCU+kIqzCzmx/ahOAaDnvpzQq5pOvZKTd+Gb8CTjuTh8e/fe5+wQYlDo/CN55rVbuWp5SHwmKpyV94tldbdfAnNucnrYmbvk9xBsBpGAC3Xi0LJfa2wCQKEd+7Wmu92"
Jul 05 15:58:24 volumio go-librespot[16030]: time="2025-07-05T15:58:24+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Jul 05 15:58:24 volumio go-librespot[16030]: time="2025-07-05T15:58:24+03:00" level=debug msg="completed keyexchange"
Jul 05 15:58:24 volumio go-librespot[16030]: time="2025-07-05T15:58:24+03:00" level=debug msg="completed challenge"
Jul 05 15:58:24 volumio go-librespot[16030]: time="2025-07-05T15:58:24+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jul 05 15:58:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jul 05 15:58:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jul 05 15:58:25 volumio volumio[1221]: info: Initializing connection to go-librespot Websocket
Jul 05 15:58:25 volumio volumio[1221]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jul 05 15:58:27 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Jul 05 15:58:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 478.
Jul 05 15:58:27 volumio systemd[1]: Stopped go-librespot Daemon.
Jul 05 15:58:27 volumio systemd[1]: Started go-librespot Daemon.
Jul 05 15:58:27 volumio go-librespot[16053]: go-librespot daemon starting...
Jul 05 15:58:27 volumio go-librespot[16053]: time="2025-07-05T15:58:27+03:00" level=info msg="running go-librespot 0.2.0"
Jul 05 15:58:27 volumio go-librespot[16053]: time="2025-07-05T15:58:27+03:00" level=debug msg="app state loaded"
Jul 05 15:58:27 volumio go-librespot[16053]: time="2025-07-05T15:58:27+03:00" level=debug msg="stored credentials not found"
Jul 05 15:58:27 volumio go-librespot[16053]: time="2025-07-05T15:58:27+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Jul 05 15:58:27 volumio go-librespot[16053]: time="2025-07-05T15:58:27+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]"
Jul 05 15:58:27 volumio go-librespot[16053]: time="2025-07-05T15:58:27+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Jul 05 15:58:27 volumio go-librespot[16053]: time="2025-07-05T15:58:27+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Jul 05 15:58:27 volumio go-librespot[16053]: time="2025-07-05T15:58:27+03:00" level=info msg="zeroconf server listening on port 44129"
Jul 05 15:58:28 volumio go-librespot[16053]: time="2025-07-05T15:58:28+03:00" level=debug msg="obtained new client token: AACid2OVW5vWvS7yJm7+XZ/wWNsw0MTVOBvaBd76YBdysmr/51Wue6K84RyEK37hcTOKRtXy2UqnFOWlqxOsKMt44GFi1WdZmY+3emlT5xe1I1HmIbs0u7UXomuKPTinV2Smg1jsZfgNTW6y7SPuCuEMgP07W70RMRnk0cQPE2lYH2Jo9srO4DwfRxFG+mMw7DfqFusck8cfDfZNkoftV6VtiRTPLtVqkNwxbOrkWXWa7awYUTPMothAfw=="
Jul 05 15:58:28 volumio go-librespot[16053]: time="2025-07-05T15:58:28+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Jul 05 15:58:28 volumio go-librespot[16053]: time="2025-07-05T15:58:28+03:00" level=debug msg="completed keyexchange"
Jul 05 15:58:28 volumio go-librespot[16053]: time="2025-07-05T15:58:28+03:00" level=debug msg="completed challenge"
Jul 05 15:58:28 volumio go-librespot[16053]: time="2025-07-05T15:58:28+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jul 05 15:58:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jul 05 15:58:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jul 05 15:58:28 volumio volumio[1221]: info: Initializing connection to go-librespot Websocket
Jul 05 15:58:28 volumio volumio[1221]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jul 05 15:58:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Jul 05 15:58:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 479.
Jul 05 15:58:31 volumio systemd[1]: Stopped go-librespot Daemon.
Jul 05 15:58:31 volumio volumio[1221]: info: Initializing connection to go-librespot Websocket
Jul 05 15:58:31 volumio volumio[1221]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jul 05 15:58:31 volumio systemd[1]: Started go-librespot Daemon.
Jul 05 15:58:31 volumio go-librespot[16061]: go-librespot daemon starting...
Jul 05 15:58:31 volumio go-librespot[16061]: time="2025-07-05T15:58:31+03:00" level=info msg="running go-librespot 0.2.0"
Jul 05 15:58:31 volumio go-librespot[16061]: time="2025-07-05T15:58:31+03:00" level=debug msg="app state loaded"
Jul 05 15:58:31 volumio go-librespot[16061]: time="2025-07-05T15:58:31+03:00" level=debug msg="stored credentials not found"
Jul 05 15:58:31 volumio go-librespot[16061]: time="2025-07-05T15:58:31+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Jul 05 15:58:31 volumio go-librespot[16061]: time="2025-07-05T15:58:31+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Jul 05 15:58:31 volumio go-librespot[16061]: time="2025-07-05T15:58:31+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Jul 05 15:58:31 volumio go-librespot[16061]: time="2025-07-05T15:58:31+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Jul 05 15:58:31 volumio go-librespot[16061]: time="2025-07-05T15:58:31+03:00" level=info msg="zeroconf server listening on port 36911"
Jul 05 15:58:31 volumio go-librespot[16061]: time="2025-07-05T15:58:31+03:00" level=debug msg="obtained new client token: AABHzcHVTliDnFXbx5TvReGc5pCWRv03YQ7nGaBrbDrGopif3+qPekvebXW3o7bhY66qkPZxV+xDAec0Un8ZMdtP4h/ZR0psHDQm3DANRVLVXc4sWUeX9RmUiHTt36nYpln1D+9lQNvvUZToZIdVDydR/9Ie/9ERRLUGjlQ0xwXFztxEe5e0aVAza/wDo4iUPlMVzi2l9UGOXyIrUewVaBEpUinfCsXpynXT1Zh0gFrC2JXQUVnlVBqFNuCq"
Jul 05 15:58:31 volumio go-librespot[16061]: time="2025-07-05T15:58:31+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Jul 05 15:58:31 volumio go-librespot[16061]: time="2025-07-05T15:58:31+03:00" level=debug msg="completed keyexchange"
Jul 05 15:58:31 volumio go-librespot[16061]: time="2025-07-05T15:58:31+03:00" level=debug msg="completed challenge"
Jul 05 15:58:32 volumio go-librespot[16061]: time="2025-07-05T15:58:32+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jul 05 15:58:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jul 05 15:58:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jul 05 15:58:33 volumio wpa_supplicant[938]: wlan0: Failed to initiate sched scan
Jul 05 15:58:34 volumio volumio[1221]: info: Initializing connection to go-librespot Websocket
Jul 05 15:58:34 volumio volumio[1221]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jul 05 15:58:35 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Jul 05 15:58:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 480.
Jul 05 15:58:35 volumio systemd[1]: Stopped go-librespot Daemon.
Jul 05 15:58:35 volumio systemd[1]: Started go-librespot Daemon.
Jul 05 15:58:35 volumio go-librespot[16086]: go-librespot daemon starting...
Jul 05 15:58:35 volumio go-librespot[16086]: time="2025-07-05T15:58:35+03:00" level=info msg="running go-librespot 0.2.0"
Jul 05 15:58:35 volumio go-librespot[16086]: time="2025-07-05T15:58:35+03:00" level=debug msg="app state loaded"
Jul 05 15:58:35 volumio go-librespot[16086]: time="2025-07-05T15:58:35+03:00" level=debug msg="stored credentials not found"
Jul 05 15:58:35 volumio go-librespot[16086]: time="2025-07-05T15:58:35+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Jul 05 15:58:35 volumio go-librespot[16086]: time="2025-07-05T15:58:35+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]"
Jul 05 15:58:35 volumio go-librespot[16086]: time="2025-07-05T15:58:35+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Jul 05 15:58:35 volumio go-librespot[16086]: time="2025-07-05T15:58:35+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Jul 05 15:58:35 volumio go-librespot[16086]: time="2025-07-05T15:58:35+03:00" level=info msg="zeroconf server listening on port 35767"
Jul 05 15:58:35 volumio go-librespot[16086]: time="2025-07-05T15:58:35+03:00" level=debug msg="obtained new client token: AAB5m+EQ3Z7XVD3zxSCBrCtHpzNTzN8zHU/8zacBfnO7lyVx39SDTDwFQ5Hs5MbqY9eiaADxwePhj5KXzmuTYLwJK2UQDQIAQR3QvakwslFU971edBdxMl6pbTrKJoxkhmRjO148/WmuNYmBXcIhXM9Nz2TagaWd2cKA5qsdlE2as6/OAZ2/gV/zVydK7GcigLyajikrJjf4JIhGY1x/6z/yQnDSfMtgoMnhouRxF6aPo4A/5Ms6BSWLEbp8"
Jul 05 15:58:35 volumio go-librespot[16086]: time="2025-07-05T15:58:35+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Jul 05 15:58:35 volumio go-librespot[16086]: time="2025-07-05T15:58:35+03:00" level=debug msg="completed keyexchange"
Jul 05 15:58:35 volumio go-librespot[16086]: time="2025-07-05T15:58:35+03:00" level=debug msg="completed challenge"
Jul 05 15:58:35 volumio go-librespot[16086]: time="2025-07-05T15:58:35+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jul 05 15:58:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jul 05 15:58:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jul 05 15:58:37 volumio volumio[1221]: info: Initializing connection to go-librespot Websocket
Jul 05 15:58:37 volumio volumio[1221]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jul 05 15:58:38 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Jul 05 15:58:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 481.
Jul 05 15:58:38 volumio systemd[1]: Stopped go-librespot Daemon.
Jul 05 15:58:38 volumio systemd[1]: Started go-librespot Daemon.
Jul 05 15:58:38 volumio go-librespot[16102]: go-librespot daemon starting...
Jul 05 15:58:38 volumio go-librespot[16102]: time="2025-07-05T15:58:38+03:00" level=info msg="running go-librespot 0.2.0"
Jul 05 15:58:38 volumio go-librespot[16102]: time="2025-07-05T15:58:38+03:00" level=debug msg="app state loaded"
Jul 05 15:58:39 volumio go-librespot[16102]: time="2025-07-05T15:58:39+03:00" level=debug msg="stored credentials not found"
Jul 05 15:58:39 volumio go-librespot[16102]: time="2025-07-05T15:58:39+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Jul 05 15:58:39 volumio go-librespot[16102]: time="2025-07-05T15:58:39+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Jul 05 15:58:39 volumio go-librespot[16102]: time="2025-07-05T15:58:39+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Jul 05 15:58:39 volumio go-librespot[16102]: time="2025-07-05T15:58:39+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Jul 05 15:58:39 volumio go-librespot[16102]: time="2025-07-05T15:58:39+03:00" level=info msg="zeroconf server listening on port 33113"
Jul 05 15:58:39 volumio go-librespot[16102]: time="2025-07-05T15:58:39+03:00" level=debug msg="obtained new client token: AADbenfm8eXa2e+Hh6cXi34xH3CMIApqUs1/B/7Z9/oudCZQt42VBtSdHiXJqsdgKXNUhMkiPdk2EUNkzsca3dApB5sYh1AHRsvLXOQbRbdmgwvQ/GCAyYWyFVzJWbF+TaEOabSBkMyXr1vIWqBtdaGbvNdIrTG+TCl1QSimXDLoEehtF74n61GtUijTpr9pRG5jabjZQM2PKR2GZo4su5rIsdxtqTIj4CyzSf//mjxhwELfp8+kdHDlf32L"
Jul 05 15:58:39 volumio go-librespot[16102]: time="2025-07-05T15:58:39+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Jul 05 15:58:39 volumio go-librespot[16102]: time="2025-07-05T15:58:39+03:00" level=debug msg="completed keyexchange"
Jul 05 15:58:39 volumio go-librespot[16102]: time="2025-07-05T15:58:39+03:00" level=debug msg="completed challenge"
Jul 05 15:58:39 volumio go-librespot[16102]: time="2025-07-05T15:58:39+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jul 05 15:58:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jul 05 15:58:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jul 05 15:58:40 volumio volumio[1221]: info: Initializing connection to go-librespot Websocket
Jul 05 15:58:40 volumio volumio[1221]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jul 05 15:58:42 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Jul 05 15:58:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 482.
Jul 05 15:58:42 volumio systemd[1]: Stopped go-librespot Daemon.
Jul 05 15:58:42 volumio systemd[1]: Started go-librespot Daemon.
Jul 05 15:58:42 volumio go-librespot[16146]: go-librespot daemon starting...
Jul 05 15:58:42 volumio go-librespot[16146]: time="2025-07-05T15:58:42+03:00" level=info msg="running go-librespot 0.2.0"
Jul 05 15:58:42 volumio go-librespot[16146]: time="2025-07-05T15:58:42+03:00" level=debug msg="app state loaded"
Jul 05 15:58:42 volumio go-librespot[16146]: time="2025-07-05T15:58:42+03:00" level=debug msg="stored credentials not found"
Jul 05 15:58:42 volumio go-librespot[16146]: time="2025-07-05T15:58:42+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Jul 05 15:58:42 volumio go-librespot[16146]: time="2025-07-05T15:58:42+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]"
Jul 05 15:58:42 volumio go-librespot[16146]: time="2025-07-05T15:58:42+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Jul 05 15:58:42 volumio go-librespot[16146]: time="2025-07-05T15:58:42+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Jul 05 15:58:42 volumio go-librespot[16146]: time="2025-07-05T15:58:42+03:00" level=info msg="zeroconf server listening on port 33925"
Jul 05 15:58:43 volumio go-librespot[16146]: time="2025-07-05T15:58:43+03:00" level=debug msg="obtained new client token: AABtYHwVLO0GDGt+g1jglNcUznnVnc7B9S8LZh98tq8wagTV8+3bZW05/JGG/ev1ye2XFnjELru+BWWUw7Z8eeqV8yYsSOcYoxloEqub08zC6SsfzIsQoFGLfouNCFh39FtVJ3ZDAUclzb9ksjCsvP6cVCTmqQcto5rqxIjyNhQT2mtRi91XbtKNsgQmv9EYjQE0raO3QgogjZZs918PaqZ6uiDM8Lai97yofWryhsmAP9W49gH+slPZqw=="
Jul 05 15:58:43 volumio go-librespot[16146]: time="2025-07-05T15:58:43+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Jul 05 15:58:43 volumio go-librespot[16146]: time="2025-07-05T15:58:43+03:00" level=debug msg="completed keyexchange"
Jul 05 15:58:43 volumio go-librespot[16146]: time="2025-07-05T15:58:43+03:00" level=debug msg="completed challenge"
Jul 05 15:58:43 volumio go-librespot[16146]: time="2025-07-05T15:58:43+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jul 05 15:58:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jul 05 15:58:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jul 05 15:58:43 volumio volumio[1221]: info: Initializing connection to go-librespot Websocket
Jul 05 15:58:43 volumio volumio[1221]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jul 05 15:58:45 volumio wpa_supplicant[938]: wlan0: Failed to initiate sched scan
Jul 05 15:58:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Jul 05 15:58:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 483.
Jul 05 15:58:46 volumio volumio[1221]: info: Initializing connection to go-librespot Websocket
Jul 05 15:58:46 volumio volumio[1221]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jul 05 15:58:46 volumio systemd[1]: Stopped go-librespot Daemon.
Jul 05 15:58:46 volumio systemd[1]: Started go-librespot Daemon.
Jul 05 15:58:46 volumio go-librespot[16169]: go-librespot daemon starting...
Jul 05 15:58:46 volumio go-librespot[16169]: time="2025-07-05T15:58:46+03:00" level=info msg="running go-librespot 0.2.0"
Jul 05 15:58:46 volumio go-librespot[16169]: time="2025-07-05T15:58:46+03:00" level=debug msg="app state loaded"
Jul 05 15:58:46 volumio go-librespot[16169]: time="2025-07-05T15:58:46+03:00" level=debug msg="stored credentials not found"
Jul 05 15:58:46 volumio go-librespot[16169]: time="2025-07-05T15:58:46+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Jul 05 15:58:46 volumio go-librespot[16169]: time="2025-07-05T15:58:46+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Jul 05 15:58:46 volumio go-librespot[16169]: time="2025-07-05T15:58:46+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Jul 05 15:58:46 volumio go-librespot[16169]: time="2025-07-05T15:58:46+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Jul 05 15:58:46 volumio go-librespot[16169]: time="2025-07-05T15:58:46+03:00" level=info msg="zeroconf server listening on port 44319"
Jul 05 15:58:46 volumio go-librespot[16169]: time="2025-07-05T15:58:46+03:00" level=debug msg="obtained new client token: AAAnzL6PtiD37AIRCY245J0xCxVYU9P32QK78ms8FLt89637XJsahQe+NWMXAmPLnJ13QVN2OyYnlZt/U3+sYeVOFTnQ8tL3CRPPW7DymnOmKSuuLDZ88DoOISEDsHIVZC7MU9sUhY4U2+BhXfwQ0rlrutJ6NW3uiIvNhmalzxHaWYVi49eWY+3iTCHnDWrqn6F0KKZZZ5MUNLBdWQkf6rICVYXrm1YjIXOjXjG9rUsmTrpoUCd7rzuu49Ki"
Jul 05 15:58:46 volumio go-librespot[16169]: time="2025-07-05T15:58:46+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Jul 05 15:58:46 volumio go-librespot[16169]: time="2025-07-05T15:58:46+03:00" level=debug msg="completed keyexchange"
Jul 05 15:58:46 volumio go-librespot[16169]: time="2025-07-05T15:58:46+03:00" level=debug msg="completed challenge"
Jul 05 15:58:47 volumio go-librespot[16169]: time="2025-07-05T15:58:47+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jul 05 15:58:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jul 05 15:58:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jul 05 15:58:47 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 05 15:58:47 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 05 15:58:47 volumio volumio[1221]: info: Discovery: Getting this device information
Jul 05 15:58:47 volumio volumio[1221]: info: CoreCommandRouter::volumioGetState
Jul 05 15:58:47 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 05 15:58:49 volumio volumio-remote-updater[645]: zsync done
Jul 05 15:58:49 volumio volumio[1221]: info: Initializing connection to go-librespot Websocket
Jul 05 15:58:49 volumio volumio[1221]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jul 05 15:58:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Jul 05 15:58:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 484.
Jul 05 15:58:50 volumio systemd[1]: Stopped go-librespot Daemon.
Jul 05 15:58:50 volumio volumio-remote-updater[645]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "2m"
Jul 05 15:58:50 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Jul 05 15:58:50 volumio volumio[1221]: updateProgress
Jul 05 15:58:50 volumio volumio[1221]: {
Jul 05 15:58:50 volumio volumio[1221]: downloadSpeed: '',
Jul 05 15:58:50 volumio volumio[1221]: eta: '2m',
Jul 05 15:58:50 volumio volumio[1221]: progress: 80,
Jul 05 15:58:50 volumio volumio[1221]: status: 'Загрузка нового обновления'
Jul 05 15:58:50 volumio volumio[1221]: }
Jul 05 15:58:50 volumio systemd[1]: Started go-librespot Daemon.
Jul 05 15:58:50 volumio go-librespot[16179]: go-librespot daemon starting...
Jul 05 15:58:50 volumio go-librespot[16179]: time="2025-07-05T15:58:50+03:00" level=info msg="running go-librespot 0.2.0"
Jul 05 15:58:50 volumio go-librespot[16179]: time="2025-07-05T15:58:50+03:00" level=debug msg="app state loaded"
Jul 05 15:58:50 volumio go-librespot[16179]: time="2025-07-05T15:58:50+03:00" level=debug msg="stored credentials not found"
Jul 05 15:58:50 volumio go-librespot[16179]: time="2025-07-05T15:58:50+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Jul 05 15:58:50 volumio go-librespot[16179]: time="2025-07-05T15:58:50+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Jul 05 15:58:50 volumio go-librespot[16179]: time="2025-07-05T15:58:50+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Jul 05 15:58:50 volumio go-librespot[16179]: time="2025-07-05T15:58:50+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Jul 05 15:58:50 volumio go-librespot[16179]: time="2025-07-05T15:58:50+03:00" level=info msg="zeroconf server listening on port 42509"
Jul 05 15:58:50 volumio go-librespot[16179]: time="2025-07-05T15:58:50+03:00" level=debug msg="obtained new client token: AADKQa3nqjIR22QF4e94PIRyv0Npw4RjHD+n2eCrQqY69fULENkxUpElnZkxTTxSIR+49ZtcVxED6OP6+lMUAleDgyOJCD3okj+RipUTDFM21KiT/f07Vto3F+y9NjYOo+467UTQMaqygEcq+gtVzN11O0XMJypiZGjLGnCbb+KjDVX3uHekVB+qMembDO1PSQpGFMFgGomiulw7UwEpc24fIacySlD1ljFJxsA1NcQuJUdx37AQeVU5ip0M"
Jul 05 15:58:52 volumio volumio[1221]: info: Initializing connection to go-librespot Websocket
Jul 05 15:58:52 volumio go-librespot[16179]: time="2025-07-05T15:58:52+03:00" level=debug msg="new websocket client"
Jul 05 15:58:52 volumio volumio[1221]: info: Connection to go-librespot Websocket established
Jul 05 15:58:54 volumio volumio-remote-updater[645]: zsync done
Jul 05 15:58:55 volumio volumio[1221]: info: Getting Spotify volume
Jul 05 15:58:55 volumio volumio[1221]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Jul 05 15:58:55 volumio volumio[1221]: info: CoreCommandRouter::volumioGetState
Jul 05 15:58:55 volumio volumio[1221]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59
Jul 05 15:58:55 volumio go-librespot[16179]: time="2025-07-05T15:58:55+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Jul 05 15:58:55 volumio go-librespot[16179]: time="2025-07-05T15:58:55+03:00" level=debug msg="completed keyexchange"
Jul 05 15:58:55 volumio go-librespot[16179]: time="2025-07-05T15:58:55+03:00" level=debug msg="completed challenge"
Jul 05 15:58:55 volumio go-librespot[16179]: time="2025-07-05T15:58:55+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jul 05 15:58:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jul 05 15:58:55 volumio volumio[1221]: (node:1221) UnhandledPromiseRejectionWarning: Error: socket hang up
Jul 05 15:58:55 volumio volumio[1221]: at connResetException (internal/errors.js:607:14)
Jul 05 15:58:55 volumio volumio[1221]: at Socket.socketOnEnd (_http_client.js:493:23)
Jul 05 15:58:55 volumio volumio[1221]: at Socket.emit (events.js:327:22)
Jul 05 15:58:55 volumio volumio[1221]: at endReadableNT (internal/streams/readable.js:1327:12)
Jul 05 15:58:55 volumio volumio[1221]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Jul 05 15:58:55 volumio volumio[1221]: (node:1221) 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: 38)
Jul 05 15:58:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jul 05 15:58:55 volumio volumio[1221]: info: Connection to go-librespot Websocket closed
Jul 05 15:58:58 volumio wpa_supplicant[938]: wlan0: Failed to initiate sched scan
Jul 05 15:58:58 volumio volumio-remote-updater[645]: PROGRESS: 90, STATUS: "Cleaning old files", ETA: "1m"
Jul 05 15:58:58 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Jul 05 15:58:58 volumio volumio[1221]: updateProgress
Jul 05 15:58:58 volumio volumio[1221]: {
Jul 05 15:58:58 volumio volumio[1221]: downloadSpeed: '',
Jul 05 15:58:58 volumio volumio[1221]: eta: '1m',
Jul 05 15:58:58 volumio volumio[1221]: progress: 90,
Jul 05 15:58:58 volumio volumio[1221]: status: 'Очистка старых файлов'
Jul 05 15:58:58 volumio volumio[1221]: }
Jul 05 15:58:58 volumio volumio-remote-updater[645]: /bin/cp: cannot stat '/data/volumio_factory.sqsh': No such file or directory
Jul 05 15:58:58 volumio volumio-remote-updater[645]: /bin/rm: cannot remove '/data/volumio_factory.sqsh': No such file or directory
Jul 05 15:58:58 volumio volumio-remote-updater[645]: PROGRESS: 95, STATUS: "Finalizing update", ETA: "30s"
Jul 05 15:58:58 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Jul 05 15:58:58 volumio volumio[1221]: updateProgress
Jul 05 15:58:58 volumio volumio[1221]: {
Jul 05 15:58:58 volumio volumio[1221]: downloadSpeed: '',
Jul 05 15:58:58 volumio volumio[1221]: eta: '30s',
Jul 05 15:58:58 volumio volumio[1221]: progress: 95,
Jul 05 15:58:58 volumio volumio[1221]: status: 'Завершение обновления'
Jul 05 15:58:58 volumio volumio[1221]: }
Jul 05 15:58:58 volumio volumio-remote-updater[645]: PROGRESS: 100, STATUS: "success", MESSAGE: "Successfully updated to \"3.819\" version. System restart required."
Jul 05 15:58:58 volumio volumio-remote-updater[645]: No test mode
Jul 05 15:58:58 volumio volumio-remote-updater[645]: No alpha test mode
Jul 05 15:58:58 volumio volumio[1221]: info: Initializing connection to go-librespot Websocket
Jul 05 15:58:58 volumio volumio[1221]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jul 05 15:58:58 volumio volumio-remote-updater[645]: [2025-07-05 15:58:58] [disconnect] Disconnect close local:[1000] remote:[1000]
Jul 05 15:58:58 volumio volumio-remote-updater[645]: [2025-07-05 15:58:58] [info] asio async_write error: system:32 (Broken pipe)
Jul 05 15:58:58 volumio volumio-remote-updater[645]: [2025-07-05 15:58:58] [fatal] handle_write_frame error: websocketpp.transport:2 (Underlying Transport Error)
Jul 05 15:58:58 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Jul 05 15:58:58 volumio volumio[1221]: updateReady
Jul 05 15:58:58 volumio volumio[1221]: {
Jul 05 15:58:58 volumio volumio[1221]: changeLogLink: 'http://volumio.org',
Jul 05 15:58:58 volumio volumio[1221]: description: 'FIXES
\n' +
Jul 05 15:58:58 volumio volumio[1221]: '\n' +
Jul 05 15:58:58 volumio volumio[1221]: '- Disable UI controls during DLNA playback
\n' +
Jul 05 15:58:58 volumio volumio[1221]: '- Fix for Cast functionality halting due to memory filling up
\n' +
Jul 05 15:58:58 volumio volumio[1221]: '- Fix for XML parsing on web-radio search
\n' +
Jul 05 15:58:58 volumio volumio[1221]: '
\n' +
Jul 05 15:58:58 volumio volumio[1221]: 'NEW ADDITIONS
\n' +
Jul 05 15:58:58 volumio volumio[1221]: '\n' +
Jul 05 15:58:58 volumio volumio[1221]: '- Add link for downloading CORRD in the side-menu
\n' +
Jul 05 15:58:58 volumio volumio[1221]: '
\n',
Jul 05 15:58:58 volumio volumio[1221]: title: 'Update v3.819',
Jul 05 15:58:58 volumio volumio[1221]: updateavailable: true
Jul 05 15:58:58 volumio volumio[1221]: }
Jul 05 15:58:58 volumio systemd[1]: volumio-remote-updater.service: Succeeded.
Jul 05 15:58:58 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Jul 05 15:58:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 485.
Jul 05 15:58:58 volumio systemd[1]: Stopped go-librespot Daemon.
Jul 05 15:58:58 volumio systemd[1]: Started go-librespot Daemon.
Jul 05 15:58:58 volumio go-librespot[16223]: go-librespot daemon starting...
Jul 05 15:58:58 volumio go-librespot[16223]: time="2025-07-05T15:58:58+03:00" level=info msg="running go-librespot 0.2.0"
Jul 05 15:58:58 volumio go-librespot[16223]: time="2025-07-05T15:58:58+03:00" level=debug msg="app state loaded"
Jul 05 15:58:58 volumio go-librespot[16223]: time="2025-07-05T15:58:58+03:00" level=debug msg="stored credentials not found"
Jul 05 15:58:58 volumio go-librespot[16223]: time="2025-07-05T15:58:58+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Jul 05 15:58:59 volumio go-librespot[16223]: time="2025-07-05T15:58:59+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Jul 05 15:58:59 volumio go-librespot[16223]: time="2025-07-05T15:58:59+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Jul 05 15:58:59 volumio go-librespot[16223]: time="2025-07-05T15:58:59+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Jul 05 15:58:59 volumio go-librespot[16223]: time="2025-07-05T15:58:59+03:00" level=info msg="zeroconf server listening on port 34769"
Jul 05 15:58:59 volumio systemd[1]: volumio-remote-updater.service: Service RestartSec=100ms expired, scheduling restart.
Jul 05 15:58:59 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1.
Jul 05 15:58:59 volumio systemd[1]: Stopped volumio-remote-updater.service.
Jul 05 15:58:59 volumio systemd[1]: Started volumio-remote-updater.service.
Jul 05 15:58:59 volumio volumio-remote-updater[16231]: Error: No active session
Jul 05 15:58:59 volumio volumio-remote-updater[16231]: [2025-07-05 15:58:59] [connect] Successful connection
Jul 05 15:58:59 volumio volumio-remote-updater[16231]: [2025-07-05 15:58:59] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751720339 101
Jul 05 15:58:59 volumio volumio[1221]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 8
Jul 05 15:58:59 volumio go-librespot[16223]: time="2025-07-05T15:58:59+03:00" level=debug msg="obtained new client token: AABHCV5JnQ280WPcsYqMBNC2KKlNRpr0roaTWg1Xm+A1N+QRknj/XjIRE/0j8DhFRIPdk/1byT5XzFbZtQepX/JYkWCT8qj96Jwox9hTQxxskG2HoRpU4jcPaXgecmshTR/DC8G2/SS+XwBafZgV3qGs3DO7LtsysnOROtP/07NpVuBMu2bsxWGwrb54krRUtTPioCZZxk0SS4FM4v25cnxv/at8ABoFy+1p9RC3FOIx3j7nYgUYFqIUKI6Z"
Jul 05 15:58:59 volumio go-librespot[16223]: time="2025-07-05T15:58:59+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Jul 05 15:58:59 volumio go-librespot[16223]: time="2025-07-05T15:58:59+03:00" level=debug msg="completed keyexchange"
Jul 05 15:58:59 volumio go-librespot[16223]: time="2025-07-05T15:58:59+03:00" level=debug msg="completed challenge"
Jul 05 15:58:59 volumio go-librespot[16223]: time="2025-07-05T15:58:59+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jul 05 15:58:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jul 05 15:58:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jul 05 15:58:59 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Jul 05 15:59:00 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Jul 05 15:59:01 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Jul 05 15:59:01 volumio volumio[1221]: info: Initializing connection to go-librespot Websocket
Jul 05 15:59:01 volumio volumio[1221]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jul 05 15:59:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Jul 05 15:59:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 486.
Jul 05 15:59:02 volumio systemd[1]: Stopped go-librespot Daemon.
Jul 05 15:59:02 volumio systemd[1]: Started go-librespot Daemon.
Jul 05 15:59:02 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Jul 05 15:59:02 volumio go-librespot[16234]: go-librespot daemon starting...
Jul 05 15:59:02 volumio go-librespot[16234]: time="2025-07-05T15:59:02+03:00" level=info msg="running go-librespot 0.2.0"
Jul 05 15:59:02 volumio go-librespot[16234]: time="2025-07-05T15:59:02+03:00" level=debug msg="app state loaded"
Jul 05 15:59:02 volumio go-librespot[16234]: time="2025-07-05T15:59:02+03:00" level=debug msg="stored credentials not found"
Jul 05 15:59:02 volumio go-librespot[16234]: time="2025-07-05T15:59:02+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Jul 05 15:59:02 volumio go-librespot[16234]: time="2025-07-05T15:59:02+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Jul 05 15:59:02 volumio go-librespot[16234]: time="2025-07-05T15:59:02+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Jul 05 15:59:02 volumio go-librespot[16234]: time="2025-07-05T15:59:02+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Jul 05 15:59:02 volumio go-librespot[16234]: time="2025-07-05T15:59:02+03:00" level=info msg="zeroconf server listening on port 43947"
Jul 05 15:59:03 volumio go-librespot[16234]: time="2025-07-05T15:59:03+03:00" level=debug msg="obtained new client token: AAANVMoTWUjIcz1SpAJ39I+Y0/PFZF5QpfLMHSElFDActl5upyY+6kTpK+oRPYI7M4XgCH6ZoGrWhUtuWM5o1O0FkQYYmz9F/lmCCKI+UCsJPWt8lYIE1/mZhCuIjP3OnmSCEBEktmyCFaOjWHT0+rjmrW+q9QxYUzHbthHMGeuC86kqu9gaW5isXMauJK7DLtLEL5mKU49tq/n/aBhgvSn5+62P1xiVXHoT+9kQmK/5WHaTLLqi/ZMBYw=="
Jul 05 15:59:03 volumio go-librespot[16234]: time="2025-07-05T15:59:03+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Jul 05 15:59:03 volumio go-librespot[16234]: time="2025-07-05T15:59:03+03:00" level=debug msg="completed keyexchange"
Jul 05 15:59:03 volumio go-librespot[16234]: time="2025-07-05T15:59:03+03:00" level=debug msg="completed challenge"
Jul 05 15:59:03 volumio go-librespot[16234]: time="2025-07-05T15:59:03+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jul 05 15:59:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jul 05 15:59:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jul 05 15:59:03 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Jul 05 15:59:04 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Jul 05 15:59:04 volumio volumio[1221]: info: Initializing connection to go-librespot Websocket
Jul 05 15:59:04 volumio volumio[1221]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jul 05 15:59:05 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Jul 05 15:59:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Jul 05 15:59:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 487.
Jul 05 15:59:06 volumio systemd[1]: Stopped go-librespot Daemon.
Jul 05 15:59:06 volumio systemd[1]: Started go-librespot Daemon.
Jul 05 15:59:06 volumio go-librespot[16256]: go-librespot daemon starting...
Jul 05 15:59:06 volumio go-librespot[16256]: time="2025-07-05T15:59:06+03:00" level=info msg="running go-librespot 0.2.0"
Jul 05 15:59:06 volumio go-librespot[16256]: time="2025-07-05T15:59:06+03:00" level=debug msg="app state loaded"
Jul 05 15:59:06 volumio go-librespot[16256]: time="2025-07-05T15:59:06+03:00" level=debug msg="stored credentials not found"
Jul 05 15:59:06 volumio go-librespot[16256]: time="2025-07-05T15:59:06+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Jul 05 15:59:06 volumio go-librespot[16256]: time="2025-07-05T15:59:06+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Jul 05 15:59:06 volumio go-librespot[16256]: time="2025-07-05T15:59:06+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Jul 05 15:59:06 volumio go-librespot[16256]: time="2025-07-05T15:59:06+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Jul 05 15:59:06 volumio go-librespot[16256]: time="2025-07-05T15:59:06+03:00" level=info msg="zeroconf server listening on port 38129"
Jul 05 15:59:06 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Jul 05 15:59:06 volumio go-librespot[16256]: time="2025-07-05T15:59:06+03:00" level=debug msg="obtained new client token: AADv24WRcI/wN4GfODi9hNcFh/iWIMV2PiNxZwKsurClowVgIm6i6H76bDQPnP4ytaPlc+Z+a+v4a5Lz8DykspTTcnopJW4UO6lTMQ0RV52jgGyPR6Bq5yYFWw+RaZvzwqOXkYJHB8lmUY0ZNxNeL9oQOQy7FTdj4LnUJ4OKXt/DyXgc0uLZJBEsJKqwn2EfJjx8WCtoUcSKyVlhIN9hm77Kt9tAvspGCa0hEJP88eIp5fYLPbKV27B6/Why"
Jul 05 15:59:06 volumio go-librespot[16256]: time="2025-07-05T15:59:06+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Jul 05 15:59:06 volumio go-librespot[16256]: time="2025-07-05T15:59:06+03:00" level=debug msg="completed keyexchange"
Jul 05 15:59:06 volumio go-librespot[16256]: time="2025-07-05T15:59:06+03:00" level=debug msg="completed challenge"
Jul 05 15:59:07 volumio go-librespot[16256]: time="2025-07-05T15:59:07+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jul 05 15:59:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jul 05 15:59:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jul 05 15:59:07 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Jul 05 15:59:07 volumio volumio[1221]: info: Initializing connection to go-librespot Websocket
Jul 05 15:59:07 volumio volumio[1221]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jul 05 15:59:08 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Jul 05 15:59:09 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Jul 05 15:59:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Jul 05 15:59:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 488.
Jul 05 15:59:10 volumio systemd[1]: Stopped go-librespot Daemon.
Jul 05 15:59:10 volumio systemd[1]: Started go-librespot Daemon.
Jul 05 15:59:10 volumio go-librespot[16267]: go-librespot daemon starting...
Jul 05 15:59:10 volumio go-librespot[16267]: time="2025-07-05T15:59:10+03:00" level=info msg="running go-librespot 0.2.0"
Jul 05 15:59:10 volumio go-librespot[16267]: time="2025-07-05T15:59:10+03:00" level=debug msg="app state loaded"
Jul 05 15:59:10 volumio go-librespot[16267]: time="2025-07-05T15:59:10+03:00" level=debug msg="stored credentials not found"
Jul 05 15:59:10 volumio go-librespot[16267]: time="2025-07-05T15:59:10+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Jul 05 15:59:10 volumio wpa_supplicant[938]: wlan0: Failed to initiate sched scan
Jul 05 15:59:10 volumio go-librespot[16267]: time="2025-07-05T15:59:10+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]"
Jul 05 15:59:10 volumio go-librespot[16267]: time="2025-07-05T15:59:10+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Jul 05 15:59:10 volumio go-librespot[16267]: time="2025-07-05T15:59:10+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Jul 05 15:59:10 volumio go-librespot[16267]: time="2025-07-05T15:59:10+03:00" level=info msg="zeroconf server listening on port 46613"
Jul 05 15:59:10 volumio go-librespot[16267]: time="2025-07-05T15:59:10+03:00" level=debug msg="obtained new client token: AADf2qa1gK4J+04v3E1ycpkzLr7z4PkvGnMYfVg6e8Hr2gbaTpEhYHtYfSmbDJAiPCQO7JkwRtbQlP88waNpaebD1Cgwv5Ie3ZjTBn0h+jlp/vbS66Gs86PfKNnxIPQyAYBi0eSzZCk3Bu10LuKYVR+8Ipe/JaDpcDw/f8TdtVKgMklFEVgs50vCe6vvCNOhVp6oHii52ZQ9PYUJWy6PiaND+Kuc8eDnt9i+sOtbHKSkpd98tyl+7qu1mabw"
Jul 05 15:59:10 volumio go-librespot[16267]: time="2025-07-05T15:59:10+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Jul 05 15:59:10 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Jul 05 15:59:10 volumio go-librespot[16267]: time="2025-07-05T15:59:10+03:00" level=debug msg="completed keyexchange"
Jul 05 15:59:10 volumio go-librespot[16267]: time="2025-07-05T15:59:10+03:00" level=debug msg="completed challenge"
Jul 05 15:59:10 volumio go-librespot[16267]: time="2025-07-05T15:59:10+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jul 05 15:59:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jul 05 15:59:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jul 05 15:59:10 volumio volumio[1221]: info: Initializing connection to go-librespot Websocket
Jul 05 15:59:10 volumio volumio[1221]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jul 05 15:59:11 volumio volumio[1221]: info:
Jul 05 15:59:11 volumio volumio[1221]: ---------------------------- MPD announces system playlist update
Jul 05 15:59:11 volumio volumio[1221]: info: Ignoring MPD Status Update
Jul 05 15:59:11 volumio volumio[1221]: info:
Jul 05 15:59:11 volumio volumio[1221]: ---------------------------- MPD announces state update: player
Jul 05 15:59:11 volumio volumio[1221]: info: ControllerMpd::getState
Jul 05 15:59:11 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status
Jul 05 15:59:11 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Jul 05 15:59:11 volumio volumio[1221]: info: ------------------------------ 3ms
Jul 05 15:59:11 volumio volumio[1221]: info: sendMpdCommand status took 2 milliseconds
Jul 05 15:59:11 volumio volumio[1221]: verbose: ControllerMpd::parseState
Jul 05 15:59:11 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 05 15:59:11 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 1 milliseconds
Jul 05 15:59:11 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo
Jul 05 15:59:11 volumio volumio[1221]: info: ControllerMpd::pushState
Jul 05 15:59:11 volumio volumio[1221]: info: CoreCommandRouter::servicePushState
Jul 05 15:59:11 volumio volumio[1221]: info: CorePlayQueue::getTrack 0
Jul 05 15:59:11 volumio volumio[1221]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1881253,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Radio Jazz - www.radiojazz.ua","artist":"Radio Jazz","album":null,"uri":"http://online.radiojazz.ua/RadioJazz_HD","trackType":""}
Jul 05 15:59:11 volumio volumio[1221]: verbose: CURRENT POSITION 0
Jul 05 15:59:11 volumio volumio[1221]: info: CoreStateMachine::syncState stateService play
Jul 05 15:59:11 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus play
Jul 05 15:59:11 volumio volumio[1221]: info: Received an update from plugin. extracting info from payload
Jul 05 15:59:11 volumio volumio[1221]: info: CoreStateMachine::pushState
Jul 05 15:59:11 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 05 15:59:11 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState
Jul 05 15:59:11 volumio volumio[1221]: info: CoreStateMachine::pushState
Jul 05 15:59:11 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState
Jul 05 15:59:11 volumio volumio[1221]: info: ------------------------------ 9ms
Jul 05 15:59:11 volumio volumio[1221]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59
Jul 05 15:59:11 volumio volumio[1221]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59
Jul 05 15:59:12 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Jul 05 15:59:13 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Jul 05 15:59:13 volumio volumio[1221]: info: Initializing connection to go-librespot Websocket
Jul 05 15:59:13 volumio volumio[1221]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jul 05 15:59:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Jul 05 15:59:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 489.
Jul 05 15:59:13 volumio systemd[1]: Stopped go-librespot Daemon.
Jul 05 15:59:13 volumio systemd[1]: Started go-librespot Daemon.
Jul 05 15:59:13 volumio go-librespot[16275]: go-librespot daemon starting...
Jul 05 15:59:13 volumio go-librespot[16275]: time="2025-07-05T15:59:13+03:00" level=info msg="running go-librespot 0.2.0"
Jul 05 15:59:13 volumio go-librespot[16275]: time="2025-07-05T15:59:13+03:00" level=debug msg="app state loaded"
Jul 05 15:59:13 volumio go-librespot[16275]: time="2025-07-05T15:59:13+03:00" level=debug msg="stored credentials not found"
Jul 05 15:59:13 volumio go-librespot[16275]: time="2025-07-05T15:59:13+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Jul 05 15:59:14 volumio go-librespot[16275]: time="2025-07-05T15:59:14+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]"
Jul 05 15:59:14 volumio go-librespot[16275]: time="2025-07-05T15:59:14+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Jul 05 15:59:14 volumio go-librespot[16275]: time="2025-07-05T15:59:14+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Jul 05 15:59:14 volumio go-librespot[16275]: time="2025-07-05T15:59:14+03:00" level=info msg="zeroconf server listening on port 35903"
Jul 05 15:59:14 volumio go-librespot[16275]: time="2025-07-05T15:59:14+03:00" level=debug msg="obtained new client token: AABySXlnotCKQcVZ4awz45zWYpWe67saGnokSfrkTGXUuswzhSKYz6PFpvHy6PyvLAW4BQEVUAUY9r5PsbR135S52mGZH5wJJf8AHqIq/M5219z8DIJto45WUsTWHzm1H9PF2/RGiUBMSmdKjFbBy7mIJvaqqM/PMO15muQoKCitUivRp5AJTWn+Rdm+qOl0r8/eieUhPMpKUH/EoCVQKGCLMy7XWeJPCUt8DZQd4LO/tcYRvrRSqZbsYmX+"
Jul 05 15:59:14 volumio go-librespot[16275]: time="2025-07-05T15:59:14+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Jul 05 15:59:14 volumio go-librespot[16275]: time="2025-07-05T15:59:14+03:00" level=debug msg="completed keyexchange"
Jul 05 15:59:14 volumio go-librespot[16275]: time="2025-07-05T15:59:14+03:00" level=debug msg="completed challenge"
Jul 05 15:59:14 volumio go-librespot[16275]: time="2025-07-05T15:59:14+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jul 05 15:59:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jul 05 15:59:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jul 05 15:59:14 volumio volumio[1221]: info: CoreCommandRouter::Close All Modals sent
Jul 05 15:59:14 volumio volumio[1221]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________
Jul 05 15:59:14 volumio volumio[1221]: info: PLUGIN onReboot : networkfs
Jul 05 15:59:14 volumio sudo[16301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/X
Jul 05 15:59:14 volumio sudo[16301]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 05 15:59:14 volumio sudo[16301]: pam_unix(sudo:session): session closed for user root
Jul 05 15:59:14 volumio volumio[1221]: umount: /mnt/NAS/X: not mounted.
Jul 05 15:59:14 volumio volumio[1221]: error: Cannot umount share X : Error: Command failed: /usr/bin/sudo /bin/umount -f /mnt/NAS/X
Jul 05 15:59:14 volumio volumio[1221]: umount: /mnt/NAS/X: not mounted.
Jul 05 15:59:14 volumio volumio[1221]: info: PLUGIN onReboot : audiophonicsonoff
Jul 05 15:59:14 volumio volumio[1221]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 05 15:59:14 volumio volumio[1221]: TypeError: Cannot read property 'writeSync' of undefined
Jul 05 15:59:14 volumio volumio[1221]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_hardware/audiophonicsonoff/index.js:40:25)
Jul 05 15:59:14 volumio volumio[1221]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30)
Jul 05 15:59:14 volumio volumio[1221]: at HashMap. (/volumio/app/pluginmanager.js:668:31)
Jul 05 15:59:14 volumio volumio[1221]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10)
Jul 05 15:59:14 volumio volumio[1221]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7)
Jul 05 15:59:14 volumio volumio[1221]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20)
Jul 05 15:59:14 volumio volumio[1221]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22)
Jul 05 15:59:14 volumio volumio[1221]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/updater_comm/index.js:156:35)
Jul 05 15:59:14 volumio volumio[1221]: at listOnTimeout (internal/timers.js:554:17)
Jul 05 15:59:14 volumio volumio[1221]: at processTimers (internal/timers.js:497:7)
Jul 05 15:59:14 volumio volumio[1221]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 05 15:59:15 volumio sudo[16312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-05 15:58
Jul 05 15:59:15 volumio sudo[16312]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST"
VOLUMIO_VERSION="3.816"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"