Oct 15 21:04:00 volumio wireless.js[10245]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless Oct 15 21:04:00 volumio wireless.js[10245]: WIRELESS.JS - INFO: === SNM TRANSITION === Oct 15 21:04:00 volumio wireless.js[10245]: WIRELESS.JS - INFO: Previous ethernet state: disconnected Oct 15 21:04:00 volumio wireless.js[10245]: WIRELESS.JS - INFO: New ethernet state: connected Oct 15 21:04:00 volumio wireless.js[10245]: WIRELESS.JS - INFO: Single Network Mode: enabled Oct 15 21:04:00 volumio wireless.js[10245]: WIRELESS.JS - INFO: First start: yes Oct 15 21:04:00 volumio wireless.js[10245]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) Oct 15 21:04:00 volumio wireless.js[10245]: WIRELESS.JS - INFO: === END TRANSITION === Oct 15 21:04:00 volumio wireless.js[10245]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Oct 15 21:04:01 volumio sudo[10270]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 15 21:04:01 volumio sudo[10270]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 15 21:04:01 volumio sudo[10270]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:01 volumio sudo[10272]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 15 21:04:01 volumio sudo[10272]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 15 21:04:01 volumio sudo[10272]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:01 volumio wireless.js[10245]: WIRELESS.JS - INFO: Cleaning previous... Oct 15 21:04:01 volumio sudo[10275]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 15 21:04:01 volumio sudo[10275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 15 21:04:01 volumio sudo[10275]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:01 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 15 21:04:01 volumio wireless.js[10245]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Oct 15 21:04:01 volumio wireless.js[10245]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 9ms Oct 15 21:04:01 volumio wireless.js[10245]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: b8:27:eb:60:eb:c4) Oct 15 21:04:01 volumio sudo[10282]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Oct 15 21:04:01 volumio sudo[10282]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:04:01 volumio sudo[10282]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:02 volumio sudo[10290]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Oct 15 21:04:02 volumio sudo[10290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:04:02 volumio volumio[8759]: info: Received Get System Info Oct 15 21:04:02 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 21:04:02 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 21:04:02 volumio volumio[8759]: info: Discovery: Getting this device information Oct 15 21:04:02 volumio volumio[8759]: info: CoreCommandRouter::volumioGetState Oct 15 21:04:02 volumio volumio[8759]: info: CorePlayQueue::getTrack 0 Oct 15 21:04:02 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 21:04:02 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 15 21:04:02 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 15 21:04:02 volumio sudo[10290]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:02 volumio wireless.js[10245]: WIRELESS.JS - INFO: Regdomain already correct: DE Oct 15 21:04:02 volumio wireless.js[10245]: WIRELESS.JS - INFO: netconfigured file not found, starting hotspot Oct 15 21:04:02 volumio wireless.js[10245]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Oct 15 21:04:02 volumio wireless.js[10245]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Oct 15 21:04:02 volumio wireless.js[10245]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Oct 15 21:04:02 volumio sudo[10299]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 15 21:04:02 volumio sudo[10299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 15 21:04:02 volumio sudo[10299]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:02 volumio sudo[10302]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 15 21:04:02 volumio sudo[10302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 15 21:04:02 volumio sudo[10302]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:03 volumio wpa_supplicant[10305]: Successfully initialized wpa_supplicant Oct 15 21:04:03 volumio volumio5-onboarding[1882]: time=2026-10-15T19:04:03.096Z level=INFO msg="service successfully established" component=discovery/localnet Oct 15 21:04:03 volumio volumio[8759]: info: Received Get System Info Oct 15 21:04:03 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 21:04:03 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 21:04:03 volumio volumio[8759]: info: Discovery: Getting this device information Oct 15 21:04:03 volumio volumio[8759]: info: CoreCommandRouter::volumioGetState Oct 15 21:04:03 volumio volumio[8759]: info: CorePlayQueue::getTrack 0 Oct 15 21:04:03 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 21:04:03 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 15 21:04:03 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 15 21:04:03 volumio wireless.js[10245]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 2554ms Oct 15 21:04:03 volumio wireless.js[10245]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Oct 15 21:04:03 volumio wireless.js[10245]: Failed to connect to non-global ctrl_ifname: wlan0 error: No such file or directory Oct 15 21:04:03 volumio wireless.js[10245]: WIRELESS.JS - INFO: Notified systemd about wireless ready Oct 15 21:04:03 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 15 21:04:03 volumio systemd[1]: Started wireless.service - Wireless Services. Oct 15 21:04:03 volumio sudo[10242]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:04 volumio volumio5-onboarding[1882]: time=2026-10-15T19:04:04.095Z level=INFO msg="service successfully established" component=discovery/localnet Oct 15 21:04:08 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Oct 15 21:04:08 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Oct 15 21:04:08 volumio sudo[10339]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 21:04:08 volumio sudo[10339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:04:08 volumio sudo[10339]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:09 volumio volumio[8759]: info: Discovery: Networking Restart detected, restarting advertisement and browsing Oct 15 21:04:09 volumio volumio[8759]: info: Discovery: Restarting Advertising Oct 15 21:04:09 volumio volumio[8759]: info: Discovery: Stopping existing advertisement Oct 15 21:04:09 volumio volumio[8759]: info: Discovery: Restarting Browsing Oct 15 21:04:10 volumio volumio[8759]: info: Discovery: A device disappeared from network Oct 15 21:04:10 volumio volumio[8759]: info: Discovery: A device disappeared from network Oct 15 21:04:13 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Oct 15 21:04:13 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Oct 15 21:04:13 volumio volumio[8759]: info: Refreshing Cached IP Addresses Oct 15 21:04:13 volumio sudo[10343]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Oct 15 21:04:13 volumio sudo[10343]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:04:13 volumio sudo[10345]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 21:04:13 volumio sudo[10345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:04:13 volumio sudo[10343]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:13 volumio sudo[10347]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 21:04:13 volumio sudo[10345]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:13 volumio sudo[10347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:04:13 volumio sudo[10347]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:13 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 15 21:04:13 volumio volumio[8759]: verbose: New Socket.io Connection to 192.168.1.153 from 192.168.1.56 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/152.0.0.0 Safari/537.36 Engine version: 3 Transport: websocket Total Clients: 6 Oct 15 21:04:13 volumio volumio[8759]: info: CALLMETHOD: user_interface artwork_one getSettings [object Object] Oct 15 21:04:13 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: artwork_one , getSettings Oct 15 21:04:13 volumio volumio[8759]: info: CALLMETHOD: miscellanea artwork_companion getSettings [object Object] Oct 15 21:04:13 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: artwork_companion , getSettings Oct 15 21:04:13 volumio volumio[8759]: info: CoreCommandRouter::volumioGetState Oct 15 21:04:13 volumio volumio[8759]: info: CorePlayQueue::getTrack 0 Oct 15 21:04:13 volumio volumio[8759]: info: Received Get System Info Oct 15 21:04:13 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 21:04:13 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 21:04:13 volumio volumio[8759]: info: Discovery: Getting this device information Oct 15 21:04:13 volumio volumio[8759]: info: CoreCommandRouter::volumioGetState Oct 15 21:04:13 volumio volumio[8759]: info: CorePlayQueue::getTrack 0 Oct 15 21:04:13 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 21:04:13 volumio volumio[8759]: info: CoreCommandRouter::volumioGetVisibleSources Oct 15 21:04:13 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 21:04:13 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: alarm-clock , getSleep Oct 15 21:04:13 volumio volumio[8759]: info: CoreCommandRouter::volumioGetQueue Oct 15 21:04:13 volumio volumio[8759]: info: CoreStateMachine::getQueue Oct 15 21:04:13 volumio volumio[8759]: info: CorePlayQueue::getQueue Oct 15 21:04:13 volumio volumio[8759]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 21:04:13 volumio volumio[8759]: info: Listing playlists Oct 15 21:04:13 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 15 21:04:13 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 15 21:04:13 volumio sudo[10352]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 15 21:04:13 volumio sudo[10352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:04:13 volumio sudo[10352]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:13 volumio sudo[10357]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 15 21:04:13 volumio sudo[10357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:04:13 volumio sudo[10368]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 15 21:04:13 volumio sudo[10368]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:04:13 volumio sudo[10364]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 15 21:04:13 volumio sudo[10357]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:13 volumio sudo[10364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:04:13 volumio sudo[10368]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:13 volumio sudo[10364]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:13 volumio volumio[8759]: info: CALLMETHOD: user_interface artwork_one getSettings [object Object] Oct 15 21:04:13 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: artwork_one , getSettings Oct 15 21:04:13 volumio volumio[8759]: info: CALLMETHOD: miscellanea artwork_companion getSettings [object Object] Oct 15 21:04:13 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: artwork_companion , getSettings Oct 15 21:04:13 volumio sudo[10378]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 21:04:13 volumio sudo[10378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:04:13 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 21:04:13 volumio sudo[10378]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:13 volumio sudo[10380]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 21:04:13 volumio sudo[10380]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:04:13 volumio sudo[10380]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:13 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 15 21:04:13 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 15 21:04:14 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 21:04:14 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 21:04:14 volumio volumio[8759]: info: Getting Alsa Cards List without I2S DAC Oct 15 21:04:14 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 15 21:04:14 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 21:04:14 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 21:04:14 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 21:04:14 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 21:04:14 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 21:04:14 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 21:04:14 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 21:04:14 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 21:04:14 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 15 21:04:14 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 15 21:04:14 volumio volumio[8759]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 21:04:14 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 15 21:04:14 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 15 21:04:14 volumio sudo[10402]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 15 21:04:14 volumio sudo[10402]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:04:14 volumio systemd[1]: upmpdcli.service: Deactivated successfully. Oct 15 21:04:14 volumio systemd[1]: upmpdcli.service: Consumed 35.249s CPU time. Oct 15 21:04:14 volumio sudo[10402]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:14 volumio sudo[10418]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 15 21:04:14 volumio sudo[10418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:04:14 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 15 21:04:14 volumio sudo[10418]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:14 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 15 21:04:14 volumio sudo[10424]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 21:04:14 volumio sudo[10413]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 15 21:04:14 volumio sudo[10413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:04:14 volumio sudo[10424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:04:14 volumio sudo[10408]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 15 21:04:14 volumio sudo[10408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:04:14 volumio sudo[10408]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:14 volumio sudo[10424]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:14 volumio sudo[10413]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:14 volumio volumio[8759]: Upnp client error: Error: read ECONNRESET Oct 15 21:04:14 volumio sudo[10433]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 15 21:04:14 volumio sudo[10433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:04:14 volumio sudo[10427]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 21:04:14 volumio sudo[10427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:04:14 volumio sudo[10427]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:14 volumio volumio[8759]: info: Discovery: Started advertising with name: Volumio Oct 15 21:04:15 volumio sudo[10433]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:15 volumio volumio[8759]: info: Received Get System Info Oct 15 21:04:15 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 21:04:15 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 21:04:15 volumio volumio[8759]: info: Discovery: Getting this device information Oct 15 21:04:15 volumio volumio[8759]: info: CoreCommandRouter::volumioGetState Oct 15 21:04:15 volumio volumio[8759]: info: CorePlayQueue::getTrack 0 Oct 15 21:04:15 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 21:04:15 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 15 21:04:15 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 15 21:04:15 volumio volumio[8759]: info: Discovery: adding 2dab63ac-9ef7-4c48-bda9-9363f5a653ec Oct 15 21:04:15 volumio volumio[8759]: info: Discovery: Found device Volumio Oct 15 21:04:15 volumio volumio[8759]: info: CoreCommandRouter::volumioGetState Oct 15 21:04:15 volumio volumio[8759]: info: CorePlayQueue::getTrack 0 Oct 15 21:04:15 volumio volumio[8759]: info: Discovery: this is already registered, 2dab63ac-9ef7-4c48-bda9-9363f5a653ec Oct 15 21:04:15 volumio volumio[8759]: info: Discovery: Found device Volumio Oct 15 21:04:15 volumio volumio[8759]: info: CoreCommandRouter::volumioGetState Oct 15 21:04:15 volumio volumio[8759]: info: CorePlayQueue::getTrack 0 Oct 15 21:04:15 volumio volumio[8759]: info: Discovery: this is already registered, 2dab63ac-9ef7-4c48-bda9-9363f5a653ec Oct 15 21:04:15 volumio volumio[8759]: info: Discovery: Found device Volumio Oct 15 21:04:15 volumio volumio[8759]: info: CoreCommandRouter::volumioGetState Oct 15 21:04:15 volumio volumio[8759]: info: CorePlayQueue::getTrack 0 Oct 15 21:04:15 volumio volumio[8759]: info: Discovery: this is already registered, 2dab63ac-9ef7-4c48-bda9-9363f5a653ec Oct 15 21:04:15 volumio volumio[8759]: info: Discovery: Found device Volumio Oct 15 21:04:15 volumio volumio[8759]: info: CoreCommandRouter::volumioGetState Oct 15 21:04:15 volumio volumio[8759]: info: CorePlayQueue::getTrack 0 Oct 15 21:04:16 volumio volumio5-onboarding[1882]: time=2026-10-15T19:04:16.360Z level=INFO msg="service successfully established" component=discovery/localnet Oct 15 21:04:23 volumio sudo[10457]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 21:04:23 volumio sudo[10457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:04:23 volumio sudo[10457]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:23 volumio sudo[10459]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 21:04:23 volumio sudo[10459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:04:23 volumio sudo[10459]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:23 volumio sudo[10463]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 15 21:04:23 volumio sudo[10463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:04:29 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. Oct 15 21:04:29 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 15 21:04:29 volumio systemd[1]: upmpdcli.service: Consumed 35.249s CPU time. Oct 15 21:04:29 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 15 21:04:29 volumio sudo[10463]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:29 volumio volumio[8759]: info: Upmpdcli Daemon Started Oct 15 21:04:29 volumio upmpdcli[10496]: writing RSA key Oct 15 21:04:34 volumio volumio[8759]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Oct 15 21:04:34 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Oct 15 21:04:45 volumio volumio[8759]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Oct 15 21:04:45 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Oct 15 21:04:45 volumio sudo[10521]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Oct 15 21:04:45 volumio sudo[10521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:04:45 volumio sudo[10521]: pam_unix(sudo:session): session closed for user root Oct 15 21:04:45 volumio volumio[8759]: error: Cannot set permissions for /etc/network/interfaces: Error: Command failed: /usr/bin/sudo /bin/chmod 777 /etc/network/interfaces && /usr/bin/sudo /bin/chmod 777 /etc/dhcpcd.conf Oct 15 21:04:45 volumio volumio[8759]: /bin/chmod: changing permissions of '/etc/network/interfaces': No space left on device Oct 15 21:04:48 volumio nmbd[1041]: [2026/10/15 21:04:48.815953, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 15 21:04:48 volumio nmbd[1041]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.153 for name WORKGROUP<1d>. Oct 15 21:04:48 volumio nmbd[1041]: This response was from IP 192.168.1.111, reporting an IP address of 192.168.1.111. Oct 15 21:05:01 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 15 21:05:03 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 15 21:05:03 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 15 21:05:03 volumio systemd[1]: setdatetime-helper.service: Consumed 1.398s CPU time. Oct 15 21:05:07 volumio volumio[8759]: info: CoreCommandRouter::volumioGetVisibleSources Oct 15 21:05:07 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 21:05:07 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Oct 15 21:05:07 volumio volumio[8759]: Error Retrieving last played file: SyntaxError: /data/laststates.json: Unexpected end of JSON input Oct 15 21:05:07 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 15 21:05:07 volumio volumio[8759]: info: CURURI: albums:// Oct 15 21:05:07 volumio volumio[8759]: info: listAlbums - loading Albums from cache Oct 15 21:05:11 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 15 21:05:11 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 15 21:05:11 volumio volumio[8759]: info: CURURI: albums:// Oct 15 21:05:11 volumio volumio[8759]: info: listAlbums - loading Albums from cache Oct 15 21:05:11 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 15 21:05:11 volumio volumio[8759]: info: CURURI: artists:// Oct 15 21:05:11 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 15 21:05:11 volumio volumio[8759]: info: CURURI: playlists Oct 15 21:05:11 volumio volumio[8759]: info: Listing playlists Oct 15 21:05:11 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 15 21:05:11 volumio volumio[8759]: info: CURURI: genres:// Oct 15 21:05:12 volumio volumio[8759]: info: CoreCommandRouter::volumioGetVisibleSources Oct 15 21:05:12 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 21:05:12 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Oct 15 21:05:12 volumio volumio[8759]: Error Retrieving last played file: SyntaxError: /data/laststates.json: Unexpected end of JSON input Oct 15 21:05:12 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 15 21:05:12 volumio volumio[8759]: info: CURURI: albums:// Oct 15 21:05:12 volumio volumio[8759]: info: listAlbums - loading Albums from cache Oct 15 21:05:15 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 15 21:05:15 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 15 21:05:15 volumio volumio[8759]: info: CURURI: albums:// Oct 15 21:05:15 volumio volumio[8759]: info: listAlbums - loading Albums from cache Oct 15 21:05:15 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 15 21:05:15 volumio volumio[8759]: info: CURURI: artists:// Oct 15 21:05:15 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 15 21:05:15 volumio volumio[8759]: info: CURURI: playlists Oct 15 21:05:15 volumio volumio[8759]: info: Listing playlists Oct 15 21:05:15 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 15 21:05:15 volumio volumio[8759]: info: CURURI: genres:// Oct 15 21:05:19 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 15 21:05:19 volumio volumio[8759]: info: CURURI: albums:// Oct 15 21:05:19 volumio volumio[8759]: info: listAlbums - loading Albums from cache Oct 15 21:05:19 volumio volumio[8759]: info: Preload queue cleared Oct 15 21:05:24 volumio volumio[8759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 15 21:05:24 volumio volumio[8759]: info: CURURI: albums://Alan%20Parsons%20Project/Eve Oct 15 21:05:24 volumio volumio[8759]: info: Preload queue cleared Oct 15 21:05:24 volumio volumio[8759]: info: Preloading song: music-library/NAS/Musik/Alan Parsons Project/Eve/01 - Lucifer.flac Oct 15 21:05:24 volumio volumio[8759]: info: Preloading song: music-library/NAS/Musik/Alan Parsons Project/Eve/02 - You Lie Down with Dogs.flac Oct 15 21:05:24 volumio volumio[8759]: info: Preloading song: music-library/NAS/Musik/Alan Parsons Project/Eve/03 - I'd Rather Be a Man.flac Oct 15 21:05:24 volumio volumio[8759]: info: Preloading song: music-library/NAS/Musik/Alan Parsons Project/Eve/04 - You Won't Be There.flac Oct 15 21:05:24 volumio volumio[8759]: info: Preloading song: music-library/NAS/Musik/Alan Parsons Project/Eve/05 - Winding Me Up.flac Oct 15 21:05:24 volumio volumio[8759]: info: Preloading song: music-library/NAS/Musik/Alan Parsons Project/Eve/06 - Damned If I Do.flac Oct 15 21:05:24 volumio volumio[8759]: info: Preloading song: music-library/NAS/Musik/Alan Parsons Project/Eve/07 - Don't Hold Back.flac Oct 15 21:05:24 volumio volumio[8759]: info: Preloading song: music-library/NAS/Musik/Alan Parsons Project/Eve/08 - Secret Garden.flac Oct 15 21:05:24 volumio volumio[8759]: info: Preloading song: music-library/NAS/Musik/Alan Parsons Project/Eve/09 - If I Could Change Your Mind.flac Oct 15 21:05:24 volumio volumio[8759]: info: Exploding uri music-library/NAS/Musik/Alan Parsons Project/Eve/01 - Lucifer.flac in service mpd Oct 15 21:05:24 volumio volumio[8759]: info: ALBUMART /albumart?cacheid=960&web=Alan%20Parsons%20Project/Eve/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FAlan%20Parsons%20Project%2FEve%2F01%20%20-%20Lucifer.flac&metadata=false Oct 15 21:05:24 volumio volumio[8759]: info: URI /mnt/NAS/Musik/Alan Parsons Project/Eve/01 - Lucifer.flac Oct 15 21:05:24 volumio volumio[8759]: info: Exploding uri music-library/NAS/Musik/Alan Parsons Project/Eve/02 - You Lie Down with Dogs.flac in service mpd Oct 15 21:05:24 volumio volumio[8759]: info: ALBUMART /albumart?cacheid=960&web=Alan%20Parsons%20Project/Eve/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FAlan%20Parsons%20Project%2FEve%2F02%20%20-%20You%20Lie%20Down%20with%20Dogs.flac&metadata=false Oct 15 21:05:24 volumio volumio[8759]: info: URI /mnt/NAS/Musik/Alan Parsons Project/Eve/02 - You Lie Down with Dogs.flac Oct 15 21:05:24 volumio volumio[8759]: info: Exploding uri music-library/NAS/Musik/Alan Parsons Project/Eve/03 - I'd Rather Be a Man.flac in service mpd Oct 15 21:05:24 volumio volumio[8759]: info: ALBUMART /albumart?cacheid=960&web=Alan%20Parsons%20Project/Eve/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FAlan%20Parsons%20Project%2FEve%2F03%20%20-%20I'd%20Rather%20Be%20a%20Man.flac&metadata=false Oct 15 21:05:24 volumio volumio[8759]: info: URI /mnt/NAS/Musik/Alan Parsons Project/Eve/03 - I'd Rather Be a Man.flac Oct 15 21:05:24 volumio volumio[8759]: info: Exploding uri music-library/NAS/Musik/Alan Parsons Project/Eve/04 - You Won't Be There.flac in service mpd Oct 15 21:05:24 volumio volumio[8759]: info: ALBUMART /albumart?cacheid=960&web=Alan%20Parsons%20Project/Eve/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FAlan%20Parsons%20Project%2FEve%2F04%20%20-%20You%20Won't%20Be%20There.flac&metadata=false Oct 15 21:05:24 volumio volumio[8759]: info: URI /mnt/NAS/Musik/Alan Parsons Project/Eve/04 - You Won't Be There.flac Oct 15 21:05:24 volumio volumio[8759]: info: Exploding uri music-library/NAS/Musik/Alan Parsons Project/Eve/05 - Winding Me Up.flac in service mpd Oct 15 21:05:24 volumio volumio[8759]: info: ALBUMART /albumart?cacheid=960&web=Alan%20Parsons%20Project/Eve/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FAlan%20Parsons%20Project%2FEve%2F05%20%20-%20Winding%20Me%20Up.flac&metadata=false Oct 15 21:05:24 volumio volumio[8759]: info: URI /mnt/NAS/Musik/Alan Parsons Project/Eve/05 - Winding Me Up.flac Oct 15 21:05:24 volumio volumio[8759]: info: Exploding uri music-library/NAS/Musik/Alan Parsons Project/Eve/06 - Damned If I Do.flac in service mpd Oct 15 21:05:24 volumio volumio[8759]: info: ALBUMART /albumart?cacheid=960&web=Alan%20Parsons%20Project/Eve/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FAlan%20Parsons%20Project%2FEve%2F06%20%20-%20Damned%20If%20I%20Do.flac&metadata=false Oct 15 21:05:24 volumio volumio[8759]: info: URI /mnt/NAS/Musik/Alan Parsons Project/Eve/06 - Damned If I Do.flac Oct 15 21:05:24 volumio volumio[8759]: info: Exploding uri music-library/NAS/Musik/Alan Parsons Project/Eve/07 - Don't Hold Back.flac in service mpd Oct 15 21:05:24 volumio volumio[8759]: info: ALBUMART /albumart?cacheid=960&web=Alan%20Parsons%20Project/Eve/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FAlan%20Parsons%20Project%2FEve%2F07%20%20-%20Don't%20Hold%20Back.flac&metadata=false Oct 15 21:05:24 volumio volumio[8759]: info: URI /mnt/NAS/Musik/Alan Parsons Project/Eve/07 - Don't Hold Back.flac Oct 15 21:05:24 volumio volumio[8759]: info: Exploding uri music-library/NAS/Musik/Alan Parsons Project/Eve/08 - Secret Garden.flac in service mpd Oct 15 21:05:24 volumio volumio[8759]: info: ALBUMART /albumart?cacheid=960&web=Alan%20Parsons%20Project/Eve/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FAlan%20Parsons%20Project%2FEve%2F08%20%20-%20Secret%20Garden.flac&metadata=false Oct 15 21:05:24 volumio volumio[8759]: info: URI /mnt/NAS/Musik/Alan Parsons Project/Eve/08 - Secret Garden.flac Oct 15 21:05:24 volumio volumio[8759]: info: Exploding uri music-library/NAS/Musik/Alan Parsons Project/Eve/09 - If I Could Change Your Mind.flac in service mpd Oct 15 21:05:24 volumio volumio[8759]: info: ALBUMART /albumart?cacheid=960&web=Alan%20Parsons%20Project/Eve/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FAlan%20Parsons%20Project%2FEve%2F09%20%20-%20If%20I%20Could%20Change%20Your%20Mind.flac&metadata=false Oct 15 21:05:24 volumio volumio[8759]: info: URI /mnt/NAS/Musik/Alan Parsons Project/Eve/09 - If I Could Change Your Mind.flac Oct 15 21:05:25 volumio volumio[8759]: Error in writing albumart JSON file: Error: ENOSPC: no space left on device, write Oct 15 21:05:25 volumio volumio[8759]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 21:05:25 volumio volumio[8759]: [Error: ENOSPC: no space left on device, write] { Oct 15 21:05:25 volumio volumio[8759]: errno: -28, Oct 15 21:05:25 volumio volumio[8759]: code: 'ENOSPC', Oct 15 21:05:25 volumio volumio[8759]: syscall: 'write' Oct 15 21:05:25 volumio volumio[8759]: } Oct 15 21:05:25 volumio volumio[8759]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 21:05:26 volumio sudo[10636]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-15 21:04' Oct 15 21:05:26 volumio sudo[10636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:05:26 volumio sudo[10636]: pam_unix(sudo:session): session closed for user root Oct 15 21:05:26 volumio volumio-remote-updater[726]: [2026-10-15 19:05:26] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 15 21:05:26 volumio volumio5-onboarding[1882]: time=2026-10-15T19:05:26.407Z level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Oct 15 21:05:26 volumio volumio-remote-updater[726]: [2026-10-15 19:05:26] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 15 21:05:26 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 15 21:05:26 volumio volumio5-onboarding[1882]: time=2026-10-15T19:05:26.423Z level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 15 21:05:26 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 15 21:05:26 volumio systemd[1]: volumio.service: Consumed 1min 5.639s CPU time. Oct 15 21:05:26 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 15 21:05:26 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Oct 15 21:05:26 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Oct 15 21:05:26 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 15 21:05:26 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Oct 15 21:05:26 volumio systemd[1]: volumio.service: Consumed 1min 5.639s CPU time. Oct 15 21:05:26 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Oct 15 21:05:26 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Oct 15 21:05:27 volumio volumio5-onboarding[1882]: time=2026-10-15T19:05:27.426Z level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 15 21:05:28 volumio volumio5-onboarding[1882]: time=2026-10-15T19:05:28.428Z level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 15 21:05:29 volumio volumio[10657]: info: ------------------------------------------- Oct 15 21:05:29 volumio volumio[10657]: info: ----- Volumio3 ---- Oct 15 21:05:29 volumio volumio[10657]: info: ------------------------------------------- Oct 15 21:05:29 volumio volumio[10657]: info: ----- System startup ---- Oct 15 21:05:29 volumio volumio[10657]: info: ------------------------------------------- Oct 15 21:05:30 volumio volumio[10657]: info: MYVOLUMIO Environment detected Oct 15 21:05:30 volumio volumio[10657]: info: Plugin folders cleanup Oct 15 21:05:30 volumio volumio[10657]: info: Scanning into folder /volumio/app/plugins/ Oct 15 21:05:30 volumio volumio[10657]: info: Scanning category audio_interface Oct 15 21:05:30 volumio volumio[10657]: info: Scanning category miscellanea Oct 15 21:05:30 volumio volumio[10657]: info: Scanning category music_service Oct 15 21:05:30 volumio volumio[10657]: info: Scanning category plugins.json Oct 15 21:05:30 volumio volumio[10657]: info: Scanning category system_controller Oct 15 21:05:30 volumio volumio[10657]: info: Scanning category user_interface Oct 15 21:05:30 volumio volumio[10657]: info: Scanning into folder /data/plugins/ Oct 15 21:05:30 volumio volumio[10657]: info: Scanning category music_service Oct 15 21:05:30 volumio volumio[10657]: info: Scanning category system_controller Oct 15 21:05:30 volumio volumio[10657]: info: Scanning category user_interface Oct 15 21:05:30 volumio volumio[10657]: info: Plugin folders cleanup completed Oct 15 21:05:30 volumio volumio[10657]: info: ------------------------------------------- Oct 15 21:05:30 volumio volumio[10657]: info: ----- Core plugins startup ---- Oct 15 21:05:30 volumio volumio[10657]: info: ------------------------------------------- Oct 15 21:05:30 volumio volumio[10657]: info: Loading plugins from folder /volumio/app/plugins/ Oct 15 21:05:30 volumio volumio[10657]: info: Adding plugin upnp to MyMusic Plugins Oct 15 21:05:30 volumio volumio[10657]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 15 21:05:30 volumio volumio[10657]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 15 21:05:30 volumio volumio[10657]: info: Loading plugins from folder /data/plugins/ Oct 15 21:05:30 volumio volumio[10657]: info: Loading plugin "system"... Oct 15 21:05:30 volumio volumio[10657]: info: Loading plugin "appearance"... Oct 15 21:05:31 volumio volumio-remote-updater[726]: [2026-10-15 19:05:31] [connect] Successful connection Oct 15 21:05:32 volumio volumio[10657]: info: Loading plugin "network"... Oct 15 21:05:32 volumio volumio[10657]: info: Refreshing Cached IP Addresses Oct 15 21:05:32 volumio volumio[10657]: info: Loading plugin "services"... Oct 15 21:05:32 volumio sudo[10687]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 21:05:32 volumio sudo[10687]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:05:32 volumio sudo[10687]: pam_unix(sudo:session): session closed for user root Oct 15 21:05:32 volumio volumio[10657]: info: Loading plugin "volumio5onboarding"... Oct 15 21:05:32 volumio sudo[10689]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 21:05:32 volumio sudo[10689]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:05:32 volumio sudo[10689]: pam_unix(sudo:session): session closed for user root Oct 15 21:05:32 volumio volumio[10657]: info: Loading plugin "alsa_controller"... Oct 15 21:05:32 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 21:05:32 volumio volumio[10657]: info: Loading plugin "wizard"... Oct 15 21:05:32 volumio volumio[10657]: info: Loading plugin "networkfs"... Oct 15 21:05:32 volumio volumio[10657]: info: Cannot mount NAS Musik at system boot, trial number 1 ,retrying in 5 seconds Oct 15 21:05:32 volumio volumio[10657]: info: Starting Udev Watcher for removable devices Oct 15 21:05:32 volumio volumio[10657]: info: Ignoring mount for partition: boot Oct 15 21:05:32 volumio volumio[10657]: info: Ignoring mount for partition: volumio Oct 15 21:05:32 volumio volumio[10657]: info: Ignoring mount for partition: volumio_data Oct 15 21:05:32 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 15 21:05:32 volumio volumio[10657]: info: Loading plugin "volumio_command_line_client"... Oct 15 21:05:32 volumio volumio[10657]: info: Loading plugin "upnp"... Oct 15 21:05:32 volumio volumio[10657]: info: [1792091132655] Starting Upmpd Daemon Oct 15 21:05:32 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 15 21:05:32 volumio volumio[10657]: info: Loading plugin "my_music"... Oct 15 21:05:32 volumio volumio[10657]: info: Loading plugin "mpd"... Oct 15 21:05:33 volumio volumio[10657]: info: Loading plugin "upnp_browser"... Oct 15 21:05:34 volumio volumio[10657]: info: Starting UPNP Browser Oct 15 21:05:34 volumio volumio[10657]: info: Loading plugin "alarm-clock"... Oct 15 21:05:35 volumio volumio[10657]: info: Loading plugin "airplay_emulation"... Oct 15 21:05:35 volumio volumio[10657]: info: Starting Shairport Sync Oct 15 21:05:35 volumio volumio[10657]: info: Loading plugin "last_100"... Oct 15 21:05:35 volumio volumio[10657]: info: Loading plugin "webradio"... Oct 15 21:05:35 volumio volumio[10657]: info: Loading plugin "i2s_dacs"... Oct 15 21:05:35 volumio volumio[10657]: info: Loading plugin "volumiodiscovery"... Oct 15 21:05:35 volumio volumio[10657]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 15 21:05:35 volumio node[10657]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 15 21:05:35 volumio volumio[10657]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 15 21:05:35 volumio node[10657]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 15 21:05:35 volumio volumio[10657]: *** WARNING *** For more information see Oct 15 21:05:35 volumio node[10657]: *** WARNING *** For more information see Oct 15 21:05:35 volumio volumio[10657]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 15 21:05:35 volumio node[10657]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 15 21:05:35 volumio volumio[10657]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 15 21:05:35 volumio node[10657]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 15 21:05:35 volumio volumio[10657]: *** WARNING *** For more information see Oct 15 21:05:35 volumio node[10657]: *** WARNING *** For more information see Oct 15 21:05:35 volumio volumio[10657]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 15 21:05:35 volumio volumio[10657]: info: Discovery: Started advertising with name: Volumio Oct 15 21:05:35 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 15 21:05:35 volumio volumio[10657]: info: Loading plugin "artwork_one"... Oct 15 21:05:35 volumio volumio[10657]: info: Loading plugin "stylish_player"... Oct 15 21:05:35 volumio volumio[10657]: info: Loading plugin "outputs"... Oct 15 21:05:35 volumio volumio[10657]: info: Loading plugin "albumart"... Oct 15 21:05:35 volumio volumio[10657]: info: Plugin example_plugin is not enabled Oct 15 21:05:35 volumio volumio[10657]: info: Loading plugin "inputs"... Oct 15 21:05:35 volumio volumio[10657]: info: Loading plugin "updater_comm"... Oct 15 21:05:35 volumio volumio[10657]: info: Plugin mpdemulation is not enabled Oct 15 21:05:35 volumio volumio[10657]: info: Loading plugin "rest_api"... Oct 15 21:05:35 volumio volumio[10657]: info: Loading plugin "websocket"... Oct 15 21:05:35 volumio volumio[10657]: info: Starting Socket.io Server version 1.7.4 Oct 15 21:05:35 volumio volumio[10657]: info: Loading plugin "soloist_connect"... Oct 15 21:05:36 volumio volumio[10657]: info: Loading plugin "backup_restore"... Oct 15 21:05:36 volumio volumio[10719]: Forking 3 albumart workers Oct 15 21:05:36 volumio volumio[10657]: info: Applying required configuration parameters for plugin backup_restore Oct 15 21:05:36 volumio volumio[10657]: info: Loading plugin "Systeminfo"... Oct 15 21:05:36 volumio volumio[10657]: info: Loading i18n strings for locale de Oct 15 21:05:36 volumio volumio[10657]: Updating browse sources language Oct 15 21:05:36 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 21:05:37 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 21:05:37 volumio volumio[10657]: info: CoreCommandRouter::initPlayerControls Oct 15 21:05:37 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:37 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:37 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:37 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:37 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:37 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:37 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:37 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:37 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 21:05:37 volumio volumio[10657]: Express server listening on port 3000 Oct 15 21:05:37 volumio volumio[10657]: [Metrics] WebUI: 8s 592.81ms Oct 15 21:05:37 volumio volumio[10657]: info: CoreStateMachine::resetVolumioState Oct 15 21:05:37 volumio volumio[10657]: info: CoreStateMachine::getcurrentVolume Oct 15 21:05:37 volumio volumio[10657]: info: CoreCommandRouter::volumioRetrievevolume Oct 15 21:05:37 volumio volumio[10657]: info: CoreStateMachine::pushState Oct 15 21:05:37 volumio volumio[10657]: info: CorePlayQueue::getTrack 0 Oct 15 21:05:37 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 21:05:37 volumio volumio[10657]: info: CoreCommandRouter::volumioPushState Oct 15 21:05:37 volumio volumio[10657]: info: Volumio Network Manager: Network status updated: 1 Oct 15 21:05:37 volumio volumio[10657]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Oct 15 21:05:37 volumio volumio[10657]: info: Cannot mount NAS Musik at system boot, trial number 2 ,retrying in 5 seconds Oct 15 21:05:37 volumio volumio[10657]: verbose: New Socket.io Connection to 192.168.1.153 from 192.168.1.56 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/152.0.0.0 Safari/537.36 Engine version: 3 Transport: websocket Total Clients: 1 Oct 15 21:05:37 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 21:05:37 volumio volumio[10657]: info: Cannot read play queue from file Oct 15 21:05:37 volumio volumio[10657]: verbose: New Socket.io Connection to 192.168.1.153:3000 from 192.168.1.56 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/152.0.0.0 Safari/537.36 Engine version: 3 Transport: websocket Total Clients: 1 Oct 15 21:05:37 volumio volumio[10657]: info: CoreStateMachine::setRepeat null single undefined Oct 15 21:05:37 volumio volumio[10657]: info: CoreStateMachine::pushState Oct 15 21:05:37 volumio volumio[10657]: info: CorePlayQueue::getTrack 0 Oct 15 21:05:37 volumio volumio[10657]: info: CoreCommandRouter::volumioPushState Oct 15 21:05:37 volumio volumio[10657]: info: CoreStateMachine::setRandom null Oct 15 21:05:37 volumio volumio[10657]: info: CoreStateMachine::pushState Oct 15 21:05:37 volumio volumio[10657]: info: CorePlayQueue::getTrack 0 Oct 15 21:05:37 volumio volumio[10657]: info: CoreCommandRouter::volumioPushState Oct 15 21:05:37 volumio volumio[10657]: info: Setting Device type: Raspberry PI Oct 15 21:05:37 volumio volumio[10657]: info: Completed loading Core Plugins Oct 15 21:05:37 volumio volumio[10657]: info: Preparing to generate the ALSA configuration file Oct 15 21:05:37 volumio volumio[10731]: Starting albumart workers Oct 15 21:05:37 volumio volumio[10657]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 15 21:05:37 volumio volumio[10657]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Oct 15 21:05:37 volumio volumio[10657]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 15 21:05:37 volumio volumio[10657]: info: CoreCommandRouter::volumioGetState Oct 15 21:05:37 volumio volumio[10657]: info: CorePlayQueue::getTrack 0 Oct 15 21:05:37 volumio volumio[10657]: info: CoreCommandRouter::volumioGetQueue Oct 15 21:05:37 volumio volumio[10657]: info: CoreStateMachine::getQueue Oct 15 21:05:37 volumio volumio[10657]: info: CorePlayQueue::getQueue Oct 15 21:05:37 volumio volumio[10657]: info: The plugin stylish_player has an ALSA contribution file sp_in.sp_out.7.conf Oct 15 21:05:37 volumio volumio[10657]: info: Reading ALSA contributions from plugins. Oct 15 21:05:37 volumio volumio[10730]: Starting albumart workers Oct 15 21:05:37 volumio volumio[10657]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 15 21:05:37 volumio volumio[10729]: Starting albumart workers Oct 15 21:05:38 volumio volumio[10657]: info: Discovery: adding 2dab63ac-9ef7-4c48-bda9-9363f5a653ec Oct 15 21:05:38 volumio volumio[10657]: info: Discovery: Found device Volumio Oct 15 21:05:38 volumio volumio[10657]: info: CoreCommandRouter::volumioGetState Oct 15 21:05:38 volumio volumio[10657]: info: CorePlayQueue::getTrack 0 Oct 15 21:05:38 volumio volumio[10657]: info: CoreCommandRouter::volumioGetVisibleSources Oct 15 21:05:38 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 21:05:38 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 15 21:05:38 volumio volumio[10657]: info: CURURI: playlists Oct 15 21:05:38 volumio volumio[10657]: info: Listing playlists Oct 15 21:05:38 volumio volumio[10657]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 15 21:05:38 volumio volumio[10657]: info: Discovery: this is already registered, 2dab63ac-9ef7-4c48-bda9-9363f5a653ec Oct 15 21:05:38 volumio volumio[10657]: info: Discovery: Found device Volumio Oct 15 21:05:38 volumio volumio[10657]: info: CoreCommandRouter::volumioGetState Oct 15 21:05:38 volumio volumio[10657]: info: CorePlayQueue::getTrack 0 Oct 15 21:05:38 volumio volumio[10657]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 15 21:05:38 volumio volumio[10657]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 15 21:05:38 volumio volumio[10657]: info: Asound.conf file unchanged, so no further update is needed Oct 15 21:05:38 volumio volumio[10657]: info: Output device has changed, restarting MPD Oct 15 21:05:38 volumio volumio[10657]: info: Output device has changed, restarting Shairport Sync Oct 15 21:05:38 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:38 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 21:05:38 volumio sudo[10774]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 21:05:38 volumio sudo[10774]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:05:38 volumio sudo[10776]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 21:05:38 volumio sudo[10774]: pam_unix(sudo:session): session closed for user root Oct 15 21:05:38 volumio sudo[10776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:05:38 volumio volumio[10657]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 21:05:38 volumio volumio[10657]: info: ___________ START PLUGINS ___________ Oct 15 21:05:38 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 15 21:05:38 volumio volumio[10657]: info: ControllerMpd::onStart: Initializing MPD Oct 15 21:05:38 volumio volumio[10657]: info: Creating MPD Configuration file Oct 15 21:05:38 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 21:05:38 volumio volumio[10657]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 21:05:38 volumio volumio[10657]: info: [1792091138492] CoreMusicLibrary::Adding element Medienserver Oct 15 21:05:38 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 21:05:38 volumio volumio[10657]: info: UPNP Browser: Client initialized successfully Oct 15 21:05:38 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:38 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 21:05:38 volumio sudo[10784]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 15 21:05:38 volumio sudo[10784]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:05:38 volumio sudo[10788]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 21:05:38 volumio sudo[10788]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:05:38 volumio sudo[10787]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 21:05:38 volumio sudo[10787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:05:38 volumio sudo[10784]: pam_unix(sudo:session): session closed for user root Oct 15 21:05:38 volumio sudo[10787]: pam_unix(sudo:session): session closed for user root Oct 15 21:05:38 volumio volumio[10657]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 21:05:38 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:38 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 21:05:38 volumio volumio[10657]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 21:05:38 volumio volumio[10657]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 21:05:38 volumio volumio[10657]: info: [1792091138734] CoreMusicLibrary::Adding element Last_100 Oct 15 21:05:38 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 21:05:38 volumio volumio[10657]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 21:05:38 volumio volumio[10657]: info: [1792091138739] CoreMusicLibrary::Adding element Webradio Oct 15 21:05:38 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 21:05:38 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 21:05:38 volumio volumio[10657]: info: Initializing BBC Radios Oct 15 21:05:38 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 21:05:38 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 21:05:38 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: appearance , registerThirdPartyUI Oct 15 21:05:38 volumio volumio[10657]: info: Registering Third Party UI: {"uiPrettyName":"Artwork One","uiName":"artwork","uiPath":"/data/plugins/user_interface/artwork_one/ui"} Oct 15 21:05:38 volumio volumio[10657]: info: UI already registered: {"uiPrettyName":"Artwork One","uiName":"artwork","uiPath":"/data/plugins/user_interface/artwork_one/ui"} Oct 15 21:05:38 volumio volumio[10657]: info: [artwork_one] interface registered at /data/plugins/user_interface/artwork_one/ui Oct 15 21:05:38 volumio volumio[10657]: info: [artwork_one] started; {"version":2,"plugin":"user_interface/artwork_one"} Oct 15 21:05:38 volumio volumio[10657]: info: Stylish Player: Peppy data path ready at /data/INTERNAL/stylish_player Oct 15 21:05:38 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 15 21:05:39 volumio volumio[10657]: info: Stylish Player: Found process ID Oct 15 21:05:39 volumio volumio[10657]: info: Stylish Player: Starting audio server on port 9993 Oct 15 21:05:39 volumio volumio[10657]: info: SoloistConnect: plugin=0.9.0 shim=0.2.9 Oct 15 21:05:39 volumio volumio[10657]: info: SoloistConnect: freshness armed period=24h Oct 15 21:05:39 volumio volumio[10657]: info: Volumio Calling Home Oct 15 21:05:39 volumio volumio[10657]: info: Preparing to generate the ALSA configuration file Oct 15 21:05:39 volumio volumio[10657]: info: Stylish Player: Resilient Audio Streamer on port 9993 Oct 15 21:05:39 volumio volumio[10657]: info: Stylish Player: FIFO sentinel opened Oct 15 21:05:39 volumio volumio[10657]: info: Stylish Player: No stream clients — draining FIFO to keep ALSA unblocked Oct 15 21:05:39 volumio volumio[10657]: info: Stylish Player: Server listening on port 3339 Oct 15 21:05:39 volumio volumio[10657]: info: The plugin stylish_player has an ALSA contribution file sp_in.sp_out.7.conf Oct 15 21:05:39 volumio volumio[10657]: info: Reading ALSA contributions from plugins. Oct 15 21:05:39 volumio volumio[10657]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 15 21:05:39 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , soloistMeteringWanted Oct 15 21:05:39 volumio volumio[10657]: info: SoloistConnect: writeEnvFile cache_location="disk" cache_size_mb=1024 ceiling=217 -> ramMb=0 CACHE_SIZE=1024 Oct 15 21:05:39 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:39 volumio volumio[10657]: info: MPD Permissions set Oct 15 21:05:39 volumio volumio[10657]: info: MPD Permissions set Oct 15 21:05:39 volumio volumio[10657]: info: Volumio called home Oct 15 21:05:39 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 15 21:05:39 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 15 21:05:39 volumio systemd[1]: mpd.service: Consumed 5.034s CPU time. Oct 15 21:05:39 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 15 21:05:39 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 15 21:05:39 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 15 21:05:39 volumio sudo[10848]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl restart soloist.service Oct 15 21:05:39 volumio sudo[10848]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:05:39 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 15 21:05:39 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 15 21:05:39 volumio volumio[10657]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Oct 15 21:05:39 volumio launch-soloist.sh[10106]: 2026-10-15 21:05:39.929: shutting down Oct 15 21:05:39 volumio launch-soloist.sh[10106]: 2026-10-15 21:05:39.933: api: websocket server stopped Oct 15 21:05:39 volumio systemd[1]: Stopping soloist.service - Spotify Soloist daemon (Volumio 4 ALSA)... Oct 15 21:05:39 volumio systemd[1]: soloist.service: Deactivated successfully. Oct 15 21:05:39 volumio systemd[1]: Stopped soloist.service - Spotify Soloist daemon (Volumio 4 ALSA). Oct 15 21:05:39 volumio systemd[1]: soloist.service: Consumed 1.258s CPU time. Oct 15 21:05:40 volumio systemd[1]: Starting soloist.service - Spotify Soloist daemon (Volumio 4 ALSA)... Oct 15 21:05:40 volumio volumio[10657]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Oct 15 21:05:40 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:40 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:40 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:40 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:40 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:40 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:40 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:40 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:40 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 21:05:40 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:40 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:40 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:40 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:40 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:40 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:40 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:40 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:40 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:40 volumio sudo[10851]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 15 21:05:40 volumio sudo[10851]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 15 21:05:40 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 21:05:40 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 21:05:40 volumio sudo[10851]: pam_unix(sudo:session): session closed for user root Oct 15 21:05:40 volumio volumio[10657]: verbose: New Socket.io Connection to 192.168.1.153 from 192.168.1.56 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/152.0.0.0 Safari/537.36 Engine version: 3 Transport: websocket Total Clients: 3 Oct 15 21:05:40 volumio volumio[10657]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Oct 15 21:05:40 volumio systemd[1]: Started soloist.service - Spotify Soloist daemon (Volumio 4 ALSA). Oct 15 21:05:40 volumio volumio[10657]: info: Starting Shairport Sync Oct 15 21:05:40 volumio sudo[10848]: pam_unix(sudo:session): session closed for user root Oct 15 21:05:40 volumio volumio[10657]: info: Starting Shairport Sync Oct 15 21:05:40 volumio sudo[10878]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 21:05:40 volumio sudo[10878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:05:40 volumio sudo[10882]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 21:05:40 volumio sudo[10882]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:05:40 volumio volumio[10657]: info: Starting Shairport Sync Oct 15 21:05:40 volumio sudo[10891]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 21:05:40 volumio sudo[10891]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 21:05:40 volumio volumio[10657]: info: CALLMETHOD: user_interface artwork_one getSettings [object Object] Oct 15 21:05:40 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: artwork_one , getSettings Oct 15 21:05:40 volumio volumio[10657]: info: CALLMETHOD: miscellanea artwork_companion getSettings [object Object] Oct 15 21:05:40 volumio volumio[10657]: info: CoreCommandRouter::executeOnPlugin: artwork_companion , getSettings Oct 15 21:05:40 volumio volumio[10657]: info: Preload queue cleared Oct 15 21:05:40 volumio volumio[10657]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 15 21:05:40 volumio volumio[10657]: info: CoreStateMachine::ClearQueue Oct 15 21:05:40 volumio volumio[10657]: info: CoreStateMachine::stop Oct 15 21:05:40 volumio volumio[10657]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 21:05:40 volumio volumio[10657]: info: CorePlayQueue::clearPlayQueue Oct 15 21:05:40 volumio volumio[10657]: info: CorePlayQueue::saveQueue Oct 15 21:05:40 volumio volumio[10657]: info: CoreCommandRouter::volumioPushQueue Oct 15 21:05:40 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 15 21:05:40 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 15 21:05:40 volumio volumio[10657]: info: CoreStateMachine::addQueueItems Oct 15 21:05:40 volumio volumio[10657]: info: CorePlayQueue::addQueueItems Oct 15 21:05:40 volumio volumio[10657]: info: Preload queue cleared Oct 15 21:05:40 volumio volumio[10657]: info: Adding Item to queue: albums://Alan%20Parsons%20Project/Eve Oct 15 21:05:40 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 21:05:40 volumio systemd[1]: shairport-sync.service: Consumed 3.567s CPU time. Oct 15 21:05:40 volumio volumio[10657]: info: Exploding uri albums://Alan%20Parsons%20Project/Eve in service mpd Oct 15 21:05:40 volumio launch-soloist.sh[10872]: SoloistConnect: plugin=0.9.0 shim=0.2.9 rev=12387e557e7524f1055bf92257c4b139a0273ba1 userspace=armhf device=plug:volumio tlength_cap=500ms external_volume=false trim=0dB diag=off uname=armv7l Oct 15 21:05:40 volumio volumio[10657]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 21:05:40 volumio volumio[10657]: TypeError: Cannot read properties of undefined (reading 'sendCommand') Oct 15 21:05:40 volumio volumio[10657]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2134:20) Oct 15 21:05:40 volumio volumio[10657]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:16) Oct 15 21:05:40 volumio volumio[10657]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Oct 15 21:05:40 volumio volumio[10657]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Oct 15 21:05:40 volumio volumio[10657]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Oct 15 21:05:40 volumio volumio[10657]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:251:25) Oct 15 21:05:40 volumio volumio[10657]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:526:23) Oct 15 21:05:40 volumio volumio[10657]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Oct 15 21:05:40 volumio volumio[10657]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Oct 15 21:05:40 volumio volumio[10657]: at Socket.emit (node:events:514:28) Oct 15 21:05:40 volumio volumio[10657]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 15 21:05:40 volumio volumio[10657]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Oct 15 21:05:40 volumio volumio[10657]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 21:05:40 volumio launch-soloist.sh[10899]: SoloistConnect: engine_prefs loudness=off crossfade=off stores=1 Oct 15 21:05:40 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 21:05:40 volumio sudo[10882]: pam_unix(sudo:session): session closed for user root Oct 15 21:05:40 volumio sudo[10878]: pam_unix(sudo:session): session closed for user root Oct 15 21:05:40 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 15 21:05:40 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 15 21:05:40 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 21:05:40 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 21:05:40 volumio sudo[10891]: pam_unix(sudo:session): session closed for user root Oct 15 21:05:40 volumio launch-soloist.sh[10872]: SoloistConnect: interpreter=/data/soloist/sysroot/usr/lib/ld-linux-armhf.so.3 Oct 15 21:05:40 volumio launch-soloist.sh[10872]: 2026-10-15 21:05:40.682: soloist 1.3.9.28 build 1792087333 (20261015) (g440165f200) (linux/arm) Oct 15 21:05:40 volumio launch-soloist.sh[10872]: 2026-10-15 21:05:40.682: client expires in 89 days Oct 15 21:05:40 volumio launch-soloist.sh[10872]: 2026-10-15 21:05:40.736: api: websocket server listening on 127.0.0.1:9878 Oct 15 21:05:40 volumio launch-soloist.sh[10872]: 2026-10-15 21:05:40.736: ready, device_name=Volumio device_id=c3fd2011-30da-424f-8512-4d41cd71502c Oct 15 21:05:40 volumio launch-soloist.sh[10872]: 2026-10-15 21:05:40.737: waiting for login — connect to "Volumio" from your Spotify app Oct 15 21:05:40 volumio launch-soloist.sh[10872]: 2026-10-15 21:05:40.737: running Oct 15 21:05:41 volumio sudo[10947]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-15 21:04' Oct 15 21:05:41 volumio sudo[10947]: 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" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"