Oct 02 19:54:04 volumio dbus-daemon[873]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 02 19:54:04 volumio volumio[1138]: info: Discovery: Restarting Browsing Oct 02 19:54:06 volumio ntpd[1085]: PROTO: 204.216.214.76 unlink local addr 192.168.1.59 -> Oct 02 19:54:09 volumio dbus-daemon[873]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 02 19:54:09 volumio volumio[1138]: info: Discovery: Restarting Browsing Oct 02 19:54:14 volumio dbus-daemon[873]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 02 19:54:14 volumio volumio[1138]: info: Discovery: Restarting Browsing Oct 02 19:54:19 volumio dbus-daemon[873]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 02 19:54:19 volumio volumio[1138]: info: Discovery: Restarting Browsing Oct 02 19:54:22 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:22.995Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.16:51205 Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.024Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.16:51205 @ 0xc00069d1d0" latency=76.769708ms timeout=20s Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.024Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.16:51205 @ 0xc00069d1d0" Oct 02 19:54:23 volumio volumio[1138]: info: Received Get System Info Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 19:54:23 volumio volumio[1138]: info: Discovery: Getting this device information Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.025Z level=INFO msg="emitting device name changed event" component=server peer="192.168.1.16:51205 @ 0xc00069d1d0" name=Volumio Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.026Z level=INFO msg="emitting device language changed event" component=server peer="192.168.1.16:51205 @ 0xc00069d1d0" language=it Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.026Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.16:51205 @ 0xc00069d1d0" timezone=Etc/GMT+1 Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.026Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.16:51205 @ 0xc00069d1d0" available=true connected=true macAddress=78:7b:8a:d5:40:70 ip4Address=192.168.1.59/24 ip6Address= Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.027Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.16:51205 @ 0xc00069d1d0" available=false connected=false macAddress= ip4Address= ip6Address= ssid= Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.027Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.16:51205 @ 0xc00069d1d0" setupComplete=true Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.032Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51205 @ 0xc00069d1d0" latency=77.774261ms timeout=10s Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.053Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51205 @ 0xc00069d1d0" latency=77.774261ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=20.909839ms Oct 02 19:54:23 volumio volumio[1138]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 1 -D 8 Oct 02 19:54:23 volumio volumio[1138]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 02 19:54:23 volumio volumio[1138]: {"cmd":"/usr/local/bin/alsacap -C 1 -D 8","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 1 -D 8\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)"} Oct 02 19:54:23 volumio volumio[1138]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 1 -D 7 Oct 02 19:54:23 volumio volumio[1138]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 02 19:54:23 volumio volumio[1138]: {"cmd":"/usr/local/bin/alsacap -C 1 -D 7","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 1 -D 7\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)"} Oct 02 19:54:23 volumio volumio[1138]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 1 -D 3 Oct 02 19:54:23 volumio volumio[1138]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 02 19:54:23 volumio volumio[1138]: {"cmd":"/usr/local/bin/alsacap -C 1 -D 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 1 -D 3\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)"} Oct 02 19:54:23 volumio volumio[1138]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 1 Oct 02 19:54:23 volumio volumio[1138]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 02 19:54:23 volumio volumio[1138]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 1","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 1\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)"} Oct 02 19:54:23 volumio volumio[1138]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 0 Oct 02 19:54:23 volumio volumio[1138]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 02 19:54:23 volumio volumio[1138]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 0\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)"} Oct 02 19:54:23 volumio volumio[1138]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Oct 02 19:54:23 volumio volumio[1138]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 02 19:54:23 volumio volumio[1138]: {"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)"} Oct 02 19:54:23 volumio volumio[1138]: amixer -c 5 info | grep "SMSL USB AUDIO" Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.079Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51205 @ 0xc00069d1d0" latency=77.774261ms timeout=10s endpoint=http://pushupdates.volumio.org duration=46.213992ms Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.082Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51205 @ 0xc00069d1d0" latency=77.774261ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=50.070994ms Oct 02 19:54:23 volumio volumio[1138]: Card sysdefault:5 'AUDIO'/'SMSL SMSL USB AUDIO at usb-0000:00:14.0-2, high speed' Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.088Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51205 @ 0xc00069d1d0" latency=77.774261ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=55.675049ms Oct 02 19:54:23 volumio volumio[1138]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Oct 02 19:54:23 volumio volumio[1138]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 02 19:54:23 volumio volumio[1138]: {"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)"} Oct 02 19:54:23 volumio volumio[1138]: amixer -c 5 info | grep "SMSL USB AUDIO" Oct 02 19:54:23 volumio volumio[1138]: Card sysdefault:5 'AUDIO'/'SMSL SMSL USB AUDIO at usb-0000:00:14.0-2, high speed' Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.101Z level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.16:51205 @ 0xc00069d1d0" selectedOutputId=5 Oct 02 19:54:23 volumio volumio[1138]: info: Received Get System Info Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 19:54:23 volumio volumio[1138]: info: Discovery: Getting this device information Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.125Z level=INFO msg="emitting software info changed event" component=server peer="192.168.1.16:51205 @ 0xc00069d1d0" currentVersion=4.119 latestVersion=4.119 Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.125Z level=INFO msg="emitting software update progress event" component=server peer="192.168.1.16:51205 @ 0xc00069d1d0" status=UPDATE_STATUS_NONE progress=0 Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.125Z level=INFO msg="emitting user changed event" component=server peer="192.168.1.16:51205 @ 0xc00069d1d0" userId= Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.125Z level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.16:51205 @ 0xc00069d1d0" providers=3 Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.125Z level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.16:51205 @ 0xc00069d1d0" plugins=54 Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.126Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.16:51205 @ 0xc00069d1d0" state=STATUS_PLAYING positionMs=429307 volume=53 Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.126Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.16:51205 @ 0xc00069d1d0" id=https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3 title="ALVARO SOLER - MUERO" Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.158Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51205 @ 0xc00069d1d0" latency=77.774261ms timeout=10s endpoint=https://www.googleapis.com duration=125.228462ms Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.177Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51205 @ 0xc00069d1d0" latency=77.774261ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=144.672444ms Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.189Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51205 @ 0xc00069d1d0" latency=77.774261ms timeout=10s endpoint=https://database.volumio.cloud duration=156.801749ms Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.199Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51205 @ 0xc00069d1d0" latency=77.774261ms timeout=10s endpoint=https://securetoken.googleapis.com duration=166.305164ms Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 19:54:23 volumio volumio[1138]: info: Discovery: Getting this device information Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 19:54:23 volumio volumio[1138]: verbose: New Socket.io Connection to 192.168.1.59:3000 from 192.168.1.16 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.262Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51205 @ 0xc00069d1d0" latency=77.774261ms timeout=10s endpoint=https://functions.volumio.cloud duration=229.858267ms Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.262Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51205 @ 0xc00069d1d0" latency=77.774261ms timeout=10s endpoint=https://functions.volumio.cloud duration=229.984245ms Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.514Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51205 @ 0xc00069d1d0" latency=77.774261ms timeout=10s endpoint=http://cddb.volumio.org duration=481.094874ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.586Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51205 @ 0xc00069d1d0" latency=77.774261ms timeout=10s endpoint=http://plugins.volumio.org duration=553.171451ms Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 19:54:23 volumio volumio[1138]: info: Discovery: Getting this device information Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 19:54:23 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:23.749Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51205 @ 0xc00069d1d0" latency=77.774261ms timeout=10s endpoint=https://google.com duration=716.754607ms Oct 02 19:54:23 volumio volumio[1138]: verbose: New Socket.io Connection to 192.168.1.59:3000 from 192.168.1.16 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 19:54:23 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 19:54:24 volumio volumio[1138]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.16 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260929.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 02 19:54:24 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 19:54:24 volumio volumio[1138]: info: CoreCommandRouter::volumioGetVisibleSources Oct 02 19:54:24 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 19:54:24 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Oct 02 19:54:24 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 02 19:54:24 volumio volumio[1138]: info: CoreCommandRouter::volumioGetQueue Oct 02 19:54:24 volumio volumio[1138]: info: CoreStateMachine::getQueue Oct 02 19:54:24 volumio volumio[1138]: info: CorePlayQueue::getQueue Oct 02 19:54:24 volumio volumio[1138]: info: Listing playlists Oct 02 19:54:24 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 02 19:54:24 volumio volumio[1138]: info: Received Get System Info Oct 02 19:54:24 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 19:54:24 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 19:54:24 volumio volumio[1138]: info: Discovery: Getting this device information Oct 02 19:54:24 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Oct 02 19:54:24 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 19:54:24 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Oct 02 19:54:24 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 02 19:54:24 volumio dbus-daemon[873]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 02 19:54:24 volumio volumio[1138]: info: Discovery: Restarting Browsing Oct 02 19:54:26 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 19:54:26 volumio volumio[1138]: info: CURURI: playlists Oct 02 19:54:26 volumio volumio[1138]: info: Listing playlists Oct 02 19:54:26 volumio volumio[1138]: info: Preload queue cleared Oct 02 19:54:28 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 19:54:28 volumio volumio[1138]: info: CURURI: music-library Oct 02 19:54:28 volumio volumio[1138]: error: Failed LSINFO: null Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.479Z level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.1.16:51205 Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.479Z level=INFO msg="connection to address closed" component=conn/multi addr=192.168.1.16:51205 Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.494Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.16:51417 Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.507Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.16:51417 @ 0xc000303680" latency=77.580151ms timeout=20s Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.507Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.16:51417 @ 0xc000303680" Oct 02 19:54:28 volumio volumio[1138]: info: Received Get System Info Oct 02 19:54:28 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 19:54:28 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 19:54:28 volumio volumio[1138]: info: Discovery: Getting this device information Oct 02 19:54:28 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Oct 02 19:54:28 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.508Z level=INFO msg="emitting device name changed event" component=server peer="192.168.1.16:51417 @ 0xc000303680" name=Volumio Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.509Z level=INFO msg="emitting device language changed event" component=server peer="192.168.1.16:51417 @ 0xc000303680" language=it Oct 02 19:54:28 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.509Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.16:51417 @ 0xc000303680" timezone=Etc/GMT+1 Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.509Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.16:51417 @ 0xc000303680" available=true connected=true macAddress=78:7b:8a:d5:40:70 ip4Address=192.168.1.59/24 ip6Address= Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.509Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.16:51417 @ 0xc000303680" available=false connected=false macAddress= ip4Address= ip6Address= ssid= Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.509Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.16:51417 @ 0xc000303680" setupComplete=true Oct 02 19:54:28 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.516Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=77.075781ms timeout=10s Oct 02 19:54:28 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 02 19:54:28 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:54:28 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.536Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=77.075781ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=20.362749ms Oct 02 19:54:28 volumio volumio[1138]: info: Preload queue cleared Oct 02 19:54:28 volumio volumio[1138]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 1 -D 8 Oct 02 19:54:28 volumio volumio[1138]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 02 19:54:28 volumio volumio[1138]: {"cmd":"/usr/local/bin/alsacap -C 1 -D 8","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 1 -D 8\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)"} Oct 02 19:54:28 volumio volumio[1138]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 1 -D 7 Oct 02 19:54:28 volumio volumio[1138]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 02 19:54:28 volumio volumio[1138]: {"cmd":"/usr/local/bin/alsacap -C 1 -D 7","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 1 -D 7\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)"} Oct 02 19:54:28 volumio volumio[1138]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 1 -D 3 Oct 02 19:54:28 volumio volumio[1138]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 02 19:54:28 volumio volumio[1138]: {"cmd":"/usr/local/bin/alsacap -C 1 -D 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 1 -D 3\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)"} Oct 02 19:54:28 volumio volumio[1138]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 1 Oct 02 19:54:28 volumio volumio[1138]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 02 19:54:28 volumio volumio[1138]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 1","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 1\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)"} Oct 02 19:54:28 volumio volumio[1138]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 0 Oct 02 19:54:28 volumio volumio[1138]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 02 19:54:28 volumio volumio[1138]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 0\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)"} Oct 02 19:54:28 volumio volumio[1138]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Oct 02 19:54:28 volumio volumio[1138]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 02 19:54:28 volumio volumio[1138]: {"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)"} Oct 02 19:54:28 volumio volumio[1138]: amixer -c 5 info | grep "SMSL USB AUDIO" Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.561Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=77.075781ms timeout=10s endpoint=http://pushupdates.volumio.org duration=45.113201ms Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.565Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=77.075781ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=49.354535ms Oct 02 19:54:28 volumio volumio[1138]: Card sysdefault:5 'AUDIO'/'SMSL SMSL USB AUDIO at usb-0000:00:14.0-2, high speed' Oct 02 19:54:28 volumio volumio[1138]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Oct 02 19:54:28 volumio volumio[1138]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 02 19:54:28 volumio volumio[1138]: {"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)"} Oct 02 19:54:28 volumio volumio[1138]: amixer -c 5 info | grep "SMSL USB AUDIO" Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.571Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=77.075781ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=54.787866ms Oct 02 19:54:28 volumio volumio[1138]: Card sysdefault:5 'AUDIO'/'SMSL SMSL USB AUDIO at usb-0000:00:14.0-2, high speed' Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.582Z level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.16:51417 @ 0xc000303680" selectedOutputId=5 Oct 02 19:54:28 volumio volumio[1138]: info: Received Get System Info Oct 02 19:54:28 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 19:54:28 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 19:54:28 volumio volumio[1138]: info: Discovery: Getting this device information Oct 02 19:54:28 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Oct 02 19:54:28 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.606Z level=INFO msg="emitting software info changed event" component=server peer="192.168.1.16:51417 @ 0xc000303680" currentVersion=4.119 latestVersion=4.119 Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.607Z level=INFO msg="emitting software update progress event" component=server peer="192.168.1.16:51417 @ 0xc000303680" status=UPDATE_STATUS_NONE progress=0 Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.607Z level=INFO msg="emitting user changed event" component=server peer="192.168.1.16:51417 @ 0xc000303680" userId= Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.607Z level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.16:51417 @ 0xc000303680" providers=3 Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.607Z level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.16:51417 @ 0xc000303680" plugins=54 Oct 02 19:54:28 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.607Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.16:51417 @ 0xc000303680" state=STATUS_PLAYING positionMs=434768 volume=53 Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.607Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.16:51417 @ 0xc000303680" id=https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3 title="ALVARO SOLER - MUERO" Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.640Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=77.075781ms timeout=10s endpoint=https://www.googleapis.com duration=124.664425ms Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.658Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=77.075781ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=141.780356ms Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.667Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=77.075781ms timeout=10s endpoint=https://database.volumio.cloud duration=151.024128ms Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.667Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=77.075781ms timeout=10s endpoint=https://functions.volumio.cloud duration=151.530598ms Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.668Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=77.075781ms timeout=10s endpoint=https://functions.volumio.cloud duration=151.785982ms Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.683Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=77.075781ms timeout=10s endpoint=https://securetoken.googleapis.com duration=167.63843ms Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.803Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=77.075781ms timeout=10s endpoint=http://cddb.volumio.org duration=287.168552ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 02 19:54:28 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:28.853Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=77.075781ms timeout=10s endpoint=http://plugins.volumio.org duration=336.8479ms Oct 02 19:54:29 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:29.222Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=77.075781ms timeout=10s endpoint=https://google.com duration=706.048017ms Oct 02 19:54:29 volumio dbus-daemon[873]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 02 19:54:29 volumio volumio[1138]: info: Discovery: Restarting Browsing Oct 02 19:54:33 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 19:54:33 volumio volumio[1138]: info: CURURI: albums:// Oct 02 19:54:33 volumio volumio[1138]: info: listAlbums - loading Albums from cache Oct 02 19:54:33 volumio volumio[1138]: info: Preload queue cleared Oct 02 19:54:34 volumio dbus-daemon[873]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 02 19:54:34 volumio volumio[1138]: info: Discovery: Restarting Browsing Oct 02 19:54:39 volumio dbus-daemon[873]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 02 19:54:39 volumio volumio[1138]: info: Discovery: Restarting Browsing Oct 02 19:54:44 volumio volumio[1138]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 19:54:44 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 02 19:54:44 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:54:44 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 19:54:44 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 19:54:44 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 19:54:44 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 19:54:44 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 19:54:44 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 19:54:44 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 19:54:44 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 19:54:44 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 02 19:54:44 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 02 19:54:44 volumio dbus-daemon[873]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 02 19:54:44 volumio volumio[1138]: info: Discovery: Restarting Browsing Oct 02 19:54:49 volumio dbus-daemon[873]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 02 19:54:49 volumio volumio[1138]: info: Discovery: Restarting Browsing Oct 02 19:54:49 volumio kernel: INFO: task smbd:10287 blocked for more than 120 seconds. Oct 02 19:54:49 volumio kernel: Not tainted 6.12.57-volumio #2 Oct 02 19:54:49 volumio kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. Oct 02 19:54:49 volumio kernel: task:smbd state:D stack:0 pid:10287 tgid:10130 ppid:1589 flags:0x00000002 Oct 02 19:54:49 volumio kernel: Call Trace: Oct 02 19:54:49 volumio kernel: Oct 02 19:54:49 volumio kernel: __schedule+0x457/0xa80 Oct 02 19:54:49 volumio kernel: schedule+0x2b/0x100 Oct 02 19:54:49 volumio kernel: schedule_preempt_disabled+0x19/0x30 Oct 02 19:54:49 volumio kernel: rwsem_down_write_slowpath+0x26c/0x5e0 Oct 02 19:54:49 volumio kernel: down_write+0x57/0x70 Oct 02 19:54:49 volumio kernel: ovl_write_iter+0xb7/0x140 [overlay] Oct 02 19:54:49 volumio kernel: ? __pfx_ovl_file_end_write+0x10/0x10 [overlay] Oct 02 19:54:49 volumio kernel: vfs_write+0x285/0x440 Oct 02 19:54:49 volumio kernel: __x64_sys_pwrite64+0x9e/0xd0 Oct 02 19:54:49 volumio kernel: x64_sys_call+0x1afa/0x2060 Oct 02 19:54:49 volumio kernel: do_syscall_64+0x4b/0x110 Oct 02 19:54:49 volumio kernel: entry_SYSCALL_64_after_hwframe+0x76/0x7e Oct 02 19:54:49 volumio kernel: RIP: 0033:0x7f377041d4b7 Oct 02 19:54:49 volumio kernel: RSP: 002b:00007f376b8e7a40 EFLAGS: 00000293 ORIG_RAX: 0000000000000012 Oct 02 19:54:49 volumio kernel: RAX: ffffffffffffffda RBX: 0000000000100000 RCX: 00007f377041d4b7 Oct 02 19:54:49 volumio kernel: RDX: 0000000000100000 RSI: 000055e5d9bb63c0 RDI: 000000000000001f Oct 02 19:54:49 volumio kernel: RBP: 0000000002300000 R08: 0000000000000000 R09: 000055e5d9871fb4 Oct 02 19:54:49 volumio kernel: R10: 0000000002300000 R11: 0000000000000293 R12: 0000000000100000 Oct 02 19:54:49 volumio kernel: R13: 000055e5d9bb63c0 R14: 000000000000001f R15: 000055e5d9871f50 Oct 02 19:54:49 volumio kernel: Oct 02 19:54:49 volumio kernel: INFO: task smbd:10288 blocked for more than 120 seconds. Oct 02 19:54:49 volumio kernel: Not tainted 6.12.57-volumio #2 Oct 02 19:54:49 volumio kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. Oct 02 19:54:49 volumio kernel: task:smbd state:D stack:0 pid:10288 tgid:10130 ppid:1589 flags:0x00000002 Oct 02 19:54:49 volumio kernel: Call Trace: Oct 02 19:54:49 volumio kernel: Oct 02 19:54:49 volumio kernel: __schedule+0x457/0xa80 Oct 02 19:54:49 volumio kernel: schedule+0x2b/0x100 Oct 02 19:54:49 volumio kernel: schedule_preempt_disabled+0x19/0x30 Oct 02 19:54:49 volumio kernel: rwsem_down_write_slowpath+0x26c/0x5e0 Oct 02 19:54:49 volumio kernel: down_write+0x57/0x70 Oct 02 19:54:49 volumio kernel: ovl_write_iter+0xb7/0x140 [overlay] Oct 02 19:54:49 volumio kernel: ? __pfx_ovl_file_end_write+0x10/0x10 [overlay] Oct 02 19:54:49 volumio kernel: vfs_write+0x285/0x440 Oct 02 19:54:49 volumio kernel: __x64_sys_pwrite64+0x9e/0xd0 Oct 02 19:54:49 volumio kernel: x64_sys_call+0x1afa/0x2060 Oct 02 19:54:49 volumio kernel: do_syscall_64+0x4b/0x110 Oct 02 19:54:49 volumio kernel: entry_SYSCALL_64_after_hwframe+0x76/0x7e Oct 02 19:54:49 volumio kernel: RIP: 0033:0x7f377041d4b7 Oct 02 19:54:49 volumio kernel: RSP: 002b:00007f376b0e6a40 EFLAGS: 00000293 ORIG_RAX: 0000000000000012 Oct 02 19:54:49 volumio kernel: RAX: ffffffffffffffda RBX: 0000000000100000 RCX: 00007f377041d4b7 Oct 02 19:54:49 volumio kernel: RDX: 0000000000100000 RSI: 000055e5d9ebc9d0 RDI: 000000000000001f Oct 02 19:54:49 volumio kernel: RBP: 0000000002400000 R08: 0000000000000000 R09: 000055e5d9871fb4 Oct 02 19:54:49 volumio kernel: R10: 0000000002400000 R11: 0000000000000293 R12: 0000000000100000 Oct 02 19:54:49 volumio kernel: R13: 000055e5d9ebc9d0 R14: 000000000000001f R15: 000055e5d9871f50 Oct 02 19:54:49 volumio kernel: Oct 02 19:54:49 volumio kernel: INFO: task smbd:10315 blocked for more than 120 seconds. Oct 02 19:54:49 volumio kernel: Not tainted 6.12.57-volumio #2 Oct 02 19:54:49 volumio kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. Oct 02 19:54:49 volumio kernel: task:smbd state:D stack:0 pid:10315 tgid:10130 ppid:1589 flags:0x00000002 Oct 02 19:54:49 volumio kernel: Call Trace: Oct 02 19:54:49 volumio kernel: Oct 02 19:54:49 volumio kernel: __schedule+0x457/0xa80 Oct 02 19:54:49 volumio kernel: schedule+0x2b/0x100 Oct 02 19:54:49 volumio kernel: schedule_preempt_disabled+0x19/0x30 Oct 02 19:54:49 volumio kernel: rwsem_down_write_slowpath+0x26c/0x5e0 Oct 02 19:54:49 volumio kernel: down_write+0x57/0x70 Oct 02 19:54:49 volumio kernel: ovl_write_iter+0xb7/0x140 [overlay] Oct 02 19:54:49 volumio kernel: ? __pfx_ovl_file_end_write+0x10/0x10 [overlay] Oct 02 19:54:49 volumio kernel: vfs_write+0x285/0x440 Oct 02 19:54:49 volumio kernel: __x64_sys_pwrite64+0x9e/0xd0 Oct 02 19:54:49 volumio kernel: x64_sys_call+0x1afa/0x2060 Oct 02 19:54:49 volumio kernel: do_syscall_64+0x4b/0x110 Oct 02 19:54:49 volumio kernel: entry_SYSCALL_64_after_hwframe+0x76/0x7e Oct 02 19:54:49 volumio kernel: RIP: 0033:0x7f377041d4b7 Oct 02 19:54:49 volumio kernel: RSP: 002b:00007f376a8e5a40 EFLAGS: 00000293 ORIG_RAX: 0000000000000012 Oct 02 19:54:49 volumio kernel: RAX: ffffffffffffffda RBX: 0000000000100000 RCX: 00007f377041d4b7 Oct 02 19:54:49 volumio kernel: RDX: 0000000000100000 RSI: 000055e5d99b4170 RDI: 000000000000001f Oct 02 19:54:49 volumio kernel: RBP: 0000000002600000 R08: 0000000000000000 R09: 000055e5d9871fb4 Oct 02 19:54:49 volumio kernel: R10: 0000000002600000 R11: 0000000000000293 R12: 0000000000100000 Oct 02 19:54:49 volumio kernel: R13: 000055e5d99b4170 R14: 000000000000001f R15: 000055e5d9871f50 Oct 02 19:54:49 volumio kernel: Oct 02 19:54:49 volumio kernel: INFO: task smbd:10316 blocked for more than 120 seconds. Oct 02 19:54:49 volumio kernel: Not tainted 6.12.57-volumio #2 Oct 02 19:54:49 volumio kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. Oct 02 19:54:49 volumio kernel: task:smbd state:D stack:0 pid:10316 tgid:10130 ppid:1589 flags:0x00000002 Oct 02 19:54:49 volumio kernel: Call Trace: Oct 02 19:54:49 volumio kernel: Oct 02 19:54:49 volumio kernel: __schedule+0x457/0xa80 Oct 02 19:54:49 volumio kernel: schedule+0x2b/0x100 Oct 02 19:54:49 volumio kernel: schedule_preempt_disabled+0x19/0x30 Oct 02 19:54:49 volumio kernel: rwsem_down_write_slowpath+0x26c/0x5e0 Oct 02 19:54:49 volumio kernel: down_write+0x57/0x70 Oct 02 19:54:49 volumio kernel: ovl_write_iter+0xb7/0x140 [overlay] Oct 02 19:54:49 volumio kernel: ? __pfx_ovl_file_end_write+0x10/0x10 [overlay] Oct 02 19:54:49 volumio kernel: vfs_write+0x285/0x440 Oct 02 19:54:49 volumio kernel: __x64_sys_pwrite64+0x9e/0xd0 Oct 02 19:54:49 volumio kernel: x64_sys_call+0x1afa/0x2060 Oct 02 19:54:49 volumio kernel: do_syscall_64+0x4b/0x110 Oct 02 19:54:49 volumio kernel: entry_SYSCALL_64_after_hwframe+0x76/0x7e Oct 02 19:54:49 volumio kernel: RIP: 0033:0x7f377041d4b7 Oct 02 19:54:49 volumio kernel: RSP: 002b:00007f376a0e4a40 EFLAGS: 00000293 ORIG_RAX: 0000000000000012 Oct 02 19:54:49 volumio kernel: RAX: ffffffffffffffda RBX: 0000000000100000 RCX: 00007f377041d4b7 Oct 02 19:54:49 volumio kernel: RDX: 0000000000100000 RSI: 000055e5d98b4090 RDI: 000000000000001f Oct 02 19:54:49 volumio kernel: RBP: 0000000002500000 R08: 0000000000000000 R09: 000055e5d9871fb4 Oct 02 19:54:49 volumio kernel: R10: 0000000002500000 R11: 0000000000000293 R12: 0000000000100000 Oct 02 19:54:49 volumio kernel: R13: 000055e5d98b4090 R14: 000000000000001f R15: 000055e5d9871f50 Oct 02 19:54:49 volumio kernel: Oct 02 19:54:49 volumio kernel: INFO: task smbd:10318 blocked for more than 120 seconds. Oct 02 19:54:49 volumio kernel: Not tainted 6.12.57-volumio #2 Oct 02 19:54:49 volumio kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. Oct 02 19:54:49 volumio kernel: task:smbd state:D stack:0 pid:10318 tgid:10130 ppid:1589 flags:0x00000002 Oct 02 19:54:49 volumio kernel: Call Trace: Oct 02 19:54:49 volumio kernel: Oct 02 19:54:49 volumio kernel: __schedule+0x457/0xa80 Oct 02 19:54:49 volumio kernel: schedule+0x2b/0x100 Oct 02 19:54:49 volumio kernel: schedule_preempt_disabled+0x19/0x30 Oct 02 19:54:49 volumio kernel: rwsem_down_write_slowpath+0x26c/0x5e0 Oct 02 19:54:49 volumio kernel: down_write+0x57/0x70 Oct 02 19:54:49 volumio kernel: ovl_write_iter+0xb7/0x140 [overlay] Oct 02 19:54:49 volumio kernel: ? __pfx_ovl_file_end_write+0x10/0x10 [overlay] Oct 02 19:54:49 volumio kernel: vfs_write+0x285/0x440 Oct 02 19:54:49 volumio kernel: __x64_sys_pwrite64+0x9e/0xd0 Oct 02 19:54:49 volumio kernel: x64_sys_call+0x1afa/0x2060 Oct 02 19:54:49 volumio kernel: do_syscall_64+0x4b/0x110 Oct 02 19:54:49 volumio kernel: entry_SYSCALL_64_after_hwframe+0x76/0x7e Oct 02 19:54:49 volumio kernel: RIP: 0033:0x7f377041d4b7 Oct 02 19:54:49 volumio kernel: RSP: 002b:00007f37690e2a40 EFLAGS: 00000293 ORIG_RAX: 0000000000000012 Oct 02 19:54:49 volumio kernel: RAX: ffffffffffffffda RBX: 0000000000100000 RCX: 00007f377041d4b7 Oct 02 19:54:49 volumio kernel: RDX: 0000000000100000 RSI: 000055e5d9dbc730 RDI: 000000000000001f Oct 02 19:54:49 volumio kernel: RBP: 0000000002700000 R08: 0000000000000000 R09: 000055e5d9871fb0 Oct 02 19:54:49 volumio kernel: R10: 0000000002700000 R11: 0000000000000293 R12: 0000000000100000 Oct 02 19:54:49 volumio kernel: R13: 000055e5d9dbc730 R14: 000000000000001f R15: 000055e5d9871f50 Oct 02 19:54:49 volumio kernel: Oct 02 19:54:49 volumio kernel: INFO: task smbd:10319 blocked for more than 120 seconds. Oct 02 19:54:49 volumio kernel: Not tainted 6.12.57-volumio #2 Oct 02 19:54:49 volumio kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. Oct 02 19:54:49 volumio kernel: task:smbd state:D stack:0 pid:10319 tgid:10130 ppid:1589 flags:0x00000002 Oct 02 19:54:49 volumio kernel: Call Trace: Oct 02 19:54:49 volumio kernel: Oct 02 19:54:49 volumio kernel: __schedule+0x457/0xa80 Oct 02 19:54:49 volumio kernel: schedule+0x2b/0x100 Oct 02 19:54:49 volumio kernel: schedule_preempt_disabled+0x19/0x30 Oct 02 19:54:49 volumio kernel: rwsem_down_write_slowpath+0x26c/0x5e0 Oct 02 19:54:49 volumio kernel: down_write+0x57/0x70 Oct 02 19:54:49 volumio kernel: ovl_write_iter+0xb7/0x140 [overlay] Oct 02 19:54:49 volumio kernel: ? __pfx_ovl_file_end_write+0x10/0x10 [overlay] Oct 02 19:54:49 volumio kernel: vfs_write+0x285/0x440 Oct 02 19:54:49 volumio kernel: __x64_sys_pwrite64+0x9e/0xd0 Oct 02 19:54:49 volumio kernel: x64_sys_call+0x1afa/0x2060 Oct 02 19:54:49 volumio kernel: do_syscall_64+0x4b/0x110 Oct 02 19:54:49 volumio kernel: entry_SYSCALL_64_after_hwframe+0x76/0x7e Oct 02 19:54:49 volumio kernel: RIP: 0033:0x7f377041d4b7 Oct 02 19:54:49 volumio kernel: RSP: 002b:00007f37688e1a40 EFLAGS: 00000293 ORIG_RAX: 0000000000000012 Oct 02 19:54:49 volumio kernel: RAX: ffffffffffffffda RBX: 0000000000100000 RCX: 00007f377041d4b7 Oct 02 19:54:49 volumio kernel: RDX: 0000000000100000 RSI: 000055e5da0c2510 RDI: 000000000000001f Oct 02 19:54:49 volumio kernel: RBP: 0000000002100000 R08: 0000000000000000 R09: 000055e5d9871fb0 Oct 02 19:54:49 volumio kernel: R10: 0000000002100000 R11: 0000000000000293 R12: 0000000000100000 Oct 02 19:54:49 volumio kernel: R13: 000055e5da0c2510 R14: 000000000000001f R15: 000055e5d9871f50 Oct 02 19:54:49 volumio kernel: Oct 02 19:54:49 volumio kernel: INFO: task smbd:10320 blocked for more than 120 seconds. Oct 02 19:54:49 volumio kernel: Not tainted 6.12.57-volumio #2 Oct 02 19:54:49 volumio kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. Oct 02 19:54:49 volumio kernel: task:smbd state:D stack:0 pid:10320 tgid:10130 ppid:1589 flags:0x00000002 Oct 02 19:54:49 volumio kernel: Call Trace: Oct 02 19:54:49 volumio kernel: Oct 02 19:54:49 volumio kernel: __schedule+0x457/0xa80 Oct 02 19:54:49 volumio kernel: schedule+0x2b/0x100 Oct 02 19:54:49 volumio kernel: schedule_preempt_disabled+0x19/0x30 Oct 02 19:54:49 volumio kernel: rwsem_down_write_slowpath+0x26c/0x5e0 Oct 02 19:54:49 volumio kernel: down_write+0x57/0x70 Oct 02 19:54:49 volumio kernel: ovl_write_iter+0xb7/0x140 [overlay] Oct 02 19:54:49 volumio kernel: ? __pfx_ovl_file_end_write+0x10/0x10 [overlay] Oct 02 19:54:49 volumio kernel: vfs_write+0x285/0x440 Oct 02 19:54:49 volumio kernel: __x64_sys_pwrite64+0x9e/0xd0 Oct 02 19:54:49 volumio kernel: x64_sys_call+0x1afa/0x2060 Oct 02 19:54:49 volumio kernel: do_syscall_64+0x4b/0x110 Oct 02 19:54:49 volumio kernel: entry_SYSCALL_64_after_hwframe+0x76/0x7e Oct 02 19:54:49 volumio kernel: RIP: 0033:0x7f377041d4b7 Oct 02 19:54:49 volumio kernel: RSP: 002b:00007f37680e0a40 EFLAGS: 00000293 ORIG_RAX: 0000000000000012 Oct 02 19:54:49 volumio kernel: RAX: ffffffffffffffda RBX: 0000000000100000 RCX: 00007f377041d4b7 Oct 02 19:54:49 volumio kernel: RDX: 0000000000100000 RSI: 000055e5d9cbc650 RDI: 000000000000001f Oct 02 19:54:49 volumio kernel: RBP: 0000000002200000 R08: 0000000000000000 R09: 000055e5d9871fb4 Oct 02 19:54:49 volumio kernel: R10: 0000000002200000 R11: 0000000000000293 R12: 0000000000100000 Oct 02 19:54:49 volumio kernel: R13: 000055e5d9cbc650 R14: 000000000000001f R15: 000055e5d9871f50 Oct 02 19:54:49 volumio kernel: Oct 02 19:54:54 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:54.526Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.16:51417 @ 0xc000303680" latency=77.804956ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 02 19:54:54 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:54.539Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=76.981512ms timeout=10s Oct 02 19:54:54 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:54.560Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=76.981512ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=20.130541ms Oct 02 19:54:54 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:54.585Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=76.981512ms timeout=10s endpoint=http://pushupdates.volumio.org duration=45.426976ms Oct 02 19:54:54 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:54.589Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=76.981512ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=49.291636ms Oct 02 19:54:54 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:54.592Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=76.981512ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=52.79491ms Oct 02 19:54:54 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:54.664Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=76.981512ms timeout=10s endpoint=https://www.googleapis.com duration=124.390355ms Oct 02 19:54:54 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:54.684Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=76.981512ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=144.37009ms Oct 02 19:54:54 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:54.692Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=76.981512ms timeout=10s endpoint=https://functions.volumio.cloud duration=151.862929ms Oct 02 19:54:54 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:54.692Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=76.981512ms timeout=10s endpoint=https://functions.volumio.cloud duration=152.651655ms Oct 02 19:54:54 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:54.695Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=76.981512ms timeout=10s endpoint=https://database.volumio.cloud duration=155.505171ms Oct 02 19:54:54 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:54.706Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=76.981512ms timeout=10s endpoint=https://securetoken.googleapis.com duration=165.932275ms Oct 02 19:54:54 volumio sudo[10927]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 02 19:54:54 volumio sudo[10927]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 19:54:54 volumio sudo[10927]: pam_unix(sudo:session): session closed for user root Oct 02 19:54:54 volumio sudo[10930]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 02 19:54:54 volumio sudo[10930]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 19:54:54 volumio sudo[10930]: pam_unix(sudo:session): session closed for user root Oct 02 19:54:54 volumio volumio[1138]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.16 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260929.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 02 19:54:54 volumio dbus-daemon[873]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 02 19:54:54 volumio volumio[1138]: info: Discovery: Restarting Browsing Oct 02 19:54:54 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 19:54:54 volumio volumio[1138]: info: CoreCommandRouter::volumioGetVisibleSources Oct 02 19:54:54 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 19:54:54 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Oct 02 19:54:54 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 02 19:54:54 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 02 19:54:54 volumio volumio[1138]: info: Received Get System Info Oct 02 19:54:54 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 19:54:54 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 19:54:54 volumio volumio[1138]: info: Discovery: Getting this device information Oct 02 19:54:54 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Oct 02 19:54:54 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 19:54:54 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Oct 02 19:54:54 volumio volumio[1138]: info: Listing playlists Oct 02 19:54:54 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 02 19:54:54 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 19:54:54 volumio sudo[10936]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 02 19:54:54 volumio sudo[10936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 19:54:54 volumio sudo[10936]: pam_unix(sudo:session): session closed for user root Oct 02 19:54:54 volumio sudo[10938]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 02 19:54:54 volumio sudo[10938]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 19:54:55 volumio sudo[10938]: pam_unix(sudo:session): session closed for user root Oct 02 19:54:55 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:55.017Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=76.981512ms timeout=10s endpoint=http://cddb.volumio.org duration=476.952537ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 02 19:54:55 volumio volumio[1138]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.16 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260929.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 02 19:54:55 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 02 19:54:55 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:55.071Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=76.981512ms timeout=10s endpoint=http://plugins.volumio.org duration=531.631197ms Oct 02 19:54:55 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 19:54:55 volumio volumio[1138]: info: CoreCommandRouter::volumioGetVisibleSources Oct 02 19:54:55 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 19:54:55 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Oct 02 19:54:55 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 02 19:54:55 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 02 19:54:55 volumio volumio[1138]: info: Received Get System Info Oct 02 19:54:55 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 19:54:55 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 19:54:55 volumio volumio[1138]: info: Discovery: Getting this device information Oct 02 19:54:55 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Oct 02 19:54:55 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 19:54:55 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Oct 02 19:54:55 volumio volumio[1138]: info: Listing playlists Oct 02 19:54:55 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 02 19:54:55 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 19:54:55 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 02 19:54:55 volumio volumio5-onboarding[1673]: time=2026-10-02T20:54:55.236Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.16:51417 @ 0xc000303680" latency=76.981512ms timeout=10s endpoint=https://google.com duration=696.27926ms Oct 02 19:54:56 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 02 19:54:56 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 19:54:56 volumio volumio[1138]: info: Received Get System Info Oct 02 19:54:56 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 19:54:56 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 19:54:56 volumio volumio[1138]: info: Discovery: Getting this device information Oct 02 19:54:56 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Oct 02 19:54:56 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 19:54:58 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 19:54:58 volumio volumio[1138]: info: Received Get System Info Oct 02 19:54:58 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 19:54:58 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 19:54:58 volumio volumio[1138]: info: Discovery: Getting this device information Oct 02 19:54:58 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Oct 02 19:54:58 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 19:54:59 volumio dbus-daemon[873]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 02 19:54:59 volumio volumio[1138]: info: Discovery: Restarting Browsing Oct 02 19:55:00 volumio volumio[1138]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 19:55:00 volumio volumio[1138]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 19:55:00 volumio volumio[1138]: info: Retrieving Cloud Streaming UI Oct 02 19:55:00 volumio volumio[1138]: info: Getting Tidal Cloud Configuration Oct 02 19:55:00 volumio volumio[1138]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 19:55:00 volumio volumio[1138]: info: Getting Qobuz Cloud Configuration Oct 02 19:55:00 volumio volumio[1138]: info: Asking plugin for UI Config Oct 02 19:55:00 volumio volumio[1138]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 19:55:00 volumio volumio[1138]: info: Getting Spotify Cloud Configuration Oct 02 19:55:00 volumio volumio[1138]: info: Asking plugin for UI Config Oct 02 19:55:00 volumio volumio[1138]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 19:55:00 volumio volumio[1138]: info: Saving Spotify Acccount Oct 02 19:55:00 volumio volumio[1138]: info: Got Tidal Cloud Configuration Oct 02 19:55:00 volumio volumio[1138]: info: Got it Oct 02 19:55:00 volumio volumio[1138]: info: Got it Oct 02 19:55:00 volumio volumio[1138]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 02 19:55:00 volumio volumio[1138]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 19:55:00 volumio volumio[1138]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Oct 02 19:55:00 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 02 19:55:00 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 02 19:55:00 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 02 19:55:00 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 02 19:55:00 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 19:55:00 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 19:55:00 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 19:55:00 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 19:55:00 volumio volumio[1138]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 19:55:00 volumio volumio[1138]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 19:55:00 volumio volumio[1138]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 19:55:00 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 19:55:00 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 02 19:55:03 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Oct 02 19:55:03 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Oct 02 19:55:03 volumio dbus-daemon[873]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 02 19:55:03 volumio volumio[1138]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 19:55:03 volumio volumio[1138]: Error: dns service error: refused Oct 02 19:55:03 volumio volumio[1138]: at new Browser (/volumio/node_modules/mdns/lib/browser.js:88:10) Oct 02 19:55:03 volumio volumio[1138]: at Object.create [as createBrowser] (/volumio/node_modules/mdns/lib/browser.js:116:10) Oct 02 19:55:03 volumio volumio[1138]: at ControllerVolumioDiscovery.browseForService (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:718:32) Oct 02 19:55:03 volumio volumio[1138]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Oct 02 19:55:03 volumio volumio[1138]: at ControllerNetworkfs.discoverShares (/volumio/app/plugins/system_controller/networkfs/index.js:826:36) Oct 02 19:55:03 volumio volumio[1138]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Oct 02 19:55:03 volumio volumio[1138]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1614:45) Oct 02 19:55:03 volumio volumio[1138]: at Socket.emit (node:events:514:28) Oct 02 19:55:03 volumio volumio[1138]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 02 19:55:03 volumio volumio[1138]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Oct 02 19:55:03 volumio volumio[1138]: errorCode: -65553 Oct 02 19:55:03 volumio volumio[1138]: } Oct 02 19:55:03 volumio volumio[1138]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 19:55:03 volumio sudo[10970]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-02 19:54' Oct 02 19:55:03 volumio sudo[10970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"