Oct 16 14:36:02 volumio-signature systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4362. Oct 16 14:36:02 volumio-signature systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 14:36:02 volumio-signature systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 14:36:02 volumio-signature upmpdcli[14177]: Could not open config: /tmp/upmpdcli.conf Oct 16 14:36:02 volumio-signature systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 16 14:36:02 volumio-signature systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 16 14:36:17 volumio-signature systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4363. Oct 16 14:36:17 volumio-signature systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 14:36:17 volumio-signature systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 14:36:17 volumio-signature upmpdcli[14194]: Could not open config: /tmp/upmpdcli.conf Oct 16 14:36:17 volumio-signature systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 16 14:36:17 volumio-signature systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 16 14:36:32 volumio-signature systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4364. Oct 16 14:36:32 volumio-signature systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 14:36:32 volumio-signature systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 14:36:32 volumio-signature upmpdcli[14227]: Could not open config: /tmp/upmpdcli.conf Oct 16 14:36:32 volumio-signature systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 16 14:36:32 volumio-signature systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 16 14:36:40 volumio-signature volumio[26460]: info: Oct 16 14:36:40 volumio-signature volumio[26460]: ---------------------------- MPD announces system playlist update Oct 16 14:36:40 volumio-signature volumio[26460]: info: Ignoring MPD Status Update Oct 16 14:36:40 volumio-signature volumio[26460]: info: Oct 16 14:36:40 volumio-signature volumio[26460]: ---------------------------- MPD announces state update: player Oct 16 14:36:40 volumio-signature volumio[26460]: info: ControllerMpd::getState Oct 16 14:36:40 volumio-signature volumio[26460]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:36:40 volumio-signature volumio[26460]: info: ------------------------------ 4ms Oct 16 14:36:40 volumio-signature volumio[26460]: info: sendMpdCommand status took 3 milliseconds Oct 16 14:36:40 volumio-signature volumio[26460]: verbose: ControllerMpd::parseState Oct 16 14:36:40 volumio-signature volumio[26460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 14:36:40 volumio-signature volumio[26460]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 16 14:36:40 volumio-signature volumio[26460]: verbose: ControllerMpd::parseTrackInfo Oct 16 14:36:40 volumio-signature volumio[26460]: info: ControllerMpd::pushState Oct 16 14:36:40 volumio-signature volumio[26460]: info: CoreCommandRouter::servicePushState Oct 16 14:36:40 volumio-signature volumio[26460]: info: CorePlayQueue::getTrack 0 Oct 16 14:36:40 volumio-signature volumio[26460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3942605,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Radio Swiss Classic - www.radioswissclassic.ch","artist":"Swiss Classic D","album":null,"uri":"http://stream.srg-ssr.ch/srgssr/rsc_de/aac/96","trackType":""} Oct 16 14:36:40 volumio-signature volumio[26460]: verbose: CURRENT POSITION 0 Oct 16 14:36:40 volumio-signature volumio[26460]: info: CoreStateMachine::syncState stateService play Oct 16 14:36:40 volumio-signature volumio[26460]: info: CoreStateMachine::syncState currentStatus play Oct 16 14:36:40 volumio-signature volumio[26460]: info: Received an update from plugin. extracting info from payload Oct 16 14:36:40 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 16 14:36:40 volumio-signature volumio[26460]: info: CoreStateMachine::pushState Oct 16 14:36:40 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:36:40 volumio-signature volumio[26460]: info: CoreCommandRouter::volumioPushState Oct 16 14:36:40 volumio-signature volumio[26460]: info: MRS: Pushing multiroomSync output update for this device Oct 16 14:36:40 volumio-signature volumio[26460]: info: MRS: Pushing multiroomSync output Oct 16 14:36:40 volumio-signature volumio[26460]: info: CoreCommandRouter::volumioGetState Oct 16 14:36:40 volumio-signature volumio[26460]: info: CoreStateMachine::pushState Oct 16 14:36:40 volumio-signature volumio[26460]: info: CoreCommandRouter::volumioPushState Oct 16 14:36:40 volumio-signature volumio[26460]: info: MRS: Pushing multiroomSync output update for this device Oct 16 14:36:40 volumio-signature volumio[26460]: info: MRS: Pushing multiroomSync output Oct 16 14:36:40 volumio-signature volumio[26460]: info: CoreCommandRouter::volumioGetState Oct 16 14:36:40 volumio-signature volumio[26460]: info: ------------------------------ 17ms Oct 16 14:36:48 volumio-signature systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4365. Oct 16 14:36:48 volumio-signature systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 14:36:48 volumio-signature systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 14:36:48 volumio-signature upmpdcli[14243]: Could not open config: /tmp/upmpdcli.conf Oct 16 14:36:48 volumio-signature systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 16 14:36:48 volumio-signature systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 16 14:37:03 volumio-signature systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4366. Oct 16 14:37:03 volumio-signature systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 14:37:03 volumio-signature systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 14:37:03 volumio-signature upmpdcli[14274]: Could not open config: /tmp/upmpdcli.conf Oct 16 14:37:03 volumio-signature systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 16 14:37:03 volumio-signature systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 16 14:37:10 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:10.934+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.198:56714 Oct 16 14:37:10 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:10.952+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.198:56714 @ 0x1ce6120" latency=884.964707ms platform=PLATFORM_ANDROID version=6.260930.0 Oct 16 14:37:10 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:10.952+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.198:56714 @ 0x1ce6120" latency=883.099707ms timeout=20s Oct 16 14:37:10 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:10.952+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.198:56714 @ 0x1ce6120" Oct 16 14:37:10 volumio-signature volumio[26460]: info: Received Get System Info Oct 16 14:37:10 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 14:37:10 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 14:37:10 volumio-signature volumio[26460]: info: Discovery: Getting this device information Oct 16 14:37:10 volumio-signature volumio[26460]: info: CoreCommandRouter::volumioGetState Oct 16 14:37:10 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 14:37:10 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:10.955+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.198:56714 @ 0x1ce6120" name="volumio signature" Oct 16 14:37:10 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:10.957+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.198:56714 @ 0x1ce6120" language=de Oct 16 14:37:10 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 16 14:37:10 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:10.959+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.198:56714 @ 0x1ce6120" timezone=Europe/Zurich Oct 16 14:37:10 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:10.961+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.198:56714 @ 0x1ce6120" available=true connected=true macAddress=b8:27:eb:27:04:d3 ip4Address=192.168.1.46/24 ip6Address= Oct 16 14:37:10 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:10.963+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.198:56714 @ 0x1ce6120" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Oct 16 14:37:10 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:10.963+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.198:56714 @ 0x1ce6120" setupComplete=true Oct 16 14:37:10 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 16 14:37:10 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 16 14:37:10 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 14:37:10 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 14:37:11 volumio-signature volumio[26460]: amixer -c 0 info | grep "bcm2835 ALSA" Oct 16 14:37:11 volumio-signature volumio[26460]: amixer -c 1 info | grep "bcm2835 Headphones" Oct 16 14:37:11 volumio-signature volumio[26460]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Oct 16 14:37:11 volumio-signature volumio[26460]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Oct 16 14:37:11 volumio-signature volumio[26460]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 16 14:37:11 volumio-signature volumio[26460]: {"cmd":"/usr/local/bin/alsacap -C 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 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 16 14:37:11 volumio-signature volumio[26460]: amixer -c 3 info | grep "snd_allo_digione" Oct 16 14:37:11 volumio-signature volumio[26460]: Card sysdefault:3 'sndallodigione'/'snd_allo_digione' Oct 16 14:37:11 volumio-signature volumio[26460]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 2 Oct 16 14:37:11 volumio-signature volumio[26460]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 16 14:37:11 volumio-signature volumio[26460]: {"cmd":"/usr/local/bin/alsacap -C 2","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 2\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 16 14:37:11 volumio-signature volumio[26460]: amixer -c 2 info | grep "vc4-hdmi" Oct 16 14:37:11 volumio-signature volumio[26460]: Card sysdefault:2 'vc4hdmi'/'vc4-hdmi' Oct 16 14:37:11 volumio-signature volumio[26460]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Oct 16 14:37:11 volumio-signature volumio[26460]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 16 14:37:11 volumio-signature volumio[26460]: {"cmd":"/usr/local/bin/alsacap -C 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 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 16 14:37:11 volumio-signature volumio[26460]: amixer -c 3 info | grep "Allo DigiOne" Oct 16 14:37:11 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:11.166+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.198:56714 @ 0x1ce6120" selectedOutputId=3 Oct 16 14:37:11 volumio-signature volumio[26460]: info: Received Get System Info Oct 16 14:37:11 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 14:37:11 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 14:37:11 volumio-signature volumio[26460]: info: Discovery: Getting this device information Oct 16 14:37:11 volumio-signature volumio[26460]: info: CoreCommandRouter::volumioGetState Oct 16 14:37:11 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 14:37:11 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:11.191+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.198:56714 @ 0x1ce6120" currentVersion=4.119 latestVersion=4.119 Oct 16 14:37:11 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:11.192+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.198:56714 @ 0x1ce6120" status=UPDATE_STATUS_NONE progress=0 Oct 16 14:37:11 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:11.192+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.198:56714 @ 0x1ce6120" userId= Oct 16 14:37:11 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:11.192+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.198:56714 @ 0x1ce6120" providers=3 Oct 16 14:37:11 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:11.193+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.198:56714 @ 0x1ce6120" plugins=78 Oct 16 14:37:11 volumio-signature volumio[26460]: info: CoreCommandRouter::volumioGetState Oct 16 14:37:11 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:11.198+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:56714 @ 0x1ce6120" state=STATUS_PLAYING positionMs=3972045 volume=100 Oct 16 14:37:11 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:11.199+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:56714 @ 0x1ce6120" id=http://stream.srg-ssr.ch/srgssr/rsc_de/aac/96 title="Radio Swiss Classic - www.radioswissclassic.ch" Oct 16 14:37:11 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 14:37:11 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 14:37:11 volumio-signature volumio[26460]: info: Discovery: Getting this device information Oct 16 14:37:11 volumio-signature volumio[26460]: info: CoreCommandRouter::volumioGetState Oct 16 14:37:11 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 14:37:11 volumio-signature volumio[26460]: verbose: New Socket.io Connection to 192.168.1.46:3000 from 192.168.1.198 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Oct 16 14:37:11 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 14:37:11 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 14:37:12 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:12.066+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.198:56714 @ 0x1ce6120" latency=886.05377ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 16 14:37:12 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:12.087+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:56714 @ 0x1ce6120" latency=883.90029ms timeout=10s Oct 16 14:37:12 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:12.444+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:56714 @ 0x1ce6120" latency=883.90029ms timeout=10s endpoint=http://pushupdates.volumio.org duration=357.20551ms Oct 16 14:37:12 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:12.698+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:56714 @ 0x1ce6120" latency=883.90029ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=610.866293ms Oct 16 14:37:12 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:12.711+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:56714 @ 0x1ce6120" latency=883.90029ms timeout=10s endpoint=https://www.googleapis.com duration=623.387218ms Oct 16 14:37:12 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:12.743+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:56714 @ 0x1ce6120" latency=883.90029ms timeout=10s endpoint=http://plugins.volumio.org duration=655.620646ms Oct 16 14:37:12 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:12.763+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:56714 @ 0x1ce6120" latency=883.90029ms timeout=10s endpoint=http://cddb.volumio.org duration=676.75736ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 16 14:37:12 volumio-signature sudo[14325]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 14:37:12 volumio-signature sudo[14325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 14:37:12 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:12.855+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:56714 @ 0x1ce6120" latency=883.90029ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=767.84106ms Oct 16 14:37:12 volumio-signature sudo[14325]: pam_unix(sudo:session): session closed for user root Oct 16 14:37:12 volumio-signature sudo[14326]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 14:37:12 volumio-signature sudo[14326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 14:37:12 volumio-signature sudo[14326]: pam_unix(sudo:session): session closed for user root Oct 16 14:37:12 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:12.869+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:56714 @ 0x1ce6120" latency=883.90029ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=782.202653ms Oct 16 14:37:12 volumio-signature volumio[26460]: verbose: New Socket.io Connection to 192.168.1.46 from 192.168.1.198 UA: Mozilla/5.0 (Linux; Android 16; CPH2629 Build/BP2A.250605.015; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.36 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 16 14:37:12 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:12.923+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:56714 @ 0x1ce6120" latency=883.90029ms timeout=10s endpoint=https://securetoken.googleapis.com duration=836.218104ms Oct 16 14:37:12 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:12.934+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:56714 @ 0x1ce6120" latency=883.90029ms timeout=10s endpoint=https://functions.volumio.cloud duration=846.668208ms Oct 16 14:37:12 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:12.937+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:56714 @ 0x1ce6120" latency=883.90029ms timeout=10s endpoint=https://functions.volumio.cloud duration=850.175586ms Oct 16 14:37:12 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:12.956+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:56714 @ 0x1ce6120" latency=883.90029ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=866.874354ms Oct 16 14:37:13 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:13.025+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:56714 @ 0x1ce6120" latency=883.90029ms timeout=10s endpoint=https://database.volumio.cloud duration=937.969926ms Oct 16 14:37:13 volumio-signature sudo[14330]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 14:37:13 volumio-signature sudo[14330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 14:37:13 volumio-signature sudo[14330]: pam_unix(sudo:session): session closed for user root Oct 16 14:37:13 volumio-signature sudo[14332]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 14:37:13 volumio-signature sudo[14332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 14:37:13 volumio-signature sudo[14332]: pam_unix(sudo:session): session closed for user root Oct 16 14:37:13 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:13.272+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:56714 @ 0x1ce6120" latency=883.90029ms timeout=10s endpoint=https://google.com duration=1.184915638s Oct 16 14:37:13 volumio-signature volumio[26460]: verbose: New Socket.io Connection to 192.168.1.46 from 192.168.1.198 UA: Mozilla/5.0 (Linux; Android 16; CPH2629 Build/BP2A.250605.015; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.36 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 16 14:37:13 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 14:37:13 volumio-signature volumio[26460]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 14:37:13 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 14:37:13 volumio-signature volumio[26460]: info: CoreCommandRouter::volumioGetState Oct 16 14:37:13 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 16 14:37:13 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 16 14:37:13 volumio-signature volumio[26460]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 16 14:37:13 volumio-signature volumio[26460]: info: Received Get System Info Oct 16 14:37:13 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 14:37:13 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 14:37:13 volumio-signature volumio[26460]: info: Discovery: Getting this device information Oct 16 14:37:13 volumio-signature volumio[26460]: info: CoreCommandRouter::volumioGetState Oct 16 14:37:13 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 14:37:13 volumio-signature volumio[26460]: info: CoreCommandRouter::volumioGetState Oct 16 14:37:13 volumio-signature volumio[26460]: info: Listing playlists Oct 16 14:37:13 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 16 14:37:13 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 16 14:37:13 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 16 14:37:15 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 16 14:37:15 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 14:37:15 volumio-signature volumio[26460]: info: Received Get System Info Oct 16 14:37:15 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 14:37:15 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 14:37:15 volumio-signature volumio[26460]: info: Discovery: Getting this device information Oct 16 14:37:15 volumio-signature volumio[26460]: info: CoreCommandRouter::volumioGetState Oct 16 14:37:15 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 14:37:15 volumio-signature volumio[26460]: info: Oct 16 14:37:15 volumio-signature volumio[26460]: ---------------------------- MPD announces system playlist update Oct 16 14:37:15 volumio-signature volumio[26460]: info: Ignoring MPD Status Update Oct 16 14:37:15 volumio-signature volumio[26460]: info: Oct 16 14:37:15 volumio-signature volumio[26460]: ---------------------------- MPD announces state update: player Oct 16 14:37:15 volumio-signature volumio[26460]: info: ControllerMpd::getState Oct 16 14:37:15 volumio-signature volumio[26460]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:37:15 volumio-signature volumio[26460]: info: ------------------------------ 3ms Oct 16 14:37:15 volumio-signature volumio[26460]: info: sendMpdCommand status took 2 milliseconds Oct 16 14:37:15 volumio-signature volumio[26460]: verbose: ControllerMpd::parseState Oct 16 14:37:15 volumio-signature volumio[26460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 14:37:15 volumio-signature volumio[26460]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 16 14:37:15 volumio-signature volumio[26460]: verbose: ControllerMpd::parseTrackInfo Oct 16 14:37:15 volumio-signature volumio[26460]: info: ControllerMpd::pushState Oct 16 14:37:15 volumio-signature volumio[26460]: info: CoreCommandRouter::servicePushState Oct 16 14:37:15 volumio-signature volumio[26460]: info: CorePlayQueue::getTrack 0 Oct 16 14:37:15 volumio-signature volumio[26460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3977986,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Allegro molto aus der Sinfonie Nr.1 c-Moll","artist":"Richard Hol","album":null,"uri":"http://stream.srg-ssr.ch/srgssr/rsc_de/aac/96","trackType":""} Oct 16 14:37:15 volumio-signature volumio[26460]: verbose: CURRENT POSITION 0 Oct 16 14:37:15 volumio-signature volumio[26460]: info: CoreStateMachine::syncState stateService play Oct 16 14:37:15 volumio-signature volumio[26460]: info: CoreStateMachine::syncState currentStatus play Oct 16 14:37:15 volumio-signature volumio[26460]: info: Received an update from plugin. extracting info from payload Oct 16 14:37:15 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 16 14:37:15 volumio-signature volumio[26460]: info: CoreStateMachine::pushState Oct 16 14:37:15 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:15 volumio-signature volumio[26460]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:15 volumio-signature volumio[26460]: info: MRS: Pushing multiroomSync output update for this device Oct 16 14:37:15 volumio-signature volumio[26460]: info: MRS: Pushing multiroomSync output Oct 16 14:37:15 volumio-signature volumio[26460]: info: CoreCommandRouter::volumioGetState Oct 16 14:37:15 volumio-signature volumio[26460]: info: CoreStateMachine::pushState Oct 16 14:37:15 volumio-signature volumio[26460]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:15 volumio-signature volumio[26460]: info: MRS: Pushing multiroomSync output update for this device Oct 16 14:37:15 volumio-signature volumio[26460]: info: MRS: Pushing multiroomSync output Oct 16 14:37:15 volumio-signature volumio[26460]: info: CoreCommandRouter::volumioGetState Oct 16 14:37:15 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:15.828+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:56714 @ 0x1ce6120" state=STATUS_PLAYING positionMs=3976576 volume=100 Oct 16 14:37:15 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:15.829+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:56714 @ 0x1ce6120" id=http://stream.srg-ssr.ch/srgssr/rsc_de/aac/96 title="Allegro molto aus der Sinfonie Nr.1 c-Moll" Oct 16 14:37:15 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:15.829+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:56714 @ 0x1ce6120" state=STATUS_PLAYING positionMs=3976576 volume=100 Oct 16 14:37:15 volumio-signature volumio5-onboarding[1468]: time=2026-10-16T14:37:15.830+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:56714 @ 0x1ce6120" id=http://stream.srg-ssr.ch/srgssr/rsc_de/aac/96 title="Allegro molto aus der Sinfonie Nr.1 c-Moll" Oct 16 14:37:15 volumio-signature volumio[26460]: info: ------------------------------ 18ms Oct 16 14:37:15 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 14:37:15 volumio-signature volumio[26460]: info: Received Get System Info Oct 16 14:37:15 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 14:37:15 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 14:37:15 volumio-signature volumio[26460]: info: Discovery: Getting this device information Oct 16 14:37:15 volumio-signature volumio[26460]: info: CoreCommandRouter::volumioGetState Oct 16 14:37:15 volumio-signature volumio[26460]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 14:37:18 volumio-signature systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4367. Oct 16 14:37:18 volumio-signature systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 14:37:18 volumio-signature systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 14:37:18 volumio-signature upmpdcli[14336]: Could not open config: /tmp/upmpdcli.conf Oct 16 14:37:18 volumio-signature systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 16 14:37:18 volumio-signature systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 16 14:37:20 volumio-signature volumio[26460]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 14:37:20 volumio-signature volumio[26460]: TypeError: Cannot read properties of null (reading 'slice') Oct 16 14:37:20 volumio-signature volumio[26460]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Oct 16 14:37:20 volumio-signature volumio[26460]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Oct 16 14:37:20 volumio-signature volumio[26460]: at Object.onceWrapper (node:events:629:26) Oct 16 14:37:20 volumio-signature volumio[26460]: at ClientRequest.emit (node:events:514:28) Oct 16 14:37:20 volumio-signature volumio[26460]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Oct 16 14:37:20 volumio-signature volumio[26460]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Oct 16 14:37:20 volumio-signature volumio[26460]: at TLSSocket.socketOnData (node:_http_client:535:22) Oct 16 14:37:20 volumio-signature volumio[26460]: at TLSSocket.emit (node:events:514:28) Oct 16 14:37:20 volumio-signature volumio[26460]: at addChunk (node:internal/streams/readable:343:12) Oct 16 14:37:20 volumio-signature volumio[26460]: at readableAddChunk (node:internal/streams/readable:316:9) Oct 16 14:37:20 volumio-signature volumio[26460]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 14:37:21 volumio-signature sudo[14353]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-16 14:36' Oct 16 14:37:21 volumio-signature sudo[14353]: 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"