-- Logs begin at Sun 2024-11-24 09:21:16 CET, end at Sun 2024-11-24 09:23:26 CET. -- Nov 24 09:22:00 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 24 09:22:10 volumio volumio[1160]: error: MyVolumio Plugin failed to start in a timely fashion Nov 24 09:22:10 volumio volumio[1160]: info: BOOT COMPLETED Nov 24 09:22:10 volumio volumio[1160]: [Metrics] CommandRouter: 24s 878.97ms Nov 24 09:22:10 volumio volumio[1160]: info: CoreCommandRouter::volumiosetStartupVolume Nov 24 09:22:10 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 09:22:10 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 09:22:10 volumio volumio[1160]: info: CoreCommandRouter::Close All Modals sent Nov 24 09:22:10 volumio volumio[1160]: info: CoreCommandRouter::Close All Modals sent Nov 24 09:22:11 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 24 09:22:11 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 24 09:22:11 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 24 09:22:19 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 24 09:22:19 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 09:22:19 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 24 09:22:27 volumio dhcpcd[884]: wlan0: carrier lost Nov 24 09:22:27 volumio kernel: wlan0: deauthenticated from 44:fe:3b:65:b7:b2 (Reason: 3=DEAUTH_LEAVING) Nov 24 09:22:27 volumio avahi-daemon[807]: Withdrawing address record for 169.254.53.33 on wlan0. Nov 24 09:22:27 volumio avahi-daemon[807]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.53.33. Nov 24 09:22:27 volumio dhcpcd[884]: wlan0: deleting route to 169.254.0.0/16 Nov 24 09:22:27 volumio wpa_supplicant[1059]: wlan0: CTRL-EVENT-DISCONNECTED bssid=44:fe:3b:65:b7:b2 reason=3 Nov 24 09:22:27 volumio dhcpcd[884]: wlan0: deleting default route Nov 24 09:22:27 volumio avahi-daemon[807]: Interface wlan0.IPv4 no longer relevant for mDNS. Nov 24 09:22:27 volumio volumio[1160]: info: Discovery: A device disappeared from network Nov 24 09:22:27 volumio volumio[1160]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Nov 24 09:22:28 volumio ntpd[991]: Deleting interface #3 wlan0, 169.254.53.33#123, interface stats: received=0, sent=0, dropped=0, active_time=29 secs Nov 24 09:22:28 volumio ntpd[991]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Nov 24 09:22:28 volumio ntpd[991]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Nov 24 09:22:28 volumio ntpd[991]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Nov 24 09:22:28 volumio ntpd[991]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Nov 24 09:22:29 volumio volumio[1160]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.742&uuid=4937365e1436b6644775e1bf21941c04" http://updates.volumio.org/downloader-v1/track-device Nov 24 09:22:29 volumio volumio[1160]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 24 09:22:29 volumio volumio[1160]: Dload Upload Total Spent Left Speed Nov 24 09:22:29 volumio volumio[1160]: [1.6K blob data] Nov 24 09:22:29 volumio volumio[1160]: retrying in 5 seconds, trial 1 Nov 24 09:22:29 volumio volumio[1160]: info: Volumio Calling Home Nov 24 09:22:30 volumio wpa_supplicant[1059]: wlan0: SME: Trying to authenticate with 44:fe:3b:65:b7:b2 (SSID='Wi-Fi' freq=2437 MHz) Nov 24 09:22:30 volumio kernel: wlan0: authenticate with 44:fe:3b:65:b7:b2 Nov 24 09:22:30 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Nov 24 09:22:30 volumio kernel: wlan0: send auth to 44:fe:3b:65:b7:b2 (try 1/3) Nov 24 09:22:30 volumio kernel: wlan0: send auth to 44:fe:3b:65:b7:b2 (try 2/3) Nov 24 09:22:30 volumio kernel: wlan0: send auth to 44:fe:3b:65:b7:b2 (try 3/3) Nov 24 09:22:30 volumio kernel: wlan0: authentication with 44:fe:3b:65:b7:b2 timed out Nov 24 09:22:34 volumio volumio[1160]: info: Discovery: Browse raised the following error Error: getaddrinfo -3008 Nov 24 09:22:39 volumio volumio[1160]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.742&uuid=4937365e1436b6644775e1bf21941c04" http://updates.volumio.org/downloader-v1/track-device Nov 24 09:22:39 volumio volumio[1160]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 24 09:22:39 volumio volumio[1160]: Dload Upload Total Spent Left Speed Nov 24 09:22:39 volumio volumio[1160]: [132B blob data] Nov 24 09:22:39 volumio volumio[1160]: retrying in 5 seconds, trial 2 Nov 24 09:22:39 volumio volumio[1160]: info: Volumio Calling Home Nov 24 09:23:11 volumio wpa_supplicant[1059]: wlan0: SME: Trying to authenticate with 98:42:65:e3:cb:70 (SSID='Wi-Fi' freq=5500 MHz) Nov 24 09:23:11 volumio kernel: wlan0: authenticate with 98:42:65:e3:cb:70 Nov 24 09:23:11 volumio kernel: wlan0: send auth to 98:42:65:e3:cb:70 (try 1/3) Nov 24 09:23:11 volumio wpa_supplicant[1059]: wlan0: Trying to associate with 98:42:65:e3:cb:70 (SSID='Wi-Fi' freq=5500 MHz) Nov 24 09:23:11 volumio kernel: wlan0: authenticated Nov 24 09:23:11 volumio kernel: wlan0: associate with 98:42:65:e3:cb:70 (try 1/3) Nov 24 09:23:11 volumio kernel: wlan0: RX AssocResp from 98:42:65:e3:cb:70 (capab=0x1011 status=0 aid=8) Nov 24 09:23:11 volumio wpa_supplicant[1059]: wlan0: Associated with 98:42:65:e3:cb:70 Nov 24 09:23:11 volumio wpa_supplicant[1059]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 24 09:23:11 volumio kernel: wlan0: associated Nov 24 09:23:11 volumio wpa_supplicant[1059]: wlan0: WPA: Key negotiation completed with 98:42:65:e3:cb:70 [PTK=CCMP GTK=CCMP] Nov 24 09:23:11 volumio wpa_supplicant[1059]: wlan0: CTRL-EVENT-CONNECTED - Connection to 98:42:65:e3:cb:70 completed [id=0 id_str=] Nov 24 09:23:11 volumio dhcpcd[884]: wlan0: carrier acquired Nov 24 09:23:11 volumio kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by 98:42:65:e3:cb:70 Nov 24 09:23:11 volumio dhcpcd[884]: wlan0: IAID c6:c2:26:5d Nov 24 09:23:11 volumio dhcpcd[884]: wlan0: soliciting an IPv6 router Nov 24 09:23:12 volumio dhcpcd[884]: wlan0: soliciting a DHCP lease Nov 24 09:23:12 volumio dhcpcd[884]: wlan0: offered 192.168.1.65 from 192.168.1.1 Nov 24 09:23:12 volumio dhcpcd[884]: wlan0: probing address 192.168.1.65/24 Nov 24 09:23:13 volumio nmbd[929]: [2024/11/24 09:23:13.085082, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 24 09:23:13 volumio nmbd[929]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Nov 24 09:23:17 volumio dhcpcd[884]: wlan0: leased 192.168.1.65 for 86400 seconds Nov 24 09:23:17 volumio avahi-daemon[807]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.65. Nov 24 09:23:17 volumio dhcpcd[884]: wlan0: adding route to 192.168.1.0/24 Nov 24 09:23:17 volumio avahi-daemon[807]: New relevant interface wlan0.IPv4 for mDNS. Nov 24 09:23:17 volumio dhcpcd[884]: wlan0: adding default route via 192.168.1.1 Nov 24 09:23:17 volumio avahi-daemon[807]: Registering new address record for 192.168.1.65 on wlan0.IPv4. Nov 24 09:23:17 volumio ntpd[991]: ntpd exiting on signal 15 (Terminated) Nov 24 09:23:17 volumio systemd[1]: Stopping Network Time Service... Nov 24 09:23:17 volumio systemd[1]: ntp.service: Succeeded. Nov 24 09:23:17 volumio systemd[1]: Stopped Network Time Service. Nov 24 09:23:17 volumio systemd[1]: Starting Network Time Service... Nov 24 09:23:17 volumio ntpd[1969]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 24 09:23:17 volumio ntpd[1969]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Nov 24 09:23:17 volumio systemd[1]: Started Network Time Service. Nov 24 09:23:17 volumio ntpd[1975]: proto: precision = 0.063 usec (-24) Nov 24 09:23:17 volumio ntpd[1975]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 24 09:23:17 volumio ntpd[1975]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 24 09:23:17 volumio ntpd[1975]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 698 days ago Nov 24 09:23:17 volumio ntpd[1975]: Listen and drop on 0 v6wildcard [::]:123 Nov 24 09:23:17 volumio ntpd[1975]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 24 09:23:17 volumio ntpd[1975]: Listen normally on 2 lo 127.0.0.1:123 Nov 24 09:23:17 volumio ntpd[1975]: Listen normally on 3 wlan0 192.168.1.65:123 Nov 24 09:23:17 volumio ntpd[1975]: Listening on routing socket on fd #20 for interface updates Nov 24 09:23:17 volumio ntpd[1975]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 24 09:23:17 volumio ntpd[1975]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 24 09:23:18 volumio volumio[1160]: info: Discovery: adding a2bf3c07-da66-4979-9b26-c2f1e6aa312d Nov 24 09:23:18 volumio volumio[1160]: info: Discovery: Found device Volumio Nov 24 09:23:18 volumio volumio[1160]: info: CoreCommandRouter::volumioGetState Nov 24 09:23:18 volumio volumio[1160]: info: CorePlayQueue::getTrack 0 Nov 24 09:23:18 volumio ntpd[1975]: Soliciting pool server 91.212.242.20 Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 24 09:23:18 volumio volumio[1160]: info: Adding plugin bluetooth to MyMusic Plugins Nov 24 09:23:18 volumio volumio[1160]: info: Adding plugin multiroom to MyMusic Plugins Nov 24 09:23:18 volumio volumio[1160]: info: Adding plugin metavolumio to MyMusic Plugins Nov 24 09:23:18 volumio volumio[1160]: info: Adding plugin cd_controller to MyMusic Plugins Nov 24 09:23:18 volumio volumio[1160]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 24 09:23:18 volumio volumio[1160]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 24 09:23:18 volumio volumio[1160]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 24 09:23:19 volumio volumio[1160]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 24 09:23:19 volumio volumio[1160]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 24 09:23:19 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 09:23:19 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 09:23:19 volumio volumio[1160]: info: Starting MyVolumio Remote Streaming Endpoints Nov 24 09:23:19 volumio volumio[1160]: info: MyVolumio login type: Token Nov 24 09:23:19 volumio volumio[1160]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 24 09:23:19 volumio volumio[1160]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 24 09:23:19 volumio ntpd[1975]: Soliciting pool server 85.115.212.250 Nov 24 09:23:19 volumio ntpd[1975]: Soliciting pool server 89.161.47.136 Nov 24 09:23:19 volumio volumio[1160]: info: Starting Streaming Service Transparent Proxy Nov 24 09:23:19 volumio volumio[1160]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 24 09:23:19 volumio volumio[1160]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 24 09:23:19 volumio volumio[1160]: info: Streaming services startup Nov 24 09:23:19 volumio volumio[1160]: info: Starting Streaming Daemon Nov 24 09:23:19 volumio sudo[2040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 24 09:23:19 volumio sudo[2040]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 09:23:19 volumio volumio[1160]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 24 09:23:19 volumio sudo[2040]: pam_unix(sudo:session): session closed for user root Nov 24 09:23:19 volumio volumio[1160]: error: Cannot start Volumio Streaming Daemon Nov 24 09:23:19 volumio volumio[1160]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 24 09:23:19 volumio volumio[1160]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 24 09:23:20 volumio volumio[1160]: STREAMING PROXY: Starting server on port 3245 Nov 24 09:23:20 volumio volumio[1160]: Node JS runtime: 14 Nov 24 09:23:20 volumio volumio[1160]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 24 09:23:20 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 24 09:23:20 volumio volumio[1160]: info: Received Get System Info Nov 24 09:23:20 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 09:23:20 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 09:23:20 volumio volumio[1160]: info: Discovery: Getting this device information Nov 24 09:23:20 volumio volumio[1160]: info: CoreCommandRouter::volumioGetState Nov 24 09:23:20 volumio volumio[1160]: info: CorePlayQueue::getTrack 0 Nov 24 09:23:20 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 09:23:20 volumio ntpd[1975]: Soliciting pool server 178.215.228.24 Nov 24 09:23:20 volumio ntpd[1975]: Soliciting pool server 213.135.57.60 Nov 24 09:23:20 volumio ntpd[1975]: Soliciting pool server 80.50.102.206 Nov 24 09:23:21 volumio volumio[1160]: info: MyVolumio token set successfully Nov 24 09:23:21 volumio volumio[1160]: info: MYVOLUMIO: Adding device Nov 24 09:23:21 volumio volumio[1160]: info: MYVOLUMIO: Evaluating Server Nov 24 09:23:21 volumio volumio[1160]: info: MyVolumio status changed Nov 24 09:23:21 volumio volumio[1160]: info: Streaming services startup Nov 24 09:23:21 volumio volumio[1160]: info: Starting Streaming Daemon Nov 24 09:23:21 volumio volumio[1160]: info: Removing browser output: myVolumio user plan is not superstar Nov 24 09:23:21 volumio volumio[1160]: info: Removing audio output: Nov 24 09:23:21 volumio volumio[1160]: info: Stoppping Tunnel 1 Nov 24 09:23:21 volumio sudo[2065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 24 09:23:21 volumio sudo[2065]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 09:23:21 volumio sudo[2065]: pam_unix(sudo:session): session closed for user root Nov 24 09:23:21 volumio sudo[2068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 24 09:23:21 volumio volumio[1160]: error: Cannot start Volumio Streaming Daemon Nov 24 09:23:21 volumio sudo[2068]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 09:23:21 volumio volumio[1160]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 24 09:23:21 volumio volumio[1160]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 24 09:23:21 volumio sudo[2068]: pam_unix(sudo:session): session closed for user root Nov 24 09:23:21 volumio volumio[1160]: info: Remote SSH Stopped Nov 24 09:23:21 volumio ntpd[1975]: Soliciting pool server 212.127.78.21 Nov 24 09:23:21 volumio ntpd[1975]: Soliciting pool server 213.222.217.10 Nov 24 09:23:21 volumio ntpd[1975]: Soliciting pool server 213.222.217.10 Nov 24 09:23:21 volumio volumio[1160]: info: Setting Geolocation for MyVolumio to eu10 Nov 24 09:23:21 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 09:23:21 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 09:23:21 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 09:23:21 volumio ntpd[1975]: Soliciting pool server 162.159.200.123 Nov 24 09:23:22 volumio volumio[1160]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 24 09:23:22 volumio ntpd[1975]: Soliciting pool server 91.210.128.220 Nov 24 09:23:22 volumio ntpd[1975]: Soliciting pool server 188.241.28.254 Nov 24 09:23:22 volumio ntpd[1975]: Soliciting pool server 150.254.65.61 Nov 24 09:23:22 volumio volumio[1160]: info: Updating MyVolumio device info Nov 24 09:23:22 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 09:23:23 volumio volumio[1160]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 24 09:23:23 volumio ntpd[1975]: Soliciting pool server 185.157.13.96 Nov 24 09:23:23 volumio ntpd[1975]: Soliciting pool server 2001:678:8::123 Nov 24 09:23:24 volumio ntpd[1975]: Soliciting pool server 91.227.212.78 Nov 24 09:23:26 volumio volumio[1160]: info: MYVOLUMIO: Adding device Nov 24 09:23:26 volumio volumio[1160]: info: MYVOLUMIO: Evaluating Server Nov 24 09:23:26 volumio ntpd[1975]: receive: Unexpected origin timestamp 0xeaed60fe.aa6b63d6 does not match aorg 0000000000.00000000 from server@213.135.57.60 xmt 0xeaed60fe.316bdc24 Nov 24 09:23:26 volumio volumio[1160]: error: Failed to ping endpoint au1.myvolumio.org : unknown error Nov 24 09:23:26 volumio volumio[1160]: info: Setting Geolocation for MyVolumio to eu4 Nov 24 09:23:26 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 09:23:26 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 09:23:26 volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 09:23:26 volumio ntpd[1975]: receive: Unexpected origin timestamp 0xeaed60fe.aa6cf6c5 does not match aorg 0000000000.00000000 from server@91.212.242.20 xmt 0xeaed60fe.32066da9 Nov 24 09:23:26 volumio ntpd[1975]: receive: Unexpected origin timestamp 0xeaed60fe.aa674ac2 does not match aorg 0000000000.00000000 from server@91.227.212.78 xmt 0xeaed60fe.31eb85a8 Nov 24 09:23:26 volumio volumio[1160]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 09:23:26 volumio volumio[1160]: Error: Unable to resolve or reject the same promise twice Nov 24 09:23:26 volumio volumio[1160]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Nov 24 09:23:26 volumio volumio[1160]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Nov 24 09:23:26 volumio volumio[1160]: at Socket.emit (events.js:412:35) Nov 24 09:23:26 volumio volumio[1160]: at endReadableNT (internal/streams/readable.js:1333:12) Nov 24 09:23:26 volumio volumio[1160]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Nov 24 09:23:26 volumio volumio[1160]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 09:23:26 volumio ntpd[1975]: receive: Unexpected origin timestamp 0xeaed60fe.aa6ad49a does not match aorg 0000000000.00000000 from server@212.127.78.21 xmt 0xeaed60fe.346c3581 Nov 24 09:23:26 volumio ntpd[1975]: receive: Unexpected origin timestamp 0xeaed60fe.aa6bebab does not match aorg 0000000000.00000000 from server@89.161.47.136 xmt 0xeaed60fe.3e70a19c Nov 24 09:23:26 volumio sudo[2100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-24 09:22 Nov 24 09:23:26 volumio sudo[2100]: 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"