-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Sun 2025-09-14 17:25:47 EDT. -- Sep 14 17:25:09 volumio002 systemd-timedated[877]: Changed local time to Sun Sep 14 17:25:09 2025 Sep 14 17:25:09 volumio002 sudo[875]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:09 volumio002 volumio-time-update[557]: volumio-time-update-util: System time updated successfully. Sep 14 17:25:09 volumio002 systemd[1]: Started Volumio Time Update Utility. Sep 14 17:25:09 volumio002 sudo[811]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:09 volumio002 wireless.js[562]: SETTING APPROPRIATE REG DOMAIN: US Sep 14 17:25:09 volumio002 sudo[879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 14 17:25:09 volumio002 sudo[879]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:09 volumio002 sudo[879]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:09 volumio002 sudo[881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Sep 14 17:25:09 volumio002 sudo[881]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:09 volumio002 sudo[881]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:09 volumio002 wireless.js[562]: SUCCESSFULLY SET NEW REGDOMAIN: US Sep 14 17:25:09 volumio002 wireless.js[562]: Start wireless flow Sep 14 17:25:09 volumio002 wireless.js[562]: Stopped hotspot (if there).. Sep 14 17:25:09 volumio002 sudo[886]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 14 17:25:09 volumio002 sudo[886]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:09 volumio002 sudo[886]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:09 volumio002 sudo[888]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 14 17:25:09 volumio002 sudo[888]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:09 volumio002 sudo[888]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:09 volumio002 wireless.js[562]: DHCP IP Sep 14 17:25:09 volumio002 wireless.js[562]: Start ap Sep 14 17:25:09 volumio002 wpa_supplicant[890]: Successfully initialized wpa_supplicant Sep 14 17:25:09 volumio002 nmbd[721]: [2025/09/14 17:25:09.836813, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 14 17:25:09 volumio002 nmbd[721]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Sep 14 17:25:09 volumio002 sudo[891]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Sep 14 17:25:09 volumio002 systemd[1]: Started Samba NMB Daemon. Sep 14 17:25:09 volumio002 sudo[891]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:09 volumio002 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 14 17:25:09 volumio002 dhcpcd[892]: dev: loaded udev Sep 14 17:25:09 volumio002 dhcpcd-run-hooks[903]: wlan0: starting wpa_supplicant Sep 14 17:25:09 volumio002 systemd[1]: Starting Samba Winbind Daemon... Sep 14 17:25:10 volumio002 winbindd[893]: [2025/09/14 17:25:10.190522, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Sep 14 17:25:10 volumio002 winbindd[893]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Sep 14 17:25:10 volumio002 winbindd[893]: [2025/09/14 17:25:10.212456, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 14 17:25:10 volumio002 systemd[1]: Started Samba Winbind Daemon. Sep 14 17:25:10 volumio002 winbindd[893]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Sep 14 17:25:10 volumio002 dhcpcd-run-hooks[911]: wlan0: failed to start wpa_supplicant Sep 14 17:25:10 volumio002 dhcpcd-run-hooks[912]: 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 14 17:25:10 volumio002 dhcpcd[892]: wlan0: connected to Access Point `' Sep 14 17:25:10 volumio002 dhcpcd[892]: DUID 00:01:00:01:2c:2b:35:3f:b8:27:eb:a9:05:39 Sep 14 17:25:10 volumio002 dhcpcd[892]: eth0: IAID eb:a9:05:39 Sep 14 17:25:10 volumio002 dhcpcd[892]: eth0: adding address fe80::9e66:651a:3203:ec7b Sep 14 17:25:10 volumio002 dhcpcd[892]: ipv6_addaddr1: Permission denied Sep 14 17:25:10 volumio002 dhcpcd[892]: wlan0: waiting for carrier Sep 14 17:25:10 volumio002 systemd[1]: Starting Samba SMB Daemon... Sep 14 17:25:10 volumio002 dhcpcd[892]: eth0: soliciting an IPv6 router Sep 14 17:25:10 volumio002 smbd[929]: [2025/09/14 17:25:10.766746, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 14 17:25:10 volumio002 smbd[929]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Sep 14 17:25:10 volumio002 systemd[1]: Started Samba SMB Daemon. Sep 14 17:25:10 volumio002 wireless.js[562]: trying... Sep 14 17:25:10 volumio002 sudo[938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 17:25:10 volumio002 sudo[938]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:10 volumio002 sudo[938]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:11 volumio002 dhcpcd[892]: eth0: rebinding lease of 192.168.1.152 Sep 14 17:25:11 volumio002 ntpd[744]: Listen normally on 3 eth0 192.168.1.152:123 Sep 14 17:25:11 volumio002 ntpd[744]: new interface(s) found: waking up resolver Sep 14 17:25:11 volumio002 dhcpcd[892]: eth0: leased 192.168.1.152 for infinity Sep 14 17:25:11 volumio002 dhcpcd[892]: eth0: adding route to 192.168.0.0/23 Sep 14 17:25:11 volumio002 dhcpcd[892]: eth0: adding default route via 192.168.0.1 Sep 14 17:25:11 volumio002 dhcpcd[892]: forked to background, child pid 960 Sep 14 17:25:11 volumio002 sudo[891]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:11 volumio002 wireless.js[562]: trying... Sep 14 17:25:11 volumio002 sudo[962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 17:25:11 volumio002 sudo[962]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:11 volumio002 sudo[962]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:12 volumio002 wireless.js[562]: trying... Sep 14 17:25:12 volumio002 sudo[965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 17:25:12 volumio002 sudo[965]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:12 volumio002 sudo[965]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:13 volumio002 wireless.js[562]: trying... Sep 14 17:25:14 volumio002 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 14 17:25:14 volumio002 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 14 17:25:14 volumio002 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 14 17:25:14 volumio002 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 14 17:25:16 volumio002 volumio-remote-updater[571]: [2025-09-14 17:25:16] [info] asio async_connect error: system:111 (Connection refused) Sep 14 17:25:16 volumio002 volumio-remote-updater[571]: [2025-09-14 17:25:16] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 14 17:25:16 volumio002 volumio-remote-updater[571]: [2025-09-14 17:25:16] [error] handle_connect error: Underlying Transport Error Sep 14 17:25:16 volumio002 wpa_supplicant[907]: wlan0: Trying to associate with SSID 'FBI Surveillance Van 55' Sep 14 17:25:16 volumio002 sudo[968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 17:25:16 volumio002 sudo[968]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:16 volumio002 sudo[968]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:16 volumio002 wireless.js[562]: trying... Sep 14 17:25:16 volumio002 sudo[971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 17:25:16 volumio002 sudo[971]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:16 volumio002 sudo[971]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:16 volumio002 dhcpcd[960]: wlan0: carrier acquired Sep 14 17:25:16 volumio002 dhcpcd[960]: wlan0: IAID eb:fc:50:6c Sep 14 17:25:16 volumio002 dhcpcd[960]: wlan0: adding address fe80::51f1:888:edd1:c4b3 Sep 14 17:25:16 volumio002 dhcpcd[960]: ipv6_addaddr1: Permission denied Sep 14 17:25:16 volumio002 dhcpcd[960]: wlan0: carrier lost Sep 14 17:25:16 volumio002 wpa_supplicant[907]: wlan0: Associated with b2:af:97:77:93:74 Sep 14 17:25:16 volumio002 wpa_supplicant[907]: wlan0: CTRL-EVENT-CONNECTED - Connection to b2:af:97:77:93:74 completed [id=0 id_str=] Sep 14 17:25:16 volumio002 wpa_supplicant[907]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 14 17:25:16 volumio002 wpa_supplicant[907]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Sep 14 17:25:16 volumio002 dhcpcd[960]: wlan0: carrier acquired Sep 14 17:25:16 volumio002 dhcpcd[960]: wlan0: connected to Access Point `FBI Surveillance Van 55' Sep 14 17:25:16 volumio002 dhcpcd[960]: wlan0: IAID eb:fc:50:6c Sep 14 17:25:17 volumio002 dhcpcd[960]: wlan0: soliciting an IPv6 router Sep 14 17:25:17 volumio002 dhcpcd[960]: wlan0: rebinding lease of 192.168.1.100 Sep 14 17:25:17 volumio002 wireless.js[562]: trying... Sep 14 17:25:17 volumio002 sudo[997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 17:25:17 volumio002 sudo[997]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:17 volumio002 sudo[997]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:18 volumio002 wireless.js[562]: trying... Sep 14 17:25:18 volumio002 sudo[1000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 17:25:18 volumio002 sudo[1000]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:18 volumio002 sudo[1000]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:19 volumio002 wireless.js[562]: trying... Sep 14 17:25:19 volumio002 sudo[1003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 17:25:19 volumio002 sudo[1003]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:19 volumio002 sudo[1003]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:20 volumio002 wireless.js[562]: trying... Sep 14 17:25:20 volumio002 sudo[1006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 17:25:20 volumio002 sudo[1006]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:20 volumio002 sudo[1006]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:21 volumio002 dhcpcd[960]: wlan0: probing address 192.168.1.100/23 Sep 14 17:25:21 volumio002 wireless.js[562]: trying... Sep 14 17:25:21 volumio002 sudo[1009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 17:25:21 volumio002 sudo[1009]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:21 volumio002 sudo[1009]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:22 volumio002 wireless.js[562]: trying... Sep 14 17:25:22 volumio002 sudo[1012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 17:25:22 volumio002 sudo[1012]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:22 volumio002 sudo[1012]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:23 volumio002 wireless.js[562]: trying... Sep 14 17:25:23 volumio002 sudo[1015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 17:25:23 volumio002 sudo[1015]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:23 volumio002 sudo[1015]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:24 volumio002 wireless.js[562]: trying... Sep 14 17:25:24 volumio002 sudo[1018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 17:25:24 volumio002 sudo[1018]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:24 volumio002 sudo[1018]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:25 volumio002 wireless.js[562]: trying... Sep 14 17:25:25 volumio002 sudo[1021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 17:25:25 volumio002 sudo[1021]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:25 volumio002 sudo[1021]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:26 volumio002 dhcpcd[960]: wlan0: leased 192.168.1.100 for 7200 seconds Sep 14 17:25:26 volumio002 avahi-daemon[587]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.100. Sep 14 17:25:26 volumio002 avahi-daemon[587]: New relevant interface wlan0.IPv4 for mDNS. Sep 14 17:25:26 volumio002 dhcpcd[960]: wlan0: adding route to 192.168.0.0/23 Sep 14 17:25:26 volumio002 avahi-daemon[587]: Registering new address record for 192.168.1.100 on wlan0.IPv4. Sep 14 17:25:26 volumio002 dhcpcd[960]: wlan0: adding default route via 192.168.0.1 Sep 14 17:25:26 volumio002 volumio-remote-updater[571]: [2025-09-14 17:25:26] [info] asio async_connect error: system:111 (Connection refused) Sep 14 17:25:26 volumio002 volumio-remote-updater[571]: [2025-09-14 17:25:26] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 14 17:25:26 volumio002 volumio-remote-updater[571]: [2025-09-14 17:25:26] [error] handle_connect error: Underlying Transport Error Sep 14 17:25:26 volumio002 wireless.js[562]: trying... Sep 14 17:25:26 volumio002 sudo[1044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 17:25:26 volumio002 sudo[1044]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:26 volumio002 sudo[1044]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:26 volumio002 wireless.js[562]: Connected to: ----FBI Surveillance Van 55 Sep 14 17:25:26 volumio002 wireless.js[562]: ---- Sep 14 17:25:26 volumio002 sudo[1047]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 14 17:25:26 volumio002 sudo[1047]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:26 volumio002 sudo[1047]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:26 volumio002 wireless.js[562]: ... joined AP, wlan0 IPv4 is 192.168.1.100, ipV6 is undefined Sep 14 17:25:26 volumio002 wireless.js[562]: It's done! AP Sep 14 17:25:27 volumio002 systemd[1]: Started Wireless Services. Sep 14 17:25:27 volumio002 systemd[1]: Started Volumio Backend Module. Sep 14 17:25:27 volumio002 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 14 17:25:27 volumio002 systemd[1]: Started Volumio Cpu Tweaker. Sep 14 17:25:27 volumio002 systemd[1]: Reached target Multi-User System. Sep 14 17:25:27 volumio002 systemd[1]: Reached target Graphical Interface. Sep 14 17:25:27 volumio002 systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 14 17:25:27 volumio002 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 14 17:25:27 volumio002 systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 14 17:25:27 volumio002 systemd[1]: Startup finished in 13.299s (kernel) + 29.463s (userspace) = 42.763s. Sep 14 17:25:27 volumio002 volumio-cpu-tweak[1053]: Setting RT Priority for mpd Sep 14 17:25:27 volumio002 volumio-cpu-tweak[1053]: Setting MPD Affinity Sep 14 17:25:27 volumio002 volumio-cpu-tweak[1053]: pid 774's current affinity mask: f Sep 14 17:25:27 volumio002 volumio-cpu-tweak[1053]: pid 774's new affinity mask: 3 Sep 14 17:25:27 volumio002 volumio-cpu-tweak[1053]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Sep 14 17:25:27 volumio002 volumio-cpu-tweak[1053]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Sep 14 17:25:27 volumio002 systemd[1]: volumio_cpu_tweak.service: Succeeded. Sep 14 17:25:28 volumio002 ntpd[744]: Listen normally on 4 wlan0 192.168.1.100:123 Sep 14 17:25:28 volumio002 ntpd[744]: new interface(s) found: waking up resolver Sep 14 17:25:29 volumio002 volumio[1052]: info: ------------------------------------------- Sep 14 17:25:29 volumio002 volumio[1052]: info: ----- Volumio3 ---- Sep 14 17:25:29 volumio002 volumio[1052]: info: ------------------------------------------- Sep 14 17:25:29 volumio002 volumio[1052]: info: ----- System startup ---- Sep 14 17:25:29 volumio002 volumio[1052]: info: ------------------------------------------- Sep 14 17:25:30 volumio002 systemd[1]: systemd-fsckd.service: Succeeded. Sep 14 17:25:30 volumio002 volumio[1052]: info: MYVOLUMIO Environment detected Sep 14 17:25:30 volumio002 volumio[1052]: info: Plugin folders cleanup Sep 14 17:25:30 volumio002 volumio[1052]: info: Scanning into folder /volumio/app/plugins/ Sep 14 17:25:30 volumio002 volumio[1052]: info: Scanning category audio_interface Sep 14 17:25:30 volumio002 volumio[1052]: info: Scanning category miscellanea Sep 14 17:25:30 volumio002 volumio[1052]: info: Scanning category music_service Sep 14 17:25:30 volumio002 volumio[1052]: info: Scanning category plugins.json Sep 14 17:25:30 volumio002 volumio[1052]: info: Scanning category system_controller Sep 14 17:25:30 volumio002 volumio[1052]: info: Scanning category user_interface Sep 14 17:25:30 volumio002 volumio[1052]: info: Scanning into folder /data/plugins/ Sep 14 17:25:30 volumio002 volumio[1052]: info: Plugin folders cleanup completed Sep 14 17:25:30 volumio002 volumio[1052]: info: ------------------------------------------- Sep 14 17:25:30 volumio002 volumio[1052]: info: ----- Core plugins startup ---- Sep 14 17:25:30 volumio002 volumio[1052]: info: ------------------------------------------- Sep 14 17:25:30 volumio002 volumio[1052]: info: Loading plugins from folder /volumio/app/plugins/ Sep 14 17:25:30 volumio002 volumio[1052]: info: Adding plugin upnp to MyMusic Plugins Sep 14 17:25:30 volumio002 volumio[1052]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 14 17:25:30 volumio002 volumio[1052]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 14 17:25:30 volumio002 volumio[1052]: info: Loading plugins from folder /data/plugins/ Sep 14 17:25:30 volumio002 volumio[1052]: info: Loading plugin "system"... Sep 14 17:25:30 volumio002 volumio[1052]: info: Loading plugin "appearance"... Sep 14 17:25:32 volumio002 volumio[1052]: info: Loading plugin "network"... Sep 14 17:25:32 volumio002 volumio[1052]: info: Refreshing Cached IP Addresses Sep 14 17:25:32 volumio002 sudo[1087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 14 17:25:32 volumio002 sudo[1087]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:32 volumio002 sudo[1087]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:32 volumio002 sudo[1089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 14 17:25:32 volumio002 sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:32 volumio002 volumio[1052]: info: Loading plugin "services"... Sep 14 17:25:32 volumio002 sudo[1089]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:32 volumio002 volumio[1052]: info: Loading plugin "alsa_controller"... Sep 14 17:25:32 volumio002 sudo[1097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 14 17:25:32 volumio002 sudo[1097]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:32 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 17:25:32 volumio002 volumio[1052]: info: Loading plugin "wizard"... Sep 14 17:25:32 volumio002 volumio[1052]: info: Loading plugin "networkfs"... Sep 14 17:25:32 volumio002 sudo[1115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=todd,password=retard6!Askeeter236,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.213/Backup1\\my_music /mnt/NAS/Nas Sep 14 17:25:32 volumio002 sudo[1115]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:32 volumio002 volumio[1052]: info: Starting Udev Watcher for removable devices Sep 14 17:25:32 volumio002 sudo[1117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=todd,password=retard6!Askeeter236,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.213/music\\My_Music3 /mnt/NAS/Nas Sep 14 17:25:32 volumio002 sudo[1117]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:32 volumio002 volumio[1052]: info: Ignoring mount for partition: boot Sep 14 17:25:32 volumio002 volumio[1052]: info: Ignoring mount for partition: volumio Sep 14 17:25:32 volumio002 volumio[1052]: info: Ignoring mount for partition: volumio_data Sep 14 17:25:32 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 17:25:32 volumio002 volumio[1052]: info: Loading plugin "volumio_command_line_client"... Sep 14 17:25:32 volumio002 volumio[1052]: info: Loading plugin "upnp"... Sep 14 17:25:32 volumio002 volumio[1052]: info: [1757885132763] Starting Upmpd Daemon Sep 14 17:25:32 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 17:25:32 volumio002 volumio[1052]: info: Loading plugin "my_music"... Sep 14 17:25:32 volumio002 volumio[1052]: info: Loading plugin "mpd"... Sep 14 17:25:32 volumio002 kernel: Key type cifs.spnego registered Sep 14 17:25:32 volumio002 kernel: Key type cifs.idmap registered Sep 14 17:25:32 volumio002 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 14 17:25:32 volumio002 kernel: CIFS: Attempting to mount //192.168.0.213/Backup1/my_music Sep 14 17:25:33 volumio002 kernel: cryptd: max_cpu_qlen set to 1000 Sep 14 17:25:33 volumio002 sudo[1115]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:33 volumio002 kernel: CIFS: Attempting to mount //192.168.0.213/music/My_Music3 Sep 14 17:25:33 volumio002 sudo[1117]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:33 volumio002 volumio[1052]: info: Loading plugin "upnp_browser"... Sep 14 17:25:33 volumio002 volumio[1052]: info: Loading plugin "alarm-clock"... Sep 14 17:25:33 volumio002 volumio[1052]: info: Loading plugin "airplay_emulation"... Sep 14 17:25:34 volumio002 volumio[1052]: info: Starting Shairport Sync Sep 14 17:25:34 volumio002 volumio[1052]: info: Loading plugin "last_100"... Sep 14 17:25:34 volumio002 volumio[1052]: info: Loading plugin "webradio"... Sep 14 17:25:34 volumio002 volumio[1052]: info: Loading plugin "i2s_dacs"... Sep 14 17:25:34 volumio002 volumio[1052]: info: Loading plugin "volumiodiscovery"... Sep 14 17:25:34 volumio002 volumio[1052]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 14 17:25:34 volumio002 volumio[1052]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 17:25:34 volumio002 volumio[1052]: *** WARNING *** For more information see Sep 14 17:25:34 volumio002 volumio[1052]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 14 17:25:34 volumio002 volumio[1052]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 17:25:34 volumio002 node[1052]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 14 17:25:34 volumio002 volumio[1052]: *** WARNING *** For more information see Sep 14 17:25:34 volumio002 node[1052]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 17:25:34 volumio002 node[1052]: *** WARNING *** For more information see Sep 14 17:25:34 volumio002 node[1052]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 14 17:25:34 volumio002 node[1052]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 17:25:34 volumio002 node[1052]: *** WARNING *** For more information see Sep 14 17:25:34 volumio002 volumio[1052]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 14 17:25:34 volumio002 volumio[1052]: info: Discovery: Started advertising with name: volumio002 Sep 14 17:25:34 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 17:25:34 volumio002 volumio[1052]: info: Loading plugin "outputs"... Sep 14 17:25:34 volumio002 volumio[1052]: info: Loading plugin "albumart"... Sep 14 17:25:34 volumio002 systemd[1]: systemd-hostnamed.service: Succeeded. Sep 14 17:25:34 volumio002 volumio[1052]: info: Plugin example_plugin is not enabled Sep 14 17:25:34 volumio002 volumio[1052]: info: Loading plugin "inputs"... Sep 14 17:25:34 volumio002 volumio[1052]: info: Loading plugin "updater_comm"... Sep 14 17:25:34 volumio002 volumio[1052]: info: Plugin mpdemulation is not enabled Sep 14 17:25:34 volumio002 volumio[1052]: info: Loading plugin "rest_api"... Sep 14 17:25:34 volumio002 volumio[1052]: info: Loading plugin "websocket"... Sep 14 17:25:34 volumio002 volumio[1052]: info: Starting Socket.io Server version 2.3.0 Sep 14 17:25:34 volumio002 volumio[1052]: info: Loading i18n strings for locale en Sep 14 17:25:34 volumio002 volumio[1052]: Updating browse sources language Sep 14 17:25:34 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 17:25:34 volumio002 volumio[1052]: Forking 3 albumart workers Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreCommandRouter::initPlayerControls Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 17:25:35 volumio002 volumio[1052]: Express server listening on port 3000 Sep 14 17:25:35 volumio002 volumio[1052]: [Metrics] WebUI: 6s 351.96ms Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreStateMachine::resetVolumioState Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreStateMachine::getcurrentVolume Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreCommandRouter::volumioRetrievevolume Sep 14 17:25:35 volumio002 volumio[1052]: info: Volumio Network Manager: Network status updated: 3 Sep 14 17:25:35 volumio002 volumio[1052]: verbose: New Socket.io Connection to 192.168.1.100 from 192.168.1.29 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Sep 14 17:25:35 volumio002 volumio[1052]: verbose: New Socket.io Connection to 192.168.1.152 from 192.168.1.29 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Sep 14 17:25:35 volumio002 volumio[1052]: verbose: New Socket.io Connection to 192.168.1.100 from 192.168.1.29 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Sep 14 17:25:35 volumio002 volumio[1052]: verbose: New Socket.io Connection to 192.168.1.152 from 192.168.1.29 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Sep 14 17:25:35 volumio002 volumio[1052]: verbose: New Socket.io Connection to 192.168.1.100 from 192.168.1.29 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Sep 14 17:25:35 volumio002 volumio[1052]: verbose: New Socket.io Connection to 192.168.1.152 from 192.168.1.29 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Sep 14 17:25:35 volumio002 volumio[1052]: verbose: New Socket.io Connection to 192.168.1.100 from 192.168.1.29 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Sep 14 17:25:35 volumio002 volumio[1052]: verbose: New Socket.io Connection to 192.168.1.152 from 192.168.1.29 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Sep 14 17:25:35 volumio002 volumio[1052]: verbose: New Socket.io Connection to 192.168.1.100 from 192.168.1.29 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Sep 14 17:25:35 volumio002 volumio[1052]: verbose: New Socket.io Connection to 192.168.1.100 from 192.168.1.29 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Sep 14 17:25:35 volumio002 volumio[1052]: verbose: New Socket.io Connection to 192.168.1.152 from 192.168.1.29 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Sep 14 17:25:35 volumio002 volumio[1052]: verbose: New Socket.io Connection to 192.168.1.152 from 192.168.1.29 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 17:25:35 volumio002 volumio[1052]: info: Reloading queue from file Sep 14 17:25:35 volumio002 volumio[1052]: info: VolumeController:: Volume=94 Mute =false Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreStateMachine::pushState Sep 14 17:25:35 volumio002 volumio[1052]: info: CorePlayQueue::getTrack 0 Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreCommandRouter::volumioPushState Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreStateMachine::updateTrackBlock Sep 14 17:25:35 volumio002 volumio[1052]: info: CorePlayQueue::getTrackBlock Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreCommandRouter::volumioRetrievevolume Sep 14 17:25:35 volumio002 volumio[1052]: info: Setting Device type: Raspberry PI Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreStateMachine::setRepeat null single undefined Sep 14 17:25:35 volumio002 volumio[1052]: Starting albumart workers Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreStateMachine::pushState Sep 14 17:25:35 volumio002 volumio[1052]: info: CorePlayQueue::getTrack 0 Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreCommandRouter::volumioPushState Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreStateMachine::setRandom null Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreStateMachine::pushState Sep 14 17:25:35 volumio002 volumio[1052]: info: CorePlayQueue::getTrack 0 Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreCommandRouter::volumioPushState Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 17:25:35 volumio002 volumio[1052]: info: Discovery: Getting this device information Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreCommandRouter::volumioGetState Sep 14 17:25:35 volumio002 volumio[1052]: info: CorePlayQueue::getTrack 0 Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 17:25:35 volumio002 volumio[1052]: Starting albumart workers Sep 14 17:25:35 volumio002 volumio[1052]: verbose: New Socket.io Connection to 192.168.1.152 from 192.168.1.29 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Sep 14 17:25:35 volumio002 volumio[1052]: info: Completed loading Core Plugins Sep 14 17:25:35 volumio002 volumio[1052]: info: Preparing to generate the ALSA configuration file Sep 14 17:25:35 volumio002 volumio[1052]: Starting albumart workers Sep 14 17:25:35 volumio002 volumio[1052]: info: Asound.conf file unchanged, so no further update is needed Sep 14 17:25:35 volumio002 volumio[1052]: info: Output device has changed, restarting MPD Sep 14 17:25:35 volumio002 volumio[1052]: info: Output device has changed, restarting Shairport Sync Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:35 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 17:25:35 volumio002 sudo[1205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 17:25:35 volumio002 sudo[1205]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:35 volumio002 sudo[1205]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:35 volumio002 sudo[1207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 17:25:35 volumio002 sudo[1207]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:35 volumio002 systemd[1]: Stopping Music Player Daemon... Sep 14 17:25:36 volumio002 volumio[1052]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 17:25:36 volumio002 volumio[1052]: info: ___________ START PLUGINS ___________ Sep 14 17:25:36 volumio002 volumio[1052]: info: ControllerMpd::onStart: Initializing MPD Sep 14 17:25:36 volumio002 volumio[1052]: info: Creating MPD Configuration file Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 17:25:36 volumio002 volumio[1052]: info: [1757885136082] CoreMusicLibrary::Adding element Media Servers Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 17:25:36 volumio002 sudo[1216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 17:25:36 volumio002 sudo[1214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 17:25:36 volumio002 sudo[1216]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:36 volumio002 sudo[1214]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:36 volumio002 sudo[1214]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:36 volumio002 systemd[1]: mpd.service: Succeeded. Sep 14 17:25:36 volumio002 systemd[1]: Stopped Music Player Daemon. Sep 14 17:25:36 volumio002 volumio[1052]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 17:25:36 volumio002 systemd[1]: Starting Music Player Daemon... Sep 14 17:25:36 volumio002 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 14 17:25:36 volumio002 systemd[1]: mpd.service: Succeeded. Sep 14 17:25:36 volumio002 systemd[1]: Stopped Music Player Daemon. Sep 14 17:25:36 volumio002 systemd[1]: Starting Music Player Daemon... Sep 14 17:25:36 volumio002 volumio[1052]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 17:25:36 volumio002 volumio[1052]: info: [1757885136292] CoreMusicLibrary::Adding element Last_100 Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 17:25:36 volumio002 volumio[1052]: info: [1757885136299] CoreMusicLibrary::Adding element Webradio Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 17:25:36 volumio002 volumio[1052]: info: Initializing BBC Radios Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 17:25:36 volumio002 volumio[1052]: info: Volumio Calling Home Sep 14 17:25:36 volumio002 sudo[1226]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 17:25:36 volumio002 sudo[1226]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:36 volumio002 sudo[1226]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:36 volumio002 sudo[1097]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:36 volumio002 volumio[1052]: verbose: New Socket.io Connection to 192.168.1.100 from 192.168.1.29 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Sep 14 17:25:36 volumio002 volumio[1052]: verbose: New Socket.io Connection to 192.168.1.152 from 192.168.1.29 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Sep 14 17:25:36 volumio002 volumio[1052]: info: MPD Permissions set Sep 14 17:25:36 volumio002 volumio[1052]: info: MPD Permissions set Sep 14 17:25:36 volumio002 volumio[1052]: info: VolumeController:: Volume=94 Mute =false Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreStateMachine::pushState Sep 14 17:25:36 volumio002 volumio[1052]: info: CorePlayQueue::getTrack 0 Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::volumioPushState Sep 14 17:25:36 volumio002 volumio-remote-updater[571]: [2025-09-14 17:25:36] [connect] Successful connection Sep 14 17:25:36 volumio002 volumio[1052]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 17:25:36 volumio002 volumio[1052]: info: Discovery: adding 9766e047-5292-4e68-9430-c50f73567ccc Sep 14 17:25:36 volumio002 volumio[1052]: info: Discovery: Found device volumio002 Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::volumioGetState Sep 14 17:25:36 volumio002 volumio[1052]: info: CorePlayQueue::getTrack 0 Sep 14 17:25:36 volumio002 volumio[1052]: info: Discovery: this is already registered, 9766e047-5292-4e68-9430-c50f73567ccc Sep 14 17:25:36 volumio002 volumio[1052]: info: Discovery: Found device volumio002 Sep 14 17:25:36 volumio002 volumio[1052]: info: CoreCommandRouter::volumioGetState Sep 14 17:25:36 volumio002 volumio[1052]: info: CorePlayQueue::getTrack 0 Sep 14 17:25:37 volumio002 volumio[1052]: info: Volumio called home Sep 14 17:25:37 volumio002 volumio-remote-updater[571]: [2025-09-14 17:25:37] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757885136 101 Sep 14 17:25:37 volumio002 volumio[1052]: 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: 17 Sep 14 17:25:37 volumio002 volumio[1052]: info: Received Get System Info Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 17:25:37 volumio002 volumio[1052]: info: Discovery: Getting this device information Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::volumioGetState Sep 14 17:25:37 volumio002 volumio[1052]: info: CorePlayQueue::getTrack 0 Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::volumioGetState Sep 14 17:25:37 volumio002 volumio[1052]: info: CorePlayQueue::getTrack 0 Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::volumioGetVisibleSources Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::volumioGetState Sep 14 17:25:37 volumio002 volumio[1052]: info: CorePlayQueue::getTrack 0 Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 14 17:25:37 volumio002 volumio[1052]: info: Received Get System Info Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 17:25:37 volumio002 volumio[1052]: info: Discovery: Getting this device information Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::volumioGetState Sep 14 17:25:37 volumio002 volumio[1052]: info: CorePlayQueue::getTrack 0 Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::volumioGetState Sep 14 17:25:37 volumio002 volumio[1052]: info: CorePlayQueue::getTrack 0 Sep 14 17:25:37 volumio002 volumio[1052]: info: Listing playlists Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: Starting Shairport Sync Sep 14 17:25:37 volumio002 volumio[1052]: info: Starting Shairport Sync Sep 14 17:25:37 volumio002 volumio[1052]: info: Starting Shairport Sync Sep 14 17:25:37 volumio002 sudo[1275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 17:25:37 volumio002 sudo[1275]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:37 volumio002 sudo[1277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 17:25:37 volumio002 sudo[1277]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:37 volumio002 sudo[1280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 17:25:37 volumio002 sudo[1280]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::volumioGetState Sep 14 17:25:37 volumio002 volumio[1052]: info: CorePlayQueue::getTrack 0 Sep 14 17:25:37 volumio002 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 17:25:37 volumio002 systemd[1]: shairport-sync.service: Succeeded. Sep 14 17:25:37 volumio002 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 sudo[1275]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:37 volumio002 sudo[1277]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:37 volumio002 volumio[1052]: info: Shairport-Sync Started Sep 14 17:25:37 volumio002 volumio[1052]: Error adding Membership: Error: addMembership EINVAL Sep 14 17:25:37 volumio002 volumio[1052]: info: Shairport-Sync Started Sep 14 17:25:37 volumio002 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 17:25:37 volumio002 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 14 17:25:37 volumio002 systemd[1]: shairport-sync.service: Succeeded. Sep 14 17:25:37 volumio002 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 17:25:37 volumio002 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 17:25:37 volumio002 sudo[1280]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:37 volumio002 volumio[1052]: info: Shairport-Sync Started Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:37 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 mpd[1240]: Sep 14 17:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 14 17:25:38 volumio002 systemd[1]: Started Music Player Daemon. Sep 14 17:25:38 volumio002 sudo[1216]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:38 volumio002 sudo[1207]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:38 volumio002 volumio[1052]: info: Completed starting Core Plugins Sep 14 17:25:38 volumio002 volumio[1052]: info: ------------------------------------------- Sep 14 17:25:38 volumio002 volumio[1052]: info: ----- MyVolumio plugins startup ---- Sep 14 17:25:38 volumio002 volumio[1052]: info: ------------------------------------------- Sep 14 17:25:38 volumio002 volumio[1052]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 14 17:25:38 volumio002 volumio[1052]: error: MPD error: The expression evaluated to a falsy value: Sep 14 17:25:38 volumio002 volumio[1052]: assert.ok(self.idling) Sep 14 17:25:38 volumio002 volumio[1052]: error: The expression evaluated to a falsy value: Sep 14 17:25:38 volumio002 volumio[1052]: assert.ok(self.idling) Sep 14 17:25:38 volumio002 volumio[1052]: info: MPD running with PID1240 Sep 14 17:25:38 volumio002 volumio[1052]: ,establishing connection Sep 14 17:25:38 volumio002 volumio[1052]: error: MPD error: The expression evaluated to a falsy value: Sep 14 17:25:38 volumio002 volumio[1052]: assert.ok(self.idling) Sep 14 17:25:38 volumio002 volumio[1052]: error: The expression evaluated to a falsy value: Sep 14 17:25:38 volumio002 volumio[1052]: assert.ok(self.idling) Sep 14 17:25:38 volumio002 volumio[1052]: error: updateQueue error: null Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:38 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:39 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:39 volumio002 systemd[1]: systemd-timedated.service: Succeeded. Sep 14 17:25:39 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:39 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:40 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:40 volumio002 volumio[1052]: verbose: New Socket.io Connection to 192.168.1.100 from 192.168.1.29 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 Sep 14 17:25:40 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 17:25:40 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:41 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:41 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:42 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:42 volumio002 sudo[1312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 14 17:25:42 volumio002 sudo[1312]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:42 volumio002 sudo[1312]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:42 volumio002 sudo[1314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 14 17:25:42 volumio002 sudo[1314]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:42 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:42 volumio002 sudo[1314]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:42 volumio002 sudo[1318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 14 17:25:42 volumio002 sudo[1318]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:42 volumio002 systemd[1]: Started UPnP Renderer front-end to MPD. Sep 14 17:25:42 volumio002 sudo[1318]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:42 volumio002 volumio[1052]: info: Upmpdcli Daemon Started Sep 14 17:25:43 volumio002 volumio[1320]: Generating RSA private key, 4096 bit long modulus (2 primes) Sep 14 17:25:43 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:43 volumio002 volumio[1320]: .++++ Sep 14 17:25:43 volumio002 volumio[1052]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 14 17:25:43 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:44 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:44 volumio002 volumio[1320]: ...........++++ Sep 14 17:25:44 volumio002 volumio[1320]: e is 65537 (0x010001) Sep 14 17:25:44 volumio002 volumio[1320]: writing RSA key Sep 14 17:25:45 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 17:25:45 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:45 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:45 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:46 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 17:25:46 volumio002 volumio[1052]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Sep 14 17:25:46 volumio002 sudo[1358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/Nas Sep 14 17:25:46 volumio002 sudo[1358]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 17:25:46 volumio002 sudo[1358]: pam_unix(sudo:session): session closed for user root Sep 14 17:25:46 volumio002 volumio[1052]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 17:25:46 volumio002 volumio[1052]: Error: EBUSY: resource busy or locked, rmdir '/mnt/NAS/Nas' Sep 14 17:25:46 volumio002 volumio[1052]: at Object.rmdirSync (fs.js:890:10) Sep 14 17:25:46 volumio002 volumio[1052]: at /volumio/node_modules/linux-mountutils/mountutils.js:178:12 Sep 14 17:25:46 volumio002 volumio[1052]: at ChildProcess.exithandler (child_process.js:299:7) Sep 14 17:25:46 volumio002 volumio[1052]: at ChildProcess.emit (events.js:315:20) Sep 14 17:25:46 volumio002 volumio[1052]: at maybeClose (internal/child_process.js:1048:16) Sep 14 17:25:46 volumio002 volumio[1052]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) { Sep 14 17:25:46 volumio002 volumio[1052]: errno: -16, Sep 14 17:25:46 volumio002 volumio[1052]: syscall: 'rmdir', Sep 14 17:25:46 volumio002 volumio[1052]: code: 'EBUSY', Sep 14 17:25:46 volumio002 volumio[1052]: path: '/mnt/NAS/Nas' Sep 14 17:25:46 volumio002 volumio[1052]: } Sep 14 17:25:46 volumio002 volumio[1052]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 17:25:47 volumio002 sudo[1370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-14 17:24 Sep 14 17:25:47 volumio002 sudo[1370]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"