Mar 27 23:15:07 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 27 23:15:07 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:15:13 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:13.374+03:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.50:53304 Mar 27 23:15:13 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:13.688+03:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.50:53304 @ 0xc0001de090" latency=526.460068ms platform=PLATFORM_ANDROID version=5.260209.0 Mar 27 23:15:13 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 27 23:15:13 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 27 23:15:13 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:13.695+03:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.50:53304 @ 0xc0001de090" latency=530.934188ms timeout=10s Mar 27 23:15:13 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:13.695+03:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" Mar 27 23:15:13 volumio volumio[1322]: info: Received Get System Info Mar 27 23:15:13 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 23:15:13 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 23:15:13 volumio volumio[1322]: info: Discovery: Getting this device information Mar 27 23:15:13 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 27 23:15:13 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:15:13 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 23:15:13 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:13.698+03:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" name=Volumio Mar 27 23:15:13 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:13.701+03:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" language=ru Mar 27 23:15:13 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 27 23:15:13 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:13.703+03:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" timezone=Europe/Moscow Mar 27 23:15:13 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:13.705+03:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" available=true connected=true macAddress=00:e0:4c:12:2a:35 ip4Address=192.168.1.134/24 ip6Address= Mar 27 23:15:13 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:13.706+03:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 27 23:15:13 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:13.707+03:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" setupComplete=true Mar 27 23:15:13 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 27 23:15:13 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 27 23:15:13 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 23:15:13 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 27 23:15:13 volumio volumio[1322]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Mar 27 23:15:13 volumio volumio[1322]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 27 23:15:13 volumio volumio[1322]: {"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)"} Mar 27 23:15:13 volumio volumio[1322]: amixer -c 5 info | grep "Cambridge Audio DAC100 USB 2" Mar 27 23:15:13 volumio volumio[1322]: Card sysdefault:5 'C2'/'Cambridge Audio Cambridge Audio DAC100 USB 2 at usb-0000:00:15.0-8.3, high spee' Mar 27 23:15:13 volumio volumio[1322]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Mar 27 23:15:13 volumio volumio[1322]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 27 23:15:13 volumio volumio[1322]: {"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)"} Mar 27 23:15:13 volumio volumio[1322]: amixer -c 5 info | grep "Cambridge Audio DAC100 USB 2" Mar 27 23:15:13 volumio volumio[1322]: Card sysdefault:5 'C2'/'Cambridge Audio Cambridge Audio DAC100 USB 2 at usb-0000:00:15.0-8.3, high spee' Mar 27 23:15:13 volumio volumio[1322]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 7 Mar 27 23:15:13 volumio volumio[1322]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 27 23:15:13 volumio volumio[1322]: {"cmd":"/usr/local/bin/alsacap -C 7","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 7\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 27 23:15:13 volumio volumio[1322]: amixer -c 7 info | grep "Loopback" Mar 27 23:15:13 volumio volumio[1322]: Card sysdefault:7 'Loopback'/'Loopback 1' Mar 27 23:15:13 volumio volumio[1322]: Mixer name : 'Loopback Mixer' Mar 27 23:15:13 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:13.830+03:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" selectedOutputId=5 Mar 27 23:15:13 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 23:15:13 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 23:15:13 volumio volumio[1322]: info: Discovery: Getting this device information Mar 27 23:15:13 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 27 23:15:13 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:15:13 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 23:15:13 volumio volumio[1322]: verbose: New Socket.io Connection to 192.168.1.134:3000 from 192.168.1.50 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Mar 27 23:15:13 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 27 23:15:13 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 27 23:15:13 volumio volumio[1322]: info: Received Get System Info Mar 27 23:15:13 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 23:15:13 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 23:15:13 volumio volumio[1322]: info: Discovery: Getting this device information Mar 27 23:15:13 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 27 23:15:13 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:15:13 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 23:15:13 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:13.950+03:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" currentVersion=4.103 latestVersion=4.103 Mar 27 23:15:13 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:13.950+03:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" status=UPDATE_STATUS_NONE progress=0 Mar 27 23:15:13 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 23:15:13 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 27 23:15:16 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:16.067+03:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=h7Kph3fSineqx2RDVuPi41shgyG3 tokenExpiry=2026-03-28T00:15:16.066+03:00 Mar 27 23:15:16 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:16.229+03:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.50:53304 @ 0xc0001de090" latency=528.602069ms timeout=10s Mar 27 23:15:16 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:16.229+03:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.50:53304 @ 0xc0001de090" latency=529.569991ms platform=PLATFORM_ANDROID version=5.260209.0 Mar 27 23:15:16 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:16.230+03:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" Mar 27 23:15:16 volumio volumio[1322]: info: Received Get System Info Mar 27 23:15:16 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 23:15:16 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 23:15:16 volumio volumio[1322]: info: Discovery: Getting this device information Mar 27 23:15:16 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 27 23:15:16 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:15:16 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 23:15:16 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:16.233+03:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" name=Volumio Mar 27 23:15:16 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:16.234+03:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" language=ru Mar 27 23:15:16 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 27 23:15:16 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:16.235+03:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" timezone=Europe/Moscow Mar 27 23:15:16 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:16.238+03:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" available=true connected=true macAddress=00:e0:4c:12:2a:35 ip4Address=192.168.1.134/24 ip6Address= Mar 27 23:15:16 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:16.240+03:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 27 23:15:16 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:16.240+03:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" setupComplete=true Mar 27 23:15:16 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 27 23:15:16 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 27 23:15:16 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 23:15:16 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 27 23:15:16 volumio volumio[1322]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Mar 27 23:15:16 volumio volumio[1322]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 27 23:15:16 volumio volumio[1322]: {"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)"} Mar 27 23:15:16 volumio volumio[1322]: amixer -c 5 info | grep "Cambridge Audio DAC100 USB 2" Mar 27 23:15:16 volumio volumio[1322]: Card sysdefault:5 'C2'/'Cambridge Audio Cambridge Audio DAC100 USB 2 at usb-0000:00:15.0-8.3, high spee' Mar 27 23:15:16 volumio volumio[1322]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Mar 27 23:15:16 volumio volumio[1322]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 27 23:15:16 volumio volumio[1322]: {"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)"} Mar 27 23:15:16 volumio volumio[1322]: amixer -c 5 info | grep "Cambridge Audio DAC100 USB 2" Mar 27 23:15:16 volumio volumio[1322]: Card sysdefault:5 'C2'/'Cambridge Audio Cambridge Audio DAC100 USB 2 at usb-0000:00:15.0-8.3, high spee' Mar 27 23:15:16 volumio volumio[1322]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 7 Mar 27 23:15:16 volumio volumio[1322]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 27 23:15:16 volumio volumio[1322]: {"cmd":"/usr/local/bin/alsacap -C 7","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 7\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 27 23:15:16 volumio volumio[1322]: amixer -c 7 info | grep "Loopback" Mar 27 23:15:16 volumio volumio[1322]: Card sysdefault:7 'Loopback'/'Loopback 1' Mar 27 23:15:16 volumio volumio[1322]: Mixer name : 'Loopback Mixer' Mar 27 23:15:16 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:16.350+03:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" selectedOutputId=5 Mar 27 23:15:16 volumio volumio[1322]: info: Received Get System Info Mar 27 23:15:16 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 23:15:16 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 23:15:16 volumio volumio[1322]: info: Discovery: Getting this device information Mar 27 23:15:16 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 27 23:15:16 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:15:16 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 23:15:16 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:16.479+03:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" currentVersion=4.103 latestVersion=4.103 Mar 27 23:15:16 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:16.479+03:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" status=UPDATE_STATUS_NONE progress=0 Mar 27 23:15:17 volumio bluealsa[1134]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_7B_6C_8D_42_91_B0, ...) Mar 27 23:15:17 volumio rfkill[3470653]: unblock set for type bluetooth Mar 27 23:15:17 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:17.508+03:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" userId=h7Kph3fSineqx2RDVuPi41shgyG3 Mar 27 23:15:17 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 23:15:17 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 23:15:17 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 27 23:15:17 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:17.870+03:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" userId=h7Kph3fSineqx2RDVuPi41shgyG3 Mar 27 23:15:17 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 27 23:15:17 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:15:17 volumio volumio[1322]: info: Listing playlists Mar 27 23:15:17 volumio volumio[1322]: info: Listing playlists Mar 27 23:15:18 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:18.608+03:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Mar 27 23:15:18 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:18.608+03:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Mar 27 23:15:18 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:18.608+03:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Mar 27 23:15:18 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:18.608+03:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=spotify error="could not open plugin config file for \"spop\": open /data/configuration/music_service/spop/config.json: no such file or directory" Mar 27 23:15:18 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:18.609+03:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" providers=9 Mar 27 23:15:18 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:18.609+03:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" providers=9 Mar 27 23:15:18 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 23:15:18 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 23:15:18 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 27 23:15:19 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:19.337+03:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" plugins=46 Mar 27 23:15:19 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:19.338+03:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" plugins=46 Mar 27 23:15:19 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 27 23:15:19 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:15:19 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 27 23:15:19 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:15:19 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:19.342+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" state=STATUS_STOPPED positionMs=0 volume=100 Mar 27 23:15:19 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:19.342+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" id="mnt/NAS/music/Bruce Springsteen & The E Street Band -02-04 AAMI Park, Melbourne, Australia (2017 48 kHz 24-bit)/1.01. American Land.flac" title="American Land" Mar 27 23:15:19 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:19.342+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" state=STATUS_STOPPED positionMs=0 volume=100 Mar 27 23:15:19 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:19.342+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" id="mnt/NAS/music/Bruce Springsteen & The E Street Band -02-04 AAMI Park, Melbourne, Australia (2017 48 kHz 24-bit)/1.01. American Land.flac" title="American Land" Mar 27 23:15:19 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:19.344+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" state=STATUS_STOPPED positionMs=0 volume=100 Mar 27 23:15:19 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:19.345+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" id="mnt/NAS/music/Bruce Springsteen & The E Street Band -02-04 AAMI Park, Melbourne, Australia (2017 48 kHz 24-bit)/1.01. American Land.flac" title="American Land" Mar 27 23:15:19 volumio volumio5-onboarding[1877]: time=2026-03-27T23:15:19.479+03:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.50:53304 @ 0xc0001de090" latency=524.066553ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 27 23:15:20 volumio sudo[3470659]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 27 23:15:20 volumio sudo[3470659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 23:15:20 volumio sudo[3470659]: pam_unix(sudo:session): session closed for user root Mar 27 23:15:20 volumio sudo[3470661]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 27 23:15:20 volumio sudo[3470661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 23:15:20 volumio sudo[3470661]: pam_unix(sudo:session): session closed for user root Mar 27 23:15:21 volumio volumio[1322]: verbose: New Socket.io Connection to 192.168.1.134 from 192.168.1.50 UA: Mozilla/5.0 (Linux; Android 15; CPH2481 Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 27 23:15:21 volumio volumio[1322]: info: CoreCommandRouter::volumioGetVisibleSources Mar 27 23:15:21 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 23:15:21 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 27 23:15:21 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:15:21 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 27 23:15:21 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 27 23:15:21 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 27 23:15:21 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 27 23:15:21 volumio sudo[3470665]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 27 23:15:21 volumio sudo[3470665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 23:15:21 volumio sudo[3470665]: pam_unix(sudo:session): session closed for user root Mar 27 23:15:21 volumio sudo[3470668]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 27 23:15:21 volumio sudo[3470668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 23:15:21 volumio sudo[3470668]: pam_unix(sudo:session): session closed for user root Mar 27 23:15:21 volumio volumio[1322]: verbose: New Socket.io Connection to 192.168.1.134 from 192.168.1.50 UA: Mozilla/5.0 (Linux; Android 15; CPH2481 Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 27 23:15:21 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 27 23:15:21 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:15:21 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 27 23:15:21 volumio volumio[1322]: info: Listing playlists Mar 27 23:15:21 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 27 23:15:21 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 27 23:15:21 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 27 23:15:21 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 27 23:15:22 volumio volumio[1322]: info: Display-configuration --- → Sleep (DPMS) triggered after 120s Mar 27 23:15:22 volumio volumio[1322]: info: Display-configuration --- sleepScreen: DPMS - screen off in 120s Mar 27 23:15:23 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 23:15:23 volumio volumio[1322]: info: Received Get System Info Mar 27 23:15:23 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 23:15:23 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 23:15:23 volumio volumio[1322]: info: Discovery: Getting this device information Mar 27 23:15:23 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 27 23:15:23 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:15:23 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 23:15:23 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 27 23:15:26 volumio volumio[1322]: info: Display-configuration --- → Sleep (DPMS) triggered after 120s Mar 27 23:15:26 volumio volumio[1322]: info: Display-configuration --- sleepScreen: DPMS - screen off in 120s Mar 27 23:15:26 volumio volumio[1322]: info: Display-configuration --- → Sleep (DPMS) triggered after 120s Mar 27 23:15:26 volumio volumio[1322]: info: Display-configuration --- sleepScreen: DPMS - screen off in 120s Mar 27 23:15:26 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 23:15:26 volumio volumio[1322]: info: Received Get System Info Mar 27 23:15:26 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 23:15:26 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 23:15:26 volumio volumio[1322]: info: Discovery: Getting this device information Mar 27 23:15:26 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 27 23:15:26 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:15:26 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 23:15:26 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 23:15:26 volumio volumio[1322]: info: Received Get System Info Mar 27 23:15:26 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 23:15:26 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 23:15:26 volumio volumio[1322]: info: Discovery: Getting this device information Mar 27 23:15:26 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 27 23:15:26 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:15:26 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 23:15:26 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 23:15:26 volumio volumio[1322]: info: Received Get System Info Mar 27 23:15:26 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 23:15:26 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 23:15:26 volumio volumio[1322]: info: Discovery: Getting this device information Mar 27 23:15:26 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 27 23:15:26 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:15:26 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 23:15:27 volumio volumio[1322]: info: CoreCommandRouter::volumioGetVisibleSources Mar 27 23:15:27 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 23:15:27 volumio volumio[1322]: info: CoreCommandRouter::volumioGetQueue Mar 27 23:15:27 volumio volumio[1322]: info: CoreStateMachine::getQueue Mar 27 23:15:27 volumio volumio[1322]: info: CorePlayQueue::getQueue Mar 27 23:15:27 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 27 23:15:27 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:15:30 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 27 23:15:30 volumio volumio[1322]: info: CURURI: music-library Mar 27 23:15:30 volumio volumio[1322]: info: Preload queue cleared Mar 27 23:15:31 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 27 23:15:31 volumio volumio[1322]: info: CURURI: music-library/NAS Mar 27 23:15:31 volumio volumio[1322]: info: Preload queue cleared Mar 27 23:15:32 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 27 23:15:32 volumio volumio[1322]: info: CURURI: music-library/NAS/music Mar 27 23:15:32 volumio volumio[1322]: info: Preload queue cleared Mar 27 23:15:37 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 27 23:15:37 volumio volumio[1322]: info: CURURI: music-library/NAS/music/AC-DC Mar 27 23:15:37 volumio volumio[1322]: info: Preload queue cleared Mar 27 23:15:38 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 27 23:15:38 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:15:38 volumio volumio[1322]: info: Listing playlists Mar 27 23:15:38 volumio volumio[1322]: info: Listing playlists Mar 27 23:15:38 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 27 23:15:38 volumio volumio[1322]: info: CURURI: music-library/NAS/music/AC-DC/2014 - Rock or Bust Mar 27 23:15:39 volumio volumio[1322]: info: Preload queue cleared Mar 27 23:15:40 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 27 23:15:40 volumio volumio[1322]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 27 23:15:40 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 27 23:15:40 volumio volumio[1322]: info: Received Get System Version Mar 27 23:15:40 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 23:15:40 volumio volumio[1322]: info: Received Get System Info Mar 27 23:15:40 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 23:15:40 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 23:15:40 volumio volumio[1322]: info: Discovery: Getting this device information Mar 27 23:15:40 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 27 23:15:40 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:15:40 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 23:15:40 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 27 23:15:40 volumio volumio[1322]: info: CURURI: music-library/NAS/music/AC-DC/2014 - Rock or Bust/©88875 03484 1 =Vinyl 32-192= =®= Mar 27 23:15:40 volumio volumio[1322]: info: Preload queue cleared Mar 27 23:15:42 volumio volumio[1322]: info: Preload queue cleared Mar 27 23:15:42 volumio volumio[1322]: info: Mar 27 23:15:42 volumio volumio[1322]: [1774642542114] ---------------------------- Client requests add and Play Volumio CUE entry Mar 27 23:15:42 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: mpd , addPlayCue Mar 27 23:15:42 volumio volumio[1322]: info: Adding CUE individual entry: 0 NAS/music/AC-DC/2014 - Rock or Bust/©88875 03484 1 =Vinyl 32-192= =®=/AC-DC (=2014=) Rock or Bust.cue Mar 27 23:15:42 volumio volumio[1322]: info: CoreCommandRouter::volumioAddQueueItems Mar 27 23:15:42 volumio volumio[1322]: info: CoreStateMachine::addQueueItems Mar 27 23:15:42 volumio volumio[1322]: info: CorePlayQueue::addQueueItems Mar 27 23:15:42 volumio volumio[1322]: info: Preload queue cleared Mar 27 23:15:42 volumio volumio[1322]: info: Adding Item to queue: cue://NAS/music/AC-DC/2014 - Rock or Bust/©88875 03484 1 =Vinyl 32-192= =®=/AC-DC (=2014=) Rock or Bust.cue@0 Mar 27 23:15:42 volumio volumio[1322]: info: Exploding uri cue://NAS/music/AC-DC/2014 - Rock or Bust/©88875 03484 1 =Vinyl 32-192= =®=/AC-DC (=2014=) Rock or Bust.cue@0 in service mpd Mar 27 23:15:42 volumio volumio[1322]: info: CoreCommandRouter::volumioPlay Mar 27 23:15:42 volumio volumio[1322]: info: CoreStateMachine::play index 28 Mar 27 23:15:42 volumio volumio[1322]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 23:15:42 volumio volumio[1322]: info: CoreStateMachine::stop Mar 27 23:15:42 volumio volumio[1322]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 23:15:42 volumio volumio[1322]: info: CoreStateMachine::play index undefined Mar 27 23:15:42 volumio volumio[1322]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 23:15:42 volumio volumio[1322]: info: CorePlayQueue::getTrack 28 Mar 27 23:15:42 volumio volumio[1322]: info: CoreCommandRouter::volumioPushQueue Mar 27 23:15:42 volumio volumio[1322]: info: CorePlayQueue::saveQueue Mar 27 23:15:42 volumio volumio[1322]: info: CoreStateMachine::updateTrackBlock Mar 27 23:15:42 volumio volumio[1322]: info: CorePlayQueue::getTrackBlock Mar 27 23:15:43 volumio rfkill[3470759]: unblock set for type bluetooth Mar 27 23:15:47 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 27 23:15:47 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:15:57 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 27 23:15:57 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:15:57 volumio volumio[1322]: info: Listing playlists Mar 27 23:15:57 volumio volumio[1322]: info: Listing playlists Mar 27 23:15:59 volumio volumio[1322]: info: CoreCommandRouter::volumioPlay Mar 27 23:15:59 volumio volumio[1322]: info: CoreStateMachine::play index undefined Mar 27 23:15:59 volumio volumio[1322]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 23:15:59 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:15:59 volumio volumio[1322]: info: CoreStateMachine::startPlaybackTimer Mar 27 23:15:59 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:15:59 volumio volumio[1322]: verbose: ControllerMpd::clearAddPlayTracks NAS/music/Bruce Springsteen & The E Street Band -02-04 AAMI Park, Melbourne, Australia (2017 48 kHz 24-bit)/1.01. American Land.flac Mar 27 23:15:59 volumio volumio[1322]: verbose: ControllerMpd::sendMpdCommand stop Mar 27 23:15:59 volumio volumio[1322]: info: sendMpdCommand stop took 2 milliseconds Mar 27 23:15:59 volumio volumio[1322]: verbose: ControllerMpd::sendMpdCommand clear Mar 27 23:15:59 volumio volumio[1322]: info: Mar 27 23:15:59 volumio volumio[1322]: ---------------------------- MPD announces system playlist update Mar 27 23:15:59 volumio volumio[1322]: info: Ignoring MPD Status Update Mar 27 23:15:59 volumio volumio[1322]: info: sendMpdCommand clear took 3 milliseconds Mar 27 23:15:59 volumio volumio[1322]: verbose: ControllerMpd::sendMpdCommand add "NAS/music/Bruce Springsteen & The E Street Band -02-04 AAMI Park, Melbourne, Australia (2017 48 kHz 24-bit)/1.01. American Land.flac" Mar 27 23:15:59 volumio volumio[1322]: error: updateQueue error: null Mar 27 23:15:59 volumio volumio[1322]: info: ------------------------------ 5ms Mar 27 23:15:59 volumio volumio[1322]: info: sendMpdCommand add "NAS/music/Bruce Springsteen & The E Street Band -02-04 AAMI Park, Melbourne, Australia (2017 48 kHz 24-bit)/1.01. American Land.flac" took 2 milliseconds Mar 27 23:15:59 volumio volumio[1322]: verbose: ControllerMpd::sendMpdCommand play Mar 27 23:15:59 volumio volumio[1322]: info: Mar 27 23:15:59 volumio volumio[1322]: ---------------------------- MPD announces system playlist update Mar 27 23:15:59 volumio volumio[1322]: info: Ignoring MPD Status Update Mar 27 23:15:59 volumio volumio[1322]: info: sendMpdCommand play took 2 milliseconds Mar 27 23:15:59 volumio volumio[1322]: info: ------------------------------ 1ms Mar 27 23:16:00 volumio volumio[1322]: info: Mar 27 23:16:00 volumio volumio[1322]: ---------------------------- MPD announces state update: player Mar 27 23:16:00 volumio volumio[1322]: info: ControllerMpd::getState Mar 27 23:16:00 volumio volumio[1322]: verbose: ControllerMpd::sendMpdCommand status Mar 27 23:16:00 volumio volumio[1322]: info: Mar 27 23:16:00 volumio volumio[1322]: ---------------------------- MPD announces state update: player Mar 27 23:16:00 volumio volumio[1322]: info: sendMpdCommand status took 226 milliseconds Mar 27 23:16:00 volumio volumio[1322]: info: ControllerMpd::getState Mar 27 23:16:00 volumio volumio[1322]: verbose: ControllerMpd::sendMpdCommand status Mar 27 23:16:00 volumio volumio[1322]: verbose: ControllerMpd::parseState Mar 27 23:16:00 volumio volumio[1322]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 23:16:00 volumio volumio[1322]: info: sendMpdCommand status took 3 milliseconds Mar 27 23:16:00 volumio volumio[1322]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 27 23:16:00 volumio volumio[1322]: verbose: ControllerMpd::parseState Mar 27 23:16:00 volumio volumio[1322]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 23:16:00 volumio volumio[1322]: verbose: ControllerMpd::parseTrackInfo Mar 27 23:16:00 volumio volumio[1322]: info: ControllerMpd::pushState Mar 27 23:16:00 volumio volumio[1322]: info: CoreCommandRouter::servicePushState Mar 27 23:16:00 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:16:00 volumio volumio[1322]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":312,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1271 Kbps","isStreaming":false,"title":"American Land","artist":"Bruce Springsteen & The E Street Band","album":"2017/02/04 Melbourne, AU","uri":"NAS/music/Bruce Springsteen & The E Street Band -02-04 AAMI Park, Melbourne, Australia (2017 48 kHz 24-bit)/1.01. American Land.flac","trackType":"flac"} Mar 27 23:16:00 volumio volumio[1322]: verbose: CURRENT POSITION 0 Mar 27 23:16:00 volumio volumio[1322]: info: CoreStateMachine::syncState stateService play Mar 27 23:16:00 volumio volumio[1322]: info: CoreStateMachine::syncState currentStatus stop Mar 27 23:16:00 volumio volumio[1322]: info: ------------------------------ 242ms Mar 27 23:16:00 volumio volumio[1322]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 27 23:16:00 volumio volumio[1322]: verbose: ControllerMpd::parseTrackInfo Mar 27 23:16:00 volumio volumio[1322]: info: ControllerMpd::pushState Mar 27 23:16:00 volumio volumio[1322]: info: CoreCommandRouter::servicePushState Mar 27 23:16:00 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:16:00 volumio volumio[1322]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":312,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1393 Kbps","isStreaming":false,"title":"American Land","artist":"Bruce Springsteen & The E Street Band","album":"2017/02/04 Melbourne, AU","uri":"NAS/music/Bruce Springsteen & The E Street Band -02-04 AAMI Park, Melbourne, Australia (2017 48 kHz 24-bit)/1.01. American Land.flac","trackType":"flac"} Mar 27 23:16:00 volumio volumio[1322]: verbose: CURRENT POSITION 0 Mar 27 23:16:00 volumio volumio[1322]: info: CoreStateMachine::syncState stateService play Mar 27 23:16:00 volumio volumio[1322]: info: CoreStateMachine::syncState currentStatus play Mar 27 23:16:00 volumio volumio[1322]: info: Received an update from plugin. extracting info from payload Mar 27 23:16:00 volumio volumio[1322]: info: CoreStateMachine::pushState Mar 27 23:16:00 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:16:00 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 23:16:00 volumio volumio[1322]: info: CoreCommandRouter::volumioPushState Mar 27 23:16:00 volumio volumio[1322]: info: CoreStateMachine::pushState Mar 27 23:16:00 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:16:00 volumio volumio[1322]: info: CoreCommandRouter::volumioPushState Mar 27 23:16:00 volumio volumio5-onboarding[1877]: time=2026-03-27T23:16:00.357+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" state=STATUS_PLAYING positionMs=985 volume=100 Mar 27 23:16:00 volumio volumio5-onboarding[1877]: time=2026-03-27T23:16:00.357+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" state=STATUS_PLAYING positionMs=985 volume=100 Mar 27 23:16:00 volumio volumio5-onboarding[1877]: time=2026-03-27T23:16:00.358+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" id="mnt/NAS/music/Bruce Springsteen & The E Street Band -02-04 AAMI Park, Melbourne, Australia (2017 48 kHz 24-bit)/1.01. American Land.flac" title="American Land" Mar 27 23:16:00 volumio volumio5-onboarding[1877]: time=2026-03-27T23:16:00.358+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" id="mnt/NAS/music/Bruce Springsteen & The E Street Band -02-04 AAMI Park, Melbourne, Australia (2017 48 kHz 24-bit)/1.01. American Land.flac" title="American Land" Mar 27 23:16:00 volumio volumio[1322]: info: ------------------------------ 29ms Mar 27 23:16:00 volumio volumio[1322]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Mar 27 23:16:00 volumio volumio[1322]: info: Display-configuration --- → Wakeup triggered Mar 27 23:16:00 volumio volumio[1322]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Mar 27 23:16:00 volumio volumio[1322]: info: Display-configuration --- → Wakeup triggered Mar 27 23:16:00 volumio volumio[1322]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 27 23:16:00 volumio volumio[1322]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 27 23:16:00 volumio sudo[3470810]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 27 23:16:00 volumio sudo[3470810]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 23:16:00 volumio sudo[3470812]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 27 23:16:00 volumio sudo[3470812]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 23:16:00 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Mar 27 23:16:00 volumio sudo[3470810]: pam_unix(sudo:session): session closed for user root Mar 27 23:16:00 volumio sudo[3470812]: pam_unix(sudo:session): session closed for user root Mar 27 23:16:00 volumio volumio[1322]: info: peppymeterbasic Daemon Started Mar 27 23:16:00 volumio volumio[1322]: info: peppymeterbasic Daemon Started Mar 27 23:16:00 volumio volumio[1322]: info: Display-configuration --- wakeupScreen: DPMS - screen on Mar 27 23:16:00 volumio volumio[1322]: info: Display-configuration --- wakeupScreen: DPMS - screen on Mar 27 23:16:07 volumio volumio[1322]: info: Preload queue cleared Mar 27 23:16:07 volumio volumio[1322]: info: Mar 27 23:16:07 volumio volumio[1322]: [1774642567504] ---------------------------- Client requests add and Play Volumio CUE entry Mar 27 23:16:07 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: mpd , addPlayCue Mar 27 23:16:07 volumio volumio[1322]: info: Adding CUE individual entry: 0 NAS/music/AC-DC/2014 - Rock or Bust/©88875 03484 1 =Vinyl 32-192= =®=/AC-DC (=2014=) Rock or Bust.cue Mar 27 23:16:07 volumio volumio[1322]: info: CoreCommandRouter::volumioAddQueueItems Mar 27 23:16:07 volumio volumio[1322]: info: CoreStateMachine::addQueueItems Mar 27 23:16:07 volumio volumio[1322]: info: CorePlayQueue::addQueueItems Mar 27 23:16:07 volumio volumio[1322]: info: Preload queue cleared Mar 27 23:16:07 volumio volumio[1322]: info: Adding Item to queue: cue://NAS/music/AC-DC/2014 - Rock or Bust/©88875 03484 1 =Vinyl 32-192= =®=/AC-DC (=2014=) Rock or Bust.cue@0 Mar 27 23:16:07 volumio volumio[1322]: info: Exploding uri cue://NAS/music/AC-DC/2014 - Rock or Bust/©88875 03484 1 =Vinyl 32-192= =®=/AC-DC (=2014=) Rock or Bust.cue@0 in service mpd Mar 27 23:16:07 volumio volumio[1322]: info: CoreCommandRouter::volumioPlay Mar 27 23:16:07 volumio volumio[1322]: info: CoreStateMachine::play index 28 Mar 27 23:16:07 volumio volumio[1322]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 23:16:07 volumio volumio[1322]: info: CoreStateMachine::stop Mar 27 23:16:07 volumio volumio[1322]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 23:16:07 volumio volumio[1322]: info: CoreStateMachine::stPlaybackTimer Mar 27 23:16:07 volumio volumio[1322]: info: CoreStateMachine::updateTrackBlock Mar 27 23:16:07 volumio volumio[1322]: info: CorePlayQueue::getTrackBlock Mar 27 23:16:07 volumio volumio[1322]: info: CoreStateMachine::pushState Mar 27 23:16:07 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:16:07 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 23:16:07 volumio volumio[1322]: info: CoreCommandRouter::volumioPushState Mar 27 23:16:07 volumio volumio[1322]: info: CoreStateMachine::serviceStop Mar 27 23:16:07 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:16:07 volumio volumio[1322]: info: CoreCommandRouter::serviceStop Mar 27 23:16:07 volumio volumio[1322]: info: ControllerMpd::stop Mar 27 23:16:07 volumio volumio[1322]: verbose: ControllerMpd::sendMpdCommand stop Mar 27 23:16:07 volumio volumio5-onboarding[1877]: time=2026-03-27T23:16:07.556+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" state=STATUS_STOPPED positionMs=0 volume=100 Mar 27 23:16:07 volumio volumio[1322]: info: CoreCommandRouter::volumioPushQueue Mar 27 23:16:07 volumio volumio5-onboarding[1877]: time=2026-03-27T23:16:07.559+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" id="mnt/NAS/music/Bruce Springsteen & The E Street Band -02-04 AAMI Park, Melbourne, Australia (2017 48 kHz 24-bit)/1.01. American Land.flac" title="American Land" Mar 27 23:16:07 volumio volumio[1322]: info: CorePlayQueue::saveQueue Mar 27 23:16:07 volumio volumio[1322]: info: CoreStateMachine::updateTrackBlock Mar 27 23:16:07 volumio volumio[1322]: info: CorePlayQueue::getTrackBlock Mar 27 23:16:07 volumio volumio[1322]: info: Display-configuration --- Volumio status=stop timeout=120 noifplay=true screensavertype=dpms Mar 27 23:16:07 volumio volumio[1322]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 27 23:16:07 volumio volumio[1322]: info: Mar 27 23:16:07 volumio volumio[1322]: ---------------------------- MPD announces state update: player Mar 27 23:16:07 volumio sudo[3470855]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 27 23:16:07 volumio volumio[1322]: info: sendMpdCommand stop took 49 milliseconds Mar 27 23:16:07 volumio volumio[1322]: info: ControllerMpd::getState Mar 27 23:16:07 volumio volumio[1322]: verbose: ControllerMpd::sendMpdCommand status Mar 27 23:16:07 volumio volumio[1322]: info: CoreStateMachine::play index undefined Mar 27 23:16:07 volumio volumio[1322]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 23:16:07 volumio volumio[1322]: info: CorePlayQueue::getTrack 28 Mar 27 23:16:07 volumio volumio[1322]: info: sendMpdCommand status took 4 milliseconds Mar 27 23:16:07 volumio sudo[3470855]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 23:16:07 volumio volumio[1322]: verbose: ControllerMpd::parseState Mar 27 23:16:07 volumio volumio[1322]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 23:16:07 volumio volumio[1322]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 27 23:16:07 volumio volumio[1322]: verbose: ControllerMpd::parseTrackInfo Mar 27 23:16:07 volumio volumio[1322]: info: ControllerMpd::pushState Mar 27 23:16:07 volumio volumio[1322]: info: CoreCommandRouter::servicePushState Mar 27 23:16:07 volumio volumio[1322]: info: CoreStateMachine::pushState Mar 27 23:16:07 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:16:07 volumio volumio[1322]: info: CoreCommandRouter::volumioPushState Mar 27 23:16:07 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:16:07 volumio volumio[1322]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"American Land","artist":"Bruce Springsteen & The E Street Band","album":"2017/02/04 Melbourne, AU","uri":"NAS/music/Bruce Springsteen & The E Street Band -02-04 AAMI Park, Melbourne, Australia (2017 48 kHz 24-bit)/1.01. American Land.flac","trackType":"flac"} Mar 27 23:16:07 volumio volumio[1322]: verbose: CURRENT POSITION 0 Mar 27 23:16:07 volumio volumio[1322]: info: CoreStateMachine::syncState stateService stop Mar 27 23:16:07 volumio volumio[1322]: info: CoreStateMachine::syncState currentStatus stop Mar 27 23:16:07 volumio volumio[1322]: info: CoreStateMachine::pushState Mar 27 23:16:07 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:16:07 volumio volumio[1322]: info: CoreCommandRouter::volumioPushState Mar 27 23:16:07 volumio volumio[1322]: info: No code Mar 27 23:16:07 volumio volumio[1322]: info: CoreStateMachine::pushState Mar 27 23:16:07 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:16:07 volumio volumio[1322]: info: CoreCommandRouter::volumioPushState Mar 27 23:16:07 volumio volumio5-onboarding[1877]: time=2026-03-27T23:16:07.636+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" state=STATUS_STOPPED positionMs=0 volume=100 Mar 27 23:16:07 volumio volumio5-onboarding[1877]: time=2026-03-27T23:16:07.636+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" state=STATUS_STOPPED positionMs=0 volume=100 Mar 27 23:16:07 volumio volumio5-onboarding[1877]: time=2026-03-27T23:16:07.636+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" id="mnt/NAS/music/Bruce Springsteen & The E Street Band -02-04 AAMI Park, Melbourne, Australia (2017 48 kHz 24-bit)/1.01. American Land.flac" title="American Land" Mar 27 23:16:07 volumio volumio5-onboarding[1877]: time=2026-03-27T23:16:07.636+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" state=STATUS_STOPPED positionMs=0 volume=100 Mar 27 23:16:07 volumio volumio5-onboarding[1877]: time=2026-03-27T23:16:07.636+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" id="mnt/NAS/music/Bruce Springsteen & The E Street Band -02-04 AAMI Park, Melbourne, Australia (2017 48 kHz 24-bit)/1.01. American Land.flac" title="American Land" Mar 27 23:16:07 volumio volumio5-onboarding[1877]: time=2026-03-27T23:16:07.636+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.50:53304 @ 0xc0001de090" id="mnt/NAS/music/Bruce Springsteen & The E Street Band -02-04 AAMI Park, Melbourne, Australia (2017 48 kHz 24-bit)/1.01. American Land.flac" title="American Land" Mar 27 23:16:07 volumio volumio[1322]: info: ------------------------------ 46ms Mar 27 23:16:07 volumio volumio[1322]: info: Display-configuration --- Volumio status=stop timeout=120 noifplay=true screensavertype=dpms Mar 27 23:16:07 volumio volumio[1322]: info: Display-configuration --- Volumio status=stop timeout=120 noifplay=true screensavertype=dpms Mar 27 23:16:07 volumio volumio[1322]: info: Display-configuration --- Volumio status=stop timeout=120 noifplay=true screensavertype=dpms Mar 27 23:16:07 volumio volumio[1322]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 27 23:16:07 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Mar 27 23:16:07 volumio volumio[1322]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 27 23:16:07 volumio volumio[1322]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 27 23:16:07 volumio sudo[3470858]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 27 23:16:07 volumio sudo[3470858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 23:16:07 volumio sudo[3470860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 27 23:16:07 volumio sudo[3470860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 23:16:07 volumio sudo[3470863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 27 23:16:07 volumio sudo[3470863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 23:16:07 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Mar 27 23:16:07 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Mar 27 23:16:07 volumio systemd[1]: peppymeterbasic.service: Consumed 3.257s CPU time. Mar 27 23:16:07 volumio sudo[3470860]: pam_unix(sudo:session): session closed for user root Mar 27 23:16:07 volumio sudo[3470858]: pam_unix(sudo:session): session closed for user root Mar 27 23:16:07 volumio volumio[1322]: info: peppymeterbasic Daemon Stop Mar 27 23:16:07 volumio sudo[3470855]: pam_unix(sudo:session): session closed for user root Mar 27 23:16:07 volumio volumio[1322]: info: peppymeterbasic Daemon Stop Mar 27 23:16:07 volumio sudo[3470863]: pam_unix(sudo:session): session closed for user root Mar 27 23:16:07 volumio volumio[1322]: info: peppymeterbasic Daemon Stop Mar 27 23:16:07 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 27 23:16:07 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:16:07 volumio volumio[1322]: info: peppymeterbasic Daemon Stop Mar 27 23:16:17 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 27 23:16:17 volumio volumio[1322]: info: CURURI: music-library/NAS/music/Accept Mar 27 23:16:17 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 27 23:16:17 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:16:18 volumio volumio[1322]: info: Listing playlists Mar 27 23:16:18 volumio volumio[1322]: info: Listing playlists Mar 27 23:16:18 volumio volumio[1322]: info: Preload queue cleared Mar 27 23:16:19 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 27 23:16:19 volumio volumio[1322]: info: CURURI: music-library/NAS/music/Accept/2012 - Stalingrad [Japan UICN-1010] Mar 27 23:16:19 volumio volumio[1322]: info: Preload queue cleared Mar 27 23:16:21 volumio volumio[1322]: info: Preload queue cleared Mar 27 23:16:21 volumio volumio[1322]: info: Mar 27 23:16:21 volumio volumio[1322]: [1774642581444] ---------------------------- Client requests add and Play Volumio CUE entry Mar 27 23:16:21 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: mpd , addPlayCue Mar 27 23:16:21 volumio volumio[1322]: info: Adding CUE individual entry: 0 NAS/music/Accept/2012 - Stalingrad [Japan UICN-1010]/Accept - Stalingrad [Japan UICN-1010].cue Mar 27 23:16:21 volumio volumio[1322]: info: CoreCommandRouter::volumioAddQueueItems Mar 27 23:16:21 volumio volumio[1322]: info: CoreStateMachine::addQueueItems Mar 27 23:16:21 volumio volumio[1322]: info: CorePlayQueue::addQueueItems Mar 27 23:16:21 volumio volumio[1322]: info: Preload queue cleared Mar 27 23:16:21 volumio volumio[1322]: info: Adding Item to queue: cue://NAS/music/Accept/2012 - Stalingrad [Japan UICN-1010]/Accept - Stalingrad [Japan UICN-1010].cue@0 Mar 27 23:16:21 volumio volumio[1322]: info: Exploding uri cue://NAS/music/Accept/2012 - Stalingrad [Japan UICN-1010]/Accept - Stalingrad [Japan UICN-1010].cue@0 in service mpd Mar 27 23:16:21 volumio volumio[1322]: info: CoreCommandRouter::volumioPlay Mar 27 23:16:21 volumio volumio[1322]: info: CoreStateMachine::play index 28 Mar 27 23:16:21 volumio volumio[1322]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 23:16:21 volumio volumio[1322]: info: CoreStateMachine::stop Mar 27 23:16:21 volumio volumio[1322]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 23:16:21 volumio volumio[1322]: info: CoreStateMachine::play index undefined Mar 27 23:16:21 volumio volumio[1322]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 23:16:21 volumio volumio[1322]: info: CorePlayQueue::getTrack 28 Mar 27 23:16:21 volumio volumio[1322]: info: CoreCommandRouter::volumioPushQueue Mar 27 23:16:21 volumio volumio[1322]: info: CorePlayQueue::saveQueue Mar 27 23:16:21 volumio volumio[1322]: info: CoreStateMachine::updateTrackBlock Mar 27 23:16:21 volumio volumio[1322]: info: CorePlayQueue::getTrackBlock Mar 27 23:16:27 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 27 23:16:27 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:16:29 volumio volumio[1322]: info: Preload queue cleared Mar 27 23:16:29 volumio volumio[1322]: info: Mar 27 23:16:29 volumio volumio[1322]: [1774642589126] ---------------------------- Client requests add and Play Volumio CUE entry Mar 27 23:16:29 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: mpd , addPlayCue Mar 27 23:16:29 volumio volumio[1322]: info: Adding CUE individual entry: 2 NAS/music/Accept/2012 - Stalingrad [Japan UICN-1010]/Accept - Stalingrad [Japan UICN-1010].cue Mar 27 23:16:29 volumio volumio[1322]: info: CoreCommandRouter::volumioAddQueueItems Mar 27 23:16:29 volumio volumio[1322]: info: CoreStateMachine::addQueueItems Mar 27 23:16:29 volumio volumio[1322]: info: CorePlayQueue::addQueueItems Mar 27 23:16:29 volumio volumio[1322]: info: Preload queue cleared Mar 27 23:16:29 volumio volumio[1322]: info: Adding Item to queue: cue://NAS/music/Accept/2012 - Stalingrad [Japan UICN-1010]/Accept - Stalingrad [Japan UICN-1010].cue@2 Mar 27 23:16:29 volumio volumio[1322]: info: Exploding uri cue://NAS/music/Accept/2012 - Stalingrad [Japan UICN-1010]/Accept - Stalingrad [Japan UICN-1010].cue@2 in service mpd Mar 27 23:16:29 volumio volumio[1322]: info: CoreCommandRouter::volumioPlay Mar 27 23:16:29 volumio volumio[1322]: info: CoreStateMachine::play index 29 Mar 27 23:16:29 volumio volumio[1322]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 23:16:29 volumio volumio[1322]: info: CoreStateMachine::stop Mar 27 23:16:29 volumio volumio[1322]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 23:16:29 volumio volumio[1322]: info: CoreStateMachine::play index undefined Mar 27 23:16:29 volumio volumio[1322]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 23:16:29 volumio volumio[1322]: info: CorePlayQueue::getTrack 29 Mar 27 23:16:29 volumio volumio[1322]: info: CoreCommandRouter::volumioPushQueue Mar 27 23:16:29 volumio volumio[1322]: info: CorePlayQueue::saveQueue Mar 27 23:16:29 volumio volumio[1322]: info: CoreStateMachine::updateTrackBlock Mar 27 23:16:29 volumio volumio[1322]: info: CorePlayQueue::getTrackBlock Mar 27 23:16:37 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 27 23:16:37 volumio volumio[1322]: info: CorePlayQueue::getTrack 0 Mar 27 23:16:37 volumio volumio[1322]: info: Listing playlists Mar 27 23:16:37 volumio volumio[1322]: info: Listing playlists Mar 27 23:16:37 volumio volumio[1322]: info: Preload queue cleared Mar 27 23:16:37 volumio volumio[1322]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 27 23:16:37 volumio volumio[1322]: info: CoreStateMachine::ClearQueue Mar 27 23:16:37 volumio volumio[1322]: info: CoreStateMachine::stop Mar 27 23:16:37 volumio volumio[1322]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 23:16:37 volumio volumio[1322]: info: CorePlayQueue::clearPlayQueue Mar 27 23:16:37 volumio volumio[1322]: info: CorePlayQueue::saveQueue Mar 27 23:16:37 volumio volumio[1322]: info: CoreCommandRouter::volumioPushQueue Mar 27 23:16:37 volumio volumio[1322]: info: CoreStateMachine::addQueueItems Mar 27 23:16:37 volumio volumio[1322]: info: CorePlayQueue::addQueueItems Mar 27 23:16:37 volumio volumio[1322]: info: Preload queue cleared Mar 27 23:16:37 volumio volumio[1322]: info: Adding Item to queue: music-library/NAS/music/Accept/2012 - Stalingrad [Japan UICN-1010] Mar 27 23:16:37 volumio volumio[1322]: info: Exploding uri music-library/NAS/music/Accept/2012 - Stalingrad [Japan UICN-1010] in service mpd Mar 27 23:16:37 volumio volumio[1322]: info: ALBUMART /albumart?cacheid=177&web=Accept/Stalingrad%20%5BJapan%20UICN-1010%5D/mega&path=%2Fmnt%2FNAS%2Fmusic%2FAccept%2F2012%20-%20Stalingrad%20%5BJapan%20UICN-1010%5D%2FAccept%20-%20Stalingrad%20%5BJapan%20UICN-1010%5D.cue&metadata=false Mar 27 23:16:37 volumio volumio[1322]: info: URI /mnt/NAS/music/Accept/2012 - Stalingrad [Japan UICN-1010]/Accept - Stalingrad [Japan UICN-1010].cue Mar 27 23:16:37 volumio volumio[1322]: info: ALBUMART /albumart?cacheid=177&web=Accept/Stalingrad%20%5BJapan%20UICN-1010%5D/mega&path=%2Fmnt%2FNAS%2Fmusic%2FAccept%2F2012%20-%20Stalingrad%20%5BJapan%20UICN-1010%5D%2FAccept%20-%20Stalingrad%20%5BJapan%20UICN-1010%5D.cue&metadata=false Mar 27 23:16:37 volumio volumio[1322]: info: URI /mnt/NAS/music/Accept/2012 - Stalingrad [Japan UICN-1010]/Accept - Stalingrad [Japan UICN-1010].cue Mar 27 23:16:37 volumio volumio[1322]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 23:16:37 volumio volumio[1322]: Error: Unable to resolve or reject the same promise twice Mar 27 23:16:37 volumio volumio[1322]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 27 23:16:37 volumio volumio[1322]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Mar 27 23:16:37 volumio volumio[1322]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Mar 27 23:16:37 volumio volumio[1322]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Mar 27 23:16:37 volumio volumio[1322]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Mar 27 23:16:37 volumio volumio[1322]: at Socket.emit (node:events:514:28) Mar 27 23:16:37 volumio volumio[1322]: at addChunk (node:internal/streams/readable:343:12) Mar 27 23:16:37 volumio volumio[1322]: at readableAddChunk (node:internal/streams/readable:312:11) Mar 27 23:16:37 volumio volumio[1322]: at Readable.push (node:internal/streams/readable:253:10) Mar 27 23:16:37 volumio volumio[1322]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Mar 27 23:16:37 volumio volumio[1322]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 23:16:38 volumio sudo[3470957]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-27 23:15' Mar 27 23:16:38 volumio sudo[3470957]: 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 17:11:42 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="21a5161aef4cb85a1f93b140ccadc4f1"