Feb 24 17:52:02 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 17:52:02 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 17:52:02 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 24 17:52:02 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 24 17:52:02 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 24 17:52:02 volumio volumio[964]: info: CoreCommandRouter::volumioGetBrowseSources Feb 24 17:52:02 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 17:52:07 volumio volumio[964]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 24 17:52:07 volumio volumio[964]: info: Completed starting MyVolumio Plugin Feb 24 17:52:07 volumio volumio[964]: [Metrics] CommandRouter: 57s 360.23ms Feb 24 17:52:07 volumio volumio[964]: info: CoreCommandRouter::volumiosetStartupVolume Feb 24 17:52:07 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 17:52:07 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 17:52:07 volumio volumio[964]: info: CoreCommandRouter::Close All Modals sent Feb 24 17:52:07 volumio volumio[964]: info: CoreCommandRouter::Close All Modals sent Feb 24 17:52:08 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 24 17:52:08 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 24 17:52:08 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 24 17:52:14 volumio volumio[964]: info: BOOT COMPLETED Feb 24 17:53:26 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:26.212Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.68.71:62252 Feb 24 17:53:26 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 17:53:26 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 17:53:26 volumio volumio[964]: info: Discovery: Getting this device information Feb 24 17:53:26 volumio volumio[964]: info: CoreCommandRouter::volumioGetState Feb 24 17:53:26 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Feb 24 17:53:26 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 17:53:26 volumio volumio[964]: verbose: New Socket.io Connection to 192.168.68.86:3000 from 192.168.68.71 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Feb 24 17:53:26 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 24 17:53:26 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 24 17:53:32 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:32.148Z level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.68.71:62252 @ 0x389a120" latency=14.83154ms platform=PLATFORM_IOS version=5.260120.0 Feb 24 17:53:32 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:32.148Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.68.71:62252 @ 0x389a120" latency=13.147425ms timeout=10s Feb 24 17:53:32 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:32.148Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.68.71:62252 @ 0x389a120" Feb 24 17:53:32 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 24 17:53:32 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 24 17:53:32 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:32.172Z level=INFO msg="updating device settings from app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.68.71:62252 @ 0x389a120" latency=14.83154ms language=en timezone=America/Indiana/Indianapolis Feb 24 17:53:32 volumio volumio[964]: info: Received Get System Info Feb 24 17:53:32 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 17:53:32 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 17:53:32 volumio volumio[964]: info: Discovery: Getting this device information Feb 24 17:53:32 volumio volumio[964]: info: CoreCommandRouter::volumioGetState Feb 24 17:53:32 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Feb 24 17:53:32 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 17:53:32 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:32.182Z level=INFO msg="emitting device name changed event" component=server peer="192.168.68.71:62252 @ 0x389a120" name=Volumio Feb 24 17:53:32 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:32.183Z level=INFO msg="emitting device language changed event" component=server peer="192.168.68.71:62252 @ 0x389a120" language=en Feb 24 17:53:32 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 24 17:53:32 volumio volumio[964]: info: Loading i18n strings for locale en Feb 24 17:53:32 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 24 17:53:32 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:32.303Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.68.71:62252 @ 0x389a120" timezone=UTC Feb 24 17:53:32 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:32.304Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.68.71:62252 @ 0x389a120" available=true connected=true macAddress=b8:27:eb:c4:12:30 ip4Address=192.168.68.86/22 ip6Address= Feb 24 17:53:32 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:32.305Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.68.71:62252 @ 0x389a120" available=false connected=false macAddress= ip4Address= ip6Address= ssid= Feb 24 17:53:32 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:32.306Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.68.71:62252 @ 0x389a120" setupComplete=false Feb 24 17:53:32 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Feb 24 17:53:32 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 24 17:53:32 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 17:53:32 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 24 17:53:32 volumio volumio[964]: amixer -c 0 info | grep "bcm2835 ALSA" Feb 24 17:53:32 volumio volumio[964]: amixer -c 0 info | grep "bcm2835 ALSA" Feb 24 17:53:32 volumio volumio[964]: amixer -c 1 info | grep "bcm2835 Headphones" Feb 24 17:53:32 volumio volumio[964]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Feb 24 17:53:32 volumio volumio[964]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 2 Feb 24 17:53:32 volumio volumio[964]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 24 17:53:32 volumio volumio[964]: {"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 24 17:53:32 volumio volumio[964]: amixer -c 2 info | grep "vc4-hdmi" Feb 24 17:53:32 volumio volumio[964]: Card sysdefault:2 'vc4hdmi'/'vc4-hdmi' Feb 24 17:53:32 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:32.802Z level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.68.71:62252 @ 0x389a120" selectedOutputId=0 Feb 24 17:53:32 volumio volumio[964]: info: Fetching Streaming Services browse cache Feb 24 17:53:32 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:32.874Z level=INFO msg="emitting device language changed event" component=server peer="192.168.68.71:62252 @ 0x389a120" language=en Feb 24 17:53:32 volumio volumio[964]: Updating browse sources language Feb 24 17:53:32 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 17:53:32 volumio volumio[964]: info: Received Get System Info Feb 24 17:53:32 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 17:53:32 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 17:53:32 volumio volumio[964]: info: Discovery: Getting this device information Feb 24 17:53:32 volumio volumio[964]: info: CoreCommandRouter::volumioGetState Feb 24 17:53:32 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Feb 24 17:53:32 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 17:53:32 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Feb 24 17:53:32 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:32.971Z level=INFO msg="emitting software info changed event" component=server peer="192.168.68.71:62252 @ 0x389a120" currentVersion=4.096 latestVersion=4.096 Feb 24 17:53:32 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:32.971Z level=INFO msg="emitting software update progress event" component=server peer="192.168.68.71:62252 @ 0x389a120" status=UPDATE_STATUS_NONE progress=0 Feb 24 17:53:32 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:32.972Z level=INFO msg="emitting user changed event" component=server peer="192.168.68.71:62252 @ 0x389a120" userId= Feb 24 17:53:32 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:32.972Z level=INFO msg="emitting music providers changed event" component=server peer="192.168.68.71:62252 @ 0x389a120" providers=3 Feb 24 17:53:32 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 24 17:53:33 volumio volumio[964]: info: Setting timezone to America/Indiana/Indianapolis Feb 24 17:53:33 volumio sudo[1706]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 24 17:53:33 volumio sudo[1706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 17:53:33 volumio sudo[1706]: pam_unix(sudo:session): session closed for user root Feb 24 17:53:33 volumio sudo[1710]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/Indiana/Indianapolis /etc/localtime Feb 24 17:53:33 volumio sudo[1710]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 17:53:33 volumio sudo[1710]: pam_unix(sudo:session): session closed for user root Feb 24 17:53:33 volumio sudo[1714]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 24 17:53:33 volumio sudo[1714]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 17:53:33 volumio sudo[1714]: pam_unix(sudo:session): session closed for user root Feb 24 17:53:33 volumio sudo[1718]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/Indiana/Indianapolis Feb 24 17:53:33 volumio sudo[1718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 17:53:33 volumio dbus-daemon[684]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.19' (uid=0 pid=1719 comm="/usr/bin/timedatectl set-timezone America/Indiana/") Feb 24 17:53:33 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Feb 24 17:53:34 volumio dbus-daemon[684]: [system] Successfully activated service 'org.freedesktop.timedate1' Feb 24 17:53:34 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Feb 24 17:53:34 volumio sudo[1718]: pam_unix(sudo:session): session closed for user root Feb 24 17:53:34 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 24 17:53:34 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 17:53:34 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:34.021Z level=INFO msg="emitting plugins changed event" component=server peer="192.168.68.71:62252 @ 0x389a120" plugins=0 Feb 24 17:53:34 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 24 17:53:34 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:34.264Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.68.71:62252 @ 0x389a120" timezone=America/Indiana/Indianapolis Feb 24 17:53:34 volumio volumio[964]: info: CoreCommandRouter::volumioGetState Feb 24 17:53:34 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Feb 24 17:53:34 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:34.275Z level=INFO msg="emitting player state changed event" component=server peer="192.168.68.71:62252 @ 0x389a120" state=STATUS_STOPPED positionMs=0 volume=86 Feb 24 17:53:34 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:34.276Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.71:62252 @ 0x389a120" id= title= Feb 24 17:53:34 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:34.294Z level=ERROR msg="failed to broadcast WiFi networks" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.68.71:62252 @ 0x389a120" latency=20.335029ms timeout=10s from=APP_PAGE_ROOT error="failed to scan wifi networks: interface not found" Feb 24 17:53:34 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:34.295Z level=INFO msg="emitting device language changed event" component=server peer="192.168.68.71:62252 @ 0x389a120" language=en Feb 24 17:53:34 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 24 17:53:34 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:34.549Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.68.71:62252 @ 0x389a120" timezone=America/Indiana/Indianapolis Feb 24 17:53:34 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:34.550Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.68.71:62252 @ 0x389a120" latency=20.335029ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_SETUP_V1_INTRO Feb 24 17:53:37 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:37.882Z level=INFO msg="set device language" component=server type=REQUEST_TYPE_SET_DEVICE_LANGUAGE peer="192.168.68.71:62252 @ 0x389a120" latency=12.42185ms timeout=10s language=en Feb 24 17:53:37 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 24 17:53:37 volumio volumio[964]: info: Loading i18n strings for locale en Feb 24 17:53:38 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:38.038Z level=INFO msg="emitting device language changed event" component=server peer="192.168.68.71:62252 @ 0x389a120" language=en Feb 24 17:53:38 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:38.045Z level=INFO msg="set device timezone" component=server type=REQUEST_TYPE_SET_DEVICE_TIMEZONE peer="192.168.68.71:62252 @ 0x389a120" latency=11.92159ms timeout=10s timezone=America/Indiana/Indianapolis Feb 24 17:53:38 volumio volumio[964]: Updating browse sources language Feb 24 17:53:38 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 17:53:38 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Feb 24 17:53:38 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 24 17:53:38 volumio volumio[964]: info: Setting timezone to America/Indiana/Indianapolis Feb 24 17:53:38 volumio sudo[1737]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 24 17:53:38 volumio sudo[1737]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 17:53:38 volumio sudo[1737]: pam_unix(sudo:session): session closed for user root Feb 24 17:53:38 volumio sudo[1741]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/Indiana/Indianapolis /etc/localtime Feb 24 17:53:38 volumio sudo[1741]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 17:53:38 volumio sudo[1741]: pam_unix(sudo:session): session closed for user root Feb 24 17:53:38 volumio sudo[1745]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 24 17:53:38 volumio sudo[1745]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 17:53:38 volumio sudo[1745]: pam_unix(sudo:session): session closed for user root Feb 24 17:53:38 volumio sudo[1749]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/Indiana/Indianapolis Feb 24 17:53:38 volumio sudo[1749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 17:53:38 volumio sudo[1749]: pam_unix(sudo:session): session closed for user root Feb 24 17:53:38 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 24 17:53:38 volumio volumio[964]: info: Fetching Streaming Services browse cache Feb 24 17:53:38 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 24 17:53:38 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:38.605Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.68.71:62252 @ 0x389a120" timezone=America/Indiana/Indianapolis Feb 24 17:53:38 volumio volumio[964]: info: Received Get System Info Feb 24 17:53:38 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 17:53:38 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 17:53:38 volumio volumio[964]: info: Discovery: Getting this device information Feb 24 17:53:38 volumio volumio[964]: info: CoreCommandRouter::volumioGetState Feb 24 17:53:38 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Feb 24 17:53:38 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 17:53:38 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:38.956Z level=INFO msg="emitting device name changed event" component=server peer="192.168.68.71:62252 @ 0x389a120" name=Volumio Feb 24 17:53:38 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:38.957Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.68.71:62252 @ 0x389a120" latency=11.805808ms timeout=10s from=APP_PAGE_SETUP_V1_INTRO to=APP_PAGE_SETUP_V1_NAME Feb 24 17:53:48 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:48.159Z level=INFO msg="set device name" component=server type=REQUEST_TYPE_SET_DEVICE_NAME peer="192.168.68.71:62252 @ 0x389a120" latency=14.547524ms timeout=10s name=Lounge Feb 24 17:53:48 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Feb 24 17:53:48 volumio volumio[964]: info: System name has changed, restarting Shairport Sync Feb 24 17:53:48 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 17:53:48 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 17:53:48 volumio volumio[964]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 17:53:48 volumio volumio[964]: info: Discovery: Restarting Advertising Feb 24 17:53:48 volumio volumio[964]: info: Discovery: Stopping existing advertisement Feb 24 17:53:48 volumio volumio[964]: info: Received Get System Info Feb 24 17:53:48 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 17:53:48 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 17:53:48 volumio volumio[964]: info: Discovery: Getting this device information Feb 24 17:53:48 volumio volumio[964]: info: CoreCommandRouter::volumioGetState Feb 24 17:53:48 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Feb 24 17:53:48 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 17:53:48 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:48.341Z level=INFO msg="emitting device name changed event" component=server peer="192.168.68.71:62252 @ 0x389a120" name=Lounge Feb 24 17:53:48 volumio sudo[1770]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Feb 24 17:53:48 volumio sudo[1770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 17:53:48 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:48.428Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.68.71:62252 @ 0x389a120" available=true connected=true macAddress=b8:27:eb:c4:12:30 ip4Address=192.168.68.86/22 ip6Address= Feb 24 17:53:48 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:48.429Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.68.71:62252 @ 0x389a120" available=false connected=false macAddress= ip4Address= ip6Address= ssid= Feb 24 17:53:48 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:48.429Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.68.71:62252 @ 0x389a120" latency=12.473357ms timeout=10s from=APP_PAGE_SETUP_V1_NAME to=APP_PAGE_SETUP_V1_INTERNET Feb 24 17:53:48 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:48.447Z level=INFO msg="run WiFi scan" component=server type=REQUEST_TYPE_RUN_WIFI_SCAN peer="192.168.68.71:62252 @ 0x389a120" latency=10.661482ms timeout=1m0s Feb 24 17:53:48 volumio volumio5-onboarding[1310]: time=2026-02-24T22:53:48.448Z level=ERROR msg="failed to broadcast WiFi networks" component=server type=REQUEST_TYPE_RUN_WIFI_SCAN peer="192.168.68.71:62252 @ 0x389a120" latency=10.661482ms timeout=1m0s error="failed to scan wifi networks: interface not found" Feb 24 17:53:48 volumio sudo[1770]: pam_unix(sudo:session): session closed for user root Feb 24 17:53:48 volumio volumio[964]: info: Starting Shairport Sync Feb 24 17:53:48 volumio sudo[1773]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Feb 24 17:53:48 volumio sudo[1773]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 17:53:48 volumio sudo[1773]: pam_unix(sudo:session): session closed for user root Feb 24 17:53:48 volumio volumio[964]: info: Permissions for /etc/hosts set Feb 24 17:53:48 volumio sudo[1775]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 17:53:48 volumio sudo[1775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 17:53:48 volumio volumio[964]: info: Hostname now is lounge Feb 24 17:53:48 volumio sudo[1778]: volumio : unable to resolve host volumio: System error Feb 24 17:53:48 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 24 17:53:48 volumio sudo[1781]: volumio : unable to resolve host volumio: System error Feb 24 17:53:48 volumio sudo[1778]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname lounge Feb 24 17:53:48 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 24 17:53:48 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 24 17:53:48 volumio systemd[1]: shairport-sync.service: Consumed 2.200s CPU time. Feb 24 17:53:48 volumio sudo[1778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 17:53:48 volumio sudo[1781]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Feb 24 17:53:48 volumio sudo[1781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 17:53:48 lounge sudo[1778]: pam_unix(sudo:session): session closed for user root Feb 24 17:53:48 lounge volumio[964]: info: New hostname set Feb 24 17:53:48 lounge sudo[1781]: pam_unix(sudo:session): session closed for user root Feb 24 17:53:48 lounge systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 24 17:53:48 lounge sudo[1775]: pam_unix(sudo:session): session closed for user root Feb 24 17:53:48 lounge volumio[964]: info: Shairport-Sync Started Feb 24 17:53:48 lounge volumio[964]: info: Permissions for /etc/avahi/services/volumio.service Feb 24 17:53:48 lounge systemd[1]: upmpdcli.service: Deactivated successfully. Feb 24 17:53:48 lounge systemd[1]: upmpdcli.service: Consumed 25.960s CPU time. Feb 24 17:53:48 lounge avahi-daemon[680]: Files changed, reloading. Feb 24 17:53:48 lounge avahi-daemon[680]: Loading service file /services/volumio.service. Feb 24 17:53:49 lounge volumio[964]: info: Avahi name changed to lounge Feb 24 17:53:49 lounge volumio[964]: info: Discovery: A device disappeared from network Feb 24 17:53:49 lounge volumio[964]: info: Discovery: Device volumio disappeared from network Feb 24 17:53:49 lounge volumio[964]: info: Discovery: A device disappeared from network Feb 24 17:53:49 lounge avahi-daemon[680]: Service "Lounge" (/services/volumio.service) successfully established. Feb 24 17:53:53 lounge volumio[964]: info: Discovery: Started advertising with name: Lounge Feb 24 17:53:54 lounge volumio[964]: compat.c: read() failed: Resource temporarily unavailable Feb 24 17:53:54 lounge volumio[964]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 17:53:54 lounge volumio[964]: Error: dns service error: unknown Feb 24 17:53:54 lounge volumio[964]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Feb 24 17:53:54 lounge volumio[964]: errorCode: -65537 Feb 24 17:53:54 lounge volumio[964]: } Feb 24 17:53:54 lounge volumio[964]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 17:53:57 lounge sudo[1830]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-24 17:52' Feb 24 17:53:57 lounge sudo[1830]: 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"