oct. 11 13:02:00 chatbry volumio[1137]: info: Plugin example_plugin is not enabled oct. 11 13:02:00 chatbry volumio[1137]: info: Loading plugin "inputs"... oct. 11 13:02:00 chatbry volumio[1137]: info: Loading plugin "updater_comm"... oct. 11 13:02:00 chatbry volumio[1137]: info: Plugin mpdemulation is not enabled oct. 11 13:02:00 chatbry volumio[1137]: info: Loading plugin "rest_api"... oct. 11 13:02:00 chatbry volumio[1137]: info: Loading plugin "websocket"... oct. 11 13:02:00 chatbry volumio[1137]: info: Starting Socket.io Server version 1.7.4 oct. 11 13:02:00 chatbry volumio[1137]: info: Loading plugin "Systeminfo"... oct. 11 13:02:01 chatbry volumio[1237]: Forking 3 albumart workers oct. 11 13:02:02 chatbry volumio[1137]: info: Loading i18n strings for locale fr oct. 11 13:02:02 chatbry volumio[1137]: Updating browse sources language oct. 11 13:02:02 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources oct. 11 13:02:03 chatbry systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. oct. 11 13:02:03 chatbry systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. oct. 11 13:02:03 chatbry systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. oct. 11 13:02:03 chatbry upmpdcli[1291]: Could not open config: /tmp/upmpdcli.conf oct. 11 13:02:03 chatbry systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE oct. 11 13:02:03 chatbry systemd[1]: upmpdcli.service: Failed with result 'exit-code'. oct. 11 13:02:04 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam oct. 11 13:02:04 chatbry volumio[1137]: info: CoreCommandRouter::initPlayerControls oct. 11 13:02:04 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:04 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:04 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:04 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:04 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:04 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:04 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:04 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:04 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam oct. 11 13:02:04 chatbry volumio[1137]: Express server listening on port 3000 oct. 11 13:02:04 chatbry volumio[1137]: [Metrics] WebUI: 19s 791.79ms oct. 11 13:02:04 chatbry volumio[1137]: info: CoreStateMachine::resetVolumioState oct. 11 13:02:04 chatbry systemd[1]: systemd-hostnamed.service: Deactivated successfully. oct. 11 13:02:04 chatbry volumio[1137]: info: CoreStateMachine::getcurrentVolume oct. 11 13:02:04 chatbry volumio[1137]: info: CoreCommandRouter::volumioRetrievevolume oct. 11 13:02:04 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 13:02:05 chatbry sudo[1302]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 oct. 11 13:02:05 chatbry sudo[1300]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 oct. 11 13:02:05 chatbry sudo[1300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 13:02:05 chatbry sudo[1302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 13:02:05 chatbry sudo[1300]: pam_unix(sudo:session): session closed for user root oct. 11 13:02:05 chatbry sudo[1302]: pam_unix(sudo:session): session closed for user root oct. 11 13:02:05 chatbry volumio[1137]: info: Volumio Network Manager: Network status updated: 1 oct. 11 13:02:06 chatbry volumio[1137]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.19 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/154.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 oct. 11 13:02:06 chatbry volumio[1137]: info: VolumeController:: Volume=60 Mute =false oct. 11 13:02:06 chatbry volumio[1137]: info: CoreStateMachine::pushState oct. 11 13:02:06 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:02:06 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo oct. 11 13:02:06 chatbry volumio[1137]: info: CoreCommandRouter::volumioPushState oct. 11 13:02:06 chatbry volumio[1137]: info: CoreStateMachine::updateTrackBlock oct. 11 13:02:06 chatbry volumio[1137]: info: CorePlayQueue::getTrackBlock oct. 11 13:02:06 chatbry volumio[1137]: info: CoreCommandRouter::volumioRetrievevolume oct. 11 13:02:07 chatbry volumio[1137]: MPD error: Connection refused oct. 11 13:02:07 chatbry volumio[1137]: info: Reloading queue from file oct. 11 13:02:07 chatbry volumio[1137]: verbose: New Socket.io Connection to chatbry.local from 192.168.1.19 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/154.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 oct. 11 13:02:07 chatbry volumio[1137]: info: CoreStateMachine::setRepeat false single undefined oct. 11 13:02:07 chatbry volumio[1137]: info: CoreStateMachine::pushState oct. 11 13:02:07 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:02:07 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo oct. 11 13:02:07 chatbry volumio[1137]: info: CoreCommandRouter::volumioPushState oct. 11 13:02:07 chatbry volumio[1137]: info: CoreStateMachine::setRandom false oct. 11 13:02:07 chatbry volumio[1137]: info: CoreStateMachine::pushState oct. 11 13:02:07 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:02:07 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo oct. 11 13:02:07 chatbry volumio[1137]: info: CoreCommandRouter::volumioPushState oct. 11 13:02:07 chatbry volumio[1137]: info: Setting Device type: Raspberry PI oct. 11 13:02:07 chatbry volumio[1137]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.19 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/154.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 oct. 11 13:02:08 chatbry volumio[1137]: info: Discovery: adding 9c0a4e48-fb30-4943-b9b0-01588af221b5 oct. 11 13:02:08 chatbry volumio[1137]: info: Discovery: Found device Chatbry oct. 11 13:02:08 chatbry volumio[1137]: info: CoreCommandRouter::volumioGetState oct. 11 13:02:08 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:02:08 chatbry sudo[1323]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service oct. 11 13:02:08 chatbry sudo[1323]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 13:02:08 chatbry volumio[1137]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.19 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/154.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 oct. 11 13:02:08 chatbry volumio[1137]: info: VolumeController:: Volume=60 Mute =false oct. 11 13:02:08 chatbry volumio[1137]: info: CoreStateMachine::pushState oct. 11 13:02:08 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:02:08 chatbry volumio[1137]: info: CoreCommandRouter::volumioPushState oct. 11 13:02:08 chatbry volumio[1137]: info: CoreCommandRouter::volumioGetState oct. 11 13:02:08 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:02:08 chatbry volumio[1137]: info: CoreCommandRouter::volumioGetState oct. 11 13:02:08 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:02:08 chatbry volumio[1137]: info: CoreCommandRouter::volumioGetQueue oct. 11 13:02:08 chatbry volumio[1137]: info: CoreStateMachine::getQueue oct. 11 13:02:08 chatbry volumio[1137]: info: CorePlayQueue::getQueue oct. 11 13:02:08 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid oct. 11 13:02:08 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel oct. 11 13:02:08 chatbry volumio[1137]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] oct. 11 13:02:08 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction oct. 11 13:02:08 chatbry volumio[1137]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs oct. 11 13:02:08 chatbry volumio[1137]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] oct. 11 13:02:08 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection oct. 11 13:02:09 chatbry volumio[1137]: info: Discovery: this is already registered, 9c0a4e48-fb30-4943-b9b0-01588af221b5 oct. 11 13:02:09 chatbry volumio[1137]: info: Discovery: Found device Chatbry oct. 11 13:02:09 chatbry volumio[1137]: info: CoreCommandRouter::volumioGetState oct. 11 13:02:09 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:02:09 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom oct. 11 13:02:09 chatbry volumio[1137]: info: CoreCommandRouter::volumioGetVisibleSources oct. 11 13:02:09 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources oct. 11 13:02:09 chatbry volumio[1137]: info: Listing playlists oct. 11 13:02:09 chatbry volumio[1137]: info: Completed loading Core Plugins oct. 11 13:02:09 chatbry volumio[1137]: info: Preparing to generate the ALSA configuration file oct. 11 13:02:10 chatbry volumio[1137]: info: Asound.conf file unchanged, so no further update is needed oct. 11 13:02:10 chatbry volumio[1137]: info: Output device has changed, restarting MPD oct. 11 13:02:10 chatbry volumio[1137]: info: Output device has changed, restarting Shairport Sync oct. 11 13:02:10 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:10 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 13:02:10 chatbry sudo[1327]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf oct. 11 13:02:10 chatbry sudo[1327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 13:02:10 chatbry sudo[1327]: pam_unix(sudo:session): session closed for user root oct. 11 13:02:10 chatbry sudo[1329]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service oct. 11 13:02:10 chatbry sudo[1329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 13:02:10 chatbry volumio[1137]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 oct. 11 13:02:10 chatbry volumio[1137]: info: ___________ START PLUGINS ___________ oct. 11 13:02:10 chatbry volumio[1137]: info: ControllerMpd::onStart: Initializing MPD oct. 11 13:02:10 chatbry volumio[1137]: info: Creating MPD Configuration file oct. 11 13:02:10 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam oct. 11 13:02:11 chatbry systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. oct. 11 13:02:11 chatbry systemd[1]: Starting mpd.service - Music Player Daemon... oct. 11 13:02:11 chatbry systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... oct. 11 13:02:11 chatbry volumio[1137]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] oct. 11 13:02:11 chatbry volumio[1137]: info: [1791716531101] CoreMusicLibrary::Adding element Serveurs Média oct. 11 13:02:11 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources oct. 11 13:02:11 chatbry sudo[1344]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log oct. 11 13:02:11 chatbry sudo[1344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) oct. 11 13:02:11 chatbry sudo[1369]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory oct. 11 13:02:11 chatbry sudo[1344]: pam_unix(sudo:session): session closed for user root oct. 11 13:02:11 chatbry sudo[1337]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf oct. 11 13:02:11 chatbry sudo[1337]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 13:02:11 chatbry sudo[1337]: pam_unix(sudo:session): session closed for user root oct. 11 13:02:11 chatbry volumio[1137]: info: UPNP Browser: Client initialized successfully oct. 11 13:02:11 chatbry sudo[1345]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service oct. 11 13:02:11 chatbry sudo[1345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 13:02:11 chatbry systemd[1]: mpd.service: Deactivated successfully. oct. 11 13:02:11 chatbry systemd[1]: Stopped mpd.service - Music Player Daemon. oct. 11 13:02:11 chatbry systemd[1]: mpd.socket: Deactivated successfully. oct. 11 13:02:11 chatbry systemd[1]: Closed mpd.socket - Music Player Daemon Socket. oct. 11 13:02:11 chatbry systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... oct. 11 13:02:11 chatbry systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. oct. 11 13:02:11 chatbry systemd[1]: Starting mpd.service - Music Player Daemon... oct. 11 13:02:11 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:11 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 13:02:12 chatbry sudo[1378]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log oct. 11 13:02:12 chatbry sudo[1378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) oct. 11 13:02:12 chatbry sudo[1381]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory oct. 11 13:02:12 chatbry sudo[1378]: pam_unix(sudo:session): session closed for user root oct. 11 13:02:12 chatbry volumio[1261]: Starting albumart workers oct. 11 13:02:12 chatbry volumio[1137]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 oct. 11 13:02:12 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:12 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 13:02:12 chatbry systemd[1]: setdatetime-helper.service: Deactivated successfully. oct. 11 13:02:12 chatbry systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. oct. 11 13:02:12 chatbry systemd[1]: setdatetime-helper.service: Consumed 1.173s CPU time. oct. 11 13:02:12 chatbry volumio[1137]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 oct. 11 13:02:12 chatbry volumio[1137]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] oct. 11 13:02:12 chatbry volumio[1137]: info: [1791716532913] CoreMusicLibrary::Adding element Last_100 oct. 11 13:02:12 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources oct. 11 13:02:12 chatbry volumio[1137]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] oct. 11 13:02:12 chatbry volumio[1137]: info: [1791716532972] CoreMusicLibrary::Adding element Webradio oct. 11 13:02:12 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources oct. 11 13:02:13 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion oct. 11 13:02:13 chatbry volumio[1137]: info: Initializing BBC Radios oct. 11 13:02:13 chatbry volumio[1262]: Starting albumart workers oct. 11 13:02:13 chatbry volumio[1263]: Starting albumart workers oct. 11 13:02:13 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion oct. 11 13:02:13 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 13:02:13 chatbry volumio[1137]: info: AutoStart - onStart - waiting for system ready state oct. 11 13:02:13 chatbry volumio[1137]: info: AutoStart - Polling config: interval=10000ms, maxAttempts=20 oct. 11 13:02:13 chatbry volumio[1137]: info: AutoStart - Maximum wait time: 200 seconds oct. 11 13:02:13 chatbry volumio[1137]: info: AutoStart - Startup volume enabled, level=60 oct. 11 13:02:13 chatbry volumio[1137]: info: AutoStart - Check #1/20 - VOLUMIO_SYSTEM_STATUS = starting oct. 11 13:02:14 chatbry volumio[1137]: info: [now-playing] ConfigUpdater: config is up to date. oct. 11 13:02:14 chatbry volumio[1137]: info: Cannot retrieve data for calling home oct. 11 13:02:14 chatbry volumio-remote-updater[614]: [2026-10-11 13:02:14] [connect] Successful connection oct. 11 13:02:18 chatbry systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. oct. 11 13:02:18 chatbry systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. oct. 11 13:02:18 chatbry systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. oct. 11 13:02:18 chatbry sudo[1323]: pam_unix(sudo:session): session closed for user root oct. 11 13:02:19 chatbry mpd[1382]: 2026-10-11T13:02:19 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg oct. 11 13:02:19 chatbry systemd[1]: Started mpd.service - Music Player Daemon. oct. 11 13:02:20 chatbry sudo[1345]: pam_unix(sudo:session): session closed for user root oct. 11 13:02:20 chatbry sudo[1329]: pam_unix(sudo:session): session closed for user root oct. 11 13:02:20 chatbry volumio[1137]: info: [now-playing] App is listening on port 4004. oct. 11 13:02:20 chatbry volumio[1137]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds oct. 11 13:02:20 chatbry volumio[1137]: info: Completed starting Core Plugins oct. 11 13:02:20 chatbry volumio[1137]: info: ------------------------------------------- oct. 11 13:02:20 chatbry volumio[1137]: info: ----- MyVolumio plugins startup ---- oct. 11 13:02:20 chatbry volumio[1137]: info: ------------------------------------------- oct. 11 13:02:20 chatbry volumio[1137]: info: [MyVolumio PluginManager] Fetching plans data.... oct. 11 13:02:20 chatbry volumio[1137]: info: MPD Permissions set oct. 11 13:02:20 chatbry volumio[1137]: info: MPD Permissions set oct. 11 13:02:20 chatbry volumio[1137]: info: Upmpdcli Daemon Started oct. 11 13:02:21 chatbry volumio[1137]: info: MPD running with PID1382 oct. 11 13:02:21 chatbry volumio[1137]: ,establishing connection oct. 11 13:02:22 chatbry volumio[1137]: error: MPD error: The expression evaluated to a falsy value: oct. 11 13:02:22 chatbry volumio[1137]: assert.ok(self.idling) oct. 11 13:02:22 chatbry volumio[1137]: error: The expression evaluated to a falsy value: oct. 11 13:02:22 chatbry volumio[1137]: assert.ok(self.idling) oct. 11 13:02:22 chatbry volumio[1137]: error: MPD error: The expression evaluated to a falsy value: oct. 11 13:02:22 chatbry volumio[1137]: assert.ok(self.idling) oct. 11 13:02:22 chatbry volumio[1137]: error: The expression evaluated to a falsy value: oct. 11 13:02:22 chatbry volumio[1137]: assert.ok(self.idling) oct. 11 13:02:22 chatbry volumio[1137]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 oct. 11 13:02:22 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:22 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:22 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:22 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:22 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:22 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:22 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:22 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:23 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 13:02:23 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:23 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:23 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:23 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:23 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:23 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:23 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:23 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:23 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:23 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 13:02:23 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:02:23 chatbry volumio[1137]: error: updateQueue error: null oct. 11 13:02:23 chatbry volumio[1137]: info: Starting Shairport Sync oct. 11 13:02:23 chatbry volumio[1137]: info: Starting Shairport Sync oct. 11 13:02:23 chatbry volumio[1137]: info: Starting Shairport Sync oct. 11 13:02:23 chatbry sudo[1476]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync oct. 11 13:02:23 chatbry sudo[1475]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync oct. 11 13:02:23 chatbry sudo[1475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 13:02:23 chatbry sudo[1476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 13:02:23 chatbry sudo[1478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync oct. 11 13:02:23 chatbry sudo[1478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 13:02:23 chatbry volumio[1137]: info: AutoStart - Check #2/20 - VOLUMIO_SYSTEM_STATUS = starting oct. 11 13:02:23 chatbry systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... oct. 11 13:02:23 chatbry systemd[1]: shairport-sync.service: Deactivated successfully. oct. 11 13:02:23 chatbry systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. oct. 11 13:02:23 chatbry systemd[1]: shairport-sync.service: Consumed 1.826s CPU time. oct. 11 13:02:24 chatbry sudo[1483]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service oct. 11 13:02:24 chatbry sudo[1483]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 13:02:24 chatbry systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. oct. 11 13:02:24 chatbry sudo[1476]: pam_unix(sudo:session): session closed for user root oct. 11 13:02:24 chatbry sudo[1475]: pam_unix(sudo:session): session closed for user root oct. 11 13:02:24 chatbry sudo[1478]: pam_unix(sudo:session): session closed for user root oct. 11 13:02:24 chatbry systemd[1]: Started mpd_monitor.service - MPD Monitor Service. oct. 11 13:02:24 chatbry mpd_monitor.sh[1498]: MPD Monitor Service: Starting MPD Monitor Service oct. 11 13:02:24 chatbry sudo[1483]: pam_unix(sudo:session): session closed for user root oct. 11 13:02:24 chatbry volumio[1137]: info: Shairport-Sync Started oct. 11 13:02:24 chatbry volumio[1137]: Error adding Membership: Error: addMembership EINVAL oct. 11 13:02:24 chatbry volumio[1137]: info: Successfully started MPD Monitor oct. 11 13:02:24 chatbry volumio[1137]: info: Shairport-Sync Started oct. 11 13:02:24 chatbry volumio[1137]: info: Shairport-Sync Started oct. 11 13:02:24 chatbry sudo[1487]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service oct. 11 13:02:24 chatbry sudo[1487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 13:02:24 chatbry volumio[1137]: info: CoreCommandRouter::volumioGetState oct. 11 13:02:24 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:02:24 chatbry systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... oct. 11 13:02:24 chatbry systemd[1]: mpd_monitor.service: Deactivated successfully. oct. 11 13:02:24 chatbry systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. oct. 11 13:02:24 chatbry systemd[1]: Started mpd_monitor.service - MPD Monitor Service. oct. 11 13:02:24 chatbry mpd_monitor.sh[1512]: MPD Monitor Service: Starting MPD Monitor Service oct. 11 13:02:24 chatbry sudo[1487]: pam_unix(sudo:session): session closed for user root oct. 11 13:02:24 chatbry volumio[1137]: info: Successfully started MPD Monitor oct. 11 13:02:27 chatbry kernel: usb 1-1-port2: over-current change #10 oct. 11 13:02:27 chatbry kernel: usb 1-1.1-port2: over-current change #10 oct. 11 13:02:27 chatbry kernel: usb 1-1-port3: over-current change #10 oct. 11 13:02:27 chatbry kernel: usb 1-1.1-port3: over-current change #9 oct. 11 13:02:28 chatbry kernel: usb 1-1-port4: over-current change #10 oct. 11 13:02:28 chatbry kernel: usb 1-1.1.2: USB disconnect, device number 8 oct. 11 13:02:28 chatbry volumio[1137]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-uuid/61BB-782A /dev/disk/by-path/platform-3f980000.usb-usb-0:1.1.2:1.0-scsi-0:0:0:0-part1 /dev/disk/by-id/usb-USB_SanDisk_3.2Gen1_01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476-0:0-part1","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"32","ID_BUS":"usb","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"61BB-782A","ID_FS_UUID_ENC":"61BB-782A","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"SanDisk_3.2Gen1","ID_MODEL_ENC":"\\x20SanDisk\\x203.2Gen1","ID_MODEL_ID":"5583","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"32","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"30080992","ID_PART_ENTRY_TYPE":"0xc","ID_PART_TABLE_TYPE":"dos","ID_PATH":"platform-3f980000.usb-usb-0:1.1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"USB_SanDisk_3.2Gen1_01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476-0:0","ID_SERIAL_SHORT":"01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INSTANCE":"0:0","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_USB_MODEL":"SanDisk_3.2Gen1","ID_USB_MODEL_ENC":"\\x20SanDisk\\x203.2Gen1","ID_USB_MODEL_ID":"5583","ID_USB_REVISION":"1.00","ID_USB_SERIAL":"USB_SanDisk_3.2Gen1_01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476-0:0","ID_USB_SERIAL_SHORT":"01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476","ID_USB_TYPE":"disk","ID_USB_VENDOR":"USB","ID_USB_VENDOR_ENC":"\\x20USB\\x20\\x20\\x20\\x20","ID_USB_VENDOR_ID":"0781","ID_VENDOR":"USB","ID_VENDOR_ENC":"\\x20USB\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"0781","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2268","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"37426588"} oct. 11 13:02:28 chatbry kernel: usb 1-1.1-port3: over-current change #10 oct. 11 13:02:28 chatbry sudo[1524]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 oct. 11 13:02:28 chatbry sudo[1524]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 13:02:28 chatbry kernel: usb 1-1-port2: over-current change #11 oct. 11 13:02:28 chatbry systemd[1]: media-61BB\x2d782A.mount: Deactivated successfully. oct. 11 13:02:28 chatbry kernel: usb 1-1.1-port2: over-current change #11 oct. 11 13:02:28 chatbry sudo[1524]: pam_unix(sudo:session): session closed for user root oct. 11 13:02:28 chatbry kernel: usb 1-1-port3: over-current change #11 oct. 11 13:02:29 chatbry kernel: usb 1-1-port4: over-current change #11 oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar oct. 11 13:02:29 chatbry kernel: usb 1-1.1.2: new high-speed USB device number 9 using dwc_otg oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso oct. 11 13:02:29 chatbry volumio[1137]: info: Adding plugin bluetooth to MyMusic Plugins oct. 11 13:02:29 chatbry volumio[1137]: info: Adding plugin multiroom to MyMusic Plugins oct. 11 13:02:29 chatbry volumio[1137]: info: Adding plugin metavolumio to MyMusic Plugins oct. 11 13:02:29 chatbry volumio[1137]: info: Adding plugin cd_controller to MyMusic Plugins oct. 11 13:02:29 chatbry volumio[1137]: info: Adding plugin qobuzconnect to MyMusic Plugins oct. 11 13:02:29 chatbry volumio[1137]: info: Adding plugin smart_inputs to MyMusic Plugins oct. 11 13:02:29 chatbry kernel: usb 1-1.1.2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 oct. 11 13:02:29 chatbry kernel: usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 oct. 11 13:02:29 chatbry kernel: usb 1-1.1.2: Product: SanDisk 3.2Gen1 oct. 11 13:02:29 chatbry kernel: usb 1-1.1.2: Manufacturer: USB oct. 11 13:02:29 chatbry kernel: usb 1-1.1.2: SerialNumber: 01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476 oct. 11 13:02:29 chatbry kernel: usb-storage 1-1.1.2:1.0: USB Mass Storage device detected oct. 11 13:02:29 chatbry kernel: scsi host0: usb-storage 1-1.1.2:1.0 oct. 11 13:02:29 chatbry volumio[1137]: info: Adding plugin tidalconnect to MyMusic Plugins oct. 11 13:02:29 chatbry volumio[1137]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... oct. 11 13:02:29 chatbry kernel: usb 1-1.1-port2: over-current change #12 oct. 11 13:02:29 chatbry kernel: usb 1-1-port2: over-current change #12 oct. 11 13:02:29 chatbry volumio-remote-updater[614]: [2026-10-11 13:02:29] [connect] Successful connection oct. 11 13:02:30 chatbry kernel: usb 1-1.1-port3: over-current change #11 oct. 11 13:02:30 chatbry kernel: usb 1-1-port3: over-current change #12 oct. 11 13:02:30 chatbry kernel: usb 1-1-port4: over-current change #12 oct. 11 13:02:30 chatbry kernel: usb 1-1.1.2: USB disconnect, device number 9 oct. 11 13:02:30 chatbry kernel: usb 1-1-port2: over-current change #13 oct. 11 13:02:30 chatbry kernel: usb 1-1.1-port3: over-current change #12 oct. 11 13:02:31 chatbry kernel: usb 1-1-port3: over-current change #13 oct. 11 13:02:31 chatbry kernel: usb 1-1.1-port2: over-current change #13 oct. 11 13:02:31 chatbry kernel: usb 1-1-port4: over-current change #13 oct. 11 13:02:31 chatbry kernel: usb 1-1.1-port2: over-current change #14 oct. 11 13:02:31 chatbry kernel: usb 1-1-port2: over-current change #14 oct. 11 13:02:31 chatbry kernel: usb 1-1.1-port3: over-current change #13 oct. 11 13:02:31 chatbry kernel: usb 1-1-port3: over-current change #14 oct. 11 13:02:32 chatbry kernel: usb 1-1-port4: over-current change #14 oct. 11 13:02:32 chatbry kernel: usb 1-1.1.2: new high-speed USB device number 10 using dwc_otg oct. 11 13:02:32 chatbry kernel: usb 1-1-port2: over-current change #15 oct. 11 13:02:32 chatbry kernel: usb 1-1-port3: over-current change #15 oct. 11 13:02:33 chatbry kernel: usb 1-1.1.2: device not accepting address 10, error -71 oct. 11 13:02:33 chatbry kernel: usb 1-1-port4: over-current change #15 oct. 11 13:02:33 chatbry kernel: usb 1-1-port2: over-current change #16 oct. 11 13:02:33 chatbry kernel: usb 1-1-port3: over-current change #16 oct. 11 13:02:33 chatbry kernel: usb 1-1.1-port3: over-current change #14 oct. 11 13:02:33 chatbry kernel: usb 1-1-port4: over-current change #16 oct. 11 13:02:34 chatbry kernel: usb 1-1.1-port2: over-current change #15 oct. 11 13:02:34 chatbry kernel: usb 1-1-port2: over-current change #17 oct. 11 13:02:34 chatbry kernel: usb 1-1.1-port3: over-current change #15 oct. 11 13:02:34 chatbry kernel: usb 1-1-port3: over-current change #17 oct. 11 13:02:34 chatbry kernel: usb 1-1.1-port2: over-current change #16 oct. 11 13:02:34 chatbry kernel: usb 1-1-port4: over-current change #17 oct. 11 13:02:34 chatbry kernel: usb 1-1.1-port2: over-current change #17 oct. 11 13:02:35 chatbry kernel: usb 1-1-port2: over-current change #18 oct. 11 13:02:35 chatbry kernel: usb 1-1.1-port3: over-current change #16 oct. 11 13:02:35 chatbry kernel: usb 1-1-port3: over-current change #18 oct. 11 13:02:35 chatbry kernel: usb 1-1-port4: over-current change #18 oct. 11 13:02:35 chatbry kernel: usb 1-1.1-port2: over-current change #18 oct. 11 13:02:35 chatbry kernel: usb 1-1-port2: over-current change #19 oct. 11 13:02:35 chatbry kernel: usb 1-1.1-port3: over-current change #17 oct. 11 13:02:36 chatbry kernel: usb 1-1-port3: over-current change #19 oct. 11 13:02:36 chatbry kernel: usb 1-1-port4: over-current change #19 oct. 11 13:02:36 chatbry kernel: usb 1-1.1-port2: over-current change #19 oct. 11 13:02:36 chatbry kernel: usb 1-1-port2: over-current change #20 oct. 11 13:02:36 chatbry kernel: usb 1-1.1-port3: over-current change #18 oct. 11 13:02:36 chatbry kernel: usb 1-1-port3: over-current change #20 oct. 11 13:02:37 chatbry kernel: usb 1-1-port4: over-current change #20 oct. 11 13:02:37 chatbry kernel: usb 1-1.1-port2: over-current change #20 oct. 11 13:02:37 chatbry kernel: usb 1-1-port2: over-current change #21 oct. 11 13:02:37 chatbry kernel: usb 1-1-port3: over-current change #21 oct. 11 13:02:37 chatbry kernel: usb 1-1.1-port3: over-current change #19 oct. 11 13:02:37 chatbry kernel: usb 1-1-port4: over-current change #21 oct. 11 13:02:37 chatbry kernel: usb 1-1.1-port2: over-current change #21 oct. 11 13:02:37 chatbry kernel: usb 1-1-port2: over-current change #22 oct. 11 13:02:38 chatbry kernel: usb 1-1.1-port3: over-current change #20 oct. 11 13:02:38 chatbry kernel: usb 1-1-port3: over-current change #22 oct. 11 13:02:38 chatbry kernel: usb 1-1.1.2: new high-speed USB device number 12 using dwc_otg oct. 11 13:02:38 chatbry kernel: usb 1-1.1.2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 oct. 11 13:02:38 chatbry kernel: usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 oct. 11 13:02:38 chatbry kernel: usb 1-1.1.2: Product: SanDisk 3.2Gen1 oct. 11 13:02:38 chatbry kernel: usb 1-1.1.2: Manufacturer: USB oct. 11 13:02:38 chatbry kernel: usb 1-1.1.2: SerialNumber: 01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476 oct. 11 13:02:38 chatbry kernel: usb-storage 1-1.1.2:1.0: USB Mass Storage device detected oct. 11 13:02:38 chatbry kernel: scsi host0: usb-storage 1-1.1.2:1.0 oct. 11 13:02:39 chatbry kernel: usb 1-1.1-port2: over-current change #22 oct. 11 13:02:39 chatbry kernel: usb 1-1-port2: over-current change #23 oct. 11 13:02:39 chatbry kernel: usb 1-1.1-port3: over-current change #21 oct. 11 13:02:39 chatbry kernel: usb 1-1-port3: over-current change #23 oct. 11 13:02:39 chatbry kernel: usb 1-1-port4: over-current change #22 oct. 11 13:02:40 chatbry kernel: usb 1-1-port2: over-current change #24 oct. 11 13:02:40 chatbry kernel: usb 1-1-port3: over-current change #24 oct. 11 13:02:40 chatbry kernel: usb 1-1-port4: over-current change #23 oct. 11 13:02:40 chatbry kernel: usb 1-1.1-port2: over-current change #23 oct. 11 13:02:40 chatbry volumio[1137]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded oct. 11 13:02:40 chatbry volumio[1137]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio oct. 11 13:02:40 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 13:02:40 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 13:02:40 chatbry volumio[1137]: info: Starting MyVolumio Remote Streaming Endpoints oct. 11 13:02:40 chatbry kernel: usb 1-1.1.2: USB disconnect, device number 12 oct. 11 13:02:40 chatbry kernel: usb 1-1.1-port3: over-current change #22 oct. 11 13:02:40 chatbry kernel: usb 1-1-port2: over-current change #25 oct. 11 13:02:40 chatbry volumio[1137]: info: MyVolumio login type: Token oct. 11 13:02:41 chatbry kernel: usb 1-1.1-port2: over-current change #24 oct. 11 13:02:41 chatbry kernel: usb 1-1-port3: over-current change #25 oct. 11 13:02:41 chatbry volumio[1137]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started oct. 11 13:02:41 chatbry volumio[1137]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... oct. 11 13:02:41 chatbry kernel: usb 1-1-port4: over-current change #24 oct. 11 13:02:41 chatbry kernel: usb 1-1.1.2: new high-speed USB device number 13 using dwc_otg oct. 11 13:02:41 chatbry kernel: usb 1-1-port2: over-current change #26 oct. 11 13:02:42 chatbry kernel: usb 1-1-port3: over-current change #26 oct. 11 13:02:42 chatbry kernel: usb 1-1-port4: over-current change #25 oct. 11 13:02:42 chatbry kernel: usb 1-1.1-port3: over-current change #23 oct. 11 13:02:42 chatbry kernel: usb 1-1-port2: over-current change #27 oct. 11 13:02:42 chatbry kernel: usb 1-1.1-port2: over-current change #25 oct. 11 13:02:42 chatbry kernel: usb 1-1-port3: over-current change #27 oct. 11 13:02:43 chatbry kernel: usb 1-1.1.2: new high-speed USB device number 14 using dwc_otg oct. 11 13:02:43 chatbry kernel: usb 1-1.1.2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 oct. 11 13:02:43 chatbry kernel: usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 oct. 11 13:02:43 chatbry kernel: usb 1-1.1.2: Product: SanDisk 3.2Gen1 oct. 11 13:02:43 chatbry kernel: usb 1-1.1.2: Manufacturer: USB oct. 11 13:02:43 chatbry kernel: usb 1-1.1.2: SerialNumber: 01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476 oct. 11 13:02:43 chatbry kernel: usb-storage 1-1.1.2:1.0: USB Mass Storage device detected oct. 11 13:02:43 chatbry kernel: scsi host0: usb-storage 1-1.1.2:1.0 oct. 11 13:02:43 chatbry kernel: usb 1-1-port2: over-current change #28 oct. 11 13:02:43 chatbry kernel: usb 1-1.1-port2: over-current change #26 oct. 11 13:02:43 chatbry kernel: usb 1-1-port3: over-current change #28 oct. 11 13:02:44 chatbry kernel: usb 1-1.1-port3: over-current change #24 oct. 11 13:02:44 chatbry kernel: usb 1-1-port4: over-current change #26 oct. 11 13:02:44 chatbry kernel: usb 1-1.1.2: USB disconnect, device number 14 oct. 11 13:02:44 chatbry kernel: usb 1-1-port2: over-current change #29 oct. 11 13:02:44 chatbry kernel: usb 1-1.1-port2: over-current change #27 oct. 11 13:02:44 chatbry kernel: usb 1-1-port3: over-current change #29 oct. 11 13:02:44 chatbry kernel: usb 1-1.1-port3: over-current change #25 oct. 11 13:02:44 chatbry volumio-remote-updater[614]: [2026-10-11 13:02:44] [connect] Successful connection oct. 11 13:02:44 chatbry kernel: usb 1-1-port4: over-current change #27 oct. 11 13:02:45 chatbry kernel: usb 1-1.1-port2: over-current change #28 oct. 11 13:02:45 chatbry kernel: usb 1-1-port2: over-current change #30 oct. 11 13:02:45 chatbry kernel: usb 1-1.1-port3: over-current change #26 oct. 11 13:02:45 chatbry kernel: usb 1-1-port3: over-current change #30 oct. 11 13:02:45 chatbry kernel: usb 1-1.1-port2: over-current change #29 oct. 11 13:02:45 chatbry kernel: usb 1-1-port4: over-current change #28 oct. 11 13:02:45 chatbry kernel: usb 1-1.1-port3: over-current change #27 oct. 11 13:02:45 chatbry kernel: usb 1-1-port2: over-current change #31 oct. 11 13:02:46 chatbry kernel: usb 1-1-port3: over-current change #31 oct. 11 13:02:46 chatbry kernel: usb 1-1.1.2: new high-speed USB device number 15 using dwc_otg oct. 11 13:02:46 chatbry kernel: usb 1-1.1.2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 oct. 11 13:02:46 chatbry kernel: usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 oct. 11 13:02:46 chatbry kernel: usb 1-1.1.2: Product: SanDisk 3.2Gen1 oct. 11 13:02:46 chatbry kernel: usb 1-1.1.2: Manufacturer: USB oct. 11 13:02:46 chatbry kernel: usb 1-1.1.2: SerialNumber: 01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476 oct. 11 13:02:46 chatbry kernel: usb-storage 1-1.1.2:1.0: USB Mass Storage device detected oct. 11 13:02:46 chatbry kernel: scsi host0: usb-storage 1-1.1.2:1.0 oct. 11 13:02:47 chatbry kernel: usb 1-1.1-port2: over-current change #30 oct. 11 13:02:47 chatbry kernel: usb 1-1-port2: over-current change #32 oct. 11 13:02:47 chatbry kernel: usb 1-1.1-port3: over-current change #28 oct. 11 13:02:47 chatbry kernel: usb 1-1-port3: over-current change #32 oct. 11 13:02:47 chatbry kernel: usb 1-1-port4: over-current change #29 oct. 11 13:02:47 chatbry kernel: usb 1-1.1.2: reset high-speed USB device number 15 using dwc_otg oct. 11 13:02:48 chatbry kernel: scsi 0:0:0:0: Direct-Access USB SanDisk 3.2Gen1 1.00 PQ: 0 ANSI: 6 oct. 11 13:02:48 chatbry kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 oct. 11 13:02:48 chatbry kernel: sd 0:0:0:0: [sda] 30081024 512-byte logical blocks: (15.4 GB/14.3 GiB) oct. 11 13:02:48 chatbry kernel: sd 0:0:0:0: [sda] Write Protect is off oct. 11 13:02:48 chatbry kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00 oct. 11 13:02:48 chatbry kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA oct. 11 13:02:48 chatbry kernel: sda: sda1 oct. 11 13:02:48 chatbry kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk oct. 11 13:02:49 chatbry volumio[1137]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded oct. 11 13:02:49 chatbry volumio[1137]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services oct. 11 13:02:49 chatbry volumio[1137]: info: Streaming services startup oct. 11 13:02:49 chatbry volumio[1137]: info: Starting Streaming Daemon oct. 11 13:02:50 chatbry volumio[1137]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started oct. 11 13:02:50 chatbry sudo[1571]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service oct. 11 13:02:50 chatbry sudo[1571]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 13:02:50 chatbry sudo[1571]: pam_unix(sudo:session): session closed for user root oct. 11 13:02:50 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion oct. 11 13:02:50 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 13:02:50 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled oct. 11 13:02:50 chatbry volumio[1137]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/61BB-782A oct. 11 13:02:50 chatbry upmpdcli[1580]: writing RSA key oct. 11 13:02:50 chatbry volumio[1137]: info: AutoStart - Check #3/20 - VOLUMIO_SYSTEM_STATUS = starting oct. 11 13:02:50 chatbry volumio[1137]: error: Cannot start Volumio Streaming Daemon oct. 11 13:02:50 chatbry volumio[1137]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service oct. 11 13:02:50 chatbry volumio[1137]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. oct. 11 13:02:51 chatbry volumio[1137]: info: Mounting Device 61BB-782A oct. 11 13:02:51 chatbry sudo[1590]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/61BB-782A -o noatime,dmask=0000,fmask=0000,iocharset=utf8 oct. 11 13:02:51 chatbry sudo[1590]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 13:02:51 chatbry kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! oct. 11 13:02:51 chatbry sudo[1590]: pam_unix(sudo:session): session closed for user root oct. 11 13:02:51 chatbry kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. oct. 11 13:02:51 chatbry volumio[1137]: info: Scanning removed location : "USB/61BB-782A" oct. 11 13:02:52 chatbry volumio[1137]: verbose: ControllerMpd::sendMpdCommand status oct. 11 13:02:52 chatbry volumio[1137]: info: oct. 11 13:02:52 chatbry volumio[1137]: ---------------------------- MPD announces state update: update oct. 11 13:02:52 chatbry volumio[1137]: info: ControllerMpd::getState oct. 11 13:02:52 chatbry volumio[1137]: verbose: ControllerMpd::sendMpdCommand status oct. 11 13:02:52 chatbry volumio[1137]: verbose: ControllerMpd::sendMpdCommand status oct. 11 13:02:52 chatbry volumio[1137]: info: oct. 11 13:02:52 chatbry volumio[1137]: ---------------------------- MPD announces state update: update oct. 11 13:02:52 chatbry volumio[1137]: info: ControllerMpd::getState oct. 11 13:02:52 chatbry volumio[1137]: verbose: ControllerMpd::sendMpdCommand status oct. 11 13:02:52 chatbry volumio[1137]: verbose: ControllerMpd::sendMpdCommand status oct. 11 13:02:52 chatbry volumio[1137]: info: oct. 11 13:02:52 chatbry volumio[1137]: ---------------------------- MPD announces state update: update oct. 11 13:02:52 chatbry volumio[1137]: info: ControllerMpd::getState oct. 11 13:02:52 chatbry volumio[1137]: verbose: ControllerMpd::sendMpdCommand status oct. 11 13:02:52 chatbry volumio[1137]: info: sendMpdCommand status took 77 milliseconds oct. 11 13:02:52 chatbry volumio[1137]: info: sendMpdCommand status took 122 milliseconds oct. 11 13:02:52 chatbry volumio[1137]: info: sendMpdCommand status took 122 milliseconds oct. 11 13:02:52 chatbry volumio[1137]: info: sendMpdCommand status took 128 milliseconds oct. 11 13:02:52 chatbry volumio[1137]: verbose: ControllerMpd::parseState oct. 11 13:02:52 chatbry volumio[1137]: info: Command Router : Notfying DB Updatetrue oct. 11 13:02:52 chatbry volumio[1137]: verbose: ControllerMpd::parseState oct. 11 13:02:52 chatbry volumio[1137]: verbose: ControllerMpd::parseState oct. 11 13:02:52 chatbry volumio[1137]: info: Command Router : Notfying DB Updatetrue oct. 11 13:02:52 chatbry volumio[1137]: info: CoreCommandRouter::Close All Modals sent oct. 11 13:02:52 chatbry volumio[1137]: verbose: ControllerMpd::parseState oct. 11 13:02:52 chatbry volumio[1137]: info: ControllerMpd::pushState oct. 11 13:02:52 chatbry volumio[1137]: info: CoreCommandRouter::servicePushState oct. 11 13:02:52 chatbry volumio[1137]: info: CoreStateMachine::pushState oct. 11 13:02:52 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:02:52 chatbry volumio[1137]: info: CoreCommandRouter::volumioPushState oct. 11 13:02:52 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:02:52 chatbry volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 13:02:52 chatbry volumio[1137]: info: ControllerMpd::pushState oct. 11 13:02:52 chatbry volumio[1137]: info: CoreCommandRouter::servicePushState oct. 11 13:02:52 chatbry volumio[1137]: info: CoreStateMachine::pushState oct. 11 13:02:52 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:02:52 chatbry volumio[1137]: info: CoreCommandRouter::volumioPushState oct. 11 13:02:52 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:02:52 chatbry volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 13:02:52 chatbry volumio[1137]: info: ------------------------------ 645ms oct. 11 13:02:52 chatbry volumio[1137]: info: ------------------------------ 642ms oct. 11 13:02:52 chatbry volumio[1137]: info: sendMpdCommand status took 646 milliseconds oct. 11 13:02:52 chatbry volumio[1137]: info: sendMpdCommand status took 645 milliseconds oct. 11 13:02:52 chatbry volumio[1137]: verbose: ControllerMpd::parseState oct. 11 13:02:53 chatbry volumio[1137]: info: Command Router : Notfying DB Updatetrue oct. 11 13:02:53 chatbry volumio[1137]: info: CoreCommandRouter::Close All Modals sent oct. 11 13:02:53 chatbry volumio[1137]: verbose: ControllerMpd::parseState oct. 11 13:02:53 chatbry volumio[1137]: info: ControllerMpd::pushState oct. 11 13:02:53 chatbry volumio[1137]: info: CoreCommandRouter::servicePushState oct. 11 13:02:53 chatbry volumio[1137]: info: CoreStateMachine::pushState oct. 11 13:02:53 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:02:53 chatbry volumio[1137]: info: CoreCommandRouter::volumioPushState oct. 11 13:02:53 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:02:53 chatbry volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 13:02:53 chatbry volumio[1137]: info: ------------------------------ 881ms oct. 11 13:02:54 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled oct. 11 13:02:54 chatbry volumio[1137]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN oct. 11 13:02:56 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings oct. 11 13:02:56 chatbry volumio[1137]: info: MPD Database updated - AlbumList cache refreshed oct. 11 13:02:56 chatbry volumio[1137]: info: oct. 11 13:02:56 chatbry volumio[1137]: ---------------------------- MPD announces state update: database oct. 11 13:02:56 chatbry volumio[1137]: verbose: ControllerMpd::sendMpdCommand status oct. 11 13:02:56 chatbry volumio[1137]: info: oct. 11 13:02:56 chatbry volumio[1137]: ---------------------------- MPD announces state update: update oct. 11 13:02:56 chatbry volumio[1137]: info: ControllerMpd::getState oct. 11 13:02:56 chatbry volumio[1137]: verbose: ControllerMpd::sendMpdCommand status oct. 11 13:02:56 chatbry volumio[1137]: info: ControllerMpd::getState oct. 11 13:02:56 chatbry volumio[1137]: verbose: ControllerMpd::sendMpdCommand status oct. 11 13:02:56 chatbry volumio[1137]: info: MPD Database updated - AlbumList cache refreshed oct. 11 13:02:56 chatbry volumio[1137]: info: oct. 11 13:02:56 chatbry volumio[1137]: ---------------------------- MPD announces state update: database oct. 11 13:02:56 chatbry volumio[1137]: verbose: ControllerMpd::sendMpdCommand status oct. 11 13:02:56 chatbry volumio[1137]: info: oct. 11 13:02:56 chatbry volumio[1137]: ---------------------------- MPD announces state update: update oct. 11 13:02:56 chatbry volumio[1137]: info: ControllerMpd::getState oct. 11 13:02:56 chatbry volumio[1137]: verbose: ControllerMpd::sendMpdCommand status oct. 11 13:02:56 chatbry volumio[1137]: info: ControllerMpd::getState oct. 11 13:02:56 chatbry volumio[1137]: verbose: ControllerMpd::sendMpdCommand status oct. 11 13:02:56 chatbry volumio[1137]: info: MPD Database updated - AlbumList cache refreshed oct. 11 13:02:56 chatbry volumio[1137]: info: oct. 11 13:02:56 chatbry volumio[1137]: ---------------------------- MPD announces state update: database oct. 11 13:02:56 chatbry volumio[1137]: verbose: ControllerMpd::sendMpdCommand status oct. 11 13:02:56 chatbry volumio[1137]: info: oct. 11 13:02:56 chatbry volumio[1137]: ---------------------------- MPD announces state update: update oct. 11 13:02:56 chatbry volumio[1137]: info: ControllerMpd::getState oct. 11 13:02:56 chatbry volumio[1137]: verbose: ControllerMpd::sendMpdCommand status oct. 11 13:02:56 chatbry volumio[1137]: info: ControllerMpd::getState oct. 11 13:02:56 chatbry volumio[1137]: verbose: ControllerMpd::sendMpdCommand status oct. 11 13:02:56 chatbry volumio[1137]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 oct. 11 13:02:56 chatbry volumio[1137]: info: MyVolumio token set successfully oct. 11 13:02:56 chatbry volumio[1137]: info: MYVOLUMIO: Adding device oct. 11 13:02:56 chatbry volumio[1137]: info: MYVOLUMIO: Evaluating Server oct. 11 13:02:57 chatbry volumio[1137]: info: sendMpdCommand status took 1500 milliseconds oct. 11 13:02:57 chatbry volumio[1137]: info: sendMpdCommand status took 1500 milliseconds oct. 11 13:02:57 chatbry volumio[1137]: info: sendMpdCommand status took 1463 milliseconds oct. 11 13:02:57 chatbry volumio[1137]: verbose: ControllerMpd::parseState oct. 11 13:02:57 chatbry volumio[1137]: info: Command Router : Notfying DB Updatefalse oct. 11 13:02:57 chatbry volumio[1137]: info: CoreCommandRouter::Close All Modals sent oct. 11 13:02:57 chatbry volumio[1137]: verbose: ControllerMpd::parseState oct. 11 13:02:57 chatbry volumio[1137]: verbose: ControllerMpd::parseState oct. 11 13:02:57 chatbry volumio[1137]: info: ControllerMpd::pushState oct. 11 13:02:57 chatbry volumio[1137]: info: CoreCommandRouter::servicePushState oct. 11 13:02:57 chatbry volumio[1137]: info: CoreStateMachine::pushState oct. 11 13:02:57 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:02:57 chatbry volumio[1137]: info: CoreCommandRouter::volumioPushState oct. 11 13:02:57 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:02:57 chatbry volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 13:02:57 chatbry volumio[1137]: info: ControllerMpd::pushState oct. 11 13:02:57 chatbry volumio[1137]: info: CoreCommandRouter::servicePushState oct. 11 13:02:57 chatbry volumio[1137]: info: CoreStateMachine::pushState oct. 11 13:02:57 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:02:57 chatbry volumio[1137]: info: CoreCommandRouter::volumioPushState oct. 11 13:02:57 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:02:57 chatbry volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 13:02:58 chatbry volumio[1137]: info: ------------------------------ 1797ms oct. 11 13:02:58 chatbry volumio[1137]: info: ------------------------------ 1796ms oct. 11 13:02:59 chatbry volumio[1137]: info: sendMpdCommand status took 2913 milliseconds oct. 11 13:02:59 chatbry volumio[1137]: info: sendMpdCommand status took 2896 milliseconds oct. 11 13:02:59 chatbry volumio[1137]: info: sendMpdCommand status took 2895 milliseconds oct. 11 13:02:59 chatbry volumio[1137]: verbose: ControllerMpd::parseState oct. 11 13:02:59 chatbry volumio[1137]: info: Command Router : Notfying DB Updatefalse oct. 11 13:02:59 chatbry volumio[1137]: info: CoreCommandRouter::Close All Modals sent oct. 11 13:02:59 chatbry volumio[1137]: verbose: ControllerMpd::parseState oct. 11 13:02:59 chatbry volumio[1137]: verbose: ControllerMpd::parseState oct. 11 13:02:59 chatbry volumio[1137]: info: ControllerMpd::pushState oct. 11 13:02:59 chatbry volumio[1137]: info: CoreCommandRouter::servicePushState oct. 11 13:02:59 chatbry volumio[1137]: info: CoreStateMachine::pushState oct. 11 13:02:59 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:02:59 chatbry volumio[1137]: info: CoreCommandRouter::volumioPushState oct. 11 13:02:59 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:02:59 chatbry volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 13:02:59 chatbry volumio[1137]: info: ControllerMpd::pushState oct. 11 13:02:59 chatbry volumio[1137]: info: CoreCommandRouter::servicePushState oct. 11 13:02:59 chatbry volumio[1137]: info: CoreStateMachine::pushState oct. 11 13:02:59 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:02:59 chatbry volumio[1137]: info: CoreCommandRouter::volumioPushState oct. 11 13:02:59 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:02:59 chatbry volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 13:02:59 chatbry volumio[1137]: info: ------------------------------ 3189ms oct. 11 13:02:59 chatbry volumio[1137]: info: ------------------------------ 3217ms oct. 11 13:02:59 chatbry volumio-remote-updater[614]: [2026-10-11 13:02:59] [connect] Successful connection oct. 11 13:03:01 chatbry volumio[1137]: info: sendMpdCommand status took 5028 milliseconds oct. 11 13:03:01 chatbry volumio[1137]: info: sendMpdCommand status took 5026 milliseconds oct. 11 13:03:01 chatbry volumio[1137]: info: sendMpdCommand status took 5025 milliseconds oct. 11 13:03:01 chatbry volumio[1137]: verbose: ControllerMpd::parseState oct. 11 13:03:01 chatbry volumio[1137]: info: Command Router : Notfying DB Updatefalse oct. 11 13:03:01 chatbry volumio[1137]: info: CoreCommandRouter::Close All Modals sent oct. 11 13:03:01 chatbry volumio[1137]: verbose: ControllerMpd::parseState oct. 11 13:03:01 chatbry volumio[1137]: verbose: ControllerMpd::parseState oct. 11 13:03:01 chatbry volumio[1137]: info: ControllerMpd::pushState oct. 11 13:03:01 chatbry volumio[1137]: info: CoreCommandRouter::servicePushState oct. 11 13:03:01 chatbry volumio[1137]: info: CoreStateMachine::pushState oct. 11 13:03:01 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:03:01 chatbry volumio[1137]: info: CoreCommandRouter::volumioPushState oct. 11 13:03:01 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:03:01 chatbry volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 13:03:01 chatbry volumio[1137]: info: ControllerMpd::pushState oct. 11 13:03:01 chatbry volumio[1137]: info: CoreCommandRouter::servicePushState oct. 11 13:03:01 chatbry volumio[1137]: info: CoreStateMachine::pushState oct. 11 13:03:01 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:03:01 chatbry volumio[1137]: info: CoreCommandRouter::volumioPushState oct. 11 13:03:01 chatbry volumio[1137]: info: CorePlayQueue::getTrack 0 oct. 11 13:03:01 chatbry volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 13:03:01 chatbry volumio[1137]: info: ------------------------------ 5276ms oct. 11 13:03:01 chatbry volumio[1137]: info: ------------------------------ 5276ms oct. 11 13:03:02 chatbry volumio[1137]: info: MyVolumio Plan changed: premium oct. 11 13:03:02 chatbry volumio[1137]: info: [MyVolumio PluginManager] Subscribed plan changed to premium oct. 11 13:03:02 chatbry volumio[1137]: info: Removing browser output: myVolumio user plan is not superstar oct. 11 13:03:02 chatbry volumio[1137]: info: Removing audio output: oct. 11 13:03:02 chatbry volumio[1137]: info: MYVOLUMIO: Adding device oct. 11 13:03:02 chatbry volumio[1137]: info: MYVOLUMIO: Evaluating Server oct. 11 13:03:02 chatbry volumio[1137]: info: AutoStart - Check #4/20 - VOLUMIO_SYSTEM_STATUS = starting oct. 11 13:03:02 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:03:02 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 13:03:02 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam oct. 11 13:03:02 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam oct. 11 13:03:02 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject oct. 11 13:03:02 chatbry volumio[1137]: info: CoreCommandRouter::volumioGetBrowseSources oct. 11 13:03:02 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion oct. 11 13:03:02 chatbry volumio[1137]: info: Setting Geolocation for MyVolumio to eu3 oct. 11 13:03:03 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 13:03:03 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 13:03:03 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 13:03:03 chatbry volumio-remote-updater[614]: [2026-10-11 13:03:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1791716579 101 oct. 11 13:03:03 chatbry volumio[1137]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 7 oct. 11 13:03:03 chatbry volumio[1137]: info: Remote config written successfully oct. 11 13:03:03 chatbry volumio[1137]: info: Starting Tunnel 1 oct. 11 13:03:03 chatbry volumio[1137]: info: Starting Tunnel Connection Checker oct. 11 13:03:03 chatbry volumio[1137]: info: Completed starting MyVolumio Plugin oct. 11 13:03:04 chatbry volumio[1137]: info: MYVolumio Device enabled oct. 11 13:03:04 chatbry volumio[1137]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... oct. 11 13:03:04 chatbry volumio[1137]: info: Updating MyVolumio device info oct. 11 13:03:04 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 13:03:04 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 13:03:04 chatbry volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 13:03:04 chatbry volumio[1137]: [2026-10-11T11:03:04.487Z] @firebase/database: FIREBASE WARNING: Exception was thrown by user callback. TypeError: Cannot read properties of null (reading 'replace') oct. 11 13:03:04 chatbry volumio[1137]: at MyVolumioPluginManager.fetchPluginKeys (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:8044) oct. 11 13:03:04 chatbry volumio[1137]: at MyVolumioPluginManager.loadMyVolumioPlugins (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:11563) oct. 11 13:03:04 chatbry volumio[1137]: at MyVolumioPluginManager.myVolumioDeviceStatusChanged (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:5527) oct. 11 13:03:04 chatbry volumio[1137]: at /myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:6807 oct. 11 13:03:04 chatbry volumio[1137]: at /volumio/node_modules/v-conf/index.js:166:13 oct. 11 13:03:04 chatbry volumio[1137]: at /volumio/node_modules/multimap/index.js:140:9 oct. 11 13:03:04 chatbry volumio[1137]: at Array.forEach () oct. 11 13:03:04 chatbry volumio[1137]: at /volumio/node_modules/multimap/index.js:139:13 oct. 11 13:03:04 chatbry volumio[1137]: at mapEach (/volumio/node_modules/multimap/index.js:9:5) oct. 11 13:03:04 chatbry volumio[1137]: at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5) oct. 11 13:03:04 chatbry volumio[1137]: at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10) oct. 11 13:03:04 chatbry volumio[1137]: at Config.set (/volumio/node_modules/v-conf/index.js:163:20) oct. 11 13:03:04 chatbry volumio[1137]: at myVolumio.togglePremiumPlugins (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:36229) oct. 11 13:03:04 chatbry volumio[1137]: at /myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:17831 oct. 11 13:03:04 chatbry volumio[1137]: at /volumio/node_modules/@firebase/database/dist/index.node.cjs.js:4549:22 oct. 11 13:03:04 chatbry volumio[1137]: at exceptionGuard (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:698:9) oct. 11 13:03:04 chatbry volumio[1137]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| oct. 11 13:03:04 chatbry volumio[1137]: TypeError: Cannot read properties of null (reading 'replace') oct. 11 13:03:04 chatbry volumio[1137]: at MyVolumioPluginManager.fetchPluginKeys (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:8044) oct. 11 13:03:04 chatbry volumio[1137]: at MyVolumioPluginManager.loadMyVolumioPlugins (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:11563) oct. 11 13:03:04 chatbry volumio[1137]: at MyVolumioPluginManager.myVolumioDeviceStatusChanged (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:5527) oct. 11 13:03:04 chatbry volumio[1137]: at /myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:6807 oct. 11 13:03:04 chatbry volumio[1137]: at /volumio/node_modules/v-conf/index.js:166:13 oct. 11 13:03:04 chatbry volumio[1137]: at /volumio/node_modules/multimap/index.js:140:9 oct. 11 13:03:04 chatbry volumio[1137]: at Array.forEach () oct. 11 13:03:04 chatbry volumio[1137]: at /volumio/node_modules/multimap/index.js:139:13 oct. 11 13:03:04 chatbry volumio[1137]: at mapEach (/volumio/node_modules/multimap/index.js:9:5) oct. 11 13:03:04 chatbry volumio[1137]: at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5) oct. 11 13:03:04 chatbry volumio[1137]: at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10) oct. 11 13:03:04 chatbry volumio[1137]: at Config.set (/volumio/node_modules/v-conf/index.js:163:20) oct. 11 13:03:04 chatbry volumio[1137]: at myVolumio.togglePremiumPlugins (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:36229) oct. 11 13:03:04 chatbry volumio[1137]: at /myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:17831 oct. 11 13:03:04 chatbry volumio[1137]: at /volumio/node_modules/@firebase/database/dist/index.node.cjs.js:4549:22 oct. 11 13:03:04 chatbry volumio[1137]: at exceptionGuard (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:698:9) oct. 11 13:03:04 chatbry volumio[1137]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| oct. 11 13:03:10 chatbry sudo[1703]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-11 13:02' oct. 11 13:03:10 chatbry sudo[1703]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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"