-- Logs begin at Thu 2019-02-14 19:11:58 KST, end at Mon 2026-10-12 18:06:51 KST. -- Oct 12 18:05:00 rasberry-5-usb volumio[1142]: info: MYVOLUMIO: Adding device Oct 12 18:05:00 rasberry-5-usb volumio[1142]: info: MYVOLUMIO: Evaluating Server Oct 12 18:05:01 rasberry-5-usb volumio[1142]: info: Setting Geolocation for MyVolumio to as1 Oct 12 18:05:01 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 18:05:01 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 18:05:01 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 18:05:01 rasberry-5-usb volumio[1142]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 12 18:05:02 rasberry-5-usb volumio[1142]: info: Updating MyVolumio device info Oct 12 18:05:02 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 18:05:02 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 18:05:02 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 18:05:02 rasberry-5-usb volumio[1142]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 12 18:05:04 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 18:05:06 rasberry-5-usb volumiologrotate[742]: ls: cannot access '/var/log/samba/log.wb-RASBERRY': No such file or directory Oct 12 18:05:06 rasberry-5-usb volumiologrotate[742]: ls: cannot access '5': No such file or directory Oct 12 18:05:06 rasberry-5-usb volumiologrotate[742]: ls: cannot access 'USB': No such file or directory Oct 12 18:05:07 rasberry-5-usb volumio[1142]: info: Received Get System Info Oct 12 18:05:07 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 18:05:07 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 18:05:07 rasberry-5-usb volumio[1142]: info: Discovery: Getting this device information Oct 12 18:05:07 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 12 18:05:07 rasberry-5-usb volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 12 18:05:07 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 18:05:07 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 18:05:07 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 18:05:07 rasberry-5-usb wpa_supplicant[1040]: wlan0: Failed to initiate sched scan Oct 12 18:05:07 rasberry-5-usb volumio[1142]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 12 18:05:07 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 12 18:05:07 rasberry-5-usb volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 12 18:05:08 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:05:08.185+09:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 12 18:05:09 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 18:05:09 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 18:05:09 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 12 18:05:09 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 12 18:05:09 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 12 18:05:09 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetBrowseSources Oct 12 18:05:09 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 18:05:17 rasberry-5-usb systemd[1]: systemd-timedated.service: Succeeded. Oct 12 18:05:18 rasberry-5-usb volumio[1142]: info: Received Get System Info Oct 12 18:05:18 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 18:05:18 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 18:05:18 rasberry-5-usb volumio[1142]: info: Discovery: Getting this device information Oct 12 18:05:18 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 12 18:05:18 rasberry-5-usb volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 12 18:05:18 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 18:05:18 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 18:05:18 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 18:05:18 rasberry-5-usb wpa_supplicant[1040]: wlan0: Failed to initiate sched scan Oct 12 18:05:19 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:05:19.870+09:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 12 18:05:26 rasberry-5-usb ntpd[959]: Soliciting pool server 115.15.44.33 Oct 12 18:05:26 rasberry-5-usb ntpd[959]: Soliciting pool server 193.123.243.2 Oct 12 18:05:27 rasberry-5-usb ntpd[959]: Soliciting pool server 121.174.142.82 Oct 12 18:05:27 rasberry-5-usb ntpd[959]: Soliciting pool server 211.222.238.30 Oct 12 18:05:30 rasberry-5-usb volumio[1142]: info: Received Get System Info Oct 12 18:05:30 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 18:05:30 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 18:05:30 rasberry-5-usb volumio[1142]: info: Discovery: Getting this device information Oct 12 18:05:30 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 12 18:05:30 rasberry-5-usb volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 12 18:05:30 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 18:05:30 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 18:05:30 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 18:05:30 rasberry-5-usb wpa_supplicant[1040]: wlan0: Failed to initiate sched scan Oct 12 18:05:30 rasberry-5-usb ntpd[959]: Soliciting pool server 1.230.135.74 Oct 12 18:05:30 rasberry-5-usb ntpd[959]: Soliciting pool server 121.134.215.104 Oct 12 18:05:31 rasberry-5-usb ntpd[959]: Soliciting pool server 194.0.5.123 Oct 12 18:05:31 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:05:31.199+09:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 12 18:05:31 rasberry-5-usb ntpd[959]: Soliciting pool server 175.195.167.194 Oct 12 18:05:31 rasberry-5-usb ntpd[959]: Soliciting pool server 211.108.117.211 Oct 12 18:05:31 rasberry-5-usb ntpd[959]: Soliciting pool server 121.174.142.81 Oct 12 18:05:32 rasberry-5-usb ntpd[959]: Soliciting pool server 1.230.135.74 Oct 12 18:05:36 rasberry-5-usb ntpd[959]: receive: Unexpected origin timestamp 0xee77215f.b225f841 does not match aorg 0000000000.00000000 from server@194.0.5.123 xmt 0xee772160.9c126248 Oct 12 18:05:42 rasberry-5-usb volumio[1142]: info: Received Get System Info Oct 12 18:05:42 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 18:05:42 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 18:05:42 rasberry-5-usb volumio[1142]: info: Discovery: Getting this device information Oct 12 18:05:42 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 12 18:05:42 rasberry-5-usb volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 12 18:05:42 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 18:05:42 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 18:05:42 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 18:05:42 rasberry-5-usb wpa_supplicant[1040]: wlan0: Failed to initiate sched scan Oct 12 18:05:43 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:05:43.695+09:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 12 18:05:54 rasberry-5-usb volumio[1142]: info: Received Get System Info Oct 12 18:05:54 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 18:05:54 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 18:05:54 rasberry-5-usb volumio[1142]: info: Discovery: Getting this device information Oct 12 18:05:54 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 12 18:05:54 rasberry-5-usb volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 12 18:05:54 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 18:05:54 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 18:05:54 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 18:05:54 rasberry-5-usb wpa_supplicant[1040]: wlan0: Failed to initiate sched scan Oct 12 18:05:55 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:05:55.075+09:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 12 18:06:05 rasberry-5-usb volumio[1142]: info: Received Get System Info Oct 12 18:06:05 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 18:06:05 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 18:06:05 rasberry-5-usb volumio[1142]: info: Discovery: Getting this device information Oct 12 18:06:05 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 12 18:06:05 rasberry-5-usb volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 12 18:06:05 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 18:06:05 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 18:06:05 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 18:06:05 rasberry-5-usb wpa_supplicant[1040]: wlan0: Failed to initiate sched scan Oct 12 18:06:06 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:06.755+09:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 12 18:06:06 rasberry-5-usb volumiologrotate[742]: ls: cannot access '/var/log/samba/log.wb-RASBERRY': No such file or directory Oct 12 18:06:06 rasberry-5-usb volumiologrotate[742]: ls: cannot access '5': No such file or directory Oct 12 18:06:06 rasberry-5-usb volumiologrotate[742]: ls: cannot access 'USB': No such file or directory Oct 12 18:06:17 rasberry-5-usb volumio[1142]: info: Received Get System Info Oct 12 18:06:17 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 18:06:17 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 18:06:17 rasberry-5-usb volumio[1142]: info: Discovery: Getting this device information Oct 12 18:06:17 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 12 18:06:17 rasberry-5-usb volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 12 18:06:17 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 18:06:17 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 18:06:17 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 18:06:17 rasberry-5-usb wpa_supplicant[1040]: wlan0: Failed to initiate sched scan Oct 12 18:06:18 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:18.232+09:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 12 18:06:28 rasberry-5-usb volumio[1142]: info: Received Get System Info Oct 12 18:06:28 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 18:06:28 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 18:06:28 rasberry-5-usb volumio[1142]: info: Discovery: Getting this device information Oct 12 18:06:28 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 12 18:06:28 rasberry-5-usb volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 12 18:06:28 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 18:06:28 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 18:06:28 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 18:06:28 rasberry-5-usb wpa_supplicant[1040]: wlan0: Failed to initiate sched scan Oct 12 18:06:29 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:29.755+09:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 12 18:06:31 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:31.546+09:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.8.101:40348 Oct 12 18:06:31 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:31.680+09:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.8.101:40348 @ 0x16ec6c0" latency=35.494514ms platform=PLATFORM_ANDROID version=6.260930.0 Oct 12 18:06:31 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:31.680+09:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.8.101:40348 @ 0x16ec6c0" latency=33.647872ms timeout=20s Oct 12 18:06:31 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:31.680+09:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" Oct 12 18:06:31 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 18:06:31 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 18:06:31 rasberry-5-usb volumio[1142]: info: Received Get System Info Oct 12 18:06:31 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 18:06:31 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 18:06:31 rasberry-5-usb volumio[1142]: info: Discovery: Getting this device information Oct 12 18:06:31 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 12 18:06:31 rasberry-5-usb volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 12 18:06:31 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 18:06:31 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:31.684+09:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" name="Rasberry 5 USB" Oct 12 18:06:31 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:31.685+09:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" language=ko Oct 12 18:06:31 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 12 18:06:31 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:31.687+09:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" timezone=Asia/Seoul Oct 12 18:06:31 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:31.687+09:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" available=true connected=true macAddress=d8:3a:dd:cd:36:93 ip4Address=192.168.8.100/24 ip6Address= Oct 12 18:06:31 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:31.688+09:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Oct 12 18:06:31 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:31.688+09:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" setupComplete=true Oct 12 18:06:31 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 12 18:06:31 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 12 18:06:31 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 18:06:31 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 18:06:31 rasberry-5-usb volumio[1142]: amixer -c 0 info | grep "vc4-hdmi-0" Oct 12 18:06:31 rasberry-5-usb volumio[1142]: Card hw:0 'vc4hdmi0'/'vc4-hdmi-0' Oct 12 18:06:31 rasberry-5-usb volumio[1142]: amixer -c 1 info | grep "vc4-hdmi-1" Oct 12 18:06:31 rasberry-5-usb volumio[1142]: Card hw:1 'vc4hdmi1'/'vc4-hdmi-1' Oct 12 18:06:31 rasberry-5-usb volumio[1142]: amixer -c 5 info | grep "Tone1" Oct 12 18:06:31 rasberry-5-usb volumio[1142]: Card hw:5 'Tone1'/'Khadas Tone1 at usb-xhci-hcd.1-2, high speed' Oct 12 18:06:31 rasberry-5-usb volumio[1142]: amixer -c 5 info | grep "Tone1" Oct 12 18:06:31 rasberry-5-usb volumio[1142]: Card hw:5 'Tone1'/'Khadas Tone1 at usb-xhci-hcd.1-2, high speed' Oct 12 18:06:31 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:31.814+09:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" selectedOutputId=5 Oct 12 18:06:31 rasberry-5-usb volumio[1142]: info: Received Get System Info Oct 12 18:06:31 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 18:06:31 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 18:06:31 rasberry-5-usb volumio[1142]: info: Discovery: Getting this device information Oct 12 18:06:31 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 12 18:06:31 rasberry-5-usb volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 12 18:06:31 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 18:06:31 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:31.873+09:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" currentVersion=3.912 latestVersion=3.912 Oct 12 18:06:31 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:31.873+09:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" status=UPDATE_STATUS_NONE progress=0 Oct 12 18:06:31 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 18:06:31 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 12 18:06:32 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 18:06:32 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 18:06:32 rasberry-5-usb volumio[1142]: info: Discovery: Getting this device information Oct 12 18:06:32 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 12 18:06:32 rasberry-5-usb volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 12 18:06:32 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 18:06:32 rasberry-5-usb volumio[1142]: verbose: New Socket.io Connection to 192.168.8.100:3000 from 192.168.8.101 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Oct 12 18:06:32 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 18:06:32 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 18:06:32 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:32.910+09:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.8.101:40348 @ 0x16ec6c0" latency=59.396657ms platform=PLATFORM_ANDROID version=6.260930.0 Oct 12 18:06:32 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:32.910+09:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.8.101:40348 @ 0x16ec6c0" latency=57.508056ms timeout=20s Oct 12 18:06:32 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:32.910+09:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" Oct 12 18:06:32 rasberry-5-usb volumio[1142]: info: Received Get System Info Oct 12 18:06:32 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 18:06:32 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 18:06:32 rasberry-5-usb volumio[1142]: info: Discovery: Getting this device information Oct 12 18:06:32 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 12 18:06:32 rasberry-5-usb volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 12 18:06:32 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 18:06:32 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:32.912+09:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" name="Rasberry 5 USB" Oct 12 18:06:32 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:32.913+09:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" language=ko Oct 12 18:06:32 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 12 18:06:32 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:32.913+09:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" timezone=Asia/Seoul Oct 12 18:06:32 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:32.914+09:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" available=true connected=true macAddress=d8:3a:dd:cd:36:93 ip4Address=192.168.8.100/24 ip6Address= Oct 12 18:06:32 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:32.915+09:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Oct 12 18:06:32 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:32.915+09:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" setupComplete=true Oct 12 18:06:32 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 12 18:06:32 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 12 18:06:32 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 18:06:32 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 18:06:32 rasberry-5-usb volumio[1142]: amixer -c 0 info | grep "vc4-hdmi-0" Oct 12 18:06:32 rasberry-5-usb volumio[1142]: Card hw:0 'vc4hdmi0'/'vc4-hdmi-0' Oct 12 18:06:32 rasberry-5-usb volumio[1142]: amixer -c 1 info | grep "vc4-hdmi-1" Oct 12 18:06:32 rasberry-5-usb volumio[1142]: Card hw:1 'vc4hdmi1'/'vc4-hdmi-1' Oct 12 18:06:32 rasberry-5-usb volumio[1142]: amixer -c 5 info | grep "Tone1" Oct 12 18:06:33 rasberry-5-usb volumio[1142]: Card hw:5 'Tone1'/'Khadas Tone1 at usb-xhci-hcd.1-2, high speed' Oct 12 18:06:33 rasberry-5-usb volumio[1142]: amixer -c 5 info | grep "Tone1" Oct 12 18:06:33 rasberry-5-usb volumio[1142]: Card hw:5 'Tone1'/'Khadas Tone1 at usb-xhci-hcd.1-2, high speed' Oct 12 18:06:33 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:33.035+09:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" selectedOutputId=5 Oct 12 18:06:33 rasberry-5-usb volumio[1142]: info: Received Get System Info Oct 12 18:06:33 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 18:06:33 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 18:06:33 rasberry-5-usb volumio[1142]: info: Discovery: Getting this device information Oct 12 18:06:33 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 12 18:06:33 rasberry-5-usb volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 12 18:06:33 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 18:06:33 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:33.093+09:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" currentVersion=3.912 latestVersion=3.912 Oct 12 18:06:33 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:33.093+09:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" status=UPDATE_STATUS_NONE progress=0 Oct 12 18:06:33 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 18:06:33 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 12 18:06:33 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:33.334+09:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=ec7xfuamW1guvrFe9lMorPBh6GC2 tokenExpiry=2026-10-12T19:06:33.334+09:00 Oct 12 18:06:34 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:34.391+09:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=ec7xfuamW1guvrFe9lMorPBh6GC2 tokenExpiry=2026-10-12T19:06:34.391+09:00 Oct 12 18:06:35 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:35.041+09:00 level=INFO msg="emitting user changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" userId=ec7xfuamW1guvrFe9lMorPBh6GC2 Oct 12 18:06:35 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 18:06:35 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 18:06:35 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 12 18:06:35 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:35.292+09:00 level=INFO msg="emitting user changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" userId=ec7xfuamW1guvrFe9lMorPBh6GC2 Oct 12 18:06:35 rasberry-5-usb kernel: hwmon hwmon2: Undervoltage detected! Oct 12 18:06:36 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:36.093+09:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Oct 12 18:06:36 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:36.093+09:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Oct 12 18:06:36 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:36.093+09:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Oct 12 18:06:36 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:36.093+09:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=spotify error="could not open plugin config file for \"spop\": open /data/configuration/music_service/spop/config.json: no such file or directory" Oct 12 18:06:36 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:36.093+09:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" providers=9 Oct 12 18:06:36 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:36.093+09:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" providers=9 Oct 12 18:06:36 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 18:06:36 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 18:06:36 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 12 18:06:36 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:36.970+09:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" plugins=54 Oct 12 18:06:36 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 12 18:06:36 rasberry-5-usb volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 12 18:06:36 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:36.972+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 12 18:06:36 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:36.972+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" id= title= Oct 12 18:06:36 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 12 18:06:36 rasberry-5-usb volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 12 18:06:36 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:36.973+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 12 18:06:36 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:36.973+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" id= title= Oct 12 18:06:36 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:36.973+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 12 18:06:36 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:36.973+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" id= title= Oct 12 18:06:37 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:37.103+09:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.8.101:40348 @ 0x16ec6c0" latency=30.68881ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 12 18:06:37 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:37.136+09:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.8.101:40348 @ 0x16ec6c0" latency=30.397209ms timeout=10s Oct 12 18:06:37 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:37.346+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.8.101:40348 @ 0x16ec6c0" latency=30.397209ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=209.947281ms Oct 12 18:06:37 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:37.435+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.8.101:40348 @ 0x16ec6c0" latency=30.397209ms timeout=10s endpoint=https://functions.volumio.cloud duration=297.960087ms Oct 12 18:06:37 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:37.441+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.8.101:40348 @ 0x16ec6c0" latency=30.397209ms timeout=10s endpoint=https://functions.volumio.cloud duration=304.811372ms Oct 12 18:06:37 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:37.497+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.8.101:40348 @ 0x16ec6c0" latency=30.397209ms timeout=10s endpoint=https://www.googleapis.com duration=360.971755ms Oct 12 18:06:37 rasberry-5-usb kernel: hwmon hwmon2: Voltage normalised Oct 12 18:06:37 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:37.617+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.8.101:40348 @ 0x16ec6c0" latency=30.397209ms timeout=10s endpoint=https://securetoken.googleapis.com duration=480.901555ms Oct 12 18:06:37 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:37.907+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.8.101:40348 @ 0x16ec6c0" latency=30.397209ms timeout=10s endpoint=http://pushupdates.volumio.org duration=770.123307ms Oct 12 18:06:40 rasberry-5-usb volumio[1142]: info: Received Get System Info Oct 12 18:06:40 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 18:06:40 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 18:06:40 rasberry-5-usb volumio[1142]: info: Discovery: Getting this device information Oct 12 18:06:40 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 12 18:06:40 rasberry-5-usb volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 12 18:06:40 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 18:06:40 rasberry-5-usb sudo[2133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 18:06:40 rasberry-5-usb sudo[2133]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 18:06:40 rasberry-5-usb sudo[2135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 18:06:40 rasberry-5-usb sudo[2135]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 18:06:40 rasberry-5-usb wpa_supplicant[1040]: wlan0: Failed to initiate sched scan Oct 12 18:06:40 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 18:06:40 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 18:06:40 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:40.435+09:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Oct 12 18:06:40 rasberry-5-usb sudo[2133]: pam_unix(sudo:session): session closed for user root Oct 12 18:06:40 rasberry-5-usb sudo[2135]: pam_unix(sudo:session): session closed for user root Oct 12 18:06:40 rasberry-5-usb volumio[1142]: verbose: New Socket.io Connection to 192.168.8.100 from 192.168.8.101 UA: Mozilla/5.0 (Linux; Android 13; SM-A325N Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.36 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 5 Oct 12 18:06:40 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:40.542+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.8.101:40348 @ 0x16ec6c0" latency=30.397209ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=3.40620372s Oct 12 18:06:40 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:40.779+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.8.101:40348 @ 0x16ec6c0" latency=30.397209ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=3.642510933s Oct 12 18:06:40 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:40.851+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.8.101:40348 @ 0x16ec6c0" latency=30.397209ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=3.715152007s Oct 12 18:06:40 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:40.943+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.8.101:40348 @ 0x16ec6c0" latency=30.397209ms timeout=10s endpoint=http://plugins.volumio.org duration=3.806490425s Oct 12 18:06:41 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:41.023+09:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.8.101:40348 @ 0x16ec6c0" latency=30.397209ms timeout=10s endpoint=http://cddb.volumio.org duration=3.886822008s error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 12 18:06:41 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:41.072+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.8.101:40348 @ 0x16ec6c0" latency=30.397209ms timeout=10s endpoint=https://google.com duration=3.936413647s Oct 12 18:06:41 rasberry-5-usb sudo[2153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 18:06:41 rasberry-5-usb sudo[2153]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 18:06:41 rasberry-5-usb sudo[2153]: pam_unix(sudo:session): session closed for user root Oct 12 18:06:41 rasberry-5-usb sudo[2155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 18:06:41 rasberry-5-usb sudo[2155]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 18:06:41 rasberry-5-usb sudo[2155]: pam_unix(sudo:session): session closed for user root Oct 12 18:06:41 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:41.108+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.8.101:40348 @ 0x16ec6c0" latency=30.397209ms timeout=10s endpoint=https://database.volumio.cloud duration=3.969940323s Oct 12 18:06:41 rasberry-5-usb volumio[1142]: verbose: New Socket.io Connection to 192.168.8.100 from 192.168.8.101 UA: Mozilla/5.0 (Linux; Android 13; SM-A325N Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.36 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 12 18:06:41 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:41.363+09:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 12 18:06:41 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 12 18:06:41 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 18:06:41 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 18:06:41 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 12 18:06:41 rasberry-5-usb volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 12 18:06:41 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 12 18:06:41 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 18:06:41 rasberry-5-usb volumio[1142]: info: Received Get System Info Oct 12 18:06:41 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 18:06:41 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 18:06:41 rasberry-5-usb volumio[1142]: info: Discovery: Getting this device information Oct 12 18:06:41 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 12 18:06:41 rasberry-5-usb volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 12 18:06:41 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 18:06:41 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 12 18:06:41 rasberry-5-usb volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 12 18:06:41 rasberry-5-usb volumio[1142]: info: Listing playlists Oct 12 18:06:41 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 12 18:06:41 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 18:06:41 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 12 18:06:43 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 18:06:43 rasberry-5-usb volumio[1142]: info: Received Get System Info Oct 12 18:06:43 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 18:06:43 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 18:06:43 rasberry-5-usb volumio[1142]: info: Discovery: Getting this device information Oct 12 18:06:43 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 12 18:06:43 rasberry-5-usb volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 12 18:06:43 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 18:06:43 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 12 18:06:43 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: Retrieving Cloud Streaming UI Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: Getting Tidal Cloud Configuration Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: Getting Qobuz Cloud Configuration Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: Asking plugin for UI Config Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: Getting Spotify Cloud Configuration Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: Asking plugin for UI Config Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: Saving Spotify Acccount Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: Got Tidal Cloud Configuration Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: Got it Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: Got it Oct 12 18:06:45 rasberry-5-usb volumio[1142]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 18:06:45 rasberry-5-usb volumio[1142]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetBrowseSources Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetBrowseSources Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetBrowseSources Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: Received Get System Info Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: Discovery: Getting this device information Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 18:06:45 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 12 18:06:47 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 18:06:47 rasberry-5-usb volumio[1142]: info: Received Get System Info Oct 12 18:06:47 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 18:06:47 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 18:06:47 rasberry-5-usb volumio[1142]: info: Discovery: Getting this device information Oct 12 18:06:47 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 12 18:06:47 rasberry-5-usb volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 12 18:06:47 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 18:06:47 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 18:06:47 rasberry-5-usb volumio[1142]: info: Received Get System Info Oct 12 18:06:47 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 18:06:47 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 18:06:47 rasberry-5-usb volumio[1142]: info: Discovery: Getting this device information Oct 12 18:06:47 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::volumioGetState Oct 12 18:06:47 rasberry-5-usb volumio[1142]: info: CorePlayQueue::getTrack 0 Oct 12 18:06:47 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 18:06:48 rasberry-5-usb volumio[1142]: info: Disabling MyMusic plugin upnp Oct 12 18:06:49 rasberry-5-usb volumio[1142]: info: Enabling MyMusic plugin upnp Oct 12 18:06:49 rasberry-5-usb volumio[1142]: info: Enabling plugin upnp Oct 12 18:06:49 rasberry-5-usb volumio[1142]: info: Loading plugin "upnp"... Oct 12 18:06:49 rasberry-5-usb volumio[1142]: info: [1791796009289] Starting Upmpd Daemon Oct 12 18:06:49 rasberry-5-usb volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 18:06:49 rasberry-5-usb volumio[1142]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 18:06:49 rasberry-5-usb volumio[1142]: Error: listen EADDRINUSE: address already in use :::6599 Oct 12 18:06:49 rasberry-5-usb volumio[1142]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Oct 12 18:06:49 rasberry-5-usb volumio[1142]: at listenInCluster (net.js:1366:12) Oct 12 18:06:49 rasberry-5-usb volumio[1142]: at Server.listen (net.js:1452:7) Oct 12 18:06:49 rasberry-5-usb volumio[1142]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 12 18:06:49 rasberry-5-usb volumio[1142]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 12 18:06:49 rasberry-5-usb volumio[1142]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 12 18:06:49 rasberry-5-usb volumio[1142]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 12 18:06:49 rasberry-5-usb volumio[1142]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Oct 12 18:06:49 rasberry-5-usb volumio[1142]: code: 'EADDRINUSE', Oct 12 18:06:49 rasberry-5-usb volumio[1142]: errno: -98, Oct 12 18:06:49 rasberry-5-usb volumio[1142]: syscall: 'listen', Oct 12 18:06:49 rasberry-5-usb volumio[1142]: address: '::', Oct 12 18:06:49 rasberry-5-usb volumio[1142]: port: 6599 Oct 12 18:06:49 rasberry-5-usb volumio[1142]: } Oct 12 18:06:49 rasberry-5-usb volumio[1142]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 18:06:49 rasberry-5-usb kernel: hwmon hwmon2: Undervoltage detected! Oct 12 18:06:51 rasberry-5-usb kernel: hwmon hwmon2: Voltage normalised Oct 12 18:06:51 rasberry-5-usb sudo[2169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-12 18:05 Oct 12 18:06:51 rasberry-5-usb sudo[2159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 12 18:06:51 rasberry-5-usb sudo[2169]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 18:06:51 rasberry-5-usb sudo[2159]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 18:06:51 rasberry-5-usb wpa_supplicant[1040]: wlan0: Failed to initiate sched scan Oct 12 18:06:51 rasberry-5-usb volumio5-onboarding[1269]: time=2026-10-12T18:06:51.932+09:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.8.101:40348 @ 0x16ec6c0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Oct 12 18:06:51 rasberry-5-usb systemd[1]: Stopping UPnP Renderer front-end to MPD... PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"