-- Logs begin at Sun 2024-03-03 06:40:54 UTC, end at Sat 2024-04-06 16:17:43 UTC. -- Apr 06 16:16:34 volumio ntpd[713]: receive: Unexpected origin timestamp 0xe98e9a46.e101cd87 does not match aorg 0000000000.00000000 from server@193.93.164.195 xmt 0xe9bbf3e2.40768951 Apr 06 16:16:34 volumio ntpd[713]: receive: Unexpected origin timestamp 0xe98e9a46.e100ac5f does not match aorg 0000000000.00000000 from server@193.239.214.226 xmt 0xe9bbf3e2.3f47d4b2 Apr 06 16:16:34 volumio systemd[1]: Starting Daily apt download activities... Apr 06 16:16:34 volumio volumio[775]: info: Getting Spotify volume Apr 06 16:16:34 volumio volumio[775]: (node:775) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 06 16:16:34 volumio volumio[775]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 06 16:16:34 volumio volumio[775]: (node:775) 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: 2) Apr 06 16:16:34 volumio volumio[775]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Apr 06 16:16:34 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:16:34 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:16:35 volumio volumio[775]: info: Initializing connection to go-librespot Websocket Apr 06 16:16:35 volumio volumio[775]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 06 16:16:35 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 06 16:16:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Apr 06 16:16:35 volumio systemd[1]: Stopped go-librespot Daemon. Apr 06 16:16:35 volumio systemd[1]: Started go-librespot Daemon. Apr 06 16:16:35 volumio go-librespot[1156]: Librespot-go daemon starting... Apr 06 16:16:35 volumio go-librespot[1156]: time="2024-04-06T16:16:35Z" level=info msg="generated new device id: 4529f6b4cd5e6c277c9b79f94b73f9075939fe61" Apr 06 16:16:35 volumio go-librespot[1156]: time="2024-04-06T16:16:35Z" level=debug msg="stored credentials found for radiopokopokito" Apr 06 16:16:35 volumio go-librespot[1156]: time="2024-04-06T16:16:35Z" level=debug msg="obtained new client token: AABvx371FcX1pZMZEmlLJkTSi5hZ6QPTtL4bB3Zl8VWmxYrY2CiiNjch5sBW6ufJ/W0cDQrV77w4IjReDz4E16BUMGb+VepShf9OLH3Od7vHtbKqSR2DIb8SJKaRzqgnykkhKAL7X/HgGuAlzNleOCiZ8WwSgrN0wnCUcJgKdqCcal2L7UT7s7n1bbkmuiSMysJjFAUYb6ZqwFz5fyMDpGvTAHWWbg5HlpwVlCUlkqdMcSr7LiN9Cbp1cPt8zHU=" Apr 06 16:16:35 volumio go-librespot[1156]: time="2024-04-06T16:16:35Z" 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]" Apr 06 16:16:35 volumio go-librespot[1156]: time="2024-04-06T16:16:35Z" level=debug msg="completed keyexchange" Apr 06 16:16:36 volumio go-librespot[1156]: time="2024-04-06T16:16:36Z" level=debug msg="completed challenge" Apr 06 16:16:36 volumio go-librespot[1156]: time="2024-04-06T16:16:36Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 06 16:16:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 06 16:16:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 06 16:16:36 volumio systemd[1]: apt-daily.service: Succeeded. Apr 06 16:16:36 volumio systemd[1]: Started Daily apt download activities. Apr 06 16:16:36 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Apr 06 16:16:37 volumio volumio[775]: verbose: New Socket.io Connection to 192.168.2.180 from 192.168.2.13 UA: okhttp/4.9.2 Total Clients: 4 Apr 06 16:16:37 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Apr 06 16:16:37 volumio systemd[1]: Started Daily apt upgrade and clean activities. Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 06 16:16:37 volumio volumio[775]: info: Adding plugin bluetooth to MyMusic Plugins Apr 06 16:16:37 volumio volumio[775]: info: Adding plugin multiroom to MyMusic Plugins Apr 06 16:16:37 volumio volumio[775]: info: Adding plugin metavolumio to MyMusic Plugins Apr 06 16:16:37 volumio volumio[775]: info: Adding plugin cd_controller to MyMusic Plugins Apr 06 16:16:37 volumio volumio[775]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 06 16:16:37 volumio volumio[775]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 06 16:16:37 volumio volumio[775]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 06 16:16:38 volumio volumio[775]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 06 16:16:38 volumio volumio[775]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 06 16:16:38 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:38 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:38 volumio volumio[775]: info: Starting MyVolumio Remote Streaming Endpoints Apr 06 16:16:38 volumio volumio[775]: info: MyVolumio login type: Token Apr 06 16:16:38 volumio volumio[775]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 06 16:16:38 volumio volumio[775]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 06 16:16:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 06 16:16:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Apr 06 16:16:39 volumio systemd[1]: Stopped go-librespot Daemon. Apr 06 16:16:39 volumio systemd[1]: Started go-librespot Daemon. Apr 06 16:16:39 volumio go-librespot[1243]: Librespot-go daemon starting... Apr 06 16:16:39 volumio go-librespot[1243]: time="2024-04-06T16:16:39Z" level=info msg="generated new device id: 0b6e5f2fba561967c214d805d0278394d535a80d" Apr 06 16:16:39 volumio go-librespot[1243]: time="2024-04-06T16:16:39Z" level=debug msg="stored credentials found for radiopokopokito" Apr 06 16:16:39 volumio volumio[775]: info: Starting Streaming Service Transparent Proxy Apr 06 16:16:39 volumio volumio[775]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 06 16:16:39 volumio volumio[775]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 06 16:16:39 volumio volumio[775]: info: Streaming services startup Apr 06 16:16:39 volumio volumio[775]: info: Starting Streaming Daemon Apr 06 16:16:39 volumio sudo[1258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 06 16:16:39 volumio sudo[1258]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:16:39 volumio volumio[775]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 06 16:16:39 volumio sudo[1258]: pam_unix(sudo:session): session closed for user root Apr 06 16:16:39 volumio volumio[775]: info: Initializing connection to go-librespot Websocket Apr 06 16:16:39 volumio volumio[775]: error: Cannot start Volumio Streaming Daemon Apr 06 16:16:39 volumio volumio[775]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 06 16:16:39 volumio volumio[775]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 06 16:16:39 volumio go-librespot[1243]: time="2024-04-06T16:16:39Z" level=debug msg="new websocket client" Apr 06 16:16:39 volumio volumio[775]: info: Connection to go-librespot Websocket established Apr 06 16:16:39 volumio go-librespot[1243]: time="2024-04-06T16:16:39Z" level=debug msg="obtained new client token: AACTgdZhDPWezir4iV5/1KX8AUvvIhr8om9Fa1z0kodmF2xkeODhatV0Z649qIN5wx2RrEbcdxhHSuhzcTd2+9f8qDbLsMAo2htKPTTnigMZZRErXIsVHS9OIMp6qWZwngcfhd7rE1J03Qv0pXjzewvAtKvX+QNK4W0aynOeslhh5j+4vqmdGWFS3/MrFffvgnaGY0vxE43IJCzWAov0p/VNFUjHs3OgKhZFVWxVq9o0awHgJpth4tC5FSLvJAY=" Apr 06 16:16:39 volumio go-librespot[1243]: time="2024-04-06T16:16:39Z" 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]" Apr 06 16:16:40 volumio go-librespot[1243]: time="2024-04-06T16:16:40Z" level=debug msg="completed keyexchange" Apr 06 16:16:40 volumio volumio[775]: STREAMING PROXY: Starting server on port 3245 Apr 06 16:16:40 volumio volumio[775]: Node JS runtime: 14 Apr 06 16:16:40 volumio volumio[775]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 06 16:16:40 volumio go-librespot[1243]: time="2024-04-06T16:16:40Z" level=debug msg="completed challenge" Apr 06 16:16:40 volumio go-librespot[1243]: time="2024-04-06T16:16:40Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 06 16:16:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 06 16:16:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 06 16:16:40 volumio volumio[775]: info: Connection to go-librespot Websocket closed Apr 06 16:16:40 volumio volumio[775]: info: MyVolumio token set successfully Apr 06 16:16:40 volumio volumio[775]: info: MYVOLUMIO: Adding device Apr 06 16:16:40 volumio volumio[775]: info: MYVOLUMIO: Evaluating Server Apr 06 16:16:41 volumio volumio[775]: info: MyVolumio status changed Apr 06 16:16:41 volumio volumio[775]: info: Streaming services startup Apr 06 16:16:41 volumio volumio[775]: info: Starting Streaming Daemon Apr 06 16:16:41 volumio volumio[775]: info: Removing browser output: myVolumio user plan is not superstar Apr 06 16:16:41 volumio volumio[775]: info: Removing audio output: Apr 06 16:16:41 volumio volumio[775]: info: Stoppping Tunnel 1 Apr 06 16:16:41 volumio sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 06 16:16:41 volumio sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:16:41 volumio sudo[1283]: pam_unix(sudo:session): session closed for user root Apr 06 16:16:41 volumio sudo[1286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 06 16:16:41 volumio volumio[775]: info: Discovery: A device disappeared from network Apr 06 16:16:41 volumio volumio[775]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Apr 06 16:16:41 volumio sudo[1286]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:16:41 volumio volumio[775]: error: Cannot start Volumio Streaming Daemon Apr 06 16:16:41 volumio volumio[775]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 06 16:16:41 volumio volumio[775]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 06 16:16:41 volumio sudo[1286]: pam_unix(sudo:session): session closed for user root Apr 06 16:16:41 volumio volumio[775]: info: Remote SSH Stopped Apr 06 16:16:41 volumio volumio[775]: verbose: New Socket.io Connection to 192.168.2.180 from 192.168.2.13 UA: okhttp/4.9.2 Total Clients: 5 Apr 06 16:16:41 volumio volumio[775]: verbose: New Socket.io Connection to 192.168.2.180 from 192.168.2.13 UA: okhttp/4.9.2 Total Clients: 6 Apr 06 16:16:41 volumio volumio[775]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Apr 06 16:16:41 volumio volumio[775]: info: Setting Geolocation for MyVolumio to eu7 Apr 06 16:16:41 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:41 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:41 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:42 volumio volumio[775]: info: Successfully Added MyVolumio device Apr 06 16:16:42 volumio volumio[775]: info: Updating MyVolumio device info Apr 06 16:16:42 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:42 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:42 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:42 volumio volumio[775]: info: Getting Spotify volume Apr 06 16:16:42 volumio volumio[775]: (node:775) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 06 16:16:42 volumio volumio[775]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 06 16:16:42 volumio volumio[775]: (node:775) 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: 3) Apr 06 16:16:42 volumio volumio[775]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Apr 06 16:16:42 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:16:42 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:16:42 volumio volumio[775]: info: Successfully Updated MyVolumio device Apr 06 16:16:43 volumio volumio[775]: info: Initializing connection to go-librespot Websocket Apr 06 16:16:43 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 06 16:16:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Apr 06 16:16:43 volumio systemd[1]: Stopped go-librespot Daemon. Apr 06 16:16:43 volumio volumio[775]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 06 16:16:43 volumio systemd[1]: Started go-librespot Daemon. Apr 06 16:16:43 volumio go-librespot[1292]: Librespot-go daemon starting... Apr 06 16:16:43 volumio go-librespot[1292]: time="2024-04-06T16:16:43Z" level=info msg="generated new device id: f158a5fa4473aa5ebcf7c5d20c8167ed1787769c" Apr 06 16:16:43 volumio go-librespot[1292]: time="2024-04-06T16:16:43Z" level=debug msg="stored credentials found for radiopokopokito" Apr 06 16:16:43 volumio go-librespot[1292]: time="2024-04-06T16:16:43Z" level=debug msg="obtained new client token: AABOW2ws7rooRdYSG1j7Oyijf0rqznD/w7/mw3wOTNEWR4rVM9bL2/gbaGNFlsQJUr3O1Rk2TMWqq/nBQOg+eUOpIjFBs54gRl0KDp5R0J1zMNzjrwRc9+AjoCvQ9kiiT2sTbH7e56p2ABDuyZGh9HTxpO36uUyueTze/UeAnXUCCpD/o9QLX+rcMVWX73vBzMITWt5ttXZl1H+3At4/vnyKqw6el5lRCbG71smLt0vk/LMohTvM0LbgS+fZgtQ=" Apr 06 16:16:44 volumio go-librespot[1292]: time="2024-04-06T16:16:44Z" 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]" Apr 06 16:16:44 volumio go-librespot[1292]: time="2024-04-06T16:16:44Z" level=debug msg="completed keyexchange" Apr 06 16:16:44 volumio volumio[775]: verbose: New Socket.io Connection to 192.168.2.180 from 192.168.2.13 UA: okhttp/4.9.2 Total Clients: 7 Apr 06 16:16:44 volumio go-librespot[1292]: time="2024-04-06T16:16:44Z" level=debug msg="completed challenge" Apr 06 16:16:44 volumio go-librespot[1292]: time="2024-04-06T16:16:44Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 06 16:16:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 06 16:16:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 06 16:16:45 volumio volumio[775]: verbose: New Socket.io Connection to 192.168.2.180 from 192.168.2.13 UA: okhttp/4.9.2 Total Clients: 8 Apr 06 16:16:45 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:16:45 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:16:45 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 06 16:16:45 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 06 16:16:46 volumio volumio[775]: info: Initializing connection to go-librespot Websocket Apr 06 16:16:46 volumio volumio[775]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 06 16:16:46 volumio sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 06 16:16:46 volumio sudo[1301]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:16:46 volumio sudo[1301]: pam_unix(sudo:session): session closed for user root Apr 06 16:16:46 volumio sudo[1304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 06 16:16:46 volumio sudo[1304]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:16:46 volumio sudo[1304]: pam_unix(sudo:session): session closed for user root Apr 06 16:16:46 volumio volumio[775]: verbose: New Socket.io Connection to 192.168.2.180 from 192.168.2.13 UA: okhttp/4.9.2 Total Clients: 9 Apr 06 16:16:46 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 06 16:16:46 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 06 16:16:46 volumio volumio[775]: info: CoreCommandRouter::volumioGetVisibleSources Apr 06 16:16:46 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 06 16:16:46 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:16:46 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:16:46 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 06 16:16:47 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 06 16:16:47 volumio volumio[775]: info: Received Get System Info Apr 06 16:16:47 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 06 16:16:47 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 06 16:16:47 volumio volumio[775]: info: Discovery: Getting this device information Apr 06 16:16:47 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:16:47 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:16:47 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 06 16:16:47 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:16:47 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:16:47 volumio volumio[775]: info: Listing playlists Apr 06 16:16:47 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 06 16:16:47 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 06 16:16:47 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 06 16:16:48 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 06 16:16:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Apr 06 16:16:48 volumio systemd[1]: Stopped go-librespot Daemon. Apr 06 16:16:48 volumio systemd[1]: Started go-librespot Daemon. Apr 06 16:16:48 volumio go-librespot[1306]: Librespot-go daemon starting... Apr 06 16:16:48 volumio go-librespot[1306]: time="2024-04-06T16:16:48Z" level=info msg="generated new device id: 2283f4dcb29008b9f1d9f3389460506bfdb9f347" Apr 06 16:16:48 volumio go-librespot[1306]: time="2024-04-06T16:16:48Z" level=debug msg="stored credentials found for radiopokopokito" Apr 06 16:16:48 volumio go-librespot[1306]: time="2024-04-06T16:16:48Z" level=debug msg="obtained new client token: AAA8wCytIp1NJ1OtDMtaaCEJIC+EbmR2P1bZh6Wu8kPOw9QCK7LuFzVtxp84wyIYG9f2/3wBkfxTh1ukGPA9mfzLWvt8FsNJxLmJP5q5kzoV+rXRX36DersTk3wMx5iYOzkz+ONvL/E1Fm5r4MIULcd69PTM9QjhgxL0tReub3Vt8K+aYu/USZPy0KL5MMRsjEoEWM3xrnIcQjmzXEE2VAzQZ9bkrlnpXVtxj3QQo3MSEJA5wuMtprZVKO29" Apr 06 16:16:48 volumio go-librespot[1306]: time="2024-04-06T16:16:48Z" 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]" Apr 06 16:16:48 volumio go-librespot[1306]: time="2024-04-06T16:16:48Z" level=debug msg="completed keyexchange" Apr 06 16:16:48 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 06 16:16:48 volumio volumio[775]: info: Received Get System Info Apr 06 16:16:48 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 06 16:16:48 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 06 16:16:48 volumio volumio[775]: info: Discovery: Getting this device information Apr 06 16:16:48 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:16:48 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:16:48 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 06 16:16:48 volumio volumio[775]: info: CoreCommandRouter::volumioGetQueue Apr 06 16:16:48 volumio volumio[775]: info: CoreStateMachine::getQueue Apr 06 16:16:48 volumio volumio[775]: info: CorePlayQueue::getQueue Apr 06 16:16:49 volumio go-librespot[1306]: time="2024-04-06T16:16:49Z" level=debug msg="completed challenge" Apr 06 16:16:49 volumio go-librespot[1306]: time="2024-04-06T16:16:49Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 06 16:16:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 06 16:16:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 06 16:16:49 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 06 16:16:49 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 06 16:16:49 volumio volumio[775]: info: Discovery: Getting this device information Apr 06 16:16:49 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:16:49 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:16:49 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 06 16:16:49 volumio volumio[775]: info: Initializing connection to go-librespot Websocket Apr 06 16:16:49 volumio volumio[775]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 06 16:16:49 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 06 16:16:50 volumio volumio[775]: info: Received Get System Info Apr 06 16:16:50 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 06 16:16:50 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 06 16:16:50 volumio volumio[775]: info: Discovery: Getting this device information Apr 06 16:16:50 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:16:50 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:16:50 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 06 16:16:50 volumio volumio[775]: info: Preload queue cleared Apr 06 16:16:50 volumio volumio[775]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 06 16:16:50 volumio volumio[775]: info: CoreStateMachine::ClearQueue Apr 06 16:16:50 volumio volumio[775]: info: CoreStateMachine::stop Apr 06 16:16:50 volumio volumio[775]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 16:16:50 volumio volumio[775]: info: CorePlayQueue::clearPlayQueue Apr 06 16:16:50 volumio volumio[775]: info: CorePlayQueue::saveQueue Apr 06 16:16:50 volumio volumio[775]: info: CoreCommandRouter::volumioPushQueue Apr 06 16:16:50 volumio volumio[775]: info: CoreStateMachine::addQueueItems Apr 06 16:16:50 volumio volumio[775]: info: CorePlayQueue::addQueueItems Apr 06 16:16:50 volumio volumio[775]: info: Preload queue cleared Apr 06 16:16:50 volumio volumio[775]: info: Adding Item to queue: music-library/USB/Elements/PLAYLISTS 2024/Sposa Son Disprezzata Apr 06 16:16:50 volumio volumio[775]: info: Exploding uri music-library/USB/Elements/PLAYLISTS 2024/Sposa Son Disprezzata in service mpd Apr 06 16:16:50 volumio volumio[775]: info: CoreCommandRouter::volumioPushQueue Apr 06 16:16:50 volumio volumio[775]: info: CorePlayQueue::saveQueue Apr 06 16:16:50 volumio volumio[775]: info: CoreStateMachine::updateTrackBlock Apr 06 16:16:50 volumio volumio[775]: info: CorePlayQueue::getTrackBlock Apr 06 16:16:50 volumio volumio[775]: info: CoreCommandRouter::volumioPlay Apr 06 16:16:50 volumio volumio[775]: info: CoreStateMachine::play index 0 Apr 06 16:16:50 volumio volumio[775]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 16:16:50 volumio volumio[775]: info: CoreStateMachine::stop Apr 06 16:16:50 volumio volumio[775]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 16:16:50 volumio volumio[775]: info: CoreStateMachine::play index undefined Apr 06 16:16:50 volumio volumio[775]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 16:16:50 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:16:51 volumio volumio[775]: info: Preload queue cleared Apr 06 16:16:51 volumio volumio[775]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 06 16:16:51 volumio volumio[775]: info: CoreStateMachine::ClearQueue Apr 06 16:16:51 volumio volumio[775]: info: CoreStateMachine::stop Apr 06 16:16:51 volumio volumio[775]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 16:16:51 volumio volumio[775]: info: CorePlayQueue::clearPlayQueue Apr 06 16:16:51 volumio volumio[775]: info: CorePlayQueue::saveQueue Apr 06 16:16:51 volumio volumio[775]: info: CoreCommandRouter::volumioPushQueue Apr 06 16:16:51 volumio volumio[775]: info: CoreStateMachine::addQueueItems Apr 06 16:16:51 volumio volumio[775]: info: CorePlayQueue::addQueueItems Apr 06 16:16:51 volumio volumio[775]: info: Preload queue cleared Apr 06 16:16:51 volumio volumio[775]: info: Adding Item to queue: music-library/USB/Elements/PLAYLISTS 2024/Sposa Son Disprezzata Apr 06 16:16:51 volumio volumio[775]: info: Using cached record of: music-library/USB/Elements/PLAYLISTS 2024/Sposa Son Disprezzata Apr 06 16:16:51 volumio volumio[775]: info: CoreCommandRouter::volumioPushQueue Apr 06 16:16:51 volumio volumio[775]: info: CorePlayQueue::saveQueue Apr 06 16:16:51 volumio volumio[775]: info: CoreStateMachine::updateTrackBlock Apr 06 16:16:51 volumio volumio[775]: info: CorePlayQueue::getTrackBlock Apr 06 16:16:51 volumio volumio[775]: info: CoreCommandRouter::volumioPlay Apr 06 16:16:51 volumio volumio[775]: info: CoreStateMachine::play index 0 Apr 06 16:16:51 volumio volumio[775]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 16:16:51 volumio volumio[775]: info: CoreStateMachine::stop Apr 06 16:16:51 volumio volumio[775]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 16:16:51 volumio volumio[775]: info: CoreStateMachine::play index undefined Apr 06 16:16:51 volumio volumio[775]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 16:16:51 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:16:52 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 06 16:16:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Apr 06 16:16:52 volumio systemd[1]: Stopped go-librespot Daemon. Apr 06 16:16:52 volumio systemd[1]: Started go-librespot Daemon. Apr 06 16:16:52 volumio go-librespot[1314]: Librespot-go daemon starting... Apr 06 16:16:52 volumio go-librespot[1314]: time="2024-04-06T16:16:52Z" level=info msg="generated new device id: 0814329e20cd8c08fa3be412a028a4612b8fa558" Apr 06 16:16:52 volumio go-librespot[1314]: time="2024-04-06T16:16:52Z" level=debug msg="stored credentials found for radiopokopokito" Apr 06 16:16:52 volumio go-librespot[1314]: time="2024-04-06T16:16:52Z" level=debug msg="obtained new client token: AADA6R5/hCuV1v6KosdWZ8YMmp//1d5H2lSp2FWtMyq5GJqdMGOKXvplpfZs2Vsz0rLHXeRTTnN+2e81A2Tg9iT3HL5NsZFcaNGU6uE9uRzAF9kWBs0mR8PIejWrePnN4zpJoHeJH29YIbZQF3TlIqVO+rknWr6ebuIiIq2AJVDYizIR/lHxtJWDM7z9VZtoxY9SqUeq8PRnvu7zWkshW5KleA5x3c5OilHtk1pDPA+2xSxhdLtudSQSuyKvy1Y=" Apr 06 16:16:52 volumio go-librespot[1314]: time="2024-04-06T16:16:52Z" 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]" Apr 06 16:16:52 volumio volumio[775]: info: Initializing connection to go-librespot Websocket Apr 06 16:16:52 volumio go-librespot[1314]: time="2024-04-06T16:16:52Z" level=debug msg="new websocket client" Apr 06 16:16:52 volumio volumio[775]: info: Connection to go-librespot Websocket established Apr 06 16:16:52 volumio go-librespot[1314]: time="2024-04-06T16:16:52Z" level=debug msg="completed keyexchange" Apr 06 16:16:53 volumio go-librespot[1314]: time="2024-04-06T16:16:53Z" level=debug msg="completed challenge" Apr 06 16:16:53 volumio go-librespot[1314]: time="2024-04-06T16:16:53Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 06 16:16:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 06 16:16:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 06 16:16:53 volumio volumio[775]: info: Connection to go-librespot Websocket closed Apr 06 16:16:53 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 06 16:16:53 volumio volumio[775]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Apr 06 16:16:53 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:16:53 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:16:55 volumio volumio[775]: info: Getting Spotify volume Apr 06 16:16:55 volumio volumio[775]: (node:775) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 06 16:16:55 volumio volumio[775]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 06 16:16:55 volumio volumio[775]: (node:775) 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: 4) Apr 06 16:16:55 volumio volumio[775]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 Apr 06 16:16:55 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:16:55 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:16:56 volumio volumio[775]: info: Initializing connection to go-librespot Websocket Apr 06 16:16:56 volumio volumio[775]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 06 16:16:56 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 06 16:16:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Apr 06 16:16:56 volumio systemd[1]: Stopped go-librespot Daemon. Apr 06 16:16:56 volumio systemd[1]: Started go-librespot Daemon. Apr 06 16:16:56 volumio go-librespot[1322]: Librespot-go daemon starting... Apr 06 16:16:56 volumio go-librespot[1322]: time="2024-04-06T16:16:56Z" level=info msg="generated new device id: 5bae9730eb7e7d2f6a0fbb0f406428fa028b9c11" Apr 06 16:16:56 volumio go-librespot[1322]: time="2024-04-06T16:16:56Z" level=debug msg="stored credentials found for radiopokopokito" Apr 06 16:16:56 volumio go-librespot[1322]: time="2024-04-06T16:16:56Z" level=debug msg="obtained new client token: AAAwy8qkiuWF1JL7H67V4ogpztrAxD5oVtvFrSrxk1OWMDMihunf/B4Z+/DYxCxH5r8LQFxAaUx/b/nR1GfWBZLolQ1Ilx+LdV/Z52h+ixjoBKtJNPFdQ4hX6HzV9CDxpVwmdRcp3Ti9sbNNXaBv6zpo4XZHRRHXfhUdy8wnPrT3HpqqN06t9I2NsWnhihSssYuCTsXla68HaY+9HqrqQ9vCDm1AfJeCo/izDMTO6WgfxBfNJOFAAelrrLImPcs=" Apr 06 16:16:56 volumio go-librespot[1322]: time="2024-04-06T16:16:56Z" 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]" Apr 06 16:16:57 volumio go-librespot[1322]: time="2024-04-06T16:16:57Z" level=debug msg="completed keyexchange" Apr 06 16:16:57 volumio go-librespot[1322]: time="2024-04-06T16:16:57Z" level=debug msg="completed challenge" Apr 06 16:16:57 volumio go-librespot[1322]: time="2024-04-06T16:16:57Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 06 16:16:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 06 16:16:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 06 16:16:58 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:58 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:58 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 06 16:16:58 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 06 16:16:58 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 06 16:16:58 volumio volumio[775]: info: CoreCommandRouter::volumioGetBrowseSources Apr 06 16:16:58 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 06 16:16:58 volumio volumio[775]: info: Preload queue cleared Apr 06 16:16:58 volumio volumio[775]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 06 16:16:58 volumio volumio[775]: info: CoreStateMachine::ClearQueue Apr 06 16:16:58 volumio volumio[775]: info: CoreStateMachine::stop Apr 06 16:16:58 volumio volumio[775]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 16:16:58 volumio volumio[775]: info: CorePlayQueue::clearPlayQueue Apr 06 16:16:58 volumio volumio[775]: info: CorePlayQueue::saveQueue Apr 06 16:16:58 volumio volumio[775]: info: CoreCommandRouter::volumioPushQueue Apr 06 16:16:58 volumio volumio[775]: info: CoreStateMachine::addQueueItems Apr 06 16:16:58 volumio volumio[775]: info: CorePlayQueue::addQueueItems Apr 06 16:16:58 volumio volumio[775]: info: Preload queue cleared Apr 06 16:16:58 volumio volumio[775]: info: Adding Item to queue: music-library/USB/Elements/PLAYLISTS 2024/Sposa Son Disprezzata Apr 06 16:16:58 volumio volumio[775]: info: Using cached record of: music-library/USB/Elements/PLAYLISTS 2024/Sposa Son Disprezzata Apr 06 16:16:58 volumio volumio[775]: info: CoreCommandRouter::volumioPushQueue Apr 06 16:16:58 volumio volumio[775]: info: CorePlayQueue::saveQueue Apr 06 16:16:58 volumio volumio[775]: info: CoreStateMachine::updateTrackBlock Apr 06 16:16:58 volumio volumio[775]: info: CorePlayQueue::getTrackBlock Apr 06 16:16:58 volumio volumio[775]: info: CoreCommandRouter::volumioPlay Apr 06 16:16:58 volumio volumio[775]: info: CoreStateMachine::play index 0 Apr 06 16:16:58 volumio volumio[775]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 16:16:58 volumio volumio[775]: info: CoreStateMachine::stop Apr 06 16:16:58 volumio volumio[775]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 16:16:58 volumio volumio[775]: info: CoreStateMachine::play index undefined Apr 06 16:16:58 volumio volumio[775]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 16:16:58 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:16:59 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 06 16:16:59 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 06 16:16:59 volumio volumio[775]: info: Discovery: Getting this device information Apr 06 16:16:59 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:16:59 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:16:59 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 06 16:16:59 volumio volumio[775]: info: Initializing connection to go-librespot Websocket Apr 06 16:16:59 volumio volumio[775]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 06 16:17:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 06 16:17:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Apr 06 16:17:00 volumio systemd[1]: Stopped go-librespot Daemon. Apr 06 16:17:00 volumio systemd[1]: Started go-librespot Daemon. Apr 06 16:17:00 volumio go-librespot[1330]: Librespot-go daemon starting... Apr 06 16:17:00 volumio go-librespot[1330]: time="2024-04-06T16:17:00Z" level=info msg="generated new device id: 9b026a1fbb7fe4205d70e6864722ec8dc5db45a8" Apr 06 16:17:00 volumio go-librespot[1330]: time="2024-04-06T16:17:00Z" level=debug msg="stored credentials found for radiopokopokito" Apr 06 16:17:01 volumio go-librespot[1330]: time="2024-04-06T16:17:01Z" level=debug msg="obtained new client token: AABA3lx/jtbXEGIfBnGRlsWowhzaQ1jQ0Hp4uPorSUGuRBc1mPleM9SWCTyAQIUE8rmu54Gh3fH+SkChtlJFoxkYWUytOdUvqji4tI5CoNhOFo1XbYlF1BmYl7pjVqPc9cLX92Pm8rrv2QkNAly1EBrUjL6a/4EC+lzWrI3X8urio5PAZUnzDp7Vos5DhQiom0Y0SAX1NMvVhhQvN+EfK9aPUAJQmcOeZdymDrVgqVyc/cyN8cgokHr7F+aZ" Apr 06 16:17:01 volumio go-librespot[1330]: time="2024-04-06T16:17:01Z" 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]" Apr 06 16:17:01 volumio go-librespot[1330]: time="2024-04-06T16:17:01Z" level=debug msg="completed keyexchange" Apr 06 16:17:01 volumio go-librespot[1330]: time="2024-04-06T16:17:01Z" level=debug msg="completed challenge" Apr 06 16:17:01 volumio go-librespot[1330]: time="2024-04-06T16:17:01Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 06 16:17:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 06 16:17:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 06 16:17:02 volumio volumio[775]: info: Initializing connection to go-librespot Websocket Apr 06 16:17:02 volumio volumio[775]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 06 16:17:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 06 16:17:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Apr 06 16:17:05 volumio systemd[1]: Stopped go-librespot Daemon. Apr 06 16:17:05 volumio systemd[1]: Started go-librespot Daemon. Apr 06 16:17:05 volumio go-librespot[1339]: Librespot-go daemon starting... Apr 06 16:17:05 volumio go-librespot[1339]: time="2024-04-06T16:17:05Z" level=info msg="generated new device id: ee4640ff8d01df71014cf7bf09f2779f5563dd76" Apr 06 16:17:05 volumio go-librespot[1339]: time="2024-04-06T16:17:05Z" level=debug msg="stored credentials found for radiopokopokito" Apr 06 16:17:05 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 06 16:17:05 volumio volumio[775]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 06 16:17:05 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 06 16:17:05 volumio volumio[775]: info: Received Get System Version Apr 06 16:17:05 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 06 16:17:05 volumio volumio[775]: info: Received Get System Info Apr 06 16:17:05 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 06 16:17:05 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 06 16:17:05 volumio volumio[775]: info: Discovery: Getting this device information Apr 06 16:17:05 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:17:05 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:17:05 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 06 16:17:05 volumio go-librespot[1339]: time="2024-04-06T16:17:05Z" level=debug msg="obtained new client token: AADnEkn1CMmLiD4u3oUoEHBw2PdEEYlFd4GWrwBQmrTlis3lOP/b2+Yp0DV8gM38pkADlMoYnYV3gPtE6FiErj4LlQ0xKjON6kg6OzutS/4pX2TyJF1+vmCGvUQEr1LnTcp2XZ5X542dsUARv4riAwQKYU93T3RwOvDuZ16pAau+y/S2vnE8Dv87pkCNKHTDg9slXlgrBY+u8QnW4ng5Bx0dUADb4ZU3QR4MjGtNz5RJ9ZGkGn6JVDKtUx2r" Apr 06 16:17:05 volumio volumio[775]: info: Initializing connection to go-librespot Websocket Apr 06 16:17:05 volumio go-librespot[1339]: time="2024-04-06T16:17:05Z" level=debug msg="new websocket client" Apr 06 16:17:05 volumio volumio[775]: info: Connection to go-librespot Websocket established Apr 06 16:17:05 volumio go-librespot[1339]: time="2024-04-06T16:17:05Z" 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]" Apr 06 16:17:05 volumio go-librespot[1339]: time="2024-04-06T16:17:05Z" level=debug msg="completed keyexchange" Apr 06 16:17:06 volumio go-librespot[1339]: time="2024-04-06T16:17:06Z" level=debug msg="completed challenge" Apr 06 16:17:06 volumio go-librespot[1339]: time="2024-04-06T16:17:06Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 06 16:17:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 06 16:17:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 06 16:17:06 volumio volumio[775]: info: Connection to go-librespot Websocket closed Apr 06 16:17:08 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 06 16:17:08 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 06 16:17:08 volumio volumio[775]: info: Discovery: Getting this device information Apr 06 16:17:08 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:17:08 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:17:08 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 06 16:17:08 volumio volumio[775]: info: Getting Spotify volume Apr 06 16:17:08 volumio volumio[775]: (node:775) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 06 16:17:08 volumio volumio[775]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 06 16:17:08 volumio volumio[775]: (node:775) 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: 5) Apr 06 16:17:08 volumio volumio[775]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 Apr 06 16:17:08 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:17:08 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:17:09 volumio volumio[775]: info: Initializing connection to go-librespot Websocket Apr 06 16:17:09 volumio volumio[775]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 06 16:17:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 06 16:17:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Apr 06 16:17:09 volumio systemd[1]: Stopped go-librespot Daemon. Apr 06 16:17:09 volumio systemd[1]: Started go-librespot Daemon. Apr 06 16:17:09 volumio go-librespot[1347]: Librespot-go daemon starting... Apr 06 16:17:09 volumio go-librespot[1347]: time="2024-04-06T16:17:09Z" level=info msg="generated new device id: 91b47ba6027934335d56f9445de8f5d8005ab439" Apr 06 16:17:09 volumio go-librespot[1347]: time="2024-04-06T16:17:09Z" level=debug msg="stored credentials found for radiopokopokito" Apr 06 16:17:09 volumio go-librespot[1347]: time="2024-04-06T16:17:09Z" level=debug msg="obtained new client token: AADh0z/A4VCAqLBWgtXXuVpam5DgbzqLLPqa1QBh6gY0+q50rTUShOx9H9AwnxLA5GdMS1pecq8t8RhmLsSYKDUMLmTPWWeAYrOlDTP12rWhMCrRYIGedsP2pG2v0ZHke3jHr6T+icxBYq9eQFXjANW/GFGuGzPdyT/j1dT4imN7PSYLWUM83+rS1I68eMGA/biloFxFDDwz415RCZl88X3AVcC8w+sW/1PeI3EWYNgod0+ez2Z2l18db6nxCrs=" Apr 06 16:17:09 volumio go-librespot[1347]: time="2024-04-06T16:17:09Z" 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]" Apr 06 16:17:09 volumio go-librespot[1347]: time="2024-04-06T16:17:09Z" level=debug msg="completed keyexchange" Apr 06 16:17:10 volumio go-librespot[1347]: time="2024-04-06T16:17:10Z" level=debug msg="completed challenge" Apr 06 16:17:10 volumio go-librespot[1347]: time="2024-04-06T16:17:10Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 06 16:17:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 06 16:17:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 06 16:17:10 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 06 16:17:10 volumio volumio[775]: info: CURURI: music-library Apr 06 16:17:10 volumio volumio[775]: error: Failed LSINFO: null Apr 06 16:17:10 volumio volumio[775]: info: Preload queue cleared Apr 06 16:17:12 volumio volumio[775]: info: Initializing connection to go-librespot Websocket Apr 06 16:17:12 volumio volumio[775]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 06 16:17:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 06 16:17:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Apr 06 16:17:13 volumio systemd[1]: Stopped go-librespot Daemon. Apr 06 16:17:13 volumio systemd[1]: Started go-librespot Daemon. Apr 06 16:17:13 volumio go-librespot[1355]: Librespot-go daemon starting... Apr 06 16:17:13 volumio go-librespot[1355]: time="2024-04-06T16:17:13Z" level=info msg="generated new device id: 27d850302a71f0adf34d3d6053fd2225c71cb081" Apr 06 16:17:13 volumio go-librespot[1355]: time="2024-04-06T16:17:13Z" level=debug msg="stored credentials found for radiopokopokito" Apr 06 16:17:13 volumio go-librespot[1355]: time="2024-04-06T16:17:13Z" level=debug msg="obtained new client token: AAD7bQFbQRdFsGCyN/QTWD3Cz4Fhv3SJaM6/UpIhUj01PPMqQ5r8Mp1zr2Lao6FDjpECzLp09KchATSlZl4eKEkZ7ejdK+LIKP4TdJqskVRGEcdGOWfKfT8m9Fb2YhwMxaUU1mvW4eVEbdkRaFSW5s41qwDG4SmhLFppIh8kBTBm1HmxjEwMm3hOwJ3LbTRCJTm7o2S/sAPFTx5+4/foW1Ko3Mq1ZgV2sNxOQSL4S0W8fY2qypJyshocexQbWnQ=" Apr 06 16:17:13 volumio go-librespot[1355]: time="2024-04-06T16:17:13Z" 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]" Apr 06 16:17:14 volumio go-librespot[1355]: time="2024-04-06T16:17:14Z" level=debug msg="completed keyexchange" Apr 06 16:17:14 volumio go-librespot[1355]: time="2024-04-06T16:17:14Z" level=debug msg="completed challenge" Apr 06 16:17:14 volumio go-librespot[1355]: time="2024-04-06T16:17:14Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 06 16:17:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 06 16:17:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 06 16:17:14 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 06 16:17:14 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 06 16:17:15 volumio volumio[775]: info: Initializing connection to go-librespot Websocket Apr 06 16:17:15 volumio volumio[775]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 06 16:17:17 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 06 16:17:17 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 06 16:17:17 volumio volumio[775]: info: Discovery: Getting this device information Apr 06 16:17:17 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:17:17 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:17:17 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 06 16:17:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 06 16:17:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Apr 06 16:17:17 volumio systemd[1]: Stopped go-librespot Daemon. Apr 06 16:17:17 volumio systemd[1]: Started go-librespot Daemon. Apr 06 16:17:17 volumio go-librespot[1363]: Librespot-go daemon starting... Apr 06 16:17:17 volumio go-librespot[1363]: time="2024-04-06T16:17:17Z" level=info msg="generated new device id: 07b4be9ab208ea3a66f1c5751ac3ebe93ae843dc" Apr 06 16:17:17 volumio go-librespot[1363]: time="2024-04-06T16:17:17Z" level=debug msg="stored credentials found for radiopokopokito" Apr 06 16:17:18 volumio go-librespot[1363]: time="2024-04-06T16:17:18Z" level=debug msg="obtained new client token: AACN3bGOm8dqvqytxsXZD7ab7AU6i/ladQsCYHajrzzbrFgaU2kABGiFWVFAOkygiT5YCYe++JFW2R6vqtV7/x0ISibierZt/nRaGX4sX6qq68dn+lhZc5O1H3W27vmOZOJNAWiNvvFasnFaP79ffPuNLFA2HZF7CgNCkyiAJl6bmnVB9i3jgpgeqMSoBcBWmMd57oapHUMyJ20aYH8lxByVXJjVN+2pDH3I5tKS16Z/z7K5p6KJ3vL53Ul6" Apr 06 16:17:18 volumio volumio[775]: info: Initializing connection to go-librespot Websocket Apr 06 16:17:18 volumio go-librespot[1363]: time="2024-04-06T16:17:18Z" level=debug msg="new websocket client" Apr 06 16:17:18 volumio volumio[775]: info: Connection to go-librespot Websocket established Apr 06 16:17:18 volumio go-librespot[1363]: time="2024-04-06T16:17:18Z" 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]" Apr 06 16:17:18 volumio go-librespot[1363]: time="2024-04-06T16:17:18Z" level=debug msg="completed keyexchange" Apr 06 16:17:18 volumio go-librespot[1363]: time="2024-04-06T16:17:18Z" level=debug msg="completed challenge" Apr 06 16:17:18 volumio go-librespot[1363]: time="2024-04-06T16:17:18Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 06 16:17:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 06 16:17:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 06 16:17:18 volumio volumio[775]: info: Connection to go-librespot Websocket closed Apr 06 16:17:21 volumio volumio[775]: info: Getting Spotify volume Apr 06 16:17:21 volumio volumio[775]: (node:775) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 06 16:17:21 volumio volumio[775]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 06 16:17:21 volumio volumio[775]: (node:775) 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: 6) Apr 06 16:17:21 volumio volumio[775]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Apr 06 16:17:21 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:17:21 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:17:21 volumio volumio[775]: info: Initializing connection to go-librespot Websocket Apr 06 16:17:21 volumio volumio[775]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 06 16:17:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 06 16:17:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Apr 06 16:17:22 volumio systemd[1]: Stopped go-librespot Daemon. Apr 06 16:17:22 volumio systemd[1]: Started go-librespot Daemon. Apr 06 16:17:22 volumio go-librespot[1409]: Librespot-go daemon starting... Apr 06 16:17:22 volumio go-librespot[1409]: time="2024-04-06T16:17:22Z" level=info msg="generated new device id: 0bebc860148f717c7081636a8b4f0c55e0dad3e2" Apr 06 16:17:22 volumio go-librespot[1409]: time="2024-04-06T16:17:22Z" level=debug msg="stored credentials found for radiopokopokito" Apr 06 16:17:22 volumio go-librespot[1409]: time="2024-04-06T16:17:22Z" level=debug msg="obtained new client token: AAAW0W303wiUKDdpLnqGzDwOaqKibWD0kNdHrH5ceFOnxC5h/MbrZJ6fK0LSBcYqGGZaFNEycqWqM/t0C8WudfO8SA6WIeUuXZKP4Stb7QJvBZXlBFGSuuBhhAT+6eqgL/53qaN3hTrdclx8G3RiUSkW//WglDM7g1kL7HKPJeybHeU1LNKXfDIR7XzJuXupNj17aubiiXTlv4H20MQh06L4T1XPPt8YNtBZ6sIXh0rTObUmiwxSkCkUvtnW" Apr 06 16:17:22 volumio go-librespot[1409]: time="2024-04-06T16:17:22Z" 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]" Apr 06 16:17:22 volumio go-librespot[1409]: time="2024-04-06T16:17:22Z" level=debug msg="completed keyexchange" Apr 06 16:17:23 volumio go-librespot[1409]: time="2024-04-06T16:17:23Z" level=debug msg="completed challenge" Apr 06 16:17:23 volumio go-librespot[1409]: time="2024-04-06T16:17:23Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 06 16:17:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 06 16:17:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 06 16:17:24 volumio volumio[775]: info: Initializing connection to go-librespot Websocket Apr 06 16:17:24 volumio volumio[775]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 06 16:17:24 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 06 16:17:24 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 06 16:17:24 volumio volumio[775]: info: Discovery: Getting this device information Apr 06 16:17:24 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:17:24 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:17:24 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 06 16:17:25 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 06 16:17:25 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 06 16:17:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 06 16:17:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Apr 06 16:17:26 volumio systemd[1]: Stopped go-librespot Daemon. Apr 06 16:17:26 volumio systemd[1]: Started go-librespot Daemon. Apr 06 16:17:26 volumio go-librespot[1417]: Librespot-go daemon starting... Apr 06 16:17:26 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 06 16:17:26 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 06 16:17:26 volumio go-librespot[1417]: time="2024-04-06T16:17:26Z" level=info msg="generated new device id: 404baaa7e85dae69a26cb024f8aa6ca4b2ec536f" Apr 06 16:17:26 volumio go-librespot[1417]: time="2024-04-06T16:17:26Z" level=debug msg="stored credentials found for radiopokopokito" Apr 06 16:17:26 volumio go-librespot[1417]: time="2024-04-06T16:17:26Z" level=debug msg="obtained new client token: AAALJefozKdb6UUQ8/krxW36Oq2ocOILS5uKoupr4lAVBYj5Nlat0Vcjp0WQdPk7DImIYj3hL0kbXm8Ueup9Y4W+fxYjwRmKR35XCRyXP7aP5H/+ch3GM6gCsSCiMX5vTh7M+G53WrfaUq5dmFxzca13Ib3pIg4EVRLRCh4kguI/iTZa82xIWm5C80LE+XdfoQ0gUaROYA5VzFN0nG59dsh3oObBMVaV0Fww3b7ZAndSUUJ/5ReiYNnjK/psa5k=" Apr 06 16:17:26 volumio go-librespot[1417]: time="2024-04-06T16:17:26Z" 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]" Apr 06 16:17:26 volumio go-librespot[1417]: time="2024-04-06T16:17:26Z" level=debug msg="completed keyexchange" Apr 06 16:17:27 volumio go-librespot[1417]: time="2024-04-06T16:17:27Z" level=debug msg="completed challenge" Apr 06 16:17:27 volumio go-librespot[1417]: time="2024-04-06T16:17:27Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 06 16:17:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 06 16:17:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 06 16:17:27 volumio volumio[775]: info: Initializing connection to go-librespot Websocket Apr 06 16:17:27 volumio volumio[775]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 06 16:17:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 06 16:17:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Apr 06 16:17:30 volumio systemd[1]: Stopped go-librespot Daemon. Apr 06 16:17:30 volumio systemd[1]: Started go-librespot Daemon. Apr 06 16:17:30 volumio go-librespot[1425]: Librespot-go daemon starting... Apr 06 16:17:30 volumio go-librespot[1425]: time="2024-04-06T16:17:30Z" level=info msg="generated new device id: aba64f95e063af0a4350ea346a444db89071d8f5" Apr 06 16:17:30 volumio go-librespot[1425]: time="2024-04-06T16:17:30Z" level=debug msg="stored credentials found for radiopokopokito" Apr 06 16:17:30 volumio go-librespot[1425]: time="2024-04-06T16:17:30Z" level=debug msg="obtained new client token: AAA44Hea8SLQzZVFCW/bCskhtFtHuhFGAVA0mEhJDUN3PcVCrYbcLMhq7LpNlKGMtOGId5w6rceOmcWvEZ0Tnj72VVIlsu6mdu7gjHAYnp+KHJZUfGFRHtr0PKaWlxQcrSOAxum0QpuSi/Ff5s62kXk0FRH8wCs4m6+qTLiWhCP9r80/vsGGtAYGTIx6z8RiFxU/YA8wHhYG5yZlIGHDKpNnlmJvGILirvY2aodeHyt/DXFsLseUoDUX1ErS4Ag=" Apr 06 16:17:30 volumio sudo[1434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 06 16:17:30 volumio sudo[1434]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:17:30 volumio sudo[1434]: pam_unix(sudo:session): session closed for user root Apr 06 16:17:30 volumio sudo[1437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 06 16:17:30 volumio sudo[1437]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:17:30 volumio sudo[1437]: pam_unix(sudo:session): session closed for user root Apr 06 16:17:30 volumio volumio[775]: info: Initializing connection to go-librespot Websocket Apr 06 16:17:30 volumio go-librespot[1425]: time="2024-04-06T16:17:30Z" level=debug msg="new websocket client" Apr 06 16:17:30 volumio volumio[775]: info: Connection to go-librespot Websocket established Apr 06 16:17:30 volumio go-librespot[1425]: time="2024-04-06T16:17:30Z" 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]" Apr 06 16:17:30 volumio volumio[775]: verbose: New Socket.io Connection to 192.168.2.180 from 192.168.2.13 UA: okhttp/4.9.2 Total Clients: 10 Apr 06 16:17:30 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 06 16:17:30 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 06 16:17:31 volumio volumio[775]: info: CoreCommandRouter::volumioGetVisibleSources Apr 06 16:17:31 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 06 16:17:31 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:17:31 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:17:31 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 06 16:17:31 volumio go-librespot[1425]: time="2024-04-06T16:17:31Z" level=debug msg="completed keyexchange" Apr 06 16:17:31 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 06 16:17:31 volumio volumio[775]: info: Received Get System Info Apr 06 16:17:31 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 06 16:17:31 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 06 16:17:31 volumio volumio[775]: info: Discovery: Getting this device information Apr 06 16:17:31 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:17:31 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:17:31 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 06 16:17:31 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:17:31 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:17:31 volumio volumio[775]: info: Listing playlists Apr 06 16:17:31 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 06 16:17:31 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 06 16:17:31 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 06 16:17:31 volumio go-librespot[1425]: time="2024-04-06T16:17:31Z" level=debug msg="completed challenge" Apr 06 16:17:31 volumio go-librespot[1425]: time="2024-04-06T16:17:31Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 06 16:17:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 06 16:17:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 06 16:17:31 volumio volumio[775]: info: Connection to go-librespot Websocket closed Apr 06 16:17:32 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 06 16:17:32 volumio volumio[775]: info: Received Get System Info Apr 06 16:17:32 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 06 16:17:32 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 06 16:17:32 volumio volumio[775]: info: Discovery: Getting this device information Apr 06 16:17:32 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:17:32 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:17:32 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 06 16:17:33 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 06 16:17:33 volumio volumio[775]: info: Received Get System Info Apr 06 16:17:33 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 06 16:17:33 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 06 16:17:33 volumio volumio[775]: info: Discovery: Getting this device information Apr 06 16:17:33 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:17:33 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:17:33 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 06 16:17:33 volumio volumio[775]: info: Getting Spotify volume Apr 06 16:17:33 volumio volumio[775]: (node:775) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 06 16:17:33 volumio volumio[775]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 06 16:17:33 volumio volumio[775]: (node:775) 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: 7) Apr 06 16:17:33 volumio volumio[775]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 Apr 06 16:17:33 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:17:33 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:17:34 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 06 16:17:34 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 06 16:17:34 volumio volumio[775]: info: Discovery: Getting this device information Apr 06 16:17:34 volumio volumio[775]: info: CoreCommandRouter::volumioGetState Apr 06 16:17:34 volumio volumio[775]: info: CorePlayQueue::getTrack 0 Apr 06 16:17:34 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 06 16:17:34 volumio volumio[775]: info: Initializing connection to go-librespot Websocket Apr 06 16:17:34 volumio volumio[775]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 06 16:17:34 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 06 16:17:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Apr 06 16:17:34 volumio systemd[1]: Stopped go-librespot Daemon. Apr 06 16:17:34 volumio systemd[1]: Started go-librespot Daemon. Apr 06 16:17:34 volumio go-librespot[1439]: Librespot-go daemon starting... Apr 06 16:17:34 volumio go-librespot[1439]: time="2024-04-06T16:17:34Z" level=info msg="generated new device id: 683d7e137f05dcb7dd14b2753b81ccc21864f965" Apr 06 16:17:34 volumio go-librespot[1439]: time="2024-04-06T16:17:34Z" level=debug msg="stored credentials found for radiopokopokito" Apr 06 16:17:35 volumio go-librespot[1439]: time="2024-04-06T16:17:35Z" level=debug msg="obtained new client token: AADLoYAO8E9yEaWWu7As9XGjZbL8IbCXXJnVqBr5jkftF8O98jWKzCDCcOtv1p+rj4W+96cnQqYg2sGzj7dgKqalIEKmvEpRcIPOKS84tatcLPvdyJbRThdiSdivOe7+IDehKl8Ee5PV8xoyoNXz47Fd4qTUr0EfKz1fXtdOQ8aWLO8ARVdEm37/NciNKXFUwpmDmyxMZxiVSC2ap9fvvR/43g2SHLpRG0+omBp82JPxLfNQ7+h6V9bMz0It" Apr 06 16:17:35 volumio go-librespot[1439]: time="2024-04-06T16:17:35Z" 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]" Apr 06 16:17:35 volumio go-librespot[1439]: time="2024-04-06T16:17:35Z" level=debug msg="completed keyexchange" Apr 06 16:17:35 volumio go-librespot[1439]: time="2024-04-06T16:17:35Z" level=debug msg="completed challenge" Apr 06 16:17:35 volumio go-librespot[1439]: time="2024-04-06T16:17:35Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 06 16:17:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 06 16:17:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 06 16:17:36 volumio volumio[775]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 06 16:17:36 volumio volumio[775]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 06 16:17:36 volumio volumio[775]: info: Retrieving Cloud Streaming UI Apr 06 16:17:36 volumio volumio[775]: info: Getting Tidal Cloud Configuration Apr 06 16:17:36 volumio volumio[775]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 06 16:17:36 volumio volumio[775]: info: Getting Qobuz Cloud Configuration Apr 06 16:17:36 volumio volumio[775]: info: Asking plugin for UI Config Apr 06 16:17:36 volumio volumio[775]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 06 16:17:36 volumio volumio[775]: info: Getting Spotify Cloud Configuration Apr 06 16:17:36 volumio volumio[775]: info: Asking plugin for UI Config Apr 06 16:17:36 volumio volumio[775]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 06 16:17:36 volumio volumio[775]: info: Saving Spotify Acccount Apr 06 16:17:36 volumio volumio[775]: info: Got Tidal Cloud Configuration Apr 06 16:17:36 volumio volumio[775]: info: Got it Apr 06 16:17:36 volumio volumio[775]: info: Got it Apr 06 16:17:36 volumio volumio[775]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 06 16:17:36 volumio volumio[775]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 06 16:17:36 volumio volumio[775]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 06 16:17:36 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 06 16:17:36 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 06 16:17:36 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 06 16:17:36 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 06 16:17:36 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 06 16:17:36 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 06 16:17:36 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 06 16:17:36 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 06 16:17:36 volumio volumio[775]: info: CoreCommandRouter::volumioGetBrowseSources Apr 06 16:17:36 volumio volumio[775]: info: CoreCommandRouter::volumioGetBrowseSources Apr 06 16:17:36 volumio volumio[775]: info: CoreCommandRouter::volumioGetBrowseSources Apr 06 16:17:36 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 06 16:17:36 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 06 16:17:37 volumio volumio[775]: info: Initializing connection to go-librespot Websocket Apr 06 16:17:37 volumio volumio[775]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 06 16:17:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 06 16:17:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Apr 06 16:17:39 volumio systemd[1]: Stopped go-librespot Daemon. Apr 06 16:17:39 volumio systemd[1]: Started go-librespot Daemon. Apr 06 16:17:39 volumio go-librespot[1448]: Librespot-go daemon starting... Apr 06 16:17:39 volumio go-librespot[1448]: time="2024-04-06T16:17:39Z" level=info msg="generated new device id: 8f9d77a4cd254e9ddb90dcdbc29fd8d64398b9d3" Apr 06 16:17:39 volumio go-librespot[1448]: time="2024-04-06T16:17:39Z" level=debug msg="stored credentials found for radiopokopokito" Apr 06 16:17:39 volumio go-librespot[1448]: time="2024-04-06T16:17:39Z" level=debug msg="obtained new client token: AADQPRn1f+C9PA5pWUSeeAxKVoUVLbq9md0ulRKFQ2EDJnxrQF2W0wDJveXM1q8ykcdcUpfsIcyrMnTfKzFu8V3aQVgt1pDMJZGx9D62mkooQOoSUzci7r2Rmf8w7laTj81cjoj0iK1l7+A/MWW+agykyTlVh5Im1jEWS81h4fvDl2NWWeyJFdXjGlOXCXN1eSFifXYPiXho5WzoM/lenFVbi1pGgSB6K91rZyGDDGLO2o2GRpcLiZlPE7oIMGA=" Apr 06 16:17:39 volumio go-librespot[1448]: time="2024-04-06T16:17:39Z" 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]" Apr 06 16:17:39 volumio go-librespot[1448]: time="2024-04-06T16:17:39Z" level=debug msg="completed keyexchange" Apr 06 16:17:40 volumio go-librespot[1448]: time="2024-04-06T16:17:40Z" level=debug msg="completed challenge" Apr 06 16:17:40 volumio go-librespot[1448]: time="2024-04-06T16:17:40Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 06 16:17:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 06 16:17:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 06 16:17:40 volumio volumio[775]: info: Initializing connection to go-librespot Websocket Apr 06 16:17:40 volumio volumio[775]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 06 16:17:40 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 06 16:17:42 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 06 16:17:42 volumio volumio[775]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 06 16:17:42 volumio volumio[775]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 06 16:17:42 volumio volumio[775]: Error: getaddrinfo -3007 Apr 06 16:17:42 volumio volumio[775]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Apr 06 16:17:42 volumio volumio[775]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Apr 06 16:17:42 volumio volumio[775]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Apr 06 16:17:42 volumio volumio[775]: code: -3007, Apr 06 16:17:42 volumio volumio[775]: errno: -3007, Apr 06 16:17:42 volumio volumio[775]: syscall: 'getaddrinfo' Apr 06 16:17:42 volumio volumio[775]: } Apr 06 16:17:42 volumio volumio[775]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 06 16:17:43 volumio sudo[1467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-06 16:16 Apr 06 16:17:43 volumio sudo[1467]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"