Oct 14 18:23:00 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:00 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:00 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:00 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:00 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:00 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:00 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:00 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:00 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:00 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:00 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:00 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:01 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:01 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:01 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:01 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:01 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:01 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:01 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:01 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:01 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:01 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:01 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:02 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:02 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:02 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:02 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:02 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:02 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:02 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:02 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:02 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:02 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:02 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:03 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:03 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:03 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:03 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:03 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:03 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:03 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:03 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:03 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:03 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:04 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:04 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:04 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:04 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:04 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:04 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:04 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:04 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:04 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:04 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:05 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:05 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:05 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:05 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:05 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:05 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:05 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:05 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:05 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:05 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:05 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:05 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:06 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:06 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:06 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:06 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:06 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:06 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:06 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:06 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:06 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:06 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:06 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:06 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:07 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:07 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:07 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:07 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:07 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:07.493+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.3.5:57730 Oct 14 18:23:07 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:07.534+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.3.5:57730 @ 0x2894030" latency=-1.863274939s platform=PLATFORM_ANDROID version=6.260930.0 Oct 14 18:23:07 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:07.540+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.3.5:57730 @ 0x2894030" latency=-1.864149776s timeout=20s Oct 14 18:23:07 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:07.540+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" Oct 14 18:23:07 volumio volumio[5453]: info: Received Get System Info Oct 14 18:23:07 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 18:23:07 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 18:23:07 volumio volumio[5453]: info: Discovery: Getting this device information Oct 14 18:23:07 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:07 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:07 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 18:23:07 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:07.544+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" name=Volumio Oct 14 18:23:07 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:07.554+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" language=pl Oct 14 18:23:07 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 14 18:23:07 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:07.557+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" timezone=Europe/Warsaw Oct 14 18:23:07 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:07.558+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" available=true connected=false macAddress= ip4Address= ip6Address= Oct 14 18:23:07 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:07.562+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" available=true connected=true macAddress=b8:27:eb:bb:61:40 ip4Address=192.168.3.219/24 ip6Address= ssid=HUAWEI-1CT6G6 Oct 14 18:23:07 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:07.563+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" setupComplete=true Oct 14 18:23:07 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 14 18:23:07 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 14 18:23:07 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 18:23:07 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 14 18:23:07 volumio volumio[5453]: amixer -c 0 info | grep "bcm2835 ALSA" Oct 14 18:23:07 volumio volumio[5453]: amixer -c 1 info | grep "bcm2835 Headphones" Oct 14 18:23:07 volumio volumio[5453]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Oct 14 18:23:07 volumio volumio[5453]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Oct 14 18:23:07 volumio volumio[5453]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 14 18:23:07 volumio volumio[5453]: {"cmd":"/usr/local/bin/alsacap -C 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 3\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Oct 14 18:23:07 volumio volumio[5453]: amixer -c 3 info | grep "RPi DAC+" Oct 14 18:23:07 volumio volumio[5453]: Card sysdefault:3 'DAC'/'RPi DAC+' Oct 14 18:23:07 volumio volumio[5453]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 2 Oct 14 18:23:07 volumio volumio[5453]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 14 18:23:07 volumio volumio[5453]: {"cmd":"/usr/local/bin/alsacap -C 2","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 2\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Oct 14 18:23:07 volumio volumio[5453]: amixer -c 2 info | grep "vc4-hdmi" Oct 14 18:23:07 volumio volumio[5453]: Card sysdefault:2 'vc4hdmi'/'vc4-hdmi' Oct 14 18:23:07 volumio volumio[5453]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Oct 14 18:23:07 volumio volumio[5453]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 14 18:23:07 volumio volumio[5453]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Oct 14 18:23:07 volumio volumio[5453]: amixer -c 5 info | grep "Combo384 Amanero" Oct 14 18:23:07 volumio volumio[5453]: Card sysdefault:5 'Amanero'/'Amanero Technologies Combo384 Amanero at usb-3f980000.usb-1.2, high speed' Oct 14 18:23:07 volumio volumio[5453]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Oct 14 18:23:07 volumio volumio[5453]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 14 18:23:07 volumio volumio[5453]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Oct 14 18:23:07 volumio volumio[5453]: amixer -c 5 info | grep "Combo384 Amanero" Oct 14 18:23:07 volumio volumio[5453]: Card sysdefault:5 'Amanero'/'Amanero Technologies Combo384 Amanero at usb-3f980000.usb-1.2, high speed' Oct 14 18:23:07 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:07.926+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" selectedOutputId=5 Oct 14 18:23:07 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:07 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:07 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:07 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:07 volumio volumio[5453]: info: Received Get System Info Oct 14 18:23:07 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 18:23:07 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 18:23:07 volumio volumio[5453]: info: Discovery: Getting this device information Oct 14 18:23:07 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:07 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:07 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 18:23:07 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:07.997+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" currentVersion=4.119 latestVersion=4.119 Oct 14 18:23:07 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:07.997+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.3.5:57730 @ 0x2894030" status=UPDATE_STATUS_NONE progress=0 Oct 14 18:23:07 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:07.998+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" userId= Oct 14 18:23:07 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:07.998+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" providers=3 Oct 14 18:23:07 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:07.998+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" plugins=77 Oct 14 18:23:08 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:08 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:08 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:08.003+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" state=STATUS_PAUSED positionMs=58286 volume=100 Oct 14 18:23:08 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:08.003+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" id="mnt/USB/Muzyka/Nowy 3/Chandelier - Sia.flac" title=Chandelier Oct 14 18:23:08 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:08 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:08 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:08 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:08 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:08 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:08 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:08 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:08 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:08 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:09 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:09 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:09 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:09 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:09 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:09 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:09 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:09 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:09 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:09 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:09 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:09 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:10 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:10 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:10 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:10 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:10 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:10 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:10 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:10.633+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.3.5:57730 @ 0x2894030" latency=-1.819399689s timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 14 18:23:10 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:10.724+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.5:57730 @ 0x2894030" latency=-1.858528136s timeout=10s Oct 14 18:23:10 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:10 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:10 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:10 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:10 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:10.960+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.5:57730 @ 0x2894030" latency=-1.858528136s timeout=10s endpoint=http://pushupdates.volumio.org duration=234.723327ms Oct 14 18:23:11 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:11.014+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.5:57730 @ 0x2894030" latency=-1.858528136s timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=289.473412ms Oct 14 18:23:11 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:11 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:11 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:11.214+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.5:57730 @ 0x2894030" latency=-1.858528136s timeout=10s endpoint=https://functions.volumio.cloud duration=488.402318ms Oct 14 18:23:11 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:11.214+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.5:57730 @ 0x2894030" latency=-1.858528136s timeout=10s endpoint=https://functions.volumio.cloud duration=489.018822ms Oct 14 18:23:11 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:11.214+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.5:57730 @ 0x2894030" latency=-1.858528136s timeout=10s endpoint=https://myvolumio.firebaseio.com duration=488.255601ms Oct 14 18:23:11 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:11 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:11 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:11 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:11 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:11.426+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.5:57730 @ 0x2894030" latency=-1.858528136s timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=700.613552ms Oct 14 18:23:11 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:11.564+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.5:57730 @ 0x2894030" latency=-1.858528136s timeout=10s endpoint=http://plugins.volumio.org duration=838.693521ms Oct 14 18:23:11 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:11 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:11 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:11 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:12 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:12 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:12 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:12 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:12 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:12 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:12 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:12 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:12 volumio sudo[30359]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 14 18:23:12 volumio sudo[30359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:23:12 volumio sudo[30359]: pam_unix(sudo:session): session closed for user root Oct 14 18:23:12 volumio sudo[30361]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 14 18:23:12 volumio sudo[30361]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:23:12 volumio sudo[30361]: pam_unix(sudo:session): session closed for user root Oct 14 18:23:12 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:12 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:12 volumio volumio[5453]: verbose: New Socket.io Connection to 192.168.3.219 from 192.168.3.5 UA: Mozilla/5.0 (Linux; Android 11; Hammer_Explorer_Plus Build/RP1A.200720.011; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.106 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 10 Oct 14 18:23:12 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:12 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:13 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:13 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:13 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:13 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:13 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:13.368+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.5:57730 @ 0x2894030" latency=-1.858528136s timeout=10s endpoint=https://www.googleapis.com duration=2.643522867s Oct 14 18:23:13 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:13.422+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.5:57730 @ 0x2894030" latency=-1.858528136s timeout=10s endpoint=https://securetoken.googleapis.com duration=2.696753434s Oct 14 18:23:13 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:13 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:13 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:13 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:13 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:13.791+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.5:57730 @ 0x2894030" latency=-1.858528136s timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=3.065497963s Oct 14 18:23:13 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:13.792+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.5:57730 @ 0x2894030" latency=-1.858528136s timeout=10s endpoint=https://database.volumio.cloud duration=3.067450707s Oct 14 18:23:13 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:13.897+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.5:57730 @ 0x2894030" latency=-1.858528136s timeout=10s endpoint=http://cddb.volumio.org duration=3.172154254s error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 14 18:23:13 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:13 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:14 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:14 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:14 volumio sudo[30390]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 14 18:23:14 volumio sudo[30390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:23:14 volumio sudo[30391]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 14 18:23:14 volumio sudo[30391]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:23:14 volumio sudo[30390]: pam_unix(sudo:session): session closed for user root Oct 14 18:23:14 volumio sudo[30391]: pam_unix(sudo:session): session closed for user root Oct 14 18:23:14 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:14 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:14 volumio volumio[5453]: verbose: New Socket.io Connection to 192.168.3.219 from 192.168.3.5 UA: Mozilla/5.0 (Linux; Android 11; Hammer_Explorer_Plus Build/RP1A.200720.011; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.106 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 10 Oct 14 18:23:14 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:14.523+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.5:57730 @ 0x2894030" latency=-1.858528136s timeout=10s endpoint=https://google.com duration=3.799085077s Oct 14 18:23:14 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:14 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:14 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:14 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:14 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:14 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:14 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:14 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:15 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:15 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:15 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:15 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:15 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:15 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:15 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:15 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:15 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 14 18:23:15 volumio volumio[5453]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 14 18:23:15 volumio volumio[5453]: info: Listing playlists Oct 14 18:23:15 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 14 18:23:15 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 18:23:15 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 18:23:15 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 14 18:23:15 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:15 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:15 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:15 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:16 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:16 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:16 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:16 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:16 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:16 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:16 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:16 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:16 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:16 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:17 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:17 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:17 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:17 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:17 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:17 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:17 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:17 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:17 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:17 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:18 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:18 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:18 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:18 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:18 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:18 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:18 volumio volumio[5453]: info: CoreCommandRouter::volumioGetQueue Oct 14 18:23:18 volumio volumio[5453]: info: CoreStateMachine::getQueue Oct 14 18:23:18 volumio volumio[5453]: info: CorePlayQueue::getQueue Oct 14 18:23:18 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:18 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:18 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:18 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:18 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:18 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:18 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:18 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:19 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:19 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:19 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:19 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:19 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:19 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:19 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:19 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:19 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:19 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:20 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:20 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:20 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:20 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:20 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:20 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:20 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:20 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:20 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:20 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:21 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:21 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:21 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:21 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:21 volumio volumio[5453]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 18:23:21 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 14 18:23:21 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 18:23:21 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 14 18:23:21 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:23:21 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:23:21 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:23:21 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:23:21 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:23:21 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:23:21 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:23:21 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 14 18:23:21 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 14 18:23:21 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:21 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:21 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:21 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:21 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:21 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:21 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:21 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:22 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:22 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:22 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 14 18:23:22 volumio volumio[5453]: info: Received Get System Info Oct 14 18:23:22 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 18:23:22 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 18:23:22 volumio volumio[5453]: info: Discovery: Getting this device information Oct 14 18:23:22 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:22 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:22 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 18:23:22 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:22 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:22 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:22 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:22 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:22 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:22 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:22 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:23 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:23 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:23 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:23 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:23 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:23 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:23 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:23 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:23 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:23 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:23 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:24 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:24 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:24 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:24 volumio volumio[5453]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 14 18:23:24 volumio volumio[5453]: info: Preparing to save Alsa Options, stopping services first Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:24 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::volumioPause Oct 14 18:23:24 volumio volumio[5453]: info: CoreStateMachine::pause Oct 14 18:23:24 volumio volumio[5453]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Combo384 Amanero"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 14 18:23:24 volumio volumio[5453]: info: Setting mixer PCM for card Combo384 Amanero Oct 14 18:23:24 volumio volumio[5453]: info: QobuzConnect: setDeactiveState invoked Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:24 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:24 volumio vtcs[27439]: [2026-10-14 18:23:24.534] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 14 18:23:24 volumio vtcs[27439]: [2026-10-14 18:23:24.534] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 14 18:23:24 volumio sudo[30620]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 18:23:24 volumio sudo[30620]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 14 18:23:24 volumio volumio[5453]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 14 18:23:24 volumio volumio[5453]: info: Disabling external Volume Control Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 18:23:24 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 14 18:23:24 volumio volumio[5453]: info: Preparing to generate the ALSA configuration file Oct 14 18:23:24 volumio systemd[1]: vtcs.service: Deactivated successfully. Oct 14 18:23:24 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 14 18:23:24 volumio sudo[30620]: pam_unix(sudo:session): session closed for user root Oct 14 18:23:24 volumio sudo[30627]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 18:23:24 volumio sudo[30627]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:23:24 volumio volumio[5453]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 14 18:23:24 volumio volumio[5453]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Oct 14 18:23:24 volumio volumio[5453]: info: Reading ALSA contributions from plugins. Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:24 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:24 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:24 volumio sudo[30635]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 14 18:23:24 volumio sudo[30635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:23:24 volumio sudo[30627]: pam_unix(sudo:session): session closed for user root Oct 14 18:23:24 volumio sudo[30635]: pam_unix(sudo:session): session closed for user root Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 14 18:23:24 volumio sudo[30642]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 14 18:23:24 volumio sudo[30642]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 14 18:23:24 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 14 18:23:24 volumio volumio[5453]: info: VolumeController:: Volume=100 Mute =false Oct 14 18:23:24 volumio volumio[5453]: info: CoreStateMachine::pushState Oct 14 18:23:24 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::volumioPushState Oct 14 18:23:24 volumio qobuz-connect[27397]: 20261014 18:23:24.912 [27397.27397] INFO SampleApp: Stopping Local configuration server Oct 14 18:23:24 volumio volumio[5453]: info: MRS: Pushing multiroomSync output update for this device Oct 14 18:23:24 volumio volumio[5453]: info: MRS: Pushing multiroomSync output Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:24 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:24 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:24.928+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" state=STATUS_PAUSED positionMs=58286 volume=100 Oct 14 18:23:24 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:24.929+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" id="mnt/USB/Muzyka/Nowy 3/Chandelier - Sia.flac" title=Chandelier Oct 14 18:23:24 volumio volumio[5453]: info: Asound.conf file unchanged, so no further update is needed Oct 14 18:23:24 volumio volumio[5453]: info: Output device has changed, restarting MPD Oct 14 18:23:24 volumio volumio[5453]: info: Output device has changed, restarting Shairport Sync Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:23:24 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 18:23:25 volumio sudo[30649]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 14 18:23:25 volumio sudo[30649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:23:25 volumio sudo[30649]: pam_unix(sudo:session): session closed for user root Oct 14 18:23:25 volumio sudo[30651]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 14 18:23:25 volumio sudo[30651]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:23:25 volumio volumio[5453]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 14 18:23:25 volumio volumio[5453]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 14 18:23:25 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:23:25 volumio volumio[5453]: info: QobuzConnect: setDeactiveState invoked Oct 14 18:23:25 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:25 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:25 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 14 18:23:25 volumio sudo[30661]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 18:23:25 volumio sudo[30661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:23:25 volumio sudo[30663]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 18:23:25 volumio volumio[5453]: info: MPD Permissions set Oct 14 18:23:25 volumio sudo[30663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:23:25 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:25 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:25 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:23:25 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:23:25 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:23:25 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:23:25 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:23:25 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:23:25 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:23:25 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:23:25 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 14 18:23:25 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 14 18:23:25 volumio systemd[1]: mpd.service: Consumed 3.695s CPU time. Oct 14 18:23:25 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 14 18:23:25 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 14 18:23:25 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 18:23:25 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:23:25 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 14 18:23:25 volumio volumio[5453]: info: Starting Shairport Sync Oct 14 18:23:25 volumio sudo[30671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 14 18:23:25 volumio sudo[30671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:23:25 volumio sudo[30687]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 14 18:23:25 volumio sudo[30687]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:23:25 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 14 18:23:25 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:25 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:25 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 14 18:23:25 volumio sudo[30671]: pam_unix(sudo:session): session closed for user root Oct 14 18:23:25 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 14 18:23:25 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 14 18:23:25 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 14 18:23:25 volumio systemd[1]: shairport-sync.service: Consumed 2.873s CPU time. Oct 14 18:23:25 volumio sudo[30663]: pam_unix(sudo:session): session closed for user root Oct 14 18:23:25 volumio sudo[30690]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 14 18:23:25 volumio sudo[30690]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:23:25 volumio sudo[30661]: pam_unix(sudo:session): session closed for user root Oct 14 18:23:25 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:25 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:25 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 14 18:23:25 volumio sudo[30689]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 14 18:23:25 volumio sudo[30689]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 14 18:23:25 volumio sudo[30687]: pam_unix(sudo:session): session closed for user root Oct 14 18:23:25 volumio volumio[5453]: info: Shairport-Sync Started Oct 14 18:23:25 volumio sudo[30689]: pam_unix(sudo:session): session closed for user root Oct 14 18:23:25 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:25 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:25 volumio qobuz-connect[27397]: 20261014 18:23:25.831 [27397.27397] INFO SampleApp: shat down connection on UNIX socket Oct 14 18:23:25 volumio volumio[5453]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 14 18:23:25 volumio volumio[5453]: info: QobuzConnect: setDeactiveState invoked Oct 14 18:23:25 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:25 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:25 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 14 18:23:25 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 14 18:23:25 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 14 18:23:25 volumio sudo[30690]: pam_unix(sudo:session): session closed for user root Oct 14 18:23:25 volumio sudo[30642]: pam_unix(sudo:session): session closed for user root Oct 14 18:23:25 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:25 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:26 volumio volumio[5453]: info: Executing endpoint qc_getconfig Oct 14 18:23:26 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 14 18:23:26 volumio qobuz-connect[30719]: 20261014 18:23:26.035 [30719.30719] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 14 18:23:26 volumio volumio[5453]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 14 18:23:26 volumio volumio[5453]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 14 18:23:26 volumio qobuz-connect[30719]: 20261014 18:23:26.045 [30719.30719] INFO VolumeManager: [0xfb8068]: Setting new playback volume: 75 Oct 14 18:23:26 volumio qobuz-connect[30719]: 20261014 18:23:26.045 [30719.30719] INFO VolumeManager: [0xfb8068]: Setting new mute state: 0 Oct 14 18:23:26 volumio qobuz-connect[30719]: 20261014 18:23:26.045 [30719.30719] INFO AudioStreamManager: [0xfb7dc0]: Setting new audio download buffer size: 1048576 Oct 14 18:23:26 volumio qobuz-connect[30719]: 20261014 18:23:26.045 [30719.30719] INFO QobuzConnect: [0xfb8930]: Client initialized! Oct 14 18:23:26 volumio qobuz-connect[30719]: 20261014 18:23:26.045 [30719.30719] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Oct 14 18:23:26 volumio qobuz-connect[30719]: 20261014 18:23:26.082 [30719.30719] INFO LocalConfigManager: [0xfb7ae8]: Starting Local Configuration server Oct 14 18:23:26 volumio qobuz-connect[30719]: 20261014 18:23:26.082 [30719.30719] INFO SampleApp: Starting Local configuration server Oct 14 18:23:26 volumio qobuz-connect[30719]: 20261014 18:23:26.083 [30719.30719] INFO SampleApp: Connected to UNIX socket client 0xfa2818 Oct 14 18:23:26 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:26 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:26 volumio qobuz-connect[30719]: 20261014 18:23:26.184 [30719.30719] INFO SampleApp: Playback volume changed: 75 Oct 14 18:23:26 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:26 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:26 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:26 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:26 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:26 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:26 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:26 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:27 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:27 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:27 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:27 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:27 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:27 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:27 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:27 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:27 volumio volumio[5453]: info: TidalConnect service stoped! Oct 14 18:23:27 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:27 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:27 volumio volumio[5453]: info: TidalConnect service stoped! Oct 14 18:23:27 volumio volumio[5453]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 14 18:23:27 volumio volumio[5453]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 14 18:23:27 volumio sudo[30758]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 14 18:23:27 volumio sudo[30758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:23:27 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 14 18:23:27 volumio sudo[30758]: pam_unix(sudo:session): session closed for user root Oct 14 18:23:28 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:28 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:28 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:28 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:28 volumio volumio[5453]: info: Executing endpoint tc_getconfig Oct 14 18:23:28 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 14 18:23:28 volumio vtcs[30762]: STARTING TidalConnect services, version: 1.6.1 Oct 14 18:23:28 volumio vtcs[30762]: STARTED TidalConnect services. Oct 14 18:23:28 volumio volumio[5453]: info: Executing endpoint tc_connect Oct 14 18:23:28 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 14 18:23:28 volumio volumio[5453]: info: Connecting to TidalConnect Oct 14 18:23:28 volumio volumio[5453]: info: CoreCommandRouter::servicePushState Oct 14 18:23:28 volumio volumio[5453]: info: CoreStateMachine::pushState Oct 14 18:23:28 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:28 volumio volumio[5453]: info: CoreCommandRouter::volumioPushState Oct 14 18:23:28 volumio volumio[5453]: info: MRS: Pushing multiroomSync output update for this device Oct 14 18:23:28 volumio volumio[5453]: info: MRS: Pushing multiroomSync output Oct 14 18:23:28 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:28 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:28 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:28 volumio volumio[5453]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 14 18:23:28 volumio volumio[5453]: info: CoreCommandRouter::servicePushState Oct 14 18:23:28 volumio volumio[5453]: info: CoreStateMachine::pushState Oct 14 18:23:28 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:28 volumio volumio[5453]: info: CoreCommandRouter::volumioPushState Oct 14 18:23:28 volumio volumio[5453]: info: MRS: Pushing multiroomSync output update for this device Oct 14 18:23:28 volumio volumio[5453]: info: MRS: Pushing multiroomSync output Oct 14 18:23:28 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:28 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:28 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:28 volumio volumio[5453]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 14 18:23:28 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:28.304+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" state=STATUS_PAUSED positionMs=58286 volume=100 Oct 14 18:23:28 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:28.304+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" id="mnt/USB/Muzyka/Nowy 3/Chandelier - Sia.flac" title=Chandelier Oct 14 18:23:28 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:28.307+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" state=STATUS_PAUSED positionMs=58286 volume=100 Oct 14 18:23:28 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:28.308+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" id="mnt/USB/Muzyka/Nowy 3/Chandelier - Sia.flac" title=Chandelier Oct 14 18:23:28 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:28 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:28 volumio volumio[5453]: info: TidalConnect service stoped! Oct 14 18:23:28 volumio volumio[5453]: info: TidalConnect service stoped! Oct 14 18:23:28 volumio volumio[5453]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 14 18:23:28 volumio volumio[5453]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 14 18:23:28 volumio sudo[30794]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 14 18:23:28 volumio sudo[30794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:23:28 volumio sudo[30794]: pam_unix(sudo:session): session closed for user root Oct 14 18:23:28 volumio volumio[5453]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 14 18:23:28 volumio volumio[5453]: info: CoreStateMachine::getcurrentVolume Oct 14 18:23:28 volumio volumio[5453]: info: CoreCommandRouter::volumioRetrievevolume Oct 14 18:23:28 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:28 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:28 volumio volumio[5453]: info: VolumeController:: Volume=100 Mute =false Oct 14 18:23:28 volumio volumio[5453]: info: CoreStateMachine::pushState Oct 14 18:23:28 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:28 volumio volumio[5453]: info: CoreCommandRouter::volumioPushState Oct 14 18:23:28 volumio volumio[5453]: info: MRS: Pushing multiroomSync output update for this device Oct 14 18:23:28 volumio volumio[5453]: info: MRS: Pushing multiroomSync output Oct 14 18:23:28 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:28 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:28 volumio volumio[5453]: info: CoreStateMachine::updateTrackBlock Oct 14 18:23:28 volumio volumio[5453]: info: CorePlayQueue::getTrackBlock Oct 14 18:23:28 volumio volumio[5453]: info: CoreCommandRouter::volumioRetrievevolume Oct 14 18:23:28 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:28.712+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" state=STATUS_PAUSED positionMs=58286 volume=100 Oct 14 18:23:28 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:28.712+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" id="mnt/USB/Muzyka/Nowy 3/Chandelier - Sia.flac" title=Chandelier Oct 14 18:23:28 volumio volumio[5453]: info: VolumeController:: Volume=100 Mute =false Oct 14 18:23:28 volumio volumio[5453]: info: CoreStateMachine::pushState Oct 14 18:23:28 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:28 volumio volumio[5453]: info: CoreCommandRouter::volumioPushState Oct 14 18:23:28 volumio volumio[5453]: info: MRS: Pushing multiroomSync output update for this device Oct 14 18:23:28 volumio volumio[5453]: info: MRS: Pushing multiroomSync output Oct 14 18:23:28 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:28 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:28 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:28.792+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" state=STATUS_PAUSED positionMs=58286 volume=100 Oct 14 18:23:28 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:28.792+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" id="mnt/USB/Muzyka/Nowy 3/Chandelier - Sia.flac" title=Chandelier Oct 14 18:23:28 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:28 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:28 volumio mpd[30696]: 2026-10-14T18:23:28 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 14 18:23:28 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 14 18:23:28 volumio sudo[30651]: pam_unix(sudo:session): session closed for user root Oct 14 18:23:28 volumio volumio[5453]: error: updateQueue error: null Oct 14 18:23:28 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:28 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:29 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:29 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:29 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:29 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:29 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:23:29 volumio volumio[5453]: info: VolumeController:: Setting startup Volume 100 Oct 14 18:23:29 volumio volumio[5453]: info: VolumeController::SetAlsaVolume100 Oct 14 18:23:29 volumio volumio[5453]: info: CoreStateMachine::pushState Oct 14 18:23:29 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:29 volumio volumio[5453]: info: CoreCommandRouter::volumioPushState Oct 14 18:23:29 volumio volumio[5453]: info: MRS: Pushing multiroomSync output update for this device Oct 14 18:23:29 volumio volumio[5453]: info: MRS: Pushing multiroomSync output Oct 14 18:23:29 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:29 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:29 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:29.623+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" state=STATUS_PAUSED positionMs=58286 volume=100 Oct 14 18:23:29 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:29.627+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" id="mnt/USB/Muzyka/Nowy 3/Chandelier - Sia.flac" title=Chandelier Oct 14 18:23:29 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:29 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:29 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:29 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:29 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:29 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:30 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:30 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:30 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:30 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:30 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:30 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:30 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:30 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:30 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:30 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:30 volumio volumio[5453]: info: TidalConnect service started! Oct 14 18:23:31 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:31 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:31 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:31 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:31 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:31 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:31 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:31 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:31 volumio volumio[5453]: info: TidalConnect service started! Oct 14 18:23:31 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:31 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:31 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:31 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:32 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:32 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:32 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 14 18:23:32 volumio volumio[5453]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 14 18:23:32 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 14 18:23:32 volumio volumio[5453]: info: Received Get System Version Oct 14 18:23:32 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 14 18:23:32 volumio volumio[5453]: info: Received Get System Info Oct 14 18:23:32 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 18:23:32 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 18:23:32 volumio volumio[5453]: info: Discovery: Getting this device information Oct 14 18:23:32 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:32 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:32 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 18:23:32 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:32 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:32 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:32 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:32 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:32 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:32 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:32 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:33 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:33 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:33 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:33 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:33 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:33 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:33 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:33 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:33 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:33 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:34 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:34 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:34 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:34 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:34 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:34 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:34 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:34 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:34 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:34 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:34 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:34 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:34 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:34 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:35 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:35 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:35 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:35 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:35 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:35 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:35 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:35 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:35 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 14 18:23:35 volumio volumio[5453]: info: CoreCommandRouter::volumioPlay Oct 14 18:23:35 volumio volumio[5453]: info: CoreStateMachine::play index undefined Oct 14 18:23:35 volumio volumio[5453]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 18:23:35 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:35 volumio volumio[5453]: info: CoreStateMachine::startPlaybackTimer Oct 14 18:23:35 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:35 volumio volumio[5453]: info: ControllerMpd::resume Oct 14 18:23:35 volumio volumio[5453]: verbose: ControllerMpd::sendMpdCommand play Oct 14 18:23:35 volumio volumio[5453]: info: sendMpdCommand play took 1 milliseconds Oct 14 18:23:35 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:35 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:35 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:35 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:36 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:36 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:36 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:36 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:36 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:36 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:36 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:36 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:36 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:36 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:37 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:37 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:37 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:37 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:37 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:37 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:37 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:37 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:37 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:37 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:37 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:37 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:38 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:38 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:38 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:38 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:38 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:38 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:38 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:38 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:38 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:38 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:39 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:39 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:39 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:39 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:39 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:39 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:39 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:39 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:39 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:39 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:39 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:39 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:40 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:40 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:40 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:40 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:40 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:40 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:40 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:40 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:40 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:40 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:40 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:40 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:41 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:41 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:41 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 14 18:23:41 volumio volumio[5453]: info: CoreCommandRouter::volumioPlay Oct 14 18:23:41 volumio volumio[5453]: info: CoreStateMachine::play index undefined Oct 14 18:23:41 volumio volumio[5453]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 18:23:41 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:41 volumio volumio[5453]: info: CoreStateMachine::startPlaybackTimer Oct 14 18:23:41 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:41 volumio volumio[5453]: info: ControllerMpd::resume Oct 14 18:23:41 volumio volumio[5453]: verbose: ControllerMpd::sendMpdCommand play Oct 14 18:23:41 volumio volumio[5453]: info: sendMpdCommand play took 2 milliseconds Oct 14 18:23:41 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:41 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:41 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:41 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:41 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:41 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:41 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:41 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:41 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:41 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:42 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:42 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:42 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:42 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:42 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:42 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:42 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:42 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:42 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:42 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:43 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:43 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:43 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:43 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:43 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:43 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:43 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:43 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:43 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:43 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:43 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:43 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:44 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:44 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:44 volumio volumio[5453]: info: CoreCommandRouter::volumioGetVisibleSources Oct 14 18:23:44 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 18:23:44 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:44 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:44 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:44 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:44 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:44 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:44 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:44 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:44 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:44 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:45 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:45 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:45 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:45 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:45 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:45 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:45 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:45 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:45 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:45 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:46 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:46 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:46 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:46 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:46 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:46 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:46 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:46 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:46 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:46 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:46 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:46 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:47 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:47 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:47 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:47 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:47 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:47 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:47 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:47 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:47 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:47 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:48 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:48 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:48 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:48 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:48 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:48 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:48 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:48 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:48 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:48 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:49 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:49 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:49 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:49 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:49 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:49 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:49 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:49 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:49 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:49 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:49 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:49 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:50 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:50 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:50 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:50 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:50 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:50 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:50 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:50 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:50 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:50 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:50 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:50 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:51 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:51 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:51 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:51 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:51 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:51 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:51 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 14 18:23:51 volumio volumio[5453]: info: CoreCommandRouter::volumioPlay Oct 14 18:23:51 volumio volumio[5453]: info: CoreStateMachine::play index 2 Oct 14 18:23:51 volumio volumio[5453]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 18:23:51 volumio volumio[5453]: info: CoreStateMachine::stop Oct 14 18:23:51 volumio volumio[5453]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 18:23:51 volumio volumio[5453]: info: CoreStateMachine::updateTrackBlock Oct 14 18:23:51 volumio volumio[5453]: info: CorePlayQueue::getTrackBlock Oct 14 18:23:51 volumio volumio[5453]: info: CoreStateMachine::stPlaybackTimer Oct 14 18:23:51 volumio volumio[5453]: info: CoreStateMachine::pushState Oct 14 18:23:51 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:51 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 18:23:51 volumio volumio[5453]: info: CoreCommandRouter::volumioPushState Oct 14 18:23:51 volumio volumio[5453]: info: MRS: Pushing multiroomSync output update for this device Oct 14 18:23:51 volumio volumio[5453]: info: MRS: Pushing multiroomSync output Oct 14 18:23:51 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:51 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:51 volumio volumio[5453]: info: CoreStateMachine::serviceStop Oct 14 18:23:51 volumio volumio[5453]: info: CorePlayQueue::getTrack 3 Oct 14 18:23:51 volumio volumio[5453]: info: CoreCommandRouter::serviceStop Oct 14 18:23:51 volumio volumio[5453]: info: ControllerMpd::stop Oct 14 18:23:51 volumio volumio[5453]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 18:23:51 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:51.428+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 18:23:51 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:51.428+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" id="mnt/USB/Muzyka/Nowy 3/Chandelier - Sia.flac" title=Chandelier Oct 14 18:23:51 volumio volumio[5453]: info: sendMpdCommand stop took 24 milliseconds Oct 14 18:23:51 volumio volumio[5453]: info: CoreStateMachine::play index undefined Oct 14 18:23:51 volumio volumio[5453]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 18:23:51 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:51 volumio volumio[5453]: info: CoreStateMachine::startPlaybackTimer Oct 14 18:23:51 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:51 volumio volumio[5453]: verbose: ControllerMpd::clearAddPlayTracks USB/Muzyka/Nowy 3/Bracia Figo Fagot - Nie zaufam nigdy już (Figo&Samogony).flac Oct 14 18:23:51 volumio volumio[5453]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 18:23:51 volumio volumio[5453]: info: sendMpdCommand stop took 2 milliseconds Oct 14 18:23:51 volumio volumio[5453]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 18:23:51 volumio volumio[5453]: info: Oct 14 18:23:51 volumio volumio[5453]: ---------------------------- MPD announces system playlist update Oct 14 18:23:51 volumio volumio[5453]: info: Ignoring MPD Status Update Oct 14 18:23:51 volumio volumio[5453]: info: sendMpdCommand clear took 6 milliseconds Oct 14 18:23:51 volumio volumio[5453]: verbose: ControllerMpd::sendMpdCommand add "USB/Muzyka/Nowy 3/Bracia Figo Fagot - Nie zaufam nigdy już (Figo&Samogony).flac" Oct 14 18:23:51 volumio volumio[5453]: error: updateQueue error: null Oct 14 18:23:51 volumio volumio[5453]: info: Oct 14 18:23:51 volumio volumio[5453]: ---------------------------- MPD announces system playlist update Oct 14 18:23:51 volumio volumio[5453]: info: Ignoring MPD Status Update Oct 14 18:23:51 volumio volumio[5453]: info: ------------------------------ 3ms Oct 14 18:23:51 volumio volumio[5453]: info: sendMpdCommand add "USB/Muzyka/Nowy 3/Bracia Figo Fagot - Nie zaufam nigdy już (Figo&Samogony).flac" took 2 milliseconds Oct 14 18:23:51 volumio volumio[5453]: verbose: ControllerMpd::sendMpdCommand play Oct 14 18:23:51 volumio volumio[5453]: info: ------------------------------ 2ms Oct 14 18:23:51 volumio volumio[5453]: info: sendMpdCommand play took 2 milliseconds Oct 14 18:23:51 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:51 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:51 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:51 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:51 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:51 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:52 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:52 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:52 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 14 18:23:52 volumio volumio[5453]: info: CoreCommandRouter::volumioPlay Oct 14 18:23:52 volumio volumio[5453]: info: CoreStateMachine::play index 2 Oct 14 18:23:52 volumio volumio[5453]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 18:23:52 volumio volumio[5453]: info: CoreStateMachine::stop Oct 14 18:23:52 volumio volumio[5453]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 18:23:52 volumio volumio[5453]: info: CoreStateMachine::play index undefined Oct 14 18:23:52 volumio volumio[5453]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 18:23:52 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:52 volumio volumio[5453]: info: CoreStateMachine::startPlaybackTimer Oct 14 18:23:52 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:52 volumio volumio[5453]: verbose: ControllerMpd::clearAddPlayTracks USB/Muzyka/Nowy 3/Bracia Figo Fagot - Nie zaufam nigdy już (Figo&Samogony).flac Oct 14 18:23:52 volumio volumio[5453]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 18:23:52 volumio volumio[5453]: info: Oct 14 18:23:52 volumio volumio[5453]: ---------------------------- MPD announces state update: player Oct 14 18:23:52 volumio volumio[5453]: info: sendMpdCommand stop took 4 milliseconds Oct 14 18:23:52 volumio volumio[5453]: info: ControllerMpd::getState Oct 14 18:23:52 volumio volumio[5453]: verbose: ControllerMpd::sendMpdCommand status Oct 14 18:23:52 volumio volumio[5453]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 18:23:52 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:52 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:52 volumio volumio[5453]: info: Oct 14 18:23:52 volumio volumio[5453]: ---------------------------- MPD announces system playlist update Oct 14 18:23:52 volumio volumio[5453]: info: Ignoring MPD Status Update Oct 14 18:23:52 volumio volumio[5453]: info: sendMpdCommand status took 44 milliseconds Oct 14 18:23:52 volumio volumio[5453]: info: sendMpdCommand clear took 45 milliseconds Oct 14 18:23:52 volumio volumio[5453]: verbose: ControllerMpd::parseState Oct 14 18:23:52 volumio volumio[5453]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 18:23:52 volumio volumio[5453]: verbose: ControllerMpd::sendMpdCommand add "USB/Muzyka/Nowy 3/Bracia Figo Fagot - Nie zaufam nigdy już (Figo&Samogony).flac" Oct 14 18:23:52 volumio volumio[5453]: error: updateQueue error: null Oct 14 18:23:52 volumio volumio[5453]: info: Oct 14 18:23:52 volumio volumio[5453]: ---------------------------- MPD announces system playlist update Oct 14 18:23:52 volumio volumio[5453]: info: Ignoring MPD Status Update Oct 14 18:23:52 volumio volumio[5453]: info: ------------------------------ 7ms Oct 14 18:23:52 volumio volumio[5453]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 14 18:23:52 volumio volumio[5453]: info: sendMpdCommand add "USB/Muzyka/Nowy 3/Bracia Figo Fagot - Nie zaufam nigdy już (Figo&Samogony).flac" took 2 milliseconds Oct 14 18:23:52 volumio volumio[5453]: verbose: ControllerMpd::parseTrackInfo Oct 14 18:23:52 volumio volumio[5453]: verbose: ControllerMpd::sendMpdCommand play Oct 14 18:23:52 volumio volumio[5453]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 14 18:23:52 volumio volumio[5453]: info: ------------------------------ 52ms Oct 14 18:23:52 volumio volumio[5453]: info: ------------------------------ 5ms Oct 14 18:23:52 volumio volumio[5453]: info: sendMpdCommand play took 4 milliseconds Oct 14 18:23:52 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:52 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:52 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:52 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:52 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:52 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:52 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:52 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:53 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:53 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:53 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:53 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:53 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:53 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:53 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:53 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:53 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:53 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:53 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:53 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:54 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:54 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:54 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:54 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:54 volumio volumio[5453]: info: Oct 14 18:23:54 volumio volumio[5453]: ---------------------------- MPD announces state update: player Oct 14 18:23:54 volumio volumio[5453]: info: ControllerMpd::getState Oct 14 18:23:54 volumio volumio[5453]: verbose: ControllerMpd::sendMpdCommand status Oct 14 18:23:54 volumio volumio[5453]: info: sendMpdCommand status took 31 milliseconds Oct 14 18:23:54 volumio volumio[5453]: verbose: ControllerMpd::parseState Oct 14 18:23:54 volumio volumio[5453]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 18:23:54 volumio volumio[5453]: info: Oct 14 18:23:54 volumio volumio[5453]: ---------------------------- MPD announces state update: player Oct 14 18:23:54 volumio volumio[5453]: info: ControllerMpd::getState Oct 14 18:23:54 volumio volumio[5453]: verbose: ControllerMpd::sendMpdCommand status Oct 14 18:23:54 volumio volumio[5453]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 14 18:23:54 volumio volumio[5453]: verbose: ControllerMpd::parseTrackInfo Oct 14 18:23:54 volumio volumio[5453]: info: ControllerMpd::pushState Oct 14 18:23:54 volumio volumio[5453]: info: CoreCommandRouter::servicePushState Oct 14 18:23:54 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:54 volumio volumio[5453]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":222,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nie zaufam nigdy już (Figo&Samogony)","artist":"Bracia Figo Fagot","album":"Miliony internautów mogą się mylić (Concept Album)","uri":"USB/Muzyka/Nowy 3/Bracia Figo Fagot - Nie zaufam nigdy już (Figo&Samogony).flac","trackType":"flac"} Oct 14 18:23:54 volumio volumio[5453]: verbose: CURRENT POSITION 2 Oct 14 18:23:54 volumio volumio[5453]: info: CoreStateMachine::syncState stateService play Oct 14 18:23:54 volumio volumio[5453]: info: CoreStateMachine::syncState currentStatus stop Oct 14 18:23:54 volumio volumio[5453]: info: ------------------------------ 50ms Oct 14 18:23:54 volumio volumio[5453]: info: sendMpdCommand status took 11 milliseconds Oct 14 18:23:54 volumio volumio[5453]: verbose: ControllerMpd::parseState Oct 14 18:23:54 volumio volumio[5453]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 18:23:54 volumio volumio[5453]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 18:23:54 volumio volumio[5453]: verbose: ControllerMpd::parseTrackInfo Oct 14 18:23:54 volumio volumio[5453]: info: ControllerMpd::pushState Oct 14 18:23:54 volumio volumio[5453]: info: CoreCommandRouter::servicePushState Oct 14 18:23:54 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:54 volumio volumio[5453]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1925,"duration":222,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"923 Kbps","isStreaming":false,"title":"Nie zaufam nigdy już (Figo&Samogony)","artist":"Bracia Figo Fagot","album":"Miliony internautów mogą się mylić (Concept Album)","uri":"USB/Muzyka/Nowy 3/Bracia Figo Fagot - Nie zaufam nigdy już (Figo&Samogony).flac","trackType":"flac"} Oct 14 18:23:54 volumio volumio[5453]: verbose: CURRENT POSITION 2 Oct 14 18:23:54 volumio volumio[5453]: info: CoreStateMachine::syncState stateService play Oct 14 18:23:54 volumio volumio[5453]: info: CoreStateMachine::syncState currentStatus play Oct 14 18:23:54 volumio volumio[5453]: info: Received an update from plugin. extracting info from payload Oct 14 18:23:54 volumio volumio[5453]: info: CoreStateMachine::pushState Oct 14 18:23:54 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:54 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 18:23:54 volumio volumio[5453]: info: CoreCommandRouter::volumioPushState Oct 14 18:23:54 volumio volumio[5453]: info: MRS: Pushing multiroomSync output update for this device Oct 14 18:23:54 volumio volumio[5453]: info: MRS: Pushing multiroomSync output Oct 14 18:23:54 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:54 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:54 volumio volumio[5453]: info: CoreStateMachine::pushState Oct 14 18:23:54 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:54 volumio volumio[5453]: info: CoreCommandRouter::volumioPushState Oct 14 18:23:54 volumio volumio[5453]: info: MRS: Pushing multiroomSync output update for this device Oct 14 18:23:54 volumio volumio[5453]: info: MRS: Pushing multiroomSync output Oct 14 18:23:54 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:54 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:54 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:54.480+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" state=STATUS_PLAYING positionMs=1925 volume=100 Oct 14 18:23:54 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:54.481+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" id="mnt/USB/Muzyka/Nowy 3/Bracia Figo Fagot - Nie zaufam nigdy już (Figo&Samogony).flac" title="Nie zaufam nigdy już (Figo&Samogony)" Oct 14 18:23:54 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:54.487+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" state=STATUS_PLAYING positionMs=1925 volume=100 Oct 14 18:23:54 volumio volumio5-onboarding[2147]: time=2026-10-14T18:23:54.489+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" id="mnt/USB/Muzyka/Nowy 3/Bracia Figo Fagot - Nie zaufam nigdy już (Figo&Samogony).flac" title="Nie zaufam nigdy już (Figo&Samogony)" Oct 14 18:23:54 volumio volumio[5453]: info: ------------------------------ 73ms Oct 14 18:23:54 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:54 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:54 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:54 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:54 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:54 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:55 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:55 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:55 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:55 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:55 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:55 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:55 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:55 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:55 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:55 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:56 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:56 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:56 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:56 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:56 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:56 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:56 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:56 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:56 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:56 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:57 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:57 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:57 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:57 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:57 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:57 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:57 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:57 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:57 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:57 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:57 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:57 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:58 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:58 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:58 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:58 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:58 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:58 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:58 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:58 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:58 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:58 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:58 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:58 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:59 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:59 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:59 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:59 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:59 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:59 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:59 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:59 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:23:59 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:23:59 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:00 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:00 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:00 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:00 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:00 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:00 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:00 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:00 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:00 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:00 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:00 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:00 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:01 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:01 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:01 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:01 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:01 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:01 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:01 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:01 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:01 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:01 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:02 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:02 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:02 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:02 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:02 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:02 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:02 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:02 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:02 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:02 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:03 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:03 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:03 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:03 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:03 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:03 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:03 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:03 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:03 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:03 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:03 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:03 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:04 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:04 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:04 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:04 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:04 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:04 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:04 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:04 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:04 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:04 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:04 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:04 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:05 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:05 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:05 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:05 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:05 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:05 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:05 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:05 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:05 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:05 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:06 volumio volumio[5453]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 18:24:06 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 14 18:24:06 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 18:24:06 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 14 18:24:06 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:24:06 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:24:06 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:24:06 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:24:06 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:24:06 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:24:06 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:24:06 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 14 18:24:06 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 14 18:24:06 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:06 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:06 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:06 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:06 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:06 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:06 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:06 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:06 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:06 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:07 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:07 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:07 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:07 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:07 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:07 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:07 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:07 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:07 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:07 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:07 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:07 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:08 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:08 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:08 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:08 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:08 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:08 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:08 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:08 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:08 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:08 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:09 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:09 volumio volumio[5453]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 14 18:24:09 volumio volumio[5453]: info: Preparing to save Alsa Options, stopping services first Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:09 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::volumioPause Oct 14 18:24:09 volumio volumio[5453]: info: CoreStateMachine::pause Oct 14 18:24:09 volumio volumio[5453]: info: CoreStateMachine::stPlaybackTimer Oct 14 18:24:09 volumio volumio[5453]: info: CoreStateMachine::servicePause Oct 14 18:24:09 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::servicePause Oct 14 18:24:09 volumio volumio[5453]: info: ControllerMpd::pause Oct 14 18:24:09 volumio volumio[5453]: verbose: ControllerMpd::sendMpdCommand pause Oct 14 18:24:09 volumio volumio[5453]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Combo384 Amanero"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 14 18:24:09 volumio volumio[5453]: info: Setting mixer PCM for card Combo384 Amanero Oct 14 18:24:09 volumio volumio[5453]: info: QobuzConnect: setDeactiveState invoked Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:09 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:09 volumio vtcs[30762]: [2026-10-14 18:24:09.218] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 14 18:24:09 volumio vtcs[30762]: [2026-10-14 18:24:09.218] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 14 18:24:09 volumio sudo[31641]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 18:24:09 volumio sudo[31641]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 14 18:24:09 volumio volumio[5453]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 14 18:24:09 volumio volumio[5453]: info: Disabling external Volume Control Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 18:24:09 volumio volumio[5453]: info: Preparing to generate the ALSA configuration file Oct 14 18:24:09 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 14 18:24:09 volumio systemd[1]: vtcs.service: Deactivated successfully. Oct 14 18:24:09 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 14 18:24:09 volumio sudo[31641]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:09 volumio sudo[31650]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 18:24:09 volumio sudo[31650]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:09 volumio volumio[5453]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 14 18:24:09 volumio volumio[5453]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Oct 14 18:24:09 volumio volumio[5453]: info: Reading ALSA contributions from plugins. Oct 14 18:24:09 volumio volumio[5453]: info: Oct 14 18:24:09 volumio volumio[5453]: ---------------------------- MPD announces state update: player Oct 14 18:24:09 volumio volumio[5453]: info: sendMpdCommand pause took 308 milliseconds Oct 14 18:24:09 volumio volumio[5453]: info: ControllerMpd::getState Oct 14 18:24:09 volumio volumio[5453]: verbose: ControllerMpd::sendMpdCommand status Oct 14 18:24:09 volumio sudo[31656]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 14 18:24:09 volumio volumio[5453]: info: sendMpdCommand status took 21 milliseconds Oct 14 18:24:09 volumio volumio[5453]: verbose: ControllerMpd::parseState Oct 14 18:24:09 volumio volumio[5453]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 18:24:09 volumio sudo[31656]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:09 volumio volumio[5453]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 14 18:24:09 volumio volumio[5453]: verbose: ControllerMpd::parseTrackInfo Oct 14 18:24:09 volumio volumio[5453]: info: ControllerMpd::pushState Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::servicePushState Oct 14 18:24:09 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:09 volumio volumio[5453]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":26455,"duration":222,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"932 Kbps","isStreaming":false,"title":"Nie zaufam nigdy już (Figo&Samogony)","artist":"Bracia Figo Fagot","album":"Miliony internautów mogą się mylić (Concept Album)","uri":"USB/Muzyka/Nowy 3/Bracia Figo Fagot - Nie zaufam nigdy już (Figo&Samogony).flac","trackType":"flac"} Oct 14 18:24:09 volumio volumio[5453]: verbose: CURRENT POSITION 2 Oct 14 18:24:09 volumio volumio[5453]: info: CoreStateMachine::syncState stateService pause Oct 14 18:24:09 volumio volumio[5453]: info: CoreStateMachine::syncState currentStatus pause Oct 14 18:24:09 volumio volumio[5453]: info: CoreStateMachine::pushState Oct 14 18:24:09 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::volumioPushState Oct 14 18:24:09 volumio volumio[5453]: info: MRS: Pushing multiroomSync output update for this device Oct 14 18:24:09 volumio volumio[5453]: info: MRS: Pushing multiroomSync output Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:09 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:09 volumio volumio[5453]: info: CoreStateMachine::stPlaybackTimer Oct 14 18:24:09 volumio volumio5-onboarding[2147]: time=2026-10-14T18:24:09.496+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" state=STATUS_PAUSED positionMs=18019 volume=100 Oct 14 18:24:09 volumio volumio5-onboarding[2147]: time=2026-10-14T18:24:09.496+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" id="mnt/USB/Muzyka/Nowy 3/Bracia Figo Fagot - Nie zaufam nigdy już (Figo&Samogony).flac" title="Nie zaufam nigdy już (Figo&Samogony)" Oct 14 18:24:09 volumio sudo[31650]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:09 volumio volumio[5453]: info: ------------------------------ 96ms Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:09 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:09 volumio sudo[31656]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:09 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:09 volumio volumio[5453]: info: VolumeController:: Volume=100 Mute =false Oct 14 18:24:09 volumio sudo[31660]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 14 18:24:09 volumio sudo[31660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:09 volumio volumio[5453]: info: CoreStateMachine::pushState Oct 14 18:24:09 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::volumioPushState Oct 14 18:24:09 volumio volumio[5453]: info: MRS: Pushing multiroomSync output update for this device Oct 14 18:24:09 volumio volumio[5453]: info: MRS: Pushing multiroomSync output Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:09 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:09 volumio volumio5-onboarding[2147]: time=2026-10-14T18:24:09.575+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" state=STATUS_PAUSED positionMs=18019 volume=100 Oct 14 18:24:09 volumio volumio5-onboarding[2147]: time=2026-10-14T18:24:09.575+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" id="mnt/USB/Muzyka/Nowy 3/Bracia Figo Fagot - Nie zaufam nigdy już (Figo&Samogony).flac" title="Nie zaufam nigdy już (Figo&Samogony)" Oct 14 18:24:09 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 14 18:24:09 volumio qobuz-connect[30719]: 20261014 18:24:09.598 [30719.30719] INFO SampleApp: Stopping Local configuration server Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 14 18:24:09 volumio volumio[5453]: info: Asound.conf file unchanged, so no further update is needed Oct 14 18:24:09 volumio volumio[5453]: info: Output device has changed, restarting MPD Oct 14 18:24:09 volumio volumio[5453]: info: Output device has changed, restarting Shairport Sync Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 18:24:09 volumio sudo[31670]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 14 18:24:09 volumio sudo[31670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:09 volumio sudo[31670]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:09 volumio volumio[5453]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 14 18:24:09 volumio volumio[5453]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:09 volumio sudo[31672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 14 18:24:09 volumio sudo[31672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:09 volumio volumio[5453]: info: QobuzConnect: setDeactiveState invoked Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:09 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:09 volumio sudo[31689]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 18:24:09 volumio sudo[31689]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:09 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 14 18:24:09 volumio sudo[31691]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 18:24:09 volumio sudo[31691]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:09 volumio volumio[5453]: info: MPD Permissions set Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:09 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:09 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:10 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:10 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 14 18:24:10 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 14 18:24:10 volumio systemd[1]: mpd.service: Consumed 4.404s CPU time. Oct 14 18:24:10 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 14 18:24:10 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 14 18:24:10 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 14 18:24:10 volumio sudo[31699]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 14 18:24:10 volumio sudo[31699]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:10 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:10 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:10 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:10 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:10 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:10 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:10 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:10 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:10 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 18:24:10 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:10 volumio volumio[5453]: info: Starting Shairport Sync Oct 14 18:24:10 volumio sudo[31708]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 14 18:24:10 volumio sudo[31708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:10 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 14 18:24:10 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 14 18:24:10 volumio qobuz-connect[30719]: 20261014 18:24:10.138 [30719.30719] INFO SampleApp: shat down connection on UNIX socket Oct 14 18:24:10 volumio volumio[5453]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 14 18:24:10 volumio volumio[5453]: info: QobuzConnect: setDeactiveState invoked Oct 14 18:24:10 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:10 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:10 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 14 18:24:10 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 14 18:24:10 volumio sudo[31689]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:10 volumio sudo[31691]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:10 volumio sudo[31699]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:10 volumio sudo[31714]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 14 18:24:10 volumio sudo[31714]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:10 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:10 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:10 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 14 18:24:10 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 14 18:24:10 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 14 18:24:10 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 14 18:24:10 volumio systemd[1]: shairport-sync.service: Consumed 1.932s CPU time. Oct 14 18:24:10 volumio sudo[31660]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:10 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 14 18:24:10 volumio sudo[31708]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:10 volumio sudo[31710]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 14 18:24:10 volumio volumio[5453]: info: Shairport-Sync Started Oct 14 18:24:10 volumio sudo[31710]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 14 18:24:10 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 14 18:24:10 volumio sudo[31710]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:10 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 14 18:24:10 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 14 18:24:10 volumio volumio[5453]: info: Executing endpoint qc_getconfig Oct 14 18:24:10 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 14 18:24:10 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 14 18:24:10 volumio sudo[31714]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:10 volumio volumio[5453]: info: Executing endpoint qc_getconfig Oct 14 18:24:10 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 14 18:24:10 volumio qobuz-connect[31719]: 20261014 18:24:10.597 [31719.31719] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 14 18:24:10 volumio volumio[5453]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 14 18:24:10 volumio volumio[5453]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 14 18:24:10 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:10 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:10 volumio qobuz-connect[31719]: 20261014 18:24:10.611 [31719.31719] INFO VolumeManager: [0x226d068]: Setting new playback volume: 75 Oct 14 18:24:10 volumio qobuz-connect[31719]: 20261014 18:24:10.611 [31719.31719] INFO VolumeManager: [0x226d068]: Setting new mute state: 0 Oct 14 18:24:10 volumio qobuz-connect[31719]: 20261014 18:24:10.611 [31719.31719] INFO AudioStreamManager: [0x226cdc0]: Setting new audio download buffer size: 1048576 Oct 14 18:24:10 volumio qobuz-connect[31719]: 20261014 18:24:10.616 [31719.31719] INFO QobuzConnect: [0x226d930]: Client initialized! Oct 14 18:24:10 volumio qobuz-connect[31719]: 20261014 18:24:10.616 [31719.31719] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Oct 14 18:24:10 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:10 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:10 volumio qobuz-connect[31719]: 20261014 18:24:10.658 [31719.31719] INFO LocalConfigManager: [0x226cae8]: Starting Local Configuration server Oct 14 18:24:10 volumio qobuz-connect[31719]: 20261014 18:24:10.658 [31719.31719] INFO SampleApp: Starting Local configuration server Oct 14 18:24:10 volumio qobuz-connect[31719]: 20261014 18:24:10.659 [31719.31719] INFO SampleApp: Connected to UNIX socket client 0x2257818 Oct 14 18:24:10 volumio qobuz-connect[31719]: 20261014 18:24:10.750 [31719.31719] INFO SampleApp: Playback volume changed: 75 Oct 14 18:24:10 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:10 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:10 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:10 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:11 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:11 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:11 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:11 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:11 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:11 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:11 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:11 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:11 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:11 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:12 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:12 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:12 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:12 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:12 volumio volumio[5453]: info: TidalConnect service stoped! Oct 14 18:24:12 volumio volumio[5453]: info: TidalConnect service stoped! Oct 14 18:24:12 volumio volumio[5453]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 14 18:24:12 volumio volumio[5453]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 14 18:24:12 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:12 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:12 volumio sudo[31801]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 14 18:24:12 volumio sudo[31801]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:12 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 14 18:24:12 volumio sudo[31801]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:12 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:12 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:12 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:12 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:13 volumio volumio[5453]: info: Executing endpoint tc_getconfig Oct 14 18:24:13 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 14 18:24:13 volumio vtcs[31811]: STARTING TidalConnect services, version: 1.6.1 Oct 14 18:24:13 volumio vtcs[31811]: STARTED TidalConnect services. Oct 14 18:24:13 volumio volumio[5453]: info: Executing endpoint tc_connect Oct 14 18:24:13 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 14 18:24:13 volumio volumio[5453]: info: Connecting to TidalConnect Oct 14 18:24:13 volumio volumio[5453]: info: CoreCommandRouter::servicePushState Oct 14 18:24:13 volumio volumio[5453]: info: CoreStateMachine::pushState Oct 14 18:24:13 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:13 volumio volumio[5453]: info: CoreCommandRouter::volumioPushState Oct 14 18:24:13 volumio volumio[5453]: info: MRS: Pushing multiroomSync output update for this device Oct 14 18:24:13 volumio volumio[5453]: info: MRS: Pushing multiroomSync output Oct 14 18:24:13 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:13 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:13 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:13 volumio volumio[5453]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 14 18:24:13 volumio volumio[5453]: info: CoreCommandRouter::servicePushState Oct 14 18:24:13 volumio volumio[5453]: info: CoreStateMachine::pushState Oct 14 18:24:13 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:13 volumio volumio[5453]: info: CoreCommandRouter::volumioPushState Oct 14 18:24:13 volumio volumio[5453]: info: MRS: Pushing multiroomSync output update for this device Oct 14 18:24:13 volumio volumio[5453]: info: MRS: Pushing multiroomSync output Oct 14 18:24:13 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:13 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:13 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:13 volumio volumio[5453]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 14 18:24:13 volumio volumio5-onboarding[2147]: time=2026-10-14T18:24:13.138+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" state=STATUS_PAUSED positionMs=18019 volume=100 Oct 14 18:24:13 volumio volumio5-onboarding[2147]: time=2026-10-14T18:24:13.138+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" state=STATUS_PAUSED positionMs=18019 volume=100 Oct 14 18:24:13 volumio volumio5-onboarding[2147]: time=2026-10-14T18:24:13.141+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" id="mnt/USB/Muzyka/Nowy 3/Bracia Figo Fagot - Nie zaufam nigdy już (Figo&Samogony).flac" title="Nie zaufam nigdy już (Figo&Samogony)" Oct 14 18:24:13 volumio volumio5-onboarding[2147]: time=2026-10-14T18:24:13.141+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" id="mnt/USB/Muzyka/Nowy 3/Bracia Figo Fagot - Nie zaufam nigdy już (Figo&Samogony).flac" title="Nie zaufam nigdy już (Figo&Samogony)" Oct 14 18:24:13 volumio volumio[5453]: info: TidalConnect service stoped! Oct 14 18:24:13 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:13 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:13 volumio volumio[5453]: info: TidalConnect service stoped! Oct 14 18:24:13 volumio volumio[5453]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 14 18:24:13 volumio volumio[5453]: info: CoreStateMachine::getcurrentVolume Oct 14 18:24:13 volumio volumio[5453]: info: CoreCommandRouter::volumioRetrievevolume Oct 14 18:24:13 volumio volumio[5453]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 14 18:24:13 volumio volumio[5453]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 14 18:24:13 volumio volumio[5453]: info: VolumeController:: Volume=100 Mute =false Oct 14 18:24:13 volumio volumio[5453]: info: CoreStateMachine::pushState Oct 14 18:24:13 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:13 volumio volumio[5453]: info: CoreCommandRouter::volumioPushState Oct 14 18:24:13 volumio volumio[5453]: info: MRS: Pushing multiroomSync output update for this device Oct 14 18:24:13 volumio volumio[5453]: info: MRS: Pushing multiroomSync output Oct 14 18:24:13 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:13 volumio sudo[31846]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 14 18:24:13 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:13 volumio volumio[5453]: info: CoreStateMachine::updateTrackBlock Oct 14 18:24:13 volumio volumio[5453]: info: CorePlayQueue::getTrackBlock Oct 14 18:24:13 volumio volumio[5453]: info: CoreCommandRouter::volumioRetrievevolume Oct 14 18:24:13 volumio sudo[31846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:13 volumio volumio5-onboarding[2147]: time=2026-10-14T18:24:13.451+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" state=STATUS_PAUSED positionMs=18019 volume=100 Oct 14 18:24:13 volumio volumio5-onboarding[2147]: time=2026-10-14T18:24:13.451+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" id="mnt/USB/Muzyka/Nowy 3/Bracia Figo Fagot - Nie zaufam nigdy już (Figo&Samogony).flac" title="Nie zaufam nigdy już (Figo&Samogony)" Oct 14 18:24:13 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:13 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:13 volumio sudo[31846]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:13 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:13 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:13 volumio volumio[5453]: info: VolumeController:: Volume=100 Mute =false Oct 14 18:24:13 volumio volumio[5453]: info: CoreStateMachine::pushState Oct 14 18:24:13 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:13 volumio volumio[5453]: info: CoreCommandRouter::volumioPushState Oct 14 18:24:13 volumio volumio[5453]: info: MRS: Pushing multiroomSync output update for this device Oct 14 18:24:13 volumio volumio[5453]: info: MRS: Pushing multiroomSync output Oct 14 18:24:13 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:13 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:13 volumio volumio5-onboarding[2147]: time=2026-10-14T18:24:13.571+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" state=STATUS_PAUSED positionMs=18019 volume=100 Oct 14 18:24:13 volumio volumio5-onboarding[2147]: time=2026-10-14T18:24:13.572+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.5:57730 @ 0x2894030" id="mnt/USB/Muzyka/Nowy 3/Bracia Figo Fagot - Nie zaufam nigdy już (Figo&Samogony).flac" title="Nie zaufam nigdy już (Figo&Samogony)" Oct 14 18:24:13 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:13 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:13 volumio mpd[31722]: 2026-10-14T18:24:13 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 14 18:24:13 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 14 18:24:13 volumio sudo[31672]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:13 volumio volumio[5453]: error: updateQueue error: null Oct 14 18:24:13 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:13 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:14 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:14 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:14 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:14 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:14 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:14 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:14 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:14 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:14 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:14 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:15 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:15 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:15 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:15 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:15 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:15 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:15 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:15 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:15 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:15 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:15 volumio volumio[5453]: info: TidalConnect service started! Oct 14 18:24:16 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:16 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:16 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:16 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:16 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:16 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:16 volumio volumio[5453]: info: TidalConnect service started! Oct 14 18:24:16 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:16 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:16 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:16 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:17 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:17 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:17 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:17 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:17 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:17 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:17 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:17 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:17 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:17 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:17 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:17 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:17 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:17 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:18 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:18 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:18 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:18 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:18 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:18 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:18 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:18 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:18 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:18 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:19 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:19 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:19 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:19 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:19 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:19 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:19 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:19 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:19 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:19 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:19 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:19 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:19 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 14 18:24:19 volumio volumio[5453]: info: CoreCommandRouter::volumioPlay Oct 14 18:24:19 volumio volumio[5453]: info: CoreStateMachine::play index undefined Oct 14 18:24:19 volumio volumio[5453]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 18:24:19 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:19 volumio volumio[5453]: info: CoreStateMachine::startPlaybackTimer Oct 14 18:24:19 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:19 volumio volumio[5453]: info: ControllerMpd::resume Oct 14 18:24:19 volumio volumio[5453]: verbose: ControllerMpd::sendMpdCommand play Oct 14 18:24:19 volumio volumio[5453]: info: sendMpdCommand play took 1 milliseconds Oct 14 18:24:19 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:19 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:20 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:20 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:20 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:20 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:20 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:20 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:20 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:20 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:20 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:20 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:21 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:21 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:21 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:21 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:21 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:21 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:21 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:21 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:21 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:21 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:22 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:22 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:22 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:22 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:22 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:22 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:22 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:22 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:22 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:22 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:23 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:23 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:23 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:23 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:23 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:23 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:23 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:23 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:23 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:23 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:23 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:23 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:23 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:23 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:24 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:24 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:24 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:24 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:24 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:24 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:24 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:24 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:24 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:24 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:25 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:25 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:25 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:25 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:25 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:25 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:25 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:25 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:25 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:25 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:26 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:26 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:26 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:26 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:26 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:26 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:26 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:26 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:26 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:26 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:26 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:26 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:26 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:27 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:27 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:27 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:27 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:27 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:27 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:27 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:27 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:27 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:27 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:28 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:28 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:28 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:28 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:28 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:28 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:28 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 14 18:24:28 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 14 18:24:28 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:28 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:28 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:28 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:28 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:28 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:29 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:29 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:29 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:29 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:29 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:29 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:29 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:29 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:29 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:29 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:29 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:29 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:30 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:30 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:30 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:30 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:30 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:30 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:30 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:30 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:30 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:30 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:31 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:31 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:31 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:31 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:31 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:31 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:31 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:31 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:31 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:31 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:32 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:32 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:32 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:32 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:32 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:32 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:32 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:32 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:32 volumio sudo[32239]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd_oled_plugin.service Oct 14 18:24:32 volumio sudo[32239]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:32 volumio volumio[5453]: info: Disabling plugin mpd_oled Oct 14 18:24:32 volumio volumio[5453]: info: Preparing to generate the ALSA configuration file Oct 14 18:24:32 volumio volumio[5453]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 14 18:24:32 volumio volumio[5453]: info: Reading ALSA contributions from plugins. Oct 14 18:24:32 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:32 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:32 volumio sudo[32239]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:32 volumio volumio[5453]: info: Asound.conf file written Oct 14 18:24:32 volumio sudo[32247]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 14 18:24:32 volumio sudo[32247]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:32 volumio sudo[32247]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:32 volumio volumio[5453]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 14 18:24:32 volumio volumio[5453]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 14 18:24:32 volumio volumio[5453]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 14 18:24:32 volumio volumio[5453]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Oct 14 18:24:32 volumio volumio[5453]: info: Output device has changed, restarting MPD Oct 14 18:24:32 volumio volumio[5453]: info: Output device has changed, restarting Shairport Sync Oct 14 18:24:32 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:32 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 18:24:32 volumio sudo[32253]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 14 18:24:32 volumio sudo[32253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:32 volumio sudo[32255]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 14 18:24:32 volumio sudo[32255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:32 volumio sudo[32253]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:32 volumio volumio[5453]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 14 18:24:32 volumio volumio[5453]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 14 18:24:32 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:32 volumio volumio[5453]: info: QobuzConnect: setDeactiveState invoked Oct 14 18:24:32 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:32 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:32 volumio vtcs[31811]: [2026-10-14 18:24:32.961] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 14 18:24:32 volumio vtcs[31811]: [2026-10-14 18:24:32.962] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 14 18:24:32 volumio volumio[5453]: info: Done. Oct 14 18:24:32 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 14 18:24:33 volumio sudo[32265]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 18:24:33 volumio sudo[32265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:33 volumio sudo[32267]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 18:24:33 volumio sudo[32267]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:33 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 14 18:24:33 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 14 18:24:33 volumio systemd[1]: mpd.service: Consumed 3.624s CPU time. Oct 14 18:24:33 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 14 18:24:33 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 14 18:24:33 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 14 18:24:33 volumio volumio[5453]: info: MPD Permissions set Oct 14 18:24:33 volumio volumio[5453]: info: [MPD_OLED Plugin] Stopped mpd_oled_plugin service Oct 14 18:24:33 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:33 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:33 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:33 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:33 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:33 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:33 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:33 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:33 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:33 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:33 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 14 18:24:33 volumio sudo[32283]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 14 18:24:33 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 14 18:24:33 volumio sudo[32283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:33 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 18:24:33 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:33 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:33 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:33 volumio volumio[5453]: info: Starting Shairport Sync Oct 14 18:24:33 volumio sudo[32283]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:33 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 14 18:24:33 volumio sudo[32293]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 14 18:24:33 volumio sudo[32293]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:33 volumio systemd[1]: vtcs.service: Deactivated successfully. Oct 14 18:24:33 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 14 18:24:33 volumio sudo[32295]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 14 18:24:33 volumio sudo[32295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:33 volumio sudo[32265]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:33 volumio sudo[32267]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:33 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 14 18:24:33 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 14 18:24:33 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 14 18:24:33 volumio systemd[1]: shairport-sync.service: Consumed 1.718s CPU time. Oct 14 18:24:33 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 14 18:24:33 volumio qobuz-connect[31719]: 20261014 18:24:33.383 [31719.31719] INFO SampleApp: Stopping Local configuration server Oct 14 18:24:33 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 14 18:24:33 volumio sudo[32289]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 14 18:24:33 volumio sudo[32289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 14 18:24:33 volumio sudo[32293]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:33 volumio sudo[32289]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:33 volumio volumio[5453]: info: Shairport-Sync Started Oct 14 18:24:33 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:33 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:33 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:33 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:33 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:33 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:34 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:34 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:34 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:34 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:34 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:34 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:34 volumio qobuz-connect[31719]: 20261014 18:24:34.684 [31719.31719] INFO SampleApp: shat down connection on UNIX socket Oct 14 18:24:34 volumio volumio[5453]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 14 18:24:34 volumio volumio[5453]: info: QobuzConnect: setDeactiveState invoked Oct 14 18:24:34 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:34 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:34 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 14 18:24:34 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 14 18:24:34 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 14 18:24:34 volumio sudo[32295]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:34 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:34 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:34 volumio volumio[5453]: info: Executing endpoint qc_getconfig Oct 14 18:24:34 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 14 18:24:34 volumio qobuz-connect[32333]: 20261014 18:24:34.850 [32333.32333] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 14 18:24:34 volumio volumio[5453]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 14 18:24:34 volumio volumio[5453]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 14 18:24:34 volumio qobuz-connect[32333]: 20261014 18:24:34.858 [32333.32333] INFO VolumeManager: [0x11dd068]: Setting new playback volume: 75 Oct 14 18:24:34 volumio qobuz-connect[32333]: 20261014 18:24:34.858 [32333.32333] INFO VolumeManager: [0x11dd068]: Setting new mute state: 0 Oct 14 18:24:34 volumio qobuz-connect[32333]: 20261014 18:24:34.858 [32333.32333] INFO AudioStreamManager: [0x11dcdc0]: Setting new audio download buffer size: 1048576 Oct 14 18:24:34 volumio qobuz-connect[32333]: 20261014 18:24:34.858 [32333.32333] INFO QobuzConnect: [0x11dd930]: Client initialized! Oct 14 18:24:34 volumio qobuz-connect[32333]: 20261014 18:24:34.858 [32333.32333] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Oct 14 18:24:34 volumio qobuz-connect[32333]: 20261014 18:24:34.889 [32333.32333] INFO LocalConfigManager: [0x11dcae8]: Starting Local Configuration server Oct 14 18:24:34 volumio qobuz-connect[32333]: 20261014 18:24:34.889 [32333.32333] INFO SampleApp: Starting Local configuration server Oct 14 18:24:34 volumio qobuz-connect[32333]: 20261014 18:24:34.889 [32333.32333] INFO SampleApp: Connected to UNIX socket client 0x11c7818 Oct 14 18:24:34 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:34 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:34 volumio qobuz-connect[32333]: 20261014 18:24:34.997 [32333.32333] INFO SampleApp: Playback volume changed: 75 Oct 14 18:24:34 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:34 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:35 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:35 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:35 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:35 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:35 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:35 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:35 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:35 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:35 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:35 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:36 volumio volumio[5453]: info: Enabling plugin mpd_oled Oct 14 18:24:36 volumio volumio[5453]: info: Loading plugin "mpd_oled"... Oct 14 18:24:36 volumio volumio[5453]: info: Applying required configuration parameters for plugin mpd_oled Oct 14 18:24:36 volumio volumio[5453]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Oct 14 18:24:36 volumio volumio[5453]: info: Preparing to generate the ALSA configuration file Oct 14 18:24:36 volumio volumio[5453]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 14 18:24:36 volumio volumio[5453]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Oct 14 18:24:36 volumio volumio[5453]: info: Reading ALSA contributions from plugins. Oct 14 18:24:36 volumio volumio[5453]: info: Asound.conf file written Oct 14 18:24:36 volumio sudo[32365]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 14 18:24:36 volumio sudo[32365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:36 volumio sudo[32365]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:36 volumio volumio[5453]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 14 18:24:36 volumio volumio[5453]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 14 18:24:36 volumio volumio[5453]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 14 18:24:36 volumio volumio[5453]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Oct 14 18:24:36 volumio volumio[5453]: info: Output device has changed, restarting MPD Oct 14 18:24:36 volumio volumio[5453]: info: Output device has changed, restarting Shairport Sync Oct 14 18:24:36 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:36 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 18:24:36 volumio sudo[32371]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 14 18:24:36 volumio sudo[32371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:36 volumio sudo[32371]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:36 volumio volumio[5453]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 14 18:24:36 volumio volumio[5453]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 14 18:24:36 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:36 volumio sudo[32374]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 14 18:24:36 volumio sudo[32374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:36 volumio volumio[5453]: info: QobuzConnect: setDeactiveState invoked Oct 14 18:24:36 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:36 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:36 volumio volumio[5453]: info: PLUGIN START: mpd_oled Oct 14 18:24:36 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 14 18:24:36 volumio sudo[32384]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 18:24:36 volumio sudo[32384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:36 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 14 18:24:36 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 14 18:24:36 volumio systemd[1]: mpd.service: Consumed 3.093s CPU time. Oct 14 18:24:36 volumio sudo[32390]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /tmp/mpd_oled_plugin.sh Oct 14 18:24:36 volumio volumio[5453]: info: Preparing to generate the ALSA configuration file Oct 14 18:24:36 volumio sudo[32390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:36 volumio sudo[32390]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:36 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 14 18:24:36 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 14 18:24:36 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 14 18:24:36 volumio sudo[32392]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start mpd_oled_plugin.service Oct 14 18:24:36 volumio sudo[32392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:36 volumio volumio[5453]: info: Done. Oct 14 18:24:36 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 14 18:24:36 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 14 18:24:36 volumio volumio[5453]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 14 18:24:36 volumio volumio[5453]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Oct 14 18:24:36 volumio volumio[5453]: info: Reading ALSA contributions from plugins. Oct 14 18:24:36 volumio volumio[5453]: info: [MPD_OLED Plugin] Set execute permissions on /tmp/mpd_oled_plugin.sh Oct 14 18:24:36 volumio volumio[5453]: info: MPD Permissions set Oct 14 18:24:36 volumio volumio[5453]: info: TidalConnect service stoped! Oct 14 18:24:36 volumio sudo[32397]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 18:24:36 volumio sudo[32397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:36 volumio sudo[32411]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 14 18:24:36 volumio volumio[5453]: info: TidalConnect service stoped! Oct 14 18:24:36 volumio sudo[32411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:36 volumio volumio[5453]: info: [MPD_OLED Plugin] tmp script created /tmp/mpd_oled_plugin.sh Oct 14 18:24:36 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:36 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:36 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:36 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:36 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:36 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:36 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:36 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:36 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:36 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:36 volumio sudo[32384]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:36 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 18:24:36 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:36 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:36 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:36 volumio volumio[5453]: info: Starting Shairport Sync Oct 14 18:24:36 volumio sudo[32411]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:36 volumio volumio[5453]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 14 18:24:36 volumio volumio[5453]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 14 18:24:36 volumio volumio[5453]: info: Asound.conf file unchanged, so no further update is needed Oct 14 18:24:36 volumio volumio[5453]: info: Output device has changed, restarting MPD Oct 14 18:24:36 volumio volumio[5453]: info: Output device has changed, restarting Shairport Sync Oct 14 18:24:36 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:36 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 18:24:36 volumio sudo[32397]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:36 volumio sudo[32430]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 14 18:24:36 volumio sudo[32430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:36 volumio volumio[5453]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 14 18:24:36 volumio volumio[5453]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 14 18:24:36 volumio sudo[32435]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 14 18:24:36 volumio sudo[32410]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 14 18:24:36 volumio sudo[32431]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 14 18:24:36 volumio sudo[32435]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:36 volumio sudo[32431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:36 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:36 volumio sudo[32410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 14 18:24:36 volumio sudo[32433]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 14 18:24:36 volumio sudo[32410]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:36 volumio sudo[32433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:36 volumio volumio[5453]: info: QobuzConnect: setDeactiveState invoked Oct 14 18:24:36 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:36 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:36 volumio sudo[32433]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:36 volumio sudo[32450]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 18:24:36 volumio sudo[32450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:36 volumio sudo[32452]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 18:24:36 volumio sudo[32452]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:37 volumio volumio[5453]: info: MPD Permissions set Oct 14 18:24:37 volumio sudo[32460]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 14 18:24:37 volumio sudo[32460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:37 volumio volumio[5453]: info: CoreCommandRouter::volumioGetState Oct 14 18:24:37 volumio volumio[5453]: info: CorePlayQueue::getTrack 2 Oct 14 18:24:37 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:37 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:37 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:37 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:37 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:37 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:37 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:37 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:37 volumio sudo[32467]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 14 18:24:37 volumio sudo[32467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:37 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 18:24:37 volumio volumio[5453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 18:24:37 volumio volumio[5453]: info: Starting Shairport Sync Oct 14 18:24:37 volumio sudo[32460]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:37 volumio sudo[32475]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 14 18:24:37 volumio sudo[32452]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:37 volumio sudo[32475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:37 volumio volumio[5453]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Oct 14 18:24:37 volumio volumio[5453]: Job for vtcs.service canceled. Oct 14 18:24:37 volumio volumio[5453]: {"cmd":"/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3\nJob for vtcs.service canceled.\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 ChildProcess._handle.onexit (node:internal/child_process:305:5)","stderr":"Job for vtcs.service canceled.\n","stdout":""} Oct 14 18:24:37 volumio sudo[32450]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:37 volumio qobuz-connect[32333]: 20261014 18:24:37.209 [32333.32333] INFO SampleApp: Stopping Local configuration server Oct 14 18:24:37 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 14 18:24:37 volumio volumio[5453]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 18:24:37 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 14 18:24:37 volumio sudo[32476]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 14 18:24:37 volumio sudo[32476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 18:24:37 volumio volumio[5453]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Oct 14 18:24:37 volumio volumio[5453]: Job for vtcs.service canceled. Oct 14 18:24:37 volumio volumio[5453]: at ChildProcess.exithandler (node:child_process:421:12) Oct 14 18:24:37 volumio volumio[5453]: at ChildProcess.emit (node:events:514:28) Oct 14 18:24:37 volumio volumio[5453]: at maybeClose (node:internal/child_process:1105:16) Oct 14 18:24:37 volumio volumio[5453]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) { Oct 14 18:24:37 volumio volumio[5453]: code: 1, Oct 14 18:24:37 volumio volumio[5453]: killed: false, Oct 14 18:24:37 volumio volumio[5453]: signal: null, Oct 14 18:24:37 volumio volumio[5453]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Oct 14 18:24:37 volumio volumio[5453]: stdout: '', Oct 14 18:24:37 volumio volumio[5453]: stderr: 'Job for vtcs.service canceled.\n' Oct 14 18:24:37 volumio volumio[5453]: } Oct 14 18:24:37 volumio volumio[5453]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 18:24:37 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 14 18:24:37 volumio sudo[32467]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:37 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 14 18:24:37 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 14 18:24:37 volumio systemd[1]: shairport-sync.service: Consumed 1.633s CPU time. Oct 14 18:24:37 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 14 18:24:37 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 14 18:24:37 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 14 18:24:37 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 14 18:24:37 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 14 18:24:37 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 14 18:24:37 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 14 18:24:37 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 14 18:24:37 volumio sudo[32431]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:37 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 14 18:24:37 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 14 18:24:37 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 14 18:24:37 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 14 18:24:37 volumio sudo[32475]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:37 volumio sudo[32503]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 14 18:24:37 volumio sudo[32503]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 14 18:24:37 volumio sudo[32503]: pam_unix(sudo:session): session closed for user root Oct 14 18:24:38 volumio sudo[32524]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-14 18:23' Oct 14 18:24:38 volumio sudo[32524]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"