-- Logs begin at Thu 2019-02-14 05:11:58 EST, end at Wed 2025-06-04 15:01:21 EDT. -- Jun 04 15:00:16 volumio-rpi systemd-timedated[1148]: Changed local time to Wed Jun 4 15:00:16 2025 Jun 04 15:00:16 volumio-rpi sudo[1146]: pam_unix(sudo:session): session closed for user root Jun 04 15:00:16 volumio-rpi volumio-time-update[595]: volumio-time-update-util: System time updated successfully. Jun 04 15:00:16 volumio-rpi volumio[849]: info: Discovery: A device disappeared from network Jun 04 15:00:16 volumio-rpi systemd[1]: Starting Daily apt download activities... Jun 04 15:00:16 volumio-rpi systemd[1]: Started Volumio Time Update Utility. Jun 04 15:00:16 volumio-rpi systemd[1]: Reached target Multi-User System. Jun 04 15:00:16 volumio-rpi systemd[1]: Reached target Graphical Interface. Jun 04 15:00:16 volumio-rpi systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 04 15:00:16 volumio-rpi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 04 15:00:16 volumio-rpi systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 04 15:00:16 volumio-rpi volumio[849]: info: Discovery: adding 9fe67853-389d-423b-ab4c-e593acd1fbf8 Jun 04 15:00:16 volumio-rpi volumio[849]: info: Discovery: Found device Volumio Rpi Jun 04 15:00:16 volumio-rpi volumio[849]: info: CoreCommandRouter::volumioGetState Jun 04 15:00:16 volumio-rpi volumio[849]: info: CorePlayQueue::getTrack 0 Jun 04 15:00:16 volumio-rpi volumio[849]: info: Volumio Network Manager: Network status updated: 1 Jun 04 15:00:16 volumio-rpi nmbd[806]: [2025/06/04 15:00:16.801233, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 04 15:00:16 volumio-rpi nmbd[806]: query_name_response: Multiple (2) responses received for a query on subnet 10.0.0.88 for name WORKGROUP<1d>. Jun 04 15:00:16 volumio-rpi nmbd[806]: This response was from IP 10.0.0.170, reporting an IP address of 10.0.0.170. Jun 04 15:00:16 volumio-rpi nmbd[806]: [2025/06/04 15:00:16.801434, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 04 15:00:16 volumio-rpi nmbd[806]: query_name_response: Multiple (3) responses received for a query on subnet 10.0.0.88 for name WORKGROUP<1d>. Jun 04 15:00:16 volumio-rpi nmbd[806]: This response was from IP 10.0.0.131, reporting an IP address of 10.0.0.170. Jun 04 15:00:16 volumio-rpi nmbd[806]: [2025/06/04 15:00:16.801579, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 04 15:00:16 volumio-rpi nmbd[806]: query_name_response: Multiple (4) responses received for a query on subnet 10.0.0.88 for name WORKGROUP<1d>. Jun 04 15:00:16 volumio-rpi nmbd[806]: This response was from IP 10.0.0.131, reporting an IP address of 10.0.0.170. Jun 04 15:00:16 volumio-rpi nmbd[806]: [2025/06/04 15:00:16.801749, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 04 15:00:16 volumio-rpi nmbd[806]: query_name_response: Multiple (5) responses received for a query on subnet 10.0.0.88 for name WORKGROUP<1d>. Jun 04 15:00:16 volumio-rpi nmbd[806]: This response was from IP 10.0.0.170, reporting an IP address of 10.0.0.170. Jun 04 15:00:16 volumio-rpi nmbd[806]: [2025/06/04 15:00:16.801895, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 04 15:00:16 volumio-rpi nmbd[806]: query_name_response: Multiple (6) responses received for a query on subnet 10.0.0.88 for name WORKGROUP<1d>. Jun 04 15:00:16 volumio-rpi nmbd[806]: This response was from IP 10.0.0.170, reporting an IP address of 10.0.0.170. Jun 04 15:00:17 volumio-rpi volumio[849]: verbose: New Socket.io Connection to 10.0.0.88 from 10.0.0.186 UA: Mozilla/5.0 (Linux; Android 15; Pixel 9 Pro XL Build/BP1A.250505.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.127 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jun 04 15:00:17 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 04 15:00:17 volumio-rpi volumio-remote-updater[592]: [2025-06-04 15:00:17] [connect] Successful connection Jun 04 15:00:17 volumio-rpi volumio-remote-updater[592]: [2025-06-04 15:00:17] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749063617 101 Jun 04 15:00:17 volumio-rpi volumio[849]: 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: 9 Jun 04 15:00:17 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 04 15:00:17 volumio-rpi volumio[849]: info: CoreCommandRouter::volumioGetVisibleSources Jun 04 15:00:17 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 15:00:17 volumio-rpi volumio[849]: info: CoreCommandRouter::volumioGetState Jun 04 15:00:17 volumio-rpi volumio[849]: info: CorePlayQueue::getTrack 0 Jun 04 15:00:17 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 04 15:00:17 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 04 15:00:17 volumio-rpi volumio[849]: info: Received Get System Info Jun 04 15:00:17 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 15:00:17 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 15:00:17 volumio-rpi volumio[849]: info: Discovery: Getting this device information Jun 04 15:00:17 volumio-rpi volumio[849]: info: CoreCommandRouter::volumioGetState Jun 04 15:00:17 volumio-rpi volumio[849]: info: CorePlayQueue::getTrack 0 Jun 04 15:00:17 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 15:00:17 volumio-rpi volumio[849]: info: CoreCommandRouter::volumioGetState Jun 04 15:00:17 volumio-rpi volumio[849]: info: CorePlayQueue::getTrack 0 Jun 04 15:00:17 volumio-rpi volumio[849]: info: Listing playlists Jun 04 15:00:17 volumio-rpi volumio[849]: info: CoreCommandRouter::volumioGetQueue Jun 04 15:00:17 volumio-rpi volumio[849]: info: CoreStateMachine::getQueue Jun 04 15:00:17 volumio-rpi volumio[849]: info: CorePlayQueue::getQueue Jun 04 15:00:17 volumio-rpi sudo[1188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.1 //10.0.0.170/Music/Roon Database /mnt/NAS/Music Jun 04 15:00:17 volumio-rpi sudo[1188]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 15:00:17 volumio-rpi kernel: CIFS: Attempting to mount //10.0.0.170/Music/Roon Database Jun 04 15:00:17 volumio-rpi sudo[1193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.1 //10.0.0.131/Music /mnt/NAS/Music Jun 04 15:00:17 volumio-rpi sudo[1193]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 15:00:17 volumio-rpi sudo[1188]: pam_unix(sudo:session): session closed for user root Jun 04 15:00:18 volumio-rpi kernel: CIFS: Attempting to mount //10.0.0.131/Music Jun 04 15:00:18 volumio-rpi systemd[1]: apt-daily.service: Succeeded. Jun 04 15:00:18 volumio-rpi systemd[1]: Started Daily apt download activities. Jun 04 15:00:18 volumio-rpi systemd[1]: Starting Daily apt upgrade and clean activities... Jun 04 15:00:18 volumio-rpi sudo[1193]: pam_unix(sudo:session): session closed for user root Jun 04 15:00:19 volumio-rpi systemd[1]: apt-daily-upgrade.service: Succeeded. Jun 04 15:00:19 volumio-rpi systemd[1]: Started Daily apt upgrade and clean activities. Jun 04 15:00:19 volumio-rpi systemd[1]: Startup finished in 11.925s (kernel) + 22.626s (userspace) = 34.551s. Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 04 15:00:22 volumio-rpi volumio[849]: info: Adding plugin bluetooth to MyMusic Plugins Jun 04 15:00:22 volumio-rpi volumio[849]: info: Adding plugin multiroom to MyMusic Plugins Jun 04 15:00:22 volumio-rpi volumio[849]: info: Adding plugin metavolumio to MyMusic Plugins Jun 04 15:00:22 volumio-rpi volumio[849]: info: Adding plugin cd_controller to MyMusic Plugins Jun 04 15:00:22 volumio-rpi volumio[849]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 04 15:00:22 volumio-rpi volumio[849]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 04 15:00:22 volumio-rpi volumio[849]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 04 15:00:22 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 04 15:00:23 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 04 15:00:23 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 04 15:00:23 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 15:00:23 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 15:00:23 volumio-rpi volumio[849]: info: Starting MyVolumio Remote Streaming Endpoints Jun 04 15:00:23 volumio-rpi volumio[849]: info: MyVolumio login type: Token Jun 04 15:00:23 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 04 15:00:23 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 04 15:00:24 volumio-rpi volumio[849]: info: Starting Streaming Service Transparent Proxy Jun 04 15:00:24 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 04 15:00:24 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 04 15:00:24 volumio-rpi volumio[849]: info: Streaming services startup Jun 04 15:00:24 volumio-rpi volumio[849]: info: Starting Streaming Daemon Jun 04 15:00:24 volumio-rpi sudo[1297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 04 15:00:24 volumio-rpi sudo[1297]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 15:00:24 volumio-rpi sudo[1297]: pam_unix(sudo:session): session closed for user root Jun 04 15:00:24 volumio-rpi volumio[849]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 04 15:00:24 volumio-rpi volumio[849]: error: Cannot start Volumio Streaming Daemon Jun 04 15:00:24 volumio-rpi volumio[849]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 04 15:00:24 volumio-rpi volumio[849]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 04 15:00:25 volumio-rpi volumio[849]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 04 15:00:25 volumio-rpi volumio[849]: STREAMING PROXY: Starting server on port 3245 Jun 04 15:00:25 volumio-rpi volumio[849]: Node JS runtime: 14 Jun 04 15:00:25 volumio-rpi volumio[849]: info: MyVolumio token set successfully Jun 04 15:00:25 volumio-rpi volumio[849]: info: MYVOLUMIO: Adding device Jun 04 15:00:25 volumio-rpi volumio[849]: info: MYVOLUMIO: Evaluating Server Jun 04 15:00:25 volumio-rpi volumio[849]: info: MyVolumio status changed Jun 04 15:00:25 volumio-rpi volumio[849]: info: Streaming services startup Jun 04 15:00:25 volumio-rpi volumio[849]: info: Starting Streaming Daemon Jun 04 15:00:25 volumio-rpi volumio[849]: info: Removing browser output: myVolumio user plan is not superstar Jun 04 15:00:25 volumio-rpi volumio[849]: info: Removing audio output: Jun 04 15:00:25 volumio-rpi volumio[849]: info: Stoppping Tunnel 1 Jun 04 15:00:25 volumio-rpi sudo[1322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 04 15:00:25 volumio-rpi sudo[1322]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 15:00:25 volumio-rpi sudo[1322]: pam_unix(sudo:session): session closed for user root Jun 04 15:00:25 volumio-rpi sudo[1325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 04 15:00:25 volumio-rpi sudo[1325]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 15:00:25 volumio-rpi volumio[849]: error: Cannot start Volumio Streaming Daemon Jun 04 15:00:25 volumio-rpi volumio[849]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 04 15:00:25 volumio-rpi volumio[849]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 04 15:00:25 volumio-rpi sudo[1325]: pam_unix(sudo:session): session closed for user root Jun 04 15:00:25 volumio-rpi volumio[849]: info: Remote SSH Stopped Jun 04 15:00:26 volumio-rpi volumio[849]: info: Setting Geolocation for MyVolumio to us1 Jun 04 15:00:26 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 15:00:27 volumio-rpi volumio[849]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 04 15:00:27 volumio-rpi volumio[849]: info: Updating MyVolumio device info Jun 04 15:00:27 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 15:00:28 volumio-rpi volumio[849]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 04 15:00:29 volumio-rpi systemd[1]: systemd-fsckd.service: Succeeded. Jun 04 15:00:31 volumio-rpi volumio[849]: info: MYVOLUMIO: Adding device Jun 04 15:00:31 volumio-rpi volumio[849]: info: MYVOLUMIO: Evaluating Server Jun 04 15:00:31 volumio-rpi systemd[1]: systemd-hostnamed.service: Succeeded. Jun 04 15:00:31 volumio-rpi volumio[849]: info: Setting Geolocation for MyVolumio to us1 Jun 04 15:00:31 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 15:00:31 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 15:00:31 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 15:00:32 volumio-rpi volumio[849]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 04 15:00:32 volumio-rpi volumio[849]: info: Updating MyVolumio device info Jun 04 15:00:32 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 15:00:32 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 15:00:32 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 15:00:33 volumio-rpi volumio[849]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 04 15:00:38 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 04 15:00:38 volumio-rpi volumio[849]: 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 04 15:00:38 volumio-rpi volumio[849]: info: CoreCommandRouter::volumioGetState Jun 04 15:00:38 volumio-rpi volumio[849]: info: CorePlayQueue::getTrack 0 Jun 04 15:00:41 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 15:00:41 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 15:00:41 volumio-rpi volumio[849]: info: Discovery: Getting this device information Jun 04 15:00:41 volumio-rpi volumio[849]: info: CoreCommandRouter::volumioGetState Jun 04 15:00:41 volumio-rpi volumio[849]: info: CorePlayQueue::getTrack 0 Jun 04 15:00:41 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 15:00:43 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 04 15:00:43 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 15:00:43 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 04 15:00:43 volumio-rpi volumio-remote-updater[592]: No test mode Jun 04 15:00:43 volumio-rpi volumio-remote-updater[592]: No alpha test mode Jun 04 15:00:43 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 04 15:00:43 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:00:43 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:00:43 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 04 15:00:43 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 04 15:00:43 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 04 15:00:43 volumio-rpi volumio[849]: info: CoreCommandRouter::volumioGetBrowseSources Jun 04 15:00:43 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 04 15:00:43 volumio-rpi volumio[849]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 04 15:00:43 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 04 15:00:44 volumio-rpi volumio[849]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 04 15:00:44 volumio-rpi volumio[849]: info: Completed starting MyVolumio Plugin Jun 04 15:00:44 volumio-rpi volumio[849]: [Metrics] CommandRouter: 39s 899.46ms Jun 04 15:00:44 volumio-rpi volumio[849]: info: CoreCommandRouter::volumiosetStartupVolume Jun 04 15:00:44 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:00:44 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 15:00:44 volumio-rpi volumio[849]: info: CoreCommandRouter::Close All Modals sent Jun 04 15:00:44 volumio-rpi volumio[849]: info: CoreCommandRouter::Close All Modals sent Jun 04 15:00:45 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 04 15:00:45 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 04 15:00:45 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 04 15:00:46 volumio-rpi systemd[1]: systemd-timedated.service: Succeeded. Jun 04 15:00:51 volumio-rpi volumio[849]: info: BOOT COMPLETED Jun 04 15:00:55 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 04 15:00:55 volumio-rpi volumio[849]: info: CURURI: music-library Jun 04 15:00:55 volumio-rpi volumio[849]: info: Preload queue cleared Jun 04 15:01:00 volumio-rpi volumiologrotate[593]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Jun 04 15:01:00 volumio-rpi volumiologrotate[593]: ls: cannot access 'RPI': No such file or directory Jun 04 15:01:03 volumio-rpi volumio[849]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 04 15:01:03 volumio-rpi volumio[849]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 04 15:01:03 volumio-rpi volumio[849]: info: Retrieving Cloud Streaming UI Jun 04 15:01:03 volumio-rpi volumio[849]: info: Getting Tidal Cloud Configuration Jun 04 15:01:03 volumio-rpi volumio[849]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 04 15:01:03 volumio-rpi volumio[849]: info: Getting Qobuz Cloud Configuration Jun 04 15:01:03 volumio-rpi volumio[849]: info: Asking plugin for UI Config Jun 04 15:01:03 volumio-rpi volumio[849]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 04 15:01:03 volumio-rpi volumio[849]: info: Getting Spotify Cloud Configuration Jun 04 15:01:03 volumio-rpi volumio[849]: info: Asking plugin for UI Config Jun 04 15:01:03 volumio-rpi volumio[849]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 04 15:01:03 volumio-rpi volumio[849]: info: Saving Spotify Acccount Jun 04 15:01:03 volumio-rpi volumio[849]: info: Got Tidal Cloud Configuration Jun 04 15:01:03 volumio-rpi volumio[849]: info: Got it Jun 04 15:01:03 volumio-rpi volumio[849]: info: Got it Jun 04 15:01:03 volumio-rpi volumio[849]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 04 15:01:03 volumio-rpi volumio[849]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 04 15:01:03 volumio-rpi volumio[849]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jun 04 15:01:03 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 04 15:01:03 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 04 15:01:03 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 04 15:01:03 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 04 15:01:03 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 15:01:03 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 15:01:03 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 15:01:03 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 15:01:03 volumio-rpi volumio[849]: info: CoreCommandRouter::volumioGetBrowseSources Jun 04 15:01:03 volumio-rpi volumio[849]: info: CoreCommandRouter::volumioGetBrowseSources Jun 04 15:01:03 volumio-rpi volumio[849]: info: CoreCommandRouter::volumioGetBrowseSources Jun 04 15:01:03 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 04 15:01:03 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 04 15:01:06 volumio-rpi volumio[849]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jun 04 15:01:06 volumio-rpi sudo[1477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/Music Jun 04 15:01:06 volumio-rpi sudo[1477]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 15:01:06 volumio-rpi sudo[1477]: pam_unix(sudo:session): session closed for user root Jun 04 15:01:06 volumio-rpi volumio[849]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 04 15:01:06 volumio-rpi volumio[849]: Error: EBUSY: resource busy or locked, rmdir '/mnt/NAS/Music' Jun 04 15:01:06 volumio-rpi volumio[849]: at Object.rmdirSync (fs.js:890:10) Jun 04 15:01:06 volumio-rpi volumio[849]: at /volumio/node_modules/linux-mountutils/mountutils.js:178:12 Jun 04 15:01:06 volumio-rpi volumio[849]: at ChildProcess.exithandler (child_process.js:299:7) Jun 04 15:01:06 volumio-rpi volumio[849]: at ChildProcess.emit (events.js:315:20) Jun 04 15:01:06 volumio-rpi volumio[849]: at maybeClose (internal/child_process.js:1048:16) Jun 04 15:01:06 volumio-rpi volumio[849]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) { Jun 04 15:01:06 volumio-rpi volumio[849]: errno: -16, Jun 04 15:01:06 volumio-rpi volumio[849]: syscall: 'rmdir', Jun 04 15:01:06 volumio-rpi volumio[849]: code: 'EBUSY', Jun 04 15:01:06 volumio-rpi volumio[849]: path: '/mnt/NAS/Music' Jun 04 15:01:06 volumio-rpi volumio[849]: } Jun 04 15:01:06 volumio-rpi volumio[849]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 04 15:01:07 volumio-rpi sudo[1489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-04 15:00 Jun 04 15:01:07 volumio-rpi sudo[1489]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 15:01:07 volumio-rpi sudo[1489]: pam_unix(sudo:session): session closed for user root Jun 04 15:01:07 volumio-rpi volumio-remote-updater[592]: [2025-06-04 15:01:07] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Jun 04 15:01:07 volumio-rpi volumio-remote-updater[592]: [2025-06-04 15:01:07] [error] handle_read_frame error: websocketpp.transport:2 (Underlying Transport Error) Jun 04 15:01:07 volumio-rpi volumio-remote-updater[592]: [2025-06-04 15:01:07] [disconnect] Disconnect close local:[1006,Underlying Transport Error] remote:[1006] Jun 04 15:01:07 volumio-rpi systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 04 15:01:07 volumio-rpi systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 04 15:01:07 volumio-rpi systemd[1]: Started dynamicswap service. Jun 04 15:01:07 volumio-rpi systemd[1]: dynamicswap.service: Succeeded. Jun 04 15:01:07 volumio-rpi systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 04 15:01:07 volumio-rpi systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jun 04 15:01:07 volumio-rpi systemd[1]: Started dynamicswap service. Jun 04 15:01:07 volumio-rpi systemd[1]: Stopped Volumio Backend Module. Jun 04 15:01:07 volumio-rpi systemd[1]: Started Volumio Backend Module. Jun 04 15:01:07 volumio-rpi systemd[1]: dynamicswap.service: Succeeded. Jun 04 15:01:08 volumio-rpi ntpd[811]: Soliciting pool server 23.95.49.216 Jun 04 15:01:08 volumio-rpi volumio[1503]: info: ------------------------------------------- Jun 04 15:01:08 volumio-rpi volumio[1503]: info: ----- Volumio3 ---- Jun 04 15:01:08 volumio-rpi volumio[1503]: info: ------------------------------------------- Jun 04 15:01:08 volumio-rpi volumio[1503]: info: ----- System startup ---- Jun 04 15:01:08 volumio-rpi volumio[1503]: info: ------------------------------------------- Jun 04 15:01:08 volumio-rpi ntpd[811]: Soliciting pool server 104.167.215.195 Jun 04 15:01:09 volumio-rpi ntpd[811]: Soliciting pool server 23.150.41.123 Jun 04 15:01:09 volumio-rpi ntpd[811]: Soliciting pool server 199.68.201.234 Jun 04 15:01:09 volumio-rpi volumio[1503]: info: MYVOLUMIO Environment detected Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Plugin folders cleanup Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Scanning into folder /volumio/app/plugins/ Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Scanning category audio_interface Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Scanning category miscellanea Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Scanning category music_service Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Scanning category plugins.json Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Scanning category system_controller Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Scanning category user_interface Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Scanning into folder /data/plugins/ Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Scanning category music_service Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Scanning category user_interface Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Plugin folders cleanup completed Jun 04 15:01:09 volumio-rpi volumio[1503]: info: ------------------------------------------- Jun 04 15:01:09 volumio-rpi volumio[1503]: info: ----- Core plugins startup ---- Jun 04 15:01:09 volumio-rpi volumio[1503]: info: ------------------------------------------- Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Loading plugins from folder /volumio/app/plugins/ Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Adding plugin upnp to MyMusic Plugins Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Loading plugins from folder /data/plugins/ Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Loading plugin "system"... Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Loading plugin "appearance"... Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Loading plugin "network"... Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Refreshing Cached IP Addresses Jun 04 15:01:09 volumio-rpi sudo[1529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 04 15:01:09 volumio-rpi sudo[1529]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 15:01:09 volumio-rpi sudo[1529]: pam_unix(sudo:session): session closed for user root Jun 04 15:01:09 volumio-rpi sudo[1531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 04 15:01:09 volumio-rpi sudo[1531]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Loading plugin "services"... Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Loading plugin "alsa_controller"... Jun 04 15:01:09 volumio-rpi sudo[1531]: pam_unix(sudo:session): session closed for user root Jun 04 15:01:09 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Loading plugin "wizard"... Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Loading plugin "networkfs"... Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Cannot mount NAS Music at system boot, trial number 1 ,retrying in 5 seconds Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Cannot mount NAS Music at system boot, trial number 1 ,retrying in 5 seconds Jun 04 15:01:09 volumio-rpi volumio[1503]: info: Starting Udev Watcher for removable devices Jun 04 15:01:09 volumio-rpi ntpd[811]: Soliciting pool server 46.37.96.107 Jun 04 15:01:09 volumio-rpi ntpd[811]: Soliciting pool server 104.152.220.5 Jun 04 15:01:09 volumio-rpi ntpd[811]: Soliciting pool server 198.137.202.56 Jun 04 15:01:10 volumio-rpi volumio[1503]: info: Ignoring mount for partition: boot Jun 04 15:01:10 volumio-rpi volumio[1503]: info: Ignoring mount for partition: volumio Jun 04 15:01:10 volumio-rpi volumio[1503]: info: Ignoring mount for partition: volumio_data Jun 04 15:01:10 volumio-rpi volumio[1503]: info: Mounting Device CRUZER_USB Jun 04 15:01:10 volumio-rpi sudo[1555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/CRUZER_USB -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jun 04 15:01:10 volumio-rpi sudo[1555]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 15:01:10 volumio-rpi sudo[1555]: pam_unix(sudo:session): session closed for user root Jun 04 15:01:10 volumio-rpi volumio[1503]: mount: /media/CRUZER_USB: /dev/sda1 already mounted on /media/CRUZER_USB. Jun 04 15:01:10 volumio-rpi volumio[1503]: error: Failed to mount CRUZER_USB: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/CRUZER_USB" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jun 04 15:01:10 volumio-rpi volumio[1503]: mount: /media/CRUZER_USB: /dev/sda1 already mounted on /media/CRUZER_USB. Jun 04 15:01:10 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 04 15:01:10 volumio-rpi volumio[1503]: info: Loading plugin "volumio_command_line_client"... Jun 04 15:01:10 volumio-rpi volumio[1503]: info: Plugin upnp is not enabled Jun 04 15:01:10 volumio-rpi volumio[1503]: info: Loading plugin "my_music"... Jun 04 15:01:10 volumio-rpi volumio[1503]: info: Loading plugin "mpd"... Jun 04 15:01:10 volumio-rpi volumio[1503]: info: Loading plugin "upnp_browser"... Jun 04 15:01:10 volumio-rpi volumio[1503]: info: Loading plugin "alarm-clock"... Jun 04 15:01:10 volumio-rpi volumio[1503]: info: Loading plugin "airplay_emulation"... Jun 04 15:01:10 volumio-rpi volumio[1503]: info: Starting Shairport Sync Jun 04 15:01:10 volumio-rpi volumio[1503]: info: Loading plugin "last_100"... Jun 04 15:01:10 volumio-rpi volumio[1503]: info: Loading plugin "webradio"... Jun 04 15:01:10 volumio-rpi volumio[1503]: info: Loading plugin "i2s_dacs"... Jun 04 15:01:10 volumio-rpi volumio[1503]: info: I2S DAC not set, start Auto-detection Jun 04 15:01:10 volumio-rpi volumio[1503]: info: Loading plugin "volumiodiscovery"... Jun 04 15:01:10 volumio-rpi volumio[1503]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 04 15:01:10 volumio-rpi volumio[1503]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 04 15:01:10 volumio-rpi node[1503]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 04 15:01:10 volumio-rpi volumio[1503]: *** WARNING *** For more information see Jun 04 15:01:10 volumio-rpi volumio[1503]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 04 15:01:10 volumio-rpi volumio[1503]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 04 15:01:10 volumio-rpi volumio[1503]: *** WARNING *** For more information see Jun 04 15:01:10 volumio-rpi node[1503]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 04 15:01:10 volumio-rpi node[1503]: *** WARNING *** For more information see Jun 04 15:01:10 volumio-rpi node[1503]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 04 15:01:10 volumio-rpi node[1503]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 04 15:01:10 volumio-rpi node[1503]: *** WARNING *** For more information see Jun 04 15:01:10 volumio-rpi volumio[1503]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 04 15:01:10 volumio-rpi volumio[1503]: info: Discovery: Started advertising with name: Volumio Rpi Jun 04 15:01:10 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 04 15:01:10 volumio-rpi volumio[1503]: info: Loading plugin "ytmusic"... Jun 04 15:01:10 volumio-rpi ntpd[811]: Soliciting pool server 141.11.228.173 Jun 04 15:01:10 volumio-rpi ntpd[811]: Soliciting pool server 144.202.66.214 Jun 04 15:01:10 volumio-rpi ntpd[811]: Soliciting pool server 167.248.62.201 Jun 04 15:01:11 volumio-rpi ntpd[811]: Soliciting pool server 141.11.234.198 Jun 04 15:01:11 volumio-rpi ntpd[811]: Soliciting pool server 172.234.37.140 Jun 04 15:01:12 volumio-rpi ntpd[811]: Soliciting pool server 72.30.35.89 Jun 04 15:01:12 volumio-rpi ntpd[811]: Soliciting pool server 23.157.160.168 Jun 04 15:01:12 volumio-rpi volumio[1503]: info: Loading plugin "now_playing"... Jun 04 15:01:12 volumio-rpi volumio-remote-updater[592]: [2025-06-04 15:01:12] [connect] Successful connection Jun 04 15:01:12 volumio-rpi volumio[1503]: info: Loading plugin "outputs"... Jun 04 15:01:12 volumio-rpi volumio[1503]: info: Loading plugin "albumart"... Jun 04 15:01:12 volumio-rpi ntpd[811]: Soliciting pool server 2600:3c03::f03c:91ff:fedf:1e98 Jun 04 15:01:12 volumio-rpi ntpd[811]: Soliciting pool server 51.81.209.232 Jun 04 15:01:13 volumio-rpi volumio[1503]: info: Plugin example_plugin is not enabled Jun 04 15:01:13 volumio-rpi volumio[1503]: info: Loading plugin "inputs"... Jun 04 15:01:13 volumio-rpi volumio[1503]: info: Loading plugin "updater_comm"... Jun 04 15:01:13 volumio-rpi ntpd[811]: Soliciting pool server 23.150.41.122 Jun 04 15:01:13 volumio-rpi volumio[1503]: info: Plugin mpdemulation is not enabled Jun 04 15:01:13 volumio-rpi volumio[1503]: info: Loading plugin "rest_api"... Jun 04 15:01:13 volumio-rpi volumio[1503]: info: Loading plugin "websocket"... Jun 04 15:01:13 volumio-rpi volumio[1503]: info: Starting Socket.io Server version 2.3.0 Jun 04 15:01:13 volumio-rpi volumio[1503]: info: Loading plugin "motherearthradio"... Jun 04 15:01:13 volumio-rpi volumio[1503]: Forking 3 albumart workers Jun 04 15:01:13 volumio-rpi volumio[1503]: info: Applying required configuration parameters for plugin motherearthradio Jun 04 15:01:13 volumio-rpi volumio[1503]: info: [1749063673257] [MotherEarth] API delay: 5 Jun 04 15:01:13 volumio-rpi volumio[1503]: info: Loading plugin "radio_paradise"... Jun 04 15:01:13 volumio-rpi volumio[1503]: info: Applying required configuration parameters for plugin radio_paradise Jun 04 15:01:13 volumio-rpi volumio[1503]: info: [1749063673500] [RadioParadise] API delay: 5 Jun 04 15:01:13 volumio-rpi volumio[1503]: info: Loading i18n strings for locale en Jun 04 15:01:13 volumio-rpi volumio[1503]: Updating browse sources language Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CoreCommandRouter::initPlayerControls Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 15:01:13 volumio-rpi volumio[1503]: Express server listening on port 3000 Jun 04 15:01:13 volumio-rpi volumio[1503]: [Metrics] WebUI: 5s 158.35ms Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CoreStateMachine::resetVolumioState Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CoreStateMachine::getcurrentVolume Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CoreCommandRouter::volumioRetrievevolume Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CoreStateMachine::pushState Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CorePlayQueue::getTrack 0 Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CoreCommandRouter::volumioPushState Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 15:01:13 volumio-rpi volumio[1503]: info: Volumio Network Manager: Network status updated: 1 Jun 04 15:01:13 volumio-rpi volumio[1503]: verbose: New Socket.io Connection to 10.0.0.88 from 10.0.0.186 UA: Mozilla/5.0 (Linux; Android 15; Pixel 9 Pro XL Build/BP1A.250505.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.127 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jun 04 15:01:13 volumio-rpi volumio[1503]: verbose: New Socket.io Connection to 10.0.0.88 from 10.0.0.186 UA: Mozilla/5.0 (Linux; Android 15; Pixel 9 Pro XL Build/BP1A.250505.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.127 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 04 15:01:13 volumio-rpi volumio[1503]: verbose: New Socket.io Connection to 10.0.0.88:3000 from 10.0.0.186 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Jun 04 15:01:13 volumio-rpi volumio-remote-updater[592]: [2025-06-04 15:01:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749063672 101 Jun 04 15:01:13 volumio-rpi volumio[1503]: Starting albumart workers Jun 04 15:01:13 volumio-rpi volumio[1503]: 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: 4 Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 15:01:13 volumio-rpi volumio[1503]: info: Discovery: Getting this device information Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CoreCommandRouter::volumioGetState Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CorePlayQueue::getTrack 0 Jun 04 15:01:13 volumio-rpi volumio[1503]: Starting albumart workers Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 15:01:13 volumio-rpi volumio[1503]: Starting albumart workers Jun 04 15:01:13 volumio-rpi volumio[1503]: info: Reloading queue from file Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CoreStateMachine::setRepeat null single undefined Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CoreStateMachine::pushState Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CorePlayQueue::getTrack 0 Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CoreCommandRouter::volumioPushState Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CoreStateMachine::setRandom null Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CoreStateMachine::pushState Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CorePlayQueue::getTrack 0 Jun 04 15:01:13 volumio-rpi volumio[1503]: info: CoreCommandRouter::volumioPushState Jun 04 15:01:13 volumio-rpi volumio[1503]: info: Setting Device type: Raspberry PI Jun 04 15:01:13 volumio-rpi volumio[1503]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 04 15:01:13 volumio-rpi volumio[1503]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Jun 04 15:01:13 volumio-rpi volumio[1503]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 04 15:01:13 volumio-rpi volumio[1503]: verbose: New Socket.io Connection to 10.0.0.88:3000 from 10.0.0.186 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jun 04 15:01:13 volumio-rpi ntpd[811]: Soliciting pool server 217.180.209.214 Jun 04 15:01:13 volumio-rpi ntpd[811]: Soliciting pool server 44.190.5.123 Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::volumioGetState Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CorePlayQueue::getTrack 0 Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 04 15:01:14 volumio-rpi volumio[1503]: info: Completed loading Core Plugins Jun 04 15:01:14 volumio-rpi volumio[1503]: info: Preparing to generate the ALSA configuration file Jun 04 15:01:14 volumio-rpi volumio[1503]: info: Asound.conf file unchanged, so no further update is needed Jun 04 15:01:14 volumio-rpi volumio[1503]: info: Output device has changed, restarting MPD Jun 04 15:01:14 volumio-rpi volumio[1503]: info: Output device has changed, restarting Shairport Sync Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 15:01:14 volumio-rpi sudo[1596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 04 15:01:14 volumio-rpi sudo[1596]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 15:01:14 volumio-rpi sudo[1598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 04 15:01:14 volumio-rpi sudo[1598]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 15:01:14 volumio-rpi sudo[1596]: pam_unix(sudo:session): session closed for user root Jun 04 15:01:14 volumio-rpi systemd[1]: Stopping Music Player Daemon... Jun 04 15:01:14 volumio-rpi volumio[1503]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 04 15:01:14 volumio-rpi volumio[1503]: info: ___________ START PLUGINS ___________ Jun 04 15:01:14 volumio-rpi volumio[1503]: info: ControllerMpd::onStart: Initializing MPD Jun 04 15:01:14 volumio-rpi volumio[1503]: info: Creating MPD Configuration file Jun 04 15:01:14 volumio-rpi systemd[1]: mpd.service: Succeeded. Jun 04 15:01:14 volumio-rpi systemd[1]: Stopped Music Player Daemon. Jun 04 15:01:14 volumio-rpi sudo[1606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 04 15:01:14 volumio-rpi volumio[1503]: info: [1749063674253] CoreMusicLibrary::Adding element Media Servers Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:01:14 volumio-rpi systemd[1]: Starting Music Player Daemon... Jun 04 15:01:14 volumio-rpi sudo[1606]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 15:01:14 volumio-rpi sudo[1606]: pam_unix(sudo:session): session closed for user root Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 15:01:14 volumio-rpi sudo[1609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 04 15:01:14 volumio-rpi sudo[1609]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 15:01:14 volumio-rpi volumio[1503]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 15:01:14 volumio-rpi systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 04 15:01:14 volumio-rpi systemd[1]: mpd.service: Succeeded. Jun 04 15:01:14 volumio-rpi systemd[1]: Stopped Music Player Daemon. Jun 04 15:01:14 volumio-rpi systemd[1]: Starting Music Player Daemon... Jun 04 15:01:14 volumio-rpi volumio[1503]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 04 15:01:14 volumio-rpi volumio[1503]: info: [1749063674401] CoreMusicLibrary::Adding element Last_100 Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 04 15:01:14 volumio-rpi volumio[1503]: info: [1749063674404] CoreMusicLibrary::Adding element Webradio Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 04 15:01:14 volumio-rpi volumio[1503]: info: Initializing BBC Radios Jun 04 15:01:14 volumio-rpi sudo[1619]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 04 15:01:14 volumio-rpi sudo[1619]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 15:01:14 volumio-rpi sudo[1619]: pam_unix(sudo:session): session closed for user root Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 04 15:01:14 volumio-rpi volumio[1503]: info: [1749063674513] CoreMusicLibrary::Adding element YouTube Music Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 15:01:14 volumio-rpi volumio[1503]: Cannot find translation for source YouTube Music Jun 04 15:01:14 volumio-rpi volumio[1503]: info: [now-playing] ConfigUpdater: config is up to date. Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 04 15:01:14 volumio-rpi volumio[1503]: info: [1749063674534] CoreMusicLibrary::Adding element Mother Earth Radio Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 15:01:14 volumio-rpi volumio[1503]: Cannot find translation for source YouTube Music Jun 04 15:01:14 volumio-rpi volumio[1503]: Cannot find translation for source Mother Earth Radio Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 04 15:01:14 volumio-rpi volumio[1503]: info: [1749063674538] CoreMusicLibrary::Adding element Radio Paradise Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 15:01:14 volumio-rpi volumio[1503]: Cannot find translation for source YouTube Music Jun 04 15:01:14 volumio-rpi volumio[1503]: Cannot find translation for source Mother Earth Radio Jun 04 15:01:14 volumio-rpi volumio[1503]: Cannot find translation for source Radio Paradise Jun 04 15:01:14 volumio-rpi volumio[1503]: info: Volumio Calling Home Jun 04 15:01:14 volumio-rpi volumio[1503]: info: [now-playing] App is listening on port 4004. Jun 04 15:01:14 volumio-rpi volumio[1503]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jun 04 15:01:14 volumio-rpi volumio[1503]: info: Discovery: adding 9fe67853-389d-423b-ab4c-e593acd1fbf8 Jun 04 15:01:14 volumio-rpi volumio[1503]: info: Discovery: Found device Volumio Rpi Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::volumioGetState Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CorePlayQueue::getTrack 0 Jun 04 15:01:14 volumio-rpi volumio[1503]: info: MPD Permissions set Jun 04 15:01:14 volumio-rpi volumio[1503]: info: MPD Permissions set Jun 04 15:01:14 volumio-rpi volumio[1503]: 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 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 15:01:14 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 15:01:14 volumio-rpi volumio[1503]: info: Starting Shairport Sync Jun 04 15:01:14 volumio-rpi volumio[1503]: info: Starting Shairport Sync Jun 04 15:01:14 volumio-rpi sudo[1666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 04 15:01:14 volumio-rpi sudo[1666]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 15:01:14 volumio-rpi volumio[1503]: info: Starting Shairport Sync Jun 04 15:01:15 volumio-rpi sudo[1669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 04 15:01:15 volumio-rpi sudo[1669]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 15:01:15 volumio-rpi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 04 15:01:15 volumio-rpi sudo[1672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 04 15:01:15 volumio-rpi systemd[1]: shairport-sync.service: Succeeded. Jun 04 15:01:15 volumio-rpi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 04 15:01:15 volumio-rpi sudo[1672]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 15:01:15 volumio-rpi volumio[1503]: info: CoreCommandRouter::volumioGetState Jun 04 15:01:15 volumio-rpi volumio[1503]: info: CorePlayQueue::getTrack 0 Jun 04 15:01:15 volumio-rpi volumio[1503]: info: Cannot mount NAS Music at system boot, trial number 2 ,retrying in 5 seconds Jun 04 15:01:15 volumio-rpi volumio[1503]: info: Cannot mount NAS Music at system boot, trial number 2 ,retrying in 5 seconds Jun 04 15:01:16 volumio-rpi ntpd[811]: receive: Unexpected origin timestamp 0xebeb167a.fe9b1c20 does not match aorg 0000000000.00000000 from server@44.190.5.123 xmt 0xebeb167c.8436fe54 Jun 04 15:01:16 volumio-rpi ntpd[811]: receive: Unexpected origin timestamp 0xebeb167a.fea27ccf does not match aorg 0000000000.00000000 from server@104.152.220.5 xmt 0xebeb167c.860a7c9e Jun 04 15:01:16 volumio-rpi volumio[1503]: info: Volumio called home Jun 04 15:01:16 volumio-rpi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 04 15:01:16 volumio-rpi sudo[1666]: pam_unix(sudo:session): session closed for user root Jun 04 15:01:16 volumio-rpi volumio[1503]: info: Shairport-Sync Started Jun 04 15:01:16 volumio-rpi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 04 15:01:16 volumio-rpi volumio[1503]: Error adding Membership: Error: addMembership EINVAL Jun 04 15:01:16 volumio-rpi systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 04 15:01:16 volumio-rpi systemd[1]: shairport-sync.service: Succeeded. Jun 04 15:01:16 volumio-rpi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 04 15:01:16 volumio-rpi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 04 15:01:16 volumio-rpi sudo[1669]: pam_unix(sudo:session): session closed for user root Jun 04 15:01:16 volumio-rpi sudo[1672]: pam_unix(sudo:session): session closed for user root Jun 04 15:01:16 volumio-rpi volumio[1503]: info: Shairport-Sync Started Jun 04 15:01:16 volumio-rpi volumio[1503]: info: Shairport-Sync Started Jun 04 15:01:16 volumio-rpi mpd[1645]: Jun 04 15:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 04 15:01:16 volumio-rpi systemd[1]: Started Music Player Daemon. Jun 04 15:01:16 volumio-rpi sudo[1609]: pam_unix(sudo:session): session closed for user root Jun 04 15:01:16 volumio-rpi sudo[1598]: pam_unix(sudo:session): session closed for user root Jun 04 15:01:16 volumio-rpi volumio[1503]: info: Completed starting Core Plugins Jun 04 15:01:16 volumio-rpi volumio[1503]: info: ------------------------------------------- Jun 04 15:01:16 volumio-rpi volumio[1503]: info: ----- MyVolumio plugins startup ---- Jun 04 15:01:16 volumio-rpi volumio[1503]: info: ------------------------------------------- Jun 04 15:01:16 volumio-rpi volumio[1503]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 04 15:01:16 volumio-rpi volumio[1503]: error: MPD error: The expression evaluated to a falsy value: Jun 04 15:01:16 volumio-rpi volumio[1503]: assert.ok(self.idling) Jun 04 15:01:16 volumio-rpi volumio[1503]: error: The expression evaluated to a falsy value: Jun 04 15:01:16 volumio-rpi volumio[1503]: assert.ok(self.idling) Jun 04 15:01:16 volumio-rpi volumio[1503]: info: MPD running with PID1645 Jun 04 15:01:16 volumio-rpi volumio[1503]: ,establishing connection Jun 04 15:01:16 volumio-rpi volumio[1503]: error: updateQueue error: null Jun 04 15:01:16 volumio-rpi volumio[1503]: error: updateQueue error: null Jun 04 15:01:17 volumio-rpi volumio[1503]: verbose: New Socket.io Connection to 10.0.0.88 from 10.0.0.186 UA: Mozilla/5.0 (Linux; Android 15; Pixel 9 Pro XL Build/BP1A.250505.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.127 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jun 04 15:01:17 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 04 15:01:17 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 04 15:01:17 volumio-rpi volumio[1503]: info: CoreCommandRouter::volumioGetVisibleSources Jun 04 15:01:17 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 15:01:17 volumio-rpi volumio[1503]: info: CoreCommandRouter::volumioGetState Jun 04 15:01:17 volumio-rpi volumio[1503]: info: CorePlayQueue::getTrack 0 Jun 04 15:01:17 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 04 15:01:17 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 04 15:01:17 volumio-rpi volumio[1503]: info: Received Get System Info Jun 04 15:01:17 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 15:01:17 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 15:01:17 volumio-rpi volumio[1503]: info: Discovery: Getting this device information Jun 04 15:01:17 volumio-rpi volumio[1503]: info: CoreCommandRouter::volumioGetState Jun 04 15:01:17 volumio-rpi volumio[1503]: info: CorePlayQueue::getTrack 0 Jun 04 15:01:17 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 15:01:17 volumio-rpi volumio[1503]: info: CoreCommandRouter::volumioGetState Jun 04 15:01:17 volumio-rpi volumio[1503]: info: CorePlayQueue::getTrack 0 Jun 04 15:01:17 volumio-rpi volumio[1503]: info: Listing playlists Jun 04 15:01:17 volumio-rpi volumio[1503]: info: CoreCommandRouter::volumioGetQueue Jun 04 15:01:17 volumio-rpi volumio[1503]: info: CoreStateMachine::getQueue Jun 04 15:01:17 volumio-rpi volumio[1503]: info: CorePlayQueue::getQueue Jun 04 15:01:17 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 04 15:01:20 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jun 04 15:01:20 volumio-rpi sudo[1683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/Music Jun 04 15:01:20 volumio-rpi sudo[1683]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 15:01:20 volumio-rpi systemd[1]: mnt-NAS-Music.mount: Succeeded. Jun 04 15:01:20 volumio-rpi sudo[1683]: pam_unix(sudo:session): session closed for user root Jun 04 15:01:20 volumio-rpi volumio[1503]: info: Share Music successfully unmounted Jun 04 15:01:21 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 04 15:01:21 volumio-rpi volumio[1503]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 04 15:01:21 volumio-rpi volumio[1503]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 04 15:01:21 volumio-rpi volumio[1503]: TypeError: Cannot read property 'replace' of undefined Jun 04 15:01:21 volumio-rpi volumio[1503]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Jun 04 15:01:21 volumio-rpi volumio[1503]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Jun 04 15:01:21 volumio-rpi volumio[1503]: at listOnTimeout (internal/timers.js:554:17) Jun 04 15:01:21 volumio-rpi volumio[1503]: at processTimers (internal/timers.js:497:7) Jun 04 15:01:21 volumio-rpi volumio[1503]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 04 15:01:21 volumio-rpi sudo[1712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-04 15:00 Jun 04 15:01:21 volumio-rpi sudo[1712]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"