-- Logs begin at Mon 2024-09-09 17:57:52 +07, end at Mon 2024-09-09 18:00:36 +07. -- Sep 09 17:59:01 volumio ntpd[813]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Sep 09 17:59:05 volumio ntpd[813]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Sep 09 17:59:06 volumio ntpd[813]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 09 17:59:07 volumio ntpd[813]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 09 17:59:17 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Sep 09 17:59:17 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Sep 09 17:59:26 volumio systemd[1]: nmbd.service: Start operation timed out. Terminating. Sep 09 17:59:26 volumio systemd[1]: nmbd.service: Main process exited, code=killed, status=15/TERM Sep 09 17:59:26 volumio systemd[1]: nmbd.service: Failed with result 'timeout'. Sep 09 17:59:26 volumio systemd[1]: Failed to start Samba NMB Daemon. Sep 09 17:59:26 volumio systemd[1]: Starting Samba Winbind Daemon... Sep 09 17:59:27 volumio winbindd[1659]: [2024/09/09 17:59:27.206922, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Sep 09 17:59:27 volumio winbindd[1659]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Sep 09 17:59:27 volumio winbindd[1659]: [2024/09/09 17:59:27.214599, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 09 17:59:27 volumio systemd[1]: Started Samba Winbind Daemon. Sep 09 17:59:27 volumio winbindd[1659]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Sep 09 17:59:27 volumio systemd[1]: Starting Samba SMB Daemon... Sep 09 17:59:27 volumio smbd[1663]: [2024/09/09 17:59:27.642516, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 09 17:59:27 volumio systemd[1]: Started Samba SMB Daemon. Sep 09 17:59:27 volumio smbd[1663]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Sep 09 17:59:27 volumio systemd[1]: Reached target Multi-User System. Sep 09 17:59:27 volumio systemd[1]: Reached target Graphical Interface. Sep 09 17:59:27 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 09 17:59:27 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 09 17:59:27 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 09 17:59:27 volumio systemd[1]: Startup finished in 8.245s (firmware) + 2.391s (loader) + 21.057s (kernel) + 1min 36.553s (userspace) = 2min 8.247s. Sep 09 17:59:50 volumio dhcpcd[721]: eth0: carrier acquired Sep 09 17:59:50 volumio kernel: r8169 0000:01:00.0 eth0: Link is Up - 1Gbps/Full - flow control off Sep 09 17:59:50 volumio dhcpcd[721]: eth0: IAID cf:bd:f0:a5 Sep 09 17:59:50 volumio dhcpcd[721]: eth0: rebinding lease of 192.168.1.16 Sep 09 17:59:50 volumio dhcpcd[721]: eth0: soliciting an IPv6 router Sep 09 17:59:51 volumio ifplugd(eth0)[815]: Link beat detected. Sep 09 17:59:51 volumio ifplugd(eth0)[815]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Sep 09 17:59:51 volumio ifplugd(eth0)[815]: client: ifup: interface eth0 already configured Sep 09 17:59:51 volumio ifplugd(eth0)[815]: Program executed successfully. Sep 09 17:59:55 volumio dhcpcd[721]: eth0: probing for an IPv4LL address Sep 09 17:59:55 volumio dhcpcd[721]: eth0: DHCP lease expired Sep 09 17:59:55 volumio dhcpcd[721]: eth0: soliciting a DHCP lease Sep 09 18:00:00 volumio dhcpcd[721]: eth0: using IPv4LL address 169.254.179.106 Sep 09 18:00:00 volumio avahi-daemon[609]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.179.106. Sep 09 18:00:00 volumio avahi-daemon[609]: New relevant interface eth0.IPv4 for mDNS. Sep 09 18:00:00 volumio avahi-daemon[609]: Registering new address record for 169.254.179.106 on eth0.IPv4. Sep 09 18:00:00 volumio dhcpcd[721]: eth0: adding route to 169.254.0.0/16 Sep 09 18:00:00 volumio dhcpcd[721]: eth0: adding default route Sep 09 18:00:01 volumio ntpd[813]: Listen normally on 3 eth0 169.254.179.106:123 Sep 09 18:00:01 volumio ntpd[813]: new interface(s) found: waking up resolver Sep 09 18:00:23 volumio dhcpcd[721]: eth0: offered 192.168.1.16 from 192.168.1.1 Sep 09 18:00:23 volumio dhcpcd[721]: eth0: probing address 192.168.1.16/24 Sep 09 18:00:28 volumio dhcpcd[721]: eth0: leased 192.168.1.16 for 86400 seconds Sep 09 18:00:28 volumio dhcpcd[721]: eth0: adding route to 192.168.1.0/24 Sep 09 18:00:28 volumio dhcpcd[721]: eth0: changing default route via 192.168.1.1 Sep 09 18:00:28 volumio avahi-daemon[609]: Registering new address record for 192.168.1.16 on eth0.IPv4. Sep 09 18:00:28 volumio ntpd[813]: ntpd exiting on signal 15 (Terminated) Sep 09 18:00:28 volumio systemd[1]: Stopping Network Time Service... Sep 09 18:00:28 volumio systemd[1]: ntp.service: Succeeded. Sep 09 18:00:28 volumio systemd[1]: Stopped Network Time Service. Sep 09 18:00:28 volumio systemd[1]: Starting Network Time Service... Sep 09 18:00:28 volumio ntpd[1777]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 09 18:00:28 volumio ntpd[1777]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 09 18:00:28 volumio systemd[1]: Started Network Time Service. Sep 09 18:00:28 volumio avahi-daemon[609]: Withdrawing address record for 169.254.179.106 on eth0. Sep 09 18:00:28 volumio avahi-daemon[609]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.179.106. Sep 09 18:00:28 volumio dhcpcd[721]: eth0: deleting route to 169.254.0.0/16 Sep 09 18:00:28 volumio ntpd[1783]: proto: precision = 0.191 usec (-22) Sep 09 18:00:28 volumio ntpd[1783]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 09 18:00:28 volumio ntpd[1783]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 09 18:00:28 volumio ntpd[1783]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 622 days ago Sep 09 18:00:28 volumio avahi-daemon[609]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.16. Sep 09 18:00:28 volumio ntpd[1783]: Listen and drop on 0 v6wildcard [::]:123 Sep 09 18:00:28 volumio ntpd[1783]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 09 18:00:28 volumio ntpd[1783]: Listen normally on 2 lo 127.0.0.1:123 Sep 09 18:00:28 volumio ntpd[1783]: Listen normally on 3 eth0 192.168.1.16:123 Sep 09 18:00:28 volumio ntpd[1783]: Listening on routing socket on fd #20 for interface updates Sep 09 18:00:28 volumio ntpd[1783]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 09 18:00:28 volumio ntpd[1783]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 09 18:00:29 volumio ntpd[1783]: Soliciting pool server 103.130.217.41 Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 09 18:00:30 volumio volumio[848]: info: Adding plugin bluetooth to MyMusic Plugins Sep 09 18:00:30 volumio volumio[848]: info: Adding plugin multiroom to MyMusic Plugins Sep 09 18:00:30 volumio volumio[848]: info: Adding plugin metavolumio to MyMusic Plugins Sep 09 18:00:30 volumio volumio[848]: info: Adding plugin cd_controller to MyMusic Plugins Sep 09 18:00:30 volumio volumio[848]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 09 18:00:30 volumio volumio[848]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 09 18:00:30 volumio volumio[848]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 09 18:00:30 volumio ntpd[1783]: Soliciting pool server 115.165.161.155 Sep 09 18:00:30 volumio ntpd[1783]: Soliciting pool server 27.71.27.209 Sep 09 18:00:31 volumio volumio[848]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 09 18:00:31 volumio volumio[848]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 09 18:00:31 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 18:00:31 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 18:00:31 volumio volumio[848]: info: Starting MyVolumio Remote Streaming Endpoints Sep 09 18:00:31 volumio volumio[848]: info: MyVolumio login type: Token Sep 09 18:00:31 volumio volumio[848]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 09 18:00:31 volumio volumio[848]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 09 18:00:31 volumio ntpd[1783]: Soliciting pool server 103.184.124.23 Sep 09 18:00:31 volumio ntpd[1783]: Soliciting pool server 103.184.124.23 Sep 09 18:00:31 volumio ntpd[1783]: Soliciting pool server 103.184.124.23 Sep 09 18:00:32 volumio volumio[848]: info: Starting Streaming Service Transparent Proxy Sep 09 18:00:32 volumio volumio[848]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 09 18:00:32 volumio volumio[848]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 09 18:00:32 volumio volumio[848]: info: Streaming services startup Sep 09 18:00:32 volumio volumio[848]: info: Starting Streaming Daemon Sep 09 18:00:32 volumio sudo[1807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 09 18:00:32 volumio sudo[1807]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 18:00:32 volumio sudo[1807]: pam_unix(sudo:session): session closed for user root Sep 09 18:00:32 volumio volumio[848]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 09 18:00:32 volumio volumio[848]: info: Discovery: adding 08b31baa-f979-4e1a-b8e6-8575fc5437db Sep 09 18:00:32 volumio volumio[848]: info: Discovery: Found device Volumio Sep 09 18:00:32 volumio volumio[848]: info: CoreCommandRouter::volumioGetState Sep 09 18:00:32 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Sep 09 18:00:32 volumio volumio[848]: error: Cannot start Volumio Streaming Daemon Sep 09 18:00:32 volumio volumio[848]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 09 18:00:32 volumio volumio[848]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 09 18:00:33 volumio volumio[848]: error: MyVolumio Custom Token format not valid, refreshing it Sep 09 18:00:33 volumio volumio[848]: STREAMING PROXY: Starting server on port 3245 Sep 09 18:00:33 volumio volumio[848]: Node JS runtime: 14 Sep 09 18:00:34 volumio volumio[848]: info: MyVolumio login type: Token Sep 09 18:00:35 volumio volumio[848]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 09 18:00:36 volumio volumio[848]: info: MyVolumio token set successfully Sep 09 18:00:36 volumio volumio[848]: info: MYVOLUMIO: Adding device Sep 09 18:00:36 volumio volumio[848]: info: MYVOLUMIO: Evaluating Server Sep 09 18:00:36 volumio volumio[848]: info: MyVolumio status changed Sep 09 18:00:36 volumio volumio[848]: info: Streaming services startup Sep 09 18:00:36 volumio volumio[848]: info: Starting Streaming Daemon Sep 09 18:00:36 volumio volumio[848]: info: Removing browser output: myVolumio user plan is not superstar Sep 09 18:00:36 volumio volumio[848]: info: Removing audio output: Sep 09 18:00:36 volumio volumio[848]: info: Stoppping Tunnel 1 Sep 09 18:00:36 volumio sudo[1832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 09 18:00:36 volumio sudo[1832]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 18:00:36 volumio sudo[1832]: pam_unix(sudo:session): session closed for user root Sep 09 18:00:36 volumio volumio[848]: error: Cannot start Volumio Streaming Daemon Sep 09 18:00:36 volumio volumio[848]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 09 18:00:36 volumio volumio[848]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 09 18:00:36 volumio sudo[1835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 09 18:00:36 volumio sudo[1835]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 18:00:36 volumio sudo[1835]: pam_unix(sudo:session): session closed for user root Sep 09 18:00:36 volumio volumio[848]: info: Remote SSH Stopped Sep 09 18:00:36 volumio ntpd[1783]: receive: Unexpected origin timestamp 0xea8953d4.e82c9d79 does not match aorg 0000000000.00000000 from server@103.184.124.23 xmt 0xea8953d4.7b7d7bab Sep 09 18:00:36 volumio volumio[848]: error: Failed to ping endpoint as2.myvolumio.org : unknown error Sep 09 18:00:36 volumio volumio[848]: info: Setting Geolocation for MyVolumio to as1 Sep 09 18:00:36 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 18:00:36 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 18:00:36 volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 18:00:36 volumio volumio[848]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 09 18:00:36 volumio volumio[848]: Error: Unable to resolve or reject the same promise twice Sep 09 18:00:36 volumio volumio[848]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Sep 09 18:00:36 volumio volumio[848]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Sep 09 18:00:36 volumio volumio[848]: at Socket.emit (events.js:412:35) Sep 09 18:00:36 volumio volumio[848]: at endReadableNT (internal/streams/readable.js:1333:12) Sep 09 18:00:36 volumio volumio[848]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Sep 09 18:00:36 volumio volumio[848]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 09 18:00:36 volumio sudo[1847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-09 17:59 Sep 09 18:00:36 volumio sudo[1847]: 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"