-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Mon 2025-01-20 20:07:37 CET. -- Jan 20 20:06:00 volumio nmbd[698]: [2025/01/20 20:06:00.285906, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 20 20:06:00 volumio systemd[1]: Started Samba NMB Daemon. Jan 20 20:06:00 volumio nmbd[698]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 20 20:06:00 volumio systemd[1]: Starting Samba Winbind Daemon... Jan 20 20:06:00 volumio wireless.js[570]: trying... Jan 20 20:06:00 volumio sudo[955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 20:06:00 volumio sudo[955]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:06:00 volumio sudo[955]: pam_unix(sudo:session): session closed for user root Jan 20 20:06:00 volumio winbindd[952]: [2025/01/20 20:06:00.928500, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 20 20:06:00 volumio winbindd[952]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 20 20:06:00 volumio winbindd[952]: [2025/01/20 20:06:00.978530, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 20 20:06:00 volumio systemd[1]: Started Samba Winbind Daemon. Jan 20 20:06:00 volumio winbindd[952]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 20 20:06:01 volumio systemd[1]: Starting Samba SMB Daemon... Jan 20 20:06:01 volumio wireless.js[570]: trying... Jan 20 20:06:01 volumio sudo[966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 20:06:01 volumio sudo[966]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:06:01 volumio sudo[966]: pam_unix(sudo:session): session closed for user root Jan 20 20:06:01 volumio smbd[960]: [2025/01/20 20:06:01.966346, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 20 20:06:01 volumio smbd[960]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 20 20:06:01 volumio systemd[1]: Started Samba SMB Daemon. Jan 20 20:06:02 volumio wpa_supplicant[885]: wlan0: Trying to associate with SSID 'SFR_7E4F' Jan 20 20:06:02 volumio dhcpcd[942]: wlan0: carrier acquired Jan 20 20:06:02 volumio dhcpcd[942]: wlan0: connected to Access Point `SFR_7E4F' Jan 20 20:06:02 volumio wpa_supplicant[885]: wlan0: Associated with cc:19:a8:0d:7e:50 Jan 20 20:06:02 volumio wpa_supplicant[885]: wlan0: CTRL-EVENT-CONNECTED - Connection to cc:19:a8:0d:7e:50 completed [id=0 id_str=] Jan 20 20:06:02 volumio wpa_supplicant[885]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 20 20:06:02 volumio dhcpcd[942]: wlan0: IAID eb:62:b1:3d Jan 20 20:06:02 volumio dhcpcd[942]: wlan0: adding address fe80::e6ae:be74:7748:646f Jan 20 20:06:02 volumio dhcpcd[942]: ipv6_addaddr1: Permission denied Jan 20 20:06:02 volumio dhcpcd[942]: wlan0: carrier lost Jan 20 20:06:02 volumio dhcpcd[942]: wlan0: carrier acquired Jan 20 20:06:02 volumio dhcpcd[942]: wlan0: IAID eb:62:b1:3d Jan 20 20:06:02 volumio dhcpcd[942]: wlan0: rebinding lease of 192.168.1.64 Jan 20 20:06:02 volumio dhcpcd[942]: wlan0: soliciting an IPv6 router Jan 20 20:06:02 volumio wireless.js[570]: trying... Jan 20 20:06:02 volumio sudo[992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 20:06:02 volumio sudo[992]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:06:02 volumio sudo[992]: pam_unix(sudo:session): session closed for user root Jan 20 20:06:03 volumio wireless.js[570]: trying... Jan 20 20:06:03 volumio sudo[995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 20:06:03 volumio sudo[995]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:06:03 volumio sudo[995]: pam_unix(sudo:session): session closed for user root Jan 20 20:06:04 volumio wireless.js[570]: trying... Jan 20 20:06:04 volumio sudo[998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 20:06:04 volumio sudo[998]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:06:04 volumio sudo[998]: pam_unix(sudo:session): session closed for user root Jan 20 20:06:05 volumio wireless.js[570]: trying... Jan 20 20:06:05 volumio sudo[1004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 20:06:05 volumio sudo[1004]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:06:05 volumio sudo[1004]: pam_unix(sudo:session): session closed for user root Jan 20 20:06:06 volumio wireless.js[570]: trying... Jan 20 20:06:06 volumio sudo[1007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 20:06:06 volumio sudo[1007]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:06:06 volumio sudo[1007]: pam_unix(sudo:session): session closed for user root Jan 20 20:06:07 volumio dhcpcd[942]: wlan0: probing for an IPv4LL address Jan 20 20:06:07 volumio dhcpcd[942]: wlan0: DHCP lease expired Jan 20 20:06:07 volumio dhcpcd[942]: wlan0: soliciting a DHCP lease Jan 20 20:06:07 volumio dhcpcd[942]: wlan0: offered 192.168.1.64 from 192.168.1.1 Jan 20 20:06:07 volumio dhcpcd[942]: wlan0: probing address 192.168.1.64/24 Jan 20 20:06:07 volumio wireless.js[570]: trying... Jan 20 20:06:07 volumio sudo[1026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 20:06:07 volumio sudo[1026]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:06:07 volumio sudo[1026]: pam_unix(sudo:session): session closed for user root Jan 20 20:06:08 volumio wireless.js[570]: trying... Jan 20 20:06:08 volumio sudo[1029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 20:06:08 volumio sudo[1029]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:06:08 volumio sudo[1029]: pam_unix(sudo:session): session closed for user root Jan 20 20:06:08 volumio volumio-remote-updater[582]: [2025-01-20 20:06:08] [info] asio async_connect error: system:111 (Connection refused) Jan 20 20:06:08 volumio volumio-remote-updater[582]: [2025-01-20 20:06:08] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 20 20:06:08 volumio volumio-remote-updater[582]: [2025-01-20 20:06:08] [error] handle_connect error: Underlying Transport Error Jan 20 20:06:09 volumio wireless.js[570]: trying... Jan 20 20:06:09 volumio sudo[1032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 20:06:09 volumio sudo[1032]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:06:09 volumio sudo[1032]: pam_unix(sudo:session): session closed for user root Jan 20 20:06:10 volumio wireless.js[570]: trying... Jan 20 20:06:10 volumio sudo[1035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 20:06:10 volumio sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:06:10 volumio sudo[1035]: pam_unix(sudo:session): session closed for user root Jan 20 20:06:11 volumio wireless.js[570]: trying... Jan 20 20:06:11 volumio sudo[1038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 20:06:11 volumio sudo[1038]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:06:11 volumio sudo[1038]: pam_unix(sudo:session): session closed for user root Jan 20 20:06:12 volumio dhcpcd[942]: wlan0: using IPv4LL address 169.254.19.242 Jan 20 20:06:12 volumio avahi-daemon[555]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.19.242. Jan 20 20:06:12 volumio dhcpcd[942]: wlan0: adding route to 169.254.0.0/16 Jan 20 20:06:12 volumio avahi-daemon[555]: New relevant interface wlan0.IPv4 for mDNS. Jan 20 20:06:12 volumio avahi-daemon[555]: Registering new address record for 169.254.19.242 on wlan0.IPv4. Jan 20 20:06:12 volumio dhcpcd[942]: wlan0: carrier lost Jan 20 20:06:12 volumio wpa_supplicant[885]: wlan0: Associated with cc:19:a8:0d:7e:51 Jan 20 20:06:12 volumio wpa_supplicant[885]: wlan0: CTRL-EVENT-CONNECTED - Connection to cc:19:a8:0d:7e:51 completed [id=0 id_str=] Jan 20 20:06:12 volumio wpa_supplicant[885]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 20 20:06:12 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jan 20 20:06:12 volumio avahi-daemon[555]: Withdrawing address record for 169.254.19.242 on wlan0. Jan 20 20:06:12 volumio avahi-daemon[555]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.19.242. Jan 20 20:06:12 volumio dhcpcd[942]: wlan0: deleting route to 169.254.0.0/16 Jan 20 20:06:12 volumio avahi-daemon[555]: Interface wlan0.IPv4 no longer relevant for mDNS. Jan 20 20:06:12 volumio dhcpcd[942]: wlan0: carrier acquired Jan 20 20:06:12 volumio dhcpcd[942]: wlan0: IAID eb:62:b1:3d Jan 20 20:06:12 volumio wireless.js[570]: trying... Jan 20 20:06:12 volumio sudo[1111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 20:06:12 volumio sudo[1111]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:06:12 volumio sudo[1111]: pam_unix(sudo:session): session closed for user root Jan 20 20:06:12 volumio dhcpcd[942]: wlan0: soliciting a DHCP lease Jan 20 20:06:13 volumio dhcpcd[942]: wlan0: soliciting an IPv6 router Jan 20 20:06:13 volumio wireless.js[570]: trying... Jan 20 20:06:13 volumio sudo[1114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 20:06:13 volumio sudo[1114]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:06:13 volumio sudo[1114]: pam_unix(sudo:session): session closed for user root Jan 20 20:06:14 volumio wireless.js[570]: trying... Jan 20 20:06:14 volumio sudo[1117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 20:06:14 volumio sudo[1117]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:06:14 volumio sudo[1117]: pam_unix(sudo:session): session closed for user root Jan 20 20:06:15 volumio wireless.js[570]: trying... Jan 20 20:06:15 volumio sudo[1123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 20:06:15 volumio sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:06:15 volumio sudo[1123]: pam_unix(sudo:session): session closed for user root Jan 20 20:06:16 volumio wireless.js[570]: Overtime, starting plan B Jan 20 20:06:17 volumio systemd[1]: Started Wireless Services. Jan 20 20:06:17 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 20 20:06:17 volumio systemd[1]: Started Volumio Backend Module. Jan 20 20:06:17 volumio systemd[1]: Started Volumio Cpu Tweaker. Jan 20 20:06:17 volumio systemd[1]: Reached target Multi-User System. Jan 20 20:06:17 volumio systemd[1]: Reached target Graphical Interface. Jan 20 20:06:17 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 20 20:06:17 volumio volumio-cpu-tweak[1129]: Setting RT Priority for mpd Jan 20 20:06:17 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 20 20:06:17 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 20 20:06:17 volumio systemd[1]: Startup finished in 16.568s (kernel) + 40.249s (userspace) = 56.818s. Jan 20 20:06:17 volumio volumio-cpu-tweak[1129]: Setting MPD Affinity Jan 20 20:06:17 volumio volumio-cpu-tweak[1129]: pid 770's current affinity mask: f Jan 20 20:06:17 volumio volumio-cpu-tweak[1129]: pid 770's new affinity mask: 3 Jan 20 20:06:17 volumio volumio-cpu-tweak[1129]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jan 20 20:06:17 volumio volumio-cpu-tweak[1129]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jan 20 20:06:17 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Jan 20 20:06:17 volumio dhcpcd[942]: wlan0: offered 192.168.1.64 from 192.168.1.1 Jan 20 20:06:17 volumio dhcpcd[942]: wlan0: probing address 192.168.1.64/24 Jan 20 20:06:18 volumio volumio-remote-updater[582]: [2025-01-20 20:06:18] [info] asio async_connect error: system:111 (Connection refused) Jan 20 20:06:18 volumio volumio-remote-updater[582]: [2025-01-20 20:06:18] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 20 20:06:18 volumio volumio-remote-updater[582]: [2025-01-20 20:06:18] [error] handle_connect error: Underlying Transport Error Jan 20 20:06:19 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jan 20 20:06:22 volumio volumio[1128]: info: ------------------------------------------- Jan 20 20:06:22 volumio volumio[1128]: info: ----- Volumio3 ---- Jan 20 20:06:22 volumio volumio[1128]: info: ------------------------------------------- Jan 20 20:06:22 volumio volumio[1128]: info: ----- System startup ---- Jan 20 20:06:22 volumio volumio[1128]: info: ------------------------------------------- Jan 20 20:06:23 volumio dhcpcd[942]: wlan0: leased 192.168.1.64 for 86400 seconds Jan 20 20:06:23 volumio avahi-daemon[555]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.64. Jan 20 20:06:23 volumio dhcpcd[942]: wlan0: adding route to 192.168.1.0/24 Jan 20 20:06:23 volumio avahi-daemon[555]: New relevant interface wlan0.IPv4 for mDNS. Jan 20 20:06:23 volumio dhcpcd[942]: wlan0: adding default route via 192.168.1.1 Jan 20 20:06:23 volumio avahi-daemon[555]: Registering new address record for 192.168.1.64 on wlan0.IPv4. Jan 20 20:06:24 volumio volumio[1128]: info: MYVOLUMIO Environment detected Jan 20 20:06:24 volumio volumio[1128]: info: Plugin folders cleanup Jan 20 20:06:24 volumio volumio[1128]: info: Scanning into folder /volumio/app/plugins/ Jan 20 20:06:24 volumio volumio[1128]: info: Scanning category audio_interface Jan 20 20:06:24 volumio volumio[1128]: info: Scanning category miscellanea Jan 20 20:06:24 volumio volumio[1128]: info: Scanning category music_service Jan 20 20:06:24 volumio volumio[1128]: info: Scanning category plugins.json Jan 20 20:06:24 volumio volumio[1128]: info: Scanning category system_controller Jan 20 20:06:24 volumio volumio[1128]: info: Scanning category user_interface Jan 20 20:06:24 volumio volumio[1128]: info: Scanning into folder /data/plugins/ Jan 20 20:06:24 volumio volumio[1128]: info: Plugin folders cleanup completed Jan 20 20:06:24 volumio ntpd[734]: Listen normally on 4 wlan0 192.168.1.64:123 Jan 20 20:06:24 volumio ntpd[734]: new interface(s) found: waking up resolver Jan 20 20:06:24 volumio volumio[1128]: info: ------------------------------------------- Jan 20 20:06:24 volumio volumio[1128]: info: ----- Core plugins startup ---- Jan 20 20:06:24 volumio volumio[1128]: info: ------------------------------------------- Jan 20 20:06:24 volumio volumio[1128]: info: Loading plugins from folder /volumio/app/plugins/ Jan 20 20:06:24 volumio volumio[1128]: info: Adding plugin upnp to MyMusic Plugins Jan 20 20:06:24 volumio volumio[1128]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 20 20:06:24 volumio volumio[1128]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 20 20:06:24 volumio volumio[1128]: info: Loading plugins from folder /data/plugins/ Jan 20 20:06:24 volumio volumio[1128]: info: Loading plugin "system"... Jan 20 20:06:25 volumio volumio[1128]: info: Loading plugin "appearance"... Jan 20 20:06:27 volumio systemd[1]: systemd-timedated.service: Succeeded. Jan 20 20:06:28 volumio volumio[1128]: info: Loading plugin "network"... Jan 20 20:06:28 volumio volumio[1128]: info: Refreshing Cached IP Addresses Jan 20 20:06:28 volumio sudo[1189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 20 20:06:28 volumio sudo[1189]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:06:28 volumio sudo[1189]: pam_unix(sudo:session): session closed for user root Jan 20 20:06:28 volumio sudo[1191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 20 20:06:28 volumio sudo[1191]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:06:28 volumio volumio[1128]: info: Loading plugin "services"... Jan 20 20:06:28 volumio sudo[1191]: pam_unix(sudo:session): session closed for user root Jan 20 20:06:28 volumio volumio[1128]: info: Loading plugin "alsa_controller"... Jan 20 20:06:28 volumio sudo[1200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 20 20:06:28 volumio sudo[1200]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:06:28 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 20 20:06:28 volumio volumio[1128]: info: Loading plugin "wizard"... Jan 20 20:06:28 volumio volumio[1128]: info: Loading plugin "networkfs"... Jan 20 20:06:28 volumio sudo[1215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Admin,password=7cEwFrof8E,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.84/Share /mnt/NAS/Share Jan 20 20:06:28 volumio sudo[1215]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:06:28 volumio volumio[1128]: info: Starting Udev Watcher for removable devices Jan 20 20:06:28 volumio sudo[1217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,7cEwFrof8E 192.168.1.84:Share /mnt/NAS/Share Jan 20 20:06:28 volumio sudo[1217]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:06:28 volumio volumio[1128]: info: Ignoring mount for partition: boot Jan 20 20:06:28 volumio volumio[1128]: info: Ignoring mount for partition: volumio Jan 20 20:06:28 volumio volumio[1128]: info: Ignoring mount for partition: volumio_data Jan 20 20:06:28 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 20 20:06:28 volumio kernel: nfs: Unknown parameter '7cEwFrof8E' Jan 20 20:06:28 volumio kernel: nfs: Unknown parameter '7cEwFrof8E' Jan 20 20:06:28 volumio kernel: nfs: Unknown parameter '7cEwFrof8E' Jan 20 20:06:28 volumio volumio[1128]: info: Loading plugin "volumio_command_line_client"... Jan 20 20:06:28 volumio volumio[1128]: info: Loading plugin "upnp"... Jan 20 20:06:28 volumio volumio[1128]: info: [1737399988688] Starting Upmpd Daemon Jan 20 20:06:28 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 20 20:06:28 volumio volumio[1128]: info: Loading plugin "my_music"... Jan 20 20:06:28 volumio volumio[1128]: info: Loading plugin "mpd"... Jan 20 20:06:28 volumio systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Jan 20 20:06:28 volumio volumio-remote-updater[582]: [2025-01-20 20:06:28] [connect] Successful connection Jan 20 20:06:29 volumio systemd[1]: Starting Preprocess NFS configuration... Jan 20 20:06:29 volumio systemd[1]: Reached target Host and Network Name Lookups. Jan 20 20:06:29 volumio kernel: Key type cifs.spnego registered Jan 20 20:06:29 volumio kernel: Key type cifs.idmap registered Jan 20 20:06:29 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jan 20 20:06:29 volumio kernel: CIFS: Attempting to mount //192.168.1.84/Share Jan 20 20:06:29 volumio systemd[1]: nfs-config.service: Succeeded. Jan 20 20:06:29 volumio systemd[1]: Started Preprocess NFS configuration. Jan 20 20:06:29 volumio systemd[1]: Starting Notify NFS peers of a restart... Jan 20 20:06:29 volumio systemd[1]: Starting NFS status monitor for NFSv2/3 locking.... Jan 20 20:06:29 volumio sm-notify[1241]: Version 1.3.3 starting Jan 20 20:06:29 volumio systemd[1]: rpc-statd-notify.service: Succeeded. Jan 20 20:06:29 volumio systemd[1]: Started Notify NFS peers of a restart. Jan 20 20:06:29 volumio kernel: cryptd: max_cpu_qlen set to 1000 Jan 20 20:06:29 volumio rpc.statd[1255]: Version 1.3.3 starting Jan 20 20:06:29 volumio rpc.statd[1255]: Flags: TI-RPC Jan 20 20:06:29 volumio systemd[1]: Started NFS status monitor for NFSv2/3 locking.. Jan 20 20:06:29 volumio sudo[1215]: pam_unix(sudo:session): session closed for user root Jan 20 20:06:29 volumio volumio[1128]: info: Loading plugin "upnp_browser"... Jan 20 20:06:30 volumio kernel: nfs: Unknown parameter '7cEwFrof8E' Jan 20 20:06:30 volumio volumio[1128]: info: Loading plugin "alarm-clock"... Jan 20 20:06:31 volumio volumio[1128]: info: Loading plugin "airplay_emulation"... Jan 20 20:06:31 volumio volumio[1128]: info: Starting Shairport Sync Jan 20 20:06:31 volumio volumio[1128]: info: Loading plugin "last_100"... Jan 20 20:06:31 volumio volumio[1128]: info: Loading plugin "webradio"... Jan 20 20:06:31 volumio sudo[1200]: pam_unix(sudo:session): session closed for user root Jan 20 20:06:31 volumio volumio[1128]: info: Loading plugin "i2s_dacs"... Jan 20 20:06:31 volumio volumio[1128]: info: I2S DAC not set, start Auto-detection Jan 20 20:06:31 volumio volumio[1128]: info: Loading plugin "volumiodiscovery"... Jan 20 20:06:31 volumio volumio[1128]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 20 20:06:31 volumio volumio[1128]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 20 20:06:31 volumio volumio[1128]: *** WARNING *** For more information see Jan 20 20:06:31 volumio node[1128]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 20 20:06:31 volumio volumio[1128]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 20 20:06:31 volumio volumio[1128]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 20 20:06:31 volumio volumio[1128]: *** WARNING *** For more information see Jan 20 20:06:31 volumio node[1128]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 20 20:06:31 volumio node[1128]: *** WARNING *** For more information see Jan 20 20:06:31 volumio node[1128]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 20 20:06:31 volumio node[1128]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 20 20:06:31 volumio node[1128]: *** WARNING *** For more information see Jan 20 20:06:31 volumio volumio[1128]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 20 20:06:31 volumio volumio[1128]: info: Discovery: Started advertising with name: Volumio Jan 20 20:06:31 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 20 20:06:31 volumio volumio[1128]: info: Loading plugin "outputs"... Jan 20 20:06:31 volumio volumio[1128]: info: Loading plugin "albumart"... Jan 20 20:06:31 volumio volumio[1128]: info: Plugin example_plugin is not enabled Jan 20 20:06:31 volumio volumio[1128]: info: Loading plugin "inputs"... Jan 20 20:06:31 volumio volumio[1128]: info: Loading plugin "updater_comm"... Jan 20 20:06:32 volumio volumio[1128]: info: Plugin mpdemulation is not enabled Jan 20 20:06:32 volumio volumio[1128]: info: Loading plugin "rest_api"... Jan 20 20:06:32 volumio volumio[1128]: info: Loading plugin "websocket"... Jan 20 20:06:32 volumio volumio[1128]: info: Starting Socket.io Server version 2.3.0 Jan 20 20:06:32 volumio volumio[1128]: info: Loading i18n strings for locale fr Jan 20 20:06:32 volumio kernel: nfs: Unknown parameter '7cEwFrof8E' Jan 20 20:06:32 volumio volumio[1128]: Updating browse sources language Jan 20 20:06:32 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 20:06:32 volumio volumio[1128]: Forking 3 albumart workers Jan 20 20:06:33 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 20:06:33 volumio volumio[1128]: info: CoreCommandRouter::initPlayerControls Jan 20 20:06:33 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:06:33 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:06:33 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:06:33 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:06:33 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:06:33 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:06:33 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 20:06:33 volumio volumio[1128]: Express server listening on port 3000 Jan 20 20:06:33 volumio volumio[1128]: [Metrics] WebUI: 12s 653.58ms Jan 20 20:06:33 volumio volumio[1128]: info: CoreStateMachine::resetVolumioState Jan 20 20:06:33 volumio volumio[1128]: info: CoreStateMachine::getcurrentVolume Jan 20 20:06:33 volumio volumio[1128]: info: CoreCommandRouter::volumioRetrievevolume Jan 20 20:06:33 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 20:06:33 volumio volumio[1128]: info: Volumio Network Manager: Network status updated: 3 Jan 20 20:06:33 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jan 20 20:06:33 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jan 20 20:06:33 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jan 20 20:06:33 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jan 20 20:06:33 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jan 20 20:06:33 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jan 20 20:06:33 volumio volumio-remote-updater[582]: [2025-01-20 20:06:33] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1737399988 101 Jan 20 20:06:33 volumio volumio[1128]: 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: 7 Jan 20 20:06:33 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:06:33 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:06:33 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 20:06:33 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:06:33 volumio volumio[1128]: info: CoreStateMachine::updateTrackBlock Jan 20 20:06:33 volumio volumio[1128]: info: CorePlayQueue::getTrackBlock Jan 20 20:06:33 volumio volumio[1128]: info: CoreCommandRouter::volumioRetrievevolume Jan 20 20:06:34 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jan 20 20:06:34 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jan 20 20:06:34 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jan 20 20:06:34 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jan 20 20:06:34 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jan 20 20:06:34 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Jan 20 20:06:34 volumio volumio[1128]: info: Reloading queue from file Jan 20 20:06:34 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Jan 20 20:06:34 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Jan 20 20:06:34 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Jan 20 20:06:34 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 Jan 20 20:06:34 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 Jan 20 20:06:34 volumio volumio[1128]: info: Setting Device type: Raspberry PI Jan 20 20:06:34 volumio volumio[1128]: info: CoreStateMachine::setRepeat null single undefined Jan 20 20:06:34 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:06:34 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:06:34 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 20:06:34 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:06:34 volumio volumio[1128]: info: CoreStateMachine::setRandom null Jan 20 20:06:34 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:06:34 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:06:34 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:06:34 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19 Jan 20 20:06:34 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 20 Jan 20 20:06:34 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 21 Jan 20 20:06:34 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 22 Jan 20 20:06:34 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 23 Jan 20 20:06:34 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:06:34 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:06:34 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:06:34 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 24 Jan 20 20:06:34 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 25 Jan 20 20:06:34 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Jan 20 20:06:34 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:06:34 volumio volumio[1128]: Starting albumart workers Jan 20 20:06:34 volumio volumio[1128]: Starting albumart workers Jan 20 20:06:34 volumio volumio[1128]: info: Received Get System Info Jan 20 20:06:34 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 20:06:34 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 20:06:34 volumio volumio[1128]: info: Discovery: Getting this device information Jan 20 20:06:34 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Jan 20 20:06:34 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:06:34 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 20:06:34 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Jan 20 20:06:34 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:06:34 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 20 20:06:34 volumio volumio[1128]: Starting albumart workers Jan 20 20:06:34 volumio volumio[1128]: info: CoreCommandRouter::volumioGetVisibleSources Jan 20 20:06:34 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 20:06:34 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Jan 20 20:06:34 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:06:34 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Jan 20 20:06:34 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:06:34 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 20 20:06:34 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 20 20:06:34 volumio volumio[1128]: info: Received Get System Info Jan 20 20:06:34 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 20:06:34 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 20:06:34 volumio volumio[1128]: info: Discovery: Getting this device information Jan 20 20:06:34 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Jan 20 20:06:34 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:06:34 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 20:06:34 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Jan 20 20:06:34 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:06:34 volumio volumio[1128]: info: Listing playlists Jan 20 20:06:34 volumio volumio[1128]: info: Discovery: adding 5401553c-07ec-4f8c-9237-68e94a4825ad Jan 20 20:06:34 volumio volumio[1128]: info: Discovery: Found device Volumio Jan 20 20:06:34 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Jan 20 20:06:34 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:06:34 volumio volumio[1128]: info: Discovery: this is already registered, 5401553c-07ec-4f8c-9237-68e94a4825ad Jan 20 20:06:34 volumio volumio[1128]: info: Discovery: Found device Volumio Jan 20 20:06:34 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Jan 20 20:06:34 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:06:35 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 20:06:35 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 20:06:35 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 20:06:36 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 20:06:36 volumio kernel: nfs: Unknown parameter '7cEwFrof8E' Jan 20 20:06:36 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 20:06:37 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 20:06:37 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 20:06:38 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 20:06:38 volumio sudo[1358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 20 20:06:38 volumio sudo[1358]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:06:38 volumio sudo[1358]: pam_unix(sudo:session): session closed for user root Jan 20 20:06:38 volumio sudo[1360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 20 20:06:38 volumio sudo[1360]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:06:38 volumio sudo[1360]: pam_unix(sudo:session): session closed for user root Jan 20 20:06:38 volumio sudo[1364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 20 20:06:38 volumio sudo[1364]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:06:38 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 20:06:38 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jan 20 20:06:38 volumio sudo[1364]: pam_unix(sudo:session): session closed for user root Jan 20 20:06:38 volumio volumio[1128]: info: Upmpdcli Daemon Started Jan 20 20:06:39 volumio volumio[1366]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 20 20:06:39 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 20:06:39 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 20:06:40 volumio volumio[1366]: ..++++ Jan 20 20:06:40 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 20:06:40 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 20:06:41 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 20:06:41 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 20:06:42 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 20:06:42 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 20:06:43 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 20:06:43 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 20:06:44 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 20:06:44 volumio kernel: nfs: Unknown parameter '7cEwFrof8E' Jan 20 20:06:47 volumio volumio-remote-updater[582]: No test mode Jan 20 20:06:47 volumio volumio-remote-updater[582]: No alpha test mode Jan 20 20:06:48 volumio volumio[1128]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 20 20:06:48 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 20 20:06:49 volumio volumio[1366]: ..................................++++ Jan 20 20:06:49 volumio volumio[1366]: e is 65537 (0x010001) Jan 20 20:06:49 volumio volumio[1128]: info: CoreCommandRouter::Close All Modals sent Jan 20 20:06:49 volumio volumio[1366]: writing RSA key Jan 20 20:06:53 volumio ntpd[734]: Soliciting pool server 194.177.34.116 Jan 20 20:06:54 volumio kernel: nfs: Unknown parameter '7cEwFrof8E' Jan 20 20:06:54 volumio ntpd[734]: Soliciting pool server 129.250.35.251 Jan 20 20:06:54 volumio ntpd[734]: Soliciting pool server 54.36.61.42 Jan 20 20:06:55 volumio ntpd[734]: Soliciting pool server 82.65.235.151 Jan 20 20:06:55 volumio ntpd[734]: Soliciting pool server 91.224.149.41 Jan 20 20:06:55 volumio ntpd[734]: Soliciting pool server 212.83.158.83 Jan 20 20:06:56 volumio ntpd[734]: Soliciting pool server 79.143.250.33 Jan 20 20:06:56 volumio ntpd[734]: Soliciting pool server 178.32.23.17 Jan 20 20:06:56 volumio ntpd[734]: Soliciting pool server 82.64.230.205 Jan 20 20:06:56 volumio ntpd[734]: Soliciting pool server 195.154.226.102 Jan 20 20:06:57 volumio ntpd[734]: Soliciting pool server 129.250.35.250 Jan 20 20:06:57 volumio ntpd[734]: Soliciting pool server 51.15.182.163 Jan 20 20:06:57 volumio ntpd[734]: Soliciting pool server 5.39.80.51 Jan 20 20:06:58 volumio ntpd[734]: Soliciting pool server 45.13.105.44 Jan 20 20:06:58 volumio ntpd[734]: Soliciting pool server 2a03:7220:8081:2900::1 Jan 20 20:06:58 volumio ntpd[734]: Soliciting pool server 37.59.63.125 Jan 20 20:06:59 volumio ntpd[734]: Soliciting pool server 5.196.160.139 Jan 20 20:07:02 volumio ntpd[734]: receive: Unexpected origin timestamp 0xeb391d55.f64c5d1b does not match aorg 0000000000.00000000 from server@82.64.230.205 xmt 0xeb391d56.db4ffadd Jan 20 20:07:02 volumio ntpd[734]: receive: Unexpected origin timestamp 0xeb391d55.f63b8c08 does not match aorg 0000000000.00000000 from server@37.59.63.125 xmt 0xeb391d56.dde072f4 Jan 20 20:07:02 volumio ntpd[734]: receive: Unexpected origin timestamp 0xeb391d55.f64ffa9a does not match aorg 0000000000.00000000 from server@54.36.61.42 xmt 0xeb391d56.dd867722 Jan 20 20:07:02 volumio ntpd[734]: receive: Unexpected origin timestamp 0xeb391d55.f647fca2 does not match aorg 0000000000.00000000 from server@178.32.23.17 xmt 0xeb391d56.ddec4257 Jan 20 20:07:02 volumio volumio[1128]: verbose: New Socket.io Connection to 192.168.1.41 from 192.168.1.212 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 26 Jan 20 20:07:02 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Jan 20 20:07:02 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:03 volumio volumio[1128]: info: Received Get System Info Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 20:07:03 volumio volumio[1128]: info: Discovery: Getting this device information Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Jan 20 20:07:03 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Jan 20 20:07:03 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::volumioGetVisibleSources Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Jan 20 20:07:03 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Jan 20 20:07:03 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 20 20:07:03 volumio volumio[1128]: info: Received Get System Info Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 20:07:03 volumio volumio[1128]: info: Discovery: Getting this device information Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Jan 20 20:07:03 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Jan 20 20:07:03 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:03 volumio volumio[1128]: info: Listing playlists Jan 20 20:07:03 volumio volumio[1128]: error: Plugin system_controller networkfs failed to complete 'onVolumioStart' in a timely fashion Jan 20 20:07:03 volumio volumio[1128]: info: Preparing to generate the ALSA configuration file Jan 20 20:07:03 volumio volumio[1128]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jan 20 20:07:03 volumio volumio[1128]: info: Reading ALSA contributions from plugins. Jan 20 20:07:03 volumio volumio[1128]: info: Asound.conf file unchanged, so no further update is needed Jan 20 20:07:03 volumio volumio[1128]: info: Output device has changed, restarting MPD Jan 20 20:07:03 volumio volumio[1128]: info: Output device has changed, restarting Shairport Sync Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 20:07:03 volumio sudo[1472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 20 20:07:03 volumio sudo[1472]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:07:03 volumio sudo[1472]: pam_unix(sudo:session): session closed for user root Jan 20 20:07:03 volumio sudo[1474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 20 20:07:03 volumio sudo[1474]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:07:03 volumio systemd[1]: Stopping Music Player Daemon... Jan 20 20:07:03 volumio volumio[1128]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 20 20:07:03 volumio volumio[1128]: info: ___________ START PLUGINS ___________ Jan 20 20:07:03 volumio volumio[1128]: info: ControllerMpd::onStart: Initializing MPD Jan 20 20:07:03 volumio volumio[1128]: info: Creating MPD Configuration file Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 20:07:03 volumio volumio[1128]: info: [1737400023759] CoreMusicLibrary::Adding element Serveurs Média Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 20:07:03 volumio sudo[1481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 20 20:07:03 volumio sudo[1481]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:07:03 volumio sudo[1481]: pam_unix(sudo:session): session closed for user root Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 20:07:03 volumio sudo[1483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 20 20:07:03 volumio sudo[1483]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:07:03 volumio volumio[1128]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:07:03 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 20:07:03 volumio systemd[1]: mpd.service: Succeeded. Jan 20 20:07:03 volumio systemd[1]: Stopped Music Player Daemon. Jan 20 20:07:04 volumio systemd[1]: Starting Music Player Daemon... Jan 20 20:07:04 volumio volumio[1128]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 20:07:04 volumio volumio[1128]: info: [1737400024038] CoreMusicLibrary::Adding element Last_100 Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 20:07:04 volumio volumio[1128]: info: [1737400024046] CoreMusicLibrary::Adding element Webradio Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 20 20:07:04 volumio volumio[1128]: info: Initializing BBC Radios Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 20:07:04 volumio volumio[1128]: info: Volumio Calling Home Jan 20 20:07:04 volumio sudo[1492]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 20 20:07:04 volumio sudo[1492]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:07:04 volumio sudo[1492]: pam_unix(sudo:session): session closed for user root Jan 20 20:07:04 volumio volumio[1128]: info: MPD Permissions set Jan 20 20:07:04 volumio volumio[1128]: info: MPD Permissions set Jan 20 20:07:04 volumio volumio[1128]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 20:07:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 20:07:04 volumio volumio[1128]: info: Volumio called home Jan 20 20:07:05 volumio volumio[1128]: info: Starting Shairport Sync Jan 20 20:07:05 volumio volumio[1128]: info: Starting Shairport Sync Jan 20 20:07:05 volumio volumio[1128]: info: Starting Shairport Sync Jan 20 20:07:05 volumio sudo[1517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 20 20:07:05 volumio sudo[1517]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:07:05 volumio sudo[1519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 20 20:07:05 volumio sudo[1519]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:07:05 volumio sudo[1522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 20 20:07:05 volumio sudo[1522]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:07:05 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Jan 20 20:07:05 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:05 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 20 20:07:05 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 20 20:07:05 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 20 20:07:05 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 20 20:07:05 volumio sudo[1517]: pam_unix(sudo:session): session closed for user root Jan 20 20:07:05 volumio sudo[1519]: pam_unix(sudo:session): session closed for user root Jan 20 20:07:05 volumio sudo[1522]: pam_unix(sudo:session): session closed for user root Jan 20 20:07:05 volumio volumio[1128]: info: Shairport-Sync Started Jan 20 20:07:05 volumio volumio[1128]: Error adding Membership: Error: addMembership EINVAL Jan 20 20:07:05 volumio volumio[1128]: info: Shairport-Sync Started Jan 20 20:07:05 volumio volumio[1128]: info: Shairport-Sync Started Jan 20 20:07:05 volumio kernel: nfs: Unknown parameter '7cEwFrof8E' Jan 20 20:07:07 volumio mpd[1507]: Jan 20 20:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 20 20:07:07 volumio systemd[1]: Started Music Player Daemon. Jan 20 20:07:07 volumio sudo[1474]: pam_unix(sudo:session): session closed for user root Jan 20 20:07:07 volumio sudo[1483]: pam_unix(sudo:session): session closed for user root Jan 20 20:07:07 volumio volumio[1128]: info: Completed starting Core Plugins Jan 20 20:07:07 volumio volumio[1128]: info: ------------------------------------------- Jan 20 20:07:07 volumio volumio[1128]: info: ----- MyVolumio plugins startup ---- Jan 20 20:07:07 volumio volumio[1128]: info: ------------------------------------------- Jan 20 20:07:07 volumio volumio[1128]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 20 20:07:07 volumio volumio[1128]: error: MPD error: The expression evaluated to a falsy value: Jan 20 20:07:07 volumio volumio[1128]: assert.ok(self.idling) Jan 20 20:07:07 volumio volumio[1128]: error: The expression evaluated to a falsy value: Jan 20 20:07:07 volumio volumio[1128]: assert.ok(self.idling) Jan 20 20:07:07 volumio volumio[1128]: info: MPD running with PID1507 Jan 20 20:07:07 volumio volumio[1128]: ,establishing connection Jan 20 20:07:07 volumio volumio[1128]: error: updateQueue error: null Jan 20 20:07:07 volumio volumio[1128]: error: updateQueue error: null Jan 20 20:07:12 volumio volumio[1128]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 20 20:07:13 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 20:07:15 volumio kernel: nfs: Unknown parameter '7cEwFrof8E' Jan 20 20:07:15 volumio sudo[1217]: pam_unix(sudo:session): session closed for user root Jan 20 20:07:15 volumio volumio[1128]: info: Cannot mount NAS Share at system boot, trial number 1 ,retrying in 5 seconds Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 20 20:07:16 volumio volumio[1128]: info: Adding plugin bluetooth to MyMusic Plugins Jan 20 20:07:16 volumio volumio[1128]: info: Adding plugin multiroom to MyMusic Plugins Jan 20 20:07:16 volumio volumio[1128]: info: Adding plugin metavolumio to MyMusic Plugins Jan 20 20:07:16 volumio volumio[1128]: info: Adding plugin cd_controller to MyMusic Plugins Jan 20 20:07:16 volumio volumio[1128]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 20 20:07:16 volumio volumio[1128]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 20 20:07:16 volumio volumio[1128]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 20 20:07:18 volumio volumio[1128]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 20 20:07:18 volumio volumio[1128]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 20 20:07:18 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 20:07:18 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 20:07:18 volumio volumio[1128]: info: Starting MyVolumio Remote Streaming Endpoints Jan 20 20:07:18 volumio volumio[1128]: info: MyVolumio login type: Token Jan 20 20:07:18 volumio volumio[1128]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 20 20:07:18 volumio volumio[1128]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 20 20:07:21 volumio volumio[1128]: info: Starting Streaming Service Transparent Proxy Jan 20 20:07:21 volumio volumio[1128]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 20 20:07:21 volumio volumio[1128]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 20 20:07:21 volumio volumio[1128]: info: Streaming services startup Jan 20 20:07:21 volumio volumio[1128]: info: Starting Streaming Daemon Jan 20 20:07:21 volumio sudo[1574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 20 20:07:21 volumio sudo[1574]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:07:21 volumio sudo[1574]: pam_unix(sudo:session): session closed for user root Jan 20 20:07:21 volumio volumio[1128]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 20 20:07:21 volumio volumio[1128]: info: Cannot mount NAS Share at system boot, trial number 2 ,retrying in 5 seconds Jan 20 20:07:21 volumio volumio[1128]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 20 20:07:21 volumio volumio[1128]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 20 20:07:21 volumio volumio[1128]: info: Retrieving Cloud Streaming UI Jan 20 20:07:21 volumio volumio[1128]: error: Cannot start Volumio Streaming Daemon Jan 20 20:07:21 volumio volumio[1128]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 20 20:07:21 volumio volumio[1128]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 20 20:07:21 volumio volumio[1128]: info: Getting Tidal Cloud Configuration Jan 20 20:07:21 volumio volumio[1128]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 20 20:07:21 volumio volumio[1128]: info: Getting Qobuz Cloud Configuration Jan 20 20:07:21 volumio volumio[1128]: info: Asking plugin for UI Config Jan 20 20:07:21 volumio volumio[1128]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 20 20:07:21 volumio volumio[1128]: info: Getting Spotify Cloud Configuration Jan 20 20:07:21 volumio volumio[1128]: info: Asking plugin for UI Config Jan 20 20:07:21 volumio volumio[1128]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 20 20:07:21 volumio volumio[1128]: info: Saving Spotify Acccount Jan 20 20:07:21 volumio volumio[1128]: info: Got Tidal Cloud Configuration Jan 20 20:07:21 volumio volumio[1128]: info: Got it Jan 20 20:07:21 volumio volumio[1128]: info: Got it Jan 20 20:07:21 volumio volumio[1128]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 20 20:07:22 volumio volumio[1128]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 20 20:07:22 volumio volumio[1128]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 20 20:07:22 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 20 20:07:22 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 20 20:07:22 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 20 20:07:22 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 20 20:07:22 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 20:07:22 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 20:07:22 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 20:07:22 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 20:07:22 volumio volumio[1128]: info: CoreCommandRouter::volumioGetBrowseSources Jan 20 20:07:22 volumio volumio[1128]: info: CoreCommandRouter::volumioGetBrowseSources Jan 20 20:07:22 volumio volumio[1128]: info: CoreCommandRouter::volumioGetBrowseSources Jan 20 20:07:22 volumio volumio[1128]: error: MyVolumio Custom Token format not valid, refreshing it Jan 20 20:07:22 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 20 20:07:22 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 20 20:07:22 volumio volumio[1128]: info: MyVolumio login type: Token Jan 20 20:07:23 volumio volumio[1128]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 20 20:07:23 volumio volumio[1128]: info: MyVolumio token set successfully Jan 20 20:07:23 volumio volumio[1128]: info: MYVOLUMIO: Adding device Jan 20 20:07:23 volumio volumio[1128]: info: MYVOLUMIO: Evaluating Server Jan 20 20:07:23 volumio volumio[1128]: STREAMING PROXY: Starting server on port 3245 Jan 20 20:07:23 volumio volumio[1128]: Node JS runtime: 14 Jan 20 20:07:24 volumio volumio[1128]: info: MyVolumio status changed Jan 20 20:07:24 volumio volumio[1128]: info: Streaming services startup Jan 20 20:07:24 volumio volumio[1128]: info: Starting Streaming Daemon Jan 20 20:07:24 volumio volumio[1128]: info: Removing browser output: myVolumio user plan is not superstar Jan 20 20:07:24 volumio volumio[1128]: info: Removing audio output: Jan 20 20:07:24 volumio volumio[1128]: info: Stoppping Tunnel 1 Jan 20 20:07:24 volumio sudo[1605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 20 20:07:24 volumio sudo[1605]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:07:24 volumio sudo[1607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 20 20:07:24 volumio sudo[1607]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:07:24 volumio sudo[1605]: pam_unix(sudo:session): session closed for user root Jan 20 20:07:24 volumio volumio[1128]: error: Cannot start Volumio Streaming Daemon Jan 20 20:07:24 volumio volumio[1128]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 20 20:07:24 volumio volumio[1128]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 20 20:07:24 volumio sudo[1607]: pam_unix(sudo:session): session closed for user root Jan 20 20:07:24 volumio volumio[1128]: info: Remote SSH Stopped Jan 20 20:07:24 volumio volumio[1128]: info: Setting Geolocation for MyVolumio to eu5 Jan 20 20:07:24 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 20:07:25 volumio volumio[1128]: info: Successfully Added MyVolumio device Jan 20 20:07:25 volumio volumio[1128]: info: Updating MyVolumio device info Jan 20 20:07:25 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 20:07:26 volumio volumio[1128]: info: Successfully Updated MyVolumio device Jan 20 20:07:26 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 20 20:07:26 volumio volumio[1128]: info: Cannot mount NAS Share at system boot, trial number 3 ,retrying in 5 seconds Jan 20 20:07:30 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 20 20:07:31 volumio volumio[1128]: info: Cannot mount NAS Share at system boot, trial number 4 ,retrying in 5 seconds Jan 20 20:07:32 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jan 20 20:07:32 volumio sudo[1628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/Share Jan 20 20:07:32 volumio sudo[1628]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 20:07:32 volumio systemd[1]: mnt-NAS-Share.mount: Succeeded. Jan 20 20:07:32 volumio sudo[1628]: pam_unix(sudo:session): session closed for user root Jan 20 20:07:32 volumio volumio[1128]: info: Share Share successfully unmounted Jan 20 20:07:33 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 20 20:07:33 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 20 20:07:33 volumio volumio[1128]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 20 20:07:33 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Jan 20 20:07:33 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:34 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 20 20:07:34 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 20:07:34 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 20 20:07:34 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 20 20:07:34 volumio volumio-remote-updater[582]: No test mode Jan 20 20:07:34 volumio volumio-remote-updater[582]: No alpha test mode Jan 20 20:07:34 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 20 20:07:34 volumio volumio[1128]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 20 20:07:34 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 20 20:07:35 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand status Jan 20 20:07:35 volumio volumio[1128]: info: Jan 20 20:07:35 volumio volumio[1128]: ---------------------------- MPD announces state update: update Jan 20 20:07:35 volumio volumio[1128]: info: ControllerMpd::getState Jan 20 20:07:35 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand status Jan 20 20:07:35 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand status Jan 20 20:07:35 volumio volumio[1128]: info: Jan 20 20:07:35 volumio volumio[1128]: ---------------------------- MPD announces state update: update Jan 20 20:07:35 volumio volumio[1128]: info: ControllerMpd::getState Jan 20 20:07:35 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand status Jan 20 20:07:35 volumio volumio[1128]: info: sendMpdCommand status took 19 milliseconds Jan 20 20:07:35 volumio volumio[1128]: info: sendMpdCommand status took 14 milliseconds Jan 20 20:07:35 volumio volumio[1128]: info: sendMpdCommand status took 13 milliseconds Jan 20 20:07:35 volumio volumio[1128]: info: sendMpdCommand status took 10 milliseconds Jan 20 20:07:35 volumio volumio[1128]: verbose: ControllerMpd::parseState Jan 20 20:07:35 volumio volumio[1128]: info: Command Router : Notfying DB Updatetrue Jan 20 20:07:35 volumio volumio[1128]: verbose: ControllerMpd::parseState Jan 20 20:07:35 volumio volumio[1128]: verbose: ControllerMpd::parseState Jan 20 20:07:35 volumio volumio[1128]: info: Command Router : Notfying DB Updatetrue Jan 20 20:07:35 volumio volumio[1128]: info: CoreCommandRouter::Close All Modals sent Jan 20 20:07:35 volumio volumio[1128]: verbose: ControllerMpd::parseState Jan 20 20:07:35 volumio volumio[1128]: info: ControllerMpd::pushState Jan 20 20:07:35 volumio volumio[1128]: info: CoreCommandRouter::servicePushState Jan 20 20:07:35 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:35 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:35 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:35 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:35 volumio volumio[1128]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 20 20:07:35 volumio volumio[1128]: verbose: CURRENT POSITION 0 Jan 20 20:07:35 volumio volumio[1128]: info: CoreStateMachine::syncState stateService stop Jan 20 20:07:35 volumio volumio[1128]: info: CoreStateMachine::syncState currentStatus stop Jan 20 20:07:35 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:35 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:35 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 20:07:35 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:35 volumio volumio[1128]: info: No code Jan 20 20:07:35 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:35 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:35 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:35 volumio volumio[1128]: info: ControllerMpd::pushState Jan 20 20:07:35 volumio volumio[1128]: info: CoreCommandRouter::servicePushState Jan 20 20:07:35 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:35 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:35 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:35 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:35 volumio volumio[1128]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 20 20:07:35 volumio volumio[1128]: verbose: CURRENT POSITION 0 Jan 20 20:07:35 volumio volumio[1128]: info: CoreStateMachine::syncState stateService stop Jan 20 20:07:35 volumio volumio[1128]: info: CoreStateMachine::syncState currentStatus stop Jan 20 20:07:35 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:35 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:35 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:35 volumio volumio[1128]: info: No code Jan 20 20:07:35 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:35 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:35 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:35 volumio volumio[1128]: info: ------------------------------ 306ms Jan 20 20:07:35 volumio volumio[1128]: info: ------------------------------ 302ms Jan 20 20:07:35 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand status Jan 20 20:07:35 volumio volumio[1128]: info: Jan 20 20:07:35 volumio volumio[1128]: ---------------------------- MPD announces state update: update Jan 20 20:07:35 volumio volumio[1128]: info: ControllerMpd::getState Jan 20 20:07:35 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand status Jan 20 20:07:35 volumio volumio[1128]: info: [1737400055950] Database update started Jan 20 20:07:36 volumio volumio[1128]: info: MPD Database updated - AlbumList cache refreshed Jan 20 20:07:36 volumio volumio[1128]: info: Jan 20 20:07:36 volumio volumio[1128]: ---------------------------- MPD announces state update: database Jan 20 20:07:36 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand status Jan 20 20:07:36 volumio volumio[1128]: info: Jan 20 20:07:36 volumio volumio[1128]: ---------------------------- MPD announces state update: update Jan 20 20:07:36 volumio volumio[1128]: info: ControllerMpd::getState Jan 20 20:07:36 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand status Jan 20 20:07:36 volumio volumio[1128]: info: ControllerMpd::getState Jan 20 20:07:36 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand status Jan 20 20:07:36 volumio volumio[1128]: info: MPD Database updated - AlbumList cache refreshed Jan 20 20:07:36 volumio volumio[1128]: info: Jan 20 20:07:36 volumio volumio[1128]: ---------------------------- MPD announces state update: database Jan 20 20:07:36 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand status Jan 20 20:07:36 volumio volumio[1128]: info: Jan 20 20:07:36 volumio volumio[1128]: ---------------------------- MPD announces state update: update Jan 20 20:07:36 volumio volumio[1128]: info: ControllerMpd::getState Jan 20 20:07:36 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand status Jan 20 20:07:36 volumio volumio[1128]: info: ControllerMpd::getState Jan 20 20:07:36 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand status Jan 20 20:07:36 volumio volumio[1128]: info: sendMpdCommand status took 161 milliseconds Jan 20 20:07:36 volumio volumio[1128]: info: sendMpdCommand status took 158 milliseconds Jan 20 20:07:36 volumio volumio[1128]: info: sendMpdCommand status took 30 milliseconds Jan 20 20:07:36 volumio volumio[1128]: info: sendMpdCommand status took 24 milliseconds Jan 20 20:07:36 volumio volumio[1128]: info: sendMpdCommand status took 22 milliseconds Jan 20 20:07:36 volumio volumio[1128]: info: sendMpdCommand status took 18 milliseconds Jan 20 20:07:36 volumio volumio[1128]: info: sendMpdCommand status took 16 milliseconds Jan 20 20:07:36 volumio volumio[1128]: info: sendMpdCommand status took 15 milliseconds Jan 20 20:07:36 volumio volumio[1128]: verbose: ControllerMpd::parseState Jan 20 20:07:36 volumio volumio[1128]: info: Command Router : Notfying DB Updatefalse Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::Close All Modals sent Jan 20 20:07:36 volumio volumio[1128]: verbose: ControllerMpd::parseState Jan 20 20:07:36 volumio volumio[1128]: verbose: ControllerMpd::parseState Jan 20 20:07:36 volumio volumio[1128]: info: Command Router : Notfying DB Updatefalse Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::Close All Modals sent Jan 20 20:07:36 volumio volumio[1128]: verbose: ControllerMpd::parseState Jan 20 20:07:36 volumio volumio[1128]: verbose: ControllerMpd::parseState Jan 20 20:07:36 volumio volumio[1128]: verbose: ControllerMpd::parseState Jan 20 20:07:36 volumio volumio[1128]: info: Command Router : Notfying DB Updatefalse Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::Close All Modals sent Jan 20 20:07:36 volumio volumio[1128]: verbose: ControllerMpd::parseState Jan 20 20:07:36 volumio volumio[1128]: verbose: ControllerMpd::parseState Jan 20 20:07:36 volumio volumio[1128]: info: ControllerMpd::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::servicePushState Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 20 20:07:36 volumio volumio[1128]: verbose: CURRENT POSITION 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::syncState stateService stop Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::syncState currentStatus stop Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:36 volumio volumio[1128]: info: No code Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:36 volumio volumio[1128]: info: ControllerMpd::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::servicePushState Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 20 20:07:36 volumio volumio[1128]: verbose: CURRENT POSITION 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::syncState stateService stop Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::syncState currentStatus stop Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:36 volumio volumio[1128]: info: No code Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:36 volumio volumio[1128]: info: ControllerMpd::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::servicePushState Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 20 20:07:36 volumio volumio[1128]: verbose: CURRENT POSITION 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::syncState stateService stop Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::syncState currentStatus stop Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:36 volumio volumio[1128]: info: No code Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:36 volumio volumio[1128]: info: ControllerMpd::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::servicePushState Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 20 20:07:36 volumio volumio[1128]: verbose: CURRENT POSITION 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::syncState stateService stop Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::syncState currentStatus stop Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:36 volumio volumio[1128]: info: No code Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:36 volumio volumio[1128]: info: ControllerMpd::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::servicePushState Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 20 20:07:36 volumio volumio[1128]: verbose: CURRENT POSITION 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::syncState stateService stop Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::syncState currentStatus stop Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:36 volumio volumio[1128]: info: No code Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:36 volumio volumio[1128]: info: ------------------------------ 592ms Jan 20 20:07:36 volumio volumio[1128]: info: ------------------------------ 463ms Jan 20 20:07:36 volumio volumio[1128]: info: ------------------------------ 466ms Jan 20 20:07:36 volumio volumio[1128]: info: ------------------------------ 456ms Jan 20 20:07:36 volumio volumio[1128]: info: ------------------------------ 458ms Jan 20 20:07:36 volumio volumio[1128]: info: MPD Database updated - AlbumList cache refreshed Jan 20 20:07:36 volumio volumio[1128]: info: Jan 20 20:07:36 volumio volumio[1128]: ---------------------------- MPD announces state update: database Jan 20 20:07:36 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand status Jan 20 20:07:36 volumio volumio[1128]: info: Jan 20 20:07:36 volumio volumio[1128]: ---------------------------- MPD announces state update: update Jan 20 20:07:36 volumio volumio[1128]: info: ControllerMpd::getState Jan 20 20:07:36 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand status Jan 20 20:07:36 volumio volumio[1128]: info: ControllerMpd::getState Jan 20 20:07:36 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand status Jan 20 20:07:36 volumio volumio[1128]: info: sendMpdCommand status took 31 milliseconds Jan 20 20:07:36 volumio volumio[1128]: info: sendMpdCommand status took 11 milliseconds Jan 20 20:07:36 volumio volumio[1128]: info: sendMpdCommand status took 10 milliseconds Jan 20 20:07:36 volumio volumio[1128]: verbose: ControllerMpd::parseState Jan 20 20:07:36 volumio volumio[1128]: info: Command Router : Notfying DB Updatefalse Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::Close All Modals sent Jan 20 20:07:36 volumio volumio[1128]: verbose: ControllerMpd::parseState Jan 20 20:07:36 volumio volumio[1128]: verbose: ControllerMpd::parseState Jan 20 20:07:36 volumio volumio[1128]: info: ControllerMpd::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::servicePushState Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 20 20:07:36 volumio volumio[1128]: verbose: CURRENT POSITION 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::syncState stateService stop Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::syncState currentStatus stop Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:36 volumio volumio[1128]: info: No code Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:36 volumio volumio[1128]: info: ControllerMpd::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::servicePushState Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 20 20:07:36 volumio volumio[1128]: verbose: CURRENT POSITION 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::syncState stateService stop Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::syncState currentStatus stop Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:36 volumio volumio[1128]: info: No code Jan 20 20:07:36 volumio volumio[1128]: info: CoreStateMachine::pushState Jan 20 20:07:36 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Jan 20 20:07:36 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Jan 20 20:07:36 volumio volumio[1128]: info: ------------------------------ 189ms Jan 20 20:07:36 volumio volumio[1128]: info: ------------------------------ 191ms Jan 20 20:07:36 volumio volumio[1128]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 20 20:07:36 volumio volumio[1128]: TypeError: Cannot read property 'replace' of undefined Jan 20 20:07:36 volumio volumio[1128]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Jan 20 20:07:36 volumio volumio[1128]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Jan 20 20:07:36 volumio volumio[1128]: at listOnTimeout (internal/timers.js:554:17) Jan 20 20:07:36 volumio volumio[1128]: at processTimers (internal/timers.js:497:7) Jan 20 20:07:36 volumio volumio[1128]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 20 20:07:37 volumio sudo[1664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-20 20:06 Jan 20 20:07:37 volumio sudo[1664]: 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"