-- Logs begin at Mon 2025-06-23 12:19:47 UTC, end at Mon 2025-06-23 12:22:20 UTC. -- Jun 23 12:21:05 volumio volumio-time-update[690]: volumio-time-update-util: Date not found in response Jun 23 12:21:05 volumio volumio-time-update[690]: volumio-time-update-util: Retrying in 5 seconds... Jun 23 12:21:06 volumio volumio[1078]: info: Discovery: adding 2f5747be-c6bb-4b7a-95cb-231637ff679d Jun 23 12:21:06 volumio volumio[1078]: info: Discovery: Found device Volumio Jun 23 12:21:06 volumio volumio[1078]: info: CoreCommandRouter::volumioGetState Jun 23 12:21:06 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jun 23 12:21:07 volumio volumio[1078]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 23 12:21:07 volumio volumio[1078]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 23 12:21:10 volumio volumio-time-update[690]: volumio-time-update-util: Fetching time from Volumio... Jun 23 12:21:24 volumio ntpd[936]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 23 12:21:27 volumio volumio[1078]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.816&uuid=f82d6b0c8b8e64c7121d2f6c462fdf7e" http://updates.volumio.org/downloader-v1/track-device Jun 23 12:21:27 volumio volumio[1078]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 23 12:21:27 volumio volumio[1078]: Dload Upload Total Spent Left Speed Jun 23 12:21:27 volumio volumio[1078]: [1.6K blob data] Jun 23 12:21:27 volumio volumio[1078]: retrying in 5 seconds, trial 1 Jun 23 12:21:27 volumio volumio[1078]: info: Volumio Calling Home Jun 23 12:21:27 volumio volumio[1078]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 23 12:21:27 volumio volumio[1078]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 23 12:21:30 volumio volumio-time-update[690]: volumio-time-update-util: Date not found in response Jun 23 12:21:30 volumio volumio-time-update[690]: volumio-time-update-util: Retrying in 5 seconds... Jun 23 12:21:35 volumio volumio-time-update[690]: volumio-time-update-util: Fetching time from Volumio... Jun 23 12:21:41 volumio dhcpcd[805]: eth0: carrier acquired Jun 23 12:21:41 volumio kernel: r8169 0000:03:00.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx Jun 23 12:21:41 volumio dhcpcd[805]: eth0: IAID d5:65:47:45 Jun 23 12:21:42 volumio ifplugd(eth0)[934]: Link beat detected. Jun 23 12:21:42 volumio ifplugd(eth0)[934]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jun 23 12:21:42 volumio dhcpcd[2108]: sending commands to master dhcpcd process Jun 23 12:21:42 volumio ifplugd(eth0)[934]: client: sending commands to master dhcpcd process Jun 23 12:21:42 volumio dhcpcd[805]: control command: /sbin/dhcpcd eth0 Jun 23 12:21:42 volumio ifplugd(eth0)[934]: Program executed successfully. Jun 23 12:21:42 volumio dhcpcd[805]: eth0: soliciting an IPv6 router Jun 23 12:21:42 volumio dhcpcd[805]: eth0: soliciting a DHCP lease Jun 23 12:21:44 volumio dhcpcd[805]: eth0: offered 192.168.1.6 from 192.168.1.254 Jun 23 12:21:44 volumio dhcpcd[805]: eth0: probing address 192.168.1.6/24 Jun 23 12:21:45 volumio ntpd[936]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 23 12:21:47 volumio volumio[1078]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 23 12:21:49 volumio dhcpcd[805]: eth0: leased 192.168.1.6 for 86400 seconds Jun 23 12:21:49 volumio dhcpcd[805]: eth0: adding route to 192.168.1.0/24 Jun 23 12:21:49 volumio dhcpcd[805]: eth0: adding default route via 192.168.1.254 Jun 23 12:21:49 volumio dhcpcd[805]: wlan0: deleting default route Jun 23 12:21:49 volumio avahi-daemon[697]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.6. Jun 23 12:21:49 volumio avahi-daemon[697]: New relevant interface eth0.IPv4 for mDNS. Jun 23 12:21:49 volumio avahi-daemon[697]: Registering new address record for 192.168.1.6 on eth0.IPv4. Jun 23 12:21:49 volumio dnsmasq[1144]: reading /etc/resolv.conf Jun 23 12:21:49 volumio dnsmasq[1144]: using nameserver 192.168.1.254#53 Jun 23 12:21:49 volumio dnsmasq[1144]: using nameserver 208.67.222.222#53 Jun 23 12:21:49 volumio dnsmasq[1144]: using nameserver 208.67.220.220#53 Jun 23 12:21:49 volumio ntpd[936]: ntpd exiting on signal 15 (Terminated) Jun 23 12:21:49 volumio systemd[1]: Stopping Network Time Service... Jun 23 12:21:49 volumio systemd[1]: ntp.service: Succeeded. Jun 23 12:21:49 volumio systemd[1]: Stopped Network Time Service. Jun 23 12:21:49 volumio systemd[1]: Starting Network Time Service... Jun 23 12:21:49 volumio ntpd[2171]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 23 12:21:49 volumio ntpd[2171]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 23 12:21:49 volumio systemd[1]: Started Network Time Service. Jun 23 12:21:49 volumio ntpd[2177]: proto: precision = 0.173 usec (-22) Jun 23 12:21:49 volumio ntpd[2177]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 23 12:21:49 volumio ntpd[2177]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 23 12:21:49 volumio ntpd[2177]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 909 days ago Jun 23 12:21:49 volumio ntpd[2177]: Listen and drop on 0 v6wildcard [::]:123 Jun 23 12:21:49 volumio ntpd[2177]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 23 12:21:49 volumio ntpd[2177]: Listen normally on 2 lo 127.0.0.1:123 Jun 23 12:21:49 volumio ntpd[2177]: Listen normally on 3 eth0 192.168.1.6:123 Jun 23 12:21:49 volumio ntpd[2177]: Listen normally on 4 wlan0 192.168.211.1:123 Jun 23 12:21:49 volumio ntpd[2177]: Listen normally on 5 wlan0 169.254.207.161:123 Jun 23 12:21:49 volumio ntpd[2177]: Listening on routing socket on fd #22 for interface updates Jun 23 12:21:49 volumio ntpd[2177]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 23 12:21:49 volumio ntpd[2177]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 23 12:21:50 volumio ntpd[2177]: Soliciting pool server 62.1.105.163 Jun 23 12:21:51 volumio volumio[1078]: info: Volumio Network Manager: Network status updated: 1 Jun 23 12:21:51 volumio volumio[1078]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 23 12:21:51 volumio volumio[1078]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 23 12:21:51 volumio ntpd[2177]: Soliciting pool server 185.201.254.21 Jun 23 12:21:51 volumio ntpd[2177]: Soliciting pool server 162.159.200.123 Jun 23 12:21:51 volumio volumio[1078]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 23 12:21:51 volumio volumio[1078]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 23 12:21:52 volumio volumio[1078]: info: Discovery: this is already registered, 2f5747be-c6bb-4b7a-95cb-231637ff679d Jun 23 12:21:52 volumio volumio[1078]: info: Discovery: Found device Volumio Jun 23 12:21:52 volumio volumio[1078]: info: CoreCommandRouter::volumioGetState Jun 23 12:21:52 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 23 12:21:52 volumio volumio[1078]: info: Adding plugin bluetooth to MyMusic Plugins Jun 23 12:21:52 volumio volumio[1078]: info: Adding plugin multiroom to MyMusic Plugins Jun 23 12:21:52 volumio volumio[1078]: info: Adding plugin metavolumio to MyMusic Plugins Jun 23 12:21:52 volumio volumio[1078]: info: Adding plugin cd_controller to MyMusic Plugins Jun 23 12:21:52 volumio volumio[1078]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 23 12:21:52 volumio volumio[1078]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 23 12:21:52 volumio volumio[1078]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 23 12:21:52 volumio volumio[1078]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 23 12:21:52 volumio ntpd[2177]: Soliciting pool server 83.212.117.131 Jun 23 12:21:52 volumio ntpd[2177]: Soliciting pool server 83.212.117.131 Jun 23 12:21:52 volumio ntpd[2177]: Soliciting pool server 193.93.164.195 Jun 23 12:21:53 volumio volumio[1078]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 23 12:21:53 volumio volumio[1078]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 23 12:21:53 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 12:21:53 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 12:21:53 volumio volumio[1078]: info: Starting MyVolumio Remote Streaming Endpoints Jun 23 12:21:53 volumio volumio[1078]: info: MyVolumio login type: Token Jun 23 12:21:53 volumio volumio[1078]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 23 12:21:53 volumio volumio[1078]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 23 12:21:53 volumio ntpd[2177]: Soliciting pool server 193.93.164.194 Jun 23 12:21:53 volumio ntpd[2177]: Soliciting pool server 83.212.109.72 Jun 23 12:21:53 volumio ntpd[2177]: Soliciting pool server 147.52.190.11 Jun 23 12:21:54 volumio volumio[1078]: info: Starting Streaming Service Transparent Proxy Jun 23 12:21:54 volumio volumio[1078]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 23 12:21:54 volumio volumio[1078]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 23 12:21:54 volumio volumio[1078]: info: Streaming services startup Jun 23 12:21:54 volumio volumio[1078]: info: Starting Streaming Daemon Jun 23 12:21:54 volumio sudo[2207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 23 12:21:54 volumio sudo[2207]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 12:21:54 volumio sudo[2207]: pam_unix(sudo:session): session closed for user root Jun 23 12:21:54 volumio volumio[1078]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 23 12:21:54 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 23 12:21:54 volumio volumio[1078]: info: Received Get System Info Jun 23 12:21:54 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 23 12:21:54 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 23 12:21:54 volumio volumio[1078]: info: Discovery: Getting this device information Jun 23 12:21:54 volumio volumio[1078]: info: CoreCommandRouter::volumioGetState Jun 23 12:21:54 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jun 23 12:21:54 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 23 12:21:54 volumio volumio[1078]: error: Cannot start Volumio Streaming Daemon Jun 23 12:21:54 volumio volumio[1078]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 23 12:21:54 volumio volumio[1078]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 23 12:21:54 volumio ntpd[2177]: Soliciting pool server 81.2.191.34 Jun 23 12:21:54 volumio ntpd[2177]: Soliciting pool server 195.167.30.249 Jun 23 12:21:55 volumio volumio[1078]: error: MyVolumio Custom Token format not valid, refreshing it Jun 23 12:21:55 volumio volumio[1078]: STREAMING PROXY: Starting server on port 3245 Jun 23 12:21:55 volumio volumio[1078]: Node JS runtime: 14 Jun 23 12:21:55 volumio volumio-time-update[690]: volumio-time-update-util: Date not found in response Jun 23 12:21:55 volumio volumio-time-update[690]: volumio-time-update-util: Retrying in 5 seconds... Jun 23 12:21:55 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 23 12:21:55 volumio volumio[1078]: info: MyVolumio login type: Token Jun 23 12:21:55 volumio ntpd[2177]: Soliciting pool server 193.239.214.226 Jun 23 12:21:55 volumio ntpd[2177]: Soliciting pool server 44.32.63.52 Jun 23 12:21:56 volumio volumio[1078]: info: MyVolumio login type: Token Jun 23 12:21:56 volumio volumio[1078]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 23 12:21:56 volumio ntpd[2177]: Soliciting pool server 2a06:de00:de11::211:352 Jun 23 12:21:57 volumio volumio[1078]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.816&uuid=f82d6b0c8b8e64c7121d2f6c462fdf7e" http://updates.volumio.org/downloader-v1/track-device Jun 23 12:21:57 volumio volumio[1078]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 23 12:21:57 volumio volumio[1078]: Dload Upload Total Spent Left Speed Jun 23 12:21:57 volumio volumio[1078]: [1.6K blob data] Jun 23 12:21:57 volumio volumio[1078]: retrying in 5 seconds, trial 2 Jun 23 12:21:57 volumio volumio[1078]: info: Volumio Calling Home Jun 23 12:21:57 volumio volumio[1078]: info: MyVolumio token set successfully Jun 23 12:21:57 volumio volumio[1078]: info: MYVOLUMIO: Adding device Jun 23 12:21:57 volumio volumio[1078]: info: MYVOLUMIO: Evaluating Server Jun 23 12:21:58 volumio ntpd[2177]: receive: Unexpected origin timestamp 0xec03c565.e1ce8248 does not match aorg 0000000000.00000000 from server@81.2.191.34 xmt 0xec03c566.7e73ff72 Jun 23 12:21:58 volumio volumio[1078]: info: Volumio called home Jun 23 12:21:58 volumio volumio[1078]: info: MyVolumio status changed Jun 23 12:21:58 volumio volumio[1078]: info: Streaming services startup Jun 23 12:21:58 volumio volumio[1078]: info: Starting Streaming Daemon Jun 23 12:21:58 volumio volumio[1078]: info: Removing browser output: myVolumio user plan is not superstar Jun 23 12:21:58 volumio volumio[1078]: info: Removing audio output: Jun 23 12:21:58 volumio volumio[1078]: info: Stoppping Tunnel 1 Jun 23 12:21:58 volumio sudo[2279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 23 12:21:58 volumio sudo[2279]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 12:21:58 volumio sudo[2279]: pam_unix(sudo:session): session closed for user root Jun 23 12:21:58 volumio volumio[1078]: error: Cannot start Volumio Streaming Daemon Jun 23 12:21:58 volumio volumio[1078]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 23 12:21:58 volumio volumio[1078]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 23 12:21:58 volumio sudo[2282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 23 12:21:58 volumio sudo[2282]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 12:21:58 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 23 12:21:58 volumio volumio[1078]: info: Received Get System Info Jun 23 12:21:58 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 23 12:21:58 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 23 12:21:58 volumio volumio[1078]: info: Discovery: Getting this device information Jun 23 12:21:58 volumio volumio[1078]: info: CoreCommandRouter::volumioGetState Jun 23 12:21:58 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jun 23 12:21:58 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 23 12:21:58 volumio sudo[2282]: pam_unix(sudo:session): session closed for user root Jun 23 12:21:58 volumio volumio[1078]: info: Remote SSH Stopped Jun 23 12:21:59 volumio volumio[1078]: info: Setting Geolocation for MyVolumio to eu4 Jun 23 12:21:59 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 12:21:59 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 12:21:59 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 12:21:59 volumio volumio[1078]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 23 12:22:00 volumio volumio[1078]: info: Updating MyVolumio device info Jun 23 12:22:00 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 12:22:00 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 12:22:00 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 12:22:00 volumio volumio-time-update[690]: volumio-time-update-util: Fetching time from Volumio... Jun 23 12:22:01 volumio volumio[1078]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 23 12:22:01 volumio volumio-time-update[690]: volumio-time-update-util: Setting system time to: 2025-06-23 12:22:01 Jun 23 12:22:01 volumio sudo[2289]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-06-23 12:22:01 Jun 23 12:22:01 volumio sudo[2289]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 12:22:01 volumio dbus-daemon[699]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.18' (uid=0 pid=2290 comm="timedatectl set-time 2025-06-23 12:22:01 ") Jun 23 12:22:01 volumio systemd[1]: Starting Time & Date Service... Jun 23 12:22:01 volumio dbus-daemon[699]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 23 12:22:01 volumio systemd[1]: Started Time & Date Service. Jun 23 12:22:01 volumio systemd-timedated[2291]: Changed local time to Mon Jun 23 12:22:01 2025 Jun 23 12:22:01 volumio sudo[2289]: pam_unix(sudo:session): session closed for user root Jun 23 12:22:01 volumio volumio-time-update[690]: volumio-time-update-util: System time updated successfully. Jun 23 12:22:01 volumio systemd[1]: Started Volumio Time Update Utility. Jun 23 12:22:01 volumio systemd[1]: Reached target Multi-User System. Jun 23 12:22:01 volumio systemd[1]: Reached target Graphical Interface. Jun 23 12:22:01 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 23 12:22:01 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 23 12:22:01 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 23 12:22:01 volumio systemd[1]: Startup finished in 13.815s (firmware) + 24.870s (loader) + 10.331s (kernel) + 2min 14.247s (userspace) = 3min 3.265s. Jun 23 12:22:02 volumio sudo[2309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 23 12:22:02 volumio sudo[2309]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 12:22:02 volumio sudo[2309]: pam_unix(sudo:session): session closed for user root Jun 23 12:22:02 volumio sudo[2311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 23 12:22:02 volumio sudo[2311]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 12:22:02 volumio sudo[2311]: pam_unix(sudo:session): session closed for user root Jun 23 12:22:02 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.6 from 192.168.1.4 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 8 Jun 23 12:22:02 volumio sudo[2315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 23 12:22:02 volumio sudo[2315]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 12:22:02 volumio sudo[2315]: pam_unix(sudo:session): session closed for user root Jun 23 12:22:02 volumio sudo[2317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 23 12:22:02 volumio sudo[2317]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 12:22:02 volumio sudo[2317]: pam_unix(sudo:session): session closed for user root Jun 23 12:22:02 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.6 from 192.168.1.4 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 9 Jun 23 12:22:02 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 23 12:22:02 volumio volumio[1078]: info: CoreCommandRouter::volumioGetVisibleSources Jun 23 12:22:02 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 23 12:22:02 volumio volumio[1078]: info: CoreCommandRouter::volumioGetState Jun 23 12:22:02 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jun 23 12:22:02 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 23 12:22:02 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 23 12:22:02 volumio volumio[1078]: info: Received Get System Info Jun 23 12:22:02 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 23 12:22:02 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 23 12:22:02 volumio volumio[1078]: info: Discovery: Getting this device information Jun 23 12:22:02 volumio volumio[1078]: info: CoreCommandRouter::volumioGetState Jun 23 12:22:02 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jun 23 12:22:02 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 23 12:22:02 volumio volumio[1078]: info: CoreCommandRouter::volumioGetState Jun 23 12:22:02 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jun 23 12:22:03 volumio volumio[1078]: info: Listing playlists Jun 23 12:22:03 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 23 12:22:03 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 23 12:22:03 volumio volumio[1078]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 23 12:22:03 volumio volumio[1078]: info: FusionDsp - baras Jun 23 12:22:03 volumio volumio[1078]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,baras.json Jun 23 12:22:03 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 23 12:22:03 volumio volumio[1078]: info: MYVOLUMIO: Adding device Jun 23 12:22:03 volumio volumio[1078]: info: MYVOLUMIO: Evaluating Server Jun 23 12:22:04 volumio volumio[1078]: info: CoreCommandRouter::volumioGetState Jun 23 12:22:04 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jun 23 12:22:04 volumio volumio[1078]: info: Setting Geolocation for MyVolumio to eu6 Jun 23 12:22:04 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 12:22:04 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 12:22:04 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 12:22:04 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 23 12:22:04 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 23 12:22:04 volumio volumio[1078]: info: Received Get System Info Jun 23 12:22:04 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 23 12:22:04 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 23 12:22:04 volumio volumio[1078]: info: Discovery: Getting this device information Jun 23 12:22:04 volumio volumio[1078]: info: CoreCommandRouter::volumioGetState Jun 23 12:22:04 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jun 23 12:22:04 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 23 12:22:04 volumio volumio[1078]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 23 12:22:05 volumio volumio[1078]: info: Updating MyVolumio device info Jun 23 12:22:05 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 12:22:05 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 12:22:05 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 12:22:05 volumio volumio[1078]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 23 12:22:06 volumio volumio[1078]: info: CoreCommandRouter::volumioGetQueue Jun 23 12:22:06 volumio volumio[1078]: info: CoreStateMachine::getQueue Jun 23 12:22:06 volumio volumio[1078]: info: CorePlayQueue::getQueue Jun 23 12:22:06 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 23 12:22:06 volumio volumio[1078]: info: Received Get System Info Jun 23 12:22:06 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 23 12:22:06 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 23 12:22:06 volumio volumio[1078]: info: Discovery: Getting this device information Jun 23 12:22:06 volumio volumio[1078]: info: CoreCommandRouter::volumioGetState Jun 23 12:22:06 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jun 23 12:22:06 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 23 12:22:08 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 23 12:22:08 volumio volumio[1078]: info: Preload queue cleared Jun 23 12:22:08 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 23 12:22:08 volumio volumio[1078]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 23 12:22:08 volumio volumio[1078]: info: CoreCommandRouter::volumioGetState Jun 23 12:22:08 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jun 23 12:22:11 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 23 12:22:12 volumio volumio[1078]: info: Preload queue cleared Jun 23 12:22:12 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 23 12:22:13 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 23 12:22:13 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 12:22:13 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 12:22:13 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 23 12:22:13 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 23 12:22:13 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 23 12:22:13 volumio volumio[1078]: info: CoreCommandRouter::volumioGetBrowseSources Jun 23 12:22:13 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 23 12:22:16 volumio volumio[1078]: info: Preload queue cleared Jun 23 12:22:19 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 23 12:22:19 volumio volumio[1078]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 23 12:22:19 volumio volumio[1078]: TypeError: Cannot read property 'length' of undefined Jun 23 12:22:19 volumio volumio[1078]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 23 12:22:19 volumio volumio[1078]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 23 12:22:19 volumio volumio[1078]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 23 12:22:19 volumio volumio[1078]: at Parser.emit (events.js:400:28) Jun 23 12:22:19 volumio volumio[1078]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 23 12:22:19 volumio volumio[1078]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jun 23 12:22:19 volumio volumio[1078]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jun 23 12:22:19 volumio volumio[1078]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jun 23 12:22:19 volumio volumio[1078]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jun 23 12:22:19 volumio volumio[1078]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 23 12:22:19 volumio volumio[1078]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 23 12:22:19 volumio volumio[1078]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 23 12:22:19 volumio volumio[1078]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 23 12:22:19 volumio volumio[1078]: at IncomingMessage.emit (events.js:412:35) Jun 23 12:22:19 volumio volumio[1078]: at endReadableNT (internal/streams/readable.js:1333:12) Jun 23 12:22:19 volumio volumio[1078]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jun 23 12:22:19 volumio volumio[1078]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 23 12:22:20 volumio sudo[2362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-23 12:21 Jun 23 12:22:20 volumio sudo[2362]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:26:48 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a63f40325536655a08b14de2b295ce76"