Sep 01 09:06:13 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Sep 01 09:06:13 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Sep 01 09:06:24 volumio kernel: usb 1-9: USB disconnect, device number 6 Sep 01 09:06:24 volumio kernel: usb 1-9.1: USB disconnect, device number 7 Sep 01 09:06:24 volumio kernel: usb 1-9.3: USB disconnect, device number 8 Sep 01 09:06:24 volumio acpid[762]: input device has been disconnected, fd 7 Sep 01 09:06:24 volumio acpid[762]: input device has been disconnected, fd 8 Sep 01 09:06:24 volumio kernel: usb 1-9.4: USB disconnect, device number 9 Sep 01 09:06:24 volumio acpid[762]: input device has been disconnected, fd 19 Sep 01 09:06:24 volumio acpid[762]: input device has been disconnected, fd 21 Sep 01 09:06:24 volumio acpid[762]: input device has been disconnected, fd 22 Sep 01 09:06:24 volumio acpid[762]: input device has been disconnected, fd 23 Sep 01 09:06:32 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 01 09:06:32 volumio dbus-daemon[769]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.22' (uid=0 pid=2007 comm="timedatectl show --property=NTPSynchronized --valu") Sep 01 09:06:32 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 01 09:06:32 volumio dbus-daemon[769]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 01 09:06:32 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 01 09:06:32 volumio setdatetime-helper.sh[2006]: Time is already synchronized. Sep 01 09:06:32 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 01 09:06:32 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 01 09:06:48 volumio kernel: usb 1-9: new full-speed USB device number 10 using xhci_hcd Sep 01 09:06:48 volumio kernel: usb 1-9: New USB device found, idVendor=0a05, idProduct=7211, bcdDevice= 1.00 Sep 01 09:06:48 volumio kernel: usb 1-9: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Sep 01 09:06:48 volumio kernel: usb 1-9: Product: USB2.0 HUB Sep 01 09:06:48 volumio kernel: hub 1-9:1.0: USB hub found Sep 01 09:06:48 volumio kernel: hub 1-9:1.0: 4 ports detected Sep 01 09:06:49 volumio kernel: usb 1-9.1: new full-speed USB device number 11 using xhci_hcd Sep 01 09:06:49 volumio kernel: usb 1-9.1: New USB device found, idVendor=0a05, idProduct=7211, bcdDevice= 1.00 Sep 01 09:06:49 volumio kernel: usb 1-9.1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Sep 01 09:06:49 volumio kernel: usb 1-9.1: Product: USB2.0 HUB Sep 01 09:06:49 volumio kernel: hub 1-9.1:1.0: USB hub found Sep 01 09:06:49 volumio kernel: hub 1-9.1:1.0: 4 ports detected Sep 01 09:06:49 volumio kernel: usb 1-9.3: new full-speed USB device number 12 using xhci_hcd Sep 01 09:06:49 volumio kernel: usb 1-9.3: New USB device found, idVendor=2717, idProduct=5001, bcdDevice= 1.00 Sep 01 09:06:49 volumio kernel: usb 1-9.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Sep 01 09:06:49 volumio kernel: usb 1-9.3: Product: MI wireless mouse Sep 01 09:06:49 volumio kernel: usb 1-9.3: Manufacturer: GTech Sep 01 09:06:49 volumio kernel: input: GTech MI wireless mouse Mouse as /devices/pci0000:00/0000:00:14.0/usb1/1-9/1-9.3/1-9.3:1.0/0003:2717:5001.0007/input/input32 Sep 01 09:06:49 volumio kernel: input: GTech MI wireless mouse as /devices/pci0000:00/0000:00:14.0/usb1/1-9/1-9.3/1-9.3:1.0/0003:2717:5001.0007/input/input33 Sep 01 09:06:49 volumio kernel: input: GTech MI wireless mouse Consumer Control as /devices/pci0000:00/0000:00:14.0/usb1/1-9/1-9.3/1-9.3:1.0/0003:2717:5001.0007/input/input34 Sep 01 09:06:49 volumio kernel: input: GTech MI wireless mouse System Control as /devices/pci0000:00/0000:00:14.0/usb1/1-9/1-9.3/1-9.3:1.0/0003:2717:5001.0007/input/input35 Sep 01 09:06:49 volumio kernel: hid-generic 0003:2717:5001.0007: input,hiddev0,hidraw0: USB HID v1.10 Mouse [GTech MI wireless mouse] on usb-0000:00:14.0-9.3/input0 Sep 01 09:06:49 volumio (udev-worker)[2060]: mouse0: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 09:06:49 volumio kernel: usb 1-9.4: new full-speed USB device number 13 using xhci_hcd Sep 01 09:06:49 volumio (udev-worker)[2059]: event3: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 09:06:49 volumio (udev-worker)[2061]: event5: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 09:06:49 volumio systemd-logind[776]: Watching system buttons on /dev/input/event5 (GTech MI wireless mouse Consumer Control) Sep 01 09:06:49 volumio (udev-worker)[2057]: event4: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 09:06:49 volumio (udev-worker)[2063]: event7: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 09:06:49 volumio systemd-logind[776]: Watching system buttons on /dev/input/event7 (GTech MI wireless mouse System Control) Sep 01 09:06:49 volumio kernel: usb 1-9.4: New USB device found, idVendor=040b, idProduct=0a67, bcdDevice= 0.01 Sep 01 09:06:49 volumio kernel: usb 1-9.4: New USB device strings: Mfr=0, Product=2, SerialNumber=0 Sep 01 09:06:49 volumio kernel: usb 1-9.4: Product: Gaming Keyboard Sep 01 09:06:49 volumio kernel: input: Gaming Keyboard as /devices/pci0000:00/0000:00:14.0/usb1/1-9/1-9.4/1-9.4:1.0/0003:040B:0A67.0008/input/input37 Sep 01 09:06:49 volumio kernel: hid-generic 0003:040B:0A67.0008: input,hidraw1: USB HID v1.10 Keyboard [Gaming Keyboard] on usb-0000:00:14.0-9.4/input0 Sep 01 09:06:49 volumio kernel: input: Gaming Keyboard System Control as /devices/pci0000:00/0000:00:14.0/usb1/1-9/1-9.4/1-9.4:1.1/0003:040B:0A67.0009/input/input38 Sep 01 09:06:49 volumio kernel: input: Gaming Keyboard Consumer Control as /devices/pci0000:00/0000:00:14.0/usb1/1-9/1-9.4/1-9.4:1.1/0003:040B:0A67.0009/input/input39 Sep 01 09:06:49 volumio kernel: input: Gaming Keyboard as /devices/pci0000:00/0000:00:14.0/usb1/1-9/1-9.4/1-9.4:1.1/0003:040B:0A67.0009/input/input40 Sep 01 09:06:49 volumio kernel: hid-generic 0003:040B:0A67.0009: input,hidraw2: USB HID v1.10 Keyboard [Gaming Keyboard] on usb-0000:00:14.0-9.4/input1 Sep 01 09:06:49 volumio (udev-worker)[2059]: event9: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 09:06:49 volumio systemd-logind[776]: Watching system buttons on /dev/input/event9 (Gaming Keyboard System Control) Sep 01 09:06:49 volumio (udev-worker)[2060]: event8: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 09:06:49 volumio (udev-worker)[2085]: event10: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 09:06:49 volumio systemd-logind[776]: Watching system buttons on /dev/input/event8 (Gaming Keyboard) Sep 01 09:06:49 volumio (udev-worker)[2088]: event11: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 09:06:49 volumio systemd-logind[776]: Watching system buttons on /dev/input/event11 (Gaming Keyboard) Sep 01 09:07:02 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 01 09:07:15 volumio dhcpcd[833]: eth0: carrier acquired Sep 01 09:07:15 volumio kernel: r8169 0000:02:00.0 eth0: Link is Up - 1Gbps/Full - flow control rx Sep 01 09:07:15 volumio dhcpcd[833]: eth0: IAID dc:94:96:39 Sep 01 09:07:16 volumio ifplugd(eth0)[1002]: Link beat detected. Sep 01 09:07:16 volumio ifplugd(eth0)[1002]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Sep 01 09:07:16 volumio ifplugd(eth0)[1002]: client: command failed: No such device (-19) Sep 01 09:07:16 volumio dhcpcd[833]: control command: dhcpcd eth0 Sep 01 09:07:16 volumio ifplugd(eth0)[1002]: client: sending commands to dhcpcd process Sep 01 09:07:16 volumio dhcpcd[833]: control_free: No such file or directory Sep 01 09:07:16 volumio dhcpcd[833]: eth0: soliciting an IPv6 router Sep 01 09:07:16 volumio dhcpcd[833]: eth0: soliciting a DHCP lease Sep 01 09:07:16 volumio ifplugd(eth0)[1002]: Program executed successfully. Sep 01 09:07:16 volumio dhcpcd[833]: eth0: offered 172.30.1.59 from 172.30.1.254 Sep 01 09:07:16 volumio dhcpcd[833]: eth0: probing address 172.30.1.59/24 Sep 01 09:07:22 volumio dhcpcd[833]: eth0: leased 172.30.1.59 for 3600 seconds Sep 01 09:07:22 volumio avahi-daemon[768]: Joining mDNS multicast group on interface eth0.IPv4 with address 172.30.1.59. Sep 01 09:07:22 volumio avahi-daemon[768]: New relevant interface eth0.IPv4 for mDNS. Sep 01 09:07:22 volumio avahi-daemon[768]: Registering new address record for 172.30.1.59 on eth0.IPv4. Sep 01 09:07:22 volumio dhcpcd[833]: eth0: adding route to 172.30.1.0/24 Sep 01 09:07:22 volumio dhcpcd[833]: eth0: adding default route via 172.30.1.254 Sep 01 09:07:22 volumio volumio[1052]: verbose: New Socket.io Connection to 172.30.1.59 from 172.30.1.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Sep 01 09:07:22 volumio volumio[1052]: verbose: New Socket.io Connection to 172.30.1.59 from 172.30.1.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Sep 01 09:07:22 volumio systemd[1]: welcome.service: Deactivated successfully. Sep 01 09:07:22 volumio volumio[1052]: verbose: New Socket.io Connection to 172.30.1.59 from 172.30.1.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Sep 01 09:07:22 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Sep 01 09:07:22 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Sep 01 09:07:22 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Sep 01 09:07:22 volumio welcome[2241]: Resolved ip:[1] 172.30.1.59 Sep 01 09:07:22 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Sep 01 09:07:22 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Sep 01 09:07:22 volumio volumio[1052]: info: Discovery: adding dc6d063d-cf6c-4d35-a730-1a2904c4aa87 Sep 01 09:07:22 volumio volumio[1052]: info: Discovery: Found device VolProx Sep 01 09:07:22 volumio volumio[1052]: info: Discovery: Connecting to remote: 172.30.1.70 Sep 01 09:07:22 volumio volumio[1052]: info: Discovery: Connected to remote: 172.30.1.70 Sep 01 09:07:23 volumio volumio[1052]: info: Discovery: this is already registered, 82436721-42b7-4ee7-bb80-3a4a38c5d39d Sep 01 09:07:23 volumio volumio[1052]: info: Discovery: Found device Volumio Sep 01 09:07:23 volumio volumio[1052]: info: CoreCommandRouter::volumioGetState Sep 01 09:07:23 volumio volumio[1052]: info: CorePlayQueue::getTrack 0 Sep 01 09:07:23 volumio volumio[1052]: verbose: New Socket.io Connection to 172.30.1.59:3000 from 172.30.1.70 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 01 09:07:23 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Sep 01 09:07:23 volumio volumio[1052]: info: CoreCommandRouter::volumioGetState Sep 01 09:07:23 volumio volumio[1052]: info: CorePlayQueue::getTrack 0 Sep 01 09:07:23 volumio volumio[1052]: info: Volumio Network Manager: Network status updated: 1 Sep 01 09:07:24 volumio ntpd[986]: IO: Listen normally on 3 eth0 172.30.1.59:123 Sep 01 09:07:24 volumio ntpd[986]: IO: new interface(s) found: waking up resolver Sep 01 09:07:24 volumio ntpd[986]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Sep 01 09:07:24 volumio ntpd[986]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Sep 01 09:07:24 volumio ntpd[986]: DNS: Pool taking: 194.0.5.123 Sep 01 09:07:24 volumio ntpd[986]: DNS: Pool taking: 175.195.167.194 Sep 01 09:07:24 volumio ntpd[986]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Sep 01 09:07:25 volumio ntpd[986]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Sep 01 09:07:25 volumio ntpd[986]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Sep 01 09:07:25 volumio ntpd[986]: DNS: Pool taking: 158.247.202.103 Sep 01 09:07:25 volumio ntpd[986]: DNS: Pool taking: 121.174.142.82 Sep 01 09:07:25 volumio ntpd[986]: DNS: Pool taking: 2001:678:8::123 Sep 01 09:07:25 volumio ntpd[986]: DNS: Pool taking: 2401:c080:1c00:24a1:5400:5ff:fe04:720 Sep 01 09:07:25 volumio ntpd[986]: DNS: Pool taking: 240b:400d:3:3300:aeda:71da:9779:d4f1 Sep 01 09:07:25 volumio ntpd[986]: DNS: Pool taking: 240b:400d:3:3300:aeda:71da:9779:d8f1 Sep 01 09:07:25 volumio ntpd[986]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Sep 01 09:07:26 volumio ntpd[986]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Sep 01 09:07:26 volumio ntpd[986]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Sep 01 09:07:26 volumio ntpd[986]: DNS: Pool taking: 121.174.142.81 Sep 01 09:07:26 volumio ntpd[986]: DNS: Pool taking: 221.151.118.78 Sep 01 09:07:26 volumio ntpd[986]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Sep 01 09:07:26 volumio volumio[1052]: verbose: New Socket.io Connection to 172.30.1.59 from 172.30.1.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Sep 01 09:07:26 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 01 09:07:26 volumio volumio[1052]: info: CoreCommandRouter::volumioGetVisibleSources Sep 01 09:07:26 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 09:07:26 volumio volumio[1052]: info: CoreCommandRouter::volumioGetState Sep 01 09:07:26 volumio volumio[1052]: info: CorePlayQueue::getTrack 0 Sep 01 09:07:26 volumio volumio[1052]: info: CoreCommandRouter::volumioGetState Sep 01 09:07:26 volumio volumio[1052]: info: CorePlayQueue::getTrack 0 Sep 01 09:07:26 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 01 09:07:26 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 01 09:07:26 volumio volumio[1052]: info: Received Get System Info Sep 01 09:07:26 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 09:07:26 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 09:07:26 volumio volumio[1052]: info: Discovery: Getting this device information Sep 01 09:07:26 volumio volumio[1052]: info: CoreCommandRouter::volumioGetState Sep 01 09:07:26 volumio volumio[1052]: info: CorePlayQueue::getTrack 0 Sep 01 09:07:26 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 09:07:26 volumio volumio[1052]: info: CoreCommandRouter::volumioGetState Sep 01 09:07:26 volumio volumio[1052]: info: CorePlayQueue::getTrack 0 Sep 01 09:07:26 volumio volumio[1052]: info: Listing playlists Sep 01 09:07:27 volumio ntpd[986]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Sep 01 09:07:27 volumio ntpd[986]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Sep 01 09:07:27 volumio ntpd[986]: DNS: Pool taking: 175.210.18.47 Sep 01 09:07:27 volumio ntpd[986]: DNS: Pool skipping: 121.174.142.82 Sep 01 09:07:27 volumio ntpd[986]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Sep 01 09:07:27 volumio volumio[1052]: verbose: New Socket.io Connection to volumio.local from 172.30.1.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Sep 01 09:07:27 volumio volumio[1052]: info: CoreCommandRouter::volumioGetState Sep 01 09:07:27 volumio volumio[1052]: info: CorePlayQueue::getTrack 0 Sep 01 09:07:27 volumio volumio[1052]: info: CoreCommandRouter::volumioGetQueue Sep 01 09:07:27 volumio volumio[1052]: info: CoreStateMachine::getQueue Sep 01 09:07:27 volumio volumio[1052]: info: CorePlayQueue::getQueue Sep 01 09:07:27 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Sep 01 09:07:27 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Sep 01 09:07:27 volumio volumio[1052]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Sep 01 09:07:27 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Sep 01 09:07:27 volumio volumio[1052]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Sep 01 09:07:27 volumio volumio[1052]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Sep 01 09:07:27 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Sep 01 09:07:29 volumio volumio[1052]: info: CoreCommandRouter::volumioGetState Sep 01 09:07:29 volumio volumio[1052]: info: CorePlayQueue::getTrack 0 Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 01 09:07:30 volumio volumio[1052]: info: Adding plugin bluetooth to MyMusic Plugins Sep 01 09:07:30 volumio volumio[1052]: info: Adding plugin multiroom to MyMusic Plugins Sep 01 09:07:30 volumio volumio[1052]: info: Adding plugin metavolumio to MyMusic Plugins Sep 01 09:07:30 volumio volumio[1052]: info: Adding plugin cd_controller to MyMusic Plugins Sep 01 09:07:30 volumio volumio[1052]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 01 09:07:30 volumio volumio[1052]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 01 09:07:30 volumio volumio[1052]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 01 09:07:30 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 09:07:30 volumio volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 09:07:30 volumio volumio[1052]: info: Starting MyVolumio Remote Streaming Endpoints Sep 01 09:07:30 volumio volumio[1052]: info: MyVolumio login type: Token Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 01 09:07:30 volumio volumio[1052]: info: Streaming services startup Sep 01 09:07:30 volumio volumio[1052]: info: Starting Streaming Daemon Sep 01 09:07:30 volumio sudo[2284]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 09:07:30 volumio sudo[2284]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 09:07:30 volumio volumio[1052]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 01 09:07:30 volumio sudo[2284]: pam_unix(sudo:session): session closed for user root Sep 01 09:07:30 volumio volumio[1052]: error: Cannot start Volumio Streaming Daemon Sep 01 09:07:30 volumio volumio[1052]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 09:07:30 volumio volumio[1052]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 09:07:30 volumio volumio[1052]: error: MyVolumio Custom Token format not valid, refreshing it Sep 01 09:07:31 volumio ntpd[986]: CLOCK: time stepped by 0.425059 Sep 01 09:07:31 volumio ntpd[986]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Sep 01 09:07:32 volumio kernel: usb 1-9: USB disconnect, device number 10 Sep 01 09:07:32 volumio kernel: usb 1-9.1: USB disconnect, device number 11 Sep 01 09:07:32 volumio kernel: usb 1-9.3: USB disconnect, device number 12 Sep 01 09:07:32 volumio acpid[762]: input device has been disconnected, fd 7 Sep 01 09:07:32 volumio volumio[1052]: info: MyVolumio login type: Token Sep 01 09:07:32 volumio acpid[762]: input device has been disconnected, fd 8 Sep 01 09:07:32 volumio kernel: usb 1-9.4: USB disconnect, device number 13 Sep 01 09:07:32 volumio acpid[762]: input device has been disconnected, fd 19 Sep 01 09:07:32 volumio acpid[762]: input device has been disconnected, fd 21 Sep 01 09:07:32 volumio acpid[762]: input device has been disconnected, fd 22 Sep 01 09:07:32 volumio acpid[762]: input device has been disconnected, fd 23 Sep 01 09:07:33 volumio volumio[1052]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 01 09:07:33 volumio volumio[1052]: info: MyVolumio token set successfully Sep 01 09:07:33 volumio volumio[1052]: info: MYVOLUMIO: Adding device Sep 01 09:07:33 volumio volumio[1052]: info: MYVOLUMIO: Evaluating Server Sep 01 09:07:34 volumio volumio[1052]: info: MyVolumio status changed Sep 01 09:07:34 volumio volumio[1052]: info: Streaming services startup Sep 01 09:07:34 volumio volumio[1052]: info: Starting Streaming Daemon Sep 01 09:07:34 volumio volumio[1052]: info: Removing browser output: myVolumio user plan is not superstar Sep 01 09:07:34 volumio volumio[1052]: info: Removing audio output: Sep 01 09:07:34 volumio volumio[1052]: info: Stoppping Tunnel 1 Sep 01 09:07:34 volumio sudo[2333]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 09:07:34 volumio sudo[2333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 09:07:34 volumio sudo[2335]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 01 09:07:34 volumio sudo[2335]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 09:07:34 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 01 09:07:34 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 09:07:34 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 09:07:34 volumio sudo[2333]: pam_unix(sudo:session): session closed for user root Sep 01 09:07:34 volumio volumio[1052]: error: Cannot start Volumio Streaming Daemon Sep 01 09:07:34 volumio volumio[1052]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 09:07:34 volumio volumio[1052]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 09:07:34 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 09:07:34 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 09:07:34 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 09:07:34 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 01 09:07:34 volumio sudo[2335]: pam_unix(sudo:session): session closed for user root Sep 01 09:07:34 volumio volumio[1052]: info: Remote SSH Stopped Sep 01 09:07:34 volumio dbus-daemon[769]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.26' (uid=0 pid=2339 comm="timedatectl show --property=NTPSynchronized --valu") Sep 01 09:07:34 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 01 09:07:34 volumio dbus-daemon[769]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 01 09:07:34 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 01 09:07:34 volumio setdatetime-helper.sh[2338]: Time is not synchronized. Attempting to sync... Sep 01 09:07:34 volumio sudo[2348]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Mon, 01 Sep 2025 00:07:34 GMT#015' Sep 01 09:07:34 volumio sudo[2348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 01 09:07:34 volumio setdatetime-helper.sh[2349]: Mon Sep 1 09:07:34 KST 2025 Sep 01 09:07:34 volumio setdatetime-helper.sh[2338]: Time synchronized successfully. Sep 01 09:07:34 volumio sudo[2348]: pam_unix(sudo:session): session closed for user root Sep 01 09:07:34 volumio volumio[1052]: error: Failed to ping endpoint us2.myvolumio.org : unknown error Sep 01 09:07:34 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 01 09:07:34 volumio volumio[1052]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 09:07:34 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 01 09:07:34 volumio systemd-journald[504]: Time jumped backwards, rotating. Sep 01 09:07:34 volumio volumio[1052]: Error: Unable to resolve or reject the same promise twice Sep 01 09:07:34 volumio volumio[1052]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Sep 01 09:07:34 volumio volumio[1052]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32090) Sep 01 09:07:34 volumio volumio[1052]: at Socket.emit (node:events:526:35) Sep 01 09:07:34 volumio volumio[1052]: at endReadableNT (node:internal/streams/readable:1376:12) Sep 01 09:07:34 volumio volumio[1052]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) Sep 01 09:07:34 volumio volumio[1052]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 09:07:34 volumio sudo[2365]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-01 09:06' Sep 01 09:07:34 volumio sudo[2365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="30967a3eaa5e867c9c73ed66f5e07b238b646dd7" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="c82d2341a3a7e284141656a9703a39c694911dfd" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jul 12 07:16:26 UTC 2025" VOLUMIO_VERSION="4.016" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ff40ef679e92e6c83bf574fa4db37120"