-- Logs begin at Thu 2019-02-14 11:12:03 CET, end at Sat 2024-10-19 13:00:44 CEST. -- Oct 19 13:00:09 hwr go-librespot[13076]: time="2024-10-19T13:00:09+02:00" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection timed out" Oct 19 13:00:09 hwr volumio[744]: (node:744) UnhandledPromiseRejectionWarning: Error: socket hang up Oct 19 13:00:09 hwr volumio[744]: at connResetException (internal/errors.js:607:14) Oct 19 13:00:09 hwr volumio[744]: at Socket.socketOnEnd (_http_client.js:493:23) Oct 19 13:00:09 hwr volumio[744]: at Socket.emit (events.js:327:22) Oct 19 13:00:09 hwr volumio[744]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 19 13:00:09 hwr volumio[744]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 19 13:00:09 hwr systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 19 13:00:09 hwr volumio[744]: (node:744) 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: 44) Oct 19 13:00:09 hwr volumio[744]: info: Connection to go-librespot Websocket closed Oct 19 13:00:09 hwr systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 19 13:00:12 hwr volumio[744]: info: Initializing connection to go-librespot Websocket Oct 19 13:00:12 hwr volumio[744]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 19 13:00:12 hwr systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 19 13:00:12 hwr systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44. Oct 19 13:00:12 hwr systemd[1]: Stopped go-librespot Daemon. Oct 19 13:00:12 hwr systemd[1]: Started go-librespot Daemon. Oct 19 13:00:12 hwr go-librespot[13349]: Librespot-go daemon starting... Oct 19 13:00:12 hwr go-librespot[13349]: time="2024-10-19T13:00:12+02:00" level=info msg="generated new device id: b30396a0d051d795d998f3b95ad249a2bd1adcdd" Oct 19 13:00:12 hwr go-librespot[13349]: time="2024-10-19T13:00:12+02:00" level=debug msg="stored credentials found for toso1964" Oct 19 13:00:13 hwr go-librespot[13349]: time="2024-10-19T13:00:13+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 19 13:00:13 hwr go-librespot[13349]: time="2024-10-19T13:00:13+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 19 13:00:13 hwr go-librespot[13349]: time="2024-10-19T13:00:13+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 19 13:00:13 hwr go-librespot[13349]: time="2024-10-19T13:00:13+02:00" level=debug msg="zeroconf server listening on port 45553" Oct 19 13:00:13 hwr go-librespot[13349]: time="2024-10-19T13:00:13+02:00" level=debug msg="obtained new client token: AAAXBgCdn2AM8ubLMyl4Dnu7mBsJAhsxfDbUKUZNxLwS6laGvIgId4jgBh7pt7Q61iZ4dzkuSkztvNEjpquQ6od5wKVOagbvAvjaZunfVmuEqk5KUXZLmUGoRNB5CoocqQkpxKPVvuaQyp+e+CCE7epns8uwC3YB0s12RsZB0wsI5iTqzD1QNS3vkVGpH9wcOuQ0PiorT4K1aj4SzTPpPiV3O6le4CsohFZ3LfOVr9DMqFj5gKaYnGRyW4U=" Oct 19 13:00:15 hwr volumio[744]: info: Initializing connection to go-librespot Websocket Oct 19 13:00:15 hwr go-librespot[13349]: time="2024-10-19T13:00:15+02:00" level=debug msg="new websocket client" Oct 19 13:00:15 hwr volumio[744]: info: Connection to go-librespot Websocket established Oct 19 13:00:18 hwr volumio[744]: info: Getting Spotify volume Oct 19 13:00:18 hwr volumio[744]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Oct 19 13:00:18 hwr volumio[744]: info: CoreCommandRouter::volumioGetState Oct 19 13:00:18 hwr volumio[744]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Oct 19 13:00:33 hwr volumio[744]: error: error Oct 19 13:00:38 hwr dhcpcd[690]: wlan0: carrier lost Oct 19 13:00:38 hwr wpa_supplicant[664]: wlan0: Associated with e8:df:70:b6:d8:df Oct 19 13:00:38 hwr wpa_supplicant[664]: wlan0: CTRL-EVENT-CONNECTED - Connection to e8:df:70:b6:d8:df completed [id=0 id_str=] Oct 19 13:00:38 hwr wpa_supplicant[664]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 19 13:00:38 hwr avahi-daemon[351]: Withdrawing address record for 192.168.2.180 on wlan0. Oct 19 13:00:38 hwr avahi-daemon[351]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.180. Oct 19 13:00:38 hwr avahi-daemon[351]: Interface wlan0.IPv4 no longer relevant for mDNS. Oct 19 13:00:38 hwr dhcpcd[690]: wlan0: deleting route to 192.168.2.0/24 Oct 19 13:00:38 hwr dhcpcd[690]: wlan0: deleting default route via 192.168.2.10 Oct 19 13:00:38 hwr volumio[744]: info: Discovery: A device disappeared from network Oct 19 13:00:38 hwr volumio[744]: info: Discovery: Device hwr disappeared from network Oct 19 13:00:38 hwr dhcpcd[690]: wlan0: carrier acquired Oct 19 13:00:38 hwr dhcpcd[690]: wlan0: IAID eb:06:16:36 Oct 19 13:00:39 hwr dhcpcd[690]: wlan0: soliciting an IPv6 router Oct 19 13:00:39 hwr dhcpcd[690]: wlan0: soliciting a DHCP lease Oct 19 13:00:39 hwr dhcpcd[690]: wlan0: offered 192.168.2.180 from 192.168.2.10 Oct 19 13:00:39 hwr dhcpcd[690]: wlan0: probing address 192.168.2.180/24 Oct 19 13:00:40 hwr ntpd[512]: Deleting interface #3 wlan0, 192.168.2.180#123, interface stats: received=730, sent=730, dropped=0, active_time=6233 secs Oct 19 13:00:40 hwr ntpd[512]: 62.169.23.198 local addr 192.168.2.180 -> Oct 19 13:00:40 hwr ntpd[512]: 51.75.67.47 local addr 192.168.2.180 -> Oct 19 13:00:40 hwr ntpd[512]: 217.197.91.176 local addr 192.168.2.180 -> Oct 19 13:00:40 hwr ntpd[512]: 194.50.19.117 local addr 192.168.2.180 -> Oct 19 13:00:40 hwr ntpd[512]: 94.16.122.152 local addr 192.168.2.180 -> Oct 19 13:00:40 hwr ntpd[512]: 79.133.44.138 local addr 192.168.2.180 -> Oct 19 13:00:40 hwr ntpd[512]: 168.119.211.223 local addr 192.168.2.180 -> Oct 19 13:00:40 hwr ntpd[512]: 131.188.3.220 local addr 192.168.2.180 -> Oct 19 13:00:40 hwr ntpd[512]: 116.202.14.29 local addr 192.168.2.180 -> Oct 19 13:00:40 hwr ntpd[512]: 85.215.229.230 local addr 192.168.2.180 -> Oct 19 13:00:40 hwr ntpd[512]: 23.88.7.139 local addr 192.168.2.180 -> Oct 19 13:00:40 hwr ntpd[512]: 188.40.142.18 local addr 192.168.2.180 -> Oct 19 13:00:40 hwr ntpd[512]: 178.215.228.24 local addr 192.168.2.180 -> Oct 19 13:00:42 hwr volumio[744]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 19 13:00:42 hwr volumio[744]: Error: send ENETUNREACH 255.255.255.255:3483 Oct 19 13:00:42 hwr volumio[744]: at doSend (dgram.js:692:16) Oct 19 13:00:42 hwr volumio[744]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Oct 19 13:00:42 hwr volumio[744]: at afterDns (dgram.js:638:5) Oct 19 13:00:42 hwr volumio[744]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Oct 19 13:00:42 hwr volumio[744]: errno: -101, Oct 19 13:00:42 hwr volumio[744]: code: 'ENETUNREACH', Oct 19 13:00:42 hwr volumio[744]: syscall: 'send', Oct 19 13:00:42 hwr volumio[744]: address: '255.255.255.255', Oct 19 13:00:42 hwr volumio[744]: port: 3483 Oct 19 13:00:42 hwr volumio[744]: } Oct 19 13:00:42 hwr volumio[744]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 19 13:00:44 hwr dhcpcd[690]: wlan0: leased 192.168.2.180 for 864000 seconds Oct 19 13:00:44 hwr avahi-daemon[351]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.180. Oct 19 13:00:44 hwr dhcpcd[690]: wlan0: adding route to 192.168.2.0/24 Oct 19 13:00:44 hwr avahi-daemon[351]: New relevant interface wlan0.IPv4 for mDNS. Oct 19 13:00:44 hwr avahi-daemon[351]: Registering new address record for 192.168.2.180 on wlan0.IPv4. Oct 19 13:00:44 hwr dhcpcd[690]: wlan0: adding default route via 192.168.2.10 Oct 19 13:00:44 hwr sudo[13477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-19 12:59 Oct 19 13:00:44 hwr sudo[13477]: 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="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"