-- 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"