-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2026-08-29 19:10:19 CEST. -- Aug 29 19:08:00 volumiopi3 ntpd[767]: Soliciting pool server 194.58.204.148 Aug 29 19:08:00 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:08:00 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:08:00 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 19:08:00 volumiopi3 volumio-remote-updater[585]: No test mode Aug 29 19:08:00 volumiopi3 volumio-remote-updater[585]: No alpha test mode Aug 29 19:08:00 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 29 19:08:01 volumiopi3 volumio[970]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 29 19:08:01 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 29 19:08:01 volumiopi3 ntpd[767]: Soliciting pool server 194.58.202.148 Aug 29 19:08:01 volumiopi3 volumio[970]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 29 19:08:01 volumiopi3 volumio[970]: info: Discovery: Restarting Browsing Aug 29 19:08:01 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:01.869+02:00 level=INFO msg="reset device setup process" component=server type=REQUEST_TYPE_RESET_DEVICE_SETUP peer="192.168.10.116:55670,00:00:00:00:00:00%01 @ 0x3126d50" latency=-634.366445ms timeout=10s complete=true Aug 29 19:08:01 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Aug 29 19:08:01 volumiopi3 volumio[970]: info: CoreCommandRouter::Reload Ui Aug 29 19:08:01 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Aug 29 19:08:01 volumiopi3 volumio[970]: info: Wizard terminated Successfully Aug 29 19:08:01 volumiopi3 volumio[970]: info: CoreCommandRouter::volumiosetStartupVolume Aug 29 19:08:01 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:08:01 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:01.892+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.10.116:55670,00:00:00:00:00:00%01 @ 0x3126d50" setupComplete=true Aug 29 19:08:01 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 19:08:01 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 19:08:01 volumiopi3 volumio[970]: info: Received Get System Info Aug 29 19:08:01 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:01.901+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.10.116:55670,00:00:00:00:00:00%01 @ 0x3126d50" setupComplete=true Aug 29 19:08:01 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 19:08:01 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 19:08:01 volumiopi3 volumio[970]: info: Discovery: Getting this device information Aug 29 19:08:01 volumiopi3 volumio[970]: info: CoreCommandRouter::volumioGetState Aug 29 19:08:01 volumiopi3 volumio[970]: info: CorePlayQueue::getTrack 0 Aug 29 19:08:01 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 19:08:01 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 19:08:01 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 19:08:02 volumiopi3 ntpd[767]: Soliciting pool server 162.159.200.1 Aug 29 19:08:02 volumiopi3 ntpd[767]: Soliciting pool server 194.58.207.148 Aug 29 19:08:02 volumiopi3 ntpd[767]: Soliciting pool server 194.58.206.20 Aug 29 19:08:02 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 19:08:02 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:02.908+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 29 19:08:02 volumiopi3 volumio[970]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 29 19:08:02 volumiopi3 volumio[970]: info: CoreCommandRouter::volumioGetState Aug 29 19:08:02 volumiopi3 volumio[970]: info: CorePlayQueue::getTrack 0 Aug 29 19:08:03 volumiopi3 ntpd[767]: Soliciting pool server 147.78.229.141 Aug 29 19:08:03 volumiopi3 ntpd[767]: Soliciting pool server 130.236.254.17 Aug 29 19:08:03 volumiopi3 ntpd[767]: Soliciting pool server 91.209.0.20 Aug 29 19:08:03 volumiopi3 ntpd[767]: Soliciting pool server 192.121.108.99 Aug 29 19:08:04 volumiopi3 ntpd[767]: Soliciting pool server 147.78.228.41 Aug 29 19:08:04 volumiopi3 ntpd[767]: Soliciting pool server 194.58.205.148 Aug 29 19:08:04 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:04.421+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.10.116:55670,00:00:00:00:00:00%01 @ 0x3126d50" latency=-641.706082ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 29 19:08:04 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:04.448+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.116:55670,00:00:00:00:00:00%01 @ 0x3126d50" latency=-642.099571ms timeout=10s Aug 29 19:08:04 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:04.520+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.116:55670,00:00:00:00:00:00%01 @ 0x3126d50" latency=-642.099571ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=72.387708ms Aug 29 19:08:04 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:04.532+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.116:55670,00:00:00:00:00:00%01 @ 0x3126d50" latency=-642.099571ms timeout=10s endpoint=http://pushupdates.volumio.org duration=83.729948ms Aug 29 19:08:04 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:04.546+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.116:55670,00:00:00:00:00:00%01 @ 0x3126d50" latency=-642.099571ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=97.183437ms Aug 29 19:08:04 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Aug 29 19:08:04 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 19:08:04 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:04.589+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.116:55670,00:00:00:00:00:00%01 @ 0x3126d50" latency=-642.099571ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=141.095ms Aug 29 19:08:04 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:04.680+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.116:55670,00:00:00:00:00:00%01 @ 0x3126d50" latency=-642.099571ms timeout=10s endpoint=https://www.googleapis.com duration=231.821927ms Aug 29 19:08:04 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:04.680+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.116:55670,00:00:00:00:00:00%01 @ 0x3126d50" latency=-642.099571ms timeout=10s endpoint=https://functions.volumio.cloud duration=232.042709ms Aug 29 19:08:04 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:04.688+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.116:55670,00:00:00:00:00:00%01 @ 0x3126d50" latency=-642.099571ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=240.216927ms Aug 29 19:08:04 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:04.791+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.116:55670,00:00:00:00:00:00%01 @ 0x3126d50" latency=-642.099571ms timeout=10s endpoint=https://functions.volumio.cloud duration=342.405208ms Aug 29 19:08:04 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:04.792+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.116:55670,00:00:00:00:00:00%01 @ 0x3126d50" latency=-642.099571ms timeout=10s endpoint=https://securetoken.googleapis.com duration=343.666354ms Aug 29 19:08:04 volumiopi3 volumio-remote-updater[585]: No test mode Aug 29 19:08:04 volumiopi3 volumio-remote-updater[585]: No alpha test mode Aug 29 19:08:04 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 29 19:08:04 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:04.929+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.116:55670,00:00:00:00:00:00%01 @ 0x3126d50" latency=-642.099571ms timeout=10s endpoint=https://database.volumio.cloud duration=480.812083ms Aug 29 19:08:05 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:05.142+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.116:55670,00:00:00:00:00:00%01 @ 0x3126d50" latency=-642.099571ms timeout=10s endpoint=http://cddb.volumio.org duration=693.748177ms Aug 29 19:08:05 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:05.142+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.116:55670,00:00:00:00:00:00%01 @ 0x3126d50" latency=-642.099571ms timeout=10s endpoint=http://plugins.volumio.org duration=694.469636ms Aug 29 19:08:05 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:05.281+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.116:55670,00:00:00:00:00:00%01 @ 0x3126d50" latency=-642.099571ms timeout=10s endpoint=https://google.com duration=833.129844ms Aug 29 19:08:05 volumiopi3 volumio[970]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 29 19:08:05 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 29 19:08:05 volumiopi3 ntpd[767]: Soliciting pool server 83.168.200.198 Aug 29 19:08:05 volumiopi3 ntpd[767]: Soliciting pool server 194.58.207.20 Aug 29 19:08:05 volumiopi3 sudo[1508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 19:08:05 volumiopi3 sudo[1508]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:08:05 volumiopi3 sudo[1508]: pam_unix(sudo:session): session closed for user root Aug 29 19:08:05 volumiopi3 sudo[1510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 19:08:05 volumiopi3 sudo[1510]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:08:05 volumiopi3 sudo[1510]: pam_unix(sudo:session): session closed for user root Aug 29 19:08:05 volumiopi3 volumio[970]: verbose: New Socket.io Connection to 192.168.10.200 from 192.168.10.116 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 5 Aug 29 19:08:05 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 19:08:06 volumiopi3 sudo[1514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 19:08:06 volumiopi3 sudo[1514]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:08:06 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 29 19:08:06 volumiopi3 sudo[1514]: pam_unix(sudo:session): session closed for user root Aug 29 19:08:06 volumiopi3 sudo[1516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 19:08:06 volumiopi3 sudo[1516]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:08:06 volumiopi3 sudo[1516]: pam_unix(sudo:session): session closed for user root Aug 29 19:08:06 volumiopi3 volumio[970]: verbose: New Socket.io Connection to 192.168.10.200 from 192.168.10.116 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 6 Aug 29 19:08:06 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 19:08:06 volumiopi3 volumio[970]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 19:08:06 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:08:06 volumiopi3 volumio[970]: info: CoreCommandRouter::volumioGetState Aug 29 19:08:06 volumiopi3 volumio[970]: info: CorePlayQueue::getTrack 0 Aug 29 19:08:06 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 19:08:06 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 19:08:06 volumiopi3 volumio[970]: info: Received Get System Info Aug 29 19:08:06 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 19:08:06 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 19:08:06 volumiopi3 volumio[970]: info: Discovery: Getting this device information Aug 29 19:08:06 volumiopi3 volumio[970]: info: CoreCommandRouter::volumioGetState Aug 29 19:08:06 volumiopi3 volumio[970]: info: CorePlayQueue::getTrack 0 Aug 29 19:08:06 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 19:08:06 volumiopi3 volumio[970]: info: CoreCommandRouter::volumioGetState Aug 29 19:08:06 volumiopi3 volumio[970]: info: CorePlayQueue::getTrack 0 Aug 29 19:08:06 volumiopi3 volumio[970]: info: Listing playlists Aug 29 19:08:06 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 29 19:08:06 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 19:08:06 volumiopi3 volumio[970]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 29 19:08:06 volumiopi3 volumio[970]: info: Discovery: Restarting Browsing Aug 29 19:08:06 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 29 19:08:07 volumiopi3 volumio[970]: info: CoreCommandRouter::volumioGetState Aug 29 19:08:07 volumiopi3 volumio[970]: info: CorePlayQueue::getTrack 0 Aug 29 19:08:08 volumiopi3 ntpd[767]: receive: Unexpected origin timestamp 0xee3d9077.585dad6e does not match aorg 0000000000.00000000 from server@192.121.108.99 xmt 0xee3d9078.19e56649 Aug 29 19:08:08 volumiopi3 ntpd[767]: receive: Unexpected origin timestamp 0xee3d9077.585b4d30 does not match aorg 0000000000.00000000 from server@147.78.228.41 xmt 0xee3d9078.19e9675f Aug 29 19:08:08 volumiopi3 ntpd[767]: receive: Unexpected origin timestamp 0xee3d9077.5853d930 does not match aorg 0000000000.00000000 from server@194.58.205.148 xmt 0xee3d9078.19f4eb88 Aug 29 19:08:08 volumiopi3 ntpd[767]: receive: Unexpected origin timestamp 0xee3d9077.586436c8 does not match aorg 0000000000.00000000 from server@194.58.202.148 xmt 0xee3d9078.1ab45121 Aug 29 19:08:08 volumiopi3 ntpd[767]: receive: Unexpected origin timestamp 0xee3d9077.585ff43a does not match aorg 0000000000.00000000 from server@194.58.206.20 xmt 0xee3d9078.1bad8116 Aug 29 19:08:08 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:08:08 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:08:08 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 29 19:08:08 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 29 19:08:08 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 29 19:08:08 volumiopi3 volumio[970]: info: CoreCommandRouter::volumioGetBrowseSources Aug 29 19:08:08 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:08:08 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 29 19:08:08 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 19:08:08 volumiopi3 volumio[970]: info: Received Get System Info Aug 29 19:08:08 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 19:08:08 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 19:08:08 volumiopi3 volumio[970]: info: Discovery: Getting this device information Aug 29 19:08:08 volumiopi3 volumio[970]: info: CoreCommandRouter::volumioGetState Aug 29 19:08:08 volumiopi3 volumio[970]: info: CorePlayQueue::getTrack 0 Aug 29 19:08:08 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 19:08:10 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Aug 29 19:08:10 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 19:08:10 volumiopi3 systemd[1]: systemd-timedated.service: Succeeded. Aug 29 19:08:10 volumiopi3 volumio-remote-updater[585]: No test mode Aug 29 19:08:10 volumiopi3 volumio-remote-updater[585]: No alpha test mode Aug 29 19:08:10 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 29 19:08:10 volumiopi3 volumio[970]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 29 19:08:10 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 29 19:08:11 volumiopi3 volumio[970]: error: MyVolumio Plugin failed to authenticate in a timely fashion Aug 29 19:08:11 volumiopi3 volumio[970]: info: Completed starting MyVolumio Plugin Aug 29 19:08:11 volumiopi3 volumio[970]: [Metrics] CommandRouter: 54s 9.59ms Aug 29 19:08:11 volumiopi3 volumio[970]: info: CoreCommandRouter::volumiosetStartupVolume Aug 29 19:08:11 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:08:11 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:08:11 volumiopi3 volumio[970]: info: CoreCommandRouter::Close All Modals sent Aug 29 19:08:11 volumiopi3 volumio[970]: info: CoreCommandRouter::Close All Modals sent Aug 29 19:08:11 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 19:08:11 volumiopi3 volumio[970]: info: Received Get System Info Aug 29 19:08:11 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 19:08:11 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 19:08:11 volumiopi3 volumio[970]: info: Discovery: Getting this device information Aug 29 19:08:11 volumiopi3 volumio[970]: info: CoreCommandRouter::volumioGetState Aug 29 19:08:11 volumiopi3 volumio[970]: info: CorePlayQueue::getTrack 0 Aug 29 19:08:11 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 19:08:12 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Aug 29 19:08:12 volumiopi3 volumio[970]: info: MyVolumio login type: Token Aug 29 19:08:12 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 19:08:12 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:12.274+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.10.116:55670,00:00:00:00:00:00%01 @ 0x3126d50" userId= Aug 29 19:08:12 volumiopi3 volumio[970]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 29 19:08:12 volumiopi3 volumio[970]: info: Discovery: Restarting Browsing Aug 29 19:08:12 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 29 19:08:12 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 19:08:12 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 29 19:08:13 volumiopi3 volumio[970]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 29 19:08:13 volumiopi3 volumio[970]: info: MyVolumio token set successfully Aug 29 19:08:13 volumiopi3 volumio[970]: info: MYVOLUMIO: Adding device Aug 29 19:08:13 volumiopi3 volumio[970]: info: MYVOLUMIO: Evaluating Server Aug 29 19:08:14 volumiopi3 volumio[970]: info: MyVolumio status changed Aug 29 19:08:14 volumiopi3 volumio[970]: info: Streaming services startup Aug 29 19:08:14 volumiopi3 volumio[970]: info: Starting Streaming Daemon Aug 29 19:08:14 volumiopi3 volumio[970]: info: Removing browser output: myVolumio user plan is not superstar Aug 29 19:08:14 volumiopi3 volumio[970]: info: Removing audio output: Aug 29 19:08:14 volumiopi3 volumio[970]: info: Stoppping Tunnel 1 Aug 29 19:08:14 volumiopi3 sudo[1565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 29 19:08:14 volumiopi3 sudo[1565]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:08:14 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Aug 29 19:08:14 volumiopi3 sudo[1567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 29 19:08:14 volumiopi3 sudo[1567]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:08:14 volumiopi3 sudo[1565]: pam_unix(sudo:session): session closed for user root Aug 29 19:08:14 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 19:08:14 volumiopi3 volumio[970]: info: Received Get System Info Aug 29 19:08:14 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 19:08:14 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 19:08:14 volumiopi3 volumio[970]: info: Discovery: Getting this device information Aug 29 19:08:14 volumiopi3 volumio[970]: info: CoreCommandRouter::volumioGetState Aug 29 19:08:14 volumiopi3 volumio[970]: info: CorePlayQueue::getTrack 0 Aug 29 19:08:14 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 19:08:14 volumiopi3 sudo[1567]: pam_unix(sudo:session): session closed for user root Aug 29 19:08:14 volumiopi3 volumio[970]: error: Cannot start Volumio Streaming Daemon Aug 29 19:08:14 volumiopi3 volumio[970]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 29 19:08:14 volumiopi3 volumio[970]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 29 19:08:14 volumiopi3 volumio[970]: info: Remote SSH Stopped Aug 29 19:08:15 volumiopi3 volumio[970]: info: Setting Geolocation for MyVolumio to eu8 Aug 29 19:08:15 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:08:15 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:08:15 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:08:15 volumiopi3 volumio[970]: info: Successfully Added MyVolumio device Aug 29 19:08:16 volumiopi3 volumio[970]: info: Updating MyVolumio device info Aug 29 19:08:16 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:08:16 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:08:16 volumiopi3 volumio[970]: info: Successfully Updated MyVolumio device Aug 29 19:08:16 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 19:08:17 volumiopi3 volumio[970]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 29 19:08:17 volumiopi3 volumio[970]: info: Discovery: Restarting Browsing Aug 29 19:08:18 volumiopi3 volumio[970]: info: BOOT COMPLETED Aug 29 19:08:22 volumiopi3 volumio[970]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 29 19:08:22 volumiopi3 volumio[970]: info: Discovery: Restarting Browsing Aug 29 19:08:25 volumiopi3 volumio[970]: info: CoreCommandRouter::Close All Modals sent Aug 29 19:08:25 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Aug 29 19:08:25 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 19:08:25 volumiopi3 volumio[970]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 29 19:08:25 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 29 19:08:25 volumiopi3 volumio[970]: info: Received Get System Version Aug 29 19:08:25 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:08:25 volumiopi3 volumio[970]: info: Received Get System Info Aug 29 19:08:25 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 19:08:25 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 19:08:25 volumiopi3 volumio[970]: info: Discovery: Getting this device information Aug 29 19:08:25 volumiopi3 volumio[970]: info: CoreCommandRouter::volumioGetState Aug 29 19:08:25 volumiopi3 volumio[970]: info: CorePlayQueue::getTrack 0 Aug 29 19:08:25 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 19:08:27 volumiopi3 volumio[970]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 29 19:08:27 volumiopi3 volumio[970]: info: Discovery: Restarting Browsing Aug 29 19:08:32 volumiopi3 volumio[970]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 29 19:08:32 volumiopi3 volumio[970]: info: Discovery: Restarting Browsing Aug 29 19:08:37 volumiopi3 volumio[970]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 29 19:08:37 volumiopi3 volumio[970]: info: Discovery: Restarting Browsing Aug 29 19:08:41 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 19:08:41 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 29 19:08:42 volumiopi3 volumio[970]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 29 19:08:42 volumiopi3 volumio[970]: info: Discovery: Restarting Browsing Aug 29 19:08:45 volumiopi3 volumio[970]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Aug 29 19:08:45 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:45.706+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:08:47 volumiopi3 volumio[970]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 29 19:08:47 volumiopi3 volumio[970]: info: Discovery: Restarting Browsing Aug 29 19:08:51 volumiopi3 volumio[970]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Aug 29 19:08:51 volumiopi3 volumio[970]: info: Folder /tmp/plugins removed Aug 29 19:08:51 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:51.105+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:08:51 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:51.982+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:08:51 volumiopi3 volumio[970]: info: Check plugin dependencies Aug 29 19:08:52 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:08:52 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:52.118+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:08:52 volumiopi3 volumio[970]: info: Checking if plugin already exists Aug 29 19:08:52 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:52.133+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:08:52 volumiopi3 volumio[970]: info: Rename folder Aug 29 19:08:52 volumiopi3 volumio[970]: info: Folder /tmp/downloaded_plugin.zip removed Aug 29 19:08:52 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:08:52.210+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:08:52 volumiopi3 volumio[970]: info: Move to category Aug 29 19:09:11 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:11.313+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:11 volumiopi3 volumio[970]: info: Checking if install.sh is present Aug 29 19:09:11 volumiopi3 volumio[970]: info: Executing install.sh Aug 29 19:09:11 volumiopi3 volumio[970]: verbose: New Socket.io Connection to 192.168.10.200 from 192.168.10.116 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 4 Aug 29 19:09:11 volumiopi3 volumio[970]: verbose: New Socket.io Connection to 192.168.10.200 from 192.168.10.116 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 5 Aug 29 19:09:11 volumiopi3 volumio[970]: verbose: New Socket.io Connection to 192.168.10.200 from 192.168.10.116 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 6 Aug 29 19:09:11 volumiopi3 volumio[970]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 29 19:09:11 volumiopi3 volumio[970]: info: Discovery: Restarting Browsing Aug 29 19:09:11 volumiopi3 sudo[1707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh Aug 29 19:09:11 volumiopi3 sudo[1707]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:09:11 volumiopi3 volumio[970]: info: creating filters folder and copying demo filters Aug 29 19:09:11 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:11.598+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:11 volumiopi3 volumio[970]: info: copying demo flters Aug 29 19:09:11 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:11.680+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:11 volumiopi3 volumio[970]: info: ./presets/ Aug 29 19:09:11 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:11.845+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:11 volumiopi3 volumio[970]: info: ./presets/2XEQ15/ Aug 29 19:09:11 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:11.854+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:11 volumiopi3 volumio[970]: info: ./presets/2XEQ15/.Bass-FusionDsp.json Aug 29 19:09:11 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:11.868+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:11 volumiopi3 volumio[970]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json Aug 29 19:09:11 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:11.878+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:11 volumiopi3 volumio[970]: info: ./presets/2XEQ15/.Classic-FusionDsp.json Aug 29 19:09:11 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:11.892+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:11 volumiopi3 volumio[970]: info: ./presets/2XEQ15/.Voice-FusionDsp.json Aug 29 19:09:11 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:11.915+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:11 volumiopi3 volumio[970]: info: ./presets/2XEQ15/.Rock-FusionDsp.json Aug 29 19:09:11 volumiopi3 sudo[1733]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update Aug 29 19:09:11 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:11.931+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:11 volumiopi3 sudo[1733]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:09:11 volumiopi3 volumio[970]: info: ./presets/PEQ/ Aug 29 19:09:11 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:11.943+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:11 volumiopi3 volumio[970]: info: ./presets/convfir/ Aug 29 19:09:11 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:11.956+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:11 volumiopi3 volumio[970]: info: ./presets/convfir/kef.json Aug 29 19:09:11 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:11.970+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:11 volumiopi3 volumio[970]: info: ./presets/convfir/test.json Aug 29 19:09:11 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:11.983+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:11 volumiopi3 volumio[970]: info: ./presets/EQ15/ Aug 29 19:09:11 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:11.999+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:12 volumiopi3 volumio[970]: info: ./presets/EQ15/.Bass-FusionDsp.json Aug 29 19:09:12 volumiopi3 volumio[970]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json Aug 29 19:09:12 volumiopi3 volumio[970]: info: ./presets/EQ15/.Classic-FusionDsp.json Aug 29 19:09:12 volumiopi3 volumio[970]: info: ./presets/EQ15/.Voice-FusionDsp.json Aug 29 19:09:12 volumiopi3 volumio[970]: info: ./presets/EQ15/.Rock-FusionDsp.json Aug 29 19:09:12 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:12.021+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:12 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:12.022+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:12 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:12.031+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:12 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:12.032+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:12 volumiopi3 volumio[970]: info: Installing/fusiondsp dependencies Aug 29 19:09:12 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:12.055+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:12 volumiopi3 volumio[970]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 29 19:09:12 volumiopi3 volumio[970]: info: CoreCommandRouter::volumioGetState Aug 29 19:09:12 volumiopi3 volumio[970]: info: CorePlayQueue::getTrack 0 Aug 29 19:09:13 volumiopi3 volumio[970]: info: Get:1 https://archive2.volumio.org/debian buster InRelease [54.2 kB] Aug 29 19:09:13 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:13.570+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:13 volumiopi3 volumio[970]: info: Get:2 https://apt2.volumio.org/raspbian buster InRelease [15.0 kB] Aug 29 19:09:13 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:13.790+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:14 volumiopi3 volumio[970]: info: Get:3 https://apt2.volumio.org/raspbian buster/main armhf Packages [13.0 MB] Aug 29 19:09:14 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:14.847+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:15 volumiopi3 volumio[970]: verbose: New Socket.io Connection to 192.168.10.200 from 192.168.10.116 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 8 Aug 29 19:09:15 volumiopi3 volumio[970]: info: Get:4 https://archive2.volumio.org/debian buster/main armhf Packages [400 kB] Aug 29 19:09:15 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:15.825+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:15 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 19:09:16 volumiopi3 volumio[970]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 19:09:16 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:09:16 volumiopi3 volumio[970]: info: CoreCommandRouter::volumioGetState Aug 29 19:09:16 volumiopi3 volumio[970]: info: CorePlayQueue::getTrack 0 Aug 29 19:09:16 volumiopi3 volumio[970]: info: CoreCommandRouter::volumioGetState Aug 29 19:09:16 volumiopi3 volumio[970]: info: CorePlayQueue::getTrack 0 Aug 29 19:09:16 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 19:09:16 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 19:09:16 volumiopi3 volumio[970]: info: Received Get System Info Aug 29 19:09:16 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 19:09:16 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 19:09:16 volumiopi3 volumio[970]: info: Discovery: Getting this device information Aug 29 19:09:16 volumiopi3 volumio[970]: info: CoreCommandRouter::volumioGetState Aug 29 19:09:16 volumiopi3 volumio[970]: info: CorePlayQueue::getTrack 0 Aug 29 19:09:16 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 19:09:16 volumiopi3 volumio[970]: info: CoreCommandRouter::volumioGetState Aug 29 19:09:16 volumiopi3 volumio[970]: info: CorePlayQueue::getTrack 0 Aug 29 19:09:16 volumiopi3 volumio[970]: info: Listing playlists Aug 29 19:09:16 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 29 19:09:16 volumiopi3 volumio[970]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 29 19:09:16 volumiopi3 volumio[970]: info: Discovery: Restarting Browsing Aug 29 19:09:21 volumiopi3 volumio[970]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 29 19:09:21 volumiopi3 volumio[970]: info: Discovery: Restarting Browsing Aug 29 19:09:26 volumiopi3 volumio[970]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 29 19:09:26 volumiopi3 volumio[970]: info: Discovery: Restarting Browsing Aug 29 19:09:31 volumiopi3 volumio[970]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 29 19:09:31 volumiopi3 volumio[970]: info: Discovery: Restarting Browsing Aug 29 19:09:34 volumiopi3 volumio[970]: info: Get:5 https://apt2.volumio.org/raspbian buster/contrib armhf Packages [58.8 kB] Aug 29 19:09:34 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:34.656+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:34 volumiopi3 volumio[970]: info: Get:6 https://apt2.volumio.org/raspbian buster/non-free armhf Packages [110 kB] Aug 29 19:09:34 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:34.922+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:35 volumiopi3 volumio[970]: info: Get:7 https://apt2.volumio.org/raspbian buster/rpi armhf Packages [1360 B] Aug 29 19:09:35 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:35.198+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:37 volumiopi3 volumio[970]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 29 19:09:37 volumiopi3 volumio[970]: info: Discovery: Restarting Browsing Aug 29 19:09:42 volumiopi3 volumio[970]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 29 19:09:42 volumiopi3 volumio[970]: info: Discovery: Restarting Browsing Aug 29 19:09:45 volumiopi3 volumio[970]: info: Enabling plugin fusiondsp Aug 29 19:09:45 volumiopi3 volumio[970]: info: Loading plugin "fusiondsp"... Aug 29 19:09:46 volumiopi3 volumio[970]: info: Preparing to generate the ALSA configuration file Aug 29 19:09:46 volumiopi3 volumio[970]: info: Fetched 13.7 MB in 34s (398 kB/s) Aug 29 19:09:46 volumiopi3 volumio5-onboarding[1211]: time=2026-08-29T19:09:46.873+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 29 19:09:46 volumiopi3 volumio[970]: info: Asound.conf file unchanged, so no further update is needed Aug 29 19:09:46 volumiopi3 volumio[970]: info: Output device has changed, restarting MPD Aug 29 19:09:46 volumiopi3 sudo[1935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 19:09:46 volumiopi3 volumio[970]: info: Output device has changed, restarting Shairport Sync Aug 29 19:09:46 volumiopi3 sudo[1935]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:09:46 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:09:46 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:09:46 volumiopi3 sudo[1935]: pam_unix(sudo:session): session closed for user root Aug 29 19:09:46 volumiopi3 sudo[1937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 19:09:46 volumiopi3 sudo[1937]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:09:47 volumiopi3 systemd[1]: Stopping Music Player Daemon... Aug 29 19:09:47 volumiopi3 volumio[970]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 19:09:47 volumiopi3 volumio[970]: info: PLUGIN START: fusiondsp Aug 29 19:09:47 volumiopi3 volumio[970]: info: Loading i18n strings for locale sv Aug 29 19:09:47 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 29 19:09:47 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:09:47 volumiopi3 volumio[970]: info: FusionDsp - mixtype--------------------- Hardware Aug 29 19:09:47 volumiopi3 volumio[970]: info: Preparing to generate the ALSA configuration file Aug 29 19:09:47 volumiopi3 volumio[970]: info: Done. Aug 29 19:09:47 volumiopi3 volumio[970]: info: MPD Permissions set Aug 29 19:09:47 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:09:47 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:09:47 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:09:47 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:09:47 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:09:47 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:09:47 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:09:47 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:09:47 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:09:47 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:09:47 volumiopi3 volumio[970]: info: Asound.conf file unchanged, so no further update is needed Aug 29 19:09:47 volumiopi3 volumio[970]: info: Output device has changed, restarting MPD Aug 29 19:09:47 volumiopi3 volumio[970]: info: Output device has changed, restarting Shairport Sync Aug 29 19:09:47 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:09:47 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:09:47 volumiopi3 sudo[1950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 19:09:47 volumiopi3 sudo[1950]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:09:47 volumiopi3 sudo[1950]: pam_unix(sudo:session): session closed for user root Aug 29 19:09:47 volumiopi3 sudo[1952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 19:09:47 volumiopi3 sudo[1952]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:09:47 volumiopi3 volumio[970]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 19:09:47 volumiopi3 volumio[970]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:09:47 volumiopi3 volumio[970]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:09:47 volumiopi3 volumio[970]: info: MPD Permissions set Aug 29 19:09:47 volumiopi3 volumio[970]: info: Starting Shairport Sync Aug 29 19:09:47 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:09:47 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:09:47 volumiopi3 sudo[1959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 19:09:47 volumiopi3 sudo[1959]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:09:47 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:09:47 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:09:47 volumiopi3 volumio[970]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 29 19:09:47 volumiopi3 volumio[970]: info: Discovery: Restarting Browsing Aug 29 19:09:47 volumiopi3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 19:09:47 volumiopi3 volumio[970]: info: FusionDsp - Aug 29 19:09:47 volumiopi3 systemd[1]: shairport-sync.service: Succeeded. Aug 29 19:09:47 volumiopi3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 19:09:47 volumiopi3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 19:09:47 volumiopi3 sudo[1959]: pam_unix(sudo:session): session closed for user root Aug 29 19:09:48 volumiopi3 volumio[970]: info: FusionDsp - Aug 29 19:09:48 volumiopi3 volumio[970]: info: FusionDsp - undefined Aug 29 19:09:48 volumiopi3 volumio[970]: info: FusionDsp - undefined Aug 29 19:09:48 volumiopi3 volumio[970]: info: Shairport-Sync Started Aug 29 19:09:48 volumiopi3 volumio[970]: info: Starting Shairport Sync Aug 29 19:09:48 volumiopi3 sudo[1969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 19:09:48 volumiopi3 sudo[1969]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:09:48 volumiopi3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 19:09:48 volumiopi3 systemd[1]: shairport-sync.service: Succeeded. Aug 29 19:09:48 volumiopi3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 19:09:48 volumiopi3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 19:09:48 volumiopi3 sudo[1969]: pam_unix(sudo:session): session closed for user root Aug 29 19:09:48 volumiopi3 volumio[970]: info: Shairport-Sync Started Aug 29 19:09:54 volumiopi3 volumio[970]: info: camilladsp service started and running in background, instance 1 Aug 29 19:09:54 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:09:54 volumiopi3 volumio[970]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 19:09:54 volumiopi3 volumio[970]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Aug 29 19:09:54 volumiopi3 volumio[970]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 19:09:54 volumiopi3 volumio[970]: info: FusionDsp loaded Aug 29 19:09:54 volumiopi3 volumio[970]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 19:09:54 volumiopi3 sudo[2020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 29 19:09:54 volumiopi3 sudo[2020]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:09:54 volumiopi3 systemd[1]: mpd.service: Succeeded. Aug 29 19:09:54 volumiopi3 systemd[1]: Stopped Music Player Daemon. Aug 29 19:09:54 volumiopi3 volumio[970]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 29 19:09:54 volumiopi3 volumio[970]: info: Adding Signal Path Element [object Object] Aug 29 19:09:54 volumiopi3 volumio[970]: info: Adding fusiondspeq DSP Signal Path Element Aug 29 19:09:54 volumiopi3 volumio[970]: info: FusionDsp - ---- installed callbackRead Aug 29 19:09:54 volumiopi3 volumio[970]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 19:09:54 volumiopi3 systemd[1]: Starting Music Player Daemon... Aug 29 19:09:54 volumiopi3 volumio[970]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 29 19:09:54 volumiopi3 volumio[970]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Aug 29 19:09:54 volumiopi3 volumio[970]: at onErrorNT (internal/child_process.js:465:16) Aug 29 19:09:54 volumiopi3 volumio[970]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Aug 29 19:09:54 volumiopi3 volumio[970]: errno: -2, Aug 29 19:09:54 volumiopi3 volumio[970]: code: 'ENOENT', Aug 29 19:09:54 volumiopi3 volumio[970]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 19:09:54 volumiopi3 volumio[970]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 19:09:54 volumiopi3 volumio[970]: spawnargs: [ Aug 29 19:09:54 volumiopi3 volumio[970]: '-p', Aug 29 19:09:54 volumiopi3 volumio[970]: 9876, Aug 29 19:09:54 volumiopi3 volumio[970]: '-o', Aug 29 19:09:54 volumiopi3 volumio[970]: '/tmp/camilladsp.log', Aug 29 19:09:54 volumiopi3 volumio[970]: '-l', Aug 29 19:09:54 volumiopi3 volumio[970]: 'warn', Aug 29 19:09:54 volumiopi3 volumio[970]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 29 19:09:54 volumiopi3 volumio[970]: ] Aug 29 19:09:54 volumiopi3 volumio[970]: } Aug 29 19:09:54 volumiopi3 volumio[970]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 19:09:54 volumiopi3 sudo[2020]: pam_unix(sudo:session): session closed for user root Aug 29 19:09:54 volumiopi3 sudo[2022]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 19:09:54 volumiopi3 sudo[2022]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:09:54 volumiopi3 sudo[2022]: pam_unix(sudo:session): session closed for user root Aug 29 19:09:58 volumiopi3 kernel: hwmon hwmon1: Voltage normalised Aug 29 19:10:00 volumiopi3 kernel: hwmon hwmon1: Undervoltage detected! Aug 29 19:10:07 volumiopi3 volumio-remote-updater[585]: [2026-08-29 19:10:07] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Aug 29 19:10:12 volumiopi3 volumio-remote-updater[585]: [2026-08-29 19:10:12] [connect] Successful connection Aug 29 19:10:18 volumiopi3 mpd[2032]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 19:10:18 volumiopi3 mpd[2032]: output: No 'audio_output' defined in config file Aug 29 19:10:19 volumiopi3 mpd[2032]: output: Successfully detected a sndio audio device Aug 29 19:10:19 volumiopi3 mpd[2032]: zeroconf: No global port, disabling zeroconf Aug 29 19:10:19 volumiopi3 systemd[1]: Started Music Player Daemon. Aug 29 19:10:19 volumiopi3 sudo[2049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-29 19:08 Aug 29 19:10:19 volumiopi3 sudo[1937]: pam_unix(sudo:session): session closed for user root Aug 29 19:10:19 volumiopi3 sudo[2049]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:10:19 volumiopi3 sudo[1952]: pam_unix(sudo:session): session closed for user root PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"