-- Logs begin at Mon 2025-02-24 13:58:27 EET, end at Mon 2025-02-24 14:00:54 EET. -- Feb 24 13:59:01 sand-bar---volumio volumio[1036]: info: BOOT COMPLETED Feb 24 13:59:03 sand-bar---volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 13:59:03 sand-bar---volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 13:59:03 sand-bar---volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 24 13:59:03 sand-bar---volumio volumio[1036]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.779&uuid=fa5e381e53fe789df3605a93e2e9a97b" http://updates.volumio.org/downloader-v1/track-device Feb 24 13:59:03 sand-bar---volumio volumio[1036]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 24 13:59:03 sand-bar---volumio volumio[1036]: Dload Upload Total Spent Left Speed Feb 24 13:59:03 sand-bar---volumio volumio[1036]: [132B blob data] Feb 24 13:59:03 sand-bar---volumio volumio[1036]: retrying in 5 seconds, trial 2 Feb 24 13:59:03 sand-bar---volumio volumio[1036]: info: Volumio Calling Home Feb 24 13:59:04 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Fetching time from Volumio... Feb 24 13:59:04 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Date not found in response Feb 24 13:59:04 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Retrying in 5 seconds... Feb 24 13:59:09 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Fetching time from Volumio... Feb 24 13:59:09 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Date not found in response Feb 24 13:59:09 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Retrying in 5 seconds... Feb 24 13:59:14 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Fetching time from Volumio... Feb 24 13:59:14 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Date not found in response Feb 24 13:59:14 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Retrying in 5 seconds... Feb 24 13:59:19 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Fetching time from Volumio... Feb 24 13:59:19 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Date not found in response Feb 24 13:59:19 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Retrying in 5 seconds... Feb 24 13:59:24 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Fetching time from Volumio... Feb 24 13:59:24 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Date not found in response Feb 24 13:59:24 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Retrying in 5 seconds... Feb 24 13:59:29 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Fetching time from Volumio... Feb 24 13:59:29 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Date not found in response Feb 24 13:59:29 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Retrying in 5 seconds... Feb 24 13:59:31 sand-bar---volumio dhcpcd[850]: eth0: carrier acquired Feb 24 13:59:31 sand-bar---volumio kernel: e1000e 0000:00:1f.6 eth0: NIC Link is Up 1000 Mbps Full Duplex, Flow Control: None Feb 24 13:59:31 sand-bar---volumio dhcpcd[850]: eth0: IAID 30:6b:eb:0b Feb 24 13:59:31 sand-bar---volumio dhcpcd[850]: eth0: adding address fe80::f64d:30ff:fe6b:eb0b Feb 24 13:59:31 sand-bar---volumio dhcpcd[850]: ipv6_addaddr1: Permission denied Feb 24 13:59:31 sand-bar---volumio dhcpcd[850]: eth0: soliciting an IPv6 router Feb 24 13:59:31 sand-bar---volumio ifplugd(eth0)[1010]: Link beat detected. Feb 24 13:59:31 sand-bar---volumio ifplugd(eth0)[1010]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Feb 24 13:59:31 sand-bar---volumio dhcpcd[1914]: sending commands to master dhcpcd process Feb 24 13:59:31 sand-bar---volumio dhcpcd[850]: control command: /sbin/dhcpcd eth0 Feb 24 13:59:31 sand-bar---volumio ifplugd(eth0)[1010]: client: sending commands to master dhcpcd process Feb 24 13:59:31 sand-bar---volumio ifplugd(eth0)[1010]: Program executed successfully. Feb 24 13:59:32 sand-bar---volumio dhcpcd[850]: eth0: rebinding lease of 192.168.101.4 Feb 24 13:59:34 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Fetching time from Volumio... Feb 24 13:59:34 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Date not found in response Feb 24 13:59:34 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Retrying in 5 seconds... Feb 24 13:59:34 sand-bar---volumio ntpd[1012]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 24 13:59:36 sand-bar---volumio ntpd[1012]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 24 13:59:37 sand-bar---volumio dhcpcd[850]: eth0: probing for an IPv4LL address Feb 24 13:59:37 sand-bar---volumio dhcpcd[850]: eth0: DHCP lease expired Feb 24 13:59:37 sand-bar---volumio dhcpcd[850]: eth0: soliciting a DHCP lease Feb 24 13:59:37 sand-bar---volumio ntpd[1012]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 24 13:59:39 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Fetching time from Volumio... Feb 24 13:59:39 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Date not found in response Feb 24 13:59:39 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Retrying in 5 seconds... Feb 24 13:59:39 sand-bar---volumio ntpd[1012]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Feb 24 13:59:41 sand-bar---volumio dhcpcd[850]: eth0: using IPv4LL address 169.254.170.232 Feb 24 13:59:41 sand-bar---volumio dhcpcd[850]: eth0: adding route to 169.254.0.0/16 Feb 24 13:59:41 sand-bar---volumio avahi-daemon[763]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.170.232. Feb 24 13:59:41 sand-bar---volumio dhcpcd[850]: eth0: adding default route Feb 24 13:59:41 sand-bar---volumio avahi-daemon[763]: New relevant interface eth0.IPv4 for mDNS. Feb 24 13:59:41 sand-bar---volumio avahi-daemon[763]: Registering new address record for 169.254.170.232 on eth0.IPv4. Feb 24 13:59:42 sand-bar---volumio volumio[1036]: info: Volumio Network Manager: Network status updated: 1 Feb 24 13:59:43 sand-bar---volumio ntpd[1012]: Listen normally on 3 eth0 169.254.170.232:123 Feb 24 13:59:43 sand-bar---volumio ntpd[1012]: new interface(s) found: waking up resolver Feb 24 13:59:44 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Fetching time from Volumio... Feb 24 13:59:44 sand-bar---volumio nmbd[995]: [2025/02/24 13:59:44.778518, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 24 13:59:44 sand-bar---volumio nmbd[995]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 24 13:59:44 sand-bar---volumio systemd[1]: Started Samba NMB Daemon. Feb 24 13:59:44 sand-bar---volumio systemd[1]: Starting Samba Winbind Daemon... Feb 24 13:59:44 sand-bar---volumio winbindd[2003]: [2025/02/24 13:59:44.871833, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 24 13:59:44 sand-bar---volumio winbindd[2003]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 24 13:59:44 sand-bar---volumio winbindd[2003]: [2025/02/24 13:59:44.873201, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 24 13:59:44 sand-bar---volumio winbindd[2003]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 24 13:59:44 sand-bar---volumio systemd[1]: Started Samba Winbind Daemon. Feb 24 13:59:44 sand-bar---volumio systemd[1]: Starting Samba SMB Daemon... Feb 24 13:59:45 sand-bar---volumio smbd[2008]: [2025/02/24 13:59:45.008371, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 24 13:59:45 sand-bar---volumio smbd[2008]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 24 13:59:45 sand-bar---volumio systemd[1]: Started Samba SMB Daemon. Feb 24 14:00:04 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Date not found in response Feb 24 14:00:04 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Retrying in 5 seconds... Feb 24 14:00:09 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Fetching time from Volumio... Feb 24 14:00:22 sand-bar---volumio volumio[1036]: info: Discovery: adding a0ae429c-7b0a-4ddd-833a-a540d7971bc4 Feb 24 14:00:22 sand-bar---volumio volumio[1036]: info: Discovery: Found device Sand bar - Volumio Feb 24 14:00:22 sand-bar---volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Feb 24 14:00:22 sand-bar---volumio volumio[1036]: info: CorePlayQueue::getTrack 0 Feb 24 14:00:29 sand-bar---volumio volumiologrotate[768]: ls: cannot access '/var/log/samba/log.wb-SAND': No such file or directory Feb 24 14:00:29 sand-bar---volumio volumiologrotate[768]: ls: cannot access 'BAR': No such file or directory Feb 24 14:00:29 sand-bar---volumio volumiologrotate[768]: ls: cannot access '-': No such file or directory Feb 24 14:00:29 sand-bar---volumio volumiologrotate[768]: ls: cannot access 'VOLUMIO': No such file or directory Feb 24 14:00:29 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Date not found in response Feb 24 14:00:29 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Retrying in 5 seconds... Feb 24 14:00:34 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Fetching time from Volumio... Feb 24 14:00:39 sand-bar---volumio dhcpcd[850]: eth0: offered 192.168.101.4 from 192.168.2.1 Feb 24 14:00:40 sand-bar---volumio dhcpcd[850]: eth0: probing address 192.168.101.4/16 Feb 24 14:00:45 sand-bar---volumio dhcpcd[850]: eth0: leased 192.168.101.4 for 1800 seconds Feb 24 14:00:45 sand-bar---volumio dhcpcd[850]: eth0: adding route to 192.168.0.0/16 Feb 24 14:00:45 sand-bar---volumio dhcpcd[850]: eth0: changing default route via 192.168.2.1 Feb 24 14:00:45 sand-bar---volumio avahi-daemon[763]: Registering new address record for 192.168.101.4 on eth0.IPv4. Feb 24 14:00:45 sand-bar---volumio ntpd[1012]: ntpd exiting on signal 15 (Terminated) Feb 24 14:00:45 sand-bar---volumio systemd[1]: Stopping Network Time Service... Feb 24 14:00:45 sand-bar---volumio systemd[1]: ntp.service: Succeeded. Feb 24 14:00:45 sand-bar---volumio systemd[1]: Stopped Network Time Service. Feb 24 14:00:45 sand-bar---volumio systemd[1]: Starting Network Time Service... Feb 24 14:00:45 sand-bar---volumio ntpd[2227]: ntpd 4.2.8p12@1.3728-o (1): Starting Feb 24 14:00:45 sand-bar---volumio ntpd[2227]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Feb 24 14:00:45 sand-bar---volumio systemd[1]: Started Network Time Service. Feb 24 14:00:45 sand-bar---volumio ntpd[2234]: proto: precision = 0.086 usec (-23) Feb 24 14:00:45 sand-bar---volumio dhcpcd[850]: eth0: deleting route to 169.254.0.0/16 Feb 24 14:00:45 sand-bar---volumio ntpd[2234]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 24 14:00:45 sand-bar---volumio ntpd[2234]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Feb 24 14:00:45 sand-bar---volumio avahi-daemon[763]: Withdrawing address record for 169.254.170.232 on eth0. Feb 24 14:00:45 sand-bar---volumio ntpd[2234]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 790 days ago Feb 24 14:00:45 sand-bar---volumio avahi-daemon[763]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.170.232. Feb 24 14:00:45 sand-bar---volumio ntpd[2234]: Listen and drop on 0 v6wildcard [::]:123 Feb 24 14:00:45 sand-bar---volumio avahi-daemon[763]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.101.4. Feb 24 14:00:45 sand-bar---volumio ntpd[2234]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 24 14:00:45 sand-bar---volumio ntpd[2234]: Listen normally on 2 lo 127.0.0.1:123 Feb 24 14:00:45 sand-bar---volumio ntpd[2234]: Listen normally on 3 eth0 192.168.101.4:123 Feb 24 14:00:45 sand-bar---volumio ntpd[2234]: Listening on routing socket on fd #20 for interface updates Feb 24 14:00:45 sand-bar---volumio ntpd[2234]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 24 14:00:45 sand-bar---volumio ntpd[2234]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: adding 5401e5bb-34f6-4ba0-a165-4103bda062e6 Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: Found device reception Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: Connecting to remote: 192.168.101.1 Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: adding c0e45964-047f-4204-b3dc-174b59d45257 Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: Found device Rose Bar Volumio Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: Connecting to remote: 192.168.101.14 Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: adding 438bb11a-5514-4c4e-84f1-78946ed155f1 Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: Found device Bestro - Vo;umio Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: Connecting to remote: 192.168.101.15 Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: adding 7cfd104f-8234-4303-b803-62a7941c371f Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: Found device IT-Volumio Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: Connecting to remote: 192.168.100.5 Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: adding c0a2c955-7e43-4aed-bf38-e58d3f43a0d5 Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: Found device Haris-Volumio Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: Connecting to remote: 192.168.101.8 Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: adding b6ddc953-985b-4619-ba02-46bb8b9389e7 Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: Found device Palm-Volumio Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: Connecting to remote: 192.168.101.6 Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: adding 5e467fc5-d326-4209-a95d-f720251806c9 Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: Found device All Area-Volumio Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: Connecting to remote: 192.168.101.7 Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: Connected to remote: 192.168.101.14 Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: Connected to remote: 192.168.101.15 Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: Connected to remote: 192.168.100.5 Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: Connected to remote: 192.168.101.6 Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: Connected to remote: 192.168.101.8 Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: Connected to remote: 192.168.101.1 Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Discovery: Connected to remote: 192.168.101.7 Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Adding plugin bluetooth to MyMusic Plugins Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Adding plugin multiroom to MyMusic Plugins Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Adding plugin metavolumio to MyMusic Plugins Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Adding plugin cd_controller to MyMusic Plugins Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 24 14:00:46 sand-bar---volumio ntpd[2234]: Soliciting pool server 155.232.19.7 Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Starting MyVolumio Remote Streaming Endpoints Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: MyVolumio login type: Token Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Starting Streaming Service Transparent Proxy Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Streaming services startup Feb 24 14:00:46 sand-bar---volumio volumio[1036]: info: Starting Streaming Daemon Feb 24 14:00:46 sand-bar---volumio sudo[2257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 24 14:00:46 sand-bar---volumio sudo[2257]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 14:00:47 sand-bar---volumio sudo[2257]: pam_unix(sudo:session): session closed for user root Feb 24 14:00:47 sand-bar---volumio volumio[1036]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 24 14:00:47 sand-bar---volumio volumio[1036]: error: Cannot start Volumio Streaming Daemon Feb 24 14:00:47 sand-bar---volumio volumio[1036]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 24 14:00:47 sand-bar---volumio volumio[1036]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 24 14:00:47 sand-bar---volumio volumio[1036]: STREAMING PROXY: Starting server on port 3245 Feb 24 14:00:47 sand-bar---volumio volumio[1036]: Node JS runtime: 14 Feb 24 14:00:47 sand-bar---volumio volumio[1036]: error: MyVolumio Custom Token format not valid, refreshing it Feb 24 14:00:47 sand-bar---volumio ntpd[2234]: Soliciting pool server 102.216.37.99 Feb 24 14:00:47 sand-bar---volumio ntpd[2234]: Soliciting pool server 41.205.143.146 Feb 24 14:00:48 sand-bar---volumio volumio[1036]: info: Discovery: adding 2c9c8d91-710a-42fe-bde6-1bee64851a09 Feb 24 14:00:48 sand-bar---volumio volumio[1036]: info: Discovery: Found device DELFINO Feb 24 14:00:48 sand-bar---volumio volumio[1036]: info: Discovery: Connecting to remote: 192.168.101.2 Feb 24 14:00:48 sand-bar---volumio volumio[1036]: info: Discovery: Connected to remote: 192.168.101.2 Feb 24 14:00:48 sand-bar---volumio volumio[1036]: info: MyVolumio login type: Token Feb 24 14:00:48 sand-bar---volumio ntpd[2234]: Soliciting pool server 196.192.32.7 Feb 24 14:00:48 sand-bar---volumio ntpd[2234]: Soliciting pool server 196.10.55.58 Feb 24 14:00:48 sand-bar---volumio volumio[1036]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 24 14:00:49 sand-bar---volumio volumio[1036]: info: MyVolumio token set successfully Feb 24 14:00:49 sand-bar---volumio volumio[1036]: info: MYVOLUMIO: Adding device Feb 24 14:00:49 sand-bar---volumio volumio[1036]: info: MYVOLUMIO: Evaluating Server Feb 24 14:00:49 sand-bar---volumio volumio[1036]: info: MyVolumio status changed Feb 24 14:00:49 sand-bar---volumio volumio[1036]: info: Streaming services startup Feb 24 14:00:49 sand-bar---volumio volumio[1036]: info: Starting Streaming Daemon Feb 24 14:00:49 sand-bar---volumio volumio[1036]: info: Removing browser output: myVolumio user plan is not superstar Feb 24 14:00:49 sand-bar---volumio volumio[1036]: info: Removing audio output: Feb 24 14:00:49 sand-bar---volumio volumio[1036]: info: Stoppping Tunnel 1 Feb 24 14:00:49 sand-bar---volumio sudo[2288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 24 14:00:49 sand-bar---volumio sudo[2288]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 14:00:49 sand-bar---volumio sudo[2288]: pam_unix(sudo:session): session closed for user root Feb 24 14:00:49 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Date not found in response Feb 24 14:00:49 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Retrying in 5 seconds... Feb 24 14:00:49 sand-bar---volumio volumio[1036]: error: Cannot start Volumio Streaming Daemon Feb 24 14:00:49 sand-bar---volumio volumio[1036]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 24 14:00:49 sand-bar---volumio volumio[1036]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 24 14:00:49 sand-bar---volumio sudo[2291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 24 14:00:49 sand-bar---volumio sudo[2291]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 14:00:49 sand-bar---volumio sudo[2291]: pam_unix(sudo:session): session closed for user root Feb 24 14:00:49 sand-bar---volumio volumio[1036]: info: Remote SSH Stopped Feb 24 14:00:49 sand-bar---volumio ntpd[2234]: Soliciting pool server 196.10.98.182 Feb 24 14:00:49 sand-bar---volumio ntpd[2234]: Soliciting pool server 41.175.51.165 Feb 24 14:00:49 sand-bar---volumio volumio[1036]: info: Setting Geolocation for MyVolumio to eu6 Feb 24 14:00:49 sand-bar---volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 14:00:49 sand-bar---volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 14:00:49 sand-bar---volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 14:00:50 sand-bar---volumio volumio[1036]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 24 14:00:50 sand-bar---volumio ntpd[2234]: Soliciting pool server 102.64.112.165 Feb 24 14:00:50 sand-bar---volumio volumio[1036]: info: Updating MyVolumio device info Feb 24 14:00:50 sand-bar---volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 14:00:51 sand-bar---volumio volumio[1036]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 24 14:00:51 sand-bar---volumio ntpd[2234]: Soliciting pool server 196.44.136.162 Feb 24 14:00:52 sand-bar---volumio ntpd[2234]: Soliciting pool server 102.130.49.189 Feb 24 14:00:54 sand-bar---volumio volumio[1036]: info: MYVOLUMIO: Adding device Feb 24 14:00:54 sand-bar---volumio volumio[1036]: info: MYVOLUMIO: Evaluating Server Feb 24 14:00:54 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Fetching time from Volumio... Feb 24 14:00:54 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: Setting system time to: 2025-02-24 14:00:54 Feb 24 14:00:54 sand-bar---volumio sudo[2331]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-02-24 14:00:54 Feb 24 14:00:54 sand-bar---volumio sudo[2331]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 14:00:54 sand-bar---volumio dbus-daemon[772]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.22' (uid=0 pid=2332 comm="timedatectl set-time 2025-02-24 14:00:54 ") Feb 24 14:00:54 sand-bar---volumio volumio[1036]: error: Failed to ping endpoint us3.myvolumio.org : unknown error Feb 24 14:00:54 sand-bar---volumio systemd[1]: Starting Time & Date Service... Feb 24 14:00:54 sand-bar---volumio volumio[1036]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 14:00:54 sand-bar---volumio volumio[1036]: Error: Unable to resolve or reject the same promise twice Feb 24 14:00:54 sand-bar---volumio volumio[1036]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Feb 24 14:00:54 sand-bar---volumio volumio[1036]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Feb 24 14:00:54 sand-bar---volumio volumio[1036]: at Socket.emit (events.js:412:35) Feb 24 14:00:54 sand-bar---volumio volumio[1036]: at endReadableNT (internal/streams/readable.js:1333:12) Feb 24 14:00:54 sand-bar---volumio volumio[1036]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Feb 24 14:00:54 sand-bar---volumio volumio[1036]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 14:00:54 sand-bar---volumio ntpd[2234]: receive: Unexpected origin timestamp 0xeb66ddf6.6e4c6caf does not match aorg 0000000000.00000000 from server@196.192.32.7 xmt 0xeb66ddf6.14bfdcc1 Feb 24 14:00:54 sand-bar---volumio dbus-daemon[772]: [system] Successfully activated service 'org.freedesktop.timedate1' Feb 24 14:00:54 sand-bar---volumio systemd[1]: Started Time & Date Service. Feb 24 14:00:54 sand-bar---volumio systemd-timedated[2334]: Changed local time to Mon Feb 24 14:00:54 2025 Feb 24 14:00:54 sand-bar---volumio ntpd[2234]: receive: Unexpected origin timestamp 0xeb66ddf6.6e4df215 does not match aorg 0000000000.00000000 from server@155.232.19.7 xmt 0xeb66ddf6.134c0699 Feb 24 14:00:54 sand-bar---volumio volumio-time-update[765]: volumio-time-update-util: System time updated successfully. Feb 24 14:00:54 sand-bar---volumio sudo[2331]: pam_unix(sudo:session): session closed for user root Feb 24 14:00:54 sand-bar---volumio systemd[1]: Started Volumio Time Update Utility. Feb 24 14:00:54 sand-bar---volumio systemd[1]: Reached target Multi-User System. Feb 24 14:00:54 sand-bar---volumio systemd[1]: Reached target Graphical Interface. Feb 24 14:00:54 sand-bar---volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 24 14:00:54 sand-bar---volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 24 14:00:54 sand-bar---volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 24 14:00:54 sand-bar---volumio systemd[1]: Startup finished in 16.267s (firmware) + 2.232s (loader) + 7.448s (kernel) + 2min 27.116s (userspace) = 2min 53.065s. Feb 24 14:00:54 sand-bar---volumio sudo[2346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-24 13:59 Feb 24 14:00:54 sand-bar---volumio sudo[2346]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"