Aug 31 07:42:19 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:19.736-07:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.110.200:53515 error="websocket: close 1006 (abnormal closure): unexpected EOF" Aug 31 07:42:19 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:19.736-07:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.110.200:53515 Aug 31 07:42:19 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:19.736-07:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.110.200:53515 Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.160-07:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.110.200:53944 Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.181-07:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:53944 @ 0xc0004efa40" latency=-2.888777ms timeout=10s Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.187-07:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.110.200:53944 @ 0xc0004efa40" latency=-2.854372ms timeout=20s Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.187-07:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.110.200:53944 @ 0xc0004efa40" Aug 31 07:42:49 volumio volumio[1186]: info: Received Get System Info Aug 31 07:42:49 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 07:42:49 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 07:42:49 volumio volumio[1186]: info: Discovery: Getting this device information Aug 31 07:42:49 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState Aug 31 07:42:49 volumio volumio[1186]: info: CorePlayQueue::getTrack 9 Aug 31 07:42:49 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.190-07:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.110.200:53944 @ 0xc0004efa40" name=Volumio Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.191-07:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.110.200:53944 @ 0xc0004efa40" language=vi Aug 31 07:42:49 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.192-07:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.110.200:53944 @ 0xc0004efa40" timezone=Etc/GMT+7 Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.193-07:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.110.200:53944 @ 0xc0004efa40" available=true connected=false macAddress= ip4Address= ip6Address= Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.194-07:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.110.200:53944 @ 0xc0004efa40" available=true connected=true macAddress=e0:9d:31:bf:fe:14 ip4Address=192.168.110.166/24 ip6Address= ssid=Vietten02 Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.194-07:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.110.200:53944 @ 0xc0004efa40" setupComplete=true Aug 31 07:42:49 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 31 07:42:49 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 31 07:42:49 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 07:42:49 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.232-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:53944 @ 0xc0004efa40" latency=-2.888777ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=51.029678ms Aug 31 07:42:49 volumio volumio[1186]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 0 Aug 31 07:42:49 volumio volumio[1186]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 31 07:42:49 volumio volumio[1186]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 0\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 31 07:42:49 volumio volumio[1186]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 0 Aug 31 07:42:49 volumio volumio[1186]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 31 07:42:49 volumio volumio[1186]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 0\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.236-07:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.110.200:53944 @ 0xc0004efa40" selectedOutputId=0,0 Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.237-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:53944 @ 0xc0004efa40" latency=-2.888777ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=56.217048ms Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.239-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:53944 @ 0xc0004efa40" latency=-2.888777ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=58.295981ms Aug 31 07:42:49 volumio volumio[1186]: info: Received Get System Info Aug 31 07:42:49 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 07:42:49 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 07:42:49 volumio volumio[1186]: info: Discovery: Getting this device information Aug 31 07:42:49 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState Aug 31 07:42:49 volumio volumio[1186]: info: CorePlayQueue::getTrack 9 Aug 31 07:42:49 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.271-07:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.110.200:53944 @ 0xc0004efa40" currentVersion=4.119 latestVersion=4.119 Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.271-07:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.110.200:53944 @ 0xc0004efa40" status=UPDATE_STATUS_NONE progress=0 Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.271-07:00 level=INFO msg="emitting user changed event" component=server peer="192.168.110.200:53944 @ 0xc0004efa40" userId=lVXNrmlLRVW6yc3s71Ad3d7EBx82 Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.271-07:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.110.200:53944 @ 0xc0004efa40" providers=9 Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.272-07:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.110.200:53944 @ 0xc0004efa40" plugins=53 Aug 31 07:42:49 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState Aug 31 07:42:49 volumio volumio[1186]: info: CorePlayQueue::getTrack 9 Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.273-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:53944 @ 0xc0004efa40" latency=-2.888777ms timeout=10s endpoint=https://google.com duration=92.182264ms Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.274-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.110.200:53944 @ 0xc0004efa40" state=STATUS_PLAYING positionMs=111459 volume=100 Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.275-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.110.200:53944 @ 0xc0004efa40" id="mnt/USB/New_Volume/Hoa Tau trung hoa/10-Forgotten Old Dreams.wav" title="10-Forgotten Old Dreams.wav" Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.313-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:53944 @ 0xc0004efa40" latency=-2.888777ms timeout=10s endpoint=https://www.googleapis.com duration=131.512213ms Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.392-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:53944 @ 0xc0004efa40" latency=-2.888777ms timeout=10s endpoint=http://pushupdates.volumio.org duration=210.403069ms Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.399-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:53944 @ 0xc0004efa40" latency=-2.888777ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=217.637824ms Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.408-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:53944 @ 0xc0004efa40" latency=-2.888777ms timeout=10s endpoint=https://securetoken.googleapis.com duration=227.26811ms Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.454-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:53944 @ 0xc0004efa40" latency=-2.888777ms timeout=10s endpoint=https://functions.volumio.cloud duration=273.321577ms Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.458-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:53944 @ 0xc0004efa40" latency=-2.888777ms timeout=10s endpoint=https://functions.volumio.cloud duration=276.557819ms Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.472-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:53944 @ 0xc0004efa40" latency=-2.888777ms timeout=10s endpoint=https://database.volumio.cloud duration=290.151412ms Aug 31 07:42:49 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:49.862-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:53944 @ 0xc0004efa40" latency=-2.888777ms timeout=10s endpoint=http://cddb.volumio.org duration=680.402509ms Aug 31 07:42:50 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:50.460-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:53944 @ 0xc0004efa40" latency=-2.888777ms timeout=10s endpoint=http://plugins.volumio.org duration=1.278656214s Aug 31 07:42:50 volumio volumio[1186]: verbose: New Socket.io Connection to 192.168.110.166 from 192.168.110.200 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Aug 31 07:42:50 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 07:42:50 volumio volumio[1186]: info: CoreCommandRouter::volumioGetVisibleSources Aug 31 07:42:50 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 07:42:50 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState Aug 31 07:42:50 volumio volumio[1186]: info: CorePlayQueue::getTrack 9 Aug 31 07:42:50 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 31 07:42:50 volumio volumio[1186]: info: CoreCommandRouter::volumioGetQueue Aug 31 07:42:50 volumio volumio[1186]: info: CoreStateMachine::getQueue Aug 31 07:42:50 volumio volumio[1186]: info: CorePlayQueue::getQueue Aug 31 07:42:50 volumio volumio[1186]: info: Listing playlists Aug 31 07:42:50 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 31 07:42:50 volumio volumio[1186]: info: Received Get System Info Aug 31 07:42:50 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 07:42:50 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 07:42:50 volumio volumio[1186]: info: Discovery: Getting this device information Aug 31 07:42:50 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState Aug 31 07:42:50 volumio volumio[1186]: info: CorePlayQueue::getTrack 9 Aug 31 07:42:50 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 07:42:50 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState Aug 31 07:42:50 volumio volumio[1186]: info: CorePlayQueue::getTrack 9 Aug 31 07:42:50 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 31 07:42:52 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 07:42:52 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 07:42:52 volumio volumio[1186]: info: Discovery: Getting this device information Aug 31 07:42:52 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState Aug 31 07:42:52 volumio volumio[1186]: info: CorePlayQueue::getTrack 9 Aug 31 07:42:52 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 07:42:52 volumio volumio[1186]: verbose: New Socket.io Connection to 192.168.110.166:3000 from 192.168.110.200 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Aug 31 07:42:52 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 31 07:42:52 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.643-07:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.110.200:53944 Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.644-07:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.110.200:53944 Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.667-07:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.110.200:54056 Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.694-07:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.110.200:54056 @ 0xc00073e300" latency=-2.637372ms timeout=20s Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.694-07:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.110.200:54056 @ 0xc00073e300" Aug 31 07:42:54 volumio volumio[1186]: info: Received Get System Info Aug 31 07:42:54 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 07:42:54 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 07:42:54 volumio volumio[1186]: info: Discovery: Getting this device information Aug 31 07:42:54 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState Aug 31 07:42:54 volumio volumio[1186]: info: CorePlayQueue::getTrack 9 Aug 31 07:42:54 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.697-07:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.110.200:54056 @ 0xc00073e300" name=Volumio Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.698-07:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.110.200:54056 @ 0xc00073e300" language=vi Aug 31 07:42:54 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.701-07:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.110.200:54056 @ 0xc00073e300" timezone=Etc/GMT+7 Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.701-07:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:54056 @ 0xc00073e300" latency=-1.962395ms timeout=10s Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.702-07:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.110.200:54056 @ 0xc00073e300" available=true connected=false macAddress= ip4Address= ip6Address= Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.703-07:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.110.200:54056 @ 0xc00073e300" available=true connected=true macAddress=e0:9d:31:bf:fe:14 ip4Address=192.168.110.166/24 ip6Address= ssid=Vietten02 Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.703-07:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.110.200:54056 @ 0xc00073e300" setupComplete=true Aug 31 07:42:54 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 31 07:42:54 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 31 07:42:54 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 07:42:54 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 07:42:54 volumio volumio[1186]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 0 Aug 31 07:42:54 volumio volumio[1186]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 31 07:42:54 volumio volumio[1186]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 0\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 31 07:42:54 volumio volumio[1186]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 0 Aug 31 07:42:54 volumio volumio[1186]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 31 07:42:54 volumio volumio[1186]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 0\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.752-07:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.110.200:54056 @ 0xc00073e300" selectedOutputId=0,0 Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.752-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:54056 @ 0xc00073e300" latency=-1.962395ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=51.654074ms Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.762-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:54056 @ 0xc00073e300" latency=-1.962395ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=61.166897ms Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.762-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:54056 @ 0xc00073e300" latency=-1.962395ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=61.398309ms Aug 31 07:42:54 volumio volumio[1186]: info: Received Get System Info Aug 31 07:42:54 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 07:42:54 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 07:42:54 volumio volumio[1186]: info: Discovery: Getting this device information Aug 31 07:42:54 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState Aug 31 07:42:54 volumio volumio[1186]: info: CorePlayQueue::getTrack 9 Aug 31 07:42:54 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.796-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:54056 @ 0xc00073e300" latency=-1.962395ms timeout=10s endpoint=https://google.com duration=95.294515ms Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.797-07:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.110.200:54056 @ 0xc00073e300" currentVersion=4.119 latestVersion=4.119 Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.797-07:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.110.200:54056 @ 0xc00073e300" status=UPDATE_STATUS_NONE progress=0 Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.797-07:00 level=INFO msg="emitting user changed event" component=server peer="192.168.110.200:54056 @ 0xc00073e300" userId=lVXNrmlLRVW6yc3s71Ad3d7EBx82 Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.798-07:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.110.200:54056 @ 0xc00073e300" providers=9 Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.798-07:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.110.200:54056 @ 0xc00073e300" plugins=53 Aug 31 07:42:54 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState Aug 31 07:42:54 volumio volumio[1186]: info: CorePlayQueue::getTrack 9 Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.800-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.110.200:54056 @ 0xc00073e300" state=STATUS_PLAYING positionMs=116975 volume=100 Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.800-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.110.200:54056 @ 0xc00073e300" id="mnt/USB/New_Volume/Hoa Tau trung hoa/10-Forgotten Old Dreams.wav" title="10-Forgotten Old Dreams.wav" Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.832-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:54056 @ 0xc00073e300" latency=-1.962395ms timeout=10s endpoint=https://www.googleapis.com duration=130.392296ms Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.906-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:54056 @ 0xc00073e300" latency=-1.962395ms timeout=10s endpoint=http://pushupdates.volumio.org duration=205.056489ms Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.912-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:54056 @ 0xc00073e300" latency=-1.962395ms timeout=10s endpoint=https://securetoken.googleapis.com duration=210.193712ms Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.935-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:54056 @ 0xc00073e300" latency=-1.962395ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=233.958555ms Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.981-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:54056 @ 0xc00073e300" latency=-1.962395ms timeout=10s endpoint=https://database.volumio.cloud duration=279.524466ms Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.981-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:54056 @ 0xc00073e300" latency=-1.962395ms timeout=10s endpoint=https://functions.volumio.cloud duration=279.659581ms Aug 31 07:42:54 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:54.985-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:54056 @ 0xc00073e300" latency=-1.962395ms timeout=10s endpoint=https://functions.volumio.cloud duration=283.440063ms Aug 31 07:42:55 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:55.147-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:54056 @ 0xc00073e300" latency=-1.962395ms timeout=10s endpoint=http://cddb.volumio.org duration=445.789654ms Aug 31 07:42:55 volumio volumio5-onboarding[1881]: time=2026-08-31T07:42:55.421-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.110.200:54056 @ 0xc00073e300" latency=-1.962395ms timeout=10s endpoint=http://plugins.volumio.org duration=719.320333ms Aug 31 07:43:20 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 31 07:43:20 volumio volumio[1186]: info: CURURI: music-library/USB/New_Volume/Nhu Quynh Aug 31 07:43:20 volumio volumio[1186]: info: Preload queue cleared Aug 31 07:43:20 volumio volumio[1186]: info: Preloading song: music-library/USB/New_Volume/Nhu Quynh/01 - Nhu Quynh & Manh Quynh - Anh Hay Ve Di.flac Aug 31 07:43:20 volumio volumio[1186]: info: Preloading song: music-library/USB/New_Volume/Nhu Quynh/02 - Nhu Quynh & Truong Vu - Khong Gio Roi.flac Aug 31 07:43:20 volumio volumio[1186]: info: Preloading song: music-library/USB/New_Volume/Nhu Quynh/03 - Nhu Quynh & Dang Truong Phat - Con Sao Sang Song.flac Aug 31 07:43:20 volumio volumio[1186]: info: Preloading song: music-library/USB/New_Volume/Nhu Quynh/04 - Nhu Quynh & Manh Dinh - Tham Kin.flac Aug 31 07:43:20 volumio volumio[1186]: info: Preloading song: music-library/USB/New_Volume/Nhu Quynh/05 - Nhu Quynh & The Son - Lua Mua Duyen Tham.flac Aug 31 07:43:20 volumio volumio[1186]: info: Preloading song: music-library/USB/New_Volume/Nhu Quynh/06 - Nhu Quynh & Manh Quynh - Muoi Hai Ben Nuoc.flac Aug 31 07:43:20 volumio volumio[1186]: info: Preloading song: music-library/USB/New_Volume/Nhu Quynh/07 - Nhu Quynh - Hoa Tim Bang Lang.flac Aug 31 07:43:20 volumio volumio[1186]: info: Preloading song: music-library/USB/New_Volume/Nhu Quynh/08 - Nhu Quynh & Dang Truong Phat - Dieu Ru Ca Tinh Yeu.flac Aug 31 07:43:20 volumio volumio[1186]: info: Preloading song: music-library/USB/New_Volume/Nhu Quynh/09 - Nhu Quynh - Du Cho Hoa Tan Ua.flac Aug 31 07:43:20 volumio volumio[1186]: info: Preloading song: music-library/USB/New_Volume/Nhu Quynh/10 - Nhu Quynh & Manh Quynh - Ngay Vui Hai Dua.flac Aug 31 07:43:20 volumio volumio[1186]: info: Exploding uri music-library/USB/New_Volume/Nhu Quynh/01 - Nhu Quynh & Manh Quynh - Anh Hay Ve Di.flac in service mpd Aug 31 07:43:20 volumio volumio[1186]: info: ALBUMART /albumart?cacheid=895&web=Nhu%20Quynh%20%26%20Manh%20Quynh/Khong%20Gio%20Roi/extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FNhu%20Quynh%2F01%20-%20Nhu%20Quynh%20%26%20Manh%20Quynh%20-%20Anh%20Hay%20Ve%20Di.flac&metadata=false Aug 31 07:43:20 volumio volumio[1186]: info: URI /mnt/USB/New_Volume/Nhu Quynh/01 - Nhu Quynh & Manh Quynh - Anh Hay Ve Di.flac Aug 31 07:43:20 volumio volumio[1186]: info: Exploding uri music-library/USB/New_Volume/Nhu Quynh/02 - Nhu Quynh & Truong Vu - Khong Gio Roi.flac in service mpd Aug 31 07:43:20 volumio volumio[1186]: info: ALBUMART /albumart?cacheid=895&web=Nhu%20Quynh%20%26%20Truong%20Vu/Khong%20Gio%20Roi/extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FNhu%20Quynh%2F02%20-%20Nhu%20Quynh%20%26%20Truong%20Vu%20-%20Khong%20Gio%20Roi.flac&metadata=false Aug 31 07:43:20 volumio volumio[1186]: info: URI /mnt/USB/New_Volume/Nhu Quynh/02 - Nhu Quynh & Truong Vu - Khong Gio Roi.flac Aug 31 07:43:20 volumio volumio[1186]: info: Exploding uri music-library/USB/New_Volume/Nhu Quynh/03 - Nhu Quynh & Dang Truong Phat - Con Sao Sang Song.flac in service mpd Aug 31 07:43:20 volumio volumio[1186]: info: ALBUMART /albumart?cacheid=895&web=Nhu%20Quynh%20%26%20Dang%20Truong%20Phat/Khong%20Gio%20Roi/extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FNhu%20Quynh%2F03%20-%20Nhu%20Quynh%20%26%20Dang%20Truong%20Phat%20-%20Con%20Sao%20Sang%20Song.flac&metadata=false Aug 31 07:43:20 volumio volumio[1186]: info: URI /mnt/USB/New_Volume/Nhu Quynh/03 - Nhu Quynh & Dang Truong Phat - Con Sao Sang Song.flac Aug 31 07:43:20 volumio volumio[1186]: info: Exploding uri music-library/USB/New_Volume/Nhu Quynh/04 - Nhu Quynh & Manh Dinh - Tham Kin.flac in service mpd Aug 31 07:43:20 volumio volumio[1186]: info: ALBUMART /albumart?cacheid=895&web=Nhu%20Quynh%20%26%20Manh%20Dinh/Khong%20Gio%20Roi/extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FNhu%20Quynh%2F04%20-%20Nhu%20Quynh%20%26%20Manh%20Dinh%20-%20Tham%20Kin.flac&metadata=false Aug 31 07:43:20 volumio volumio[1186]: info: URI /mnt/USB/New_Volume/Nhu Quynh/04 - Nhu Quynh & Manh Dinh - Tham Kin.flac Aug 31 07:43:20 volumio volumio[1186]: info: Exploding uri music-library/USB/New_Volume/Nhu Quynh/05 - Nhu Quynh & The Son - Lua Mua Duyen Tham.flac in service mpd Aug 31 07:43:20 volumio volumio[1186]: info: ALBUMART /albumart?cacheid=895&web=Nhu%20Quynh%20%26%20The%20Son/Khong%20Gio%20Roi/extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FNhu%20Quynh%2F05%20-%20Nhu%20Quynh%20%26%20The%20Son%20-%20Lua%20Mua%20Duyen%20Tham.flac&metadata=false Aug 31 07:43:20 volumio volumio[1186]: info: URI /mnt/USB/New_Volume/Nhu Quynh/05 - Nhu Quynh & The Son - Lua Mua Duyen Tham.flac Aug 31 07:43:20 volumio volumio[1186]: info: Exploding uri music-library/USB/New_Volume/Nhu Quynh/06 - Nhu Quynh & Manh Quynh - Muoi Hai Ben Nuoc.flac in service mpd Aug 31 07:43:20 volumio volumio[1186]: info: ALBUMART /albumart?cacheid=895&web=Nhu%20Quynh%20%26%20Manh%20Quynh/Khong%20Gio%20Roi/extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FNhu%20Quynh%2F06%20-%20Nhu%20Quynh%20%26%20Manh%20Quynh%20-%20Muoi%20Hai%20Ben%20Nuoc.flac&metadata=false Aug 31 07:43:20 volumio volumio[1186]: info: URI /mnt/USB/New_Volume/Nhu Quynh/06 - Nhu Quynh & Manh Quynh - Muoi Hai Ben Nuoc.flac Aug 31 07:43:21 volumio volumio[1186]: info: Exploding uri music-library/USB/New_Volume/Nhu Quynh/07 - Nhu Quynh - Hoa Tim Bang Lang.flac in service mpd Aug 31 07:43:21 volumio volumio[1186]: info: ALBUMART /albumart?cacheid=895&web=Nhu%20Quynh/Khong%20Gio%20Roi/extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FNhu%20Quynh%2F07%20-%20Nhu%20Quynh%20-%20Hoa%20Tim%20Bang%20Lang.flac&metadata=false Aug 31 07:43:21 volumio volumio[1186]: info: URI /mnt/USB/New_Volume/Nhu Quynh/07 - Nhu Quynh - Hoa Tim Bang Lang.flac Aug 31 07:43:21 volumio volumio[1186]: info: Exploding uri music-library/USB/New_Volume/Nhu Quynh/08 - Nhu Quynh & Dang Truong Phat - Dieu Ru Ca Tinh Yeu.flac in service mpd Aug 31 07:43:21 volumio volumio[1186]: info: ALBUMART /albumart?cacheid=895&web=Nhu%20Quynh%20%26%20Dang%20Truong%20Phat/Khong%20Gio%20Roi/extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FNhu%20Quynh%2F08%20-%20Nhu%20Quynh%20%26%20Dang%20Truong%20Phat%20-%20Dieu%20Ru%20Ca%20Tinh%20Yeu.flac&metadata=false Aug 31 07:43:21 volumio volumio[1186]: info: URI /mnt/USB/New_Volume/Nhu Quynh/08 - Nhu Quynh & Dang Truong Phat - Dieu Ru Ca Tinh Yeu.flac Aug 31 07:43:21 volumio volumio[1186]: info: Exploding uri music-library/USB/New_Volume/Nhu Quynh/09 - Nhu Quynh - Du Cho Hoa Tan Ua.flac in service mpd Aug 31 07:43:21 volumio volumio[1186]: info: ALBUMART /albumart?cacheid=895&web=Nhu%20Quynh/Khong%20Gio%20Roi/extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FNhu%20Quynh%2F09%20-%20Nhu%20Quynh%20-%20Du%20Cho%20Hoa%20Tan%20Ua.flac&metadata=false Aug 31 07:43:21 volumio volumio[1186]: info: URI /mnt/USB/New_Volume/Nhu Quynh/09 - Nhu Quynh - Du Cho Hoa Tan Ua.flac Aug 31 07:43:21 volumio volumio[1186]: info: Exploding uri music-library/USB/New_Volume/Nhu Quynh/10 - Nhu Quynh & Manh Quynh - Ngay Vui Hai Dua.flac in service mpd Aug 31 07:43:21 volumio volumio[1186]: info: ALBUMART /albumart?cacheid=895&web=Nhu%20Quynh%20%26%20Manh%20Quynh/Khong%20Gio%20Roi/extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FNhu%20Quynh%2F10%20-%20Nhu%20Quynh%20%26%20Manh%20Quynh%20-%20Ngay%20Vui%20Hai%20Dua.flac&metadata=false Aug 31 07:43:21 volumio volumio[1186]: info: URI /mnt/USB/New_Volume/Nhu Quynh/10 - Nhu Quynh & Manh Quynh - Ngay Vui Hai Dua.flac Aug 31 07:43:21 volumio volumio[1186]: info: Preload queue cleared Aug 31 07:43:21 volumio volumio[1186]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 31 07:43:21 volumio volumio[1186]: info: CoreStateMachine::ClearQueue Aug 31 07:43:21 volumio volumio[1186]: info: CoreStateMachine::stop Aug 31 07:43:21 volumio volumio[1186]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 07:43:21 volumio volumio[1186]: info: CoreStateMachine::stPlaybackTimer Aug 31 07:43:21 volumio volumio[1186]: info: CoreStateMachine::updateTrackBlock Aug 31 07:43:21 volumio volumio[1186]: info: CorePlayQueue::getTrackBlock Aug 31 07:43:21 volumio volumio[1186]: info: CoreStateMachine::pushState Aug 31 07:43:21 volumio volumio[1186]: info: CorePlayQueue::getTrack 9 Aug 31 07:43:21 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 07:43:21 volumio volumio[1186]: info: CoreCommandRouter::volumioPushState Aug 31 07:43:21 volumio volumio[1186]: info: CoreStateMachine::serviceStop Aug 31 07:43:21 volumio volumio[1186]: info: CorePlayQueue::getTrack 9 Aug 31 07:43:21 volumio volumio[1186]: info: CoreCommandRouter::serviceStop Aug 31 07:43:21 volumio volumio[1186]: info: ControllerMpd::stop Aug 31 07:43:21 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 07:43:21 volumio volumio[1186]: info: CorePlayQueue::clearPlayQueue Aug 31 07:43:21 volumio volumio[1186]: info: CorePlayQueue::saveQueue Aug 31 07:43:21 volumio volumio[1186]: info: CoreCommandRouter::volumioPushQueue Aug 31 07:43:21 volumio volumio[1186]: info: CoreStateMachine::addQueueItems Aug 31 07:43:21 volumio volumio[1186]: info: CorePlayQueue::addQueueItems Aug 31 07:43:21 volumio volumio[1186]: info: Preload queue cleared Aug 31 07:43:21 volumio volumio[1186]: info: Adding Item to queue: music-library/USB/New_Volume/Nhu Quynh/01 - Nhu Quynh & Manh Quynh - Anh Hay Ve Di.flac Aug 31 07:43:21 volumio volumio[1186]: info: Using cached record of: music-library/USB/New_Volume/Nhu Quynh/01 - Nhu Quynh & Manh Quynh - Anh Hay Ve Di.flac Aug 31 07:43:21 volumio volumio5-onboarding[1881]: time=2026-08-31T07:43:21.794-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.110.200:54056 @ 0xc00073e300" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 07:43:21 volumio volumio5-onboarding[1881]: time=2026-08-31T07:43:21.794-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.110.200:54056 @ 0xc00073e300" id="mnt/USB/New_Volume/Hoa Tau trung hoa/10-Forgotten Old Dreams.wav" title="10-Forgotten Old Dreams.wav" Aug 31 07:43:21 volumio volumio[1186]: info: CoreCommandRouter::volumioPushQueue Aug 31 07:43:21 volumio volumio[1186]: info: CorePlayQueue::saveQueue Aug 31 07:43:21 volumio volumio[1186]: info: CoreStateMachine::updateTrackBlock Aug 31 07:43:21 volumio volumio[1186]: info: CorePlayQueue::getTrackBlock Aug 31 07:43:21 volumio volumio[1186]: info: CoreCommandRouter::volumioPlay Aug 31 07:43:21 volumio volumio[1186]: info: CoreStateMachine::play index 0 Aug 31 07:43:21 volumio volumio[1186]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 07:43:21 volumio volumio[1186]: info: CoreStateMachine::addQueueItems Aug 31 07:43:21 volumio volumio[1186]: info: CorePlayQueue::addQueueItems Aug 31 07:43:21 volumio volumio[1186]: info: Preload queue cleared Aug 31 07:43:21 volumio volumio[1186]: info: Adding Item to queue: music-library/USB/New_Volume/Nhu Quynh/02 - Nhu Quynh & Truong Vu - Khong Gio Roi.flac Aug 31 07:43:21 volumio volumio[1186]: info: Using cached record of: music-library/USB/New_Volume/Nhu Quynh/02 - Nhu Quynh & Truong Vu - Khong Gio Roi.flac Aug 31 07:43:21 volumio volumio[1186]: info: Adding Item to queue: music-library/USB/New_Volume/Nhu Quynh/03 - Nhu Quynh & Dang Truong Phat - Con Sao Sang Song.flac Aug 31 07:43:21 volumio volumio[1186]: info: Using cached record of: music-library/USB/New_Volume/Nhu Quynh/03 - Nhu Quynh & Dang Truong Phat - Con Sao Sang Song.flac Aug 31 07:43:21 volumio volumio[1186]: info: Adding Item to queue: music-library/USB/New_Volume/Nhu Quynh/04 - Nhu Quynh & Manh Dinh - Tham Kin.flac Aug 31 07:43:21 volumio volumio[1186]: info: Using cached record of: music-library/USB/New_Volume/Nhu Quynh/04 - Nhu Quynh & Manh Dinh - Tham Kin.flac Aug 31 07:43:21 volumio volumio[1186]: info: Adding Item to queue: music-library/USB/New_Volume/Nhu Quynh/05 - Nhu Quynh & The Son - Lua Mua Duyen Tham.flac Aug 31 07:43:21 volumio volumio[1186]: info: Using cached record of: music-library/USB/New_Volume/Nhu Quynh/05 - Nhu Quynh & The Son - Lua Mua Duyen Tham.flac Aug 31 07:43:21 volumio volumio[1186]: info: Adding Item to queue: music-library/USB/New_Volume/Nhu Quynh/06 - Nhu Quynh & Manh Quynh - Muoi Hai Ben Nuoc.flac Aug 31 07:43:21 volumio volumio[1186]: info: Using cached record of: music-library/USB/New_Volume/Nhu Quynh/06 - Nhu Quynh & Manh Quynh - Muoi Hai Ben Nuoc.flac Aug 31 07:43:21 volumio volumio[1186]: info: Adding Item to queue: music-library/USB/New_Volume/Nhu Quynh/07 - Nhu Quynh - Hoa Tim Bang Lang.flac Aug 31 07:43:21 volumio volumio[1186]: info: Using cached record of: music-library/USB/New_Volume/Nhu Quynh/07 - Nhu Quynh - Hoa Tim Bang Lang.flac Aug 31 07:43:21 volumio volumio[1186]: info: Adding Item to queue: music-library/USB/New_Volume/Nhu Quynh/08 - Nhu Quynh & Dang Truong Phat - Dieu Ru Ca Tinh Yeu.flac Aug 31 07:43:21 volumio volumio[1186]: info: Using cached record of: music-library/USB/New_Volume/Nhu Quynh/08 - Nhu Quynh & Dang Truong Phat - Dieu Ru Ca Tinh Yeu.flac Aug 31 07:43:21 volumio volumio[1186]: info: Adding Item to queue: music-library/USB/New_Volume/Nhu Quynh/09 - Nhu Quynh - Du Cho Hoa Tan Ua.flac Aug 31 07:43:21 volumio volumio[1186]: info: Using cached record of: music-library/USB/New_Volume/Nhu Quynh/09 - Nhu Quynh - Du Cho Hoa Tan Ua.flac Aug 31 07:43:21 volumio volumio[1186]: info: Adding Item to queue: music-library/USB/New_Volume/Nhu Quynh/10 - Nhu Quynh & Manh Quynh - Ngay Vui Hai Dua.flac Aug 31 07:43:21 volumio volumio[1186]: info: Using cached record of: music-library/USB/New_Volume/Nhu Quynh/10 - Nhu Quynh & Manh Quynh - Ngay Vui Hai Dua.flac Aug 31 07:43:21 volumio volumio[1186]: info: Adding Item to queue: music-library/USB/New_Volume/Nhu Quynh/Nhu Quynh - Duyen Phan Aug 31 07:43:21 volumio volumio[1186]: info: Exploding uri music-library/USB/New_Volume/Nhu Quynh/Nhu Quynh - Duyen Phan in service mpd Aug 31 07:43:21 volumio volumio[1186]: info: CoreStateMachine::stop Aug 31 07:43:21 volumio volumio[1186]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 07:43:21 volumio volumio[1186]: info: CoreStateMachine::play index undefined Aug 31 07:43:21 volumio volumio[1186]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 07:43:21 volumio volumio[1186]: info: CorePlayQueue::getTrack 0 Aug 31 07:43:21 volumio volumio[1186]: info: CoreStateMachine::startPlaybackTimer Aug 31 07:43:21 volumio volumio[1186]: info: CorePlayQueue::getTrack 0 Aug 31 07:43:21 volumio volumio[1186]: verbose: ControllerMpd::clearAddPlayTracks USB/New_Volume/Nhu Quynh/01 - Nhu Quynh & Manh Quynh - Anh Hay Ve Di.flac Aug 31 07:43:21 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 07:43:21 volumio volumio[1186]: info: Aug 31 07:43:21 volumio volumio[1186]: ---------------------------- MPD announces state update: player Aug 31 07:43:21 volumio volumio[1186]: info: ALBUMART /albumart?cacheid=895&web=//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FNhu%20Quynh%2FNhu%20Quynh%20-%20Duyen%20Phan%2FTNCD473.cue&metadata=false Aug 31 07:43:21 volumio volumio[1186]: info: URI /mnt/USB/New_Volume/Nhu Quynh/Nhu Quynh - Duyen Phan/TNCD473.cue Aug 31 07:43:21 volumio volumio[1186]: info: ALBUMART /albumart?cacheid=895&web=//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FNhu%20Quynh%2FNhu%20Quynh%20-%20Duyen%20Phan%2FTNCD473.cue&metadata=false Aug 31 07:43:21 volumio volumio[1186]: info: URI /mnt/USB/New_Volume/Nhu Quynh/Nhu Quynh - Duyen Phan/TNCD473.cue Aug 31 07:43:21 volumio volumio[1186]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 07:43:21 volumio volumio[1186]: Error: Unable to resolve or reject the same promise twice Aug 31 07:43:21 volumio volumio[1186]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 31 07:43:21 volumio volumio[1186]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Aug 31 07:43:21 volumio volumio[1186]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Aug 31 07:43:21 volumio volumio[1186]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Aug 31 07:43:21 volumio volumio[1186]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Aug 31 07:43:21 volumio volumio[1186]: at Socket.emit (node:events:514:28) Aug 31 07:43:21 volumio volumio[1186]: at addChunk (node:internal/streams/readable:343:12) Aug 31 07:43:21 volumio volumio[1186]: at readableAddChunk (node:internal/streams/readable:312:11) Aug 31 07:43:21 volumio volumio[1186]: at Readable.push (node:internal/streams/readable:253:10) Aug 31 07:43:21 volumio volumio[1186]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Aug 31 07:43:21 volumio volumio[1186]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 07:43:22 volumio sudo[45773]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-31 07:42' Aug 31 07:43:22 volumio sudo[45773]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"