May 24 18:55:09 volumio volumio[1445]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object] May 24 18:55:09 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings May 24 18:55:10 volumio sudo[4137]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf May 24 18:55:10 volumio sudo[4137]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 18:55:10 volumio sudo[4137]: pam_unix(sudo:session): session closed for user root May 24 18:55:10 volumio volumio[1445]: info: Permissions for /etc/hostapd/hostapd.conf May 24 18:55:10 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 24 18:55:10 volumio sudo[4140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service May 24 18:55:10 volumio sudo[4140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 18:55:10 volumio systemd[1]: Stopping wireless.service - Wireless Services... May 24 18:55:10 volumio systemd[1]: wireless.service: Killing process 3187 (wpa_supplicant) with signal SIGKILL. May 24 18:55:10 volumio systemd[1]: wireless.service: Deactivated successfully. May 24 18:55:10 volumio systemd[1]: Stopped wireless.service - Wireless Services. May 24 18:55:10 volumio systemd[1]: Starting wireless.service - Wireless Services... May 24 18:55:10 volumio wireless.js[4143]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless May 24 18:55:10 volumio wireless.js[4143]: WIRELESS.JS - INFO: === SNM TRANSITION === May 24 18:55:10 volumio wireless.js[4143]: WIRELESS.JS - INFO: Previous ethernet state: disconnected May 24 18:55:10 volumio wireless.js[4143]: WIRELESS.JS - INFO: New ethernet state: connected May 24 18:55:10 volumio wireless.js[4143]: WIRELESS.JS - INFO: Single Network Mode: enabled May 24 18:55:10 volumio wireless.js[4143]: WIRELESS.JS - INFO: First start: yes May 24 18:55:10 volumio wireless.js[4143]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) May 24 18:55:10 volumio wireless.js[4143]: WIRELESS.JS - INFO: === END TRANSITION === May 24 18:55:10 volumio wireless.js[4143]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow May 24 18:55:10 volumio sudo[4167]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 24 18:55:10 volumio sudo[4167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 24 18:55:10 volumio sudo[4167]: pam_unix(sudo:session): session closed for user root May 24 18:55:10 volumio sudo[4169]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 24 18:55:10 volumio sudo[4169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 24 18:55:10 volumio sudo[4169]: pam_unix(sudo:session): session closed for user root May 24 18:55:10 volumio wireless.js[4143]: WIRELESS.JS - INFO: Cleaning previous... May 24 18:55:10 volumio sudo[4172]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 24 18:55:10 volumio sudo[4172]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 24 18:55:10 volumio sudo[4172]: pam_unix(sudo:session): session closed for user root May 24 18:55:10 volumio wireless.js[4143]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations May 24 18:55:10 volumio wireless.js[4143]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 2ms May 24 18:55:10 volumio wireless.js[4143]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: 34:75:63:e6:a0:70) May 24 18:55:10 volumio sudo[4179]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 24 18:55:10 volumio sudo[4179]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 18:55:10 volumio sudo[4179]: pam_unix(sudo:session): session closed for user root May 24 18:55:10 volumio sudo[4187]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 24 18:55:10 volumio sudo[4187]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 18:55:11 volumio sudo[4187]: pam_unix(sudo:session): session closed for user root May 24 18:55:11 volumio wireless.js[4143]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: BR May 24 18:55:11 volumio sudo[4195]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set BR May 24 18:55:11 volumio sudo[4195]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 18:55:11 volumio sudo[4195]: pam_unix(sudo:session): session closed for user root May 24 18:55:11 volumio wireless.js[4143]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: BR May 24 18:55:11 volumio wireless.js[4143]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability May 24 18:55:11 volumio wireless.js[4143]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) May 24 18:55:11 volumio wireless.js[4143]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active May 24 18:55:11 volumio sudo[4204]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 24 18:55:11 volumio sudo[4204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 24 18:55:11 volumio sudo[4204]: pam_unix(sudo:session): session closed for user root May 24 18:55:11 volumio sudo[4207]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 24 18:55:11 volumio sudo[4207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 24 18:55:11 volumio sudo[4207]: pam_unix(sudo:session): session closed for user root May 24 18:55:11 volumio wpa_supplicant[4210]: Successfully initialized wpa_supplicant May 24 18:55:11 volumio wpa_supplicant[4211]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all May 24 18:55:11 volumio wireless.js[4143]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 1176ms May 24 18:55:11 volumio wireless.js[4143]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable May 24 18:55:11 volumio wireless.js[4143]: WIRELESS.JS - INFO: Notified systemd about wireless ready May 24 18:55:11 volumio iwconfig[4226]: Error for wireless request "Set Power Management" (8B2C) : May 24 18:55:11 volumio iwconfig[4226]: SET failed on device wlan0 ; Operation not supported. May 24 18:55:11 volumio systemd[1]: Started wireless.service - Wireless Services. May 24 18:55:11 volumio sudo[4140]: pam_unix(sudo:session): session closed for user root May 24 18:55:16 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection May 24 18:55:16 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo May 24 18:55:16 volumio sudo[4244]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 18:55:16 volumio sudo[4244]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 18:55:16 volumio sudo[4244]: pam_unix(sudo:session): session closed for user root May 24 18:55:17 volumio volumio[1445]: info: CoreCommandRouter::volumioGetState May 24 18:55:17 volumio volumio[1445]: info: CorePlayQueue::getTrack 0 May 24 18:55:20 volumio volumio[1445]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 May 24 18:55:20 volumio volumio[1445]: info: Discovery: Networking Restart detected, restarting advertisement and browsing May 24 18:55:20 volumio volumio[1445]: info: Discovery: Restarting Advertising May 24 18:55:20 volumio volumio[1445]: info: Discovery: Stopping existing advertisement May 24 18:55:20 volumio volumio[1445]: info: Discovery: Restarting Browsing May 24 18:55:21 volumio volumio[1445]: info: Discovery: A device disappeared from network May 24 18:55:21 volumio volumio[1445]: info: Discovery: A device disappeared from network May 24 18:55:21 volumio volumio[1445]: info: Discovery: A device disappeared from network May 24 18:55:21 volumio volumio[1445]: info: Discovery: A device disappeared from network May 24 18:55:21 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart May 24 18:55:21 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart May 24 18:55:21 volumio volumio[1445]: info: Refreshing Cached IP Addresses May 24 18:55:21 volumio sudo[4266]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli May 24 18:55:21 volumio sudo[4266]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 18:55:21 volumio sudo[4268]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 18:55:21 volumio sudo[4268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 18:55:21 volumio sudo[4268]: pam_unix(sudo:session): session closed for user root May 24 18:55:21 volumio sudo[4266]: pam_unix(sudo:session): session closed for user root May 24 18:55:21 volumio sudo[4270]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 18:55:21 volumio sudo[4270]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 18:55:21 volumio sudo[4270]: pam_unix(sudo:session): session closed for user root May 24 18:55:22 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 24 18:55:22 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 24 18:55:24 volumio volumio[1445]: info: CoreCommandRouter::getUIConfigOnPlugin May 24 18:55:24 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 24 18:55:24 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 24 18:55:24 volumio sudo[4286]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 24 18:55:24 volumio sudo[4286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 18:55:24 volumio sudo[4281]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 24 18:55:24 volumio sudo[4281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 18:55:24 volumio sudo[4281]: pam_unix(sudo:session): session closed for user root May 24 18:55:24 volumio sudo[4286]: pam_unix(sudo:session): session closed for user root May 24 18:55:24 volumio sudo[4293]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 24 18:55:24 volumio sudo[4293]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 18:55:24 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 24 18:55:24 volumio sudo[4293]: pam_unix(sudo:session): session closed for user root May 24 18:55:24 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 24 18:55:24 volumio sudo[4298]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 24 18:55:24 volumio sudo[4298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 18:55:24 volumio sudo[4302]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 18:55:24 volumio sudo[4302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 18:55:24 volumio sudo[4298]: pam_unix(sudo:session): session closed for user root May 24 18:55:24 volumio sudo[4302]: pam_unix(sudo:session): session closed for user root May 24 18:55:24 volumio sudo[4308]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 18:55:24 volumio sudo[4308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 18:55:24 volumio sudo[4308]: pam_unix(sudo:session): session closed for user root May 24 18:55:24 volumio sudo[4312]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 24 18:55:24 volumio sudo[4312]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 18:55:25 volumio volumio[1445]: info: Discovery: Started advertising with name: Volumio May 24 18:55:25 volumio sudo[4312]: pam_unix(sudo:session): session closed for user root May 24 18:55:26 volumio volumio[1445]: info: Discovery: adding 5527b6ea-ba42-42f0-aa2e-e5a415016886 May 24 18:55:26 volumio volumio[1445]: info: Discovery: Found device Volumio May 24 18:55:26 volumio volumio[1445]: info: CoreCommandRouter::volumioGetState May 24 18:55:26 volumio volumio[1445]: info: CorePlayQueue::getTrack 0 May 24 18:55:26 volumio volumio[1445]: info: Discovery: this is already registered, 5527b6ea-ba42-42f0-aa2e-e5a415016886 May 24 18:55:26 volumio volumio[1445]: info: Discovery: Found device Volumio May 24 18:55:26 volumio volumio[1445]: info: CoreCommandRouter::volumioGetState May 24 18:55:26 volumio volumio[1445]: info: CorePlayQueue::getTrack 0 May 24 18:55:26 volumio volumio[1445]: info: Discovery: this is already registered, 5527b6ea-ba42-42f0-aa2e-e5a415016886 May 24 18:55:26 volumio volumio[1445]: info: Discovery: Found device Volumio May 24 18:55:26 volumio volumio[1445]: info: CoreCommandRouter::volumioGetState May 24 18:55:26 volumio volumio[1445]: info: CorePlayQueue::getTrack 0 May 24 18:55:26 volumio volumio[1445]: info: Discovery: this is already registered, 5527b6ea-ba42-42f0-aa2e-e5a415016886 May 24 18:55:26 volumio volumio[1445]: info: Discovery: Found device Volumio May 24 18:55:26 volumio volumio[1445]: info: CoreCommandRouter::volumioGetState May 24 18:55:26 volumio volumio[1445]: info: CorePlayQueue::getTrack 0 May 24 18:55:26 volumio volumio[1445]: info: Discovery: this is already registered, 5527b6ea-ba42-42f0-aa2e-e5a415016886 May 24 18:55:26 volumio volumio[1445]: info: Discovery: Found device Volumio May 24 18:55:26 volumio volumio[1445]: info: CoreCommandRouter::volumioGetState May 24 18:55:26 volumio volumio[1445]: info: CorePlayQueue::getTrack 0 May 24 18:55:26 volumio volumio[1445]: info: Discovery: this is already registered, 5527b6ea-ba42-42f0-aa2e-e5a415016886 May 24 18:55:26 volumio volumio[1445]: info: Discovery: Found device Volumio May 24 18:55:26 volumio volumio[1445]: info: CoreCommandRouter::volumioGetState May 24 18:55:26 volumio volumio[1445]: info: CorePlayQueue::getTrack 0 May 24 18:55:29 volumio volumio[1445]: info: CoreCommandRouter::volumioGetState May 24 18:55:29 volumio volumio[1445]: info: CorePlayQueue::getTrack 0 May 24 18:55:31 volumio sudo[4345]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 18:55:31 volumio sudo[4345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 18:55:31 volumio sudo[4345]: pam_unix(sudo:session): session closed for user root May 24 18:55:31 volumio sudo[4347]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 18:55:31 volumio sudo[4347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 18:55:31 volumio sudo[4347]: pam_unix(sudo:session): session closed for user root May 24 18:55:31 volumio sudo[4350]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 24 18:55:31 volumio sudo[4350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 18:55:32 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 24 18:55:32 volumio volumio[1445]: info: CURURI: music-library May 24 18:55:32 volumio volumio[1445]: error: Failed to execute browseSource, failure: TypeError: Cannot read properties of undefined (reading 'then') May 24 18:55:35 volumio volumio[1445]: info: CoreCommandRouter::volumioGetState May 24 18:55:35 volumio volumio[1445]: info: CorePlayQueue::getTrack 0 May 24 18:55:37 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. May 24 18:55:37 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 24 18:55:37 volumio sudo[4350]: pam_unix(sudo:session): session closed for user root May 24 18:55:37 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 24 18:55:37 volumio volumio[1445]: info: Upmpdcli Daemon Started May 24 18:55:37 volumio volumio[1445]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 May 24 18:55:41 volumio volumio[1445]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 May 24 18:55:44 volumio volumio[1445]: info: CoreCommandRouter::volumioGetState May 24 18:55:44 volumio volumio[1445]: info: CorePlayQueue::getTrack 0 May 24 18:55:46 volumio volumio[1445]: info: CoreCommandRouter::getUIConfigOnPlugin May 24 18:55:46 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 24 18:55:46 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 24 18:55:46 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 24 18:55:46 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 24 18:55:46 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 18:55:46 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 18:55:46 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 18:55:46 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 18:55:46 volumio volumio[1445]: info: CoreCommandRouter::volumioGetBrowseSources May 24 18:55:46 volumio volumio[1445]: info: CoreCommandRouter::volumioGetBrowseSources May 24 18:55:46 volumio volumio[1445]: info: CoreCommandRouter::volumioGetBrowseSources May 24 18:55:46 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 24 18:55:46 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 24 18:55:47 volumio volumio[1445]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 May 24 18:55:50 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 24 18:55:54 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 24 18:55:57 volumio volumio[1445]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 May 24 18:55:58 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 24 18:56:02 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 24 18:56:04 volumio volumio[1445]: info: CALLMETHOD: music_service mpd saveMusicLibraryOptions [object Object] May 24 18:56:04 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: mpd , saveMusicLibraryOptions May 24 18:56:06 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 24 18:56:10 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 24 18:56:14 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 24 18:56:15 volumio volumio[1445]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 May 24 18:56:18 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 24 18:56:19 volumio volumio[1445]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 24 18:56:19 volumio volumio[1445]: verbose: ControllerMpd::sendMpdCommand rescan May 24 18:56:19 volumio volumio[1445]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 18:56:19 volumio volumio[1445]: TypeError: Cannot read properties of undefined (reading 'then') May 24 18:56:19 volumio volumio[1445]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:332:6) May 24 18:56:19 volumio volumio[1445]: at ControllerMpd.rescanDb (/volumio/app/plugins/music_service/mpd/index.js:1899:15) May 24 18:56:19 volumio volumio[1445]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) May 24 18:56:19 volumio volumio[1445]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1098:26) May 24 18:56:19 volumio volumio[1445]: at Socket.emit (node:events:514:28) May 24 18:56:19 volumio volumio[1445]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 24 18:56:19 volumio volumio[1445]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) May 24 18:56:19 volumio volumio[1445]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 18:56:20 volumio sudo[4502]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-24 18:55' May 24 18:56:20 volumio sudo[4502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 18:56:20 volumio sudo[4502]: pam_unix(sudo:session): session closed for user root May 24 18:56:21 volumio volumio-remote-updater[806]: [2026-05-24 18:56:21] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 24 18:56:21 volumio volumio-remote-updater[806]: [2026-05-24 18:56:21] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 24 18:56:21 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 24 18:56:21 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 24 18:56:21 volumio systemd[1]: volumio.service: Consumed 13.670s CPU time. May 24 18:56:21 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. May 24 18:56:21 volumio systemd[1]: dynamicswap.service: Deactivated successfully. May 24 18:56:21 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. May 24 18:56:21 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. May 24 18:56:21 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. May 24 18:56:21 volumio systemd[1]: volumio.service: Consumed 13.670s CPU time. May 24 18:56:21 volumio systemd[1]: Started volumio.service - Volumio Backend Module. May 24 18:56:21 volumio systemd[1]: dynamicswap.service: Deactivated successfully. May 24 18:56:21 volumio volumio[4527]: info: ------------------------------------------- May 24 18:56:21 volumio volumio[4527]: info: ----- Volumio3 ---- May 24 18:56:21 volumio volumio[4527]: info: ------------------------------------------- May 24 18:56:21 volumio volumio[4527]: info: ----- System startup ---- May 24 18:56:21 volumio volumio[4527]: info: ------------------------------------------- May 24 18:56:22 volumio volumio[4527]: info: MYVOLUMIO Environment detected May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin music_service/airplay_emulation. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin music_service/inputs. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin music_service/mpd. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin music_service/webradio. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin music_service/example_plugin. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin music_service/last_100. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin music_service/upnp_browser. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin audio_interface/outputs. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin audio_interface/upnp. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin audio_interface/alsa_controller. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin miscellanea/alarm-clock. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin miscellanea/albumart. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin miscellanea/appearance. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin miscellanea/wizard. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin miscellanea/my_music. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin user_interface/websocket. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin user_interface/mpdemulation. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin user_interface/rest_api. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin system_controller/updater_comm. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin system_controller/network. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin system_controller/networkfs. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin system_controller/services. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin system_controller/system. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin system_controller/i2s_dacs. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin system_controller/volumiodiscovery. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin system_controller/volumio_command_line_client. Adding it May 24 18:56:22 volumio volumio[4527]: info: Found new core plugin system_controller/volumio5onboarding. Adding it May 24 18:56:22 volumio volumio[4527]: info: Plugin folders cleanup May 24 18:56:22 volumio volumio[4527]: info: Scanning into folder /volumio/app/plugins/ May 24 18:56:22 volumio volumio[4527]: info: Scanning category audio_interface May 24 18:56:22 volumio volumio[4527]: info: Scanning category miscellanea May 24 18:56:22 volumio volumio[4527]: info: Scanning category music_service May 24 18:56:22 volumio volumio[4527]: info: Scanning category plugins.json May 24 18:56:22 volumio volumio[4527]: info: Scanning category system_controller May 24 18:56:22 volumio volumio[4527]: info: Scanning category user_interface May 24 18:56:22 volumio volumio[4527]: info: Scanning into folder /data/plugins/ May 24 18:56:22 volumio volumio[4527]: info: Scanning category user_interface May 24 18:56:22 volumio volumio[4527]: info: Plugin folders cleanup completed May 24 18:56:22 volumio volumio[4527]: info: ------------------------------------------- May 24 18:56:22 volumio volumio[4527]: info: ----- Core plugins startup ---- May 24 18:56:22 volumio volumio[4527]: info: ------------------------------------------- May 24 18:56:22 volumio volumio[4527]: info: Loading plugins from folder /volumio/app/plugins/ May 24 18:56:22 volumio volumio[4527]: info: Adding plugin upnp to MyMusic Plugins May 24 18:56:22 volumio volumio[4527]: info: Adding plugin airplay_emulation to MyMusic Plugins May 24 18:56:22 volumio volumio[4527]: info: Adding plugin upnp_browser to MyMusic Plugins May 24 18:56:22 volumio volumio[4527]: info: Loading plugins from folder /data/plugins/ May 24 18:56:22 volumio volumio[4527]: info: Loading plugin "system"... May 24 18:56:22 volumio volumio[4527]: error: Could not copy default configuration to /data/configuration/system_controller/system/config.json May 24 18:56:22 volumio volumio[4527]: info: No id defined. Creating one May 24 18:56:22 volumio volumio[4527]: info: Loading plugin "appearance"... May 24 18:56:22 volumio volumio[4527]: info: Loading plugin "network"... May 24 18:56:22 volumio volumio[4527]: info: Refreshing Cached IP Addresses May 24 18:56:22 volumio sudo[4556]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 18:56:22 volumio sudo[4556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 18:56:22 volumio sudo[4556]: pam_unix(sudo:session): session closed for user root May 24 18:56:22 volumio sudo[4558]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 18:56:22 volumio volumio[4527]: info: Loading plugin "services"... May 24 18:56:22 volumio volumio[4527]: info: Loading plugin "volumio5onboarding"... May 24 18:56:22 volumio sudo[4558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 18:56:22 volumio sudo[4558]: pam_unix(sudo:session): session closed for user root May 24 18:56:22 volumio volumio[4527]: info: Loading plugin "alsa_controller"... May 24 18:56:22 volumio sudo[4566]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 24 18:56:22 volumio sudo[4566]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 18:56:22 volumio volumio[4527]: error: Could not copy default configuration to /data/configuration/audio_interface/alsa_controller/config.json May 24 18:56:22 volumio volumio[4527]: warn: The ALSA output card is not set, defaulting to card [object Object] May 24 18:56:22 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 24 18:56:22 volumio volumio[4527]: info: Found match in Cards Database: setting mixer for card Analog Out May 24 18:56:22 volumio volumio[4527]: info: Setting mixer Master for card Analog Out May 24 18:56:22 volumio volumio[4527]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 24 18:56:22 volumio volumio[4527]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 24 18:56:22 volumio volumio[4527]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 24 18:56:22 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 18:56:22 volumio volumio[4527]: info: Loading plugin "wizard"... May 24 18:56:22 volumio volumio[4527]: info: Loading plugin "networkfs"... May 24 18:56:22 volumio volumio[4527]: error: Could not mount NAS: Error: ENOSPC: no space left on device, mkdir '/mnt/NAS/externo' May 24 18:56:22 volumio volumio[4527]: info: Starting Udev Watcher for removable devices May 24 18:56:22 volumio volumio[4527]: info: Ignoring mount for partition: SYSTEM May 24 18:56:22 volumio volumio[4527]: error: Cannot associate FS Label, not mounting May 24 18:56:22 volumio volumio[4527]: error: Cannot associate FS Label, not mounting May 24 18:56:22 volumio volumio[4527]: info: Ignoring mount for partition: Windows_RE_Tools May 24 18:56:22 volumio volumio[4527]: info: Mounting Device 32_GB May 24 18:56:22 volumio sudo[4618]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/32_GB -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 24 18:56:22 volumio sudo[4618]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 18:56:22 volumio sudo[4618]: pam_unix(sudo:session): session closed for user root May 24 18:56:22 volumio volumio[4527]: mount: /media/32_GB: /dev/sda1 already mounted on /media/32_GB. May 24 18:56:22 volumio volumio[4527]: dmesg(1) may have more information after failed mount system call. May 24 18:56:22 volumio volumio[4527]: error: Failed to mount 32_GB: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/32_GB" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 24 18:56:22 volumio volumio[4527]: mount: /media/32_GB: /dev/sda1 already mounted on /media/32_GB. May 24 18:56:22 volumio volumio[4527]: dmesg(1) may have more information after failed mount system call. May 24 18:56:22 volumio volumio[4527]: info: Ignoring mount for partition: boot May 24 18:56:22 volumio volumio[4527]: info: Ignoring mount for partition: volumio May 24 18:56:22 volumio volumio[4527]: info: Ignoring mount for partition: volumio_data May 24 18:56:22 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 18:56:22 volumio volumio[4527]: info: Loading plugin "volumio_command_line_client"... May 24 18:56:22 volumio volumio[4527]: info: Loading plugin "upnp"... May 24 18:56:22 volumio volumio[4527]: info: [1779659782749] Starting Upmpd Daemon May 24 18:56:22 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 18:56:22 volumio volumio[4527]: info: Loading plugin "my_music"... May 24 18:56:22 volumio volumio[4527]: info: Loading plugin "mpd"... May 24 18:56:22 volumio volumio[4527]: info: Loading plugin "upnp_browser"... May 24 18:56:23 volumio volumio[4527]: info: Starting UPNP Browser May 24 18:56:23 volumio volumio[4527]: info: Loading plugin "alarm-clock"... May 24 18:56:23 volumio volumio[4527]: info: Loading plugin "airplay_emulation"... May 24 18:56:23 volumio volumio[4527]: info: Starting Shairport Sync May 24 18:56:23 volumio volumio[4527]: info: Loading plugin "last_100"... May 24 18:56:23 volumio volumio[4527]: info: Loading plugin "webradio"... May 24 18:56:23 volumio volumio[4527]: info: Loading plugin "i2s_dacs"... May 24 18:56:23 volumio volumio[4527]: info: I2S DAC not set, start Auto-detection May 24 18:56:23 volumio volumio[4527]: info: Loading plugin "volumiodiscovery"... May 24 18:56:23 volumio volumio[4527]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 18:56:23 volumio node[4527]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 18:56:23 volumio volumio[4527]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 18:56:23 volumio node[4527]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 18:56:23 volumio volumio[4527]: *** WARNING *** For more information see May 24 18:56:23 volumio node[4527]: *** WARNING *** For more information see May 24 18:56:23 volumio volumio[4527]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 24 18:56:23 volumio node[4527]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 24 18:56:23 volumio volumio[4527]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 18:56:23 volumio node[4527]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 18:56:23 volumio volumio[4527]: *** WARNING *** For more information see May 24 18:56:23 volumio node[4527]: *** WARNING *** For more information see May 24 18:56:23 volumio volumio[4527]: info: Applying required configuration parameters for plugin volumiodiscovery May 24 18:56:23 volumio volumio[4527]: info: Discovery: Started advertising with name: undefined May 24 18:56:23 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 18:56:23 volumio volumio[4527]: info: Loading plugin "outputs"... May 24 18:56:23 volumio volumio[4527]: info: Loading plugin "albumart"... May 24 18:56:23 volumio volumio[4527]: info: Plugin example_plugin is not enabled May 24 18:56:23 volumio volumio[4527]: info: Loading plugin "inputs"... May 24 18:56:23 volumio volumio[4527]: info: Loading plugin "updater_comm"... May 24 18:56:23 volumio sudo[4566]: pam_unix(sudo:session): session closed for user root May 24 18:56:23 volumio volumio[4527]: info: Plugin mpdemulation is not enabled May 24 18:56:23 volumio volumio[4527]: info: Loading plugin "rest_api"... May 24 18:56:23 volumio volumio[4527]: info: Loading plugin "websocket"... May 24 18:56:23 volumio volumio[4527]: info: Starting Socket.io Server version 1.7.4 May 24 18:56:23 volumio volumio[4527]: info: Plugin peppyspectrum is not enabled May 24 18:56:23 volumio volumio[4527]: info: Loading i18n strings for locale pt May 24 18:56:23 volumio volumio[4527]: Updating browse sources language May 24 18:56:23 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 18:56:23 volumio volumio[4623]: Forking 1 albumart workers May 24 18:56:23 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 18:56:23 volumio volumio[4527]: info: CoreCommandRouter::initPlayerControls May 24 18:56:23 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 18:56:23 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 18:56:23 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 18:56:23 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 18:56:23 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 18:56:23 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 18:56:23 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 18:56:23 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 18:56:23 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 18:56:23 volumio volumio[4527]: Express server listening on port 3000 May 24 18:56:23 volumio volumio[4527]: [Metrics] WebUI: 1s 981.89ms May 24 18:56:23 volumio volumio[4527]: info: Setting Device type: x86 May 24 18:56:23 volumio volumio[4527]: info: CoreStateMachine::resetVolumioState May 24 18:56:23 volumio volumio[4527]: info: CoreStateMachine::getcurrentVolume May 24 18:56:23 volumio volumio[4527]: info: CoreCommandRouter::volumioRetrievevolume May 24 18:56:23 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 18:56:23 volumio volumio[4527]: Configuration write error: Error: ENOSPC: no space left on device, write May 24 18:56:23 volumio volumio[4527]: Configuration write error: Error: ENOSPC: no space left on device, write May 24 18:56:23 volumio volumio[4527]: Configuration write error: Error: ENOSPC: no space left on device, write May 24 18:56:23 volumio volumio[4527]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 24 18:56:23 volumio volumio[4527]: info: Completed loading Core Plugins May 24 18:56:23 volumio volumio[4527]: info: Preparing to generate the ALSA configuration file May 24 18:56:23 volumio volumio[4527]: info: Volumio Network Manager: Network status updated: 1 May 24 18:56:23 volumio volumio[4527]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision May 24 18:56:23 volumio volumio[4527]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 24 18:56:23 volumio volumio[4527]: info: VolumeController:: Volume=75 Mute =false May 24 18:56:23 volumio volumio[4527]: info: CoreStateMachine::pushState May 24 18:56:23 volumio volumio[4527]: info: CorePlayQueue::getTrack 0 May 24 18:56:23 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 18:56:23 volumio volumio[4527]: info: CoreCommandRouter::volumioPushState May 24 18:56:23 volumio volumio[4527]: info: CoreStateMachine::updateTrackBlock May 24 18:56:23 volumio volumio[4527]: info: CorePlayQueue::getTrackBlock May 24 18:56:23 volumio volumio[4527]: info: CoreCommandRouter::volumioRetrievevolume May 24 18:56:23 volumio volumio[4633]: Starting albumart workers May 24 18:56:23 volumio volumio[4527]: info: Cannot read play queue from file May 24 18:56:23 volumio volumio[4527]: info: CorePlayQueue::getTrack 0 May 24 18:56:23 volumio volumio[4527]: info: Failed setting player state in CoreCommandRouter::initPlayerControls May 24 18:56:23 volumio volumio[4527]: info: VolumeController:: Volume=75 Mute =false May 24 18:56:23 volumio volumio[4527]: info: CoreStateMachine::pushState May 24 18:56:23 volumio volumio[4527]: info: CorePlayQueue::getTrack 0 May 24 18:56:23 volumio volumio[4527]: info: CoreCommandRouter::volumioPushState May 24 18:56:24 volumio volumio[4527]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 May 24 18:56:24 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 24 18:56:24 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 24 18:56:24 volumio volumio[4527]: info: CoreCommandRouter::volumioGetVisibleSources May 24 18:56:24 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 18:56:24 volumio volumio[4527]: info: CoreCommandRouter::volumioGetState May 24 18:56:24 volumio volumio[4527]: info: CorePlayQueue::getTrack 0 May 24 18:56:24 volumio volumio[4527]: info: CoreCommandRouter::volumioGetState May 24 18:56:24 volumio volumio[4527]: info: CorePlayQueue::getTrack 0 May 24 18:56:24 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 24 18:56:24 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 24 18:56:24 volumio volumio[4527]: info: Received Get System Info May 24 18:56:24 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 18:56:24 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 18:56:24 volumio volumio[4527]: info: Discovery: Getting this device information May 24 18:56:24 volumio volumio[4527]: info: CoreCommandRouter::volumioGetState May 24 18:56:24 volumio volumio[4527]: info: CorePlayQueue::getTrack 0 May 24 18:56:24 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 18:56:24 volumio volumio[4527]: info: CoreCommandRouter::volumioGetState May 24 18:56:24 volumio volumio[4527]: info: CorePlayQueue::getTrack 0 May 24 18:56:24 volumio volumio[4527]: info: Listing playlists May 24 18:56:24 volumio volumio[4527]: info: CoreCommandRouter::volumioGetQueue May 24 18:56:24 volumio volumio[4527]: info: CoreStateMachine::getQueue May 24 18:56:24 volumio volumio[4527]: info: CorePlayQueue::getQueue May 24 18:56:24 volumio volumio[4527]: info: Discovery: adding 4133d066-3e59-4766-ac3a-2cae4e0db93f May 24 18:56:24 volumio volumio[4527]: info: Discovery: Found device undefined May 24 18:56:24 volumio volumio[4527]: info: CoreCommandRouter::volumioGetState May 24 18:56:24 volumio volumio[4527]: info: CorePlayQueue::getTrack 0 May 24 18:56:24 volumio volumio[4527]: info: Discovery: this is already registered, 4133d066-3e59-4766-ac3a-2cae4e0db93f May 24 18:56:24 volumio volumio[4527]: info: Discovery: Found device undefined May 24 18:56:24 volumio volumio[4527]: info: CoreCommandRouter::volumioGetState May 24 18:56:24 volumio volumio[4527]: info: CorePlayQueue::getTrack 0 May 24 18:56:26 volumio volumio-remote-updater[806]: [2026-05-24 18:56:26] [connect] Successful connection May 24 18:56:26 volumio volumio-remote-updater[806]: [2026-05-24 18:56:26] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1779659786 101 May 24 18:56:26 volumio volumio[4527]: 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: 3 May 24 18:56:26 volumio volumio[4527]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 24 18:56:26 volumio volumio[4527]: info: CoreStateMachine::getcurrentVolume May 24 18:56:26 volumio volumio[4527]: info: CoreCommandRouter::volumioRetrievevolume May 24 18:56:26 volumio volumio[4527]: info: VolumeController:: Volume=75 Mute =false May 24 18:56:26 volumio volumio[4527]: info: CoreStateMachine::pushState May 24 18:56:26 volumio volumio[4527]: info: CorePlayQueue::getTrack 0 May 24 18:56:26 volumio volumio[4527]: info: CoreCommandRouter::volumioPushState May 24 18:56:26 volumio volumio[4527]: info: CoreStateMachine::updateTrackBlock May 24 18:56:26 volumio volumio[4527]: info: CorePlayQueue::getTrackBlock May 24 18:56:26 volumio volumio[4527]: info: CoreCommandRouter::volumioRetrievevolume May 24 18:56:26 volumio volumio[4527]: info: VolumeController:: Volume=75 Mute =false May 24 18:56:26 volumio volumio[4527]: info: CoreStateMachine::pushState May 24 18:56:26 volumio volumio[4527]: info: CorePlayQueue::getTrack 0 May 24 18:56:26 volumio volumio[4527]: info: CoreCommandRouter::volumioPushState May 24 18:56:26 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 24 18:56:30 volumio volumio[4527]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 24 18:56:32 volumio volumio[4527]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 18:56:32 volumio volumio[4527]: TypeError: Cannot read properties of undefined (reading 'charAt') May 24 18:56:32 volumio volumio[4527]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) May 24 18:56:32 volumio volumio[4527]: at listOnTimeout (node:internal/timers:573:17) May 24 18:56:32 volumio volumio[4527]: at process.processTimers (node:internal/timers:514:7) May 24 18:56:32 volumio volumio[4527]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 18:56:32 volumio sudo[4702]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-24 18:55' May 24 18:56:32 volumio sudo[4702]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"