-- Logs begin at Sun 2024-08-18 00:09:55 UTC, end at Sun 2024-08-18 00:13:28 UTC. -- Aug 18 00:12:02 volumio volumio[1194]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.742&uuid=bad207329d3da78521775f75f9ce7de4" http://updates.volumio.org/downloader-v1/track-device Aug 18 00:12:02 volumio volumio[1194]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 18 00:12:02 volumio volumio[1194]: Dload Upload Total Spent Left Speed Aug 18 00:12:02 volumio volumio[1194]: [1.6K blob data] Aug 18 00:12:02 volumio volumio[1194]: retrying in 5 seconds, trial 2 Aug 18 00:12:02 volumio volumio[1194]: info: Volumio Calling Home Aug 18 00:12:07 volumio ntpd[937]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 18 00:12:12 volumio volumio[1194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 18 00:12:27 volumio ntpd[937]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 18 00:12:32 volumio volumio[1194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 18 00:12:47 volumio ntpd[937]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 18 00:12:52 volumio volumio[1194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 18 00:13:07 volumio ntpd[937]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 18 00:13:16 volumio dhcpcd[1129]: eth0: offered 192.168.1.10 from 192.168.1.1 Aug 18 00:13:16 volumio dhcpcd[1129]: eth0: probing address 192.168.1.10/24 Aug 18 00:13:21 volumio dhcpcd[1129]: eth0: leased 192.168.1.10 for 86400 seconds Aug 18 00:13:21 volumio dhcpcd[1129]: eth0: adding route to 192.168.1.0/24 Aug 18 00:13:21 volumio dhcpcd[1129]: eth0: changing default route via 192.168.1.1 Aug 18 00:13:21 volumio dhcpcd[823]: eth0: deleted default route Aug 18 00:13:21 volumio avahi-daemon[753]: Registering new address record for 192.168.1.10 on eth0.IPv4. Aug 18 00:13:21 volumio ntpd[937]: ntpd exiting on signal 15 (Terminated) Aug 18 00:13:21 volumio systemd[1]: Stopping Network Time Service... Aug 18 00:13:21 volumio systemd[1]: ntp.service: Succeeded. Aug 18 00:13:21 volumio systemd[1]: Stopped Network Time Service. Aug 18 00:13:21 volumio systemd[1]: Starting Network Time Service... Aug 18 00:13:21 volumio ntpd[2250]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 18 00:13:21 volumio ntpd[2250]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Aug 18 00:13:21 volumio systemd[1]: Started Network Time Service. Aug 18 00:13:21 volumio ntpd[2256]: proto: precision = 0.163 usec (-22) Aug 18 00:13:21 volumio ntpd[2256]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 18 00:13:21 volumio ntpd[2256]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 18 00:13:21 volumio ntpd[2256]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 600 days ago Aug 18 00:13:21 volumio ntpd[2256]: Listen and drop on 0 v6wildcard [::]:123 Aug 18 00:13:21 volumio ntpd[2256]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 18 00:13:21 volumio ntpd[2256]: Listen normally on 2 lo 127.0.0.1:123 Aug 18 00:13:21 volumio ntpd[2256]: Listen normally on 3 eth0 169.254.209.239:123 Aug 18 00:13:21 volumio ntpd[2256]: Listen normally on 4 eth0 192.168.1.10:123 Aug 18 00:13:21 volumio ntpd[2256]: Listen normally on 5 wlan0 169.254.130.218:123 Aug 18 00:13:21 volumio ntpd[2256]: Listening on routing socket on fd #22 for interface updates Aug 18 00:13:21 volumio ntpd[2256]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 18 00:13:21 volumio ntpd[2256]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 18 00:13:21 volumio dhcpcd[1129]: eth0: deleting route to 169.254.0.0/16 Aug 18 00:13:21 volumio avahi-daemon[753]: Withdrawing address record for 169.254.209.239 on eth0. Aug 18 00:13:21 volumio avahi-daemon[753]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.209.239. Aug 18 00:13:21 volumio avahi-daemon[753]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.10. Aug 18 00:13:21 volumio dhcpcd[823]: eth0: deleted route to 169.254.0.0/16 Aug 18 00:13:21 volumio dhcpcd[823]: eth0: adding route to 169.254.0.0/16 Aug 18 00:13:21 volumio dhcpcd[823]: if_route (ADD): Invalid argument Aug 18 00:13:21 volumio dhcpcd[823]: eth0: adding default route Aug 18 00:13:21 volumio dhcpcd[823]: if_route (ADD): Invalid argument Aug 18 00:13:22 volumio ntpd[2256]: Soliciting pool server 212.127.78.21 Aug 18 00:13:22 volumio volumio[1194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 18 00:13:22 volumio volumio[1194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 18 00:13:22 volumio volumio[1194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 18 00:13:23 volumio volumio[1194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 18 00:13:23 volumio volumio[1194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 18 00:13:23 volumio volumio[1194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 18 00:13:23 volumio volumio[1194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Aug 18 00:13:23 volumio ntpd[2256]: Soliciting pool server 213.222.217.10 Aug 18 00:13:23 volumio ntpd[2256]: Deleting interface #3 eth0, 169.254.209.239#123, interface stats: received=0, sent=0, dropped=0, active_time=2 secs Aug 18 00:13:23 volumio volumio[1194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Aug 18 00:13:23 volumio ntpd[2256]: Soliciting pool server 162.159.200.1 Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 18 00:13:23 volumio volumio[1194]: info: Adding plugin bluetooth to MyMusic Plugins Aug 18 00:13:23 volumio volumio[1194]: info: Adding plugin multiroom to MyMusic Plugins Aug 18 00:13:23 volumio volumio[1194]: info: Adding plugin metavolumio to MyMusic Plugins Aug 18 00:13:23 volumio volumio[1194]: info: Adding plugin cd_controller to MyMusic Plugins Aug 18 00:13:23 volumio volumio[1194]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 18 00:13:23 volumio volumio[1194]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 18 00:13:23 volumio volumio[1194]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 18 00:13:24 volumio ntpd[2256]: Soliciting pool server 185.157.13.96 Aug 18 00:13:24 volumio ntpd[2256]: Soliciting pool server 80.50.102.126 Aug 18 00:13:24 volumio ntpd[2256]: Soliciting pool server 91.227.212.78 Aug 18 00:13:24 volumio dhcpcd[823]: wlan0: offered 192.168.1.11 from 192.168.1.1 Aug 18 00:13:24 volumio dhcpcd[823]: wlan0: probing address 192.168.1.11/24 Aug 18 00:13:24 volumio volumio[1194]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 18 00:13:24 volumio volumio[1194]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 18 00:13:24 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 00:13:24 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 00:13:24 volumio volumio[1194]: info: Starting MyVolumio Remote Streaming Endpoints Aug 18 00:13:25 volumio volumio[1194]: info: MyVolumio login type: Token Aug 18 00:13:25 volumio volumio[1194]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 18 00:13:25 volumio volumio[1194]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 18 00:13:25 volumio ntpd[2256]: Soliciting pool server 162.159.200.123 Aug 18 00:13:25 volumio ntpd[2256]: Soliciting pool server 89.25.168.148 Aug 18 00:13:25 volumio ntpd[2256]: Soliciting pool server 185.79.243.74 Aug 18 00:13:25 volumio ntpd[2256]: Soliciting pool server 213.222.217.11 Aug 18 00:13:26 volumio volumio[1194]: info: Starting Streaming Service Transparent Proxy Aug 18 00:13:26 volumio volumio[1194]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 18 00:13:26 volumio volumio[1194]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 18 00:13:26 volumio volumio[1194]: info: Streaming services startup Aug 18 00:13:26 volumio volumio[1194]: info: Starting Streaming Daemon Aug 18 00:13:26 volumio sudo[2293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 18 00:13:26 volumio sudo[2293]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:13:26 volumio sudo[2293]: pam_unix(sudo:session): session closed for user root Aug 18 00:13:26 volumio volumio[1194]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 18 00:13:26 volumio volumio[1194]: error: Cannot start Volumio Streaming Daemon Aug 18 00:13:26 volumio ntpd[2256]: Soliciting pool server 193.70.94.182 Aug 18 00:13:26 volumio ntpd[2256]: Soliciting pool server 188.241.28.254 Aug 18 00:13:26 volumio ntpd[2256]: Soliciting pool server 89.161.47.132 Aug 18 00:13:26 volumio volumio[1194]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 18 00:13:26 volumio volumio[1194]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 18 00:13:26 volumio volumio[1194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Aug 18 00:13:26 volumio ntpd[2256]: Soliciting pool server 193.25.222.136 Aug 18 00:13:26 volumio volumio[1194]: error: MyVolumio Custom Token format not valid, refreshing it Aug 18 00:13:27 volumio volumio[1194]: STREAMING PROXY: Starting server on port 3245 Aug 18 00:13:27 volumio volumio[1194]: Node JS runtime: 14 Aug 18 00:13:27 volumio ntpd[2256]: Soliciting pool server 195.46.37.22 Aug 18 00:13:27 volumio ntpd[2256]: Soliciting pool server 54.37.233.160 Aug 18 00:13:27 volumio ntpd[2256]: Soliciting pool server 156.17.20.254 Aug 18 00:13:27 volumio volumio[1194]: info: MyVolumio login type: Token Aug 18 00:13:28 volumio volumio[1194]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 18 00:13:28 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 18 00:13:28 volumio volumio[1194]: info: Received Get System Info Aug 18 00:13:28 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 00:13:28 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 00:13:28 volumio volumio[1194]: info: Discovery: Getting this device information Aug 18 00:13:28 volumio volumio[1194]: info: CoreCommandRouter::volumioGetState Aug 18 00:13:28 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Aug 18 00:13:28 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 00:13:28 volumio volumio[1194]: info: MyVolumio token set successfully Aug 18 00:13:28 volumio volumio[1194]: info: MYVOLUMIO: Adding device Aug 18 00:13:28 volumio volumio[1194]: info: MYVOLUMIO: Evaluating Server Aug 18 00:13:28 volumio ntpd[2256]: receive: Unexpected origin timestamp 0xea6bbb29.50398ebf does not match aorg 0000000000.00000000 from server@185.157.13.96 xmt 0xea6bbb28.707d1e4b Aug 18 00:13:28 volumio volumio[1194]: info: MyVolumio status changed Aug 18 00:13:28 volumio volumio[1194]: info: Streaming services startup Aug 18 00:13:28 volumio volumio[1194]: info: Starting Streaming Daemon Aug 18 00:13:28 volumio volumio[1194]: info: Removing browser output: myVolumio user plan is not superstar Aug 18 00:13:28 volumio volumio[1194]: info: Removing audio output: Aug 18 00:13:28 volumio volumio[1194]: info: Stoppping Tunnel 1 Aug 18 00:13:28 volumio sudo[2319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 18 00:13:28 volumio sudo[2319]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:13:28 volumio sudo[2319]: pam_unix(sudo:session): session closed for user root Aug 18 00:13:28 volumio volumio[1194]: error: Failed to ping endpoint eu5.myvolumio.org : unknown error Aug 18 00:13:28 volumio volumio[1194]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 00:13:28 volumio volumio[1194]: Error: Unable to resolve or reject the same promise twice Aug 18 00:13:28 volumio volumio[1194]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 18 00:13:28 volumio volumio[1194]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32367) Aug 18 00:13:28 volumio volumio[1194]: at Socket.emit (events.js:400:28) Aug 18 00:13:28 volumio volumio[1194]: at addChunk (internal/streams/readable.js:293:12) Aug 18 00:13:28 volumio volumio[1194]: at readableAddChunk (internal/streams/readable.js:267:9) Aug 18 00:13:28 volumio volumio[1194]: at Socket.Readable.push (internal/streams/readable.js:206:10) Aug 18 00:13:28 volumio volumio[1194]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Aug 18 00:13:28 volumio sudo[2322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 18 00:13:28 volumio sudo[2322]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:13:28 volumio volumio[1194]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 00:13:28 volumio sudo[2322]: pam_unix(sudo:session): session closed for user root Aug 18 00:13:28 volumio dhcpcd[823]: wlan0: leased 192.168.1.11 for 86400 seconds Aug 18 00:13:28 volumio dhcpcd[823]: wlan0: adding route to 192.168.1.0/24 Aug 18 00:13:28 volumio avahi-daemon[753]: Registering new address record for 192.168.1.11 on wlan0.IPv4. Aug 18 00:13:28 volumio dhcpcd[823]: wlan0: adding default route via 192.168.1.1 Aug 18 00:13:28 volumio dhcpcd[823]: eth0: adding route to 169.254.0.0/16 Aug 18 00:13:28 volumio dhcpcd[823]: if_route (ADD): Invalid argument Aug 18 00:13:28 volumio sudo[2334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-18 00:12 Aug 18 00:13:28 volumio sudo[2334]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"