Mar 24 15:45:35 volumio ntpd[1023]: CLOCK: time stepped by 16344.360950 Mar 24 15:45:35 volumio ntpd[1023]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Mar 24 15:45:35 volumio sudo[1484]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 24 15:45:35 volumio sudo[1484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 24 15:45:35 volumio volumio[1237]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 24 15:45:35 volumio volumio[1237]: info: [1774367135332] CoreMusicLibrary::Adding element Media Servers Mar 24 15:45:35 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 15:45:35 volumio sudo[1488]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 24 15:45:35 volumio sudo[1484]: pam_unix(sudo:session): session closed for user root Mar 24 15:45:35 volumio sudo[1482]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Mar 24 15:45:35 volumio sudo[1482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 15:45:35 volumio sudo[1485]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 24 15:45:35 volumio sudo[1485]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 15:45:35 volumio sudo[1485]: pam_unix(sudo:session): session closed for user root Mar 24 15:45:35 volumio volumio[1237]: info: UPNP Browser: Client initialized successfully Mar 24 15:45:35 volumio sudo[1487]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 24 15:45:35 volumio sudo[1487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 15:45:35 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 15:45:35 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 15:45:35 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 15:45:35 volumio sudo[1482]: pam_unix(sudo:session): session closed for user root Mar 24 15:45:35 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 24 15:45:35 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 24 15:45:35 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 24 15:45:35 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 24 15:45:35 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 24 15:45:35 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 24 15:45:35 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 24 15:45:35 volumio volumio[1237]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 24 15:45:35 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 15:45:35 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 15:45:35 volumio sudo[1499]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 24 15:45:35 volumio sudo[1499]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 24 15:45:35 volumio sudo[1506]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 24 15:45:35 volumio sudo[1499]: pam_unix(sudo:session): session closed for user root Mar 24 15:45:36 volumio volumio[1237]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 24 15:45:36 volumio volumio[1237]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 24 15:45:36 volumio volumio[1237]: info: [1774367136036] CoreMusicLibrary::Adding element Last_100 Mar 24 15:45:36 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 15:45:36 volumio volumio[1237]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 24 15:45:36 volumio volumio[1237]: info: [1774367136055] CoreMusicLibrary::Adding element Webradio Mar 24 15:45:36 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 15:45:36 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 15:45:36 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:36.180Z level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 15:45:36 volumio volumio[1237]: info: Initializing BBC Radios Mar 24 15:45:36 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 15:45:36 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 15:45:36 volumio volumio[1237]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 24 15:45:36 volumio volumio[1237]: info: [1774367136735] CoreMusicLibrary::Adding element Mother Earth Radio Mar 24 15:45:36 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 15:45:36 volumio volumio[1237]: Cannot find translation for source Mother Earth Radio Mar 24 15:45:36 volumio volumio[1237]: info: [MER] Plugin started (SSE mode) Mar 24 15:45:36 volumio volumio[1237]: info: Volumio Calling Home Mar 24 15:45:37 volumio volumio[1424]: Starting albumart workers Mar 24 15:45:38 volumio volumio[1426]: Starting albumart workers Mar 24 15:45:38 volumio volumio[1425]: Starting albumart workers Mar 24 15:45:38 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Mar 24 15:45:39 volumio mpd[1510]: 2026-03-24T15:45:39 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 24 15:45:39 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 24 15:45:39 volumio sudo[1487]: pam_unix(sudo:session): session closed for user root Mar 24 15:45:39 volumio sudo[1473]: pam_unix(sudo:session): session closed for user root Mar 24 15:45:40 volumio sudo[1547]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 24 15:45:40 volumio sudo[1547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 15:45:40 volumio sudo[1549]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 24 15:45:40 volumio sudo[1549]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 15:45:40 volumio sudo[1549]: pam_unix(sudo:session): session closed for user root Mar 24 15:45:40 volumio sudo[1547]: pam_unix(sudo:session): session closed for user root Mar 24 15:45:40 volumio volumio[1237]: info: MPD Permissions set Mar 24 15:45:40 volumio volumio[1237]: info: Completed starting Core Plugins Mar 24 15:45:40 volumio volumio[1237]: info: ------------------------------------------- Mar 24 15:45:40 volumio volumio[1237]: info: ----- MyVolumio plugins startup ---- Mar 24 15:45:40 volumio volumio[1237]: info: ------------------------------------------- Mar 24 15:45:40 volumio volumio[1237]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 24 15:45:40 volumio volumio[1237]: info: MPD Permissions set Mar 24 15:45:40 volumio volumio[1237]: info: Discovery: A device disappeared from network Mar 24 15:45:40 volumio volumio[1237]: 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 Mar 24 15:45:40 volumio volumio[1237]: info: MPD running with PID1510 Mar 24 15:45:40 volumio volumio[1237]: ,establishing connection Mar 24 15:45:40 volumio volumio[1237]: info: Volumio called home Mar 24 15:45:40 volumio volumio[1237]: info: Discovery: A device disappeared from network Mar 24 15:45:40 volumio volumio[1237]: 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 Mar 24 15:45:40 volumio volumio[1237]: info: Received Get System Info Mar 24 15:45:40 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 15:45:40 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 15:45:40 volumio volumio[1237]: info: Discovery: Getting this device information Mar 24 15:45:40 volumio volumio[1237]: info: CoreCommandRouter::volumioGetState Mar 24 15:45:40 volumio volumio[1237]: info: CorePlayQueue::getTrack 0 Mar 24 15:45:40 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 15:45:40 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:40.751Z level=INFO msg="system info for e7949be0cc71e89205f6c05559ed67c8" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.104 Mar 24 15:45:40 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:40.766Z level=INFO msg="bootstrapping state" hasInternet=true Mar 24 15:45:40 volumio volumio[1237]: error: MPD error: The expression evaluated to a falsy value: Mar 24 15:45:40 volumio volumio[1237]: assert.ok(self.idling) Mar 24 15:45:40 volumio volumio[1237]: error: The expression evaluated to a falsy value: Mar 24 15:45:40 volumio volumio[1237]: assert.ok(self.idling) Mar 24 15:45:40 volumio volumio[1237]: error: MPD error: The expression evaluated to a falsy value: Mar 24 15:45:40 volumio volumio[1237]: assert.ok(self.idling) Mar 24 15:45:40 volumio volumio[1237]: error: The expression evaluated to a falsy value: Mar 24 15:45:40 volumio volumio[1237]: assert.ok(self.idling) Mar 24 15:45:40 volumio volumio[1237]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Mar 24 15:45:40 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 15:45:40 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 15:45:40 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 15:45:40 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 15:45:40 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 15:45:40 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 15:45:40 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 15:45:40 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 15:45:41 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 15:45:41 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 15:45:41 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 15:45:41 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 15:45:41 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 15:45:41 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 15:45:41 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 15:45:41 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 15:45:41 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 15:45:41 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 15:45:41 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 15:45:41 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 15:45:41 volumio volumio[1237]: info: Received Get System Info Mar 24 15:45:41 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 15:45:41 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 15:45:41 volumio volumio[1237]: info: Discovery: Getting this device information Mar 24 15:45:41 volumio volumio[1237]: info: CoreCommandRouter::volumioGetState Mar 24 15:45:41 volumio volumio[1237]: info: CorePlayQueue::getTrack 0 Mar 24 15:45:41 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 15:45:41 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Mar 24 15:45:41 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 24 15:45:41 volumio volumio-remote-updater[840]: Test mode enabled Mar 24 15:45:41 volumio volumio-remote-updater[840]: Alpha mode disabled Mar 24 15:45:41 volumio volumio-remote-updater[840]: Alpha legacy test mode disabled Mar 24 15:45:41 volumio volumio[1237]: error: updateQueue error: null Mar 24 15:45:41 volumio volumio[1237]: info: Discovery: adding a37764bd-34e7-446f-b7b6-5ea2d0485f85 Mar 24 15:45:41 volumio volumio[1237]: info: Discovery: Found device Volumio Mar 24 15:45:41 volumio volumio[1237]: info: CoreCommandRouter::volumioGetState Mar 24 15:45:41 volumio volumio[1237]: info: CorePlayQueue::getTrack 0 Mar 24 15:45:41 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 24 15:45:41 volumio volumio[1237]: info: Discovery: this is already registered, a37764bd-34e7-446f-b7b6-5ea2d0485f85 Mar 24 15:45:41 volumio volumio[1237]: info: Discovery: Found device Volumio Mar 24 15:45:41 volumio volumio[1237]: info: CoreCommandRouter::volumioGetState Mar 24 15:45:41 volumio volumio[1237]: info: CorePlayQueue::getTrack 0 Mar 24 15:45:41 volumio volumio[1237]: info: Starting Shairport Sync Mar 24 15:45:41 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Mar 24 15:45:41 volumio sudo[1601]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 24 15:45:41 volumio volumio[1237]: info: Starting Shairport Sync Mar 24 15:45:41 volumio sudo[1601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 15:45:41 volumio sudo[1606]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 24 15:45:41 volumio sudo[1606]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 15:45:41 volumio volumio[1237]: info: Starting Shairport Sync Mar 24 15:45:41 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 24 15:45:41 volumio sudo[1609]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 24 15:45:41 volumio sudo[1609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 15:45:41 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 24 15:45:41 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 24 15:45:41 volumio systemd[1]: shairport-sync.service: Consumed 1.428s CPU time. Mar 24 15:45:41 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 24 15:45:41 volumio sudo[1606]: pam_unix(sudo:session): session closed for user root Mar 24 15:45:41 volumio sudo[1601]: pam_unix(sudo:session): session closed for user root Mar 24 15:45:41 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 24 15:45:41 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 24 15:45:41 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 24 15:45:41 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 24 15:45:41 volumio sudo[1609]: pam_unix(sudo:session): session closed for user root Mar 24 15:45:41 volumio sudo[1614]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 24 15:45:41 volumio sudo[1614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 15:45:41 volumio sudo[1614]: pam_unix(sudo:session): session closed for user root Mar 24 15:45:41 volumio volumio[1237]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 15:45:41 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 15:45:41 volumio volumio[1237]: info: Shairport-Sync Started Mar 24 15:45:41 volumio volumio[1237]: Error adding Membership: Error: addMembership EINVAL Mar 24 15:45:41 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 15:45:41 volumio volumio[1237]: info: Upmpdcli Daemon Started Mar 24 15:45:41 volumio volumio[1237]: info: Shairport-Sync Started Mar 24 15:45:41 volumio volumio[1237]: info: Shairport-Sync Started Mar 24 15:45:41 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 15:45:41 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 15:45:42 volumio volumio[1237]: info: CoreCommandRouter::volumioGetState Mar 24 15:45:42 volumio volumio[1237]: info: CorePlayQueue::getTrack 0 Mar 24 15:45:42 volumio volumio[1237]: info: Received Get System Info Mar 24 15:45:42 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 15:45:42 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 15:45:42 volumio volumio[1237]: info: Discovery: Getting this device information Mar 24 15:45:42 volumio volumio[1237]: info: CoreCommandRouter::volumioGetState Mar 24 15:45:42 volumio volumio[1237]: info: CorePlayQueue::getTrack 0 Mar 24 15:45:42 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 15:45:42 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 15:45:42 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 15:45:42 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:42.177Z level=INFO msg="enabling local network discovery" Mar 24 15:45:42 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:42.198Z level=INFO msg="enabling BLE discovery" Mar 24 15:45:43 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:43.035Z level=INFO msg="service successfully established" component=discovery/localnet Mar 24 15:45:43 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:43.227Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.171:49540 Mar 24 15:45:45 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:45.459Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.171:49540 @ 0x3133650" latency=429.415187ms timeout=10s Mar 24 15:45:45 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:45.459Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.171:49540 @ 0x3133650" Mar 24 15:45:45 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:45.467Z level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.171:49540 @ 0x3133650" latency=430.308206ms platform=PLATFORM_ANDROID version=5.260209.0 Mar 24 15:45:45 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 15:45:45 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 15:45:45 volumio volumio[1237]: info: Received Get System Info Mar 24 15:45:45 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 15:45:45 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 15:45:45 volumio volumio[1237]: info: Discovery: Getting this device information Mar 24 15:45:45 volumio volumio[1237]: info: CoreCommandRouter::volumioGetState Mar 24 15:45:45 volumio volumio[1237]: info: CorePlayQueue::getTrack 0 Mar 24 15:45:45 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 15:45:45 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:45.496Z level=INFO msg="emitting device name changed event" component=server peer="192.168.1.171:49540 @ 0x3133650" name=Volumio Mar 24 15:45:45 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:45.499Z level=INFO msg="emitting device language changed event" component=server peer="192.168.1.171:49540 @ 0x3133650" language=en Mar 24 15:45:45 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 24 15:45:45 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:45.533Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.171:49540 @ 0x3133650" timezone=Europe/London Mar 24 15:45:45 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:45.534Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.171:49540 @ 0x3133650" available=true connected=false macAddress= ip4Address= ip6Address= Mar 24 15:45:45 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:45.538Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.171:49540 @ 0x3133650" available=true connected=true macAddress=2c:cf:67:40:2e:f0 ip4Address=192.168.1.190/24 ip6Address= ssid=EE-N6T73G Mar 24 15:45:45 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:45.538Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.171:49540 @ 0x3133650" setupComplete=true Mar 24 15:45:45 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 24 15:45:45 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 24 15:45:45 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 15:45:45 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 24 15:45:45 volumio volumio[1237]: amixer -c 0 info | grep "bcm2835 ALSA" Mar 24 15:45:45 volumio volumio[1237]: amixer -c 1 info | grep "bcm2835 Headphones" Mar 24 15:45:45 volumio volumio[1237]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Mar 24 15:45:45 volumio volumio[1237]: amixer -c 2 info | grep "vc4-hdmi-0" Mar 24 15:45:46 volumio volumio[1237]: Card sysdefault:2 'vc4hdmi0'/'vc4-hdmi-0' Mar 24 15:45:46 volumio volumio[1237]: amixer -c 3 info | grep "vc4-hdmi-1" Mar 24 15:45:46 volumio volumio[1237]: Card sysdefault:3 'vc4hdmi1'/'vc4-hdmi-1' Mar 24 15:45:46 volumio volumio[1237]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 24 15:45:46 volumio volumio[1237]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Mar 24 15:45:46 volumio volumio[1237]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 24 15:45:46 volumio volumio[1237]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 24 15:45:46 volumio volumio[1237]: amixer -c 5 info | grep "NAD USB Audio" Mar 24 15:45:46 volumio volumio[1237]: Card sysdefault:5 'Audio'/'NAD Electronics NAD USB Audio at usb-0000:01:00.0-1.4, full speed' Mar 24 15:45:48 volumio volumio[1237]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Mar 24 15:45:48 volumio volumio[1237]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 24 15:45:48 volumio volumio[1237]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 24 15:45:48 volumio volumio[1237]: amixer -c 5 info | grep "NAD USB Audio" Mar 24 15:45:48 volumio volumio[1237]: Card sysdefault:5 'Audio'/'NAD Electronics NAD USB Audio at usb-0000:01:00.0-1.4, full speed' Mar 24 15:45:48 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:48.303Z level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.171:49540 @ 0x3133650" selectedOutputId=5 Mar 24 15:45:51 volumio volumio[1237]: info: Received Get System Info Mar 24 15:45:51 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 15:45:51 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 15:45:51 volumio volumio[1237]: info: Discovery: Getting this device information Mar 24 15:45:51 volumio volumio[1237]: info: CoreCommandRouter::volumioGetState Mar 24 15:45:51 volumio volumio[1237]: info: CorePlayQueue::getTrack 0 Mar 24 15:45:51 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 15:45:51 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:51.046Z level=INFO msg="emitting software info changed event" component=server peer="192.168.1.171:49540 @ 0x3133650" currentVersion=4.104 latestVersion=4.104 Mar 24 15:45:51 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:51.046Z level=INFO msg="emitting software update progress event" component=server peer="192.168.1.171:49540 @ 0x3133650" status=UPDATE_STATUS_NONE progress=0 Mar 24 15:45:51 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:51.047Z level=INFO msg="emitting user changed event" component=server peer="192.168.1.171:49540 @ 0x3133650" userId= Mar 24 15:45:51 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:51.047Z level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.171:49540 @ 0x3133650" providers=3 Mar 24 15:45:51 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 15:45:51 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:51.108Z level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.171:49540 @ 0x3133650" plugins=0 Mar 24 15:45:51 volumio volumio[1237]: info: CoreCommandRouter::volumioGetState Mar 24 15:45:51 volumio volumio[1237]: info: CorePlayQueue::getTrack 0 Mar 24 15:45:51 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:51.182Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.171:49540 @ 0x3133650" state=STATUS_STOPPED positionMs=0 volume=47 Mar 24 15:45:51 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:51.182Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.171:49540 @ 0x3133650" id= title= Mar 24 15:45:51 volumio volumio5-onboarding[1497]: time=2026-03-24T15:45:51.216Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.171:49540 @ 0x3133650" latency=421.014532ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 24 15:45:51 volumio volumio[1237]: info: Adding plugin bluetooth to MyMusic Plugins Mar 24 15:45:51 volumio volumio[1237]: info: Adding plugin multiroom to MyMusic Plugins Mar 24 15:45:51 volumio volumio[1237]: info: Adding plugin metavolumio to MyMusic Plugins Mar 24 15:45:51 volumio volumio[1237]: info: Adding plugin cd_controller to MyMusic Plugins Mar 24 15:45:51 volumio volumio[1237]: info: Adding plugin qobuzconnect to MyMusic Plugins Mar 24 15:45:51 volumio volumio[1237]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 24 15:45:51 volumio volumio[1237]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 24 15:45:51 volumio volumio[1237]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 24 15:45:56 volumio volumio[1237]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 24 15:45:56 volumio volumio[1237]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 24 15:45:56 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 15:45:56 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 15:45:56 volumio volumio[1237]: info: Starting MyVolumio Remote Streaming Endpoints Mar 24 15:45:56 volumio volumio[1237]: info: MyVolumio login type: Token Mar 24 15:45:56 volumio volumio[1237]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 24 15:45:56 volumio volumio[1237]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 24 15:45:59 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Mar 24 15:46:00 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 24 15:46:00 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 24 15:46:01 volumio volumio[1237]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 24 15:46:01 volumio volumio[1237]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 24 15:46:01 volumio volumio[1237]: info: Streaming services startup Mar 24 15:46:01 volumio volumio[1237]: info: Starting Streaming Daemon Mar 24 15:46:01 volumio volumio[1237]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 24 15:46:01 volumio sudo[1726]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 24 15:46:01 volumio sudo[1726]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 15:46:01 volumio sudo[1726]: pam_unix(sudo:session): session closed for user root Mar 24 15:46:02 volumio upmpdcli[1732]: writing RSA key Mar 24 15:46:02 volumio volumio[1237]: error: Cannot start Volumio Streaming Daemon Mar 24 15:46:02 volumio volumio[1237]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 24 15:46:02 volumio volumio[1237]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 24 15:46:02 volumio volumio[1237]: error: MyVolumio Custom Token format not valid, refreshing it Mar 24 15:46:03 volumio sudo[1740]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 24 15:46:03 volumio sudo[1742]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 24 15:46:03 volumio sudo[1742]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 15:46:03 volumio sudo[1740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 15:46:03 volumio sudo[1740]: pam_unix(sudo:session): session closed for user root Mar 24 15:46:03 volumio sudo[1742]: pam_unix(sudo:session): session closed for user root Mar 24 15:46:03 volumio volumio[1237]: verbose: New Socket.io Connection to 192.168.1.190 from 192.168.1.171 UA: Mozilla/5.0 (Linux; Android 15; 22081212UG Build/AQ3A.241006.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.159 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Mar 24 15:46:03 volumio volumio[1237]: info: MyVolumio login type: Token Mar 24 15:46:04 volumio sudo[1746]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 24 15:46:04 volumio sudo[1746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 15:46:04 volumio sudo[1746]: pam_unix(sudo:session): session closed for user root Mar 24 15:46:04 volumio sudo[1748]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 24 15:46:04 volumio sudo[1748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 15:46:04 volumio sudo[1748]: pam_unix(sudo:session): session closed for user root Mar 24 15:46:04 volumio volumio[1237]: verbose: New Socket.io Connection to 192.168.1.190 from 192.168.1.171 UA: Mozilla/5.0 (Linux; Android 15; 22081212UG Build/AQ3A.241006.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.159 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Mar 24 15:46:04 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 24 15:46:04 volumio volumio[1237]: info: CoreCommandRouter::volumioGetVisibleSources Mar 24 15:46:04 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 15:46:04 volumio volumio[1237]: info: CoreCommandRouter::volumioGetState Mar 24 15:46:04 volumio volumio[1237]: info: CorePlayQueue::getTrack 0 Mar 24 15:46:04 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 24 15:46:04 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 24 15:46:04 volumio volumio[1237]: info: Received Get System Info Mar 24 15:46:04 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 15:46:04 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 15:46:04 volumio volumio[1237]: info: Discovery: Getting this device information Mar 24 15:46:04 volumio volumio[1237]: info: CoreCommandRouter::volumioGetState Mar 24 15:46:04 volumio volumio[1237]: info: CorePlayQueue::getTrack 0 Mar 24 15:46:04 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 15:46:04 volumio volumio[1237]: info: CoreCommandRouter::volumioGetState Mar 24 15:46:04 volumio volumio[1237]: info: CorePlayQueue::getTrack 0 Mar 24 15:46:04 volumio volumio[1237]: info: Listing playlists Mar 24 15:46:04 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 24 15:46:04 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 24 15:46:04 volumio volumio[1237]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 24 15:46:04 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 24 15:46:05 volumio volumio[1237]: info: MyVolumio token set successfully Mar 24 15:46:05 volumio volumio[1237]: info: MYVOLUMIO: Adding device Mar 24 15:46:05 volumio volumio[1237]: info: MYVOLUMIO: Evaluating Server Mar 24 15:46:05 volumio volumio[1237]: info: MyVolumio Plan changed: premium Mar 24 15:46:05 volumio volumio[1237]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Mar 24 15:46:05 volumio volumio[1237]: info: Removing browser output: myVolumio user plan is not superstar Mar 24 15:46:05 volumio volumio[1237]: info: Removing audio output: Mar 24 15:46:05 volumio volumio[1237]: info: MYVOLUMIO: Adding device Mar 24 15:46:05 volumio volumio[1237]: info: MYVOLUMIO: Evaluating Server Mar 24 15:46:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 15:46:06 volumio volumio[1237]: info: Received Get System Info Mar 24 15:46:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 15:46:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 15:46:06 volumio volumio[1237]: info: Discovery: Getting this device information Mar 24 15:46:06 volumio volumio[1237]: info: CoreCommandRouter::volumioGetState Mar 24 15:46:06 volumio volumio[1237]: info: CorePlayQueue::getTrack 0 Mar 24 15:46:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 15:46:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 24 15:46:06 volumio volumio[1237]: info: Setting Geolocation for MyVolumio to eu3 Mar 24 15:46:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 15:46:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 15:46:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 15:46:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 15:46:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 15:46:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 24 15:46:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 15:46:06 volumio volumio[1237]: info: Received Get System Info Mar 24 15:46:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 15:46:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 15:46:06 volumio volumio[1237]: info: Discovery: Getting this device information Mar 24 15:46:06 volumio volumio[1237]: info: CoreCommandRouter::volumioGetState Mar 24 15:46:06 volumio volumio[1237]: info: CorePlayQueue::getTrack 0 Mar 24 15:46:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 15:46:06 volumio volumio[1237]: info: Setting Geolocation for MyVolumio to eu3 Mar 24 15:46:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 15:46:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 15:46:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 15:46:06 volumio volumio[1237]: info: Remote config written successfully Mar 24 15:46:06 volumio volumio[1237]: info: Starting Tunnel 1 Mar 24 15:46:06 volumio volumio[1237]: info: Starting Tunnel Connection Checker Mar 24 15:46:06 volumio volumio[1237]: info: Completed starting MyVolumio Plugin Mar 24 15:46:07 volumio volumio-remote-updater[840]: Test mode enabled Mar 24 15:46:07 volumio volumio-remote-updater[840]: Alpha mode disabled Mar 24 15:46:07 volumio volumio-remote-updater[840]: Alpha legacy test mode disabled Mar 24 15:46:07 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 24 15:46:07 volumio volumio[1237]: info: MYVolumio Device enabled Mar 24 15:46:07 volumio volumio[1237]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Mar 24 15:46:07 volumio volumio[1237]: info: MyVolumio status changed Mar 24 15:46:07 volumio volumio[1237]: info: Streaming services startup Mar 24 15:46:07 volumio volumio[1237]: info: Starting Streaming Daemon Mar 24 15:46:07 volumio sudo[1795]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 24 15:46:07 volumio sudo[1795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 15:46:07 volumio volumio[1237]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 15:46:07 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 15:46:07 volumio sudo[1795]: pam_unix(sudo:session): session closed for user root Mar 24 15:46:07 volumio volumio[1237]: info: Successfully Added MyVolumio device Mar 24 15:46:07 volumio volumio[1237]: error: Cannot start Volumio Streaming Daemon Mar 24 15:46:07 volumio volumio[1237]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 24 15:46:07 volumio volumio[1237]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 24 15:46:07 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 24 15:46:07 volumio volumio[1237]: error: [MyVolumio PluginManager] Cache data is invalid! Mar 24 15:46:07 volumio volumio[1237]: info: Successfully Added MyVolumio device Mar 24 15:46:07 volumio volumio[1237]: info: Updating MyVolumio device info Mar 24 15:46:07 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 15:46:07 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 15:46:07 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 15:46:07 volumio volumio[1237]: info: Updating MyVolumio device info Mar 24 15:46:07 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 15:46:07 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 15:46:07 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 15:46:08 volumio volumio[1237]: info: Successfully Updated MyVolumio device Mar 24 15:46:08 volumio volumio[1237]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Mar 24 15:46:09 volumio volumio[1237]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Mar 24 15:46:09 volumio volumio[1237]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Mar 24 15:46:09 volumio volumio[1237]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Mar 24 15:46:09 volumio volumio[1237]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Mar 24 15:46:14 volumio volumio[1237]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Mar 24 15:46:14 volumio sudo[1814]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 24 15:46:14 volumio sudo[1814]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 15:46:14 volumio sudo[1814]: pam_unix(sudo:session): session closed for user root Mar 24 15:46:14 volumio volumio[1237]: info: MRS: MultiRoom plugin initialized Mar 24 15:46:14 volumio volumio[1237]: info: MRS: STOPPING SNAPCLIENT Mar 24 15:46:14 volumio volumio[1237]: info: MRS: Snap server stop Mar 24 15:46:14 volumio volumio[1237]: info: MRS: STOPPING volumioStreaming Mar 24 15:46:14 volumio volumio[1237]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Mar 24 15:46:14 volumio sudo[1831]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 24 15:46:14 volumio sudo[1831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 15:46:14 volumio sudo[1835]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 24 15:46:14 volumio sudo[1835]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 15:46:14 volumio volumio[1237]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Mar 24 15:46:14 volumio sudo[1833]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 24 15:46:14 volumio sudo[1833]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 15:46:14 volumio sudo[1838]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 24 15:46:14 volumio sudo[1838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 15:46:14 volumio volumio[1237]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Mar 24 15:46:14 volumio sudo[1838]: pam_unix(sudo:session): session closed for user root Mar 24 15:46:14 volumio sudo[1835]: pam_unix(sudo:session): session closed for user root Mar 24 15:46:14 volumio volumio[1237]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Mar 24 15:46:14 volumio sudo[1833]: pam_unix(sudo:session): session closed for user root Mar 24 15:46:14 volumio sudo[1831]: pam_unix(sudo:session): session closed for user root Mar 24 15:46:14 volumio volumio[1237]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Mar 24 15:46:14 volumio volumio[1237]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Mar 24 15:46:14 volumio volumio[1237]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Mar 24 15:46:14 volumio volumio[1237]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Mar 24 15:46:15 volumio volumio[1237]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Mar 24 15:46:15 volumio volumio[1237]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Mar 24 15:46:15 volumio volumio[1237]: info: Preparing to generate the ALSA configuration file Mar 24 15:46:15 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 24 15:46:15 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 24 15:46:15 volumio volumio[1237]: info: Successfully Updated MyVolumio device Mar 24 15:46:15 volumio volumio[1237]: info: CoreCommandRouter::volumioGetQueue Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::getQueue Mar 24 15:46:15 volumio volumio[1237]: info: CorePlayQueue::getQueue Mar 24 15:46:15 volumio volumio[1237]: info: Preload queue cleared Mar 24 15:46:15 volumio volumio[1237]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::ClearQueue Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::stop Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 15:46:15 volumio volumio[1237]: info: CorePlayQueue::clearPlayQueue Mar 24 15:46:15 volumio volumio[1237]: info: CorePlayQueue::saveQueue Mar 24 15:46:15 volumio volumio[1237]: info: CoreCommandRouter::volumioPushQueue Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::addQueueItems Mar 24 15:46:15 volumio volumio[1237]: info: CorePlayQueue::addQueueItems Mar 24 15:46:15 volumio volumio[1237]: info: Preload queue cleared Mar 24 15:46:15 volumio volumio[1237]: info: Adding Item to queue: motherearthradio/radio/flac192 Mar 24 15:46:15 volumio volumio[1237]: info: Exploding uri motherearthradio/radio/flac192 in service motherearthradio Mar 24 15:46:15 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 24 15:46:15 volumio volumio[1237]: info: CoreCommandRouter::volumioPushQueue Mar 24 15:46:15 volumio volumio[1237]: info: CorePlayQueue::saveQueue Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::updateTrackBlock Mar 24 15:46:15 volumio volumio[1237]: info: CorePlayQueue::getTrackBlock Mar 24 15:46:15 volumio volumio[1237]: info: CoreCommandRouter::volumioPlay Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::play index 0 Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::stop Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::play index undefined Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 15:46:15 volumio volumio[1237]: info: CorePlayQueue::getTrack 0 Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::startPlaybackTimer Mar 24 15:46:15 volumio volumio[1237]: info: CorePlayQueue::getTrack 0 Mar 24 15:46:15 volumio volumio[1237]: info: CoreCommandRouter::volumioGetVisibleSources Mar 24 15:46:15 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 15:46:15 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 24 15:46:15 volumio volumio[1237]: info: [MER] ▶️ Playing: https://stream.motherearthradio.de/listen/motherearth/motherearth Mar 24 15:46:15 volumio volumio[1237]: info: [MER] 🔌 Starting SSE for Radio Mar 24 15:46:15 volumio volumio[1237]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 15:46:15 volumio volumio[1237]: info: MRS: Removed streaming files Mar 24 15:46:15 volumio volumio[1237]: info: MRS: volumioStreaming STOPPED Mar 24 15:46:15 volumio volumio[1237]: info: MRS: SNAPSERVER STOPPED Mar 24 15:46:15 volumio volumio[1237]: info: MRS: SNAPCLIENT STOPPED Mar 24 15:46:15 volumio volumio[1237]: info: Preload queue cleared Mar 24 15:46:15 volumio volumio[1237]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::ClearQueue Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::stop Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 15:46:15 volumio volumio[1237]: info: CorePlayQueue::clearPlayQueue Mar 24 15:46:15 volumio volumio[1237]: info: CorePlayQueue::saveQueue Mar 24 15:46:15 volumio volumio[1237]: info: CoreCommandRouter::volumioPushQueue Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::addQueueItems Mar 24 15:46:15 volumio volumio[1237]: info: CorePlayQueue::addQueueItems Mar 24 15:46:15 volumio volumio[1237]: info: Preload queue cleared Mar 24 15:46:15 volumio volumio[1237]: info: Adding Item to queue: motherearthradio/radio/flac192 Mar 24 15:46:15 volumio volumio[1237]: info: Using cached record of: motherearthradio/radio/flac192 Mar 24 15:46:15 volumio volumio[1237]: info: CoreCommandRouter::volumioPushQueue Mar 24 15:46:15 volumio volumio[1237]: info: CorePlayQueue::saveQueue Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::updateTrackBlock Mar 24 15:46:15 volumio volumio[1237]: info: CorePlayQueue::getTrackBlock Mar 24 15:46:15 volumio volumio[1237]: info: CoreCommandRouter::volumioPlay Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::play index 0 Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::stop Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::play index undefined Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 15:46:15 volumio volumio[1237]: info: CorePlayQueue::getTrack 0 Mar 24 15:46:15 volumio volumio[1237]: info: CoreStateMachine::startPlaybackTimer Mar 24 15:46:15 volumio volumio[1237]: info: CorePlayQueue::getTrack 0 Mar 24 15:46:15 volumio volumio[1237]: info: CoreCommandRouter::volumioGetVisibleSources Mar 24 15:46:15 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 15:46:15 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 24 15:46:15 volumio volumio[1237]: info: [MER] ▶️ Playing: https://stream.motherearthradio.de/listen/motherearth/motherearth Mar 24 15:46:15 volumio volumio[1237]: info: [MER] 🔌 Starting SSE for Radio Mar 24 15:46:15 volumio volumio[1237]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 15:46:15 volumio volumio[1237]: info: sendMpdCommand stop took 284 milliseconds Mar 24 15:46:15 volumio volumio[1237]: info: sendMpdCommand stop took 75 milliseconds Mar 24 15:46:15 volumio volumio[1237]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 15:46:15 volumio volumio[1237]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 15:46:15 volumio volumio[1237]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 15:46:15 volumio volumio[1237]: Error: socket hang up Mar 24 15:46:15 volumio volumio[1237]: at connResetException (node:internal/errors:720:14) Mar 24 15:46:15 volumio volumio[1237]: at TLSSocket.socketCloseListener (node:_http_client:468:25) Mar 24 15:46:15 volumio volumio[1237]: at TLSSocket.emit (node:events:526:35) Mar 24 15:46:15 volumio volumio[1237]: at node:net:337:12 Mar 24 15:46:15 volumio volumio[1237]: at TCP.done (node:_tls_wrap:631:7) { Mar 24 15:46:15 volumio volumio[1237]: code: 'ECONNRESET' Mar 24 15:46:15 volumio volumio[1237]: } Mar 24 15:46:15 volumio volumio[1237]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 15:46:19 volumio sudo[1860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-24 15:45' Mar 24 15:46:19 volumio sudo[1860]: 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="7a00388c05f7c476e375410e75778bd28211ce87" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 13 15:36:25 UTC 2026" VOLUMIO_VERSION="4.104" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="c0a6c64d0b6ca4fe7beeef2e593f362f"