-- Logs begin at Thu 2019-02-14 12:11:59 EET, end at Sat 2024-10-12 23:11:01 EEST. -- Oct 12 23:10:00 volumio2 sudo[793]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 12 23:10:00 volumio2 sudo[793]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:00 volumio2 systemd[1]: systemd-rfkill.service: Succeeded. Oct 12 23:10:00 volumio2 sudo[793]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:00 volumio2 wireless.js[636]: Stopped aP Oct 12 23:10:00 volumio2 sudo[800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 12 23:10:00 volumio2 sudo[800]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:00 volumio2 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 12 23:10:00 volumio2 sudo[800]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:00 volumio2 sudo[802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Oct 12 23:10:00 volumio2 sudo[802]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:00 volumio2 sudo[802]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:00 volumio2 sudo[809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 12 23:10:00 volumio2 sudo[809]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:00 volumio2 sudo[809]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:00 volumio2 sudo[811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Oct 12 23:10:00 volumio2 sudo[811]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:00 volumio2 ntpd[752]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 12 23:10:01 volumio2 ntpd[752]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 12 23:10:02 volumio2 ntpd[752]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 12 23:10:03 volumio2 sudo[811]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:03 volumio2 wireless.js[636]: SETTING APPROPRIATE REG DOMAIN: DE Oct 12 23:10:03 volumio2 sudo[816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 12 23:10:03 volumio2 sudo[816]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:03 volumio2 sudo[816]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:03 volumio2 sudo[818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Oct 12 23:10:03 volumio2 sudo[818]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:03 volumio2 sudo[818]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:03 volumio2 wireless.js[636]: SUCCESSFULLY SET NEW REGDOMAIN: DE Oct 12 23:10:03 volumio2 wireless.js[636]: Start wireless flow Oct 12 23:10:03 volumio2 wireless.js[636]: Stopped hotspot (if there).. Oct 12 23:10:03 volumio2 sudo[823]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 12 23:10:03 volumio2 sudo[823]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:03 volumio2 sudo[823]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:03 volumio2 sudo[825]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 12 23:10:03 volumio2 sudo[825]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:03 volumio2 volumio-remote-updater[640]: [2024-10-12 23:10:03] [info] asio async_connect error: system:111 (Connection refused) Oct 12 23:10:03 volumio2 volumio-remote-updater[640]: [2024-10-12 23:10:03] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 12 23:10:03 volumio2 volumio-remote-updater[640]: [2024-10-12 23:10:03] [error] handle_connect error: Underlying Transport Error Oct 12 23:10:03 volumio2 sudo[825]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:03 volumio2 wireless.js[636]: DHCP IP Oct 12 23:10:03 volumio2 wireless.js[636]: Start ap Oct 12 23:10:03 volumio2 wpa_supplicant[827]: Successfully initialized wpa_supplicant Oct 12 23:10:03 volumio2 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 12 23:10:03 volumio2 sudo[828]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Oct 12 23:10:03 volumio2 sudo[828]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:03 volumio2 dhcpcd[829]: dev: loaded udev Oct 12 23:10:04 volumio2 dhcpcd-run-hooks[847]: wlan0: starting wpa_supplicant Oct 12 23:10:04 volumio2 dhcpcd-run-hooks[852]: wlan0: failed to start wpa_supplicant Oct 12 23:10:04 volumio2 dhcpcd-run-hooks[853]: 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 Oct 12 23:10:04 volumio2 dhcpcd[829]: wlan0: connected to Access Point `' Oct 12 23:10:04 volumio2 dhcpcd[829]: no interfaces have a carrier Oct 12 23:10:04 volumio2 dhcpcd[829]: forked to background, child pid 863 Oct 12 23:10:04 volumio2 dhcpcd[863]: eth0: waiting for carrier Oct 12 23:10:04 volumio2 dhcpcd[863]: wlan0: waiting for carrier Oct 12 23:10:04 volumio2 sudo[828]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:04 volumio2 wireless.js[636]: trying... Oct 12 23:10:04 volumio2 sudo[865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 12 23:10:04 volumio2 sudo[865]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:04 volumio2 sudo[865]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:05 volumio2 wireless.js[636]: trying... Oct 12 23:10:05 volumio2 sudo[868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 12 23:10:05 volumio2 sudo[868]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:05 volumio2 sudo[868]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:06 volumio2 wpa_supplicant[851]: wlan0: Trying to associate with SSID 'ZoneC1F6DE' Oct 12 23:10:06 volumio2 wireless.js[636]: trying... Oct 12 23:10:06 volumio2 sudo[871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 12 23:10:06 volumio2 sudo[871]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:06 volumio2 sudo[871]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:07 volumio2 wireless.js[636]: trying... Oct 12 23:10:07 volumio2 sudo[874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 12 23:10:07 volumio2 sudo[874]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:07 volumio2 sudo[874]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:08 volumio2 wireless.js[636]: trying... Oct 12 23:10:08 volumio2 sudo[877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 12 23:10:08 volumio2 sudo[877]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:08 volumio2 sudo[877]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:09 volumio2 dhcpcd[863]: wlan0: carrier acquired Oct 12 23:10:09 volumio2 dhcpcd[863]: wlan0: connected to Access Point `ZoneC1F6DE' Oct 12 23:10:09 volumio2 wpa_supplicant[851]: wlan0: Associated with 58:d5:6e:c3:3d:83 Oct 12 23:10:09 volumio2 wpa_supplicant[851]: wlan0: CTRL-EVENT-CONNECTED - Connection to 58:d5:6e:c3:3d:83 completed [id=0 id_str=] Oct 12 23:10:09 volumio2 wpa_supplicant[851]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 12 23:10:09 volumio2 dhcpcd[863]: DUID 00:01:00:01:2e:31:53:c6:d8:3a:dd:63:21:cf Oct 12 23:10:09 volumio2 dhcpcd[863]: wlan0: IAID dd:63:21:cf Oct 12 23:10:09 volumio2 dhcpcd[863]: wlan0: adding address fe80::dab6:48ea:44de:36ac Oct 12 23:10:09 volumio2 dhcpcd[863]: ipv6_addaddr1: Permission denied Oct 12 23:10:09 volumio2 dhcpcd[863]: wlan0: carrier lost Oct 12 23:10:09 volumio2 dhcpcd[863]: wlan0: carrier acquired Oct 12 23:10:09 volumio2 dhcpcd[863]: wlan0: IAID dd:63:21:cf Oct 12 23:10:09 volumio2 dhcpcd[863]: wlan0: soliciting an IPv6 router Oct 12 23:10:09 volumio2 wireless.js[636]: trying... Oct 12 23:10:09 volumio2 sudo[892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 12 23:10:09 volumio2 sudo[892]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:09 volumio2 sudo[892]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:10 volumio2 dhcpcd[863]: wlan0: soliciting a DHCP lease Oct 12 23:10:10 volumio2 dhcpcd[863]: wlan0: offered 192.168.0.20 from 192.168.0.1 Oct 12 23:10:10 volumio2 dhcpcd[863]: wlan0: probing address 192.168.0.20/24 Oct 12 23:10:10 volumio2 wireless.js[636]: trying... Oct 12 23:10:10 volumio2 sudo[895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 12 23:10:10 volumio2 sudo[895]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:10 volumio2 sudo[895]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:11 volumio2 volumio-remote-updater[640]: [2024-10-12 23:10:11] [info] asio async_connect error: system:111 (Connection refused) Oct 12 23:10:11 volumio2 volumio-remote-updater[640]: [2024-10-12 23:10:11] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 12 23:10:11 volumio2 volumio-remote-updater[640]: [2024-10-12 23:10:11] [error] handle_connect error: Underlying Transport Error Oct 12 23:10:11 volumio2 wireless.js[636]: trying... Oct 12 23:10:11 volumio2 sudo[898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 12 23:10:11 volumio2 sudo[898]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:11 volumio2 sudo[898]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:12 volumio2 wireless.js[636]: trying... Oct 12 23:10:12 volumio2 sudo[901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 12 23:10:12 volumio2 sudo[901]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:12 volumio2 sudo[901]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:13 volumio2 wireless.js[636]: trying... Oct 12 23:10:13 volumio2 sudo[904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 12 23:10:13 volumio2 sudo[904]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:13 volumio2 sudo[904]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:14 volumio2 dhcpcd[863]: wlan0: leased 192.168.0.20 for 86400 seconds Oct 12 23:10:14 volumio2 avahi-daemon[627]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.20. Oct 12 23:10:14 volumio2 avahi-daemon[627]: New relevant interface wlan0.IPv4 for mDNS. Oct 12 23:10:14 volumio2 dhcpcd[863]: wlan0: adding route to 192.168.0.0/24 Oct 12 23:10:14 volumio2 avahi-daemon[627]: Registering new address record for 192.168.0.20 on wlan0.IPv4. Oct 12 23:10:14 volumio2 dhcpcd[863]: wlan0: adding default route via 192.168.0.1 Oct 12 23:10:14 volumio2 systemd[1]: Started Samba NMB Daemon. Oct 12 23:10:14 volumio2 nmbd[726]: [2024/10/12 23:10:14.553871, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 12 23:10:14 volumio2 nmbd[726]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 12 23:10:14 volumio2 systemd[1]: Starting Samba Winbind Daemon... Oct 12 23:10:14 volumio2 winbindd[927]: [2024/10/12 23:10:14.829850, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Oct 12 23:10:14 volumio2 winbindd[927]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 12 23:10:14 volumio2 winbindd[927]: [2024/10/12 23:10:14.844663, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 12 23:10:14 volumio2 systemd[1]: Started Samba Winbind Daemon. Oct 12 23:10:14 volumio2 winbindd[927]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 12 23:10:14 volumio2 systemd[1]: Starting Samba SMB Daemon... Oct 12 23:10:14 volumio2 wireless.js[636]: trying... Oct 12 23:10:14 volumio2 sudo[934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 12 23:10:14 volumio2 sudo[934]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:14 volumio2 sudo[934]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:14 volumio2 wireless.js[636]: Connected to: ----ZoneC1F6DE Oct 12 23:10:14 volumio2 wireless.js[636]: ---- Oct 12 23:10:14 volumio2 sudo[937]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 23:10:14 volumio2 sudo[937]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:14 volumio2 sudo[937]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:14 volumio2 wireless.js[636]: ... joined AP, wlan0 IPv4 is 192.168.0.20, ipV6 is undefined Oct 12 23:10:14 volumio2 wireless.js[636]: It's done! AP Oct 12 23:10:15 volumio2 systemd[1]: Started Wireless Services. Oct 12 23:10:15 volumio2 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 12 23:10:15 volumio2 systemd[1]: Started Volumio Backend Module. Oct 12 23:10:15 volumio2 systemd[1]: Started Volumio Cpu Tweaker. Oct 12 23:10:15 volumio2 volumio-cpu-tweak[943]: Setting RT Priority for mpd Oct 12 23:10:15 volumio2 volumio-cpu-tweak[943]: pid 35's current scheduling policy: SCHED_OTHER Oct 12 23:10:15 volumio2 volumio-cpu-tweak[943]: pid 35's current scheduling priority: 0 Oct 12 23:10:15 volumio2 volumio-cpu-tweak[943]: Setting MPD Affinity Oct 12 23:10:15 volumio2 volumio-cpu-tweak[943]: pid 3's current affinity mask: f Oct 12 23:10:15 volumio2 volumio-cpu-tweak[943]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Oct 12 23:10:15 volumio2 volumio-cpu-tweak[943]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Oct 12 23:10:15 volumio2 systemd[1]: volumio_cpu_tweak.service: Succeeded. Oct 12 23:10:15 volumio2 smbd[932]: [2024/10/12 23:10:15.251879, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 12 23:10:15 volumio2 systemd[1]: Started Samba SMB Daemon. Oct 12 23:10:15 volumio2 smbd[932]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 12 23:10:15 volumio2 systemd[1]: Reached target Multi-User System. Oct 12 23:10:15 volumio2 systemd[1]: Reached target Graphical Interface. Oct 12 23:10:15 volumio2 systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 12 23:10:15 volumio2 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 12 23:10:15 volumio2 systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 12 23:10:15 volumio2 systemd[1]: Startup finished in 15.417s (kernel) + 26.368s (userspace) = 41.785s. Oct 12 23:10:15 volumio2 ntpd[752]: Listen normally on 3 wlan0 192.168.0.20:123 Oct 12 23:10:15 volumio2 ntpd[752]: new interface(s) found: waking up resolver Oct 12 23:10:16 volumio2 volumio[942]: info: ------------------------------------------- Oct 12 23:10:16 volumio2 volumio[942]: info: ----- Volumio3 ---- Oct 12 23:10:16 volumio2 volumio[942]: info: ------------------------------------------- Oct 12 23:10:16 volumio2 volumio[942]: info: ----- System startup ---- Oct 12 23:10:16 volumio2 volumio[942]: info: ------------------------------------------- Oct 12 23:10:17 volumio2 volumio[942]: info: MYVOLUMIO Environment detected Oct 12 23:10:17 volumio2 volumio[942]: info: Plugin folders cleanup Oct 12 23:10:17 volumio2 volumio[942]: info: Scanning into folder /volumio/app/plugins/ Oct 12 23:10:17 volumio2 volumio[942]: info: Scanning category audio_interface Oct 12 23:10:17 volumio2 volumio[942]: info: Scanning category miscellanea Oct 12 23:10:17 volumio2 volumio[942]: info: Scanning category music_service Oct 12 23:10:17 volumio2 volumio[942]: info: Scanning category plugins.json Oct 12 23:10:17 volumio2 volumio[942]: info: Scanning category system_controller Oct 12 23:10:17 volumio2 volumio[942]: info: Scanning category user_interface Oct 12 23:10:17 volumio2 volumio[942]: info: Scanning into folder /data/plugins/ Oct 12 23:10:17 volumio2 volumio[942]: info: Scanning category system_hardware Oct 12 23:10:17 volumio2 volumio[942]: info: Plugin folders cleanup completed Oct 12 23:10:17 volumio2 volumio[942]: info: ------------------------------------------- Oct 12 23:10:17 volumio2 volumio[942]: info: ----- Core plugins startup ---- Oct 12 23:10:17 volumio2 volumio[942]: info: ------------------------------------------- Oct 12 23:10:17 volumio2 volumio[942]: info: Loading plugins from folder /volumio/app/plugins/ Oct 12 23:10:17 volumio2 volumio[942]: info: Adding plugin upnp to MyMusic Plugins Oct 12 23:10:17 volumio2 volumio[942]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 12 23:10:17 volumio2 volumio[942]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 12 23:10:17 volumio2 volumio[942]: info: Loading plugins from folder /data/plugins/ Oct 12 23:10:17 volumio2 volumio[942]: info: Loading plugin "system"... Oct 12 23:10:17 volumio2 volumio[942]: info: Loading plugin "appearance"... Oct 12 23:10:18 volumio2 volumio[942]: info: Loading plugin "network"... Oct 12 23:10:18 volumio2 volumio[942]: info: Refreshing Cached IP Addresses Oct 12 23:10:18 volumio2 sudo[981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 23:10:18 volumio2 sudo[981]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:18 volumio2 sudo[981]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:18 volumio2 volumio[942]: info: Loading plugin "services"... Oct 12 23:10:18 volumio2 volumio[942]: info: Loading plugin "alsa_controller"... Oct 12 23:10:18 volumio2 sudo[983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 23:10:18 volumio2 sudo[983]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:18 volumio2 sudo[983]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:18 volumio2 sudo[986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 12 23:10:18 volumio2 sudo[986]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:18 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 23:10:18 volumio2 volumio[942]: info: Loading plugin "wizard"... Oct 12 23:10:18 volumio2 volumio[942]: info: Loading plugin "networkfs"... Oct 12 23:10:18 volumio2 volumio[942]: info: Starting Udev Watcher for removable devices Oct 12 23:10:18 volumio2 sudo[994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.0.101/NAS/Music /mnt/NAS/Music_Library Oct 12 23:10:18 volumio2 sudo[994]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:18 volumio2 sudo[996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.101/NAS /mnt/NAS/NAS Oct 12 23:10:18 volumio2 sudo[996]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:18 volumio2 volumio[942]: info: Ignoring mount for partition: boot Oct 12 23:10:18 volumio2 volumio[942]: info: Ignoring mount for partition: volumio Oct 12 23:10:18 volumio2 volumio[942]: info: Ignoring mount for partition: volumio_data Oct 12 23:10:18 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 23:10:18 volumio2 volumio[942]: info: Loading plugin "volumio_command_line_client"... Oct 12 23:10:18 volumio2 volumio[942]: info: Loading plugin "upnp"... Oct 12 23:10:18 volumio2 volumio[942]: info: [1728763818825] Starting Upmpd Daemon Oct 12 23:10:18 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 23:10:18 volumio2 volumio[942]: info: Loading plugin "my_music"... Oct 12 23:10:18 volumio2 volumio[942]: info: Loading plugin "mpd"... Oct 12 23:10:18 volumio2 kernel: Key type cifs.spnego registered Oct 12 23:10:18 volumio2 kernel: Key type cifs.idmap registered Oct 12 23:10:18 volumio2 kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Oct 12 23:10:18 volumio2 kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Oct 12 23:10:18 volumio2 kernel: CIFS: Attempting to mount \\192.168.0.101\NAS Oct 12 23:10:18 volumio2 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. Oct 12 23:10:19 volumio2 volumio[942]: info: Loading plugin "upnp_browser"... Oct 12 23:10:19 volumio2 volumio[942]: info: Loading plugin "alarm-clock"... Oct 12 23:10:19 volumio2 volumio[942]: info: Loading plugin "airplay_emulation"... Oct 12 23:10:19 volumio2 volumio[942]: info: Starting Shairport Sync Oct 12 23:10:19 volumio2 volumio[942]: info: Loading plugin "last_100"... Oct 12 23:10:19 volumio2 volumio[942]: info: Loading plugin "webradio"... Oct 12 23:10:19 volumio2 volumio[942]: info: Loading plugin "i2s_dacs"... Oct 12 23:10:19 volumio2 volumio[942]: info: Loading plugin "volumiodiscovery"... Oct 12 23:10:19 volumio2 volumio[942]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 12 23:10:19 volumio2 node[942]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 12 23:10:19 volumio2 volumio[942]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 23:10:19 volumio2 node[942]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 23:10:19 volumio2 volumio[942]: *** WARNING *** For more information see Oct 12 23:10:19 volumio2 node[942]: *** WARNING *** For more information see Oct 12 23:10:19 volumio2 volumio[942]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 12 23:10:19 volumio2 node[942]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 12 23:10:19 volumio2 volumio[942]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 23:10:19 volumio2 node[942]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 23:10:19 volumio2 volumio[942]: *** WARNING *** For more information see Oct 12 23:10:19 volumio2 node[942]: *** WARNING *** For more information see Oct 12 23:10:19 volumio2 volumio[942]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 12 23:10:19 volumio2 volumio[942]: info: Discovery: Started advertising with name: Volumio2 Oct 12 23:10:19 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 23:10:19 volumio2 volumio[942]: info: Loading plugin "gpio-buttons"... Oct 12 23:10:20 volumio2 volumio[942]: info: GPIO-Buttons initialized Oct 12 23:10:20 volumio2 volumio[942]: info: Loading plugin "outputs"... Oct 12 23:10:20 volumio2 volumio[942]: info: Loading plugin "albumart"... Oct 12 23:10:20 volumio2 volumio[942]: info: Plugin example_plugin is not enabled Oct 12 23:10:20 volumio2 volumio[942]: info: Loading plugin "inputs"... Oct 12 23:10:20 volumio2 volumio[942]: info: Loading plugin "updater_comm"... Oct 12 23:10:20 volumio2 volumio[942]: info: Plugin mpdemulation is not enabled Oct 12 23:10:20 volumio2 volumio[942]: info: Loading plugin "rest_api"... Oct 12 23:10:20 volumio2 volumio[942]: info: Loading plugin "websocket"... Oct 12 23:10:20 volumio2 volumio[942]: info: Starting Socket.io Server version 2.3.0 Oct 12 23:10:20 volumio2 volumio[942]: info: Loading i18n strings for locale en Oct 12 23:10:20 volumio2 volumio[942]: Updating browse sources language Oct 12 23:10:20 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 23:10:20 volumio2 volumio[942]: Forking 3 albumart workers Oct 12 23:10:20 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 23:10:20 volumio2 volumio[942]: info: CoreCommandRouter::initPlayerControls Oct 12 23:10:20 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:20 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:20 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:20 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:20 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:20 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:20 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:20 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:20 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 23:10:20 volumio2 volumio[942]: Express server listening on port 3000 Oct 12 23:10:20 volumio2 volumio[942]: [Metrics] WebUI: 4s 642.14ms Oct 12 23:10:20 volumio2 volumio[942]: info: CoreStateMachine::resetVolumioState Oct 12 23:10:20 volumio2 volumio[942]: info: CoreStateMachine::getcurrentVolume Oct 12 23:10:20 volumio2 volumio[942]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 23:10:21 volumio2 volumio[942]: Starting albumart workers Oct 12 23:10:21 volumio2 volumio[942]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 12 23:10:21 volumio2 volumio[942]: Starting albumart workers Oct 12 23:10:21 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 23:10:21 volumio2 volumio[942]: info: Executing start script for DAC IQaudIO DAC Plus Oct 12 23:10:21 volumio2 volumio[942]: info: Reloading queue from file Oct 12 23:10:21 volumio2 volumio[942]: Starting albumart workers Oct 12 23:10:21 volumio2 volumio[942]: info: VolumeController:: Volume=100 Mute =false Oct 12 23:10:21 volumio2 volumio[942]: info: CoreStateMachine::pushState Oct 12 23:10:21 volumio2 volumio[942]: info: CorePlayQueue::getTrack 0 Oct 12 23:10:21 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 23:10:21 volumio2 volumio[942]: info: CoreCommandRouter::volumioPushState Oct 12 23:10:21 volumio2 volumio[942]: info: CoreStateMachine::updateTrackBlock Oct 12 23:10:21 volumio2 volumio[942]: info: CorePlayQueue::getTrackBlock Oct 12 23:10:21 volumio2 volumio[942]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 23:10:21 volumio2 volumio[942]: info: CoreStateMachine::setRepeat null single undefined Oct 12 23:10:21 volumio2 volumio[942]: info: CoreStateMachine::pushState Oct 12 23:10:21 volumio2 volumio[942]: info: CorePlayQueue::getTrack 0 Oct 12 23:10:21 volumio2 volumio[942]: info: CoreCommandRouter::volumioPushState Oct 12 23:10:21 volumio2 volumio[942]: info: CoreStateMachine::setRandom null Oct 12 23:10:21 volumio2 volumio[942]: info: CoreStateMachine::pushState Oct 12 23:10:21 volumio2 volumio[942]: info: CorePlayQueue::getTrack 0 Oct 12 23:10:21 volumio2 volumio[942]: info: CoreCommandRouter::volumioPushState Oct 12 23:10:21 volumio2 volumio[942]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 12 23:10:21 volumio2 volumio[942]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Oct 12 23:10:21 volumio2 volumio[942]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 12 23:10:21 volumio2 volumio[942]: info: DAC script executed Oct 12 23:10:21 volumio2 volumio[942]: info: Setting Device type: Raspberry PI Oct 12 23:10:21 volumio2 volumio[942]: info: VolumeController:: Volume=100 Mute =false Oct 12 23:10:21 volumio2 volumio-remote-updater[640]: [2024-10-12 23:10:21] [connect] Successful connection Oct 12 23:10:21 volumio2 volumio[942]: info: CoreStateMachine::pushState Oct 12 23:10:21 volumio2 volumio[942]: info: CorePlayQueue::getTrack 0 Oct 12 23:10:21 volumio2 volumio[942]: info: CoreCommandRouter::volumioPushState Oct 12 23:10:21 volumio2 volumio-remote-updater[640]: [2024-10-12 23:10:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728763821 101 Oct 12 23:10:21 volumio2 volumio[942]: 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 Oct 12 23:10:21 volumio2 volumio[942]: info: Discovery: adding 89896dcd-1c76-4099-b825-afbe0529089f Oct 12 23:10:21 volumio2 volumio[942]: info: Discovery: Found device Volumio2 Oct 12 23:10:21 volumio2 volumio[942]: info: CoreCommandRouter::volumioGetState Oct 12 23:10:21 volumio2 volumio[942]: info: CorePlayQueue::getTrack 0 Oct 12 23:10:21 volumio2 volumio[942]: info: Discovery: adding f4fa4681-5df9-4668-bcb5-d14fb5924e1b Oct 12 23:10:21 volumio2 volumio[942]: info: Discovery: Found device Volumio Oct 12 23:10:21 volumio2 volumio[942]: info: Discovery: Connecting to remote: 192.168.0.10 Oct 12 23:10:22 volumio2 sudo[986]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:22 volumio2 volumio[942]: verbose: New Socket.io Connection to 192.168.0.20:3000 from 192.168.0.10 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 12 23:10:22 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Oct 12 23:10:22 volumio2 volumio[942]: info: Discovery: Connected to remote: 192.168.0.10 Oct 12 23:10:22 volumio2 volumio[942]: info: CoreCommandRouter::volumioGetState Oct 12 23:10:22 volumio2 volumio[942]: info: CorePlayQueue::getTrack 0 Oct 12 23:10:25 volumio2 sudo[994]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:25 volumio2 volumio[942]: info: Cannot mount NAS Music_Library at system boot, trial number 1 ,retrying in 5 seconds Oct 12 23:10:25 volumio2 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 12 23:10:25 volumio2 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 12 23:10:25 volumio2 kernel: CIFS: Attempting to mount \\192.168.0.101\NAS Oct 12 23:10:25 volumio2 volumio[942]: info: Discovery: Disconnected from remote: 192.168.0.10 Oct 12 23:10:25 volumio2 systemd[1]: systemd-fsckd.service: Succeeded. Oct 12 23:10:26 volumio2 sh[518]: timed out Oct 12 23:10:26 volumio2 dhcpcd[567]: timed out Oct 12 23:10:26 volumio2 sh[518]: dhcpcd exited Oct 12 23:10:26 volumio2 dhcpcd[567]: dhcpcd exited Oct 12 23:10:26 volumio2 sh[518]: ifup: failed to bring up eth0 Oct 12 23:10:26 volumio2 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Oct 12 23:10:26 volumio2 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Oct 12 23:10:26 volumio2 volumio[942]: info: Discovery: A device disappeared from network Oct 12 23:10:26 volumio2 volumio[942]: info: Discovery: Device volumio disappeared from network Oct 12 23:10:28 volumio2 sudo[1089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 23:10:28 volumio2 sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:28 volumio2 sudo[1089]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:28 volumio2 sudo[1091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 23:10:28 volumio2 sudo[1091]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:28 volumio2 sudo[1091]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:28 volumio2 volumio[942]: verbose: New Socket.io Connection to 192.168.0.20 from 192.168.0.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 12 23:10:28 volumio2 volumio[942]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 23:10:28 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 23:10:28 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 23:10:28 volumio2 volumio[942]: info: CoreCommandRouter::volumioGetState Oct 12 23:10:28 volumio2 volumio[942]: info: CorePlayQueue::getTrack 0 Oct 12 23:10:28 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 12 23:10:28 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 23:10:28 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 23:10:28 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 23:10:28 volumio2 sudo[1095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 23:10:28 volumio2 sudo[1095]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:28 volumio2 sudo[1095]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:28 volumio2 sudo[1097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 23:10:28 volumio2 sudo[1097]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:28 volumio2 sudo[1097]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:28 volumio2 sudo[1101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 12 23:10:28 volumio2 sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:28 volumio2 systemd[1]: Started UPnP Renderer front-end to MPD. Oct 12 23:10:28 volumio2 sudo[1101]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:28 volumio2 volumio[942]: info: Upmpdcli Daemon Started Oct 12 23:10:29 volumio2 volumio[942]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Oct 12 23:10:29 volumio2 sudo[1105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 23:10:29 volumio2 sudo[1105]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:29 volumio2 sudo[1105]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:29 volumio2 sudo[1107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 23:10:29 volumio2 sudo[1107]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:29 volumio2 sudo[1107]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:29 volumio2 volumio[942]: verbose: New Socket.io Connection to 192.168.0.20 from 192.168.0.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 12 23:10:29 volumio2 volumio[942]: info: CoreCommandRouter::volumioGetState Oct 12 23:10:29 volumio2 volumio[942]: info: CorePlayQueue::getTrack 0 Oct 12 23:10:29 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 23:10:29 volumio2 volumio[942]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 23:10:29 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 23:10:29 volumio2 volumio[942]: info: Listing playlists Oct 12 23:10:29 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 12 23:10:29 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 23:10:29 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 23:10:29 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 23:10:29 volumio2 volumio[942]: info: Discovery: adding f4fa4681-5df9-4668-bcb5-d14fb5924e1b Oct 12 23:10:29 volumio2 volumio[942]: info: Discovery: Found device Volumio Oct 12 23:10:29 volumio2 volumio[942]: info: Discovery: Connecting to remote: 192.168.0.10 Oct 12 23:10:29 volumio2 volumio[942]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/4%20Non%20Blondes/Bigger%2C%20Better%2C%20Faster%2C%20More!/ef9c4654-7408-4d7e-bd4c-258b9c824f13.jpg' Oct 12 23:10:30 volumio2 kernel: hwmon hwmon1: Undervoltage detected! Oct 12 23:10:30 volumio2 sudo[1113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.0.101/NAS/Music /mnt/NAS/Music_Library Oct 12 23:10:30 volumio2 sudo[1113]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:30 volumio2 kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Oct 12 23:10:30 volumio2 kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Oct 12 23:10:31 volumio2 volumio[942]: info: Discovery: Connected to remote: 192.168.0.10 Oct 12 23:10:31 volumio2 volumio[942]: verbose: New Socket.io Connection to 192.168.0.20:3000 from 192.168.0.10 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 12 23:10:31 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Oct 12 23:10:31 volumio2 sudo[996]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:31 volumio2 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 12 23:10:31 volumio2 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 12 23:10:31 volumio2 kernel: CIFS: Attempting to mount \\192.168.0.101\NAS Oct 12 23:10:31 volumio2 volumio[942]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Oct 12 23:10:31 volumio2 volumio[942]: info: Completed loading Core Plugins Oct 12 23:10:31 volumio2 volumio[942]: info: Preparing to generate the ALSA configuration file Oct 12 23:10:31 volumio2 volumio[942]: info: CoreCommandRouter::volumioGetState Oct 12 23:10:31 volumio2 volumio[942]: info: CorePlayQueue::getTrack 0 Oct 12 23:10:31 volumio2 volumio[942]: info: Asound.conf file unchanged, so no further update is needed Oct 12 23:10:31 volumio2 volumio[942]: info: Output device has changed, restarting MPD Oct 12 23:10:31 volumio2 volumio[942]: info: Output device has changed, restarting Shairport Sync Oct 12 23:10:31 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:31 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 23:10:31 volumio2 sudo[1118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 23:10:31 volumio2 sudo[1118]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:31 volumio2 sudo[1118]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:31 volumio2 sudo[1120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 23:10:31 volumio2 sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:31 volumio2 volumio[942]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 23:10:31 volumio2 volumio[942]: info: ___________ START PLUGINS ___________ Oct 12 23:10:31 volumio2 volumio[942]: info: ControllerMpd::onStart: Initializing MPD Oct 12 23:10:31 volumio2 volumio[942]: info: Creating MPD Configuration file Oct 12 23:10:31 volumio2 systemd[1]: Listening on mpd.socket. Oct 12 23:10:31 volumio2 systemd[1]: Starting Music Player Daemon... Oct 12 23:10:31 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 23:10:31 volumio2 volumio[942]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 23:10:31 volumio2 volumio[942]: info: [1728763831672] CoreMusicLibrary::Adding element Media Servers Oct 12 23:10:31 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 23:10:31 volumio2 sudo[1131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 23:10:31 volumio2 sudo[1128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 23:10:31 volumio2 sudo[1128]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:31 volumio2 sudo[1131]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:31 volumio2 sudo[1128]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:31 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:31 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 23:10:31 volumio2 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 12 23:10:31 volumio2 systemd[1]: mpd.service: Succeeded. Oct 12 23:10:31 volumio2 systemd[1]: Stopped Music Player Daemon. Oct 12 23:10:31 volumio2 systemd[1]: Starting Music Player Daemon... Oct 12 23:10:31 volumio2 volumio[942]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 23:10:31 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:31 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 23:10:31 volumio2 volumio[942]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 23:10:31 volumio2 volumio[942]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 23:10:31 volumio2 volumio[942]: info: [1728763831899] CoreMusicLibrary::Adding element Last_100 Oct 12 23:10:31 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 23:10:31 volumio2 volumio[942]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 23:10:31 volumio2 volumio[942]: info: [1728763831904] CoreMusicLibrary::Adding element Webradio Oct 12 23:10:31 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 23:10:31 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 23:10:31 volumio2 sudo[1137]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 12 23:10:31 volumio2 volumio[942]: info: Initializing BBC Radios Oct 12 23:10:31 volumio2 sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:31 volumio2 sudo[1137]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 12 23:10:31 volumio2 sudo[1137]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 23:10:32 volumio2 volumio[942]: info: GPIO-Buttons: Reading config and creating triggers... Oct 12 23:10:32 volumio2 volumio[942]: info: GPIO-Buttons: playPause on pin 5 Oct 12 23:10:32 volumio2 volumio[942]: info: GPIO-Buttons: previous on pin 7 Oct 12 23:10:32 volumio2 volumio[942]: info: GPIO-Buttons: next on pin 8 Oct 12 23:10:32 volumio2 volumio[942]: info: GPIO-Buttons: shutdown on pin 4 Oct 12 23:10:32 volumio2 volumio[942]: info: Volumio Calling Home Oct 12 23:10:32 volumio2 volumio[942]: info: GPIO-Buttons started Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 23:10:32 volumio2 volumio[942]: info: Received Get System Info Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 23:10:32 volumio2 volumio[942]: info: Discovery: Getting this device information Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::volumioGetState Oct 12 23:10:32 volumio2 volumio[942]: info: CorePlayQueue::getTrack 0 Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 23:10:32 volumio2 volumio[942]: info: MPD Permissions set Oct 12 23:10:32 volumio2 volumio[942]: info: MPD Permissions set Oct 12 23:10:32 volumio2 volumio[942]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 23:10:32 volumio2 volumio[942]: info: Received Get System Info Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 23:10:32 volumio2 volumio[942]: info: Discovery: Getting this device information Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::volumioGetState Oct 12 23:10:32 volumio2 volumio[942]: info: CorePlayQueue::getTrack 0 Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 23:10:32 volumio2 volumio[942]: info: Volumio called home Oct 12 23:10:32 volumio2 volumio[942]: info: CoreCommandRouter::volumioGetState Oct 12 23:10:32 volumio2 volumio[942]: info: CorePlayQueue::getTrack 0 Oct 12 23:10:32 volumio2 volumio[942]: info: Starting Shairport Sync Oct 12 23:10:32 volumio2 volumio[942]: info: Starting Shairport Sync Oct 12 23:10:32 volumio2 volumio[942]: info: Starting Shairport Sync Oct 12 23:10:32 volumio2 sudo[1191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 23:10:32 volumio2 sudo[1191]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:32 volumio2 sudo[1193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 23:10:32 volumio2 sudo[1193]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:32 volumio2 sudo[1197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 23:10:32 volumio2 sudo[1197]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:32 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 12 23:10:32 volumio2 systemd[1]: shairport-sync.service: Succeeded. Oct 12 23:10:32 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 12 23:10:32 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 12 23:10:32 volumio2 sudo[1193]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:32 volumio2 sudo[1191]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:32 volumio2 volumio[942]: info: Shairport-Sync Started Oct 12 23:10:32 volumio2 sudo[1197]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:32 volumio2 volumio[942]: Error adding Membership: Error: addMembership EINVAL Oct 12 23:10:32 volumio2 volumio[942]: info: Shairport-Sync Started Oct 12 23:10:32 volumio2 volumio[942]: info: Shairport-Sync Started Oct 12 23:10:33 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 12 23:10:34 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 12 23:10:34 volumio2 mpd[1143]: Oct 12 23:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 12 23:10:34 volumio2 systemd[1]: Started Music Player Daemon. Oct 12 23:10:34 volumio2 sudo[1131]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:34 volumio2 sudo[1120]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:34 volumio2 volumio[942]: info: Completed starting Core Plugins Oct 12 23:10:34 volumio2 volumio[942]: info: ------------------------------------------- Oct 12 23:10:34 volumio2 volumio[942]: info: ----- MyVolumio plugins startup ---- Oct 12 23:10:34 volumio2 volumio[942]: info: ------------------------------------------- Oct 12 23:10:34 volumio2 volumio[942]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 12 23:10:34 volumio2 volumio[942]: error: MPD error: The expression evaluated to a falsy value: Oct 12 23:10:34 volumio2 volumio[942]: assert.ok(self.idling) Oct 12 23:10:34 volumio2 volumio[942]: error: The expression evaluated to a falsy value: Oct 12 23:10:34 volumio2 volumio[942]: assert.ok(self.idling) Oct 12 23:10:34 volumio2 volumio[942]: info: MPD running with PID1143 Oct 12 23:10:34 volumio2 volumio[942]: ,establishing connection Oct 12 23:10:34 volumio2 volumio[942]: error: MPD error: The expression evaluated to a falsy value: Oct 12 23:10:34 volumio2 volumio[942]: assert.ok(self.idling) Oct 12 23:10:34 volumio2 volumio[942]: error: The expression evaluated to a falsy value: Oct 12 23:10:34 volumio2 volumio[942]: assert.ok(self.idling) Oct 12 23:10:34 volumio2 volumio[942]: error: updateQueue error: null Oct 12 23:10:34 volumio2 volumio[1103]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 12 23:10:36 volumio2 kernel: hwmon hwmon1: Voltage normalised Oct 12 23:10:36 volumio2 sudo[1225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.101/NAS /mnt/NAS/NAS Oct 12 23:10:36 volumio2 sudo[1225]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:37 volumio2 sudo[1113]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:37 volumio2 volumio[942]: info: Cannot mount NAS Music_Library at system boot, trial number 2 ,retrying in 5 seconds Oct 12 23:10:37 volumio2 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 12 23:10:37 volumio2 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 12 23:10:37 volumio2 kernel: CIFS: Attempting to mount \\192.168.0.101\NAS Oct 12 23:10:39 volumio2 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 23:10:39 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 12 23:10:39 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 12 23:10:39 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 12 23:10:39 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 12 23:10:39 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 23:10:39 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 23:10:39 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 23:10:39 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 23:10:39 volumio2 volumio[942]: info: CoreCommandRouter::volumioGetBrowseSources Oct 12 23:10:39 volumio2 volumio[942]: info: CoreCommandRouter::volumioGetBrowseSources Oct 12 23:10:39 volumio2 volumio[942]: info: CoreCommandRouter::volumioGetBrowseSources Oct 12 23:10:39 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:10:39 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 12 23:10:39 volumio2 volumio[942]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 12 23:10:39 volumio2 volumio[942]: info: Discovery: Disconnected from remote: 192.168.0.10 Oct 12 23:10:40 volumio2 volumio[942]: info: Discovery: Remote connection error: 192.168.0.10 Oct 12 23:10:40 volumio2 volumio[942]: info: Discovery: A device disappeared from network Oct 12 23:10:40 volumio2 volumio[942]: info: Discovery: Device volumio disappeared from network Oct 12 23:10:42 volumio2 sudo[1236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.0.101/NAS/Music /mnt/NAS/Music_Library Oct 12 23:10:42 volumio2 sudo[1236]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:42 volumio2 kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Oct 12 23:10:42 volumio2 kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 12 23:10:42 volumio2 volumio[942]: info: Adding plugin bluetooth to MyMusic Plugins Oct 12 23:10:42 volumio2 volumio[942]: info: Adding plugin multiroom to MyMusic Plugins Oct 12 23:10:42 volumio2 volumio[942]: info: Adding plugin metavolumio to MyMusic Plugins Oct 12 23:10:42 volumio2 volumio[942]: info: Adding plugin cd_controller to MyMusic Plugins Oct 12 23:10:42 volumio2 volumio[942]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 12 23:10:42 volumio2 volumio[942]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 12 23:10:42 volumio2 volumio[942]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 12 23:10:43 volumio2 volumio[942]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 12 23:10:43 volumio2 volumio[942]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 12 23:10:43 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 23:10:43 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 23:10:43 volumio2 volumio[942]: info: Starting MyVolumio Remote Streaming Endpoints Oct 12 23:10:43 volumio2 volumio[942]: info: MyVolumio login type: Token Oct 12 23:10:43 volumio2 volumio[942]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 12 23:10:43 volumio2 volumio[942]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 12 23:10:43 volumio2 sudo[1225]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:43 volumio2 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 12 23:10:43 volumio2 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 12 23:10:43 volumio2 kernel: CIFS: Attempting to mount \\192.168.0.101\NAS Oct 12 23:10:44 volumio2 volumio[942]: info: Starting Streaming Service Transparent Proxy Oct 12 23:10:44 volumio2 volumio[942]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 12 23:10:44 volumio2 volumio[942]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 12 23:10:44 volumio2 volumio[942]: info: Streaming services startup Oct 12 23:10:44 volumio2 volumio[942]: info: Starting Streaming Daemon Oct 12 23:10:44 volumio2 sudo[1247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 12 23:10:44 volumio2 sudo[1247]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:44 volumio2 sudo[1247]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:44 volumio2 volumio[942]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 12 23:10:44 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:10:44 volumio2 volumio[942]: error: Cannot start Volumio Streaming Daemon Oct 12 23:10:44 volumio2 volumio[942]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 12 23:10:44 volumio2 volumio[942]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 12 23:10:44 volumio2 volumio[942]: info: Cannot mount NAS NAS at system boot, trial number 2 ,retrying in 5 seconds Oct 12 23:10:44 volumio2 volumio[942]: info: Discovery: adding f4fa4681-5df9-4668-bcb5-d14fb5924e1b Oct 12 23:10:44 volumio2 volumio[942]: info: Discovery: Found device Volumio Oct 12 23:10:44 volumio2 volumio[942]: info: Discovery: Connecting to remote: 192.168.0.10 Oct 12 23:10:45 volumio2 volumio[942]: error: MyVolumio Custom Token format not valid, refreshing it Oct 12 23:10:45 volumio2 volumio[942]: STREAMING PROXY: Starting server on port 3245 Oct 12 23:10:45 volumio2 volumio[942]: Node JS runtime: 14 Oct 12 23:10:45 volumio2 volumio[942]: info: Discovery: Connected to remote: 192.168.0.10 Oct 12 23:10:46 volumio2 volumio[942]: info: MyVolumio login type: Token Oct 12 23:10:46 volumio2 volumio[942]: verbose: New Socket.io Connection to 192.168.0.20:3000 from 192.168.0.10 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 12 23:10:46 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Oct 12 23:10:46 volumio2 volumio[942]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 12 23:10:46 volumio2 volumio[942]: info: CoreCommandRouter::volumioGetState Oct 12 23:10:46 volumio2 volumio[942]: info: CorePlayQueue::getTrack 0 Oct 12 23:10:47 volumio2 volumio[942]: info: MyVolumio token set successfully Oct 12 23:10:47 volumio2 volumio[942]: info: MYVOLUMIO: Adding device Oct 12 23:10:47 volumio2 volumio[942]: info: MYVOLUMIO: Evaluating Server Oct 12 23:10:47 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:10:47 volumio2 volumio[942]: info: MyVolumio status changed Oct 12 23:10:47 volumio2 volumio[942]: info: Streaming services startup Oct 12 23:10:47 volumio2 volumio[942]: info: Starting Streaming Daemon Oct 12 23:10:47 volumio2 volumio[942]: info: Removing browser output: myVolumio user plan is not superstar Oct 12 23:10:47 volumio2 volumio[942]: info: Removing audio output: Oct 12 23:10:47 volumio2 volumio[942]: info: Stoppping Tunnel 1 Oct 12 23:10:47 volumio2 sudo[1273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 12 23:10:47 volumio2 sudo[1273]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:47 volumio2 sudo[1276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 12 23:10:47 volumio2 sudo[1276]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:47 volumio2 sudo[1273]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:47 volumio2 volumio[942]: error: Cannot start Volumio Streaming Daemon Oct 12 23:10:47 volumio2 volumio[942]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 12 23:10:47 volumio2 volumio[942]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 12 23:10:47 volumio2 sudo[1276]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:47 volumio2 volumio[942]: info: Remote SSH Stopped Oct 12 23:10:47 volumio2 volumio[942]: info: Setting Geolocation for MyVolumio to eu10 Oct 12 23:10:47 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 23:10:47 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 23:10:47 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 23:10:47 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 23:10:47 volumio2 volumio[942]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 12 23:10:47 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 12 23:10:47 volumio2 volumio[942]: info: Received Get System Version Oct 12 23:10:47 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 23:10:47 volumio2 volumio[942]: info: Received Get System Info Oct 12 23:10:47 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 23:10:47 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 23:10:47 volumio2 volumio[942]: info: Discovery: Getting this device information Oct 12 23:10:47 volumio2 volumio[942]: info: CoreCommandRouter::volumioGetState Oct 12 23:10:47 volumio2 volumio[942]: info: CorePlayQueue::getTrack 0 Oct 12 23:10:47 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 23:10:48 volumio2 volumio[942]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 12 23:10:48 volumio2 volumio[942]: info: Updating MyVolumio device info Oct 12 23:10:48 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 23:10:48 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 23:10:48 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 23:10:49 volumio2 volumio[942]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 12 23:10:49 volumio2 sudo[1279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.101/NAS /mnt/NAS/NAS Oct 12 23:10:49 volumio2 sudo[1279]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:50 volumio2 sudo[1236]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:50 volumio2 volumio[942]: info: Cannot mount NAS Music_Library at system boot, trial number 3 ,retrying in 5 seconds Oct 12 23:10:50 volumio2 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 12 23:10:50 volumio2 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 12 23:10:50 volumio2 kernel: CIFS: Attempting to mount \\192.168.0.101\NAS Oct 12 23:10:50 volumio2 volumio[1103]: .......................................................................................................................................................................................++++ Oct 12 23:10:51 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:10:51 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Oct 12 23:10:52 volumio2 volumio[942]: info: MYVOLUMIO: Adding device Oct 12 23:10:52 volumio2 volumio[942]: info: MYVOLUMIO: Evaluating Server Oct 12 23:10:52 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 12 23:10:53 volumio2 volumio[942]: info: Setting Geolocation for MyVolumio to eu4 Oct 12 23:10:53 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 23:10:53 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 23:10:53 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 23:10:54 volumio2 volumio[942]: info: Updating MyVolumio device info Oct 12 23:10:54 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 23:10:54 volumio2 volumio[942]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 12 23:10:54 volumio2 volumio[942]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 12 23:10:55 volumio2 sudo[1304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.0.101/NAS/Music /mnt/NAS/Music_Library Oct 12 23:10:55 volumio2 sudo[1304]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 23:10:55 volumio2 kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Oct 12 23:10:55 volumio2 kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Oct 12 23:10:55 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:10:55 volumio2 volumio[1103]: .....................................................++++ Oct 12 23:10:55 volumio2 volumio[1103]: e is 65537 (0x010001) Oct 12 23:10:55 volumio2 volumio[1103]: writing RSA key Oct 12 23:10:56 volumio2 sudo[1279]: pam_unix(sudo:session): session closed for user root Oct 12 23:10:56 volumio2 volumio[942]: info: Cannot mount NAS NAS at system boot, trial number 3 ,retrying in 5 seconds Oct 12 23:10:56 volumio2 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 12 23:10:56 volumio2 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 12 23:10:56 volumio2 kernel: CIFS: Attempting to mount \\192.168.0.101\NAS Oct 12 23:10:58 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 23:10:58 volumio2 volumio[942]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 12 23:10:58 volumio2 volumio[942]: info: CoreCommandRouter::volumioGetState Oct 12 23:10:58 volumio2 volumio[942]: info: CorePlayQueue::getTrack 0 Oct 12 23:10:59 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:11:00 volumio2 volumio[942]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Oct 12 23:11:01 volumio2 volumio[942]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 23:11:01 volumio2 volumio[942]: TypeError: Cannot read property 'replace' of undefined Oct 12 23:11:01 volumio2 volumio[942]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Oct 12 23:11:01 volumio2 volumio[942]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Oct 12 23:11:01 volumio2 volumio[942]: at listOnTimeout (internal/timers.js:554:17) Oct 12 23:11:01 volumio2 volumio[942]: at processTimers (internal/timers.js:497:7) Oct 12 23:11:01 volumio2 volumio[942]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 23:11:01 volumio2 sudo[1374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-12 23:10 Oct 12 23:11:01 volumio2 sudo[1374]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"