-- Logs begin at Thu 2019-02-14 11:12:01 CET, end at Tue 2024-05-14 21:13:35 CEST. -- May 14 21:12:00 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 14 21:12:00 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 14 21:12:00 volumio systemd[1]: systemd-timedated.service: Succeeded. May 14 21:12:00 volumio volumio-remote-updater[495]: No test mode May 14 21:12:00 volumio volumio-remote-updater[495]: No alpha test mode May 14 21:12:00 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 14 21:12:00 volumio volumio[780]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 14 21:12:00 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 14 21:12:04 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: wizard , connectWirelessNetwork May 14 21:12:04 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: network , getWiredInfo May 14 21:12:04 volumio sudo[1685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 14 21:12:04 volumio sudo[1685]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:04 volumio sudo[1685]: pam_unix(sudo:session): session closed for user root May 14 21:12:04 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings May 14 21:12:04 volumio volumio[780]: info: Saving new wireless network May 14 21:12:04 volumio sudo[1688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf May 14 21:12:04 volumio sudo[1688]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:04 volumio sudo[1688]: pam_unix(sudo:session): session closed for user root May 14 21:12:04 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 14 21:12:04 volumio volumio[780]: info: Discovery: Restarting Advertising due to device name change May 14 21:12:04 volumio sudo[1691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service May 14 21:12:05 volumio sudo[1691]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:05 volumio systemd[1]: Starting Wireless Services... May 14 21:12:05 volumio volumio[780]: info: Discovery: A device disappeared from network May 14 21:12:06 volumio volumio[780]: info: Discovery: A device disappeared from network May 14 21:12:06 volumio wireless.js[1693]: Cleaning previous... May 14 21:12:06 volumio systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... May 14 21:12:06 volumio avahi-daemon[458]: Interface wlan0.IPv4 no longer relevant for mDNS. May 14 21:12:06 volumio avahi-daemon[458]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. May 14 21:12:06 volumio avahi-daemon[458]: Withdrawing address record for 192.168.211.1 on wlan0. May 14 21:12:06 volumio avahi-daemon[458]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. May 14 21:12:06 volumio avahi-daemon[458]: New relevant interface wlan0.IPv4 for mDNS. May 14 21:12:06 volumio avahi-daemon[458]: Registering new address record for 192.168.211.1 on wlan0.IPv4. May 14 21:12:06 volumio systemd[1]: hostapd.service: Succeeded. May 14 21:12:06 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. May 14 21:12:06 volumio sudo[1707]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 14 21:12:06 volumio sudo[1707]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:06 volumio avahi-daemon[458]: Withdrawing address record for 192.168.211.1 on wlan0. May 14 21:12:06 volumio avahi-daemon[458]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. May 14 21:12:06 volumio avahi-daemon[458]: Interface wlan0.IPv4 no longer relevant for mDNS. May 14 21:12:06 volumio sudo[1707]: pam_unix(sudo:session): session closed for user root May 14 21:12:06 volumio sudo[1709]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 14 21:12:06 volumio sudo[1709]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:07 volumio sudo[1709]: pam_unix(sudo:session): session closed for user root May 14 21:12:07 volumio wireless.js[1693]: Stopped aP May 14 21:12:07 volumio sudo[1716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 14 21:12:07 volumio sudo[1716]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:07 volumio sudo[1716]: pam_unix(sudo:session): session closed for user root May 14 21:12:07 volumio sudo[1718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 14 21:12:07 volumio sudo[1718]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:07 volumio sudo[1718]: pam_unix(sudo:session): session closed for user root May 14 21:12:07 volumio sudo[1725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 14 21:12:07 volumio sudo[1725]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:07 volumio sudo[1725]: pam_unix(sudo:session): session closed for user root May 14 21:12:07 volumio sudo[1727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 14 21:12:07 volumio sudo[1727]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:08 volumio ntpd[619]: Deleting interface #5 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=75 secs May 14 21:12:08 volumio sudo[1727]: pam_unix(sudo:session): session closed for user root May 14 21:12:09 volumio wireless.js[1693]: Start wireless flow May 14 21:12:09 volumio wireless.js[1693]: Stopped hotspot (if there).. May 14 21:12:09 volumio sudo[1732]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 14 21:12:09 volumio sudo[1732]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:09 volumio sudo[1732]: pam_unix(sudo:session): session closed for user root May 14 21:12:09 volumio sudo[1734]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 14 21:12:09 volumio sudo[1734]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:09 volumio sudo[1734]: pam_unix(sudo:session): session closed for user root May 14 21:12:09 volumio wireless.js[1693]: DHCP IP May 14 21:12:09 volumio wireless.js[1693]: Start ap May 14 21:12:09 volumio wpa_supplicant[1736]: Successfully initialized wpa_supplicant May 14 21:12:09 volumio sudo[1737]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd May 14 21:12:09 volumio sudo[1737]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:09 volumio dhcpcd[1738]: dev: loaded udev May 14 21:12:09 volumio dhcpcd[1738]: wlan0: connected to Access Point `' May 14 21:12:09 volumio dhcpcd[1738]: DUID 00:01:00:01:2d:bb:b4:8c:b8:27:eb:23:c7:50 May 14 21:12:09 volumio dhcpcd[1738]: eth0: IAID eb:23:c7:50 May 14 21:12:09 volumio dhcpcd[1738]: eth0: adding address fe80::ce68:baf3:6e4d:9472 May 14 21:12:09 volumio dhcpcd[1738]: ipv6_addaddr1: Permission denied May 14 21:12:09 volumio dhcpcd[1738]: wlan0: waiting for carrier May 14 21:12:09 volumio volumio[780]: info: Discovery: Started advertising with name: Volumio May 14 21:12:10 volumio dhcpcd[1738]: eth0: soliciting a DHCP lease May 14 21:12:10 volumio wireless.js[1693]: trying... May 14 21:12:10 volumio dhcpcd[1738]: eth0: offered 10.10.60.88 from 10.10.60.254 May 14 21:12:10 volumio dhcpcd[1738]: eth0: leased 10.10.60.88 for 86400 seconds May 14 21:12:10 volumio sudo[1766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:12:10 volumio sudo[1766]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:10 volumio sudo[1766]: pam_unix(sudo:session): session closed for user root May 14 21:12:10 volumio dhcpcd[1738]: eth0: adding route to 10.10.60.0/24 May 14 21:12:10 volumio dhcpcd[1738]: eth0: adding default route via 10.10.60.254 May 14 21:12:10 volumio dhcpcd[1738]: forked to background, child pid 1788 May 14 21:12:10 volumio sudo[1737]: pam_unix(sudo:session): session closed for user root May 14 21:12:10 volumio dhcpcd[1788]: eth0: soliciting an IPv6 router May 14 21:12:11 volumio wpa_supplicant[1747]: wlan0: SME: Trying to authenticate with 86:2a:a8:c4:b5:71 (SSID='Ander' freq=2462 MHz) May 14 21:12:11 volumio kernel: wlan0: authenticate with 86:2a:a8:c4:b5:71 May 14 21:12:11 volumio kernel: wlan0: 80 MHz not supported, disabling VHT May 14 21:12:11 volumio wpa_supplicant[1747]: wlan0: Trying to associate with 86:2a:a8:c4:b5:71 (SSID='Ander' freq=2462 MHz) May 14 21:12:11 volumio kernel: wlan0: send auth to 86:2a:a8:c4:b5:71 (try 1/3) May 14 21:12:11 volumio kernel: wlan0: authenticated May 14 21:12:11 volumio kernel: wlan0: associate with 86:2a:a8:c4:b5:71 (try 1/3) May 14 21:12:11 volumio volumio[780]: info: Discovery: adding undefined May 14 21:12:11 volumio volumio[780]: info: Discovery: Found device Volumio May 14 21:12:11 volumio volumio[780]: info: CoreCommandRouter::volumioGetState May 14 21:12:11 volumio volumio[780]: info: CorePlayQueue::getTrack 0 May 14 21:12:11 volumio kernel: wlan0: RX AssocResp from 86:2a:a8:c4:b5:71 (capab=0x1431 status=0 aid=2) May 14 21:12:11 volumio wpa_supplicant[1747]: wlan0: Associated with 86:2a:a8:c4:b5:71 May 14 21:12:11 volumio wpa_supplicant[1747]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 14 21:12:11 volumio wpa_supplicant[1747]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE May 14 21:12:11 volumio kernel: wlan0: associated May 14 21:12:11 volumio kernel: wlan0: Limiting TX power to 20 (20 - 0) dBm as advertised by 86:2a:a8:c4:b5:71 May 14 21:12:11 volumio wpa_supplicant[1747]: wlan0: WPA: Key negotiation completed with 86:2a:a8:c4:b5:71 [PTK=CCMP GTK=CCMP] May 14 21:12:11 volumio wpa_supplicant[1747]: wlan0: CTRL-EVENT-CONNECTED - Connection to 86:2a:a8:c4:b5:71 completed [id=0 id_str=] May 14 21:12:11 volumio dhcpcd[1788]: wlan0: carrier acquired May 14 21:12:11 volumio dhcpcd[1788]: wlan0: connected to Access Point `Ander' May 14 21:12:11 volumio dhcpcd[1788]: wlan0: IAID eb:80:d5:21 May 14 21:12:11 volumio dhcpcd[1788]: wlan0: adding address fe80::df3f:640f:3927:e948 May 14 21:12:11 volumio dhcpcd[1788]: ipv6_addaddr1: Permission denied May 14 21:12:11 volumio wireless.js[1693]: trying... May 14 21:12:11 volumio sudo[1792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:12:11 volumio sudo[1792]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:11 volumio sudo[1792]: pam_unix(sudo:session): session closed for user root May 14 21:12:11 volumio dhcpcd[1788]: wlan0: soliciting a DHCP lease May 14 21:12:11 volumio dhcpcd[1788]: wlan0: soliciting an IPv6 router May 14 21:12:12 volumio wireless.js[1693]: trying... May 14 21:12:12 volumio sudo[1795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:12:12 volumio sudo[1795]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:12 volumio sudo[1795]: pam_unix(sudo:session): session closed for user root May 14 21:12:13 volumio wireless.js[1693]: trying... May 14 21:12:13 volumio sudo[1798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:12:13 volumio sudo[1798]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:13 volumio sudo[1798]: pam_unix(sudo:session): session closed for user root May 14 21:12:14 volumio wireless.js[1693]: trying... May 14 21:12:14 volumio sudo[1801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:12:14 volumio sudo[1801]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:14 volumio sudo[1801]: pam_unix(sudo:session): session closed for user root May 14 21:12:15 volumio wireless.js[1693]: trying... May 14 21:12:15 volumio sudo[1804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:12:15 volumio sudo[1804]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:15 volumio sudo[1804]: pam_unix(sudo:session): session closed for user root May 14 21:12:16 volumio wireless.js[1693]: trying... May 14 21:12:16 volumio sudo[1807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:12:16 volumio sudo[1807]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:16 volumio sudo[1807]: pam_unix(sudo:session): session closed for user root May 14 21:12:16 volumio dhcpcd[1788]: wlan0: probing for an IPv4LL address May 14 21:12:17 volumio wireless.js[1693]: trying... May 14 21:12:17 volumio sudo[1810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:12:17 volumio sudo[1810]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:17 volumio sudo[1810]: pam_unix(sudo:session): session closed for user root May 14 21:12:17 volumio dhcpcd[1788]: wlan0: offered 10.10.60.59 from 10.10.60.254 May 14 21:12:17 volumio dhcpcd[1788]: wlan0: probing address 10.10.60.59/24 May 14 21:12:18 volumio wireless.js[1693]: trying... May 14 21:12:18 volumio sudo[1813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:12:18 volumio sudo[1813]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:18 volumio sudo[1813]: pam_unix(sudo:session): session closed for user root May 14 21:12:19 volumio wireless.js[1693]: trying... May 14 21:12:19 volumio sudo[1816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:12:19 volumio sudo[1816]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:19 volumio sudo[1816]: pam_unix(sudo:session): session closed for user root May 14 21:12:20 volumio wireless.js[1693]: trying... May 14 21:12:20 volumio sudo[1819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:12:20 volumio sudo[1819]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:20 volumio sudo[1819]: pam_unix(sudo:session): session closed for user root May 14 21:12:21 volumio wireless.js[1693]: trying... May 14 21:12:21 volumio sudo[1823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:12:21 volumio sudo[1823]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:21 volumio sudo[1823]: pam_unix(sudo:session): session closed for user root May 14 21:12:21 volumio dhcpcd[1788]: wlan0: using IPv4LL address 169.254.142.79 May 14 21:12:21 volumio dhcpcd[1788]: wlan0: adding route to 169.254.0.0/16 May 14 21:12:21 volumio avahi-daemon[458]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.142.79. May 14 21:12:21 volumio avahi-daemon[458]: New relevant interface wlan0.IPv4 for mDNS. May 14 21:12:21 volumio avahi-daemon[458]: Registering new address record for 169.254.142.79 on wlan0.IPv4. May 14 21:12:22 volumio dhcpcd[1788]: wlan0: leased 10.10.60.59 for 86400 seconds May 14 21:12:22 volumio dhcpcd[1788]: wlan0: adding route to 10.10.60.0/24 May 14 21:12:22 volumio dhcpcd[1788]: wlan0: adding default route via 10.10.60.254 May 14 21:12:22 volumio avahi-daemon[458]: Registering new address record for 10.10.60.59 on wlan0.IPv4. May 14 21:12:22 volumio wireless.js[1693]: trying... May 14 21:12:22 volumio dnsmasq[745]: reading /etc/resolv.conf May 14 21:12:22 volumio dnsmasq[745]: using nameserver 8.8.8.8#53 May 14 21:12:22 volumio dnsmasq[745]: using nameserver 8.8.4.4#53 May 14 21:12:22 volumio dnsmasq[745]: using nameserver 208.67.222.222#53 May 14 21:12:22 volumio dnsmasq[745]: using nameserver 208.67.220.220#53 May 14 21:12:22 volumio sudo[1857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:12:22 volumio sudo[1857]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:22 volumio sudo[1857]: pam_unix(sudo:session): session closed for user root May 14 21:12:22 volumio wireless.js[1693]: Connected to: ----Ander May 14 21:12:22 volumio wireless.js[1693]: ---- May 14 21:12:22 volumio avahi-daemon[458]: Withdrawing address record for 169.254.142.79 on wlan0. May 14 21:12:22 volumio avahi-daemon[458]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.142.79. May 14 21:12:22 volumio dhcpcd[1788]: wlan0: deleting route to 169.254.0.0/16 May 14 21:12:22 volumio avahi-daemon[458]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.10.60.59. May 14 21:12:22 volumio sudo[1868]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 21:12:22 volumio sudo[1868]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:22 volumio sudo[1868]: pam_unix(sudo:session): session closed for user root May 14 21:12:22 volumio wireless.js[1693]: ... joined AP, wlan0 IPv4 is 10.10.60.59, ipV6 is undefined May 14 21:12:22 volumio wireless.js[1693]: It's done! AP May 14 21:12:22 volumio volumio[780]: error: error May 14 21:12:22 volumio volumio[780]: error: error May 14 21:12:22 volumio systemd[1]: Started Wireless Services. May 14 21:12:22 volumio sudo[1691]: pam_unix(sudo:session): session closed for user root May 14 21:12:23 volumio volumio[780]: info: Discovery: this is already registered, undefined May 14 21:12:23 volumio volumio[780]: info: Discovery: Found device Volumio May 14 21:12:23 volumio volumio[780]: info: CoreCommandRouter::volumioGetState May 14 21:12:23 volumio volumio[780]: info: CorePlayQueue::getTrack 0 May 14 21:12:23 volumio ntpd[619]: Listen normally on 6 wlan0 10.10.60.59:123 May 14 21:12:23 volumio ntpd[619]: new interface(s) found: waking up resolver May 14 21:12:27 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection May 14 21:12:27 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo May 14 21:12:27 volumio sudo[1891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 21:12:27 volumio sudo[1891]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:27 volumio sudo[1891]: pam_unix(sudo:session): session closed for user root May 14 21:12:27 volumio sudo[1894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 14 21:12:27 volumio sudo[1894]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:27 volumio sudo[1894]: pam_unix(sudo:session): session closed for user root May 14 21:12:30 volumio volumio[780]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 21:12:30 volumio volumio[780]: info: Retrieving Cloud Streaming UI May 14 21:12:30 volumio volumio[780]: info: Getting Tidal Cloud Configuration May 14 21:12:30 volumio volumio[780]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 21:12:30 volumio volumio[780]: info: Getting Qobuz Cloud Configuration May 14 21:12:30 volumio volumio[780]: info: Asking plugin for UI Config May 14 21:12:30 volumio volumio[780]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 21:12:30 volumio volumio[780]: info: Getting Spotify Cloud Configuration May 14 21:12:30 volumio volumio[780]: info: Asking plugin for UI Config May 14 21:12:30 volumio volumio[780]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 21:12:30 volumio volumio[780]: info: Saving Spotify Acccount May 14 21:12:30 volumio volumio[780]: info: Got Tidal Cloud Configuration May 14 21:12:30 volumio volumio[780]: info: Got it May 14 21:12:30 volumio volumio[780]: info: Got it May 14 21:12:30 volumio volumio[780]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 14 21:12:30 volumio volumio[780]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 21:12:30 volumio volumio[780]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 14 21:12:32 volumio volumio[1497]: .................................................................................................................................................................................................................................................................................................................................++++ May 14 21:12:32 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart May 14 21:12:32 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart May 14 21:12:32 volumio volumio[780]: info: Refreshing Cached IP Addresses May 14 21:12:32 volumio sudo[1899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli May 14 21:12:32 volumio sudo[1899]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:32 volumio sudo[1901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 14 21:12:32 volumio sudo[1901]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:32 volumio sudo[1899]: pam_unix(sudo:session): session closed for user root May 14 21:12:32 volumio volumio[780]: error: Upnp client error: Error: This socket has been ended by the other party May 14 21:12:32 volumio sudo[1901]: pam_unix(sudo:session): session closed for user root May 14 21:12:32 volumio sudo[1904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 21:12:32 volumio sudo[1904]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:32 volumio sudo[1904]: pam_unix(sudo:session): session closed for user root May 14 21:12:38 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation May 14 21:12:38 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware May 14 21:12:38 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray May 14 21:12:38 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage May 14 21:12:38 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware May 14 21:12:42 volumio sudo[1956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 14 21:12:42 volumio sudo[1956]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:42 volumio sudo[1956]: pam_unix(sudo:session): session closed for user root May 14 21:12:42 volumio sudo[1958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 21:12:42 volumio sudo[1958]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:42 volumio sudo[1958]: pam_unix(sudo:session): session closed for user root May 14 21:12:42 volumio sudo[1961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 14 21:12:42 volumio sudo[1961]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:42 volumio sudo[1961]: pam_unix(sudo:session): session closed for user root May 14 21:12:42 volumio volumio[780]: info: Upmpdcli Daemon Started May 14 21:12:43 volumio volumio[780]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object] May 14 21:12:43 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings May 14 21:12:43 volumio volumio[780]: error: Failed callmethod call: TypeError: Cannot read property 'value' of undefined May 14 21:12:43 volumio volumio[1497]: ................................................++++ May 14 21:12:43 volumio volumio[1497]: e is 65537 (0x010001) May 14 21:12:44 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction May 14 21:12:45 volumio volumio[780]: info: CoreCommandRouter::Reload Ui May 14 21:12:45 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard May 14 21:12:45 volumio volumio[780]: info: Wizard terminated Successfully May 14 21:12:45 volumio volumio[780]: info: CoreCommandRouter::volumiosetStartupVolume May 14 21:12:45 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 21:12:46 volumio sudo[1967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 14 21:12:46 volumio sudo[1967]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:46 volumio sudo[1967]: pam_unix(sudo:session): session closed for user root May 14 21:12:46 volumio sudo[1969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 21:12:46 volumio sudo[1969]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:46 volumio sudo[1969]: pam_unix(sudo:session): session closed for user root May 14 21:12:46 volumio volumio[780]: verbose: New Socket.io Connection to 10.10.60.88 from 10.10.60.163 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Mobile Safari/537.36 Total Clients: 4 May 14 21:12:47 volumio sudo[1973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 14 21:12:47 volumio sudo[1973]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:47 volumio sudo[1973]: pam_unix(sudo:session): session closed for user root May 14 21:12:47 volumio sudo[1975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 21:12:47 volumio sudo[1975]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:47 volumio sudo[1975]: pam_unix(sudo:session): session closed for user root May 14 21:12:47 volumio volumio[780]: verbose: New Socket.io Connection to 10.10.60.88 from 10.10.60.163 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Mobile Safari/537.36 Total Clients: 5 May 14 21:12:47 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 14 21:12:47 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 14 21:12:47 volumio volumio[780]: info: CoreCommandRouter::volumioGetVisibleSources May 14 21:12:47 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 21:12:47 volumio volumio[780]: info: CoreCommandRouter::volumioGetState May 14 21:12:47 volumio volumio[780]: info: CorePlayQueue::getTrack 0 May 14 21:12:47 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 14 21:12:47 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 14 21:12:47 volumio volumio[780]: info: Received Get System Info May 14 21:12:47 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 14 21:12:47 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 14 21:12:47 volumio volumio[780]: info: Discovery: Getting this device information May 14 21:12:47 volumio volumio[780]: info: CoreCommandRouter::volumioGetState May 14 21:12:47 volumio volumio[780]: info: CorePlayQueue::getTrack 0 May 14 21:12:47 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 14 21:12:47 volumio volumio[780]: info: CoreCommandRouter::volumioGetState May 14 21:12:47 volumio volumio[780]: info: CorePlayQueue::getTrack 0 May 14 21:12:47 volumio volumio[780]: info: Listing playlists May 14 21:12:47 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 14 21:12:47 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 14 21:12:47 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 14 21:12:49 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 14 21:12:49 volumio volumio[780]: info: Received Get System Info May 14 21:12:49 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 14 21:12:49 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 14 21:12:49 volumio volumio[780]: info: Discovery: Getting this device information May 14 21:12:49 volumio volumio[780]: info: CoreCommandRouter::volumioGetState May 14 21:12:49 volumio volumio[780]: info: CorePlayQueue::getTrack 0 May 14 21:12:49 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 14 21:12:49 volumio volumio[780]: info: CoreCommandRouter::Close All Modals sent May 14 21:12:49 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted May 14 21:12:52 volumio volumio[780]: info: CoreCommandRouter::volumioPlay May 14 21:12:52 volumio volumio[780]: info: CoreStateMachine::play index undefined May 14 21:12:52 volumio volumio[780]: info: CoreStateMachine::setConsumeUpdateService undefined May 14 21:12:52 volumio volumio[780]: info: CorePlayQueue::getTrack 0 May 14 21:12:59 volumio volumio[780]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 21:12:59 volumio volumio[780]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 21:12:59 volumio volumio[780]: info: Retrieving Cloud Streaming UI May 14 21:12:59 volumio volumio[780]: info: Getting Tidal Cloud Configuration May 14 21:12:59 volumio volumio[780]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 21:12:59 volumio volumio[780]: info: Getting Qobuz Cloud Configuration May 14 21:12:59 volumio volumio[780]: info: Asking plugin for UI Config May 14 21:12:59 volumio volumio[780]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 21:12:59 volumio volumio[780]: info: Getting Spotify Cloud Configuration May 14 21:12:59 volumio volumio[780]: info: Asking plugin for UI Config May 14 21:12:59 volumio volumio[780]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 21:12:59 volumio volumio[780]: info: Saving Spotify Acccount May 14 21:12:59 volumio volumio[780]: info: Got Tidal Cloud Configuration May 14 21:12:59 volumio volumio[780]: info: Got it May 14 21:12:59 volumio volumio[780]: info: Got it May 14 21:12:59 volumio volumio[780]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 14 21:12:59 volumio volumio[780]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 21:12:59 volumio volumio[780]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 14 21:12:59 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 14 21:12:59 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 14 21:12:59 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 14 21:12:59 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 14 21:12:59 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 21:12:59 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 21:12:59 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 21:12:59 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 21:12:59 volumio volumio[780]: info: CoreCommandRouter::volumioGetBrowseSources May 14 21:12:59 volumio volumio[780]: info: CoreCommandRouter::volumioGetBrowseSources May 14 21:12:59 volumio volumio[780]: info: CoreCommandRouter::volumioGetBrowseSources May 14 21:12:59 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 14 21:12:59 volumio mpd[1649]: exception: No database May 14 21:12:59 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 14 21:13:03 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares May 14 21:13:03 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService May 14 21:13:03 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 14 21:13:03 volumio mpd[1649]: exception: No database May 14 21:13:05 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 14 21:13:05 volumio volumio[780]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 14 21:13:05 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 14 21:13:05 volumio volumio[780]: info: Received Get System Version May 14 21:13:05 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 14 21:13:05 volumio volumio[780]: info: Received Get System Info May 14 21:13:05 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 14 21:13:05 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 14 21:13:05 volumio volumio[780]: info: Discovery: Getting this device information May 14 21:13:05 volumio volumio[780]: info: CoreCommandRouter::volumioGetState May 14 21:13:05 volumio volumio[780]: info: CorePlayQueue::getTrack 0 May 14 21:13:05 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 14 21:13:07 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 14 21:13:07 volumio mpd[1649]: exception: No database May 14 21:13:11 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 14 21:13:11 volumio mpd[1649]: exception: No database May 14 21:13:12 volumio volumio[780]: error: error May 14 21:13:12 volumio volumio[780]: error: error May 14 21:13:15 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 14 21:13:15 volumio mpd[1649]: exception: No database May 14 21:13:19 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 14 21:13:19 volumio mpd[1649]: exception: No database May 14 21:13:23 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare May 14 21:13:23 volumio volumio[780]: info: Adding a new share May 14 21:13:23 volumio volumio[780]: info: No correspondence found in configuration for share Multimedia on IP 10.10.40.55 May 14 21:13:23 volumio sudo[1998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.10.40.55/Multimedia /mnt/NAS/Multimedia May 14 21:13:23 volumio sudo[1998]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:13:23 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 14 21:13:23 volumio mpd[1649]: exception: No database May 14 21:13:23 volumio kernel: Key type cifs.spnego registered May 14 21:13:23 volumio kernel: Key type cifs.idmap registered May 14 21:13:23 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. May 14 21:13:23 volumio kernel: CIFS: Attempting to mount \\10.10.40.55\Multimedia May 14 21:13:24 volumio sudo[1998]: pam_unix(sudo:session): session closed for user root May 14 21:13:24 volumio volumio[780]: info: [1715714004429] Database scan error: Error: Command failed: /usr/bin/mpc update May 14 21:13:24 volumio volumio[780]: MPD error: No database May 14 21:13:25 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 14 21:13:27 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 14 21:13:27 volumio mpd[1649]: exception: No database May 14 21:13:31 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 14 21:13:31 volumio mpd[1649]: exception: No database May 14 21:13:33 volumio volumio[780]: info: Disabling MyMusic plugin upnp May 14 21:13:33 volumio sudo[2035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service May 14 21:13:33 volumio sudo[2035]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:13:33 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... May 14 21:13:33 volumio volumio[780]: error: Upnp client error: Error: This socket has been ended by the other party May 14 21:13:34 volumio volumio[780]: info: Enabling MyMusic plugin upnp May 14 21:13:34 volumio volumio[780]: info: Enabling plugin upnp May 14 21:13:34 volumio volumio[780]: info: Loading plugin "upnp"... May 14 21:13:34 volumio volumio[780]: info: [1715714014499] Starting Upmpd Daemon May 14 21:13:34 volumio volumio[780]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 14 21:13:34 volumio volumio[780]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 14 21:13:34 volumio volumio[780]: Error: listen EADDRINUSE: address already in use :::6599 May 14 21:13:34 volumio volumio[780]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) May 14 21:13:34 volumio volumio[780]: at listenInCluster (net.js:1366:12) May 14 21:13:34 volumio volumio[780]: at Server.listen (net.js:1452:7) May 14 21:13:34 volumio volumio[780]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) May 14 21:13:34 volumio volumio[780]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) May 14 21:13:34 volumio volumio[780]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) May 14 21:13:34 volumio volumio[780]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 14 21:13:34 volumio volumio[780]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { May 14 21:13:34 volumio volumio[780]: code: 'EADDRINUSE', May 14 21:13:34 volumio volumio[780]: errno: -98, May 14 21:13:34 volumio volumio[780]: syscall: 'listen', May 14 21:13:34 volumio volumio[780]: address: '::', May 14 21:13:34 volumio volumio[780]: port: 6599 May 14 21:13:34 volumio volumio[780]: } May 14 21:13:34 volumio volumio[780]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 14 21:13:35 volumio sudo[2046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-14 21:12 May 14 21:13:35 volumio sudo[2046]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"