-- Logs begin at Fri 2024-08-16 06:50:09 CEST, end at Fri 2024-08-16 21:20:33 CEST. -- Aug 16 21:19:03 rivo ntpd[3044]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 16 21:19:12 rivo ntpd[3044]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 16 21:19:14 rivo ntpd[3044]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 16 21:19:22 rivo ntpd[3044]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 16 21:20:08 rivo ntpd[3044]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 16 21:20:14 rivo wpa_supplicant[3163]: wlan0: Trying to associate with c8:78:7d:e2:8d:d1 (SSID='DIR-X1530-5G-8DD0' freq=5260 MHz) Aug 16 21:20:14 rivo kernel: Connecting with c8:78:7d:e2:8d:d1 ssid "DIR-X1530-5G-8DD0", len (17) channel=52 Aug 16 21:20:15 rivo kernel: dhd_dbg_start_pkt_monitor, 1724 Aug 16 21:20:15 rivo kernel: wl_iw_event: Link UP with c8:78:7d:e2:8d:d1 Aug 16 21:20:15 rivo kernel: wl_bss_connect_done succeeded with c8:78:7d:e2:8d:d1 Aug 16 21:20:15 rivo kernel: CFG80211-ERROR) wl_cfg80211_scan_abort : scan abort failed Aug 16 21:20:15 rivo wpa_supplicant[3163]: wlan0: Associated with c8:78:7d:e2:8d:d1 Aug 16 21:20:15 rivo wpa_supplicant[3163]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 16 21:20:15 rivo wpa_supplicant[3163]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=GB Aug 16 21:20:15 rivo wpa_supplicant[3163]: wlan0: WPA: Key negotiation completed with c8:78:7d:e2:8d:d1 [PTK=CCMP GTK=CCMP] Aug 16 21:20:15 rivo wpa_supplicant[3163]: wlan0: CTRL-EVENT-CONNECTED - Connection to c8:78:7d:e2:8d:d1 completed [id=0 id_str=] Aug 16 21:20:15 rivo dhcpcd[3346]: wlan0: carrier acquired Aug 16 21:20:15 rivo dhcpcd[3346]: wlan0: IAID 28:29:86:e2 Aug 16 21:20:15 rivo kernel: wl_bss_connect_done succeeded with c8:78:7d:e2:8d:d1 Aug 16 21:20:15 rivo dhcpcd[3346]: wlan0: soliciting a DHCP lease Aug 16 21:20:15 rivo dhcpcd[3346]: wlan0: soliciting an IPv6 router Aug 16 21:20:16 rivo ntpd[3044]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 16 21:20:19 rivo ntpd[3044]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 16 21:20:20 rivo dhcpcd[3346]: wlan0: probing for an IPv4LL address Aug 16 21:20:20 rivo dhcpcd[3346]: wlan0: offered 192.168.0.106 from 192.168.0.1 Aug 16 21:20:20 rivo dhcpcd[3346]: wlan0: probing address 192.168.0.106/24 Aug 16 21:20:25 rivo dhcpcd[3346]: wlan0: using IPv4LL address 169.254.251.203 Aug 16 21:20:25 rivo avahi-daemon[2828]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.251.203. Aug 16 21:20:25 rivo avahi-daemon[2828]: New relevant interface wlan0.IPv4 for mDNS. Aug 16 21:20:25 rivo avahi-daemon[2828]: Registering new address record for 169.254.251.203 on wlan0.IPv4. Aug 16 21:20:25 rivo dhcpcd[3346]: wlan0: adding route to 169.254.0.0/16 Aug 16 21:20:25 rivo dhcpcd[3346]: wlan0: adding default route Aug 16 21:20:25 rivo dhcpcd[3346]: wlan0: leased 192.168.0.106 for 86400 seconds Aug 16 21:20:25 rivo avahi-daemon[2828]: Registering new address record for 192.168.0.106 on wlan0.IPv4. Aug 16 21:20:25 rivo dhcpcd[3346]: wlan0: adding route to 192.168.0.0/24 Aug 16 21:20:25 rivo dhcpcd[3346]: wlan0: changing default route via 192.168.0.1 Aug 16 21:20:25 rivo ntpd[3044]: ntpd exiting on signal 15 (Terminated) Aug 16 21:20:25 rivo systemd[1]: Stopping Network Time Service... Aug 16 21:20:25 rivo systemd[1]: ntp.service: Succeeded. Aug 16 21:20:25 rivo systemd[1]: Stopped Network Time Service. Aug 16 21:20:25 rivo systemd[1]: Starting Network Time Service... Aug 16 21:20:25 rivo ntpd[13924]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 16 21:20:25 rivo ntpd[13924]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Aug 16 21:20:25 rivo systemd[1]: Started Network Time Service. Aug 16 21:20:25 rivo avahi-daemon[2828]: Withdrawing address record for 169.254.251.203 on wlan0. Aug 16 21:20:25 rivo avahi-daemon[2828]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.251.203. Aug 16 21:20:25 rivo dhcpcd[3346]: wlan0: deleting route to 169.254.0.0/16 Aug 16 21:20:25 rivo avahi-daemon[2828]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.106. Aug 16 21:20:25 rivo ntpd[13935]: proto: precision = 1.250 usec (-20) Aug 16 21:20:25 rivo ntpd[13935]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 16 21:20:25 rivo ntpd[13935]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 16 21:20:25 rivo ntpd[13935]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 598 days ago Aug 16 21:20:25 rivo ntpd[13935]: Listen and drop on 0 v6wildcard [::]:123 Aug 16 21:20:25 rivo ntpd[13935]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 16 21:20:25 rivo ntpd[13935]: Listen normally on 2 lo 127.0.0.1:123 Aug 16 21:20:25 rivo ntpd[13935]: Listen normally on 3 wlan0 192.168.0.106:123 Aug 16 21:20:25 rivo ntpd[13935]: Listening on routing socket on fd #20 for interface updates Aug 16 21:20:25 rivo ntpd[13935]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Aug 16 21:20:25 rivo ntpd[13935]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Aug 16 21:20:26 rivo volumio[3234]: info: Discovery: adding a2b15036-a351-46c5-9062-36b5bf85bba5 Aug 16 21:20:26 rivo volumio[3234]: info: Discovery: Found device Rivo Aug 16 21:20:26 rivo volumio[3234]: info: CoreCommandRouter::volumioGetState Aug 16 21:20:26 rivo volumio[3234]: info: CorePlayQueue::getTrack 0 Aug 16 21:20:26 rivo ntpd[13935]: Soliciting pool server 88.83.68.3 Aug 16 21:20:27 rivo ntpd[13935]: Soliciting pool server 5.186.224.242 Aug 16 21:20:27 rivo ntpd[13935]: Soliciting pool server 217.116.227.3 Aug 16 21:20:28 rivo ntpd[13935]: Soliciting pool server 162.159.200.1 Aug 16 21:20:28 rivo ntpd[13935]: Soliciting pool server 80.209.68.118 Aug 16 21:20:28 rivo ntpd[13935]: Soliciting pool server 192.36.143.130 Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 16 21:20:29 rivo volumio[3234]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 16 21:20:29 rivo ntpd[13935]: Soliciting pool server 213.5.39.34 Aug 16 21:20:29 rivo ntpd[13935]: Soliciting pool server 185.181.223.169 Aug 16 21:20:29 rivo ntpd[13935]: Soliciting pool server 85.191.193.248 Aug 16 21:20:29 rivo ntpd[13935]: Soliciting pool server 192.121.118.98 Aug 16 21:20:30 rivo ntpd[13935]: Soliciting pool server 193.200.91.90 Aug 16 21:20:30 rivo ntpd[13935]: Soliciting pool server 162.159.200.123 Aug 16 21:20:30 rivo ntpd[13935]: Soliciting pool server 95.154.26.34 Aug 16 21:20:31 rivo volumio[3234]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 16 21:20:31 rivo volumio[3234]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 16 21:20:31 rivo volumio[3234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 16 21:20:31 rivo volumio[3234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 16 21:20:31 rivo volumio[3234]: info: Starting MyVolumio Remote Streaming Endpoints Aug 16 21:20:31 rivo volumio[3234]: info: MyVolumio login type: Token Aug 16 21:20:31 rivo volumio[3234]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 16 21:20:31 rivo volumio[3234]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Aug 16 21:20:31 rivo volumio[3234]: error: MyVolumio Custom Token format not valid, refreshing it Aug 16 21:20:31 rivo ntpd[13935]: Soliciting pool server 213.32.246.229 Aug 16 21:20:31 rivo ntpd[13935]: Soliciting pool server 5.186.56.205 Aug 16 21:20:31 rivo ntpd[13935]: Soliciting pool server 87.104.58.9 Aug 16 21:20:32 rivo volumio[3234]: info: MyVolumio login type: Token Aug 16 21:20:32 rivo volumio[3234]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 16 21:20:32 rivo ntpd[13935]: Soliciting pool server 217.198.219.102 Aug 16 21:20:32 rivo ntpd[13935]: Soliciting pool server 2606:4700:f1::1 Aug 16 21:20:32 rivo ntpd[13935]: Soliciting pool server 80.209.87.103 Aug 16 21:20:33 rivo volumio[3234]: info: MyVolumio token set successfully Aug 16 21:20:33 rivo volumio[3234]: info: MYVOLUMIO: Adding device Aug 16 21:20:33 rivo volumio[3234]: info: MYVOLUMIO: Evaluating Server Aug 16 21:20:32 rivo ntpd[13935]: receive: Unexpected origin timestamp 0xea6a2501.f9a77616 does not match aorg 0000000000.00000000 from server@193.200.91.90 xmt 0xea6a2500.c8b2a41b Aug 16 21:20:32 rivo ntpd[13935]: receive: Unexpected origin timestamp 0xea6a2501.f9bcf564 does not match aorg 0000000000.00000000 from server@213.5.39.34 xmt 0xea6a2500.c92a3705 Aug 16 21:20:32 rivo systemd[1]: Starting Daily apt download activities... Aug 16 21:20:32 rivo volumio[3234]: info: MyVolumio status changed Aug 16 21:20:32 rivo volumio[3234]: info: Streaming services startup Aug 16 21:20:32 rivo volumio[3234]: info: Starting Streaming Daemon Aug 16 21:20:32 rivo volumio[3234]: info: Removing browser output: myVolumio user plan is not superstar Aug 16 21:20:32 rivo volumio[3234]: info: Removing audio output: Aug 16 21:20:32 rivo volumio[3234]: info: Stoppping Tunnel 1 Aug 16 21:20:32 rivo sudo[13992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 16 21:20:32 rivo sudo[13992]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 21:20:32 rivo sudo[13992]: pam_unix(sudo:session): session closed for user root Aug 16 21:20:32 rivo volumio[3234]: error: Failed to ping endpoint us1.myvolumio.org : unknown error Aug 16 21:20:32 rivo volumio[3234]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 16 21:20:32 rivo sudo[13996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 16 21:20:32 rivo sudo[13996]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 21:20:32 rivo volumio[3234]: Error: Unable to resolve or reject the same promise twice Aug 16 21:20:32 rivo volumio[3234]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 16 21:20:32 rivo volumio[3234]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32367) Aug 16 21:20:32 rivo volumio[3234]: at Socket.emit (events.js:400:28) Aug 16 21:20:32 rivo volumio[3234]: at addChunk (internal/streams/readable.js:293:12) Aug 16 21:20:32 rivo volumio[3234]: at readableAddChunk (internal/streams/readable.js:267:9) Aug 16 21:20:32 rivo volumio[3234]: at Socket.Readable.push (internal/streams/readable.js:206:10) Aug 16 21:20:32 rivo volumio[3234]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Aug 16 21:20:32 rivo volumio[3234]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 16 21:20:33 rivo sudo[13996]: pam_unix(sudo:session): session closed for user root Aug 16 21:20:33 rivo systemd[1]: apt-daily.service: Succeeded. Aug 16 21:20:33 rivo systemd[1]: Started Daily apt download activities. Aug 16 21:20:33 rivo sudo[14051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-16 21:19 Aug 16 21:20:33 rivo sudo[14051]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="ef589a0572b08c3d1e9a984ec6a655ef3698e415" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="abe72e92bc7de90ab6193cbc17e21ad2750980a2" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 28 May 2024 01:02:57 PM CEST" VOLUMIO_VERSION="3.695" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="f6b674b8f5e1472a13aff852e2f65e34"