-- Logs begin at Thu 2019-02-14 19:11:58 JST, end at Sun 2025-09-14 09:34:01 JST. -- Sep 14 09:33:59 volumio systemd-timedated[6344]: Changed local time to Sun Sep 14 09:33:59 2025 Sep 14 09:33:59 volumio systemd[1]: Starting Daily apt download activities... Sep 14 09:33:59 volumio sudo[6327]: pam_unix(sudo:session): session closed for user root Sep 14 09:33:59 volumio volumio-time-update[590]: volumio-time-update-util: System time updated successfully. Sep 14 09:33:59 volumio systemd[1]: Started Volumio Time Update Utility. Sep 14 09:33:59 volumio systemd[1]: Reached target Multi-User System. Sep 14 09:33:59 volumio systemd[1]: Reached target Graphical Interface. Sep 14 09:33:59 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 14 09:33:59 volumio volumio[2323]: info: Discovery: A device disappeared from network Sep 14 09:33:59 volumio volumio[2323]: info: Discovery: Device volumio disappeared from network Sep 14 09:33:59 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 14 09:33:59 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 14 09:33:59 volumio go-librespot[6336]: time="2025-09-14T09:33:59+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Sep 14 09:33:59 volumio go-librespot[6336]: time="2025-09-14T09:33:59+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Sep 14 09:33:59 volumio go-librespot[6336]: time="2025-09-14T09:33:59+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Sep 14 09:33:59 volumio go-librespot[6336]: time="2025-09-14T09:33:59+09:00" level=info msg="zeroconf server listening on port 43671" Sep 14 09:33:59 volumio go-librespot[6336]: time="2025-09-14T09:33:59+09:00" level=debug msg="obtained new client token: AACdzRgwgDrwiUhUFlBrrK1527kXJDoj+L1Gv0XexdRtbUPtbQNMoUHqhXanIxnDC+IFnXtkQQGAkWCf/L3ep0zG6MXKu37b5mSUnZd/x9Rue9XHBshAn2pkJVijlWGhj70gnhYQrgeHkjm4z5cd8EGt4kIrvz1JJHhpBBkJ6Wi6bAH2XT9gMjJFo9WxNyQcf2eD1BVQfvGcHgx6Fc03Z+hCGB71o09M3qBBHlYIz9nIiGfMZkjrcuU=" Sep 14 09:33:59 volumio go-librespot[6336]: time="2025-09-14T09:33:59+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Sep 14 09:33:59 volumio go-librespot[6336]: time="2025-09-14T09:33:59+09:00" level=debug msg="completed keyexchange" Sep 14 09:33:59 volumio go-librespot[6336]: time="2025-09-14T09:33:59+09:00" level=debug msg="completed challenge" Sep 14 09:33:59 volumio go-librespot[6336]: time="2025-09-14T09:33:59+09:00" level=info msg="authenticated AP as tens421" Sep 14 09:33:59 volumio go-librespot[6336]: time="2025-09-14T09:33:59+09:00" level=info msg="authenticated Login5 as tens421" Sep 14 09:33:59 volumio go-librespot[6336]: time="2025-09-14T09:33:59+09:00" level=debug msg="initializing zeroconf session, username: tens421" Sep 14 09:33:59 volumio go-librespot[6336]: time="2025-09-14T09:33:59+09:00" level=debug msg="dealer connection opened" Sep 14 09:33:59 volumio go-librespot[6336]: time="2025-09-14T09:33:59+09:00" level=trace msg="starting accesspoint recv loop" Sep 14 09:33:59 volumio go-librespot[6336]: time="2025-09-14T09:33:59+09:00" level=trace msg="starting dealer recv loop" Sep 14 09:33:59 volumio go-librespot[6336]: time="2025-09-14T09:33:59+09:00" level=trace msg="received accesspoint ping" Sep 14 09:33:59 volumio go-librespot[6336]: time="2025-09-14T09:33:59+09:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 14 09:33:59 volumio go-librespot[6336]: time="2025-09-14T09:33:59+09:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 14 09:33:59 volumio go-librespot[6336]: time="2025-09-14T09:33:59+09:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 14 09:33:59 volumio go-librespot[6336]: time="2025-09-14T09:33:59+09:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 14 09:33:59 volumio go-librespot[6336]: time="2025-09-14T09:33:59+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 14 09:33:59 volumio go-librespot[6336]: time="2025-09-14T09:33:59+09:00" level=debug msg="received connection id: NTlmMWViYWQtNmIwMy00ZmY3LTg1ZDctZmVkMjgzYzlmM2E1K2RlYWxlcit0Y3A6Ly8wYWIxNThmNC5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArMUE4MTlCQzlERUFFMjNGOEI0MTQxOEUxNzAyRTM2RDdDRUUyMDdDODkxMDc5NUE0MzMzOEQ1NzI3MEVFRUE5QQ==" Sep 14 09:33:59 volumio go-librespot[6336]: time="2025-09-14T09:33:59+09:00" level=trace msg="received accesspoint pong ack" Sep 14 09:33:59 volumio go-librespot[6336]: time="2025-09-14T09:33:59+09:00" level=debug msg="put connect state because NEW_DEVICE" Sep 14 09:34:00 volumio ntpd[815]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 14 09:34:00 volumio ntpd[815]: Soliciting pool server 160.16.113.133 Sep 14 09:34:00 volumio ntpd[815]: Soliciting pool server 210.238.46.144 Sep 14 09:34:00 volumio ntpd[815]: Soliciting pool server 167.179.119.205 Sep 14 09:34:00 volumio ntpd[815]: Soliciting pool server 162.159.200.1 Sep 14 09:34:00 volumio ntpd[815]: Soliciting pool server 133.130.121.141 Sep 14 09:34:00 volumio ntpd[815]: Soliciting pool server 45.76.211.39 Sep 14 09:34:00 volumio ntpd[815]: Soliciting pool server 153.127.202.76 Sep 14 09:34:00 volumio ntpd[815]: Soliciting pool server 213.239.154.12 Sep 14 09:34:00 volumio ntpd[815]: Soliciting pool server 202.181.103.212 Sep 14 09:34:00 volumio ntpd[815]: Soliciting pool server 108.160.132.224 Sep 14 09:34:00 volumio volumio[2323]: error: FusionDsp - WebSocket error: [object Object] Sep 14 09:34:00 volumio go-librespot[6336]: time="2025-09-14T09:34:00+09:00" level=debug msg="new websocket client" Sep 14 09:34:00 volumio volumio[2323]: info: Connection to go-librespot Websocket established Sep 14 09:34:00 volumio volumio[2323]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 14 09:34:00 volumio volumio[2323]: info: Discovery: adding b6e986d4-6d7b-4695-b30b-bde45533b333 Sep 14 09:34:00 volumio volumio[2323]: info: Discovery: Found device Volumio Sep 14 09:34:00 volumio volumio[2323]: info: CoreCommandRouter::volumioGetState Sep 14 09:34:00 volumio volumio[2323]: info: CorePlayQueue::getTrack 0 Sep 14 09:34:00 volumio volumio[2323]: info: CoreCommandRouter::volumioGetState Sep 14 09:34:00 volumio volumio[2323]: info: CorePlayQueue::getTrack 0 Sep 14 09:34:00 volumio volumio[2323]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 14 09:34:00 volumio volumio[2323]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 09:34:00 volumio volumio[2323]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Sep 14 09:34:00 volumio volumio[2323]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Sep 14 09:34:00 volumio volumio[2323]: errno: -3007, Sep 14 09:34:00 volumio volumio[2323]: code: 'ENOTFOUND', Sep 14 09:34:00 volumio volumio[2323]: syscall: 'getaddrinfo', Sep 14 09:34:00 volumio volumio[2323]: hostname: 'ws.audioscrobbler.com' Sep 14 09:34:00 volumio volumio[2323]: } Sep 14 09:34:00 volumio volumio[2323]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 09:34:00 volumio ntpd[815]: Soliciting pool server 129.250.35.251 Sep 14 09:34:00 volumio ntpd[815]: Soliciting pool server 45.138.55.60 Sep 14 09:34:00 volumio ntpd[815]: Soliciting pool server 142.91.105.55 Sep 14 09:34:00 volumio ntpd[815]: Soliciting pool server 129.250.35.251 Sep 14 09:34:01 volumio sudo[6666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-14 09:33 Sep 14 09:34:01 volumio sudo[6666]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"