-- Logs begin at Thu 2019-02-14 04:11:58 CST, end at Sun 2025-04-20 21:33:39 CDT. -- Apr 20 21:32:00 volumiocr volumio-remote-updater[843]: [2025-04-20 21:32:00] [connect] Successful connection Apr 20 21:32:00 volumiocr volumio-remote-updater[843]: [2025-04-20 21:32:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745202720 101 Apr 20 21:32:00 volumiocr volumio[1384]: 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: 7 Apr 20 21:32:01 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 20 21:32:01 volumiocr volumio[1384]: verbose: New Socket.io Connection to 192.168.1.227:3000 from 192.168.1.97 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 20 21:32:02 volumiocr volumio[1384]: verbose: New Socket.io Connection to 192.168.1.227:3000 from 192.168.1.97 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 20 21:32:02 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Apr 20 21:32:02 volumiocr volumio[1384]: info: CoreCommandRouter::volumioGetState Apr 20 21:32:02 volumiocr volumio[1384]: info: CorePlayQueue::getTrack 0 Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 20 21:32:02 volumiocr volumio[1384]: info: Adding plugin bluetooth to MyMusic Plugins Apr 20 21:32:02 volumiocr volumio[1384]: info: Adding plugin multiroom to MyMusic Plugins Apr 20 21:32:02 volumiocr volumio[1384]: info: Adding plugin metavolumio to MyMusic Plugins Apr 20 21:32:02 volumiocr volumio[1384]: info: Adding plugin cd_controller to MyMusic Plugins Apr 20 21:32:02 volumiocr volumio[1384]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 20 21:32:02 volumiocr volumio[1384]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 20 21:32:02 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 21:32:02 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 21:32:02 volumiocr volumio[1384]: info: Starting MyVolumio Remote Streaming Endpoints Apr 20 21:32:02 volumiocr volumio[1384]: info: MyVolumio login type: Token Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 20 21:32:02 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 20 21:32:03 volumiocr systemd[1]: systemd-timedated.service: Succeeded. Apr 20 21:32:03 volumiocr volumio[1384]: info: Starting Streaming Service Transparent Proxy Apr 20 21:32:03 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 20 21:32:03 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 20 21:32:03 volumiocr volumio[1384]: info: Streaming services startup Apr 20 21:32:03 volumiocr volumio[1384]: info: Starting Streaming Daemon Apr 20 21:32:03 volumiocr sudo[1730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 20 21:32:03 volumiocr sudo[1730]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 21:32:03 volumiocr sudo[1730]: pam_unix(sudo:session): session closed for user root Apr 20 21:32:03 volumiocr volumio[1384]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 20 21:32:03 volumiocr volumio[1384]: error: Cannot start Volumio Streaming Daemon Apr 20 21:32:03 volumiocr volumio[1384]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 20 21:32:03 volumiocr volumio[1384]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 20 21:32:03 volumiocr volumio[1384]: STREAMING PROXY: Starting server on port 3245 Apr 20 21:32:03 volumiocr volumio[1384]: Node JS runtime: 14 Apr 20 21:32:03 volumiocr volumio[1384]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 20 21:32:03 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 20 21:32:04 volumiocr volumio[1705]: ...................................................................................................................................................++++ Apr 20 21:32:04 volumiocr volumio[1705]: e is 65537 (0x010001) Apr 20 21:32:04 volumiocr volumio[1705]: writing RSA key Apr 20 21:32:04 volumiocr volumio[1384]: info: MyVolumio token set successfully Apr 20 21:32:04 volumiocr volumio[1384]: info: MYVOLUMIO: Adding device Apr 20 21:32:04 volumiocr volumio[1384]: info: MYVOLUMIO: Evaluating Server Apr 20 21:32:04 volumiocr volumio[1384]: info: MyVolumio status changed Apr 20 21:32:04 volumiocr volumio[1384]: info: Streaming services startup Apr 20 21:32:04 volumiocr volumio[1384]: info: Starting Streaming Daemon Apr 20 21:32:04 volumiocr volumio[1384]: info: Removing browser output: myVolumio user plan is not superstar Apr 20 21:32:04 volumiocr volumio[1384]: info: Removing audio output: Apr 20 21:32:04 volumiocr volumio[1384]: info: Stoppping Tunnel 1 Apr 20 21:32:04 volumiocr sudo[1778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 20 21:32:04 volumiocr sudo[1778]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 21:32:04 volumiocr sudo[1778]: pam_unix(sudo:session): session closed for user root Apr 20 21:32:04 volumiocr sudo[1781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 20 21:32:04 volumiocr sudo[1781]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 21:32:04 volumiocr sudo[1781]: pam_unix(sudo:session): session closed for user root Apr 20 21:32:04 volumiocr volumio[1384]: info: Remote SSH Stopped Apr 20 21:32:04 volumiocr volumio[1384]: error: Cannot start Volumio Streaming Daemon Apr 20 21:32:04 volumiocr volumio[1384]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 20 21:32:04 volumiocr volumio[1384]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 20 21:32:05 volumiocr volumio[1384]: info: Setting Geolocation for MyVolumio to us3 Apr 20 21:32:05 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 21:32:05 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 21:32:05 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 21:32:05 volumiocr volumio[1384]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 20 21:32:06 volumiocr volumio[1384]: info: Updating MyVolumio device info Apr 20 21:32:06 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 21:32:06 volumiocr volumio[1384]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 20 21:32:07 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 20 21:32:09 volumiocr volumio[1384]: info: MYVOLUMIO: Adding device Apr 20 21:32:09 volumiocr volumio[1384]: info: MYVOLUMIO: Evaluating Server Apr 20 21:32:09 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 21:32:09 volumiocr volumio[1384]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 20 21:32:09 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 20 21:32:09 volumiocr volumio[1384]: info: Received Get System Version Apr 20 21:32:09 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 21:32:09 volumiocr volumio[1384]: info: Received Get System Info Apr 20 21:32:09 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 20 21:32:09 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 20 21:32:09 volumiocr volumio[1384]: info: Discovery: Getting this device information Apr 20 21:32:09 volumiocr volumio[1384]: info: CoreCommandRouter::volumioGetState Apr 20 21:32:09 volumiocr volumio[1384]: info: CorePlayQueue::getTrack 0 Apr 20 21:32:09 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 20 21:32:09 volumiocr volumio[1384]: info: Setting Geolocation for MyVolumio to us3 Apr 20 21:32:09 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 21:32:09 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 21:32:09 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 21:32:10 volumiocr volumio[1384]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 20 21:32:10 volumiocr volumio[1384]: info: Updating MyVolumio device info Apr 20 21:32:10 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 21:32:11 volumiocr volumio[1384]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 20 21:32:11 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 20 21:32:15 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 20 21:32:17 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 21:32:17 volumiocr volumio[1384]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Apr 20 21:32:17 volumiocr volumio[1384]: info: CoreCommandRouter::volumioGetState Apr 20 21:32:17 volumiocr volumio[1384]: info: CorePlayQueue::getTrack 0 Apr 20 21:32:19 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 20 21:32:21 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 21:32:21 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 21:32:21 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 20 21:32:21 volumiocr volumio-remote-updater[843]: No test mode Apr 20 21:32:21 volumiocr volumio-remote-updater[843]: No alpha test mode Apr 20 21:32:21 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 20 21:32:21 volumiocr volumio[1384]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 20 21:32:21 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 20 21:32:22 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 21:32:22 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 21:32:22 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 20 21:32:22 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 20 21:32:22 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 20 21:32:22 volumiocr volumio[1384]: info: CoreCommandRouter::volumioGetBrowseSources Apr 20 21:32:22 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 21:32:23 volumiocr volumio[1384]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 20 21:32:23 volumiocr volumio[1384]: info: Completed starting MyVolumio Plugin Apr 20 21:32:23 volumiocr volumio[1384]: [Metrics] CommandRouter: 37s 802.52ms Apr 20 21:32:23 volumiocr volumio[1384]: info: CoreCommandRouter::volumiosetStartupVolume Apr 20 21:32:23 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 21:32:23 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 21:32:23 volumiocr volumio[1384]: info: CoreCommandRouter::Close All Modals sent Apr 20 21:32:23 volumiocr volumio[1384]: info: CoreCommandRouter::Close All Modals sent Apr 20 21:32:23 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 20 21:32:24 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 20 21:32:24 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 20 21:32:24 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 20 21:32:27 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 20 21:32:28 volumiocr ntpd[963]: Soliciting pool server 208.67.72.43 Apr 20 21:32:28 volumiocr ntpd[963]: Soliciting pool server 23.141.40.124 Apr 20 21:32:29 volumiocr ntpd[963]: Soliciting pool server 23.186.168.128 Apr 20 21:32:30 volumiocr volumio[1384]: info: BOOT COMPLETED Apr 20 21:32:30 volumiocr ntpd[963]: Soliciting pool server 104.167.215.195 Apr 20 21:32:31 volumiocr ntpd[963]: Soliciting pool server 198.137.202.32 Apr 20 21:32:31 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 20 21:32:31 volumiocr ntpd[963]: Soliciting pool server 157.245.125.229 Apr 20 21:32:32 volumiocr ntpd[963]: Soliciting pool server 129.146.193.200 Apr 20 21:32:32 volumiocr ntpd[963]: Soliciting pool server 158.51.99.19 Apr 20 21:32:32 volumiocr ntpd[963]: Soliciting pool server 104.207.148.118 Apr 20 21:32:32 volumiocr ntpd[963]: Soliciting pool server 193.29.63.226 Apr 20 21:32:33 volumiocr ntpd[963]: Soliciting pool server 144.202.41.38 Apr 20 21:32:33 volumiocr ntpd[963]: Soliciting pool server 104.233.211.205 Apr 20 21:32:33 volumiocr ntpd[963]: Soliciting pool server 23.186.168.123 Apr 20 21:32:34 volumiocr ntpd[963]: Soliciting pool server 104.131.155.175 Apr 20 21:32:34 volumiocr ntpd[963]: Soliciting pool server 2600:3c02:e000:bc::123:0 Apr 20 21:32:34 volumiocr ntpd[963]: Soliciting pool server 99.28.14.242 Apr 20 21:32:35 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 20 21:32:35 volumiocr ntpd[963]: Soliciting pool server 23.150.41.123 Apr 20 21:32:37 volumiocr ntpd[963]: receive: Unexpected origin timestamp 0xebb02cc3.f040271a does not match aorg 0000000000.00000000 from server@23.186.168.128 xmt 0xebb02cc4.f4924310 Apr 20 21:32:53 volumiocr volumio[1384]: info: Discovery: Disconnected from remote: 192.168.1.97 Apr 20 21:32:59 volumiocr volumio[1384]: info: Discovery: Remote connection error: 192.168.1.97 Apr 20 21:32:59 volumiocr volumio[1384]: info: Discovery: Remote connection timeout: 192.168.1.97 Apr 20 21:33:03 volumiocr volumio[1384]: info: [squeezelite_mc] 'client' notification received from P5LMS (192.168.1.37); type is 'disconnect' Apr 20 21:33:03 volumiocr volumio[1384]: info: [squeezelite_mc] 'client' notification received from P5LMS (192.168.1.37); type is 'disconnect' Apr 20 21:33:05 volumiocr volumio[1384]: info: Discovery: Remote connection error: 192.168.1.97 Apr 20 21:33:05 volumiocr volumio[1384]: info: Discovery: Remote connection timeout: 192.168.1.97 Apr 20 21:33:14 volumiocr volumio[1384]: info: Discovery: Remote connection error: 192.168.1.97 Apr 20 21:33:14 volumiocr volumio[1384]: info: Discovery: Remote connection timeout: 192.168.1.97 Apr 20 21:33:21 volumiocr volumio[1384]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 20 21:33:21 volumiocr volumio[1384]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 20 21:33:21 volumiocr volumio[1384]: info: Retrieving Cloud Streaming UI Apr 20 21:33:21 volumiocr volumio[1384]: info: Getting Tidal Cloud Configuration Apr 20 21:33:21 volumiocr volumio[1384]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 20 21:33:21 volumiocr volumio[1384]: info: Getting Qobuz Cloud Configuration Apr 20 21:33:21 volumiocr volumio[1384]: info: Asking plugin for UI Config Apr 20 21:33:21 volumiocr volumio[1384]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 20 21:33:21 volumiocr volumio[1384]: info: Getting Spotify Cloud Configuration Apr 20 21:33:21 volumiocr volumio[1384]: info: Asking plugin for UI Config Apr 20 21:33:21 volumiocr volumio[1384]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 20 21:33:21 volumiocr volumio[1384]: info: Saving Spotify Acccount Apr 20 21:33:21 volumiocr volumio[1384]: info: Got Tidal Cloud Configuration Apr 20 21:33:21 volumiocr volumio[1384]: info: Got it Apr 20 21:33:21 volumiocr volumio[1384]: info: Got it Apr 20 21:33:21 volumiocr volumio[1384]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 20 21:33:21 volumiocr volumio[1384]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 20 21:33:21 volumiocr volumio[1384]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 20 21:33:21 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 20 21:33:21 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 20 21:33:21 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 20 21:33:21 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 20 21:33:21 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 21:33:21 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 21:33:21 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 21:33:21 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 21:33:21 volumiocr volumio[1384]: info: CoreCommandRouter::volumioGetBrowseSources Apr 20 21:33:21 volumiocr volumio[1384]: info: CoreCommandRouter::volumioGetBrowseSources Apr 20 21:33:21 volumiocr volumio[1384]: info: CoreCommandRouter::volumioGetBrowseSources Apr 20 21:33:21 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 20 21:33:21 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 20 21:33:24 volumiocr volumio[1384]: info: Discovery: Remote connection error: 192.168.1.97 Apr 20 21:33:24 volumiocr volumio[1384]: info: Discovery: Remote connection timeout: 192.168.1.97 Apr 20 21:33:25 volumiocr volumio[1384]: verbose: New Socket.io Connection to 192.168.1.227:3000 from 192.168.1.97 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 20 21:33:25 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Apr 20 21:33:25 volumiocr volumio[1384]: info: CoreCommandRouter::volumioGetState Apr 20 21:33:25 volumiocr volumio[1384]: info: CorePlayQueue::getTrack 0 Apr 20 21:33:25 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 20 21:33:25 volumiocr volumio[1384]: verbose: New Socket.io Connection to 192.168.1.227:3000 from 192.168.1.97 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Apr 20 21:33:26 volumiocr volumio[1384]: verbose: New Socket.io Connection to 192.168.1.227:3000 from 192.168.1.97 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 20 21:33:27 volumiocr volumio[1384]: verbose: New Socket.io Connection to 192.168.1.227:3000 from 192.168.1.97 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 20 21:33:29 volumiocr volumio[1384]: info: [squeezelite_mc] 'client' notification received from P5LMS (192.168.1.37); type is 'connect' Apr 20 21:33:29 volumiocr volumio[1384]: info: [squeezelite_mc] Getting players connected to P5LMS (192.168.1.37) Apr 20 21:33:29 volumiocr volumio[1384]: info: [squeezelite_mc] Players connected to P5LMS (192.168.1.37): [{"id":"cc:cc:73:66:02:ad","uuid":null,"ip":"192.168.1.37","name":"Exercise room speaker","server":{"ip":"192.168.1.37","name":"P5LMS","ver":"9.0.0","uuid":"722a6f20-4d5f-4751-8683-1b3aa7ff1b66","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:dd:2a:b8:ee","uuid":null,"ip":"192.168.1.37","name":"Bedroom","server":{"ip":"192.168.1.37","name":"P5LMS","ver":"9.0.0","uuid":"722a6f20-4d5f-4751-8683-1b3aa7ff1b66","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:5a:f8:4d:4e","uuid":null,"ip":"192.168.1.37","name":"Living Room speaker","server":{"ip":"192.168.1.37","name":"P5LMS","ver":"9.0.0","uuid":"722a6f20-4d5f-4751-8683-1b3aa7ff1b66","jsonPort":"9000","cliPort":"9090"}},{"id":"aa:aa:d4:60:55:d5","uuid":null,"ip":"192.168.1.37","name":"volumiolr","server":{"ip":"192.168.1.37","name":"P5LMS","ver":"9.0.0","uuid":"722a6f20-4d5f-4751-8683-1b3aa7ff1b66","jsonPort":"9000","cliPort":"9090"}},{"id":"aa:aa:20:00:15:78","uuid":null,"ip":"192.168.1.37","name":"WiiM-Mini-LR","server":{"ip":"192.168.1.37","name":"P5LMS","ver":"9.0.0","uuid":"722a6f20-4d5f-4751-8683-1b3aa7ff1b66","jsonPort":"9000","cliPort":"9090"}},{"id":"aa:aa:00:18:6a:ea","uuid":null,"ip":"192.168.1.37","name":"WiiM-Mini-MBR","server":{"ip":"192.168.1.37","name":"P5LMS","ver":"9.0.0","uuid":"722a6f20-4d5f-4751-8683-1b3aa7ff1b66","jsonPort":"9000","cliPort":"9090"}},{"id":"00:e0:4c:68:00:37","uuid":null,"ip":"192.168.1.33","name":"volumiocr","server":{"ip":"192.168.1.37","name":"P5LMS","ver":"9.0.0","uuid":"722a6f20-4d5f-4751-8683-1b3aa7ff1b66","jsonPort":"9000","cliPort":"9090"}},{"id":"aa:aa:17:11:45:bb","uuid":null,"ip":"192.168.1.37","name":"MoodeCR","server":{"ip":"192.168.1.37","name":"P5LMS","ver":"9.0.0","uuid":"722a6f20-4d5f-4751-8683-1b3aa7ff1b66","jsonPort":"9000","cliPort":"9090"}},{"id":"dc:a6:32:af:bb:b4","uuid":null,"ip":"192.168.1.97","name":"volumiolr","server":{"ip":"192.168.1.37","name":"P5LMS","ver":"9.0.0","uuid":"722a6f20-4d5f-4751-8683-1b3aa7ff1b66","jsonPort":"9000","cliPort":"9090"}},{"id":"aa:aa:29:04:a1:95","uuid":null,"ip":"192.168.1.37","name":"volumio","server":{"ip":"192.168.1.37","name":"P5LMS","ver":"9.0.0","uuid":"722a6f20-4d5f-4751-8683-1b3aa7ff1b66","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:48:2c:35:66","uuid":null,"ip":"192.168.1.37","name":"Family Room speaker","server":{"ip":"192.168.1.37","name":"P5LMS","ver":"9.0.0","uuid":"722a6f20-4d5f-4751-8683-1b3aa7ff1b66","jsonPort":"9000","cliPort":"9090"}}] Apr 20 21:33:29 volumiocr volumio[1384]: info: Discovery: Connected to remote: 192.168.1.97 Apr 20 21:33:29 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 20 21:33:33 volumiocr volumio[1384]: info: [squeezelite_mc] 'client' notification received from P5LMS (192.168.1.37); type is 'connect' Apr 20 21:33:33 volumiocr volumio[1384]: info: [squeezelite_mc] Getting players connected to P5LMS (192.168.1.37) Apr 20 21:33:33 volumiocr volumio[1384]: info: [squeezelite_mc] Players connected to P5LMS (192.168.1.37): [{"id":"00:04:20:07:ed:42","uuid":null,"ip":"192.168.1.104","name":"LR Music","server":{"ip":"192.168.1.37","name":"P5LMS","ver":"9.0.0","uuid":"722a6f20-4d5f-4751-8683-1b3aa7ff1b66","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:73:66:02:ad","uuid":null,"ip":"192.168.1.37","name":"Exercise room speaker","server":{"ip":"192.168.1.37","name":"P5LMS","ver":"9.0.0","uuid":"722a6f20-4d5f-4751-8683-1b3aa7ff1b66","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:dd:2a:b8:ee","uuid":null,"ip":"192.168.1.37","name":"Bedroom","server":{"ip":"192.168.1.37","name":"P5LMS","ver":"9.0.0","uuid":"722a6f20-4d5f-4751-8683-1b3aa7ff1b66","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:5a:f8:4d:4e","uuid":null,"ip":"192.168.1.37","name":"Living Room speaker","server":{"ip":"192.168.1.37","name":"P5LMS","ver":"9.0.0","uuid":"722a6f20-4d5f-4751-8683-1b3aa7ff1b66","jsonPort":"9000","cliPort":"9090"}},{"id":"aa:aa:d4:60:55:d5","uuid":null,"ip":"192.168.1.37","name":"volumiolr","server":{"ip":"192.168.1.37","name":"P5LMS","ver":"9.0.0","uuid":"722a6f20-4d5f-4751-8683-1b3aa7ff1b66","jsonPort":"9000","cliPort":"9090"}},{"id":"aa:aa:20:00:15:78","uuid":null,"ip":"192.168.1.37","name":"WiiM-Mini-LR","server":{"ip":"192.168.1.37","name":"P5LMS","ver":"9.0.0","uuid":"722a6f20-4d5f-4751-8683-1b3aa7ff1b66","jsonPort":"9000","cliPort":"9090"}},{"id":"aa:aa:00:18:6a:ea","uuid":null,"ip":"192.168.1.37","name":"WiiM-Mini-MBR","server":{"ip":"192.168.1.37","name":"P5LMS","ver":"9.0.0","uuid":"722a6f20-4d5f-4751-8683-1b3aa7ff1b66","jsonPort":"9000","cliPort":"9090"}},{"id":"00:e0:4c:68:00:37","uuid":null,"ip":"192.168.1.33","name":"volumiocr","server":{"ip":"192.168.1.37","name":"P5LMS","ver":"9.0.0","uuid":"722a6f20-4d5f-4751-8683-1b3aa7ff1b66","jsonPort":"9000","cliPort":"9090"}},{"id":"aa:aa:17:11:45:bb","uuid":null,"ip":"192.168.1.37","name":"MoodeCR","server":{"ip":"192.168.1.37","name":"P5LMS","ver":"9.0.0","uuid":"722a6f20-4d5f-4751-8683-1b3aa7ff1b66","jsonPort":"9000","cliPort":"9090"}},{"id":"dc:a6:32:af:bb:b4","uuid":null,"ip":"192.168.1.97","name":"volumiolr","server":{"ip":"192.168.1.37","name":"P5LMS","ver":"9.0.0","uuid":"722a6f20-4d5f-4751-8683-1b3aa7ff1b66","jsonPort":"9000","cliPort":"9090"}},{"id":"aa:aa:29:04:a1:95","uuid":null,"ip":"192.168.1.37","name":"volumio","server":{"ip":"192.168.1.37","name":"P5LMS","ver":"9.0.0","uuid":"722a6f20-4d5f-4751-8683-1b3aa7ff1b66","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:48:2c:35:66","uuid":null,"ip":"192.168.1.37","name":"Family Room speaker","server":{"ip":"192.168.1.37","name":"P5LMS","ver":"9.0.0","uuid":"722a6f20-4d5f-4751-8683-1b3aa7ff1b66","jsonPort":"9000","cliPort":"9090"}}] Apr 20 21:33:33 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 20 21:33:35 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 20 21:33:37 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 20 21:33:38 volumiocr volumio[1384]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Apr 20 21:33:38 volumiocr sudo[2050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/onnC1TB Apr 20 21:33:38 volumiocr sudo[2050]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 21:33:38 volumiocr sudo[2050]: pam_unix(sudo:session): session closed for user root Apr 20 21:33:38 volumiocr volumio[1384]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 21:33:38 volumiocr volumio[1384]: Error: EBUSY: resource busy or locked, rmdir '/mnt/NAS/onnC1TB' Apr 20 21:33:38 volumiocr volumio[1384]: at Object.rmdirSync (fs.js:890:10) Apr 20 21:33:38 volumiocr volumio[1384]: at /volumio/node_modules/linux-mountutils/mountutils.js:178:12 Apr 20 21:33:38 volumiocr volumio[1384]: at ChildProcess.exithandler (child_process.js:299:7) Apr 20 21:33:38 volumiocr volumio[1384]: at ChildProcess.emit (events.js:315:20) Apr 20 21:33:38 volumiocr volumio[1384]: at maybeClose (internal/child_process.js:1048:16) Apr 20 21:33:38 volumiocr volumio[1384]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) { Apr 20 21:33:38 volumiocr volumio[1384]: errno: -16, Apr 20 21:33:38 volumiocr volumio[1384]: syscall: 'rmdir', Apr 20 21:33:38 volumiocr volumio[1384]: code: 'EBUSY', Apr 20 21:33:38 volumiocr volumio[1384]: path: '/mnt/NAS/onnC1TB' Apr 20 21:33:38 volumiocr volumio[1384]: } Apr 20 21:33:38 volumiocr volumio[1384]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 21:33:39 volumiocr sudo[2062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-20 21:32 Apr 20 21:33:39 volumiocr sudo[2062]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"