Aug 28 12:38:04 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:04.394+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.86.92:38376 Aug 28 12:38:04 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:04.446+01:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.86.92:38376 @ 0x1ff4030" latency=-562.65873ms platform=PLATFORM_ANDROID version=6.260807.0 Aug 28 12:38:04 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:04.448+01:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.86.92:38376 @ 0x1ff4030" latency=-564.552256ms timeout=20s Aug 28 12:38:04 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:04.448+01:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.86.92:38376 @ 0x1ff4030" Aug 28 12:38:04 music-server volumio[1249]: info: Received Get System Info Aug 28 12:38:04 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 12:38:04 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 12:38:04 music-server volumio[1249]: info: Discovery: Getting this device information Aug 28 12:38:04 music-server volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 12:38:04 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:38:04 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 12:38:04 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:04.451+01:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.86.92:38376 @ 0x1ff4030" name="Music Server" Aug 28 12:38:04 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:04.456+01:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.86.92:38376 @ 0x1ff4030" language=en Aug 28 12:38:04 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 28 12:38:04 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:04.457+01:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.86.92:38376 @ 0x1ff4030" timezone=Europe/London Aug 28 12:38:04 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:04.458+01:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.86.92:38376 @ 0x1ff4030" available=true connected=false macAddress= ip4Address= ip6Address= Aug 28 12:38:04 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:04.462+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.86.92:38376 @ 0x1ff4030" available=true connected=true macAddress=dc:a6:32:c8:a4:c5 ip4Address=192.168.86.79/24 ip6Address= ssid="Stayballs Net" Aug 28 12:38:04 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:04.462+01:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.86.92:38376 @ 0x1ff4030" setupComplete=true Aug 28 12:38:04 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 28 12:38:04 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 28 12:38:04 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 12:38:04 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 28 12:38:04 music-server volumio[1249]: amixer -c 0 info | grep "bcm2835 ALSA" Aug 28 12:38:04 music-server volumio[1249]: amixer -c 1 info | grep "bcm2835 Headphones" Aug 28 12:38:04 music-server volumio[1249]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Aug 28 12:38:04 music-server volumio[1249]: amixer -c 2 info | grep "vc4-hdmi-0" Aug 28 12:38:04 music-server volumio[1249]: Card sysdefault:2 'vc4hdmi0'/'vc4-hdmi-0' Aug 28 12:38:04 music-server volumio[1249]: amixer -c 3 info | grep "vc4-hdmi-1" Aug 28 12:38:04 music-server volumio[1249]: Card sysdefault:3 'vc4hdmi1'/'vc4-hdmi-1' Aug 28 12:38:04 music-server volumio[1249]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Aug 28 12:38:04 music-server volumio[1249]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 28 12:38:04 music-server volumio[1249]: {"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)"} Aug 28 12:38:04 music-server volumio[1249]: amixer -c 5 info | grep "2Qute" Aug 28 12:38:04 music-server volumio[1249]: Card sysdefault:5 'D2Qute'/'Chord Electronics Ltd 2Qute at usb-0000:01:00.0-1.1, high speed' Aug 28 12:38:04 music-server volumio[1249]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Aug 28 12:38:04 music-server volumio[1249]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 28 12:38:04 music-server volumio[1249]: {"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)"} Aug 28 12:38:04 music-server volumio[1249]: amixer -c 5 info | grep "2Qute" Aug 28 12:38:04 music-server volumio[1249]: Card sysdefault:5 'D2Qute'/'Chord Electronics Ltd 2Qute at usb-0000:01:00.0-1.1, high speed' Aug 28 12:38:04 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:04.678+01:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.86.92:38376 @ 0x1ff4030" selectedOutputId=5 Aug 28 12:38:04 music-server volumio[1249]: info: Received Get System Info Aug 28 12:38:04 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 12:38:04 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 12:38:04 music-server volumio[1249]: info: Discovery: Getting this device information Aug 28 12:38:04 music-server volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 12:38:04 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:38:04 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 12:38:04 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:04.705+01:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.86.92:38376 @ 0x1ff4030" currentVersion=4.119 latestVersion=4.119 Aug 28 12:38:04 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:04.705+01:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.86.92:38376 @ 0x1ff4030" status=UPDATE_STATUS_NONE progress=0 Aug 28 12:38:04 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:04.705+01:00 level=INFO msg="emitting user changed event" component=server peer="192.168.86.92:38376 @ 0x1ff4030" userId= Aug 28 12:38:04 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:04.706+01:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.86.92:38376 @ 0x1ff4030" providers=3 Aug 28 12:38:04 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:04.706+01:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.86.92:38376 @ 0x1ff4030" plugins=68 Aug 28 12:38:04 music-server volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 12:38:04 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:38:04 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:04.708+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.92:38376 @ 0x1ff4030" state=STATUS_PAUSED positionMs=245438 volume=31 Aug 28 12:38:04 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:04.708+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.92:38376 @ 0x1ff4030" id="mnt/USB/SSD_Drive/Music/Cindy Lee - Diamond Jubilee/15 - Til Polarity's End.flac" title="Til Polarity's End" Aug 28 12:38:04 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:04.738+01:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:38376 @ 0x1ff4030" latency=-566.189523ms timeout=10s Aug 28 12:38:04 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:04.856+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:38376 @ 0x1ff4030" latency=-566.189523ms timeout=10s endpoint=http://pushupdates.volumio.org duration=111.651509ms Aug 28 12:38:05 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:05.003+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:38376 @ 0x1ff4030" latency=-566.189523ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=258.982265ms Aug 28 12:38:05 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:05.012+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:38376 @ 0x1ff4030" latency=-566.189523ms timeout=10s endpoint=https://www.googleapis.com duration=268.526455ms Aug 28 12:38:05 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:05.063+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:38376 @ 0x1ff4030" latency=-566.189523ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=324.434968ms Aug 28 12:38:05 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:05.068+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:38376 @ 0x1ff4030" latency=-566.189523ms timeout=10s endpoint=https://securetoken.googleapis.com duration=323.310675ms Aug 28 12:38:05 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:05.081+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:38376 @ 0x1ff4030" latency=-566.189523ms timeout=10s endpoint=https://google.com duration=336.638667ms Aug 28 12:38:05 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:05.084+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:38376 @ 0x1ff4030" latency=-566.189523ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=339.760416ms Aug 28 12:38:05 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:05.101+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:38376 @ 0x1ff4030" latency=-566.189523ms timeout=10s endpoint=https://functions.volumio.cloud duration=355.503325ms Aug 28 12:38:05 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:05.112+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:38376 @ 0x1ff4030" latency=-566.189523ms timeout=10s endpoint=https://functions.volumio.cloud duration=367.684913ms Aug 28 12:38:05 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:05.136+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:38376 @ 0x1ff4030" latency=-566.189523ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=397.341053ms Aug 28 12:38:05 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:05.204+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:38376 @ 0x1ff4030" latency=-566.189523ms timeout=10s endpoint=https://database.volumio.cloud duration=458.69538ms Aug 28 12:38:05 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:05.308+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:38376 @ 0x1ff4030" latency=-566.189523ms timeout=10s endpoint=http://cddb.volumio.org duration=562.900359ms Aug 28 12:38:05 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:05.470+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:38376 @ 0x1ff4030" latency=-566.189523ms timeout=10s endpoint=http://plugins.volumio.org duration=725.394804ms Aug 28 12:38:09 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:09.803+01:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.86.92:38376 error="read tcp 192.168.86.79:7331->192.168.86.92:38376: read: connection reset by peer" Aug 28 12:38:09 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:09.803+01:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.86.92:38376 Aug 28 12:38:09 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:09.803+01:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.86.92:38376 Aug 28 12:38:54 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 12:38:54 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 12:38:54 music-server volumio[1249]: info: Discovery: Getting this device information Aug 28 12:38:54 music-server volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 12:38:54 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:38:54 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 12:38:54 music-server volumio[1249]: verbose: New Socket.io Connection to 192.168.86.79:3000 from 192.168.86.92 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Aug 28 12:38:55 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 12:38:55 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 12:38:55 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:55.727+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.86.92:60060 Aug 28 12:38:55 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:55.792+01:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:60060 @ 0x1ccd3e0" latency=-566.855624ms timeout=10s Aug 28 12:38:55 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:55.802+01:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.86.92:60060 @ 0x1ccd3e0" latency=-567.218975ms timeout=20s Aug 28 12:38:55 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:55.802+01:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" Aug 28 12:38:55 music-server volumio[1249]: info: Received Get System Info Aug 28 12:38:55 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 12:38:55 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 12:38:55 music-server volumio[1249]: info: Discovery: Getting this device information Aug 28 12:38:55 music-server volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 12:38:55 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:38:55 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 12:38:55 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:55.805+01:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" name="Music Server" Aug 28 12:38:55 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:55.805+01:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" language=en Aug 28 12:38:55 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 28 12:38:55 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:55.807+01:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" timezone=Europe/London Aug 28 12:38:55 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:55.808+01:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" available=true connected=false macAddress= ip4Address= ip6Address= Aug 28 12:38:55 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:55.811+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:60060 @ 0x1ccd3e0" latency=-566.855624ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=18.769084ms Aug 28 12:38:55 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:55.811+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" available=true connected=true macAddress=dc:a6:32:c8:a4:c5 ip4Address=192.168.86.79/24 ip6Address= ssid="Stayballs Net" Aug 28 12:38:55 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:55.811+01:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" setupComplete=true Aug 28 12:38:55 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 28 12:38:55 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 28 12:38:55 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 12:38:55 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 28 12:38:55 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:55.845+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:60060 @ 0x1ccd3e0" latency=-566.855624ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=52.410395ms Aug 28 12:38:55 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:55.845+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:60060 @ 0x1ccd3e0" latency=-566.855624ms timeout=10s endpoint=http://pushupdates.volumio.org duration=52.54308ms Aug 28 12:38:55 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:55.845+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:60060 @ 0x1ccd3e0" latency=-566.855624ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=52.628006ms Aug 28 12:38:55 music-server volumio[1249]: amixer -c 0 info | grep "bcm2835 ALSA" Aug 28 12:38:55 music-server volumio[1249]: amixer -c 1 info | grep "bcm2835 Headphones" Aug 28 12:38:55 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:55.894+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:60060 @ 0x1ccd3e0" latency=-566.855624ms timeout=10s endpoint=https://google.com duration=100.93736ms Aug 28 12:38:55 music-server volumio[1249]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Aug 28 12:38:55 music-server volumio[1249]: amixer -c 2 info | grep "vc4-hdmi-0" Aug 28 12:38:55 music-server volumio[1249]: Card sysdefault:2 'vc4hdmi0'/'vc4-hdmi-0' Aug 28 12:38:55 music-server volumio[1249]: amixer -c 3 info | grep "vc4-hdmi-1" Aug 28 12:38:55 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:55.919+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:60060 @ 0x1ccd3e0" latency=-566.855624ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=125.954108ms Aug 28 12:38:55 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:55.922+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:60060 @ 0x1ccd3e0" latency=-566.855624ms timeout=10s endpoint=https://www.googleapis.com duration=128.696784ms Aug 28 12:38:55 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:55.934+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:60060 @ 0x1ccd3e0" latency=-566.855624ms timeout=10s endpoint=https://database.volumio.cloud duration=141.495943ms Aug 28 12:38:55 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:55.940+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:60060 @ 0x1ccd3e0" latency=-566.855624ms timeout=10s endpoint=https://securetoken.googleapis.com duration=146.937962ms Aug 28 12:38:55 music-server volumio[1249]: Card sysdefault:3 'vc4hdmi1'/'vc4-hdmi-1' Aug 28 12:38:55 music-server volumio[1249]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Aug 28 12:38:55 music-server volumio[1249]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 28 12:38:55 music-server volumio[1249]: {"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)"} Aug 28 12:38:55 music-server volumio[1249]: amixer -c 5 info | grep "2Qute" Aug 28 12:38:55 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:55.957+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:60060 @ 0x1ccd3e0" latency=-566.855624ms timeout=10s endpoint=https://functions.volumio.cloud duration=165.339733ms Aug 28 12:38:55 music-server volumio[1249]: Card sysdefault:5 'D2Qute'/'Chord Electronics Ltd 2Qute at usb-0000:01:00.0-1.1, high speed' Aug 28 12:38:55 music-server volumio[1249]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Aug 28 12:38:55 music-server volumio[1249]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 28 12:38:55 music-server volumio[1249]: {"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)"} Aug 28 12:38:55 music-server volumio[1249]: amixer -c 5 info | grep "2Qute" Aug 28 12:38:55 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:55.992+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:60060 @ 0x1ccd3e0" latency=-566.855624ms timeout=10s endpoint=https://functions.volumio.cloud duration=198.83623ms Aug 28 12:38:56 music-server volumio[1249]: Card sysdefault:5 'D2Qute'/'Chord Electronics Ltd 2Qute at usb-0000:01:00.0-1.1, high speed' Aug 28 12:38:56 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:56.007+01:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" selectedOutputId=5 Aug 28 12:38:56 music-server volumio[1249]: info: Received Get System Info Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 12:38:56 music-server volumio[1249]: info: Discovery: Getting this device information Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 12:38:56 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 12:38:56 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:56.033+01:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" currentVersion=4.119 latestVersion=4.119 Aug 28 12:38:56 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:56.033+01:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" status=UPDATE_STATUS_NONE progress=0 Aug 28 12:38:56 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:56.034+01:00 level=INFO msg="emitting user changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" userId= Aug 28 12:38:56 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:56.034+01:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" providers=3 Aug 28 12:38:56 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:56.035+01:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" plugins=68 Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 12:38:56 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:38:56 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:56.037+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" state=STATUS_PAUSED positionMs=245438 volume=31 Aug 28 12:38:56 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:56.038+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" id="mnt/USB/SSD_Drive/Music/Cindy Lee - Diamond Jubilee/15 - Til Polarity's End.flac" title="Til Polarity's End" Aug 28 12:38:56 music-server volumio[1249]: verbose: New Socket.io Connection to 192.168.86.79 from 192.168.86.92 UA: Mozilla/5.0 (Linux; Android 17; Pixel 9 Pro XL Build/CP2A.260805.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::volumioGetVisibleSources Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 12:38:56 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::volumioGetQueue Aug 28 12:38:56 music-server volumio[1249]: info: CoreStateMachine::getQueue Aug 28 12:38:56 music-server volumio[1249]: info: CorePlayQueue::getQueue Aug 28 12:38:56 music-server volumio[1249]: info: Listing playlists Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 28 12:38:56 music-server volumio[1249]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 28 12:38:56 music-server volumio[1249]: info: Received Get System Info Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 12:38:56 music-server volumio[1249]: info: Discovery: Getting this device information Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 12:38:56 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 12:38:56 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:38:56 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:56.257+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:60060 @ 0x1ccd3e0" latency=-566.855624ms timeout=10s endpoint=http://cddb.volumio.org duration=464.742435ms Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: metavolumio , setInfinityPlayback Aug 28 12:38:56 music-server volumio[1249]: info: METAVOLUMIO: Enabling Infinity Playback Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 12:38:56 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 28 12:38:56 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:56.335+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.92:60060 @ 0x1ccd3e0" latency=-566.855624ms timeout=10s endpoint=http://plugins.volumio.org duration=542.368578ms Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::volumioRandom Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::writePlayerControls Aug 28 12:38:56 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:38:56 music-server volumio[1249]: info: CoreStateMachine::setRepeat false single false Aug 28 12:38:56 music-server volumio[1249]: info: CoreStateMachine::pushState Aug 28 12:38:56 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::volumioPushState Aug 28 12:38:56 music-server volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Aug 28 12:38:56 music-server volumio[1249]: info: MRS: Pushing multiroomSync output Aug 28 12:38:56 music-server volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 12:38:56 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:38:56 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:56.362+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%1a @ 0x209a150" state=STATUS_PAUSED positionMs=245438 volume=31 Aug 28 12:38:58 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: metavolumio , setInfinityPlayback Aug 28 12:38:58 music-server volumio[1249]: info: METAVOLUMIO: Disabling Infinity Playback Aug 28 12:38:58 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 28 12:38:58 music-server volumio[1249]: info: CoreCommandRouter::volumioRandom Aug 28 12:38:58 music-server volumio[1249]: info: CoreCommandRouter::writePlayerControls Aug 28 12:38:58 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:38:58 music-server volumio[1249]: info: CoreStateMachine::setRepeat false single false Aug 28 12:38:58 music-server volumio[1249]: info: CoreStateMachine::pushState Aug 28 12:38:58 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:38:58 music-server volumio[1249]: info: CoreCommandRouter::volumioPushState Aug 28 12:38:58 music-server volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Aug 28 12:38:58 music-server volumio[1249]: info: MRS: Pushing multiroomSync output Aug 28 12:38:58 music-server volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 12:38:58 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:38:58 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:58.081+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%1a @ 0x209a150" state=STATUS_PAUSED positionMs=245438 volume=31 Aug 28 12:38:59 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:59.670+01:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=26 chunks=1 index=0 tries=11 Aug 28 12:38:59 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:59.671+01:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%1a @ 0x209a150" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Aug 28 12:38:59 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:59.671+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" state=STATUS_PAUSED positionMs=245438 volume=31 Aug 28 12:38:59 music-server volumio5-onboarding[1595]: time=2026-08-28T12:38:59.672+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" id="mnt/USB/SSD_Drive/Music/Cindy Lee - Diamond Jubilee/15 - Til Polarity's End.flac" title="Til Polarity's End" Aug 28 12:39:02 music-server volumio5-onboarding[1595]: time=2026-08-28T12:39:02.981+01:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=26 chunks=1 index=0 tries=11 Aug 28 12:39:02 music-server volumio5-onboarding[1595]: time=2026-08-28T12:39:02.981+01:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%1a @ 0x209a150" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Aug 28 12:39:02 music-server volumio5-onboarding[1595]: time=2026-08-28T12:39:02.981+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" state=STATUS_PAUSED positionMs=245438 volume=31 Aug 28 12:39:02 music-server volumio5-onboarding[1595]: time=2026-08-28T12:39:02.981+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" id="mnt/USB/SSD_Drive/Music/Cindy Lee - Diamond Jubilee/15 - Til Polarity's End.flac" title="Til Polarity's End" Aug 28 12:39:04 music-server volumio[1249]: info: CoreCommandRouter::volumioPlay Aug 28 12:39:04 music-server volumio[1249]: info: CoreStateMachine::play index undefined Aug 28 12:39:04 music-server volumio[1249]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 12:39:04 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:39:04 music-server volumio[1249]: info: CoreStateMachine::startPlaybackTimer Aug 28 12:39:04 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:39:04 music-server volumio[1249]: info: ControllerMpd::resume Aug 28 12:39:04 music-server volumio[1249]: verbose: ControllerMpd::sendMpdCommand play Aug 28 12:39:04 music-server volumio[1249]: info: Aug 28 12:39:04 music-server volumio[1249]: ---------------------------- MPD announces state update: player Aug 28 12:39:04 music-server volumio[1249]: info: sendMpdCommand play took 6 milliseconds Aug 28 12:39:04 music-server volumio[1249]: info: ControllerMpd::getState Aug 28 12:39:04 music-server volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Aug 28 12:39:04 music-server volumio[1249]: info: sendMpdCommand status took 1 milliseconds Aug 28 12:39:04 music-server volumio[1249]: verbose: ControllerMpd::parseState Aug 28 12:39:04 music-server volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 12:39:04 music-server volumio[1249]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 28 12:39:04 music-server volumio[1249]: verbose: ControllerMpd::parseTrackInfo Aug 28 12:39:04 music-server volumio[1249]: info: ControllerMpd::pushState Aug 28 12:39:04 music-server volumio[1249]: info: CoreCommandRouter::servicePushState Aug 28 12:39:04 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:39:04 music-server volumio[1249]: verbose: STATE SERVICE {"status":"play","position":0,"seek":244461,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"52 Kbps","isStreaming":false,"title":"Til Polarity's End","artist":"Cindy Lee","album":"Diamond Jubilee","uri":"USB/SSD_Drive/Music/Cindy Lee - Diamond Jubilee/15 - Til Polarity's End.flac","trackType":"flac"} Aug 28 12:39:04 music-server volumio[1249]: verbose: CURRENT POSITION 14 Aug 28 12:39:04 music-server volumio[1249]: info: CoreStateMachine::syncState stateService play Aug 28 12:39:04 music-server volumio[1249]: info: CoreStateMachine::syncState currentStatus pause Aug 28 12:39:04 music-server volumio[1249]: info: CoreStateMachine::pushState Aug 28 12:39:04 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:39:04 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 12:39:04 music-server volumio[1249]: info: CoreCommandRouter::volumioPushState Aug 28 12:39:04 music-server volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Aug 28 12:39:04 music-server volumio[1249]: info: MRS: Pushing multiroomSync output Aug 28 12:39:04 music-server volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 12:39:04 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:39:04 music-server volumio5-onboarding[1595]: time=2026-08-28T12:39:04.257+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" state=STATUS_PLAYING positionMs=245438 volume=31 Aug 28 12:39:04 music-server volumio5-onboarding[1595]: time=2026-08-28T12:39:04.257+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" id="mnt/USB/SSD_Drive/Music/Cindy Lee - Diamond Jubilee/15 - Til Polarity's End.flac" title="Til Polarity's End" Aug 28 12:39:04 music-server volumio[1249]: info: ------------------------------ 20ms Aug 28 12:39:04 music-server volumio[1249]: info: CoreCommandRouter::volumioPlay Aug 28 12:39:04 music-server volumio[1249]: info: CoreStateMachine::play index undefined Aug 28 12:39:04 music-server volumio[1249]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 12:39:04 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:39:04 music-server volumio[1249]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 28 12:39:05 music-server volumio[1249]: info: Aug 28 12:39:05 music-server volumio[1249]: ---------------------------- MPD announces system playlist update Aug 28 12:39:05 music-server volumio[1249]: info: Ignoring MPD Status Update Aug 28 12:39:05 music-server volumio[1249]: info: Aug 28 12:39:05 music-server volumio[1249]: ---------------------------- MPD announces state update: player Aug 28 12:39:05 music-server volumio[1249]: info: ControllerMpd::getState Aug 28 12:39:05 music-server volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Aug 28 12:39:05 music-server volumio[1249]: error: updateQueue error: null Aug 28 12:39:05 music-server volumio[1249]: info: ------------------------------ 1ms Aug 28 12:39:05 music-server volumio[1249]: info: sendMpdCommand status took 2 milliseconds Aug 28 12:39:05 music-server volumio[1249]: verbose: ControllerMpd::parseState Aug 28 12:39:05 music-server volumio[1249]: info: ControllerMpd::pushState Aug 28 12:39:05 music-server volumio[1249]: info: CoreCommandRouter::servicePushState Aug 28 12:39:05 music-server volumio[1249]: info: CoreStateMachine::pushState Aug 28 12:39:05 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:39:05 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 12:39:05 music-server volumio[1249]: info: CoreCommandRouter::volumioPushState Aug 28 12:39:05 music-server volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Aug 28 12:39:05 music-server volumio[1249]: info: MRS: Pushing multiroomSync output Aug 28 12:39:05 music-server volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 12:39:05 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:39:05 music-server volumio[1249]: info: CorePlayQueue::getTrack 14 Aug 28 12:39:05 music-server volumio[1249]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 28 12:39:05 music-server volumio[1249]: verbose: CURRENT POSITION 14 Aug 28 12:39:05 music-server volumio[1249]: info: CoreStateMachine::syncState stateService stop Aug 28 12:39:05 music-server volumio[1249]: info: CoreStateMachine::syncState currentStatus play Aug 28 12:39:05 music-server volumio[1249]: info: CoreStateMachine::play index undefined Aug 28 12:39:05 music-server volumio[1249]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 12:39:05 music-server volumio[1249]: info: CoreStateMachine::pushState Aug 28 12:39:05 music-server volumio[1249]: info: CorePlayQueue::getTrack 15 Aug 28 12:39:05 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 12:39:05 music-server volumio[1249]: info: CoreCommandRouter::volumioPushState Aug 28 12:39:05 music-server volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Aug 28 12:39:05 music-server volumio[1249]: info: MRS: Pushing multiroomSync output Aug 28 12:39:05 music-server volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 12:39:05 music-server volumio[1249]: info: CorePlayQueue::getTrack 15 Aug 28 12:39:05 music-server volumio5-onboarding[1595]: time=2026-08-28T12:39:05.674+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" state=STATUS_PLAYING positionMs=246689 volume=31 Aug 28 12:39:05 music-server volumio5-onboarding[1595]: time=2026-08-28T12:39:05.674+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" id="mnt/USB/SSD_Drive/Music/Cindy Lee - Diamond Jubilee/15 - Til Polarity's End.flac" title="Til Polarity's End" Aug 28 12:39:05 music-server volumio5-onboarding[1595]: time=2026-08-28T12:39:05.676+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" state=STATUS_STOPPED positionMs= volume=31 Aug 28 12:39:05 music-server volumio5-onboarding[1595]: time=2026-08-28T12:39:05.676+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" id="mnt/USB/SSD_Drive/Music/Cindy Lee - Diamond Jubilee/16 - Realistik Heaven.flac" title="Realistik Heaven" Aug 28 12:39:05 music-server volumio[1249]: info: CorePlayQueue::getTrack 15 Aug 28 12:39:05 music-server volumio[1249]: info: CoreStateMachine::startPlaybackTimer Aug 28 12:39:05 music-server volumio[1249]: info: CorePlayQueue::getTrack 15 Aug 28 12:39:05 music-server volumio[1249]: verbose: ControllerMpd::clearAddPlayTracks USB/SSD_Drive/Music/Cindy Lee - Diamond Jubilee/16 - Realistik Heaven.flac Aug 28 12:39:05 music-server volumio[1249]: verbose: ControllerMpd::sendMpdCommand stop Aug 28 12:39:05 music-server volumio[1249]: info: ------------------------------ 28ms Aug 28 12:39:05 music-server volumio[1249]: info: CoreStateMachine::pushState Aug 28 12:39:05 music-server volumio[1249]: info: CorePlayQueue::getTrack 15 Aug 28 12:39:05 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 12:39:05 music-server volumio[1249]: info: CoreCommandRouter::volumioPushState Aug 28 12:39:05 music-server volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Aug 28 12:39:05 music-server volumio[1249]: info: MRS: Pushing multiroomSync output Aug 28 12:39:05 music-server volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 12:39:05 music-server volumio[1249]: info: CorePlayQueue::getTrack 15 Aug 28 12:39:05 music-server volumio5-onboarding[1595]: time=2026-08-28T12:39:05.692+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" state=STATUS_STOPPED positionMs=0 volume=31 Aug 28 12:39:05 music-server volumio5-onboarding[1595]: time=2026-08-28T12:39:05.693+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" id="mnt/USB/SSD_Drive/Music/Cindy Lee - Diamond Jubilee/16 - Realistik Heaven.flac" title="Realistik Heaven" Aug 28 12:39:05 music-server volumio[1249]: info: sendMpdCommand stop took 14 milliseconds Aug 28 12:39:05 music-server volumio[1249]: verbose: ControllerMpd::sendMpdCommand clear Aug 28 12:39:05 music-server volumio[1249]: info: Aug 28 12:39:05 music-server volumio[1249]: ---------------------------- MPD announces system playlist update Aug 28 12:39:05 music-server volumio[1249]: info: Ignoring MPD Status Update Aug 28 12:39:05 music-server volumio[1249]: info: sendMpdCommand clear took 21 milliseconds Aug 28 12:39:05 music-server volumio[1249]: verbose: ControllerMpd::sendMpdCommand add "USB/SSD_Drive/Music/Cindy Lee - Diamond Jubilee/16 - Realistik Heaven.flac" Aug 28 12:39:05 music-server volumio[1249]: error: updateQueue error: null Aug 28 12:39:05 music-server volumio[1249]: info: Aug 28 12:39:05 music-server volumio[1249]: ---------------------------- MPD announces system playlist update Aug 28 12:39:05 music-server volumio[1249]: info: Ignoring MPD Status Update Aug 28 12:39:05 music-server volumio[1249]: info: ------------------------------ 15ms Aug 28 12:39:05 music-server volumio[1249]: info: sendMpdCommand add "USB/SSD_Drive/Music/Cindy Lee - Diamond Jubilee/16 - Realistik Heaven.flac" took 13 milliseconds Aug 28 12:39:05 music-server volumio[1249]: verbose: ControllerMpd::sendMpdCommand play Aug 28 12:39:05 music-server volumio[1249]: info: ------------------------------ 2ms Aug 28 12:39:05 music-server volumio[1249]: info: sendMpdCommand play took 2 milliseconds Aug 28 12:39:05 music-server volumio[1249]: info: touch_display: Setting screensaver timeout to 120 seconds. Aug 28 12:39:05 music-server volumio[1249]: info: Aug 28 12:39:05 music-server volumio[1249]: ---------------------------- MPD announces state update: player Aug 28 12:39:05 music-server volumio[1249]: info: ControllerMpd::getState Aug 28 12:39:05 music-server volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Aug 28 12:39:05 music-server volumio[1249]: info: Aug 28 12:39:05 music-server volumio[1249]: ---------------------------- MPD announces state update: player Aug 28 12:39:05 music-server volumio[1249]: info: ControllerMpd::getState Aug 28 12:39:05 music-server volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Aug 28 12:39:05 music-server volumio[1249]: info: sendMpdCommand status took 2 milliseconds Aug 28 12:39:05 music-server volumio[1249]: verbose: ControllerMpd::parseState Aug 28 12:39:05 music-server volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 12:39:05 music-server volumio[1249]: info: sendMpdCommand status took 3 milliseconds Aug 28 12:39:05 music-server volumio[1249]: verbose: ControllerMpd::parseState Aug 28 12:39:05 music-server volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 12:39:05 music-server volumio[1249]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 28 12:39:05 music-server volumio[1249]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 28 12:39:05 music-server volumio[1249]: verbose: ControllerMpd::parseTrackInfo Aug 28 12:39:05 music-server volumio[1249]: verbose: ControllerMpd::parseTrackInfo Aug 28 12:39:05 music-server volumio[1249]: info: ControllerMpd::pushState Aug 28 12:39:05 music-server volumio[1249]: info: CoreCommandRouter::servicePushState Aug 28 12:39:05 music-server volumio[1249]: info: CorePlayQueue::getTrack 15 Aug 28 12:39:05 music-server volumio[1249]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Realistik Heaven","artist":"Cindy Lee","album":"Diamond Jubilee","uri":"USB/SSD_Drive/Music/Cindy Lee - Diamond Jubilee/16 - Realistik Heaven.flac","trackType":"flac"} Aug 28 12:39:05 music-server volumio[1249]: verbose: CURRENT POSITION 15 Aug 28 12:39:05 music-server volumio[1249]: info: CoreStateMachine::syncState stateService play Aug 28 12:39:05 music-server volumio[1249]: info: CoreStateMachine::syncState currentStatus stop Aug 28 12:39:05 music-server volumio[1249]: info: ControllerMpd::pushState Aug 28 12:39:05 music-server volumio[1249]: info: CoreCommandRouter::servicePushState Aug 28 12:39:05 music-server volumio[1249]: info: CorePlayQueue::getTrack 15 Aug 28 12:39:05 music-server volumio[1249]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Realistik Heaven","artist":"Cindy Lee","album":"Diamond Jubilee","uri":"USB/SSD_Drive/Music/Cindy Lee - Diamond Jubilee/16 - Realistik Heaven.flac","trackType":"flac"} Aug 28 12:39:05 music-server volumio[1249]: verbose: CURRENT POSITION 15 Aug 28 12:39:05 music-server volumio[1249]: info: CoreStateMachine::syncState stateService play Aug 28 12:39:05 music-server volumio[1249]: info: CoreStateMachine::syncState currentStatus play Aug 28 12:39:05 music-server volumio[1249]: info: Received an update from plugin. extracting info from payload Aug 28 12:39:05 music-server volumio[1249]: info: CoreStateMachine::pushState Aug 28 12:39:05 music-server volumio[1249]: info: CorePlayQueue::getTrack 15 Aug 28 12:39:05 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 12:39:05 music-server volumio[1249]: info: CoreCommandRouter::volumioPushState Aug 28 12:39:05 music-server volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Aug 28 12:39:05 music-server volumio[1249]: info: MRS: Pushing multiroomSync output Aug 28 12:39:05 music-server volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 12:39:05 music-server volumio[1249]: info: CorePlayQueue::getTrack 15 Aug 28 12:39:05 music-server volumio[1249]: info: CoreStateMachine::pushState Aug 28 12:39:05 music-server volumio[1249]: info: CorePlayQueue::getTrack 15 Aug 28 12:39:05 music-server volumio[1249]: info: CoreCommandRouter::volumioPushState Aug 28 12:39:05 music-server volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Aug 28 12:39:05 music-server volumio[1249]: info: MRS: Pushing multiroomSync output Aug 28 12:39:05 music-server volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 12:39:05 music-server volumio[1249]: info: CorePlayQueue::getTrack 15 Aug 28 12:39:05 music-server volumio[1249]: info: ------------------------------ 36ms Aug 28 12:39:05 music-server volumio5-onboarding[1595]: time=2026-08-28T12:39:05.803+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" state=STATUS_PLAYING positionMs=0 volume=31 Aug 28 12:39:05 music-server volumio5-onboarding[1595]: time=2026-08-28T12:39:05.803+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" id="mnt/USB/SSD_Drive/Music/Cindy Lee - Diamond Jubilee/16 - Realistik Heaven.flac" title="Realistik Heaven" Aug 28 12:39:05 music-server volumio5-onboarding[1595]: time=2026-08-28T12:39:05.807+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" state=STATUS_PLAYING positionMs=0 volume=31 Aug 28 12:39:05 music-server volumio5-onboarding[1595]: time=2026-08-28T12:39:05.808+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.92:60060 @ 0x1ccd3e0" id="mnt/USB/SSD_Drive/Music/Cindy Lee - Diamond Jubilee/16 - Realistik Heaven.flac" title="Realistik Heaven" Aug 28 12:39:05 music-server volumio[1249]: info: ------------------------------ 58ms Aug 28 12:39:05 music-server volumio[1249]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 28 12:39:06 music-server kernel: hwmon hwmon1: Undervoltage detected! Aug 28 12:39:06 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Aug 28 12:39:06 music-server volumio[1249]: info: MRS: Starting browser stream Aug 28 12:39:06 music-server volumio[1249]: info: MRS: Setting this device as Streaming Server Aug 28 12:39:06 music-server volumio[1249]: info: Aug 28 12:39:06 music-server volumio[1249]: [1787917146348] ---------------------------- MRS: Setting Streaming Server Aug 28 12:39:06 music-server volumio[1249]: info: Enabled audio output: browserPlayback Aug 28 12:39:06 music-server volumio[1249]: info: MRS: enable multiroom server output Aug 28 12:39:06 music-server volumio[1249]: info: MRS: Set multiroom target PCM to volumioMultiRoom Aug 28 12:39:06 music-server volumio[1249]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Aug 28 12:39:06 music-server volumio[1249]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Aug 28 12:39:06 music-server volumio[1249]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Aug 28 12:39:06 music-server volumio[1249]: info: MRS: STARTING volumioStreaming Aug 28 12:39:06 music-server sudo[27559]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Aug 28 12:39:06 music-server sudo[27559]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 12:39:06 music-server sudo[27557]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 28 12:39:06 music-server sudo[27557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 12:39:06 music-server sudo[27557]: pam_unix(sudo:session): session closed for user root Aug 28 12:39:06 music-server systemd[1]: Started volumioStreaming.service - VolumioStreamingService. Aug 28 12:39:06 music-server sudo[27559]: pam_unix(sudo:session): session closed for user root Aug 28 12:39:06 music-server volumio[1249]: info: MRS: volumioStreaming STARTED Aug 28 12:39:06 music-server sudo[27563]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 28 12:39:06 music-server sudo[27563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 12:39:06 music-server sudo[27563]: pam_unix(sudo:session): session closed for user root Aug 28 12:39:07 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Aug 28 12:39:07 music-server volumio[1249]: info: MRS: Starting browser stream Aug 28 12:39:07 music-server volumio[1249]: info: MRS: Setting this device as single Aug 28 12:39:07 music-server volumio[1249]: info: Aug 28 12:39:07 music-server volumio[1249]: [1787917147650] ---------------------------- Setting Multiroom Single Aug 28 12:39:07 music-server volumio[1249]: info: MRS: disable multiroom output Aug 28 12:39:07 music-server volumio[1249]: info: MRS: Set multiroom target PCM to volumioDiscard Aug 28 12:39:07 music-server volumio[1249]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard Aug 28 12:39:08 music-server volumio[1249]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Aug 28 12:39:08 music-server volumio[1249]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback Aug 28 12:39:09 music-server volumio[1249]: info: MRS: STOPPING volumioStreaming Aug 28 12:39:09 music-server sudo[27583]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Aug 28 12:39:09 music-server sudo[27583]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 12:39:09 music-server sudo[27585]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 28 12:39:09 music-server sudo[27585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 12:39:09 music-server systemd[1]: Stopping volumioStreaming.service - VolumioStreamingService... Aug 28 12:39:09 music-server sudo[27585]: pam_unix(sudo:session): session closed for user root Aug 28 12:39:09 music-server systemd[1]: volumioStreaming.service: Killing process 27565 (ffmpeg) with signal SIGKILL. Aug 28 12:39:09 music-server volumio[1249]: info: MRS: Removed streaming files Aug 28 12:39:09 music-server systemd[1]: volumioStreaming.service: Deactivated successfully. Aug 28 12:39:09 music-server systemd[1]: Stopped volumioStreaming.service - VolumioStreamingService. Aug 28 12:39:09 music-server systemd[1]: volumioStreaming.service: Consumed 1.734s CPU time. Aug 28 12:39:09 music-server sudo[27583]: pam_unix(sudo:session): session closed for user root Aug 28 12:39:09 music-server volumio[1249]: info: MRS: volumioStreaming STOPPED Aug 28 12:39:14 music-server volumio[1249]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Aug 28 12:39:14 music-server volumio[1249]: info: MRS: Starting cast device: Nest-Audio-0a523956fd898ab7251a89cf1d0a6003 Aug 28 12:39:14 music-server volumio[1249]: info: Enabled audio output: Nest-Audio-0a523956fd898ab7251a89cf1d0a6003 Aug 28 12:39:14 music-server volumio[1249]: info: MRS: Setting this device as Streaming Server Aug 28 12:39:14 music-server volumio[1249]: info: Aug 28 12:39:14 music-server volumio[1249]: [1787917154956] ---------------------------- MRS: Setting Streaming Server Aug 28 12:39:14 music-server volumio[1249]: info: MRS: enable multiroom server output Aug 28 12:39:14 music-server volumio[1249]: info: MRS: Set multiroom target PCM to volumioMultiRoom Aug 28 12:39:14 music-server volumio[1249]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Aug 28 12:39:14 music-server volumio[1249]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Aug 28 12:39:14 music-server volumio[1249]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Aug 28 12:39:14 music-server volumio[1249]: info: MRS: STARTING volumioStreaming Aug 28 12:39:15 music-server sudo[27592]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 28 12:39:15 music-server sudo[27592]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 12:39:15 music-server sudo[27592]: pam_unix(sudo:session): session closed for user root Aug 28 12:39:15 music-server sudo[27594]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Aug 28 12:39:15 music-server sudo[27594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 12:39:15 music-server systemd[1]: Started volumioStreaming.service - VolumioStreamingService. Aug 28 12:39:15 music-server sudo[27594]: pam_unix(sudo:session): session closed for user root Aug 28 12:39:15 music-server volumio[1249]: info: MRS: volumioStreaming STARTED Aug 28 12:39:15 music-server sudo[27598]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 28 12:39:15 music-server sudo[27598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 12:39:15 music-server sudo[27598]: pam_unix(sudo:session): session closed for user root Aug 28 12:39:15 music-server volumio[1249]: info: MRS: Connected to cast device: Nest-Audio-0a523956fd898ab7251a89cf1d0a6003 Aug 28 12:39:15 music-server volumio[1249]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 12:39:15 music-server volumio[1249]: TypeError: Cannot read properties of undefined (reading 'on') Aug 28 12:39:15 music-server volumio[1249]: at /myvolumio/plugins/audio_interface/multiroom/multiroom_real:1:29945 Aug 28 12:39:15 music-server volumio[1249]: at /myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/senders/platform.js:95:20 Aug 28 12:39:15 music-server volumio[1249]: at /myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/receiver.js:51:14 Aug 28 12:39:15 music-server volumio[1249]: at fn.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/request-response.js:27:7) Aug 28 12:39:15 music-server volumio[1249]: at fn.emit (node:events:526:35) Aug 28 12:39:15 music-server volumio[1249]: at Channel.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/controller.js:16:10) Aug 28 12:39:15 music-server volumio[1249]: at Channel.emit (node:events:514:28) Aug 28 12:39:15 music-server volumio[1249]: at Client.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/channel.js:23:10) Aug 28 12:39:15 music-server volumio[1249]: at Client.emit (node:events:526:35) Aug 28 12:39:15 music-server volumio[1249]: at PacketStreamWrapper.onpacket (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/client.js:81:10) Aug 28 12:39:15 music-server volumio[1249]: at PacketStreamWrapper.emit (node:events:514:28) Aug 28 12:39:15 music-server volumio[1249]: at TLSSocket. (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/packet-stream-wrapper.js:28:16) Aug 28 12:39:15 music-server volumio[1249]: at TLSSocket.emit (node:events:514:28) Aug 28 12:39:15 music-server volumio[1249]: at emitReadable_ (node:internal/streams/readable:609:12) Aug 28 12:39:15 music-server volumio[1249]: at process.processTicksAndRejections (node:internal/process/task_queues:81:21) Aug 28 12:39:15 music-server volumio[1249]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 12:39:16 music-server kernel: hwmon hwmon1: Voltage normalised Aug 28 12:39:16 music-server sudo[27616]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-28 12:38' Aug 28 12:39:16 music-server sudo[27616]: 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"