-- Logs begin at Sat 2025-06-14 23:36:52 CEST, end at Sat 2025-06-14 23:38:53 CEST. -- Jun 14 23:37:00 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 14 23:37:00 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 14 23:37:00 volumio-2 volumio[971]: info: Received Get System Info Jun 14 23:37:00 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 23:37:00 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 23:37:00 volumio-2 volumio[971]: info: Discovery: Getting this device information Jun 14 23:37:00 volumio-2 volumio[971]: info: CoreCommandRouter::volumioGetState Jun 14 23:37:00 volumio-2 volumio[971]: info: CorePlayQueue::getTrack 0 Jun 14 23:37:00 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 23:37:02 volumio-2 dhcpcd[774]: eth0: using static address 192.168.1.233/24 Jun 14 23:37:02 volumio-2 dhcpcd[774]: eth0: adding route to 192.168.1.0/24 Jun 14 23:37:02 volumio-2 dhcpcd[774]: eth0: adding default route via 192.168.1.1 Jun 14 23:37:02 volumio-2 avahi-daemon[696]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.233. Jun 14 23:37:02 volumio-2 avahi-daemon[696]: New relevant interface eth0.IPv4 for mDNS. Jun 14 23:37:02 volumio-2 avahi-daemon[696]: Registering new address record for 192.168.1.233 on eth0.IPv4. Jun 14 23:37:02 volumio-2 volumio[971]: verbose: New Socket.io Connection to 192.168.1.233:3000 from 192.168.1.185 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 14 23:37:02 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 14 23:37:02 volumio-2 volumio[971]: info: Discovery: adding 86493e5c-cac9-4880-9a7c-09f2717114cb Jun 14 23:37:02 volumio-2 volumio[971]: info: Discovery: Found device Volumio Jun 14 23:37:02 volumio-2 volumio[971]: info: Discovery: Connecting to remote: 192.168.1.185 Jun 14 23:37:02 volumio-2 volumio[971]: info: CoreCommandRouter::volumioGetState Jun 14 23:37:02 volumio-2 volumio[971]: info: CorePlayQueue::getTrack 0 Jun 14 23:37:02 volumio-2 volumio[971]: info: Discovery: Connected to remote: 192.168.1.185 Jun 14 23:37:03 volumio-2 volumio[971]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 14 23:37:03 volumio-2 volumio[971]: info: Discovery: adding 8a5f7d41-0c80-41a8-a90e-71c526fdb37f Jun 14 23:37:03 volumio-2 volumio[971]: info: Discovery: Found device Volumio 2 Jun 14 23:37:03 volumio-2 volumio[971]: info: CoreCommandRouter::volumioGetState Jun 14 23:37:03 volumio-2 volumio[971]: info: CorePlayQueue::getTrack 0 Jun 14 23:37:03 volumio-2 volumio-time-update[694]: volumio-time-update-util: Fetching time from Volumio... Jun 14 23:37:04 volumio-2 volumio-time-update[694]: volumio-time-update-util: Setting system time to: 2025-06-14 23:37:04 Jun 14 23:37:04 volumio-2 sudo[1694]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-06-14 23:37:04 Jun 14 23:37:04 volumio-2 sudo[1694]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 23:37:04 volumio-2 dbus-daemon[682]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.15' (uid=0 pid=1695 comm="timedatectl set-time 2025-06-14 23:37:04 ") Jun 14 23:37:04 volumio-2 systemd[1]: Starting Time & Date Service... Jun 14 23:37:04 volumio-2 ntpd[956]: Listen normally on 3 eth0 192.168.1.233:123 Jun 14 23:37:04 volumio-2 ntpd[956]: new interface(s) found: waking up resolver Jun 14 23:37:04 volumio-2 dbus-daemon[682]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 14 23:37:04 volumio-2 systemd[1]: Started Time & Date Service. Jun 14 23:37:04 volumio-2 systemd-timedated[1696]: Changed local time to Sat Jun 14 23:37:04 2025 Jun 14 23:37:04 volumio-2 sudo[1694]: pam_unix(sudo:session): session closed for user root Jun 14 23:37:04 volumio-2 volumio-time-update[694]: volumio-time-update-util: System time updated successfully. Jun 14 23:37:04 volumio-2 systemd[1]: Started Volumio Time Update Utility. Jun 14 23:37:04 volumio-2 nmbd[935]: [2025/06/14 23:37:04.174878, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 14 23:37:04 volumio-2 nmbd[935]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 14 23:37:04 volumio-2 systemd[1]: Started Samba NMB Daemon. Jun 14 23:37:04 volumio-2 nmbd[935]: [2025/06/14 23:37:04.178272, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 14 23:37:04 volumio-2 nmbd[935]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.233 for name WORKGROUP<1d>. Jun 14 23:37:04 volumio-2 nmbd[935]: This response was from IP 192.168.1.29, reporting an IP address of 192.168.1.29. Jun 14 23:37:04 volumio-2 systemd[1]: Starting Samba Winbind Daemon... Jun 14 23:37:04 volumio-2 winbindd[1698]: [2025/06/14 23:37:04.245343, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 14 23:37:04 volumio-2 winbindd[1698]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 14 23:37:04 volumio-2 winbindd[1698]: [2025/06/14 23:37:04.246733, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 14 23:37:04 volumio-2 winbindd[1698]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 14 23:37:04 volumio-2 systemd[1]: Started Samba Winbind Daemon. Jun 14 23:37:04 volumio-2 systemd[1]: Starting Samba SMB Daemon... Jun 14 23:37:04 volumio-2 smbd[1703]: [2025/06/14 23:37:04.351049, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 14 23:37:04 volumio-2 smbd[1703]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 14 23:37:04 volumio-2 systemd[1]: Started Samba SMB Daemon. Jun 14 23:37:04 volumio-2 systemd[1]: Reached target Multi-User System. Jun 14 23:37:04 volumio-2 systemd[1]: Reached target Graphical Interface. Jun 14 23:37:04 volumio-2 systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 14 23:37:04 volumio-2 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 14 23:37:04 volumio-2 systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 14 23:37:04 volumio-2 systemd[1]: Startup finished in 50.811s (firmware) + 1.675s (loader) + 5.425s (kernel) + 11.893s (userspace) = 1min 9.806s. Jun 14 23:37:05 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 14 23:37:05 volumio-2 volumio[971]: info: Received Get System Info Jun 14 23:37:05 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 23:37:05 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 23:37:05 volumio-2 volumio[971]: info: Discovery: Getting this device information Jun 14 23:37:05 volumio-2 volumio[971]: info: CoreCommandRouter::volumioGetState Jun 14 23:37:05 volumio-2 volumio[971]: info: CorePlayQueue::getTrack 0 Jun 14 23:37:05 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 23:37:06 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 14 23:37:06 volumio-2 volumio[971]: info: Adding plugin bluetooth to MyMusic Plugins Jun 14 23:37:06 volumio-2 volumio[971]: info: Adding plugin multiroom to MyMusic Plugins Jun 14 23:37:06 volumio-2 volumio[971]: info: Adding plugin metavolumio to MyMusic Plugins Jun 14 23:37:06 volumio-2 volumio[971]: info: Adding plugin cd_controller to MyMusic Plugins Jun 14 23:37:06 volumio-2 volumio[971]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 14 23:37:06 volumio-2 volumio[971]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 14 23:37:06 volumio-2 volumio[971]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 14 23:37:06 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:37:06 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:37:06 volumio-2 volumio[971]: info: Starting MyVolumio Remote Streaming Endpoints Jun 14 23:37:06 volumio-2 volumio[971]: info: MyVolumio login type: Token Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 14 23:37:06 volumio-2 volumio[971]: info: Starting Streaming Service Transparent Proxy Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 14 23:37:06 volumio-2 volumio[971]: info: Streaming services startup Jun 14 23:37:06 volumio-2 volumio[971]: info: Starting Streaming Daemon Jun 14 23:37:06 volumio-2 sudo[1739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 14 23:37:06 volumio-2 sudo[1739]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 23:37:06 volumio-2 sudo[1739]: pam_unix(sudo:session): session closed for user root Jun 14 23:37:06 volumio-2 volumio[971]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 14 23:37:06 volumio-2 sudo[1747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 14 23:37:06 volumio-2 sudo[1747]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 23:37:06 volumio-2 sudo[1747]: pam_unix(sudo:session): session closed for user root Jun 14 23:37:06 volumio-2 volumio[971]: info: Volumio Network Manager: Network status updated: 1 Jun 14 23:37:06 volumio-2 sudo[1750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 14 23:37:06 volumio-2 sudo[1750]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 23:37:06 volumio-2 volumio[971]: error: Cannot start Volumio Streaming Daemon Jun 14 23:37:06 volumio-2 volumio[971]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 14 23:37:06 volumio-2 volumio[971]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 14 23:37:06 volumio-2 sudo[1750]: pam_unix(sudo:session): session closed for user root Jun 14 23:37:06 volumio-2 sudo[1755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 14 23:37:06 volumio-2 sudo[1755]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 23:37:06 volumio-2 systemd[1]: Started UPnP Renderer front-end to MPD. Jun 14 23:37:06 volumio-2 sudo[1755]: pam_unix(sudo:session): session closed for user root Jun 14 23:37:06 volumio-2 volumio[971]: info: Upmpdcli Daemon Started Jun 14 23:37:06 volumio-2 volumio[1757]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 14 23:37:06 volumio-2 volumio[971]: STREAMING PROXY: Starting server on port 3245 Jun 14 23:37:06 volumio-2 volumio[971]: Node JS runtime: 14 Jun 14 23:37:07 volumio-2 volumio[971]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 14 23:37:07 volumio-2 volumio[1757]: ............................................................................................................++++ Jun 14 23:37:07 volumio-2 volumio[1757]: ....++++ Jun 14 23:37:07 volumio-2 volumio[1757]: e is 65537 (0x010001) Jun 14 23:37:07 volumio-2 volumio[1757]: writing RSA key Jun 14 23:37:07 volumio-2 volumio[971]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.812&uuid=1c123bc45d815a63ceebe945a94532dc" http://updates.volumio.org/downloader-v1/track-device Jun 14 23:37:07 volumio-2 volumio[971]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 14 23:37:07 volumio-2 volumio[971]: Dload Upload Total Spent Left Speed Jun 14 23:37:07 volumio-2 volumio[971]: [132B blob data] Jun 14 23:37:07 volumio-2 volumio[971]: retrying in 5 seconds, trial 0 Jun 14 23:37:07 volumio-2 volumio[971]: info: Volumio Calling Home Jun 14 23:37:07 volumio-2 volumio[971]: info: Volumio called home Jun 14 23:37:08 volumio-2 volumio[971]: info: MyVolumio token set successfully Jun 14 23:37:08 volumio-2 volumio[971]: info: MYVOLUMIO: Adding device Jun 14 23:37:08 volumio-2 volumio[971]: info: MYVOLUMIO: Evaluating Server Jun 14 23:37:08 volumio-2 volumio[971]: info: MyVolumio status changed Jun 14 23:37:08 volumio-2 volumio[971]: info: Streaming services startup Jun 14 23:37:08 volumio-2 volumio[971]: info: Starting Streaming Daemon Jun 14 23:37:08 volumio-2 volumio[971]: info: Removing browser output: myVolumio user plan is not superstar Jun 14 23:37:08 volumio-2 volumio[971]: info: Removing audio output: Jun 14 23:37:08 volumio-2 volumio[971]: info: Stoppping Tunnel 1 Jun 14 23:37:08 volumio-2 sudo[1815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 14 23:37:08 volumio-2 sudo[1815]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 23:37:08 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 14 23:37:08 volumio-2 sudo[1815]: pam_unix(sudo:session): session closed for user root Jun 14 23:37:08 volumio-2 volumio[971]: error: Cannot start Volumio Streaming Daemon Jun 14 23:37:08 volumio-2 volumio[971]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 14 23:37:08 volumio-2 volumio[971]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 14 23:37:08 volumio-2 sudo[1818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 14 23:37:08 volumio-2 sudo[1818]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 23:37:08 volumio-2 sudo[1818]: pam_unix(sudo:session): session closed for user root Jun 14 23:37:08 volumio-2 volumio[971]: info: Remote SSH Stopped Jun 14 23:37:08 volumio-2 volumio[971]: info: Setting Geolocation for MyVolumio to eu6 Jun 14 23:37:08 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:37:08 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:37:08 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:37:09 volumio-2 volumio[971]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 14 23:37:09 volumio-2 volumio[971]: info: Updating MyVolumio device info Jun 14 23:37:09 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:37:09 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:37:09 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:37:10 volumio-2 volumio[971]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 14 23:37:13 volumio-2 volumio[971]: info: MYVOLUMIO: Adding device Jun 14 23:37:13 volumio-2 volumio[971]: info: MYVOLUMIO: Evaluating Server Jun 14 23:37:13 volumio-2 volumio[971]: info: Setting Geolocation for MyVolumio to eu4 Jun 14 23:37:13 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:37:13 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:37:13 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:37:14 volumio-2 volumio[971]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 14 23:37:14 volumio-2 volumio[971]: info: Updating MyVolumio device info Jun 14 23:37:14 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:37:15 volumio-2 volumio[971]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 14 23:37:16 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 14 23:37:16 volumio-2 volumio[971]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 14 23:37:16 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 14 23:37:16 volumio-2 volumio[971]: info: Received Get System Version Jun 14 23:37:16 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 14 23:37:16 volumio-2 volumio[971]: info: Received Get System Info Jun 14 23:37:16 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 23:37:16 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 23:37:16 volumio-2 volumio[971]: info: Discovery: Getting this device information Jun 14 23:37:16 volumio-2 volumio[971]: info: CoreCommandRouter::volumioGetState Jun 14 23:37:16 volumio-2 volumio[971]: info: CorePlayQueue::getTrack 0 Jun 14 23:37:16 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 23:37:21 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 14 23:37:21 volumio-2 volumio[971]: 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 14 23:37:21 volumio-2 volumio[971]: info: CoreCommandRouter::volumioGetState Jun 14 23:37:21 volumio-2 volumio[971]: info: CorePlayQueue::getTrack 0 Jun 14 23:37:21 volumio-2 kernel: smbd(1717): Attempt to set a LOCK_MAND lock via flock(2). This support has been removed and the request ignored. Jun 14 23:37:23 volumio-2 systemd[1]: systemd-fsckd.service: Succeeded. Jun 14 23:37:26 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:37:26 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:37:26 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 14 23:37:26 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 14 23:37:26 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 14 23:37:26 volumio-2 volumio[971]: info: CoreCommandRouter::volumioGetBrowseSources Jun 14 23:37:26 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 14 23:37:26 volumio-2 volumio[971]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 14 23:37:26 volumio-2 volumio[971]: info: Completed starting MyVolumio Plugin Jun 14 23:37:26 volumio-2 volumio[971]: [Metrics] CommandRouter: 31s 659.69ms Jun 14 23:37:26 volumio-2 volumio[971]: info: CoreCommandRouter::volumiosetStartupVolume Jun 14 23:37:26 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:37:26 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:37:26 volumio-2 volumio[971]: info: CoreCommandRouter::Close All Modals sent Jun 14 23:37:26 volumio-2 volumio[971]: info: CoreCommandRouter::Close All Modals sent Jun 14 23:37:27 volumio-2 systemd[1]: systemd-hostnamed.service: Succeeded. Jun 14 23:37:27 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 14 23:37:27 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:37:27 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 14 23:37:27 volumio-2 volumio-remote-updater[693]: No test mode Jun 14 23:37:27 volumio-2 volumio-remote-updater[693]: No alpha test mode Jun 14 23:37:27 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 14 23:37:27 volumio-2 volumio[971]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

","title":"Update v3.816","updateavailable":true} Jun 14 23:37:27 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 14 23:37:27 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 14 23:37:27 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 14 23:37:27 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 14 23:37:33 volumio-2 volumio[971]: info: BOOT COMPLETED Jun 14 23:37:34 volumio-2 systemd[1]: systemd-timedated.service: Succeeded. Jun 14 23:37:53 volumio-2 volumiologrotate[690]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Jun 14 23:37:53 volumio-2 volumiologrotate[690]: ls: cannot access '2': No such file or directory Jun 14 23:37:58 volumio-2 ntpd[956]: Soliciting pool server 217.61.62.224 Jun 14 23:37:59 volumio-2 ntpd[956]: Soliciting pool server 162.159.200.123 Jun 14 23:38:00 volumio-2 ntpd[956]: Soliciting pool server 80.211.238.32 Jun 14 23:38:00 volumio-2 ntpd[956]: Soliciting pool server 212.45.144.88 Jun 14 23:38:01 volumio-2 ntpd[956]: Soliciting pool server 212.45.144.3 Jun 14 23:38:01 volumio-2 ntpd[956]: Soliciting pool server 95.231.207.178 Jun 14 23:38:01 volumio-2 ntpd[956]: Soliciting pool server 95.110.254.234 Jun 14 23:38:02 volumio-2 ntpd[956]: Soliciting pool server 93.94.88.50 Jun 14 23:38:02 volumio-2 ntpd[956]: Soliciting pool server 185.19.184.35 Jun 14 23:38:02 volumio-2 ntpd[956]: Soliciting pool server 162.159.200.1 Jun 14 23:38:03 volumio-2 ntpd[956]: Soliciting pool server 37.247.53.178 Jun 14 23:38:03 volumio-2 ntpd[956]: Soliciting pool server 185.157.229.254 Jun 14 23:38:03 volumio-2 ntpd[956]: Soliciting pool server 212.45.144.206 Jun 14 23:38:04 volumio-2 ntpd[956]: Soliciting pool server 89.46.74.148 Jun 14 23:38:04 volumio-2 ntpd[956]: Soliciting pool server 195.32.70.195 Jun 14 23:38:05 volumio-2 ntpd[956]: Soliciting pool server 151.22.209.90 Jun 14 23:38:05 volumio-2 ntpd[956]: Soliciting pool server 2a00:6d41:200:2::11 Jun 14 23:38:06 volumio-2 ntpd[956]: receive: Unexpected origin timestamp 0xebf86a3d.fcd083f6 does not match aorg 0000000000.00000000 from server@89.46.74.148 xmt 0xebf86a3e.3864bc8d Jun 14 23:38:06 volumio-2 ntpd[956]: receive: Unexpected origin timestamp 0xebf86a3d.fcd16285 does not match aorg 0000000000.00000000 from server@93.94.88.50 xmt 0xebf86a3e.37f391e5 Jun 14 23:38:32 volumio-2 sudo[2169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 14 23:38:32 volumio-2 sudo[2169]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 23:38:32 volumio-2 sudo[2169]: pam_unix(sudo:session): session closed for user root Jun 14 23:38:32 volumio-2 sudo[2172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 14 23:38:32 volumio-2 sudo[2172]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 23:38:32 volumio-2 sudo[2172]: pam_unix(sudo:session): session closed for user root Jun 14 23:38:32 volumio-2 volumio[971]: verbose: New Socket.io Connection to 192.168.1.233 from 192.168.1.42 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jun 14 23:38:32 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 14 23:38:32 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 14 23:38:33 volumio-2 sudo[2185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 14 23:38:33 volumio-2 sudo[2185]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 23:38:33 volumio-2 sudo[2185]: pam_unix(sudo:session): session closed for user root Jun 14 23:38:33 volumio-2 sudo[2188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 14 23:38:33 volumio-2 sudo[2188]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 23:38:33 volumio-2 sudo[2188]: pam_unix(sudo:session): session closed for user root Jun 14 23:38:34 volumio-2 volumio[971]: verbose: New Socket.io Connection to 192.168.1.233 from 192.168.1.42 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jun 14 23:38:34 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 14 23:38:34 volumio-2 volumio[971]: info: CoreCommandRouter::volumioGetVisibleSources Jun 14 23:38:34 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:38:34 volumio-2 volumio[971]: info: CoreCommandRouter::volumioGetState Jun 14 23:38:34 volumio-2 volumio[971]: info: CorePlayQueue::getTrack 0 Jun 14 23:38:34 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 14 23:38:34 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 14 23:38:34 volumio-2 volumio[971]: info: Received Get System Info Jun 14 23:38:34 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 23:38:34 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 23:38:34 volumio-2 volumio[971]: info: Discovery: Getting this device information Jun 14 23:38:34 volumio-2 volumio[971]: info: CoreCommandRouter::volumioGetState Jun 14 23:38:34 volumio-2 volumio[971]: info: CorePlayQueue::getTrack 0 Jun 14 23:38:34 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 23:38:34 volumio-2 volumio[971]: info: CoreCommandRouter::volumioGetState Jun 14 23:38:34 volumio-2 volumio[971]: info: CorePlayQueue::getTrack 0 Jun 14 23:38:34 volumio-2 volumio[971]: info: Listing playlists Jun 14 23:38:34 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 14 23:38:34 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 14 23:38:34 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 14 23:38:35 volumio-2 volumio[971]: info: CoreCommandRouter::volumioGetState Jun 14 23:38:35 volumio-2 volumio[971]: info: CorePlayQueue::getTrack 0 Jun 14 23:38:35 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 14 23:38:35 volumio-2 volumio[971]: info: Received Get System Info Jun 14 23:38:35 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 23:38:35 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 23:38:35 volumio-2 volumio[971]: info: Discovery: Getting this device information Jun 14 23:38:35 volumio-2 volumio[971]: info: CoreCommandRouter::volumioGetState Jun 14 23:38:35 volumio-2 volumio[971]: info: CorePlayQueue::getTrack 0 Jun 14 23:38:35 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 23:38:36 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 14 23:38:36 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 14 23:38:38 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 14 23:38:38 volumio-2 volumio[971]: info: Received Get System Info Jun 14 23:38:38 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 23:38:38 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 23:38:38 volumio-2 volumio[971]: info: Discovery: Getting this device information Jun 14 23:38:38 volumio-2 volumio[971]: info: CoreCommandRouter::volumioGetState Jun 14 23:38:38 volumio-2 volumio[971]: info: CorePlayQueue::getTrack 0 Jun 14 23:38:38 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 23:38:39 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 14 23:38:39 volumio-2 volumio[971]: info: Received Get System Info Jun 14 23:38:39 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 23:38:39 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 23:38:39 volumio-2 volumio[971]: info: Discovery: Getting this device information Jun 14 23:38:39 volumio-2 volumio[971]: info: CoreCommandRouter::volumioGetState Jun 14 23:38:39 volumio-2 volumio[971]: info: CorePlayQueue::getTrack 0 Jun 14 23:38:39 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 23:38:44 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 14 23:38:45 volumio-2 volumio[971]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 14 23:38:45 volumio-2 volumio[971]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 14 23:38:45 volumio-2 volumio[971]: info: Retrieving Cloud Streaming UI Jun 14 23:38:45 volumio-2 volumio[971]: info: Getting Tidal Cloud Configuration Jun 14 23:38:45 volumio-2 volumio[971]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 14 23:38:45 volumio-2 volumio[971]: info: Getting Qobuz Cloud Configuration Jun 14 23:38:45 volumio-2 volumio[971]: info: Asking plugin for UI Config Jun 14 23:38:45 volumio-2 volumio[971]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 14 23:38:45 volumio-2 volumio[971]: info: Getting Spotify Cloud Configuration Jun 14 23:38:45 volumio-2 volumio[971]: info: Asking plugin for UI Config Jun 14 23:38:45 volumio-2 volumio[971]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 14 23:38:45 volumio-2 volumio[971]: info: Saving Spotify Acccount Jun 14 23:38:45 volumio-2 volumio[971]: info: Got Tidal Cloud Configuration Jun 14 23:38:45 volumio-2 volumio[971]: info: Got it Jun 14 23:38:45 volumio-2 volumio[971]: info: Got it Jun 14 23:38:45 volumio-2 volumio[971]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 14 23:38:45 volumio-2 volumio[971]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 14 23:38:45 volumio-2 volumio[971]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jun 14 23:38:45 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 14 23:38:45 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 14 23:38:45 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 14 23:38:45 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 14 23:38:45 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 23:38:45 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 23:38:45 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 23:38:45 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 23:38:45 volumio-2 volumio[971]: info: CoreCommandRouter::volumioGetBrowseSources Jun 14 23:38:45 volumio-2 volumio[971]: info: CoreCommandRouter::volumioGetBrowseSources Jun 14 23:38:45 volumio-2 volumio[971]: info: CoreCommandRouter::volumioGetBrowseSources Jun 14 23:38:45 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 23:38:45 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 14 23:38:49 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 23:38:53 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jun 14 23:38:53 volumio-2 volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jun 14 23:38:53 volumio-2 volumio[971]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 23:38:53 volumio-2 volumio[971]: Error: dns service error: unknown Jun 14 23:38:53 volumio-2 volumio[971]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Jun 14 23:38:53 volumio-2 volumio[971]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jun 14 23:38:53 volumio-2 volumio[971]: errorCode: -65537 Jun 14 23:38:53 volumio-2 volumio[971]: } Jun 14 23:38:53 volumio-2 volumio[971]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 23:38:53 volumio-2 sudo[2295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-14 23:37 Jun 14 23:38:53 volumio-2 sudo[2295]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"