-- Logs begin at Wed 2025-09-17 21:23:33 EEST, end at Wed 2025-09-17 22:52:31 EEST. -- Sep 17 22:51:06 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 22:51:06 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 22:51:06 volumio1 volumio[1127]: info: Discovery: Getting this device information Sep 17 22:51:06 volumio1 volumio[1127]: info: CoreCommandRouter::volumioGetState Sep 17 22:51:06 volumio1 volumio[1127]: info: CorePlayQueue::getTrack 4 Sep 17 22:51:06 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 22:51:12 volumio1 volumio[1127]: info: CoreCommandRouter::volumioGetState Sep 17 22:51:12 volumio1 volumio[1127]: info: CorePlayQueue::getTrack 4 Sep 17 22:51:16 volumio1 volumio[1127]: info: Preload queue cleared Sep 17 22:51:26 volumio1 volumio[1127]: info: CoreCommandRouter::volumioGetState Sep 17 22:51:26 volumio1 volumio[1127]: info: CorePlayQueue::getTrack 4 Sep 17 22:51:30 volumio1 volumio[1127]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 22:51:30 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 17 22:51:30 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Sep 17 22:51:30 volumio1 sudo[12925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Sep 17 22:51:30 volumio1 sudo[12925]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:51:30 volumio1 sudo[12925]: pam_unix(sudo:session): session closed for user root Sep 17 22:51:30 volumio1 sudo[12931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 17 22:51:30 volumio1 sudo[12931]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:51:30 volumio1 sudo[12931]: pam_unix(sudo:session): session closed for user root Sep 17 22:51:30 volumio1 sudo[12938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 17 22:51:30 volumio1 sudo[12938]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:51:30 volumio1 sudo[12945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 17 22:51:30 volumio1 sudo[12945]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:51:30 volumio1 sudo[12938]: pam_unix(sudo:session): session closed for user root Sep 17 22:51:30 volumio1 sudo[12945]: pam_unix(sudo:session): session closed for user root Sep 17 22:51:30 volumio1 sudo[12950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 17 22:51:30 volumio1 sudo[12950]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:51:30 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Sep 17 22:51:30 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Sep 17 22:51:30 volumio1 sudo[12950]: pam_unix(sudo:session): session closed for user root Sep 17 22:51:30 volumio1 sudo[12954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 17 22:51:30 volumio1 sudo[12954]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:51:30 volumio1 sudo[12954]: pam_unix(sudo:session): session closed for user root Sep 17 22:51:30 volumio1 sudo[12957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 17 22:51:30 volumio1 sudo[12957]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:51:34 volumio1 sudo[12957]: pam_unix(sudo:session): session closed for user root Sep 17 22:51:36 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 22:51:36 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 22:51:36 volumio1 volumio[1127]: info: Discovery: Getting this device information Sep 17 22:51:36 volumio1 volumio[1127]: info: CoreCommandRouter::volumioGetState Sep 17 22:51:36 volumio1 volumio[1127]: info: CorePlayQueue::getTrack 4 Sep 17 22:51:36 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 22:52:00 volumio1 volumio[1127]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object] Sep 17 22:52:00 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Sep 17 22:52:01 volumio1 sudo[13006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Sep 17 22:52:01 volumio1 sudo[13006]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:01 volumio1 sudo[13006]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:01 volumio1 volumio[1127]: info: Permissions for /etc/hostapd/hostapd.conf Sep 17 22:52:01 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Sep 17 22:52:01 volumio1 volumio[1127]: info: Discovery: Restarting Advertising due to device name change Sep 17 22:52:01 volumio1 sudo[13009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Sep 17 22:52:01 volumio1 sudo[13009]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:01 volumio1 systemd[1]: wireless.service: Killing process 1030 (wpa_supplicant) with signal SIGKILL. Sep 17 22:52:01 volumio1 systemd[1]: wireless.service: Killing process 1042 (dhcpcd) with signal SIGKILL. Sep 17 22:52:01 volumio1 systemd[1]: Stopping Wireless Services... Sep 17 22:52:01 volumio1 systemd[1]: wireless.service: Succeeded. Sep 17 22:52:01 volumio1 systemd[1]: Stopped Wireless Services. Sep 17 22:52:01 volumio1 kernel: ieee80211 phy0: brcmf_cfg80211_reg_notifier: Firmware rejected country setting Sep 17 22:52:01 volumio1 systemd[1]: Starting Wireless Services... Sep 17 22:52:01 volumio1 wireless.js[13012]: Cleaning previous... Sep 17 22:52:01 volumio1 sudo[13027]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 17 22:52:01 volumio1 sudo[13027]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:01 volumio1 avahi-daemon[476]: Withdrawing address record for 192.168.0.100 on wlan0. Sep 17 22:52:01 volumio1 avahi-daemon[476]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.100. Sep 17 22:52:01 volumio1 avahi-daemon[476]: Interface wlan0.IPv4 no longer relevant for mDNS. Sep 17 22:52:01 volumio1 sudo[13027]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:01 volumio1 volumio[1127]: info: Discovery: A device disappeared from network Sep 17 22:52:01 volumio1 volumio[1127]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Sep 17 22:52:02 volumio1 sudo[13029]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 17 22:52:02 volumio1 sudo[13029]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:02 volumio1 sudo[13029]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:02 volumio1 wireless.js[13012]: Stopped aP Sep 17 22:52:02 volumio1 sudo[13036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 17 22:52:02 volumio1 sudo[13036]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:02 volumio1 sudo[13036]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:02 volumio1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 17 22:52:02 volumio1 sudo[13038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Sep 17 22:52:02 volumio1 sudo[13038]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:02 volumio1 sudo[13038]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:02 volumio1 sudo[13045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 17 22:52:02 volumio1 sudo[13045]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:02 volumio1 sudo[13045]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:02 volumio1 sudo[13047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Sep 17 22:52:02 volumio1 sudo[13047]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:03 volumio1 ntpd[700]: Deleting interface #3 wlan0, 192.168.0.100#123, interface stats: received=277, sent=290, dropped=0, active_time=4888 secs Sep 17 22:52:03 volumio1 ntpd[700]: 193.106.144.6 local addr 192.168.0.100 -> Sep 17 22:52:03 volumio1 ntpd[700]: 193.106.144.7 local addr 192.168.0.100 -> Sep 17 22:52:03 volumio1 ntpd[700]: 162.159.200.1 local addr 192.168.0.100 -> Sep 17 22:52:03 volumio1 ntpd[700]: 188.191.234.54 local addr 192.168.0.100 -> Sep 17 22:52:03 volumio1 ntpd[700]: 176.120.61.236 local addr 192.168.0.100 -> Sep 17 22:52:03 volumio1 ntpd[700]: 194.8.146.38 local addr 192.168.0.100 -> Sep 17 22:52:05 volumio1 sudo[13047]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:05 volumio1 wireless.js[13012]: Start wireless flow Sep 17 22:52:05 volumio1 wireless.js[13012]: Stopped hotspot (if there).. Sep 17 22:52:05 volumio1 sudo[13055]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 17 22:52:05 volumio1 sudo[13055]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:05 volumio1 sudo[13055]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:05 volumio1 sudo[13057]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 17 22:52:05 volumio1 sudo[13057]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:06 volumio1 sudo[13057]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:06 volumio1 wireless.js[13012]: DHCP IP Sep 17 22:52:06 volumio1 wireless.js[13012]: Start ap Sep 17 22:52:06 volumio1 wpa_supplicant[13060]: Successfully initialized wpa_supplicant Sep 17 22:52:06 volumio1 sudo[13061]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Sep 17 22:52:06 volumio1 sudo[13061]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:06 volumio1 dhcpcd[13062]: main: control_open: Connection refused Sep 17 22:52:06 volumio1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 17 22:52:06 volumio1 dhcpcd[13062]: dev: loaded udev Sep 17 22:52:06 volumio1 dhcpcd-run-hooks[13087]: wlan0: starting wpa_supplicant Sep 17 22:52:06 volumio1 volumio[1127]: info: Discovery: Started advertising with name: Volumio1 Sep 17 22:52:06 volumio1 dhcpcd-run-hooks[13093]: wlan0: failed to start wpa_supplicant Sep 17 22:52:06 volumio1 dhcpcd-run-hooks[13094]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Sep 17 22:52:06 volumio1 dhcpcd[13062]: wlan0: connected to Access Point `' Sep 17 22:52:06 volumio1 dhcpcd[13062]: no interfaces have a carrier Sep 17 22:52:06 volumio1 dhcpcd[13062]: forked to background, child pid 13112 Sep 17 22:52:06 volumio1 dhcpcd[13112]: eth0: waiting for carrier Sep 17 22:52:06 volumio1 dhcpcd[13112]: wlan0: waiting for carrier Sep 17 22:52:06 volumio1 sudo[13061]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:07 volumio1 wireless.js[13012]: trying... Sep 17 22:52:07 volumio1 sudo[13114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 22:52:07 volumio1 sudo[13114]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:07 volumio1 sudo[13114]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:07 volumio1 nmbd[661]: [2025/09/17 22:52:07.542622, 0] ../source3/libsmb/nmblib.c:917(send_udp) Sep 17 22:52:07 volumio1 nmbd[661]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Sep 17 22:52:07 volumio1 nmbd[661]: [2025/09/17 22:52:07.542871, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Sep 17 22:52:07 volumio1 nmbd[661]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Sep 17 22:52:07 volumio1 nmbd[661]: [2025/09/17 22:52:07.542957, 0] ../source3/libsmb/nmblib.c:917(send_udp) Sep 17 22:52:07 volumio1 nmbd[661]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Sep 17 22:52:07 volumio1 nmbd[661]: [2025/09/17 22:52:07.543021, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Sep 17 22:52:07 volumio1 nmbd[661]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Sep 17 22:52:07 volumio1 nmbd[661]: [2025/09/17 22:52:07.543084, 0] ../source3/libsmb/nmblib.c:917(send_udp) Sep 17 22:52:07 volumio1 nmbd[661]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Sep 17 22:52:07 volumio1 nmbd[661]: [2025/09/17 22:52:07.543147, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Sep 17 22:52:07 volumio1 nmbd[661]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Sep 17 22:52:07 volumio1 nmbd[661]: [2025/09/17 22:52:07.543207, 0] ../source3/libsmb/nmblib.c:917(send_udp) Sep 17 22:52:07 volumio1 nmbd[661]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Sep 17 22:52:07 volumio1 nmbd[661]: [2025/09/17 22:52:07.543264, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Sep 17 22:52:07 volumio1 nmbd[661]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Sep 17 22:52:07 volumio1 nmbd[661]: [2025/09/17 22:52:07.543324, 0] ../source3/libsmb/nmblib.c:917(send_udp) Sep 17 22:52:07 volumio1 nmbd[661]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Sep 17 22:52:07 volumio1 nmbd[661]: [2025/09/17 22:52:07.543381, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Sep 17 22:52:07 volumio1 nmbd[661]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Sep 17 22:52:07 volumio1 nmbd[661]: [2025/09/17 22:52:07.543458, 0] ../source3/libsmb/nmblib.c:917(send_udp) Sep 17 22:52:07 volumio1 nmbd[661]: Packet send failed to 192.168.0.255(137) ERRNO=Network is unreachable Sep 17 22:52:07 volumio1 nmbd[661]: [2025/09/17 22:52:07.543518, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Sep 17 22:52:07 volumio1 nmbd[661]: send_netbios_packet: send_packet() to IP 192.168.0.255 port 137 failed Sep 17 22:52:07 volumio1 nmbd[661]: [2025/09/17 22:52:07.543569, 0] ../source3/nmbd/nmbd_namequery.c:245(query_name) Sep 17 22:52:07 volumio1 nmbd[661]: query_name: Failed to send packet trying to query name WORKGROUP<1d> Sep 17 22:52:08 volumio1 wireless.js[13012]: trying... Sep 17 22:52:08 volumio1 sudo[13118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 22:52:08 volumio1 sudo[13118]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:08 volumio1 sudo[13118]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:09 volumio1 wireless.js[13012]: trying... Sep 17 22:52:09 volumio1 sudo[13121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 22:52:09 volumio1 sudo[13121]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:09 volumio1 sudo[13121]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:10 volumio1 wireless.js[13012]: trying... Sep 17 22:52:10 volumio1 sudo[13124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 22:52:10 volumio1 sudo[13124]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:10 volumio1 sudo[13124]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:10 volumio1 volumio[1127]: info: Volumio Network Manager: Network status updated: 0 Sep 17 22:52:10 volumio1 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100c fail, reason -52 Sep 17 22:52:10 volumio1 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100d fail, reason -52 Sep 17 22:52:10 volumio1 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 17 22:52:10 volumio1 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 17 22:52:10 volumio1 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 17 22:52:10 volumio1 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 17 22:52:11 volumio1 wireless.js[13012]: trying... Sep 17 22:52:11 volumio1 sudo[13141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 22:52:11 volumio1 sudo[13141]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:11 volumio1 sudo[13141]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:12 volumio1 wireless.js[13012]: trying... Sep 17 22:52:12 volumio1 sudo[13144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 22:52:12 volumio1 sudo[13144]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:12 volumio1 sudo[13144]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:12 volumio1 wpa_supplicant[13091]: wlan0: Trying to associate with SSID 'internetanet5' Sep 17 22:52:12 volumio1 dhcpcd[13112]: wlan0: carrier acquired Sep 17 22:52:12 volumio1 dhcpcd[13112]: wlan0: connected to Access Point `internetanet5' Sep 17 22:52:12 volumio1 wpa_supplicant[13091]: wlan0: Associated with 5c:a6:e6:2a:c8:05 Sep 17 22:52:12 volumio1 wpa_supplicant[13091]: wlan0: CTRL-EVENT-CONNECTED - Connection to 5c:a6:e6:2a:c8:05 completed [id=0 id_str=] Sep 17 22:52:12 volumio1 wpa_supplicant[13091]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 17 22:52:12 volumio1 dhcpcd[13112]: DUID 00:01:00:01:2d:5e:64:95:d8:3a:dd:9e:81:70 Sep 17 22:52:12 volumio1 dhcpcd[13112]: wlan0: IAID dd:9e:81:72 Sep 17 22:52:12 volumio1 dhcpcd[13112]: wlan0: adding address fe80::da3a:ddff:fe9e:8172 Sep 17 22:52:12 volumio1 dhcpcd[13112]: ipv6_addaddr1: Permission denied Sep 17 22:52:12 volumio1 dhcpcd[13112]: wlan0: carrier lost Sep 17 22:52:12 volumio1 dhcpcd[13112]: wlan0: carrier acquired Sep 17 22:52:12 volumio1 dhcpcd[13112]: wlan0: IAID dd:9e:81:72 Sep 17 22:52:13 volumio1 wireless.js[13012]: trying... Sep 17 22:52:13 volumio1 sudo[13166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 22:52:13 volumio1 sudo[13166]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:13 volumio1 sudo[13166]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:13 volumio1 dhcpcd[13112]: wlan0: soliciting an IPv6 router Sep 17 22:52:13 volumio1 dhcpcd[13112]: wlan0: rebinding lease of 192.168.0.100 Sep 17 22:52:13 volumio1 dhcpcd[13112]: wlan0: probing address 192.168.0.100/24 Sep 17 22:52:14 volumio1 wireless.js[13012]: trying... Sep 17 22:52:14 volumio1 sudo[13169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 22:52:14 volumio1 sudo[13169]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:14 volumio1 sudo[13169]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:15 volumio1 wireless.js[13012]: trying... Sep 17 22:52:15 volumio1 sudo[13172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 22:52:15 volumio1 sudo[13172]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:15 volumio1 sudo[13172]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:16 volumio1 wireless.js[13012]: trying... Sep 17 22:52:16 volumio1 sudo[13175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 22:52:16 volumio1 sudo[13175]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:16 volumio1 sudo[13175]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:17 volumio1 wireless.js[13012]: trying... Sep 17 22:52:17 volumio1 sudo[13178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 22:52:17 volumio1 sudo[13178]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:17 volumio1 sudo[13178]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:17 volumio1 dhcpcd[13112]: wlan0: leased 192.168.0.100 for infinity Sep 17 22:52:17 volumio1 avahi-daemon[476]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.100. Sep 17 22:52:17 volumio1 avahi-daemon[476]: New relevant interface wlan0.IPv4 for mDNS. Sep 17 22:52:17 volumio1 dhcpcd[13112]: wlan0: adding route to 192.168.0.0/24 Sep 17 22:52:17 volumio1 dhcpcd[13112]: wlan0: adding default route via 192.168.0.1 Sep 17 22:52:17 volumio1 avahi-daemon[476]: Registering new address record for 192.168.0.100 on wlan0.IPv4. Sep 17 22:52:18 volumio1 wireless.js[13012]: trying... Sep 17 22:52:18 volumio1 sudo[13201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 22:52:18 volumio1 sudo[13201]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:18 volumio1 sudo[13201]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:18 volumio1 wireless.js[13012]: Connected to: ----internetanet5 Sep 17 22:52:18 volumio1 wireless.js[13012]: ---- Sep 17 22:52:18 volumio1 sudo[13205]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 17 22:52:18 volumio1 sudo[13205]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:18 volumio1 sudo[13205]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:18 volumio1 wireless.js[13012]: ... joined AP, wlan0 IPv4 is 192.168.0.100, ipV6 is undefined Sep 17 22:52:18 volumio1 wireless.js[13012]: It's done! AP Sep 17 22:52:18 volumio1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 17 22:52:18 volumio1 systemd[1]: Started Wireless Services. Sep 17 22:52:18 volumio1 sudo[13009]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:18 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 22:52:18 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 22:52:18 volumio1 volumio[1127]: info: Discovery: Getting this device information Sep 17 22:52:18 volumio1 volumio[1127]: info: CoreCommandRouter::volumioGetState Sep 17 22:52:18 volumio1 volumio[1127]: info: CorePlayQueue::getTrack 4 Sep 17 22:52:18 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 22:52:18 volumio1 volumio[1127]: verbose: New Socket.io Connection to 192.168.0.100 from 192.168.0.117 UA: Mozilla/5.0 (Linux; Android 15; SM-F946B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7419.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Sep 17 22:52:18 volumio1 volumio[1127]: verbose: New Socket.io Connection to 192.168.0.100 from 192.168.0.117 UA: Mozilla/5.0 (Linux; Android 15; SM-F946B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7419.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Sep 17 22:52:18 volumio1 volumio[1127]: verbose: New Socket.io Connection to 192.168.0.100 from 192.168.0.117 UA: Mozilla/5.0 (Linux; Android 15; SM-F946B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7419.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Sep 17 22:52:18 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 17 22:52:18 volumio1 volumio[1127]: info: CoreCommandRouter::volumioGetVisibleSources Sep 17 22:52:18 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 22:52:18 volumio1 volumio[1127]: info: CoreCommandRouter::volumioGetState Sep 17 22:52:18 volumio1 volumio[1127]: info: CorePlayQueue::getTrack 4 Sep 17 22:52:18 volumio1 volumio[1127]: info: CoreCommandRouter::volumioGetState Sep 17 22:52:18 volumio1 volumio[1127]: info: CorePlayQueue::getTrack 4 Sep 17 22:52:18 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 17 22:52:18 volumio1 volumio[1127]: info: CoreCommandRouter::volumioGetQueue Sep 17 22:52:18 volumio1 volumio[1127]: info: CoreStateMachine::getQueue Sep 17 22:52:18 volumio1 volumio[1127]: info: CorePlayQueue::getQueue Sep 17 22:52:18 volumio1 volumio[1127]: info: Listing playlists Sep 17 22:52:18 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 17 22:52:18 volumio1 volumio[1127]: info: Received Get System Info Sep 17 22:52:18 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 22:52:18 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 22:52:18 volumio1 volumio[1127]: info: Discovery: Getting this device information Sep 17 22:52:18 volumio1 volumio[1127]: info: CoreCommandRouter::volumioGetState Sep 17 22:52:18 volumio1 volumio[1127]: info: CorePlayQueue::getTrack 4 Sep 17 22:52:18 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 22:52:18 volumio1 volumio[1127]: info: CoreCommandRouter::volumioGetState Sep 17 22:52:18 volumio1 volumio[1127]: info: CorePlayQueue::getTrack 4 Sep 17 22:52:18 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 17 22:52:18 volumio1 volumio[1127]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Sep 17 22:52:19 volumio1 volumio[1127]: info: CoreCommandRouter::volumioGetState Sep 17 22:52:19 volumio1 volumio[1127]: info: CorePlayQueue::getTrack 4 Sep 17 22:52:19 volumio1 ntpd[700]: Listen normally on 4 wlan0 192.168.0.100:123 Sep 17 22:52:19 volumio1 ntpd[700]: new interface(s) found: waking up resolver Sep 17 22:52:20 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 22:52:20 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 22:52:20 volumio1 volumio[1127]: info: Discovery: Getting this device information Sep 17 22:52:20 volumio1 volumio[1127]: info: CoreCommandRouter::volumioGetState Sep 17 22:52:20 volumio1 volumio[1127]: info: CorePlayQueue::getTrack 4 Sep 17 22:52:20 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 22:52:20 volumio1 volumio[1127]: verbose: New Socket.io Connection to 192.168.0.100:3000 from 192.168.0.117 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Sep 17 22:52:20 volumio1 volumio[1127]: info: CoreCommandRouter::volumioGetState Sep 17 22:52:20 volumio1 volumio[1127]: info: CorePlayQueue::getTrack 4 Sep 17 22:52:20 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 17 22:52:20 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 17 22:52:20 volumio1 volumio[1127]: info: Volumio Network Manager: Network status updated: 2 Sep 17 22:52:21 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 22:52:21 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 22:52:21 volumio1 volumio[1127]: info: Discovery: Getting this device information Sep 17 22:52:21 volumio1 volumio[1127]: info: CoreCommandRouter::volumioGetState Sep 17 22:52:21 volumio1 volumio[1127]: info: CorePlayQueue::getTrack 4 Sep 17 22:52:21 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 22:52:21 volumio1 volumio[1127]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 22:52:21 volumio1 volumio[1127]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 22:52:21 volumio1 volumio[1127]: info: Retrieving Cloud Streaming UI Sep 17 22:52:21 volumio1 volumio[1127]: info: Getting Tidal Cloud Configuration Sep 17 22:52:21 volumio1 volumio[1127]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 22:52:21 volumio1 volumio[1127]: info: Getting Qobuz Cloud Configuration Sep 17 22:52:21 volumio1 volumio[1127]: info: Asking plugin for UI Config Sep 17 22:52:21 volumio1 volumio[1127]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 22:52:21 volumio1 volumio[1127]: info: Getting Spotify Cloud Configuration Sep 17 22:52:21 volumio1 volumio[1127]: info: Asking plugin for UI Config Sep 17 22:52:21 volumio1 volumio[1127]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 22:52:21 volumio1 volumio[1127]: info: Saving Spotify Acccount Sep 17 22:52:21 volumio1 volumio[1127]: info: Got Tidal Cloud Configuration Sep 17 22:52:21 volumio1 volumio[1127]: info: Got it Sep 17 22:52:21 volumio1 volumio[1127]: info: Got it Sep 17 22:52:21 volumio1 volumio[1127]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 17 22:52:21 volumio1 volumio[1127]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 22:52:21 volumio1 volumio[1127]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Sep 17 22:52:21 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 17 22:52:21 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 17 22:52:21 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 17 22:52:21 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 17 22:52:21 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 22:52:21 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 22:52:21 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 22:52:21 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 22:52:21 volumio1 volumio[1127]: info: CoreCommandRouter::volumioGetBrowseSources Sep 17 22:52:21 volumio1 volumio[1127]: info: CoreCommandRouter::volumioGetBrowseSources Sep 17 22:52:21 volumio1 volumio[1127]: info: CoreCommandRouter::volumioGetBrowseSources Sep 17 22:52:21 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 17 22:52:21 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 17 22:52:23 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Sep 17 22:52:23 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Sep 17 22:52:23 volumio1 sudo[13225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 17 22:52:23 volumio1 sudo[13225]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:23 volumio1 sudo[13225]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:23 volumio1 sudo[13228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 17 22:52:23 volumio1 sudo[13228]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:23 volumio1 sudo[13228]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:24 volumio1 volumio[1127]: info: Disabling MyMusic plugin upnp Sep 17 22:52:24 volumio1 sudo[13274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Sep 17 22:52:24 volumio1 sudo[13274]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:24 volumio1 volumio[1127]: error: Upnp client error: Error: This socket has been ended by the other party Sep 17 22:52:24 volumio1 systemd[1]: Stopping UPnP Renderer front-end to MPD... Sep 17 22:52:25 volumio1 volumio[1127]: info: Disabling MyMusic plugin upnp_browser Sep 17 22:52:25 volumio1 volumio[1127]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMedia Servers Sep 17 22:52:25 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 22:52:25 volumio1 volumio[1127]: info: Disabling plugin upnp_browser Sep 17 22:52:25 volumio1 volumio[1127]: info: Done. Sep 17 22:52:25 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 17 22:52:26 volumio1 ntpd[700]: Soliciting pool server 91.236.251.31 Sep 17 22:52:27 volumio1 volumio[1127]: info: Enabling MyMusic plugin upnp_browser Sep 17 22:52:27 volumio1 volumio[1127]: info: Enabling plugin upnp_browser Sep 17 22:52:27 volumio1 volumio[1127]: info: Loading plugin "upnp_browser"... Sep 17 22:52:27 volumio1 volumio[1127]: info: PLUGIN START: upnp_browser Sep 17 22:52:27 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 22:52:27 volumio1 volumio[1127]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 17 22:52:27 volumio1 volumio[1127]: info: [1758138747276] CoreMusicLibrary::Adding element Media Servers Sep 17 22:52:27 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 22:52:27 volumio1 volumio[1127]: info: Done. Sep 17 22:52:28 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Sep 17 22:52:28 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Sep 17 22:52:28 volumio1 volumio[1127]: info: Refreshing Cached IP Addresses Sep 17 22:52:28 volumio1 sudo[13279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Sep 17 22:52:28 volumio1 sudo[13279]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:28 volumio1 sudo[13279]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:28 volumio1 sudo[13282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 17 22:52:28 volumio1 sudo[13282]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:28 volumio1 sudo[13282]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:28 volumio1 volumio[1127]: error: Upnp client error: Error: This socket has been ended by the other party Sep 17 22:52:28 volumio1 sudo[13285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 17 22:52:28 volumio1 sudo[13285]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:28 volumio1 sudo[13285]: pam_unix(sudo:session): session closed for user root Sep 17 22:52:28 volumio1 volumio[1127]: info: Disabling MyMusic plugin upnp_browser Sep 17 22:52:28 volumio1 volumio[1127]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMedia Servers Sep 17 22:52:28 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 22:52:28 volumio1 volumio[1127]: info: Disabling plugin upnp_browser Sep 17 22:52:28 volumio1 volumio[1127]: info: Done. Sep 17 22:52:28 volumio1 volumio[1127]: info: Enabling MyMusic plugin upnp_browser Sep 17 22:52:28 volumio1 volumio[1127]: info: Enabling plugin upnp_browser Sep 17 22:52:28 volumio1 volumio[1127]: info: Loading plugin "upnp_browser"... Sep 17 22:52:28 volumio1 volumio[1127]: info: PLUGIN START: upnp_browser Sep 17 22:52:28 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 22:52:28 volumio1 volumio[1127]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 17 22:52:28 volumio1 volumio[1127]: info: [1758138748426] CoreMusicLibrary::Adding element Media Servers Sep 17 22:52:28 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 22:52:28 volumio1 volumio[1127]: info: Done. Sep 17 22:52:29 volumio1 volumio[1127]: info: CorePlayQueue::getTrack 4 Sep 17 22:52:29 volumio1 volumio[1127]: info: CorePlayQueue::getTrack 7 Sep 17 22:52:29 volumio1 volumio[1127]: info: Prefetching next song Sep 17 22:52:29 volumio1 volumio[1127]: info: DOING PREFETCH IN MPD Sep 17 22:52:29 volumio1 volumio[1127]: verbose: ControllerMpd::sendMpdCommand add "USB/HiBy/Music/Sofia Isella/2024 - I Can Be Your Mother [24B-44.1kHz]/06. I Can Be Your Mother.flac" Sep 17 22:52:29 volumio1 volumio[1127]: info: Sep 17 22:52:29 volumio1 volumio[1127]: ---------------------------- MPD announces system playlist update Sep 17 22:52:29 volumio1 volumio[1127]: info: Ignoring MPD Status Update Sep 17 22:52:29 volumio1 volumio[1127]: info: sendMpdCommand add "USB/HiBy/Music/Sofia Isella/2024 - I Can Be Your Mother [24B-44.1kHz]/06. I Can Be Your Mother.flac" took 2 milliseconds Sep 17 22:52:29 volumio1 volumio[1127]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 17 22:52:29 volumio1 volumio[1127]: info: Sep 17 22:52:29 volumio1 volumio[1127]: ---------------------------- MPD announces system playlist update Sep 17 22:52:29 volumio1 volumio[1127]: info: Ignoring MPD Status Update Sep 17 22:52:29 volumio1 volumio[1127]: info: Sep 17 22:52:29 volumio1 volumio[1127]: ---------------------------- MPD announces state update: options Sep 17 22:52:29 volumio1 volumio[1127]: info: ------------------------------ 3ms Sep 17 22:52:29 volumio1 volumio[1127]: info: sendMpdCommand consume 1 took 2 milliseconds Sep 17 22:52:29 volumio1 volumio[1127]: info: ControllerMpd::getState Sep 17 22:52:29 volumio1 volumio[1127]: verbose: ControllerMpd::sendMpdCommand status Sep 17 22:52:29 volumio1 volumio[1127]: info: ------------------------------ 2ms Sep 17 22:52:29 volumio1 volumio[1127]: info: Sep 17 22:52:29 volumio1 volumio[1127]: ---------------------------- MPD announces state update: options Sep 17 22:52:29 volumio1 volumio[1127]: info: ControllerMpd::getState Sep 17 22:52:29 volumio1 volumio[1127]: verbose: ControllerMpd::sendMpdCommand status Sep 17 22:52:29 volumio1 volumio[1127]: info: sendMpdCommand status took 4 milliseconds Sep 17 22:52:29 volumio1 volumio[1127]: info: sendMpdCommand status took 1 milliseconds Sep 17 22:52:29 volumio1 volumio[1127]: verbose: ControllerMpd::parseState Sep 17 22:52:29 volumio1 volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 22:52:29 volumio1 volumio[1127]: verbose: ControllerMpd::parseState Sep 17 22:52:29 volumio1 volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 22:52:29 volumio1 volumio[1127]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 17 22:52:29 volumio1 volumio[1127]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 17 22:52:29 volumio1 volumio[1127]: verbose: ControllerMpd::parseTrackInfo Sep 17 22:52:29 volumio1 volumio[1127]: verbose: ControllerMpd::parseTrackInfo Sep 17 22:52:29 volumio1 volumio[1127]: info: ControllerMpd::pushState Sep 17 22:52:29 volumio1 volumio[1127]: info: CoreCommandRouter::servicePushState Sep 17 22:52:29 volumio1 volumio[1127]: info: CorePlayQueue::getTrack 4 Sep 17 22:52:29 volumio1 volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":388242,"duration":393,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2303 Kbps","isStreaming":false,"title":"Shout","artist":"Tears For Fears","album":"Songs From The Big Chair","uri":"USB/HiBy/Music/Tears For Fears/1985 - Songs From The Big Chair [24B-96kHz]/01. Shout.flac","trackType":"flac"} Sep 17 22:52:29 volumio1 volumio[1127]: verbose: CURRENT POSITION 4 Sep 17 22:52:29 volumio1 volumio[1127]: info: CoreStateMachine::syncState stateService play Sep 17 22:52:29 volumio1 volumio[1127]: info: CoreStateMachine::syncState currentStatus play Sep 17 22:52:29 volumio1 volumio[1127]: info: Received an update from plugin. extracting info from payload Sep 17 22:52:29 volumio1 volumio[1127]: info: CoreStateMachine::pushState Sep 17 22:52:29 volumio1 volumio[1127]: info: CorePlayQueue::getTrack 4 Sep 17 22:52:29 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 22:52:29 volumio1 volumio[1127]: info: CoreCommandRouter::volumioPushState Sep 17 22:52:29 volumio1 volumio[1127]: info: CoreStateMachine::pushState Sep 17 22:52:29 volumio1 volumio[1127]: info: CorePlayQueue::getTrack 4 Sep 17 22:52:29 volumio1 volumio[1127]: info: CoreCommandRouter::volumioPushState Sep 17 22:52:29 volumio1 volumio[1127]: info: ControllerMpd::pushState Sep 17 22:52:29 volumio1 volumio[1127]: info: CoreCommandRouter::servicePushState Sep 17 22:52:29 volumio1 volumio[1127]: info: CorePlayQueue::getTrack 4 Sep 17 22:52:29 volumio1 volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":388242,"duration":393,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2303 Kbps","isStreaming":false,"title":"Shout","artist":"Tears For Fears","album":"Songs From The Big Chair","uri":"USB/HiBy/Music/Tears For Fears/1985 - Songs From The Big Chair [24B-96kHz]/01. Shout.flac","trackType":"flac"} Sep 17 22:52:29 volumio1 volumio[1127]: verbose: CURRENT POSITION 4 Sep 17 22:52:29 volumio1 volumio[1127]: info: CoreStateMachine::syncState stateService play Sep 17 22:52:29 volumio1 volumio[1127]: info: CoreStateMachine::syncState currentStatus play Sep 17 22:52:29 volumio1 volumio[1127]: info: Received an update from plugin. extracting info from payload Sep 17 22:52:29 volumio1 volumio[1127]: info: CoreStateMachine::pushState Sep 17 22:52:29 volumio1 volumio[1127]: info: CorePlayQueue::getTrack 4 Sep 17 22:52:29 volumio1 volumio[1127]: info: CoreCommandRouter::volumioPushState Sep 17 22:52:29 volumio1 volumio[1127]: info: CoreStateMachine::pushState Sep 17 22:52:29 volumio1 volumio[1127]: info: CorePlayQueue::getTrack 4 Sep 17 22:52:29 volumio1 volumio[1127]: info: CoreCommandRouter::volumioPushState Sep 17 22:52:29 volumio1 volumio[1127]: info: ------------------------------ 23ms Sep 17 22:52:29 volumio1 volumio[1127]: info: ------------------------------ 23ms Sep 17 22:52:29 volumio1 volumio[1127]: info: Disabling MyMusic plugin upnp_browser Sep 17 22:52:29 volumio1 volumio[1127]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMedia Servers Sep 17 22:52:29 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 22:52:29 volumio1 volumio[1127]: info: Disabling plugin upnp_browser Sep 17 22:52:29 volumio1 volumio[1127]: info: Done. Sep 17 22:52:29 volumio1 ntpd[700]: Soliciting pool server 193.34.155.4 Sep 17 22:52:29 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 17 22:52:30 volumio1 volumio[1127]: info: Disabling MyMusic plugin upnp Sep 17 22:52:30 volumio1 sudo[13302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Sep 17 22:52:30 volumio1 sudo[13302]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 22:52:31 volumio1 volumio[1127]: info: Enabling MyMusic plugin upnp Sep 17 22:52:31 volumio1 volumio[1127]: info: Enabling plugin upnp Sep 17 22:52:31 volumio1 volumio[1127]: info: Loading plugin "upnp"... Sep 17 22:52:31 volumio1 volumio[1127]: info: [1758138751260] Starting Upmpd Daemon Sep 17 22:52:31 volumio1 volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 17 22:52:31 volumio1 volumio[1127]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 22:52:31 volumio1 volumio[1127]: Error: listen EADDRINUSE: address already in use :::6599 Sep 17 22:52:31 volumio1 volumio[1127]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Sep 17 22:52:31 volumio1 volumio[1127]: at listenInCluster (net.js:1366:12) Sep 17 22:52:31 volumio1 volumio[1127]: at Server.listen (net.js:1452:7) Sep 17 22:52:31 volumio1 volumio[1127]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Sep 17 22:52:31 volumio1 volumio[1127]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Sep 17 22:52:31 volumio1 volumio[1127]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Sep 17 22:52:31 volumio1 volumio[1127]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 17 22:52:31 volumio1 volumio[1127]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Sep 17 22:52:31 volumio1 volumio[1127]: code: 'EADDRINUSE', Sep 17 22:52:31 volumio1 volumio[1127]: errno: -98, Sep 17 22:52:31 volumio1 volumio[1127]: syscall: 'listen', Sep 17 22:52:31 volumio1 volumio[1127]: address: '::', Sep 17 22:52:31 volumio1 volumio[1127]: port: 6599 Sep 17 22:52:31 volumio1 volumio[1127]: } Sep 17 22:52:31 volumio1 volumio[1127]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 22:52:31 volumio1 sudo[13313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-17 22:51 Sep 17 22:52:31 volumio1 sudo[13313]: pam_unix(sudo:session): session opened for user root by (uid=0) 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"