Aug 28 17:43:04 volumio volumio[1255]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
Aug 28 17:43:04 volumio volumio[1255]: info: Discovery: Restarting Browsing
Aug 28 17:43:04 volumio volumio[1255]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
Aug 28 17:43:04 volumio volumio[1255]: info: Discovery: Restarting Browsing
Aug 28 17:43:04 volumio volumio[1255]: info: Discovery: Restart already pending, ignoring duplicate call
Aug 28 17:43:09 volumio volumio[1255]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
Aug 28 17:43:09 volumio volumio[1255]: info: Discovery: Restarting Browsing
Aug 28 17:43:09 volumio volumio[1255]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
Aug 28 17:43:09 volumio volumio[1255]: info: Discovery: Restarting Browsing
Aug 28 17:43:09 volumio volumio[1255]: info: Discovery: Restart already pending, ignoring duplicate call
Aug 28 17:43:14 volumio dbus-daemon[774]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256)
Aug 28 17:43:14 volumio volumio[1255]: info: Discovery: Browse raised the following error Error: dns service error: refused
Aug 28 17:43:14 volumio volumio[1255]: info: Discovery: Restarting Browsing
Aug 28 17:43:14 volumio volumio[1255]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
Aug 28 17:43:14 volumio volumio[1255]: info: Discovery: Restarting Browsing
Aug 28 17:43:14 volumio volumio[1255]: info: Discovery: Restart already pending, ignoring duplicate call
Aug 28 17:43:19 volumio dbus-daemon[774]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256)
Aug 28 17:43:19 volumio volumio[1255]: info: Discovery: Browse raised the following error Error: dns service error: refused
Aug 28 17:43:19 volumio volumio[1255]: info: Discovery: Restarting Browsing
Aug 28 17:43:19 volumio dbus-daemon[774]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256)
Aug 28 17:43:19 volumio volumio[1255]: info: Discovery: Browse raised the following error Error: dns service error: refused
Aug 28 17:43:19 volumio volumio[1255]: info: Discovery: Restarting Browsing
Aug 28 17:43:19 volumio volumio[1255]: info: Discovery: Restart already pending, ignoring duplicate call
Aug 28 17:43:24 volumio dbus-daemon[774]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256)
Aug 28 17:43:24 volumio volumio[1255]: info: Discovery: Restarting Browsing
Aug 28 17:43:29 volumio dbus-daemon[774]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256)
Aug 28 17:43:29 volumio volumio[1255]: info: Discovery: Restarting Browsing
Aug 28 17:43:34 volumio dbus-daemon[774]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256)
Aug 28 17:43:34 volumio volumio[1255]: info: Discovery: Restarting Browsing
Aug 28 17:43:39 volumio dbus-daemon[774]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256)
Aug 28 17:43:39 volumio volumio[1255]: info: Discovery: Restarting Browsing
Aug 28 17:43:44 volumio dbus-daemon[774]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256)
Aug 28 17:43:44 volumio volumio[1255]: info: Discovery: Restarting Browsing
Aug 28 17:43:49 volumio dbus-daemon[774]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256)
Aug 28 17:43:49 volumio volumio[1255]: info: Discovery: Restarting Browsing
Aug 28 17:43:54 volumio dbus-daemon[774]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256)
Aug 28 17:43:54 volumio volumio[1255]: info: Discovery: Restarting Browsing
Aug 28 17:43:59 volumio dbus-daemon[774]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256)
Aug 28 17:43:59 volumio volumio[1255]: info: Discovery: Restarting Browsing
Aug 28 17:44:04 volumio dbus-daemon[774]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256)
Aug 28 17:44:04 volumio volumio[1255]: info: Discovery: Restarting Browsing
Aug 28 17:44:09 volumio dbus-daemon[774]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256)
Aug 28 17:44:09 volumio volumio[1255]: info: Discovery: Restarting Browsing
Aug 28 17:44:13 volumio volumio5-onboarding[1541]: time=2026-08-28T17:44:13.986+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:39382 @ 0x30afc20" latency=-374.511077ms timeout=10s
Aug 28 17:44:14 volumio volumio5-onboarding[1541]: time=2026-08-28T17:44:14.000+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:39382 @ 0x30afc20" latency=-374.511077ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=14.124391ms
Aug 28 17:44:14 volumio volumio5-onboarding[1541]: time=2026-08-28T17:44:14.025+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:39382 @ 0x30afc20" latency=-374.511077ms timeout=10s endpoint=http://pushupdates.volumio.org duration=38.123508ms
Aug 28 17:44:14 volumio volumio5-onboarding[1541]: time=2026-08-28T17:44:14.030+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:39382 @ 0x30afc20" latency=-374.511077ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=43.072612ms
Aug 28 17:44:14 volumio volumio5-onboarding[1541]: time=2026-08-28T17:44:14.110+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:39382 @ 0x30afc20" latency=-374.511077ms timeout=10s endpoint=https://www.googleapis.com duration=123.833238ms
Aug 28 17:44:14 volumio volumio5-onboarding[1541]: time=2026-08-28T17:44:14.151+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:39382 @ 0x30afc20" latency=-374.511077ms timeout=10s endpoint=https://securetoken.googleapis.com duration=164.861535ms
Aug 28 17:44:14 volumio volumio5-onboarding[1541]: time=2026-08-28T17:44:14.230+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:39382 @ 0x30afc20" latency=-374.511077ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=242.651002ms
Aug 28 17:44:14 volumio volumio5-onboarding[1541]: time=2026-08-28T17:44:14.249+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:39382 @ 0x30afc20" latency=-374.511077ms timeout=10s endpoint=https://functions.volumio.cloud duration=261.822663ms
Aug 28 17:44:14 volumio volumio5-onboarding[1541]: time=2026-08-28T17:44:14.253+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:39382 @ 0x30afc20" latency=-374.511077ms timeout=10s endpoint=https://functions.volumio.cloud duration=265.550648ms
Aug 28 17:44:14 volumio volumio5-onboarding[1541]: time=2026-08-28T17:44:14.278+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:39382 @ 0x30afc20" latency=-374.511077ms timeout=10s endpoint=https://database.volumio.cloud duration=289.947743ms
Aug 28 17:44:14 volumio volumio5-onboarding[1541]: time=2026-08-28T17:44:14.305+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:39382 @ 0x30afc20" latency=-374.511077ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=318.412209ms
Aug 28 17:44:14 volumio volumio5-onboarding[1541]: time=2026-08-28T17:44:14.444+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:39382 @ 0x30afc20" latency=-374.511077ms timeout=10s endpoint=https://google.com duration=457.724956ms
Aug 28 17:44:14 volumio volumio5-onboarding[1541]: time=2026-08-28T17:44:14.447+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:39382 @ 0x30afc20" latency=-374.511077ms timeout=10s endpoint=http://cddb.volumio.org duration=460.143564ms
Aug 28 17:44:14 volumio volumio5-onboarding[1541]: time=2026-08-28T17:44:14.509+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:39382 @ 0x30afc20" latency=-374.511077ms timeout=10s endpoint=http://plugins.volumio.org duration=521.574325ms
Aug 28 17:44:14 volumio dbus-daemon[774]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256)
Aug 28 17:44:14 volumio volumio[1255]: info: Discovery: Restarting Browsing
Aug 28 17:44:17 volumio volumio[1255]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 28 17:44:17 volumio volumio[1255]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 28 17:44:17 volumio volumio[1255]: info: Retrieving Cloud Streaming UI
Aug 28 17:44:17 volumio volumio[1255]: info: Getting Tidal Cloud Configuration
Aug 28 17:44:17 volumio volumio[1255]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 28 17:44:17 volumio volumio[1255]: info: Getting Qobuz Cloud Configuration
Aug 28 17:44:17 volumio volumio[1255]: info: Asking plugin for UI Config
Aug 28 17:44:17 volumio volumio[1255]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 28 17:44:17 volumio volumio[1255]: info: Getting Spotify Cloud Configuration
Aug 28 17:44:17 volumio volumio[1255]: info: Asking plugin for UI Config
Aug 28 17:44:17 volumio volumio[1255]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 28 17:44:17 volumio volumio[1255]: info: Saving Spotify Acccount
Aug 28 17:44:17 volumio volumio[1255]: info: Got it
Aug 28 17:44:17 volumio volumio[1255]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Aug 28 17:44:17 volumio volumio[1255]: info: Got it
Aug 28 17:44:17 volumio volumio[1255]: info: Got Tidal Cloud Configuration
Aug 28 17:44:17 volumio volumio[1255]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 28 17:44:17 volumio volumio[1255]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Aug 28 17:44:17 volumio volumio[1255]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Aug 28 17:44:17 volumio volumio[1255]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Aug 28 17:44:17 volumio volumio[1255]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Aug 28 17:44:17 volumio volumio[1255]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 28 17:44:17 volumio volumio[1255]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 28 17:44:17 volumio volumio[1255]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 28 17:44:17 volumio volumio[1255]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 28 17:44:17 volumio volumio[1255]: info: CoreCommandRouter::volumioGetBrowseSources
Aug 28 17:44:17 volumio volumio[1255]: info: CoreCommandRouter::volumioGetBrowseSources
Aug 28 17:44:17 volumio volumio[1255]: info: CoreCommandRouter::volumioGetBrowseSources
Aug 28 17:44:17 volumio volumio[1255]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Aug 28 17:44:17 volumio volumio[1255]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Aug 28 17:44:19 volumio dbus-daemon[774]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256)
Aug 28 17:44:19 volumio volumio[1255]: info: Discovery: Restarting Browsing
Aug 28 17:44:21 volumio volumio[1255]: info: Disabling MyMusic plugin upnp
Aug 28 17:44:21 volumio sudo[4863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service
Aug 28 17:44:21 volumio sudo[4863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 28 17:44:21 volumio systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD...
Aug 28 17:44:21 volumio volumio[1255]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Aug 28 17:44:22 volumio volumio[1255]: info: Enabling MyMusic plugin upnp
Aug 28 17:44:22 volumio volumio[1255]: info: Enabling plugin upnp
Aug 28 17:44:22 volumio volumio[1255]: info: Loading plugin "upnp"...
Aug 28 17:44:22 volumio volumio[1255]: info: [1787931862190] Starting Upmpd Daemon
Aug 28 17:44:22 volumio volumio[1255]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Aug 28 17:44:22 volumio volumio[1255]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 28 17:44:22 volumio volumio[1255]: Error: listen EADDRINUSE: address already in use :::6599
Aug 28 17:44:22 volumio volumio[1255]: at Server.setupListenHandle [as _listen2] (node:net:1872:16)
Aug 28 17:44:22 volumio volumio[1255]: at listenInCluster (node:net:1920:12)
Aug 28 17:44:22 volumio volumio[1255]: at Server.listen (node:net:2008:7)
Aug 28 17:44:22 volumio volumio[1255]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17)
Aug 28 17:44:22 volumio volumio[1255]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38)
Aug 28 17:44:22 volumio volumio[1255]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19)
Aug 28 17:44:22 volumio volumio[1255]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)
Aug 28 17:44:22 volumio volumio[1255]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) {
Aug 28 17:44:22 volumio volumio[1255]: code: 'EADDRINUSE',
Aug 28 17:44:22 volumio volumio[1255]: errno: -98,
Aug 28 17:44:22 volumio volumio[1255]: syscall: 'listen',
Aug 28 17:44:22 volumio volumio[1255]: address: '::',
Aug 28 17:44:22 volumio volumio[1255]: port: 6599
Aug 28 17:44:22 volumio volumio[1255]: }
Aug 28 17:44:22 volumio volumio[1255]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 28 17:44:22 volumio sudo[4894]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-28 17:43'
Aug 28 17:44:22 volumio sudo[4894]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)"
NAME="Raspbian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026"
VOLUMIO_VERSION="4.119"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"