-- Logs begin at Sun 2024-08-25 09:44:21 UTC, end at Sun 2024-08-25 09:55:12 UTC. -- Aug 25 09:54:07 volumio ntpd[939]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 25 09:54:08 volumio ntpd[939]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 25 09:54:11 volumio ntpd[939]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 25 09:54:18 volumio ntpd[939]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 25 09:54:48 volumio nmbd[911]: [2024/08/25 09:54:48.018161, 0] ../source3/libsmb/nmblib.c:917(send_udp) Aug 25 09:54:48 volumio nmbd[911]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Aug 25 09:54:48 volumio nmbd[911]: [2024/08/25 09:54:48.018273, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Aug 25 09:54:48 volumio nmbd[911]: send_netbios_packet: send_packet() to IP 169.254.255.255 port 137 failed Aug 25 09:54:48 volumio nmbd[911]: [2024/08/25 09:54:48.018318, 0] ../source3/nmbd/nmbd_namequery.c:245(query_name) Aug 25 09:54:48 volumio nmbd[911]: query_name: Failed to send packet trying to query name WORKGROUP<1d> Aug 25 09:54:55 volumio dhcpcd[861]: eth1: carrier acquired Aug 25 09:54:55 volumio dhcpcd[861]: eth1: IAID 59:64:c3:46 Aug 25 09:54:55 volumio kernel: r8169 0000:03:00.0 eth1: Link is Up - 1Gbps/Full - flow control off Aug 25 09:54:56 volumio dhcpcd[861]: eth1: soliciting an IPv6 router Aug 25 09:54:56 volumio dhcpcd[861]: eth1: soliciting a DHCP lease Aug 25 09:54:56 volumio dhcpcd[861]: eth1: offered 192.168.1.41 from 192.168.1.1 Aug 25 09:54:56 volumio dhcpcd[861]: eth1: probing address 192.168.1.41/24 Aug 25 09:55:02 volumio dhcpcd[861]: eth1: leased 192.168.1.41 for 86400 seconds Aug 25 09:55:02 volumio avahi-daemon[772]: Joining mDNS multicast group on interface eth1.IPv4 with address 192.168.1.41. Aug 25 09:55:02 volumio dhcpcd[861]: eth1: adding route to 192.168.1.0/24 Aug 25 09:55:02 volumio dhcpcd[861]: eth1: adding default route via 192.168.1.1 Aug 25 09:55:02 volumio avahi-daemon[772]: New relevant interface eth1.IPv4 for mDNS. Aug 25 09:55:02 volumio avahi-daemon[772]: Registering new address record for 192.168.1.41 on eth1.IPv4. Aug 25 09:55:02 volumio ntpd[939]: ntpd exiting on signal 15 (Terminated) Aug 25 09:55:02 volumio systemd[1]: Stopping Network Time Service... Aug 25 09:55:02 volumio systemd[1]: ntp.service: Succeeded. Aug 25 09:55:02 volumio systemd[1]: Stopped Network Time Service. Aug 25 09:55:02 volumio systemd[1]: Starting Network Time Service... Aug 25 09:55:02 volumio ntpd[2639]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 25 09:55:02 volumio ntpd[2639]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Aug 25 09:55:02 volumio systemd[1]: Started Network Time Service. Aug 25 09:55:02 volumio ntpd[2645]: proto: precision = 0.064 usec (-24) Aug 25 09:55:02 volumio ntpd[2645]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 25 09:55:02 volumio ntpd[2645]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 25 09:55:02 volumio ntpd[2645]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 607 days ago Aug 25 09:55:02 volumio ntpd[2645]: Listen and drop on 0 v6wildcard [::]:123 Aug 25 09:55:02 volumio ntpd[2645]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 25 09:55:02 volumio ntpd[2645]: Listen normally on 2 lo 127.0.0.1:123 Aug 25 09:55:02 volumio ntpd[2645]: Listen normally on 3 eth1 192.168.1.41:123 Aug 25 09:55:02 volumio ntpd[2645]: Listening on routing socket on fd #20 for interface updates Aug 25 09:55:02 volumio ntpd[2645]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 25 09:55:02 volumio ntpd[2645]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 25 09:55:02 volumio volumio[1036]: info: Discovery: adding 54833fba-7437-4099-9a43-65d6428d3a70 Aug 25 09:55:02 volumio volumio[1036]: info: Discovery: Found device Volumio Aug 25 09:55:02 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Aug 25 09:55:02 volumio volumio[1036]: info: CorePlayQueue::getTrack 0 Aug 25 09:55:03 volumio ntpd[2645]: Soliciting pool server 195.46.37.22 Aug 25 09:55:04 volumio ntpd[2645]: Soliciting pool server 80.50.102.206 Aug 25 09:55:04 volumio ntpd[2645]: Soliciting pool server 162.159.200.1 Aug 25 09:55:04 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 25 09:55:04 volumio volumio[1036]: info: Received Get System Info Aug 25 09:55:04 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 09:55:04 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 09:55:04 volumio volumio[1036]: info: Discovery: Getting this device information Aug 25 09:55:04 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Aug 25 09:55:04 volumio volumio[1036]: info: CorePlayQueue::getTrack 0 Aug 25 09:55:04 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 09:55:05 volumio ntpd[2645]: Soliciting pool server 89.174.70.2 Aug 25 09:55:05 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Aug 25 09:55:05 volumio ntpd[2645]: Soliciting pool server 91.212.242.21 Aug 25 09:55:06 volumio ntpd[2645]: Soliciting pool server 91.212.242.20 Aug 25 09:55:06 volumio ntpd[2645]: Soliciting pool server 193.25.222.136 Aug 25 09:55:06 volumio ntpd[2645]: Soliciting pool server 91.210.128.220 Aug 25 09:55:07 volumio ntpd[2645]: Soliciting pool server 193.70.94.182 Aug 25 09:55:07 volumio ntpd[2645]: Soliciting pool server 2a03:7580:1:217::11 Aug 25 09:55:07 volumio ntpd[2645]: Soliciting pool server 150.254.65.62 Aug 25 09:55:08 volumio ntpd[2645]: Soliciting pool server 188.241.28.254 Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 25 09:55:08 volumio volumio[1036]: info: Adding plugin bluetooth to MyMusic Plugins Aug 25 09:55:08 volumio volumio[1036]: info: Adding plugin multiroom to MyMusic Plugins Aug 25 09:55:08 volumio volumio[1036]: info: Adding plugin metavolumio to MyMusic Plugins Aug 25 09:55:08 volumio volumio[1036]: info: Adding plugin cd_controller to MyMusic Plugins Aug 25 09:55:08 volumio volumio[1036]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 25 09:55:08 volumio volumio[1036]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 25 09:55:08 volumio volumio[1036]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 25 09:55:09 volumio volumio[1036]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 25 09:55:09 volumio volumio[1036]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 25 09:55:09 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 09:55:09 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 09:55:09 volumio volumio[1036]: info: Starting MyVolumio Remote Streaming Endpoints Aug 25 09:55:09 volumio volumio[1036]: info: MyVolumio login type: Token Aug 25 09:55:09 volumio volumio[1036]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 25 09:55:09 volumio volumio[1036]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 25 09:55:09 volumio volumio[1036]: info: Starting Streaming Service Transparent Proxy Aug 25 09:55:09 volumio volumio[1036]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 25 09:55:09 volumio volumio[1036]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 25 09:55:09 volumio volumio[1036]: info: Streaming services startup Aug 25 09:55:09 volumio volumio[1036]: info: Starting Streaming Daemon Aug 25 09:55:09 volumio sudo[2663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 25 09:55:09 volumio sudo[2663]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 09:55:09 volumio sudo[2663]: pam_unix(sudo:session): session closed for user root Aug 25 09:55:09 volumio volumio[1036]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 25 09:55:09 volumio volumio[1036]: error: Cannot start Volumio Streaming Daemon Aug 25 09:55:09 volumio volumio[1036]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 25 09:55:09 volumio volumio[1036]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 25 09:55:10 volumio volumio[1036]: STREAMING PROXY: Starting server on port 3245 Aug 25 09:55:10 volumio volumio[1036]: Node JS runtime: 14 Aug 25 09:55:10 volumio volumio[1036]: error: MyVolumio Custom Token format not valid, refreshing it Aug 25 09:55:11 volumio volumio[1036]: info: MyVolumio login type: Token Aug 25 09:55:11 volumio volumio[1036]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 25 09:55:11 volumio volumio[1036]: info: MyVolumio token set successfully Aug 25 09:55:11 volumio volumio[1036]: info: MYVOLUMIO: Adding device Aug 25 09:55:11 volumio volumio[1036]: info: MYVOLUMIO: Evaluating Server Aug 25 09:55:12 volumio volumio[1036]: info: MyVolumio Plan changed: premium Aug 25 09:55:12 volumio volumio[1036]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Aug 25 09:55:12 volumio volumio[1036]: info: Removing browser output: myVolumio user plan is not superstar Aug 25 09:55:12 volumio volumio[1036]: info: Removing audio output: Aug 25 09:55:12 volumio volumio[1036]: info: MYVOLUMIO: Adding device Aug 25 09:55:12 volumio volumio[1036]: info: MYVOLUMIO: Evaluating Server Aug 25 09:55:12 volumio volumio[1036]: info: Remote config written successfully Aug 25 09:55:12 volumio volumio[1036]: info: Starting Tunnel 1 Aug 25 09:55:12 volumio volumio[1036]: info: Starting Tunnel Connection Checker Aug 25 09:55:10 volumio volumio[1036]: info: MYVolumio Device enabled Aug 25 09:55:10 volumio volumio[1036]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Aug 25 09:55:10 volumio volumio[1036]: info: MyVolumio status changed Aug 25 09:55:10 volumio volumio[1036]: info: Streaming services startup Aug 25 09:55:10 volumio volumio[1036]: info: Starting Streaming Daemon Aug 25 09:55:10 volumio volumio[1036]: error: Failed to ping endpoint us2.myvolumio.org : unknown error Aug 25 09:55:10 volumio volumio[1036]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 09:55:10 volumio sudo[2704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 25 09:55:10 volumio sudo[2704]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 09:55:10 volumio volumio[1036]: Error: Unable to resolve or reject the same promise twice Aug 25 09:55:10 volumio volumio[1036]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 25 09:55:10 volumio volumio[1036]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Aug 25 09:55:10 volumio volumio[1036]: at Socket.emit (events.js:412:35) Aug 25 09:55:10 volumio volumio[1036]: at endReadableNT (internal/streams/readable.js:1333:12) Aug 25 09:55:10 volumio volumio[1036]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Aug 25 09:55:10 volumio volumio[1036]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 09:55:10 volumio sudo[2704]: pam_unix(sudo:session): session closed for user root Aug 25 09:55:10 volumio sudo[2715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-25 09:54 Aug 25 09:55:10 volumio sudo[2715]: 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"