-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Thu 2025-09-04 22:13:13 BST. -- Sep 04 22:12:00 volumiopi ntpd[761]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 04 22:12:00 volumiopi systemd[1]: systemd-rfkill.service: Succeeded. Sep 04 22:12:01 volumiopi ntpd[761]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 04 22:12:01 volumiopi sudo[810]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:01 volumiopi volumio-remote-updater[537]: [2025-09-04 22:12:01] [info] asio async_connect error: system:111 (Connection refused) Sep 04 22:12:01 volumiopi volumio-remote-updater[537]: [2025-09-04 22:12:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 04 22:12:01 volumiopi volumio-remote-updater[537]: [2025-09-04 22:12:01] [error] handle_connect error: Underlying Transport Error Sep 04 22:12:01 volumiopi wireless.js[570]: Start wireless flow Sep 04 22:12:01 volumiopi wireless.js[570]: Stopped hotspot (if there).. Sep 04 22:12:01 volumiopi sudo[818]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 04 22:12:01 volumiopi sudo[818]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:01 volumiopi sudo[818]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:01 volumiopi sudo[820]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 04 22:12:01 volumiopi sudo[820]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:01 volumiopi volumio-time-update[577]: volumio-time-update-util: Fetching time from Volumio... Sep 04 22:12:02 volumiopi volumio-time-update[577]: volumio-time-update-util: Date not found in response Sep 04 22:12:02 volumiopi volumio-time-update[577]: volumio-time-update-util: Retrying in 5 seconds... Sep 04 22:12:02 volumiopi bthelper[630]: Changing power off succeeded Sep 04 22:12:02 volumiopi bthelper[630]: [CHG] Controller DC:A6:32:45:0A:BB Class: 0x0000041c Sep 04 22:12:02 volumiopi bthelper[630]: Changing power on succeeded Sep 04 22:12:02 volumiopi sudo[820]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:02 volumiopi wireless.js[570]: DHCP IP Sep 04 22:12:02 volumiopi wireless.js[570]: Start ap Sep 04 22:12:02 volumiopi wpa_supplicant[835]: Successfully initialized wpa_supplicant Sep 04 22:12:02 volumiopi sudo[836]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Sep 04 22:12:02 volumiopi sudo[836]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:02 volumiopi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 04 22:12:02 volumiopi dhcpcd[837]: dev: loaded udev Sep 04 22:12:02 volumiopi dhcpcd-run-hooks[855]: wlan0: starting wpa_supplicant Sep 04 22:12:02 volumiopi dhcpcd-run-hooks[860]: wlan0: failed to start wpa_supplicant Sep 04 22:12:02 volumiopi dhcpcd-run-hooks[861]: 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 04 22:12:02 volumiopi dhcpcd[837]: wlan0: connected to Access Point `' Sep 04 22:12:02 volumiopi dhcpcd[837]: no interfaces have a carrier Sep 04 22:12:02 volumiopi dhcpcd[837]: forked to background, child pid 871 Sep 04 22:12:02 volumiopi dhcpcd[871]: eth0: waiting for carrier Sep 04 22:12:02 volumiopi dhcpcd[871]: wlan0: waiting for carrier Sep 04 22:12:02 volumiopi sudo[836]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:03 volumiopi wireless.js[570]: trying... Sep 04 22:12:03 volumiopi sudo[873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 04 22:12:03 volumiopi sudo[873]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:03 volumiopi sudo[873]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:04 volumiopi wireless.js[570]: trying... Sep 04 22:12:04 volumiopi sudo[876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 04 22:12:04 volumiopi sudo[876]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:04 volumiopi sudo[876]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:05 volumiopi wireless.js[570]: trying... Sep 04 22:12:05 volumiopi sudo[879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 04 22:12:05 volumiopi sudo[879]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:05 volumiopi sudo[879]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:06 volumiopi wireless.js[570]: trying... Sep 04 22:12:06 volumiopi sudo[882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 04 22:12:06 volumiopi sudo[882]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:06 volumiopi sudo[882]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:07 volumiopi volumio-time-update[577]: volumio-time-update-util: Fetching time from Volumio... Sep 04 22:12:07 volumiopi volumio-time-update[577]: volumio-time-update-util: Date not found in response Sep 04 22:12:07 volumiopi volumio-time-update[577]: volumio-time-update-util: Retrying in 5 seconds... Sep 04 22:12:07 volumiopi wireless.js[570]: trying... Sep 04 22:12:07 volumiopi sudo[890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 04 22:12:07 volumiopi sudo[890]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:07 volumiopi sudo[890]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:08 volumiopi wireless.js[570]: trying... Sep 04 22:12:08 volumiopi sudo[893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 04 22:12:08 volumiopi sudo[893]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:08 volumiopi sudo[893]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:08 volumiopi wpa_supplicant[859]: wlan0: Trying to associate with SSID 'BrynLlysT' Sep 04 22:12:08 volumiopi dhcpcd[871]: wlan0: carrier acquired Sep 04 22:12:08 volumiopi dhcpcd[871]: wlan0: connected to Access Point `BrynLlysT' Sep 04 22:12:08 volumiopi wpa_supplicant[859]: wlan0: Associated with 50:c7:bf:86:71:e6 Sep 04 22:12:08 volumiopi wpa_supplicant[859]: wlan0: CTRL-EVENT-CONNECTED - Connection to 50:c7:bf:86:71:e6 completed [id=0 id_str=] Sep 04 22:12:08 volumiopi wpa_supplicant[859]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 04 22:12:08 volumiopi dhcpcd[871]: DUID 00:01:00:01:2d:5e:64:96:dc:a6:32:45:0a:b9 Sep 04 22:12:08 volumiopi dhcpcd[871]: wlan0: IAID 32:45:0a:ba Sep 04 22:12:08 volumiopi dhcpcd[871]: wlan0: adding address fe80::dea6:32ff:fe45:aba Sep 04 22:12:08 volumiopi dhcpcd[871]: ipv6_addaddr1: Permission denied Sep 04 22:12:08 volumiopi dhcpcd[871]: wlan0: carrier lost Sep 04 22:12:08 volumiopi dhcpcd[871]: wlan0: carrier acquired Sep 04 22:12:08 volumiopi dhcpcd[871]: wlan0: IAID 32:45:0a:ba Sep 04 22:12:09 volumiopi volumio-remote-updater[537]: [2025-09-04 22:12:09] [info] asio async_connect error: system:111 (Connection refused) Sep 04 22:12:09 volumiopi volumio-remote-updater[537]: [2025-09-04 22:12:09] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 04 22:12:09 volumiopi volumio-remote-updater[537]: [2025-09-04 22:12:09] [error] handle_connect error: Underlying Transport Error Sep 04 22:12:09 volumiopi dhcpcd[871]: wlan0: rebinding lease of 192.168.1.189 Sep 04 22:12:09 volumiopi wireless.js[570]: trying... Sep 04 22:12:09 volumiopi sudo[909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 04 22:12:09 volumiopi sudo[909]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:09 volumiopi sudo[909]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:09 volumiopi dhcpcd[871]: wlan0: NAK: static lease available from 192.168.1.1 Sep 04 22:12:09 volumiopi dhcpcd[871]: wlan0: message: static lease available Sep 04 22:12:09 volumiopi dhcpcd[871]: wlan0: soliciting a DHCP lease Sep 04 22:12:09 volumiopi dhcpcd[871]: wlan0: offered 192.168.1.157 from 192.168.1.1 Sep 04 22:12:09 volumiopi dhcpcd[871]: wlan0: soliciting an IPv6 router Sep 04 22:12:09 volumiopi dhcpcd[871]: wlan0: probing address 192.168.1.157/24 Sep 04 22:12:09 volumiopi dhcpcd[871]: wlan0: hardware address 34:5a:60:3b:e4:f7 claims 192.168.1.157 Sep 04 22:12:09 volumiopi dhcpcd[871]: wlan0: DAD detected 192.168.1.157 Sep 04 22:12:10 volumiopi wireless.js[570]: trying... Sep 04 22:12:10 volumiopi sudo[921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 04 22:12:10 volumiopi sudo[921]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:10 volumiopi sudo[921]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:10 volumiopi dhcpcd[871]: wlan0: soliciting a DHCP lease Sep 04 22:12:11 volumiopi wireless.js[570]: trying... Sep 04 22:12:11 volumiopi sudo[924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 04 22:12:11 volumiopi sudo[924]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:11 volumiopi sudo[924]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:12 volumiopi volumio-time-update[577]: volumio-time-update-util: Fetching time from Volumio... Sep 04 22:12:12 volumiopi volumio-time-update[577]: volumio-time-update-util: Date not found in response Sep 04 22:12:12 volumiopi volumio-time-update[577]: volumio-time-update-util: Retrying in 5 seconds... Sep 04 22:12:12 volumiopi wireless.js[570]: trying... Sep 04 22:12:12 volumiopi sudo[932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 04 22:12:12 volumiopi sudo[932]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:12 volumiopi sudo[932]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:13 volumiopi wireless.js[570]: trying... Sep 04 22:12:13 volumiopi sudo[935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 04 22:12:13 volumiopi sudo[935]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:13 volumiopi sudo[935]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:13 volumiopi dhcpcd[871]: wlan0: offered 192.168.1.189 from 192.168.1.1 Sep 04 22:12:14 volumiopi dhcpcd[871]: wlan0: probing address 192.168.1.189/24 Sep 04 22:12:14 volumiopi wireless.js[570]: trying... Sep 04 22:12:14 volumiopi sudo[938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 04 22:12:14 volumiopi sudo[938]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:14 volumiopi sudo[938]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:15 volumiopi wireless.js[570]: trying... Sep 04 22:12:15 volumiopi sudo[941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 04 22:12:15 volumiopi sudo[941]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:15 volumiopi sudo[941]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:16 volumiopi wireless.js[570]: trying... Sep 04 22:12:16 volumiopi sudo[944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 04 22:12:16 volumiopi sudo[944]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:16 volumiopi sudo[944]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:17 volumiopi volumio-time-update[577]: volumio-time-update-util: Fetching time from Volumio... Sep 04 22:12:17 volumiopi volumio-time-update[577]: volumio-time-update-util: Date not found in response Sep 04 22:12:17 volumiopi volumio-time-update[577]: volumio-time-update-util: Retrying in 5 seconds... Sep 04 22:12:17 volumiopi wireless.js[570]: trying... Sep 04 22:12:17 volumiopi sudo[952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 04 22:12:17 volumiopi sudo[952]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:17 volumiopi sudo[952]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:18 volumiopi wireless.js[570]: trying... Sep 04 22:12:18 volumiopi sudo[955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 04 22:12:18 volumiopi sudo[955]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:18 volumiopi sudo[955]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:19 volumiopi dhcpcd[871]: wlan0: leased 192.168.1.189 for 43200 seconds Sep 04 22:12:19 volumiopi avahi-daemon[571]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.189. Sep 04 22:12:19 volumiopi avahi-daemon[571]: New relevant interface wlan0.IPv4 for mDNS. Sep 04 22:12:19 volumiopi dhcpcd[871]: wlan0: adding route to 192.168.1.0/24 Sep 04 22:12:19 volumiopi avahi-daemon[571]: Registering new address record for 192.168.1.189 on wlan0.IPv4. Sep 04 22:12:19 volumiopi dhcpcd[871]: wlan0: adding default route via 192.168.1.1 Sep 04 22:12:19 volumiopi volumio-remote-updater[537]: [2025-09-04 22:12:19] [info] asio async_connect error: system:111 (Connection refused) Sep 04 22:12:19 volumiopi volumio-remote-updater[537]: [2025-09-04 22:12:19] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 04 22:12:19 volumiopi volumio-remote-updater[537]: [2025-09-04 22:12:19] [error] handle_connect error: Underlying Transport Error Sep 04 22:12:19 volumiopi wireless.js[570]: trying... Sep 04 22:12:19 volumiopi sudo[980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 04 22:12:19 volumiopi sudo[980]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:19 volumiopi sudo[980]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:19 volumiopi wireless.js[570]: Connected to: ----BrynLlysT Sep 04 22:12:19 volumiopi wireless.js[570]: ---- Sep 04 22:12:19 volumiopi sudo[983]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 04 22:12:19 volumiopi sudo[983]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:19 volumiopi sudo[983]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:19 volumiopi wireless.js[570]: ... joined AP, wlan0 IPv4 is 192.168.1.189, ipV6 is undefined Sep 04 22:12:19 volumiopi wireless.js[570]: It's done! AP Sep 04 22:12:19 volumiopi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 04 22:12:19 volumiopi systemd[1]: Started Wireless Services. Sep 04 22:12:19 volumiopi systemd[1]: Started Volumio Backend Module. Sep 04 22:12:19 volumiopi systemd[1]: Started Volumio Cpu Tweaker. Sep 04 22:12:19 volumiopi volumio-cpu-tweak[989]: Setting RT Priority for mpd Sep 04 22:12:19 volumiopi volumio-cpu-tweak[989]: Setting MPD Affinity Sep 04 22:12:19 volumiopi volumio-cpu-tweak[989]: pid 770's current affinity mask: f Sep 04 22:12:19 volumiopi volumio-cpu-tweak[989]: pid 770's new affinity mask: 3 Sep 04 22:12:19 volumiopi volumio-cpu-tweak[989]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Sep 04 22:12:19 volumiopi volumio-cpu-tweak[989]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Sep 04 22:12:19 volumiopi systemd[1]: volumio_cpu_tweak.service: Succeeded. Sep 04 22:12:20 volumiopi ntpd[761]: Listen normally on 3 wlan0 192.168.1.189:123 Sep 04 22:12:20 volumiopi ntpd[761]: new interface(s) found: waking up resolver Sep 04 22:12:21 volumiopi volumio[988]: info: ------------------------------------------- Sep 04 22:12:21 volumiopi volumio[988]: info: ----- Volumio3 ---- Sep 04 22:12:21 volumiopi volumio[988]: info: ------------------------------------------- Sep 04 22:12:21 volumiopi volumio[988]: info: ----- System startup ---- Sep 04 22:12:21 volumiopi volumio[988]: info: ------------------------------------------- Sep 04 22:12:21 volumiopi volumio[988]: info: MYVOLUMIO Environment detected Sep 04 22:12:22 volumiopi volumio[988]: info: Plugin folders cleanup Sep 04 22:12:22 volumiopi volumio[988]: info: Scanning into folder /volumio/app/plugins/ Sep 04 22:12:22 volumiopi volumio[988]: info: Scanning category audio_interface Sep 04 22:12:22 volumiopi volumio[988]: info: Scanning category miscellanea Sep 04 22:12:22 volumiopi volumio[988]: info: Scanning category music_service Sep 04 22:12:22 volumiopi volumio[988]: info: Scanning category plugins.json Sep 04 22:12:22 volumiopi volumio[988]: info: Scanning category system_controller Sep 04 22:12:22 volumiopi volumio[988]: info: Scanning category user_interface Sep 04 22:12:22 volumiopi volumio[988]: info: Scanning into folder /data/plugins/ Sep 04 22:12:22 volumiopi volumio[988]: info: Scanning category music_service Sep 04 22:12:22 volumiopi volumio[988]: info: Plugin folders cleanup completed Sep 04 22:12:22 volumiopi volumio[988]: info: ------------------------------------------- Sep 04 22:12:22 volumiopi volumio[988]: info: ----- Core plugins startup ---- Sep 04 22:12:22 volumiopi volumio[988]: info: ------------------------------------------- Sep 04 22:12:22 volumiopi volumio[988]: info: Loading plugins from folder /volumio/app/plugins/ Sep 04 22:12:22 volumiopi volumio[988]: info: Adding plugin upnp to MyMusic Plugins Sep 04 22:12:22 volumiopi volumio[988]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 04 22:12:22 volumiopi volumio[988]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 04 22:12:22 volumiopi volumio[988]: info: Loading plugins from folder /data/plugins/ Sep 04 22:12:22 volumiopi volumio[988]: info: Loading plugin "system"... Sep 04 22:12:22 volumiopi volumio-time-update[577]: volumio-time-update-util: Fetching time from Volumio... Sep 04 22:12:22 volumiopi volumio[988]: info: Loading plugin "appearance"... Sep 04 22:12:22 volumiopi volumio-time-update[577]: volumio-time-update-util: Setting system time to: 2025-09-04 22:12:50 Sep 04 22:12:22 volumiopi sudo[1026]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-04 22:12:50 Sep 04 22:12:22 volumiopi sudo[1026]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:22 volumiopi dbus-daemon[564]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.9' (uid=0 pid=1027 comm="timedatectl set-time 2025-09-04 22:12:50 ") Sep 04 22:12:22 volumiopi systemd[1]: Starting Time & Date Service... Sep 04 22:12:22 volumiopi dbus-daemon[564]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 04 22:12:22 volumiopi systemd[1]: Started Time & Date Service. Sep 04 22:12:50 volumiopi systemd-timedated[1028]: Changed local time to Thu Sep 4 22:12:50 2025 Sep 04 22:12:50 volumiopi sudo[1026]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:50 volumiopi volumio-time-update[577]: volumio-time-update-util: System time updated successfully. Sep 04 22:12:50 volumiopi systemd[1]: Started Volumio Time Update Utility. Sep 04 22:12:50 volumiopi volumio[988]: info: Loading plugin "network"... Sep 04 22:12:50 volumiopi volumio[988]: info: Refreshing Cached IP Addresses Sep 04 22:12:50 volumiopi sudo[1030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 04 22:12:50 volumiopi sudo[1030]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:50 volumiopi sudo[1030]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:50 volumiopi sudo[1032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 04 22:12:50 volumiopi sudo[1032]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:50 volumiopi volumio[988]: info: Loading plugin "services"... Sep 04 22:12:50 volumiopi sudo[1032]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:50 volumiopi volumio[988]: info: Loading plugin "alsa_controller"... Sep 04 22:12:50 volumiopi sudo[1041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 04 22:12:50 volumiopi sudo[1041]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:50 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 04 22:12:50 volumiopi volumio[988]: info: Loading plugin "wizard"... Sep 04 22:12:50 volumiopi volumio[988]: info: Loading plugin "networkfs"... Sep 04 22:12:50 volumiopi volumio[988]: info: Starting Udev Watcher for removable devices Sep 04 22:12:50 volumiopi sudo[1058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Share,password=L3N!Ze,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.157/Music /mnt/NAS/Music Sep 04 22:12:50 volumiopi sudo[1058]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:50 volumiopi sudo[1060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.157:192.168.1.157/Music/ /mnt/NAS/Music Sep 04 22:12:50 volumiopi sudo[1060]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:50 volumiopi volumio[988]: info: Ignoring mount for partition: boot Sep 04 22:12:50 volumiopi volumio[988]: info: Ignoring mount for partition: volumio Sep 04 22:12:50 volumiopi volumio[988]: info: Ignoring mount for partition: volumio_data Sep 04 22:12:50 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 04 22:12:50 volumiopi volumio[988]: info: Loading plugin "volumio_command_line_client"... Sep 04 22:12:50 volumiopi volumio[988]: info: Loading plugin "upnp"... Sep 04 22:12:50 volumiopi volumio[988]: info: [1757020370819] Starting Upmpd Daemon Sep 04 22:12:50 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 04 22:12:50 volumiopi volumio[988]: info: Loading plugin "my_music"... Sep 04 22:12:50 volumiopi volumio[988]: info: Loading plugin "mpd"... Sep 04 22:12:50 volumiopi kernel: Key type cifs.spnego registered Sep 04 22:12:50 volumiopi kernel: Key type cifs.idmap registered Sep 04 22:12:50 volumiopi 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. Sep 04 22:12:50 volumiopi kernel: CIFS: Attempting to mount //192.168.1.157/Music Sep 04 22:12:50 volumiopi nmbd[758]: [2025/09/04 22:12:50.923459, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 04 22:12:50 volumiopi systemd[1]: Started Samba NMB Daemon. Sep 04 22:12:50 volumiopi nmbd[758]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Sep 04 22:12:50 volumiopi systemd[1]: Starting Samba Winbind Daemon... Sep 04 22:12:51 volumiopi kernel: cryptd: max_cpu_qlen set to 1000 Sep 04 22:12:51 volumiopi winbindd[1083]: [2025/09/04 22:12:51.186077, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Sep 04 22:12:51 volumiopi winbindd[1083]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Sep 04 22:12:51 volumiopi volumio[988]: info: Loading plugin "upnp_browser"... Sep 04 22:12:51 volumiopi winbindd[1083]: [2025/09/04 22:12:51.220943, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 04 22:12:51 volumiopi winbindd[1083]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Sep 04 22:12:51 volumiopi systemd[1]: Started Samba Winbind Daemon. Sep 04 22:12:51 volumiopi systemd[1]: Starting Samba SMB Daemon... Sep 04 22:12:51 volumiopi sudo[1058]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:51 volumiopi volumio[988]: info: Loading plugin "alarm-clock"... Sep 04 22:12:51 volumiopi smbd[1106]: [2025/09/04 22:12:51.658809, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 04 22:12:51 volumiopi systemd[1]: Started Samba SMB Daemon. Sep 04 22:12:51 volumiopi smbd[1106]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Sep 04 22:12:51 volumiopi systemd[1]: Reached target Multi-User System. Sep 04 22:12:51 volumiopi systemd[1]: Reached target Graphical Interface. Sep 04 22:12:51 volumiopi systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 04 22:12:51 volumiopi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 04 22:12:51 volumiopi systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 04 22:12:51 volumiopi systemd[1]: Startup finished in 11.967s (kernel) + 31.172s (userspace) = 43.140s. Sep 04 22:12:51 volumiopi volumio[988]: info: Loading plugin "airplay_emulation"... Sep 04 22:12:51 volumiopi volumio[988]: info: Starting Shairport Sync Sep 04 22:12:51 volumiopi volumio[988]: info: Loading plugin "last_100"... Sep 04 22:12:51 volumiopi volumio[988]: info: Loading plugin "webradio"... Sep 04 22:12:51 volumiopi volumio[988]: info: Loading plugin "i2s_dacs"... Sep 04 22:12:51 volumiopi volumio[988]: info: Loading plugin "volumiodiscovery"... Sep 04 22:12:51 volumiopi volumio[988]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 04 22:12:51 volumiopi volumio[988]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 04 22:12:51 volumiopi node[988]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 04 22:12:51 volumiopi volumio[988]: *** WARNING *** For more information see Sep 04 22:12:51 volumiopi volumio[988]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 04 22:12:51 volumiopi volumio[988]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 04 22:12:51 volumiopi volumio[988]: *** WARNING *** For more information see Sep 04 22:12:51 volumiopi node[988]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 04 22:12:51 volumiopi node[988]: *** WARNING *** For more information see Sep 04 22:12:51 volumiopi node[988]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 04 22:12:51 volumiopi node[988]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 04 22:12:51 volumiopi node[988]: *** WARNING *** For more information see Sep 04 22:12:51 volumiopi volumio[988]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 04 22:12:51 volumiopi volumio[988]: info: Discovery: Started advertising with name: VolumioPi Sep 04 22:12:51 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 04 22:12:51 volumiopi volumio[988]: info: Loading plugin "spop"... Sep 04 22:12:53 volumiopi volumio[988]: info: Loading plugin "outputs"... Sep 04 22:12:53 volumiopi volumio[988]: info: Loading plugin "albumart"... Sep 04 22:12:53 volumiopi volumio[988]: info: Plugin example_plugin is not enabled Sep 04 22:12:53 volumiopi volumio[988]: info: Loading plugin "inputs"... Sep 04 22:12:53 volumiopi volumio[988]: info: Loading plugin "updater_comm"... Sep 04 22:12:53 volumiopi systemd[1]: systemd-fsckd.service: Succeeded. Sep 04 22:12:53 volumiopi volumio[988]: info: Plugin mpdemulation is not enabled Sep 04 22:12:53 volumiopi volumio[988]: info: Loading plugin "rest_api"... Sep 04 22:12:53 volumiopi volumio[988]: info: Loading plugin "websocket"... Sep 04 22:12:53 volumiopi volumio[988]: info: Starting Socket.io Server version 2.3.0 Sep 04 22:12:53 volumiopi volumio[988]: info: Loading plugin "80s80s"... Sep 04 22:12:53 volumiopi volumio[988]: Forking 3 albumart workers Sep 04 22:12:53 volumiopi volumio[988]: info: Applying required configuration parameters for plugin 80s80s Sep 04 22:12:53 volumiopi volumio[988]: info: [1757020373558] [80s80s] API delay: 30 Sep 04 22:12:53 volumiopi volumio[988]: info: Loading i18n strings for locale en Sep 04 22:12:53 volumiopi volumio[988]: Updating browse sources language Sep 04 22:12:53 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 22:12:53 volumiopi sh[492]: timed out Sep 04 22:12:53 volumiopi dhcpcd[514]: timed out Sep 04 22:12:53 volumiopi sh[492]: dhcpcd exited Sep 04 22:12:53 volumiopi dhcpcd[514]: dhcpcd exited Sep 04 22:12:53 volumiopi sh[492]: ifup: failed to bring up eth0 Sep 04 22:12:53 volumiopi systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Sep 04 22:12:53 volumiopi systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Sep 04 22:12:53 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 22:12:53 volumiopi volumio[988]: info: CoreCommandRouter::initPlayerControls Sep 04 22:12:53 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 22:12:53 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 22:12:53 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 22:12:53 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 22:12:53 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 22:12:53 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 22:12:53 volumiopi volumio[988]: Starting albumart workers Sep 04 22:12:53 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 22:12:53 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 22:12:53 volumiopi volumio[988]: Starting albumart workers Sep 04 22:12:53 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 22:12:53 volumiopi volumio[988]: Express server listening on port 3000 Sep 04 22:12:53 volumiopi volumio[988]: Starting albumart workers Sep 04 22:12:53 volumiopi volumio[988]: [Metrics] WebUI: 5s 666.68ms Sep 04 22:12:53 volumiopi volumio[988]: info: CoreStateMachine::resetVolumioState Sep 04 22:12:53 volumiopi volumio[988]: info: CoreStateMachine::getcurrentVolume Sep 04 22:12:53 volumiopi volumio[988]: info: CoreCommandRouter::volumioRetrievevolume Sep 04 22:12:53 volumiopi volumio[988]: info: Volumio Network Manager: Network status updated: 2 Sep 04 22:12:54 volumiopi volumio[988]: info: CoreCommandRouter::volumioGetState Sep 04 22:12:54 volumiopi volumio[988]: info: CorePlayQueue::getTrack 0 Sep 04 22:12:54 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 22:12:54 volumiopi volumio[988]: info: Reloading queue from file Sep 04 22:12:54 volumiopi volumio[988]: info: VolumeController:: Volume=100 Mute =false Sep 04 22:12:54 volumiopi volumio[988]: info: CoreStateMachine::pushState Sep 04 22:12:54 volumiopi volumio[988]: info: CorePlayQueue::getTrack 0 Sep 04 22:12:54 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 22:12:54 volumiopi volumio[988]: info: CoreCommandRouter::volumioPushState Sep 04 22:12:54 volumiopi volumio[988]: info: CoreStateMachine::updateTrackBlock Sep 04 22:12:54 volumiopi volumio[988]: info: CorePlayQueue::getTrackBlock Sep 04 22:12:54 volumiopi volumio[988]: info: CoreCommandRouter::volumioRetrievevolume Sep 04 22:12:54 volumiopi volumio[988]: info: Setting Device type: Raspberry PI Sep 04 22:12:54 volumiopi volumio[988]: info: CoreStateMachine::setRepeat true single undefined Sep 04 22:12:54 volumiopi volumio[988]: info: CoreStateMachine::pushState Sep 04 22:12:54 volumiopi volumio[988]: info: CorePlayQueue::getTrack 0 Sep 04 22:12:54 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 22:12:54 volumiopi volumio[988]: info: CoreCommandRouter::volumioPushState Sep 04 22:12:54 volumiopi volumio[988]: info: CoreStateMachine::setRandom null Sep 04 22:12:54 volumiopi volumio[988]: info: CoreStateMachine::pushState Sep 04 22:12:54 volumiopi volumio[988]: info: CorePlayQueue::getTrack 0 Sep 04 22:12:54 volumiopi volumio[988]: info: CoreCommandRouter::volumioPushState Sep 04 22:12:54 volumiopi volumio[988]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 04 22:12:54 volumiopi volumio[988]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 Sep 04 22:12:54 volumiopi volumio[988]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 04 22:12:54 volumiopi volumio[988]: info: Listing playlists Sep 04 22:12:54 volumiopi volumio[988]: info: Listing playlists Sep 04 22:12:54 volumiopi volumio[988]: info: VolumeController:: Volume=100 Mute =false Sep 04 22:12:54 volumiopi volumio[988]: info: CoreStateMachine::pushState Sep 04 22:12:54 volumiopi volumio[988]: info: CorePlayQueue::getTrack 0 Sep 04 22:12:54 volumiopi volumio[988]: info: CoreCommandRouter::volumioPushState Sep 04 22:12:54 volumiopi sudo[1041]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:54 volumiopi sudo[1183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 04 22:12:54 volumiopi sudo[1183]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:54 volumiopi sudo[1183]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:54 volumiopi sudo[1185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 04 22:12:54 volumiopi sudo[1185]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:12:54 volumiopi sudo[1185]: pam_unix(sudo:session): session closed for user root Sep 04 22:12:54 volumiopi volumio[988]: info: Discovery: adding 9edca832-f61d-4e2b-a3b0-e0ac3fc38a52 Sep 04 22:12:54 volumiopi volumio[988]: info: Discovery: Found device VolumioPi Sep 04 22:12:54 volumiopi volumio[988]: info: CoreCommandRouter::volumioGetState Sep 04 22:12:54 volumiopi volumio[988]: info: CorePlayQueue::getTrack 0 Sep 04 22:12:54 volumiopi volumio[988]: verbose: New Socket.io Connection to 192.168.1.189 from 192.168.1.157 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Sep 04 22:12:54 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 04 22:12:54 volumiopi volumio[988]: info: CoreCommandRouter::volumioGetVisibleSources Sep 04 22:12:54 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 22:12:54 volumiopi volumio[988]: info: CoreCommandRouter::volumioGetState Sep 04 22:12:54 volumiopi volumio[988]: info: CorePlayQueue::getTrack 0 Sep 04 22:12:54 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 04 22:12:54 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 04 22:12:54 volumiopi volumio[988]: info: Received Get System Info Sep 04 22:12:54 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 22:12:54 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 22:12:54 volumiopi volumio[988]: info: Discovery: Getting this device information Sep 04 22:12:54 volumiopi volumio[988]: info: CoreCommandRouter::volumioGetState Sep 04 22:12:54 volumiopi volumio[988]: info: CorePlayQueue::getTrack 0 Sep 04 22:12:54 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 22:12:54 volumiopi volumio[988]: info: CoreCommandRouter::volumioGetState Sep 04 22:12:54 volumiopi volumio[988]: info: CorePlayQueue::getTrack 0 Sep 04 22:12:54 volumiopi volumio[988]: info: Listing playlists Sep 04 22:12:54 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 04 22:12:54 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 04 22:12:55 volumiopi systemd[1]: systemd-hostnamed.service: Succeeded. Sep 04 22:12:55 volumiopi volumio[988]: info: CoreCommandRouter::volumioGetState Sep 04 22:12:55 volumiopi volumio[988]: info: CorePlayQueue::getTrack 0 Sep 04 22:12:56 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 04 22:12:56 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 04 22:12:56 volumiopi volumio[988]: info: Received Get System Info Sep 04 22:12:56 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 22:12:56 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 22:12:56 volumiopi volumio[988]: info: Discovery: Getting this device information Sep 04 22:12:56 volumiopi volumio[988]: info: CoreCommandRouter::volumioGetState Sep 04 22:12:56 volumiopi volumio[988]: info: CorePlayQueue::getTrack 0 Sep 04 22:12:56 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 22:12:56 volumiopi volumio-remote-updater[537]: [2025-09-04 22:12:56] [connect] Successful connection Sep 04 22:12:56 volumiopi volumio-remote-updater[537]: [2025-09-04 22:12:56] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757020376 101 Sep 04 22:12:56 volumiopi volumio[988]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Sep 04 22:12:56 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 04 22:12:57 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 04 22:12:57 volumiopi volumio[988]: info: Received Get System Info Sep 04 22:12:57 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 22:12:57 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 22:12:57 volumiopi volumio[988]: info: Discovery: Getting this device information Sep 04 22:12:57 volumiopi volumio[988]: info: CoreCommandRouter::volumioGetState Sep 04 22:12:57 volumiopi volumio[988]: info: CorePlayQueue::getTrack 0 Sep 04 22:12:57 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 22:12:57 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 04 22:12:57 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 22:12:57 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 22:12:57 volumiopi volumio[988]: info: Discovery: Getting this device information Sep 04 22:12:57 volumiopi volumio[988]: info: CoreCommandRouter::volumioGetState Sep 04 22:12:57 volumiopi volumio[988]: info: CorePlayQueue::getTrack 0 Sep 04 22:12:57 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 22:12:57 volumiopi volumio[988]: verbose: New Socket.io Connection to 192.168.1.189:3000 from 192.168.1.180 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Sep 04 22:12:57 volumiopi volumio[988]: info: CoreCommandRouter::volumioGetState Sep 04 22:12:57 volumiopi volumio[988]: info: CorePlayQueue::getTrack 0 Sep 04 22:12:58 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 04 22:12:58 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 04 22:13:00 volumiopi sudo[1206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 04 22:13:00 volumiopi sudo[1206]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:13:00 volumiopi sudo[1206]: pam_unix(sudo:session): session closed for user root Sep 04 22:13:00 volumiopi sudo[1208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 04 22:13:00 volumiopi sudo[1208]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:13:00 volumiopi sudo[1208]: pam_unix(sudo:session): session closed for user root Sep 04 22:13:00 volumiopi sudo[1212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 04 22:13:00 volumiopi sudo[1212]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:13:00 volumiopi systemd[1]: Started UPnP Renderer front-end to MPD. Sep 04 22:13:00 volumiopi sudo[1212]: pam_unix(sudo:session): session closed for user root Sep 04 22:13:00 volumiopi volumio[988]: info: Upmpdcli Daemon Started Sep 04 22:13:01 volumiopi volumio[1214]: Generating RSA private key, 4096 bit long modulus (2 primes) Sep 04 22:13:01 volumiopi volumio[988]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 04 22:13:01 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 04 22:13:01 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 04 22:13:01 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 04 22:13:01 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 04 22:13:01 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 22:13:01 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 22:13:01 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 22:13:01 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 22:13:01 volumiopi volumio[988]: info: CoreCommandRouter::volumioGetBrowseSources Sep 04 22:13:01 volumiopi volumio[988]: info: CoreCommandRouter::volumioGetBrowseSources Sep 04 22:13:01 volumiopi volumio[988]: info: CoreCommandRouter::volumioGetBrowseSources Sep 04 22:13:01 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 22:13:01 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 04 22:13:02 volumiopi volumio[988]: info: CoreCommandRouter::volumioGetState Sep 04 22:13:02 volumiopi volumio[988]: info: CorePlayQueue::getTrack 0 Sep 04 22:13:04 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 04 22:13:05 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 22:13:06 volumiopi volumio[1214]: ......................................................++++ Sep 04 22:13:08 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Sep 04 22:13:08 volumiopi sudo[1241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/Music Sep 04 22:13:08 volumiopi sudo[1241]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 22:13:08 volumiopi systemd[1]: mnt-NAS-Music.mount: Succeeded. Sep 04 22:13:08 volumiopi sudo[1241]: pam_unix(sudo:session): session closed for user root Sep 04 22:13:08 volumiopi volumio[988]: info: Share Music successfully unmounted Sep 04 22:13:09 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 22:13:09 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 04 22:13:11 volumiopi volumio[988]: info: [1757020391437] Database update started Sep 04 22:13:12 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 04 22:13:12 volumiopi volumio[988]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 04 22:13:12 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 04 22:13:12 volumiopi volumio[988]: info: Received Get System Version Sep 04 22:13:12 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 04 22:13:12 volumiopi volumio[988]: info: Received Get System Info Sep 04 22:13:12 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 22:13:12 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 22:13:12 volumiopi volumio[988]: info: Discovery: Getting this device information Sep 04 22:13:12 volumiopi volumio[988]: info: CoreCommandRouter::volumioGetState Sep 04 22:13:12 volumiopi volumio[988]: info: CorePlayQueue::getTrack 0 Sep 04 22:13:12 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 22:13:12 volumiopi volumio[988]: info: CoreCommandRouter::volumioGetState Sep 04 22:13:12 volumiopi volumio[988]: info: CorePlayQueue::getTrack 0 Sep 04 22:13:12 volumiopi volumio[988]: info: Listing playlists Sep 04 22:13:12 volumiopi volumio[988]: info: Listing playlists Sep 04 22:13:12 volumiopi volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Sep 04 22:13:12 volumiopi volumio[988]: verbose: ControllerMpd::sendMpdCommand rescan Sep 04 22:13:12 volumiopi volumio[988]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 22:13:13 volumiopi volumio[988]: TypeError: Cannot read property 'then' of undefined Sep 04 22:13:13 volumiopi volumio[988]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:332:6) Sep 04 22:13:13 volumiopi volumio[988]: at ControllerMpd.rescanDb (/volumio/app/plugins/music_service/mpd/index.js:1886:15) Sep 04 22:13:13 volumiopi volumio[988]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Sep 04 22:13:13 volumiopi volumio[988]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1087:26) Sep 04 22:13:13 volumiopi volumio[988]: at Socket.emit (events.js:315:20) Sep 04 22:13:13 volumiopi volumio[988]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 04 22:13:13 volumiopi volumio[988]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 04 22:13:13 volumiopi volumio[988]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 22:13:13 volumiopi sudo[1273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-04 22:12 Sep 04 22:13:13 volumiopi sudo[1273]: 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"