-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2025-03-06 17:23:33 CET. -- Mar 06 17:22:00 volumio2 systemd-timedated[1419]: Changed local time to Thu Mar 6 17:22:00 2025 Mar 06 17:22:00 volumio2 sudo[1415]: pam_unix(sudo:session): session closed for user root Mar 06 17:22:00 volumio2 volumio-time-update[808]: volumio-time-update-util: System time updated successfully. Mar 06 17:22:00 volumio2 systemd[1]: Started Volumio Time Update Utility. Mar 06 17:22:00 volumio2 volumio[1158]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 06 17:22:00 volumio2 volumio[1158]: info: touch_display: X display number found: 0 Mar 06 17:22:00 volumio2 volumio[1158]: info: Discovery: Connected to remote: 10.4.4.88 Mar 06 17:22:00 volumio2 nmbd[943]: [2025/03/06 17:22:00.598029, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 06 17:22:00 volumio2 nmbd[943]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 06 17:22:00 volumio2 systemd[1]: Started Samba NMB Daemon. Mar 06 17:22:00 volumio2 nmbd[943]: [2025/03/06 17:22:00.608447, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 06 17:22:00 volumio2 nmbd[943]: query_name_response: Multiple (2) responses received for a query on subnet 10.4.4.87 for name WORKGROUP<1d>. Mar 06 17:22:00 volumio2 nmbd[943]: This response was from IP 10.4.4.10, reporting an IP address of 10.4.4.10. Mar 06 17:22:00 volumio2 systemd[1]: Starting Samba Winbind Daemon... Mar 06 17:22:00 volumio2 winbindd[1426]: [2025/03/06 17:22:00.709398, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 06 17:22:00 volumio2 winbindd[1426]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 06 17:22:00 volumio2 volumio[1158]: verbose: New Socket.io Connection to 10.4.4.87:3000 from 10.4.4.88 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Mar 06 17:22:00 volumio2 winbindd[1426]: [2025/03/06 17:22:00.773465, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 06 17:22:00 volumio2 systemd[1]: Started Samba Winbind Daemon. Mar 06 17:22:00 volumio2 winbindd[1426]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 06 17:22:00 volumio2 systemd[1]: Starting Samba SMB Daemon... Mar 06 17:22:00 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Mar 06 17:22:00 volumio2 startx[1388]: V3D 7.1 not supported by this version of Mesa. Mar 06 17:22:00 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetState Mar 06 17:22:00 volumio2 volumio[1158]: info: CorePlayQueue::getTrack 0 Mar 06 17:22:01 volumio2 smbd[1431]: [2025/03/06 17:22:01.050325, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 06 17:22:01 volumio2 smbd[1431]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 06 17:22:01 volumio2 systemd[1]: Started Samba SMB Daemon. Mar 06 17:22:01 volumio2 systemd[1]: Reached target Multi-User System. Mar 06 17:22:01 volumio2 systemd[1]: Reached target Graphical Interface. Mar 06 17:22:01 volumio2 systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 06 17:22:01 volumio2 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 06 17:22:01 volumio2 systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 06 17:22:01 volumio2 systemd[1]: Startup finished in 31.187s (kernel) + 24.912s (userspace) = 56.100s. Mar 06 17:22:01 volumio2 kernel: broken atomic modeset userspace detected, disabling atomic Mar 06 17:22:01 volumio2 startx[1388]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Mar 06 17:22:01 volumio2 volumio[1158]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 06 17:22:01 volumio2 volumio[1158]: verbose: New Socket.io Connection to 10.4.4.87 from 10.1.1.201 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 20 Mar 06 17:22:01 volumio2 sudo[1204]: pam_unix(sudo:session): session closed for user root Mar 06 17:22:01 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 06 17:22:01 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetVisibleSources Mar 06 17:22:01 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 06 17:22:01 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetState Mar 06 17:22:01 volumio2 volumio[1158]: info: CorePlayQueue::getTrack 0 Mar 06 17:22:01 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetState Mar 06 17:22:01 volumio2 volumio[1158]: info: CorePlayQueue::getTrack 0 Mar 06 17:22:01 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 06 17:22:01 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 06 17:22:01 volumio2 volumio[1158]: info: Received Get System Info Mar 06 17:22:01 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 17:22:01 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 17:22:01 volumio2 volumio[1158]: info: Discovery: Getting this device information Mar 06 17:22:01 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetState Mar 06 17:22:01 volumio2 volumio[1158]: info: CorePlayQueue::getTrack 0 Mar 06 17:22:01 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 17:22:01 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetState Mar 06 17:22:01 volumio2 volumio[1158]: info: CorePlayQueue::getTrack 0 Mar 06 17:22:01 volumio2 volumio[1158]: info: Listing playlists Mar 06 17:22:02 volumio2 volumio-remote-updater[807]: [2025-03-06 17:22:02] [connect] Successful connection Mar 06 17:22:02 volumio2 volumio-remote-updater[807]: [2025-03-06 17:22:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1741278122 101 Mar 06 17:22:02 volumio2 volumio[1158]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 21 Mar 06 17:22:04 volumio2 volumio[1158]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 06 17:22:07 volumio2 volumio[1158]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 22 Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 06 17:22:08 volumio2 volumio[1158]: info: Adding plugin bluetooth to MyMusic Plugins Mar 06 17:22:08 volumio2 volumio[1158]: info: Adding plugin multiroom to MyMusic Plugins Mar 06 17:22:08 volumio2 volumio[1158]: info: Adding plugin metavolumio to MyMusic Plugins Mar 06 17:22:08 volumio2 volumio[1158]: info: Adding plugin cd_controller to MyMusic Plugins Mar 06 17:22:08 volumio2 systemd[1]: systemd-fsckd.service: Succeeded. Mar 06 17:22:08 volumio2 volumio[1158]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 06 17:22:08 volumio2 volumio[1158]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 06 17:22:08 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 17:22:08 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 17:22:08 volumio2 volumio[1158]: info: Starting MyVolumio Remote Streaming Endpoints Mar 06 17:22:08 volumio2 volumio[1158]: info: MyVolumio login type: Token Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 06 17:22:08 volumio2 volumio[1158]: info: Starting Streaming Service Transparent Proxy Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 06 17:22:08 volumio2 volumio[1158]: info: Streaming services startup Mar 06 17:22:08 volumio2 volumio[1158]: info: Starting Streaming Daemon Mar 06 17:22:08 volumio2 sh[707]: timed out Mar 06 17:22:08 volumio2 dhcpcd[754]: timed out Mar 06 17:22:08 volumio2 sh[707]: dhcpcd exited Mar 06 17:22:08 volumio2 dhcpcd[754]: dhcpcd exited Mar 06 17:22:08 volumio2 sh[707]: ifup: failed to bring up eth0 Mar 06 17:22:08 volumio2 sudo[1661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 06 17:22:08 volumio2 sudo[1661]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 17:22:08 volumio2 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Mar 06 17:22:08 volumio2 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Mar 06 17:22:08 volumio2 volumio[1158]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 06 17:22:08 volumio2 sudo[1661]: pam_unix(sudo:session): session closed for user root Mar 06 17:22:09 volumio2 sudo[1670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 06 17:22:09 volumio2 sudo[1670]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 17:22:09 volumio2 sudo[1670]: pam_unix(sudo:session): session closed for user root Mar 06 17:22:09 volumio2 sudo[1672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 06 17:22:09 volumio2 sudo[1672]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 17:22:09 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 06 17:22:09 volumio2 sudo[1672]: pam_unix(sudo:session): session closed for user root Mar 06 17:22:09 volumio2 volumio[1158]: error: Cannot start Volumio Streaming Daemon Mar 06 17:22:09 volumio2 volumio[1158]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 06 17:22:09 volumio2 volumio[1158]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 06 17:22:09 volumio2 sudo[1676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 06 17:22:09 volumio2 sudo[1676]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 17:22:09 volumio2 systemd[1]: Started UPnP Renderer front-end to MPD. Mar 06 17:22:09 volumio2 sudo[1676]: pam_unix(sudo:session): session closed for user root Mar 06 17:22:09 volumio2 volumio[1158]: info: Upmpdcli Daemon Started Mar 06 17:22:09 volumio2 volumio[1678]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 06 17:22:09 volumio2 volumio[1158]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 23 Mar 06 17:22:09 volumio2 volumio[1158]: STREAMING PROXY: Starting server on port 3245 Mar 06 17:22:09 volumio2 volumio[1158]: Node JS runtime: 14 Mar 06 17:22:09 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 06 17:22:09 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetVisibleSources Mar 06 17:22:09 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 06 17:22:09 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetState Mar 06 17:22:09 volumio2 volumio[1158]: info: CorePlayQueue::getTrack 0 Mar 06 17:22:09 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 06 17:22:09 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 06 17:22:09 volumio2 volumio[1158]: info: Received Get System Info Mar 06 17:22:09 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 17:22:09 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 17:22:09 volumio2 volumio[1158]: info: Discovery: Getting this device information Mar 06 17:22:09 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetState Mar 06 17:22:09 volumio2 volumio[1158]: info: CorePlayQueue::getTrack 0 Mar 06 17:22:09 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 17:22:09 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetState Mar 06 17:22:09 volumio2 volumio[1158]: info: CorePlayQueue::getTrack 0 Mar 06 17:22:09 volumio2 volumio[1158]: info: Listing playlists Mar 06 17:22:09 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 06 17:22:09 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 06 17:22:09 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 06 17:22:09 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 06 17:22:09 volumio2 volumio[1158]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 06 17:22:10 volumio2 systemd[1]: systemd-hostnamed.service: Succeeded. Mar 06 17:22:10 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetState Mar 06 17:22:10 volumio2 volumio[1158]: info: CorePlayQueue::getTrack 0 Mar 06 17:22:11 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 06 17:22:11 volumio2 volumio[1158]: info: Received Get System Info Mar 06 17:22:11 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 17:22:11 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 17:22:11 volumio2 volumio[1158]: info: Discovery: Getting this device information Mar 06 17:22:11 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetState Mar 06 17:22:11 volumio2 volumio[1158]: info: CorePlayQueue::getTrack 0 Mar 06 17:22:11 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 17:22:11 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 06 17:22:12 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 06 17:22:12 volumio2 volumio[1158]: info: Received Get System Info Mar 06 17:22:12 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 17:22:12 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 17:22:12 volumio2 volumio[1158]: info: Discovery: Getting this device information Mar 06 17:22:12 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetState Mar 06 17:22:12 volumio2 volumio[1158]: info: CorePlayQueue::getTrack 0 Mar 06 17:22:12 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 17:22:12 volumio2 volumio[1158]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/buster/armhf Mar 06 17:22:17 volumio2 volumio[1678]: ..........................................................................................................................................................................++++ Mar 06 17:22:17 volumio2 volumio[1678]: .........++++ Mar 06 17:22:17 volumio2 volumio[1678]: e is 65537 (0x010001) Mar 06 17:22:17 volumio2 volumio[1678]: writing RSA key Mar 06 17:22:17 volumio2 volumio[1158]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/buster/armhf Mar 06 17:22:17 volumio2 volumio[1158]: info: Folder /tmp/plugins removed Mar 06 17:22:18 volumio2 volumio[1158]: info: Check plugin dependencies Mar 06 17:22:18 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 06 17:22:18 volumio2 volumio[1158]: info: Checking if plugin already exists Mar 06 17:22:18 volumio2 volumio[1158]: info: Rename folder Mar 06 17:22:18 volumio2 volumio[1158]: info: Folder /tmp/downloaded_plugin.zip removed Mar 06 17:22:18 volumio2 volumio[1158]: info: Move to category Mar 06 17:22:19 volumio2 volumio[1158]: info: MyVolumio token set successfully Mar 06 17:22:19 volumio2 volumio[1158]: info: MYVOLUMIO: Adding device Mar 06 17:22:19 volumio2 volumio[1158]: info: MYVOLUMIO: Evaluating Server Mar 06 17:22:19 volumio2 volumio[1158]: info: MyVolumio status changed Mar 06 17:22:19 volumio2 volumio[1158]: info: Streaming services startup Mar 06 17:22:19 volumio2 volumio[1158]: info: Starting Streaming Daemon Mar 06 17:22:19 volumio2 volumio[1158]: info: Removing browser output: myVolumio user plan is not superstar Mar 06 17:22:19 volumio2 volumio[1158]: info: Removing audio output: Mar 06 17:22:19 volumio2 volumio[1158]: info: Stoppping Tunnel 1 Mar 06 17:22:19 volumio2 sudo[1763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 06 17:22:19 volumio2 sudo[1763]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 17:22:19 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 06 17:22:19 volumio2 sudo[1766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 06 17:22:19 volumio2 sudo[1766]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 17:22:19 volumio2 sudo[1763]: pam_unix(sudo:session): session closed for user root Mar 06 17:22:19 volumio2 sudo[1766]: pam_unix(sudo:session): session closed for user root Mar 06 17:22:19 volumio2 volumio[1158]: error: Cannot start Volumio Streaming Daemon Mar 06 17:22:19 volumio2 volumio[1158]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 06 17:22:19 volumio2 volumio[1158]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 06 17:22:19 volumio2 volumio[1158]: info: Remote SSH Stopped Mar 06 17:22:30 volumio2 systemd[1]: systemd-timedated.service: Succeeded. Mar 06 17:22:32 volumio2 volumio-remote-updater[807]: [2025-03-06 17:22:32] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Mar 06 17:22:37 volumio2 volumio-remote-updater[807]: [2025-03-06 17:22:37] [connect] Successful connection Mar 06 17:22:46 volumio2 ntpd[966]: Soliciting pool server 80.50.102.114 Mar 06 17:22:46 volumio2 ntpd[966]: Soliciting pool server 46.175.224.7 Mar 06 17:22:47 volumio2 ntpd[966]: Soliciting pool server 213.135.57.60 Mar 06 17:22:47 volumio2 ntpd[966]: Soliciting pool server 91.212.242.21 Mar 06 17:22:48 volumio2 ntpd[966]: Soliciting pool server 51.68.141.5 Mar 06 17:22:48 volumio2 ntpd[966]: Soliciting pool server 109.206.205.233 Mar 06 17:22:48 volumio2 ntpd[966]: Soliciting pool server 188.241.28.254 Mar 06 17:22:48 volumio2 ntpd[966]: Soliciting pool server 162.159.200.1 Mar 06 17:22:49 volumio2 volumio-remote-updater[807]: [2025-03-06 17:22:49] [connect] Successful connection Mar 06 17:22:49 volumio2 ntpd[966]: Soliciting pool server 193.59.134.156 Mar 06 17:22:49 volumio2 ntpd[966]: Soliciting pool server 185.157.13.96 Mar 06 17:22:49 volumio2 ntpd[966]: Soliciting pool server 193.219.28.60 Mar 06 17:22:50 volumio2 ntpd[966]: Soliciting pool server 195.46.37.22 Mar 06 17:22:50 volumio2 ntpd[966]: Soliciting pool server 91.227.212.78 Mar 06 17:22:50 volumio2 ntpd[966]: Soliciting pool server 89.161.47.132 Mar 06 17:22:51 volumio2 ntpd[966]: Soliciting pool server 150.254.65.62 Mar 06 17:22:51 volumio2 ntpd[966]: Soliciting pool server 212.127.78.21 Mar 06 17:22:51 volumio2 ntpd[966]: Soliciting pool server 2606:4700:f1::123 Mar 06 17:22:51 volumio2 ntpd[966]: Soliciting pool server 212.127.95.218 Mar 06 17:22:54 volumio2 ntpd[966]: receive: Unexpected origin timestamp 0xeb744a5d.e6074d5c does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xeb744a5e.b3a2da7a Mar 06 17:22:54 volumio2 ntpd[966]: receive: Unexpected origin timestamp 0xeb744a5d.e606a7da does not match aorg 0000000000.00000000 from server@109.206.205.233 xmt 0xeb744a5e.b340c824 Mar 06 17:22:54 volumio2 ntpd[966]: receive: Unexpected origin timestamp 0xeb744a5d.e607c3f0 does not match aorg 0000000000.00000000 from server@91.212.242.21 xmt 0xeb744a5e.b44f9e3d Mar 06 17:22:54 volumio2 ntpd[966]: receive: Unexpected origin timestamp 0xeb744a5d.e603337d does not match aorg 0000000000.00000000 from server@185.157.13.96 xmt 0xeb744a5e.b40d6a9b Mar 06 17:22:54 volumio2 ntpd[966]: receive: Unexpected origin timestamp 0xeb744a5d.e606012d does not match aorg 0000000000.00000000 from server@188.241.28.254 xmt 0xeb744a5e.b4c7ef18 Mar 06 17:22:54 volumio2 ntpd[966]: receive: Unexpected origin timestamp 0xeb744a5d.e608319f does not match aorg 0000000000.00000000 from server@213.135.57.60 xmt 0xeb744a5e.b61cf700 Mar 06 17:23:05 volumio2 volumio-remote-updater[807]: [2025-03-06 17:23:05] [connect] Successful connection Mar 06 17:23:17 volumio2 volumio[1158]: info: Checking if install.sh is present Mar 06 17:23:17 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:23:17 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:23:17 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 06 17:23:17 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 06 17:23:17 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 06 17:23:17 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 06 17:23:17 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetBrowseSources Mar 06 17:23:17 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 06 17:23:17 volumio2 volumio[1158]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 06 17:23:17 volumio2 volumio[1158]: info: Completed starting MyVolumio Plugin Mar 06 17:23:17 volumio2 volumio[1158]: [Metrics] CommandRouter: 79s 670.57ms Mar 06 17:23:17 volumio2 volumio[1158]: info: CoreCommandRouter::volumiosetStartupVolume Mar 06 17:23:17 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:23:17 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 17:23:17 volumio2 volumio[1158]: info: CoreCommandRouter::Close All Modals sent Mar 06 17:23:17 volumio2 volumio[1158]: info: CoreCommandRouter::Close All Modals sent Mar 06 17:23:17 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 06 17:23:17 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 06 17:23:17 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 17:23:17 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 06 17:23:17 volumio2 volumio[1158]: info: Setting Geolocation for MyVolumio to eu4 Mar 06 17:23:17 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 17:23:17 volumio2 volumio[1158]: info: Executing install.sh Mar 06 17:23:17 volumio2 volumio[1158]: info: Discovery: Disconnected from remote: 10.4.4.88 Mar 06 17:23:17 volumio2 volumio[1158]: verbose: New Socket.io Connection to 10.4.4.87:3000 from 10.4.4.88 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Mar 06 17:23:17 volumio2 volumio[1158]: verbose: New Socket.io Connection to 10.4.4.87 from 10.1.1.201 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Mar 06 17:23:17 volumio2 volumio[1158]: verbose: New Socket.io Connection to 10.4.4.87 from 10.1.1.201 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Mar 06 17:23:17 volumio2 volumio[1158]: verbose: New Socket.io Connection to 10.4.4.87:3000 from 10.4.4.88 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 06 17:23:17 volumio2 volumio[1158]: verbose: New Socket.io Connection to 10.4.4.87 from 10.1.1.201 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Mar 06 17:23:17 volumio2 volumio[1158]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 06 17:23:17 volumio2 volumio[1158]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Mar 06 17:23:17 volumio2 volumio[1158]: verbose: New Socket.io Connection to 10.4.4.87 from 10.1.1.201 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 06 17:23:17 volumio2 volumio[1158]: verbose: New Socket.io Connection to 10.4.4.87:3000 from 10.4.4.88 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 06 17:23:17 volumio2 volumio[1158]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Mar 06 17:23:17 volumio2 volumio[1158]: verbose: New Socket.io Connection to 10.4.4.87 from 10.1.1.201 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Mar 06 17:23:17 volumio2 volumio[1158]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Mar 06 17:23:17 volumio2 volumio[1158]: verbose: New Socket.io Connection to 10.4.4.87 from 10.1.1.201 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Mar 06 17:23:17 volumio2 volumio[1158]: verbose: New Socket.io Connection to 10.4.4.87:3000 from 10.4.4.88 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Mar 06 17:23:17 volumio2 volumio[1158]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Mar 06 17:23:17 volumio2 volumio[1158]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Mar 06 17:23:17 volumio2 volumio[1158]: verbose: New Socket.io Connection to 10.4.4.87:3000 from 10.4.4.88 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Mar 06 17:23:17 volumio2 volumio[1158]: verbose: New Socket.io Connection to 10.4.4.87 from 10.1.1.201 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 Mar 06 17:23:17 volumio2 volumio[1158]: verbose: New Socket.io Connection to 10.4.4.87 from 10.1.1.201 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19 Mar 06 17:23:17 volumio2 volumio[1158]: verbose: New Socket.io Connection to 10.4.4.87 from 10.1.1.201 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 20 Mar 06 17:23:17 volumio2 volumio[1158]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 21 Mar 06 17:23:17 volumio2 volumio[1158]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 22 Mar 06 17:23:17 volumio2 volumio[1158]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 23 Mar 06 17:23:17 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 06 17:23:17 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetState Mar 06 17:23:17 volumio2 volumio[1158]: info: CorePlayQueue::getTrack 0 Mar 06 17:23:18 volumio2 volumio[1158]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 06 17:23:18 volumio2 volumio[1158]: info: Received Get System Version Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 06 17:23:18 volumio2 volumio[1158]: info: Received Get System Info Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 17:23:18 volumio2 volumio[1158]: info: Discovery: Getting this device information Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetState Mar 06 17:23:18 volumio2 volumio[1158]: info: CorePlayQueue::getTrack 0 Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetVisibleSources Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetState Mar 06 17:23:18 volumio2 volumio[1158]: info: CorePlayQueue::getTrack 0 Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetState Mar 06 17:23:18 volumio2 volumio[1158]: info: CorePlayQueue::getTrack 0 Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 06 17:23:18 volumio2 volumio[1158]: info: Received Get System Info Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 17:23:18 volumio2 volumio[1158]: info: Discovery: Getting this device information Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetState Mar 06 17:23:18 volumio2 volumio[1158]: info: CorePlayQueue::getTrack 0 Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetState Mar 06 17:23:18 volumio2 volumio[1158]: info: CorePlayQueue::getTrack 0 Mar 06 17:23:18 volumio2 volumio[1158]: info: Listing playlists Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 06 17:23:18 volumio2 volumio[1158]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 06 17:23:18 volumio2 volumio[1158]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 24 Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 06 17:23:18 volumio2 volumio[1158]: info: Updating MyVolumio device info Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 17:23:18 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 17:23:19 volumio2 volumio[1158]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 25 Mar 06 17:23:19 volumio2 volumio[1158]: info: Discovery: Connected to remote: 10.4.4.88 Mar 06 17:23:19 volumio2 volumio[1158]: verbose: New Socket.io Connection to 10.4.4.87:3000 from 10.4.4.88 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Mar 06 17:23:19 volumio2 volumio[1158]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 06 17:23:19 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Mar 06 17:23:19 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetState Mar 06 17:23:19 volumio2 volumio[1158]: info: CorePlayQueue::getTrack 0 Mar 06 17:23:20 volumio2 volumio[1158]: verbose: New Socket.io Connection to 10.4.4.87 from 10.1.1.201 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 27 Mar 06 17:23:20 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 06 17:23:20 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetVisibleSources Mar 06 17:23:20 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 06 17:23:20 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetState Mar 06 17:23:20 volumio2 volumio[1158]: info: CorePlayQueue::getTrack 0 Mar 06 17:23:20 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetState Mar 06 17:23:20 volumio2 volumio[1158]: info: CorePlayQueue::getTrack 0 Mar 06 17:23:20 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 06 17:23:20 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 06 17:23:20 volumio2 volumio[1158]: info: Received Get System Info Mar 06 17:23:20 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 17:23:20 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 17:23:20 volumio2 volumio[1158]: info: Discovery: Getting this device information Mar 06 17:23:20 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetState Mar 06 17:23:20 volumio2 volumio[1158]: info: CorePlayQueue::getTrack 0 Mar 06 17:23:20 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 17:23:20 volumio2 volumio[1158]: info: CoreCommandRouter::volumioGetState Mar 06 17:23:20 volumio2 volumio[1158]: info: CorePlayQueue::getTrack 0 Mar 06 17:23:20 volumio2 volumio[1158]: info: Listing playlists Mar 06 17:23:20 volumio2 volumio-remote-updater[807]: [2025-03-06 17:23:20] [connect] Successful connection Mar 06 17:23:20 volumio2 volumio[1158]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 28 Mar 06 17:23:20 volumio2 volumio-remote-updater[807]: [2025-03-06 17:23:20] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1741278200 101 Mar 06 17:23:20 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 06 17:23:22 volumio2 sudo[1991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/now_playing/install.sh Mar 06 17:23:22 volumio2 sudo[1991]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 17:23:22 volumio2 volumio[1158]: info: Installing geo-tz node dependency Mar 06 17:23:22 volumio2 volumio[1158]: info: MYVOLUMIO: Adding device Mar 06 17:23:22 volumio2 volumio[1158]: info: MYVOLUMIO: Evaluating Server Mar 06 17:23:22 volumio2 volumio[1158]: info: Setting Geolocation for MyVolumio to eu4 Mar 06 17:23:22 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 17:23:22 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 17:23:22 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 17:23:23 volumio2 volumio[1158]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 06 17:23:23 volumio2 volumio[1158]: info: Updating MyVolumio device info Mar 06 17:23:23 volumio2 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 17:23:24 volumio2 volumio[1158]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 06 17:23:24 volumio2 volumio[1158]: info: BOOT COMPLETED Mar 06 17:23:28 volumio2 volumio[1158]: info: Enabling plugin now_playing Mar 06 17:23:28 volumio2 volumio[1158]: info: Loading plugin "now_playing"... Mar 06 17:23:28 volumio2 volumio[1158]: error: !!!! WARNING !!!! Mar 06 17:23:28 volumio2 volumio[1158]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Mar 06 17:23:28 volumio2 volumio[1158]: Require stack: Mar 06 17:23:28 volumio2 volumio[1158]: - /data/plugins/user_interface/now_playing/dist/index.js Mar 06 17:23:28 volumio2 volumio[1158]: - /volumio/app/pluginmanager.js Mar 06 17:23:28 volumio2 volumio[1158]: - /volumio/app/index.js Mar 06 17:23:28 volumio2 volumio[1158]: - /volumio/index.js Mar 06 17:23:28 volumio2 volumio[1158]: error: Stack trace: Error: Cannot find module 'geo-tz' Mar 06 17:23:28 volumio2 volumio[1158]: Require stack: Mar 06 17:23:28 volumio2 volumio[1158]: - /data/plugins/user_interface/now_playing/dist/index.js Mar 06 17:23:28 volumio2 volumio[1158]: - /volumio/app/pluginmanager.js Mar 06 17:23:28 volumio2 volumio[1158]: - /volumio/app/index.js Mar 06 17:23:28 volumio2 volumio[1158]: - /volumio/index.js Mar 06 17:23:28 volumio2 volumio[1158]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:880:15) Mar 06 17:23:28 volumio2 volumio[1158]: at Function.Module._load (internal/modules/cjs/loader.js:725:27) Mar 06 17:23:28 volumio2 volumio[1158]: at Module.require (internal/modules/cjs/loader.js:952:19) Mar 06 17:23:28 volumio2 volumio[1158]: at require (internal/modules/cjs/helpers.js:88:18) Mar 06 17:23:28 volumio2 volumio[1158]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:46:34) Mar 06 17:23:28 volumio2 volumio[1158]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Mar 06 17:23:28 volumio2 volumio[1158]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Mar 06 17:23:28 volumio2 volumio[1158]: at Module.load (internal/modules/cjs/loader.js:928:32) Mar 06 17:23:28 volumio2 volumio[1158]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Mar 06 17:23:28 volumio2 volumio[1158]: at Module.require (internal/modules/cjs/loader.js:952:19) Mar 06 17:23:28 volumio2 volumio[1158]: at require (internal/modules/cjs/helpers.js:88:18) Mar 06 17:23:28 volumio2 volumio[1158]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Mar 06 17:23:28 volumio2 volumio[1158]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Mar 06 17:23:28 volumio2 volumio[1158]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 06 17:23:28 volumio2 volumio[1158]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Mar 06 17:23:28 volumio2 volumio[1158]: error: !!!! WARNING !!!! Mar 06 17:23:28 volumio2 volumio[1158]: info: Done. Mar 06 17:23:32 volumio2 volumio[1158]: info: + geo-tz@8.1.3 Mar 06 17:23:32 volumio2 volumio[1158]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 06 17:23:32 volumio2 volumio[1158]: TypeError: Cannot read property 'broadcastMessage' of null Mar 06 17:23:32 volumio2 volumio[1158]: at /volumio/app/index.js:1528:32 Mar 06 17:23:32 volumio2 volumio[1158]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Mar 06 17:23:32 volumio2 volumio[1158]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Mar 06 17:23:32 volumio2 volumio[1158]: at CoreCommandRouter.broadcastMessage (/volumio/app/index.js:1526:13) Mar 06 17:23:32 volumio2 volumio[1158]: at PluginManager.pushMessage (/volumio/app/pluginmanager.js:1594:20) Mar 06 17:23:32 volumio2 volumio[1158]: at Tail. (/volumio/app/pluginmanager.js:860:24) Mar 06 17:23:32 volumio2 volumio[1158]: at Tail.emit (events.js:315:20) Mar 06 17:23:32 volumio2 volumio[1158]: at ReadStream. (/volumio/node_modules/tail/lib/tail.js:51:33) Mar 06 17:23:32 volumio2 volumio[1158]: at ReadStream.emit (events.js:315:20) Mar 06 17:23:32 volumio2 volumio[1158]: at addChunk (internal/streams/readable.js:309:12) Mar 06 17:23:32 volumio2 volumio[1158]: at readableAddChunk (internal/streams/readable.js:280:11) Mar 06 17:23:32 volumio2 volumio[1158]: at ReadStream.Readable.push (internal/streams/readable.js:223:10) Mar 06 17:23:32 volumio2 volumio[1158]: at internal/fs/streams.js:226:14 Mar 06 17:23:32 volumio2 volumio[1158]: at FSReqCallback.wrapper [as oncomplete] (fs.js:539:5) Mar 06 17:23:32 volumio2 volumio[1158]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 06 17:23:33 volumio2 sudo[2172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-06 17:22 Mar 06 17:23:33 volumio2 sudo[2172]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"