Feb 21 07:22:27 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:22:27.325-05:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.7.207:54032 Feb 21 07:22:27 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:22:27.434-05:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.7.207:54032 @ 0x254a1b0" latency=12.424988ms platform=PLATFORM_IOS version=5.260120.0 Feb 21 07:22:27 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:22:27.434-05:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.7.207:54032 @ 0x254a1b0" latency=11.783007ms timeout=10s Feb 21 07:22:27 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:22:27.434-05:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: Received Get System Info Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: Discovery: Getting this device information Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetState Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: CorePlayQueue::getTrack 0 Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 07:22:27 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:22:27.439-05:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" name="Volumio Pi 3B" Feb 21 07:22:27 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:22:27.442-05:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" language=en Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 21 07:22:27 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:22:27.445-05:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" timezone=America/Indiana/Indianapolis Feb 21 07:22:27 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:22:27.447-05:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" available=true connected=false macAddress= ip4Address= ip6Address= Feb 21 07:22:27 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:22:27.464-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" available=true connected=true macAddress=b8:27:eb:04:8a:91 ip4Address=192.168.7.228/24 ip6Address= ssid=LV-426 Feb 21 07:22:27 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:22:27.465-05:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" setupComplete=true Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 07:22:27 volumio-pi-3b volumio[1173]: amixer -c 0 info | grep "bcm2835 ALSA" Feb 21 07:22:27 volumio-pi-3b volumio[1173]: amixer -c 1 info | grep "bcm2835 Headphones" Feb 21 07:22:27 volumio-pi-3b volumio[1173]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Feb 21 07:22:27 volumio-pi-3b volumio[1173]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 2 Feb 21 07:22:27 volumio-pi-3b volumio[1173]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 21 07:22:27 volumio-pi-3b volumio[1173]: {"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)"} Feb 21 07:22:27 volumio-pi-3b volumio[1173]: amixer -c 2 info | grep "vc4-hdmi" Feb 21 07:22:27 volumio-pi-3b volumio[1173]: Card sysdefault:2 'vc4hdmi'/'vc4-hdmi' Feb 21 07:22:27 volumio-pi-3b volumio[1173]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Feb 21 07:22:27 volumio-pi-3b volumio[1173]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 21 07:22:27 volumio-pi-3b volumio[1173]: {"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)"} Feb 21 07:22:27 volumio-pi-3b volumio[1173]: amixer -c 3 info | grep "Raspberry Pi DAC+" Feb 21 07:22:27 volumio-pi-3b volumio[1173]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Feb 21 07:22:27 volumio-pi-3b volumio[1173]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 21 07:22:27 volumio-pi-3b volumio[1173]: {"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)"} Feb 21 07:22:27 volumio-pi-3b volumio[1173]: amixer -c 3 info | grep "RPi DAC+" Feb 21 07:22:27 volumio-pi-3b volumio[1173]: Card sysdefault:3 'DAC'/'RPi DAC+' Feb 21 07:22:27 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:22:27.704-05:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" selectedOutputId=3 Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: Received Get System Info Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: Discovery: Getting this device information Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetState Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: CorePlayQueue::getTrack 0 Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 07:22:27 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:22:27.730-05:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" currentVersion=4.096 latestVersion=4.096 Feb 21 07:22:27 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:22:27.730-05:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" status=UPDATE_STATUS_NONE progress=0 Feb 21 07:22:27 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:22:27.730-05:00 level=INFO msg="emitting user changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" userId= Feb 21 07:22:27 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:22:27.730-05:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" providers=3 Feb 21 07:22:27 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:22:27.731-05:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" plugins=60 Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetState Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: CorePlayQueue::getTrack 0 Feb 21 07:22:27 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:22:27.739-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" state=STATUS_STOPPED positionMs=349 volume=100 Feb 21 07:22:27 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:22:27.740-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" id="https://live.amperwave.net/direct/adamsradio-wjfxfmmp3-ibc1?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MTYzOTA3MCwiaXNzIjoidGlzcnYifQ.nLuvg_3B8ux2RQiqCZKkpvJamz-FClKNc9_nTMlpBtU" title="Hot 107.9" Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: Discovery: Getting this device information Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetState Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: CorePlayQueue::getTrack 0 Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 07:22:27 volumio-pi-3b volumio[1173]: verbose: New Socket.io Connection to 192.168.7.228:3000 from 192.168.7.207 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 07:22:27 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 07:23:06 volumio-pi-3b volumio[1173]: info: Discovery: A device disappeared from network Feb 21 07:23:11 volumio-pi-3b volumio[1173]: info: Discovery: adding 2c792c11-3960-484c-b455-a460ef4874ce Feb 21 07:23:11 volumio-pi-3b volumio[1173]: info: Discovery: Found device Volumio Pi 4 Office Feb 21 07:23:16 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:16.808-05:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.7.207:54032 @ 0x254a1b0" latency=10.40722ms platform=PLATFORM_IOS version=5.260120.0 Feb 21 07:23:16 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:16.831-05:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.7.207:54032 @ 0x254a1b0" latency=31.588751ms timeout=10s Feb 21 07:23:16 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:16.831-05:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" Feb 21 07:23:16 volumio-pi-3b volumio[1173]: info: Received Get System Info Feb 21 07:23:16 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 07:23:16 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 07:23:16 volumio-pi-3b volumio[1173]: info: Discovery: Getting this device information Feb 21 07:23:16 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetState Feb 21 07:23:16 volumio-pi-3b volumio[1173]: info: CorePlayQueue::getTrack 0 Feb 21 07:23:16 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 07:23:16 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:16.836-05:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" name="Volumio Pi 3B" Feb 21 07:23:16 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:16.837-05:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" language=en Feb 21 07:23:16 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 21 07:23:16 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:16.839-05:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" timezone=America/Indiana/Indianapolis Feb 21 07:23:16 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:16.840-05:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" available=true connected=false macAddress= ip4Address= ip6Address= Feb 21 07:23:16 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:16.845-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" available=true connected=true macAddress=b8:27:eb:04:8a:91 ip4Address=192.168.7.228/24 ip6Address= ssid=LV-426 Feb 21 07:23:16 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:16.845-05:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" setupComplete=true Feb 21 07:23:16 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Feb 21 07:23:16 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 21 07:23:16 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 07:23:16 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 07:23:16 volumio-pi-3b volumio[1173]: amixer -c 0 info | grep "bcm2835 ALSA" Feb 21 07:23:16 volumio-pi-3b volumio[1173]: amixer -c 1 info | grep "bcm2835 Headphones" Feb 21 07:23:16 volumio-pi-3b volumio[1173]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Feb 21 07:23:16 volumio-pi-3b volumio[1173]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 2 Feb 21 07:23:16 volumio-pi-3b volumio[1173]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 21 07:23:16 volumio-pi-3b volumio[1173]: {"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)"} Feb 21 07:23:16 volumio-pi-3b volumio[1173]: amixer -c 2 info | grep "vc4-hdmi" Feb 21 07:23:17 volumio-pi-3b volumio[1173]: Card sysdefault:2 'vc4hdmi'/'vc4-hdmi' Feb 21 07:23:17 volumio-pi-3b volumio[1173]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Feb 21 07:23:17 volumio-pi-3b volumio[1173]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 21 07:23:17 volumio-pi-3b volumio[1173]: {"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)"} Feb 21 07:23:17 volumio-pi-3b volumio[1173]: amixer -c 3 info | grep "Raspberry Pi DAC+" Feb 21 07:23:17 volumio-pi-3b volumio[1173]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Feb 21 07:23:17 volumio-pi-3b volumio[1173]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 21 07:23:17 volumio-pi-3b volumio[1173]: {"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)"} Feb 21 07:23:17 volumio-pi-3b volumio[1173]: amixer -c 3 info | grep "RPi DAC+" Feb 21 07:23:17 volumio-pi-3b volumio[1173]: Card sysdefault:3 'DAC'/'RPi DAC+' Feb 21 07:23:17 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:17.070-05:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" selectedOutputId=3 Feb 21 07:23:17 volumio-pi-3b volumio[1173]: info: Received Get System Info Feb 21 07:23:17 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 07:23:17 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 07:23:17 volumio-pi-3b volumio[1173]: info: Discovery: Getting this device information Feb 21 07:23:17 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetState Feb 21 07:23:17 volumio-pi-3b volumio[1173]: info: CorePlayQueue::getTrack 0 Feb 21 07:23:17 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 07:23:17 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:17.086-05:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" currentVersion=4.096 latestVersion=4.096 Feb 21 07:23:17 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:17.087-05:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" status=UPDATE_STATUS_NONE progress=0 Feb 21 07:23:17 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:17.087-05:00 level=INFO msg="emitting user changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" userId= Feb 21 07:23:17 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:17.087-05:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" providers=3 Feb 21 07:23:17 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:17.088-05:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" plugins=60 Feb 21 07:23:17 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetState Feb 21 07:23:17 volumio-pi-3b volumio[1173]: info: CorePlayQueue::getTrack 0 Feb 21 07:23:17 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:17.092-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" state=STATUS_STOPPED positionMs=349 volume=100 Feb 21 07:23:17 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:17.092-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" id="https://live.amperwave.net/direct/adamsradio-wjfxfmmp3-ibc1?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MTYzOTA3MCwiaXNzIjoidGlzcnYifQ.nLuvg_3B8ux2RQiqCZKkpvJamz-FClKNc9_nTMlpBtU" title="Hot 107.9" Feb 21 07:23:17 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:17.120-05:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.7.207:54032 @ 0x254a1b0" latency=9.062548ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Feb 21 07:23:17 volumio-pi-3b sudo[8002]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 21 07:23:17 volumio-pi-3b sudo[8002]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 07:23:17 volumio-pi-3b sudo[8002]: pam_unix(sudo:session): session closed for user root Feb 21 07:23:17 volumio-pi-3b sudo[8004]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 21 07:23:17 volumio-pi-3b sudo[8004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 07:23:17 volumio-pi-3b sudo[8004]: pam_unix(sudo:session): session closed for user root Feb 21 07:23:17 volumio-pi-3b volumio[1173]: verbose: New Socket.io Connection to 192.168.7.228 from 192.168.7.207 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 6 Feb 21 07:23:17 volumio-pi-3b sudo[8008]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 21 07:23:17 volumio-pi-3b sudo[8008]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 07:23:17 volumio-pi-3b sudo[8008]: pam_unix(sudo:session): session closed for user root Feb 21 07:23:17 volumio-pi-3b sudo[8010]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 21 07:23:17 volumio-pi-3b sudo[8010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 07:23:17 volumio-pi-3b sudo[8010]: pam_unix(sudo:session): session closed for user root Feb 21 07:23:17 volumio-pi-3b volumio[1173]: verbose: New Socket.io Connection to 192.168.7.228 from 192.168.7.207 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 7 Feb 21 07:23:17 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 21 07:23:17 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 21 07:23:18 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetVisibleSources Feb 21 07:23:18 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 07:23:18 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetState Feb 21 07:23:18 volumio-pi-3b volumio[1173]: info: CorePlayQueue::getTrack 0 Feb 21 07:23:18 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 21 07:23:18 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 21 07:23:18 volumio-pi-3b volumio[1173]: info: Received Get System Info Feb 21 07:23:18 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 07:23:18 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 07:23:18 volumio-pi-3b volumio[1173]: info: Discovery: Getting this device information Feb 21 07:23:18 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetState Feb 21 07:23:18 volumio-pi-3b volumio[1173]: info: CorePlayQueue::getTrack 0 Feb 21 07:23:18 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 07:23:18 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetState Feb 21 07:23:18 volumio-pi-3b volumio[1173]: info: CorePlayQueue::getTrack 0 Feb 21 07:23:18 volumio-pi-3b volumio[1173]: info: Listing playlists Feb 21 07:23:18 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 21 07:23:18 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 21 07:23:18 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetState Feb 21 07:23:18 volumio-pi-3b volumio[1173]: info: CorePlayQueue::getTrack 0 Feb 21 07:23:19 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 21 07:23:19 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 07:23:19 volumio-pi-3b volumio[1173]: info: Received Get System Info Feb 21 07:23:19 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 07:23:19 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 07:23:19 volumio-pi-3b volumio[1173]: info: Discovery: Getting this device information Feb 21 07:23:19 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetState Feb 21 07:23:19 volumio-pi-3b volumio[1173]: info: CorePlayQueue::getTrack 0 Feb 21 07:23:19 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 07:23:19 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 07:23:19 volumio-pi-3b volumio[1173]: info: Received Get System Info Feb 21 07:23:19 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 07:23:19 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 07:23:19 volumio-pi-3b volumio[1173]: info: Discovery: Getting this device information Feb 21 07:23:19 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetState Feb 21 07:23:19 volumio-pi-3b volumio[1173]: info: CorePlayQueue::getTrack 0 Feb 21 07:23:19 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 07:23:24 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:24.211-05:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.7.207:54032 @ 0x254a1b0" latency=13.672845ms platform=PLATFORM_IOS version=5.260120.0 Feb 21 07:23:24 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:24.211-05:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.7.207:54032 @ 0x254a1b0" latency=12.81123ms timeout=10s Feb 21 07:23:24 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:24.212-05:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" Feb 21 07:23:24 volumio-pi-3b volumio[1173]: info: Received Get System Info Feb 21 07:23:24 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 07:23:24 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 07:23:24 volumio-pi-3b volumio[1173]: info: Discovery: Getting this device information Feb 21 07:23:24 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetState Feb 21 07:23:24 volumio-pi-3b volumio[1173]: info: CorePlayQueue::getTrack 0 Feb 21 07:23:24 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 07:23:24 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:24.216-05:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" name="Volumio Pi 3B" Feb 21 07:23:24 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:24.218-05:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" language=en Feb 21 07:23:24 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 21 07:23:24 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:24.220-05:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" timezone=America/Indiana/Indianapolis Feb 21 07:23:24 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:24.222-05:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" available=true connected=false macAddress= ip4Address= ip6Address= Feb 21 07:23:24 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:24.228-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" available=true connected=true macAddress=b8:27:eb:04:8a:91 ip4Address=192.168.7.228/24 ip6Address= ssid=LV-426 Feb 21 07:23:24 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:24.229-05:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" setupComplete=true Feb 21 07:23:24 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Feb 21 07:23:24 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 21 07:23:24 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 07:23:24 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 07:23:24 volumio-pi-3b volumio[1173]: amixer -c 0 info | grep "bcm2835 ALSA" Feb 21 07:23:24 volumio-pi-3b volumio[1173]: amixer -c 1 info | grep "bcm2835 Headphones" Feb 21 07:23:24 volumio-pi-3b volumio[1173]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Feb 21 07:23:24 volumio-pi-3b volumio[1173]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 2 Feb 21 07:23:24 volumio-pi-3b volumio[1173]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 21 07:23:24 volumio-pi-3b volumio[1173]: {"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)"} Feb 21 07:23:24 volumio-pi-3b volumio[1173]: amixer -c 2 info | grep "vc4-hdmi" Feb 21 07:23:24 volumio-pi-3b volumio[1173]: Card sysdefault:2 'vc4hdmi'/'vc4-hdmi' Feb 21 07:23:24 volumio-pi-3b volumio[1173]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Feb 21 07:23:24 volumio-pi-3b volumio[1173]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 21 07:23:24 volumio-pi-3b volumio[1173]: {"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)"} Feb 21 07:23:24 volumio-pi-3b volumio[1173]: amixer -c 3 info | grep "Raspberry Pi DAC+" Feb 21 07:23:24 volumio-pi-3b volumio[1173]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Feb 21 07:23:24 volumio-pi-3b volumio[1173]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 21 07:23:24 volumio-pi-3b volumio[1173]: {"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)"} Feb 21 07:23:24 volumio-pi-3b volumio[1173]: amixer -c 3 info | grep "RPi DAC+" Feb 21 07:23:24 volumio-pi-3b volumio[1173]: Card sysdefault:3 'DAC'/'RPi DAC+' Feb 21 07:23:24 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:24.462-05:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" selectedOutputId=3 Feb 21 07:23:24 volumio-pi-3b volumio[1173]: info: Received Get System Info Feb 21 07:23:24 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 07:23:24 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 07:23:24 volumio-pi-3b volumio[1173]: info: Discovery: Getting this device information Feb 21 07:23:24 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetState Feb 21 07:23:24 volumio-pi-3b volumio[1173]: info: CorePlayQueue::getTrack 0 Feb 21 07:23:24 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 07:23:24 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:24.482-05:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" currentVersion=4.096 latestVersion=4.096 Feb 21 07:23:24 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:24.482-05:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" status=UPDATE_STATUS_NONE progress=0 Feb 21 07:23:24 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:24.483-05:00 level=INFO msg="emitting user changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" userId= Feb 21 07:23:24 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:24.483-05:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" providers=3 Feb 21 07:23:24 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:24.483-05:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" plugins=60 Feb 21 07:23:24 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetState Feb 21 07:23:24 volumio-pi-3b volumio[1173]: info: CorePlayQueue::getTrack 0 Feb 21 07:23:24 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:24.487-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" state=STATUS_STOPPED positionMs=349 volume=100 Feb 21 07:23:24 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:24.488-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.7.207:54032 @ 0x254a1b0" id="https://live.amperwave.net/direct/adamsradio-wjfxfmmp3-ibc1?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MTYzOTA3MCwiaXNzIjoidGlzcnYifQ.nLuvg_3B8ux2RQiqCZKkpvJamz-FClKNc9_nTMlpBtU" title="Hot 107.9" Feb 21 07:23:24 volumio-pi-3b volumio5-onboarding[1593]: time=2026-02-21T07:23:24.550-05:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.7.207:54032 @ 0x254a1b0" latency=36.564662ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Feb 21 07:23:25 volumio-pi-3b sudo[8067]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 21 07:23:25 volumio-pi-3b sudo[8067]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 07:23:25 volumio-pi-3b sudo[8067]: pam_unix(sudo:session): session closed for user root Feb 21 07:23:25 volumio-pi-3b sudo[8069]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 21 07:23:25 volumio-pi-3b sudo[8069]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 07:23:25 volumio-pi-3b sudo[8069]: pam_unix(sudo:session): session closed for user root Feb 21 07:23:25 volumio-pi-3b volumio[1173]: verbose: New Socket.io Connection to 192.168.7.228 from 192.168.7.207 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 7 Feb 21 07:23:26 volumio-pi-3b sudo[8075]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 21 07:23:26 volumio-pi-3b sudo[8075]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 07:23:26 volumio-pi-3b sudo[8075]: pam_unix(sudo:session): session closed for user root Feb 21 07:23:26 volumio-pi-3b sudo[8077]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 21 07:23:26 volumio-pi-3b sudo[8077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 07:23:26 volumio-pi-3b sudo[8077]: pam_unix(sudo:session): session closed for user root Feb 21 07:23:26 volumio-pi-3b volumio[1173]: verbose: New Socket.io Connection to 192.168.7.228 from 192.168.7.207 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 8 Feb 21 07:23:26 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 21 07:23:26 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetVisibleSources Feb 21 07:23:26 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 07:23:26 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetState Feb 21 07:23:26 volumio-pi-3b volumio[1173]: info: CorePlayQueue::getTrack 0 Feb 21 07:23:26 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 21 07:23:26 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 21 07:23:26 volumio-pi-3b volumio[1173]: info: Received Get System Info Feb 21 07:23:26 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 07:23:26 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 07:23:26 volumio-pi-3b volumio[1173]: info: Discovery: Getting this device information Feb 21 07:23:26 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetState Feb 21 07:23:26 volumio-pi-3b volumio[1173]: info: CorePlayQueue::getTrack 0 Feb 21 07:23:26 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 07:23:26 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetState Feb 21 07:23:26 volumio-pi-3b volumio[1173]: info: CorePlayQueue::getTrack 0 Feb 21 07:23:26 volumio-pi-3b volumio[1173]: info: Listing playlists Feb 21 07:23:26 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 21 07:23:26 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 21 07:23:26 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 21 07:23:27 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetState Feb 21 07:23:27 volumio-pi-3b volumio[1173]: info: CorePlayQueue::getTrack 0 Feb 21 07:23:27 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 21 07:23:28 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 07:23:28 volumio-pi-3b volumio[1173]: info: Received Get System Info Feb 21 07:23:28 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 07:23:28 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 07:23:28 volumio-pi-3b volumio[1173]: info: Discovery: Getting this device information Feb 21 07:23:28 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetState Feb 21 07:23:28 volumio-pi-3b volumio[1173]: info: CorePlayQueue::getTrack 0 Feb 21 07:23:28 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 07:23:28 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 07:23:28 volumio-pi-3b volumio[1173]: info: Received Get System Info Feb 21 07:23:28 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 07:23:28 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 07:23:28 volumio-pi-3b volumio[1173]: info: Discovery: Getting this device information Feb 21 07:23:28 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetState Feb 21 07:23:28 volumio-pi-3b volumio[1173]: info: CorePlayQueue::getTrack 0 Feb 21 07:23:28 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 07:23:36 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 21 07:23:36 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 07:23:36 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 21 07:23:36 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 21 07:23:36 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 21 07:23:36 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Feb 21 07:23:36 volumio-pi-3b volumio[1173]: info: Received Get System Version Feb 21 07:23:36 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 07:23:44 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 21 07:23:44 volumio-pi-3b volumio[1173]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 21 07:23:44 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 21 07:23:44 volumio-pi-3b volumio[1173]: info: Received Get System Version Feb 21 07:23:44 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 07:23:44 volumio-pi-3b volumio[1173]: info: Received Get System Info Feb 21 07:23:44 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 07:23:44 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 07:23:44 volumio-pi-3b volumio[1173]: info: Discovery: Getting this device information Feb 21 07:23:44 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::volumioGetState Feb 21 07:23:44 volumio-pi-3b volumio[1173]: info: CorePlayQueue::getTrack 0 Feb 21 07:23:44 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 07:23:51 volumio-pi-3b volumio[1173]: info: CALLMETHOD: system_controller system saveGeneralSettings [object Object] Feb 21 07:23:51 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Feb 21 07:23:51 volumio-pi-3b volumio[1173]: info: System name has changed, restarting Shairport Sync Feb 21 07:23:51 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 07:23:51 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 07:23:52 volumio-pi-3b volumio[1173]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 07:23:52 volumio-pi-3b volumio[1173]: info: Updating MyVolumio device info Feb 21 07:23:52 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 07:23:52 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 07:23:52 volumio-pi-3b volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 07:23:52 volumio-pi-3b volumio[1173]: info: Discovery: Restarting Advertising Feb 21 07:23:52 volumio-pi-3b volumio[1173]: info: Discovery: Stopping existing advertisement Feb 21 07:23:52 volumio-pi-3b sudo[8153]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Feb 21 07:23:52 volumio-pi-3b sudo[8153]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 07:23:52 volumio-pi-3b sudo[8153]: pam_unix(sudo:session): session closed for user root Feb 21 07:23:52 volumio-pi-3b volumio[1173]: info: Starting Shairport Sync Feb 21 07:23:52 volumio-pi-3b sudo[8156]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Feb 21 07:23:52 volumio-pi-3b sudo[8156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 07:23:52 volumio-pi-3b sudo[8156]: pam_unix(sudo:session): session closed for user root Feb 21 07:23:52 volumio-pi-3b volumio[1173]: info: Permissions for /etc/hosts set Feb 21 07:23:52 volumio-pi-3b sudo[8158]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 07:23:52 volumio-pi-3b sudo[8158]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 07:23:52 volumio-pi-3b volumio[1173]: info: Hostname now is volumio-pi-3b-basement Feb 21 07:23:52 volumio-pi-3b systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 21 07:23:52 volumio-pi-3b sudo[8162]: volumio : unable to resolve host volumio-pi-3b: System error Feb 21 07:23:52 volumio-pi-3b systemd[1]: shairport-sync.service: Deactivated successfully. Feb 21 07:23:52 volumio-pi-3b systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 07:23:52 volumio-pi-3b systemd[1]: shairport-sync.service: Consumed 2min 20.660s CPU time. Feb 21 07:23:52 volumio-pi-3b sudo[8162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname volumio-pi-3b-basement Feb 21 07:23:52 volumio-pi-3b sudo[8162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 07:23:52 volumio-pi-3b-basement sudo[8162]: pam_unix(sudo:session): session closed for user root Feb 21 07:23:52 volumio-pi-3b-basement volumio[1173]: info: New hostname set Feb 21 07:23:52 volumio-pi-3b-basement sudo[8164]: volumio : unable to resolve host volumio-pi-3b: System error Feb 21 07:23:52 volumio-pi-3b-basement sudo[8164]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Feb 21 07:23:52 volumio-pi-3b-basement sudo[8164]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 07:23:52 volumio-pi-3b-basement sudo[8164]: pam_unix(sudo:session): session closed for user root Feb 21 07:23:52 volumio-pi-3b-basement volumio[1173]: info: Permissions for /etc/avahi/services/volumio.service Feb 21 07:23:52 volumio-pi-3b-basement systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 07:23:52 volumio-pi-3b-basement volumio[1173]: info: Avahi name changed to volumio-pi-3b-basement Feb 21 07:23:52 volumio-pi-3b-basement avahi-daemon[1281]: Files changed, reloading. Feb 21 07:23:52 volumio-pi-3b-basement avahi-daemon[1281]: Service group file /services/volumio.service changed, reloading. Feb 21 07:23:52 volumio-pi-3b-basement sudo[8158]: pam_unix(sudo:session): session closed for user root Feb 21 07:23:52 volumio-pi-3b-basement volumio[1173]: info: Shairport-Sync Started Feb 21 07:23:52 volumio-pi-3b-basement upmpdcli[1618]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Feb 21 07:23:52 volumio-pi-3b-basement upmpdcli[1618]: :2:../src/ohmetacache.cxx:94::dmcacheSaveWorker: can't get task from queue Feb 21 07:23:52 volumio-pi-3b-basement systemd[1]: upmpdcli.service: Deactivated successfully. Feb 21 07:23:52 volumio-pi-3b-basement systemd[1]: upmpdcli.service: Consumed 47.284s CPU time. Feb 21 07:23:53 volumio-pi-3b-basement volumio[1173]: info: Discovery: A device disappeared from network Feb 21 07:23:53 volumio-pi-3b-basement avahi-daemon[1281]: Service "Volumio Pi 3B Basement" (/services/volumio.service) successfully established. Feb 21 07:23:53 volumio-pi-3b-basement volumio[1173]: info: Discovery: A device disappeared from network Feb 21 07:23:53 volumio-pi-3b-basement volumio[1173]: error: Failed to update MyVolumio device: {"message":"Unauthenticated"} Feb 21 07:23:57 volumio-pi-3b-basement volumio[1173]: info: Discovery: Started advertising with name: Volumio Pi 3B Basement Feb 21 07:23:57 volumio-pi-3b-basement volumio[1173]: compat.c: read() failed: Resource temporarily unavailable Feb 21 07:23:57 volumio-pi-3b-basement volumio[1173]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 07:23:57 volumio-pi-3b-basement volumio[1173]: Error: dns service error: unknown Feb 21 07:23:57 volumio-pi-3b-basement volumio[1173]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Feb 21 07:23:57 volumio-pi-3b-basement volumio[1173]: errorCode: -65537 Feb 21 07:23:57 volumio-pi-3b-basement volumio[1173]: } Feb 21 07:23:57 volumio-pi-3b-basement volumio[1173]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 07:23:58 volumio-pi-3b-basement sudo[8214]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-21 07:22' Feb 21 07:23:58 volumio-pi-3b-basement sudo[8214]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"