-- Logs begin at Thu 2019-02-14 04:11:58 CST, end at Thu 2025-06-05 15:29:17 CDT. -- Jun 05 15:28:00 volumio wireless.js[594]: trying... Jun 05 15:28:00 volumio sudo[911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 05 15:28:00 volumio sudo[911]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:00 volumio sudo[911]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:01 volumio wireless.js[594]: trying... Jun 05 15:28:01 volumio sudo[914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 05 15:28:01 volumio sudo[914]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:01 volumio sudo[914]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:02 volumio volumio-time-update[592]: volumio-time-update-util: Fetching time from Volumio... Jun 05 15:28:02 volumio volumio-time-update[592]: volumio-time-update-util: Date not found in response Jun 05 15:28:02 volumio volumio-time-update[592]: volumio-time-update-util: Retrying in 5 seconds... Jun 05 15:28:02 volumio wireless.js[594]: trying... Jun 05 15:28:02 volumio sudo[922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 05 15:28:02 volumio sudo[922]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:02 volumio sudo[922]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:02 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 05 15:28:02 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 05 15:28:02 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 05 15:28:02 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 05 15:28:03 volumio wireless.js[594]: trying... Jun 05 15:28:03 volumio sudo[925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 05 15:28:03 volumio sudo[925]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:03 volumio sudo[925]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:04 volumio wireless.js[594]: trying... Jun 05 15:28:04 volumio sudo[928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 05 15:28:04 volumio sudo[928]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:04 volumio sudo[928]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:04 volumio volumio-remote-updater[618]: [2025-06-05 15:28:04] [info] asio async_connect error: system:111 (Connection refused) Jun 05 15:28:04 volumio volumio-remote-updater[618]: [2025-06-05 15:28:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 05 15:28:04 volumio volumio-remote-updater[618]: [2025-06-05 15:28:04] [error] handle_connect error: Underlying Transport Error Jun 05 15:28:04 volumio wpa_supplicant[894]: wlan0: Trying to associate with SSID '\xf0\x9f\x99\x88' Jun 05 15:28:05 volumio wireless.js[594]: trying... Jun 05 15:28:05 volumio sudo[931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 05 15:28:05 volumio sudo[931]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:05 volumio sudo[931]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:05 volumio dhcpcd[906]: wlan0: carrier acquired Jun 05 15:28:05 volumio dhcpcd[906]: wlan0: connected to Access Point `\360\237\231\210' Jun 05 15:28:05 volumio wpa_supplicant[894]: wlan0: Associated with 80:2a:a8:d8:ea:a3 Jun 05 15:28:05 volumio wpa_supplicant[894]: wlan0: CTRL-EVENT-CONNECTED - Connection to 80:2a:a8:d8:ea:a3 completed [id=0 id_str=] Jun 05 15:28:05 volumio wpa_supplicant[894]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 05 15:28:05 volumio wpa_supplicant[894]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Jun 05 15:28:05 volumio dhcpcd[906]: DUID 00:01:00:01:2f:b6:20:30:dc:a6:32:54:78:1d Jun 05 15:28:05 volumio dhcpcd[906]: wlan0: IAID 32:54:78:1e Jun 05 15:28:05 volumio dhcpcd[906]: wlan0: adding address fe80::dea6:32ff:fe54:781e Jun 05 15:28:05 volumio dhcpcd[906]: ipv6_addaddr1: Permission denied Jun 05 15:28:05 volumio dhcpcd[906]: wlan0: carrier lost Jun 05 15:28:05 volumio dhcpcd[906]: wlan0: carrier acquired Jun 05 15:28:05 volumio dhcpcd[906]: wlan0: IAID 32:54:78:1e Jun 05 15:28:05 volumio dhcpcd[906]: wlan0: rebinding lease of 192.168.1.100 Jun 05 15:28:05 volumio dhcpcd[906]: wlan0: probing address 192.168.1.100/24 Jun 05 15:28:05 volumio dhcpcd[906]: wlan0: soliciting an IPv6 router Jun 05 15:28:06 volumio wireless.js[594]: trying... Jun 05 15:28:06 volumio sudo[946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 05 15:28:06 volumio sudo[946]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:06 volumio sudo[946]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:07 volumio volumio-time-update[592]: volumio-time-update-util: Fetching time from Volumio... Jun 05 15:28:07 volumio wireless.js[594]: trying... Jun 05 15:28:07 volumio volumio-time-update[592]: volumio-time-update-util: Date not found in response Jun 05 15:28:07 volumio volumio-time-update[592]: volumio-time-update-util: Retrying in 5 seconds... Jun 05 15:28:07 volumio sudo[953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 05 15:28:07 volumio sudo[953]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:07 volumio sudo[953]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:08 volumio wireless.js[594]: trying... Jun 05 15:28:08 volumio sudo[957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 05 15:28:08 volumio sudo[957]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:08 volumio sudo[957]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:09 volumio wireless.js[594]: trying... Jun 05 15:28:09 volumio sudo[960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 05 15:28:09 volumio sudo[960]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:09 volumio sudo[960]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:10 volumio wireless.js[594]: trying... Jun 05 15:28:10 volumio sudo[963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 05 15:28:10 volumio sudo[963]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:10 volumio sudo[963]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:11 volumio dhcpcd[906]: wlan0: leased 192.168.1.100 for 600 seconds Jun 05 15:28:11 volumio avahi-daemon[615]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.100. Jun 05 15:28:11 volumio dhcpcd[906]: wlan0: adding route to 192.168.1.0/24 Jun 05 15:28:11 volumio avahi-daemon[615]: New relevant interface wlan0.IPv4 for mDNS. Jun 05 15:28:11 volumio avahi-daemon[615]: Registering new address record for 192.168.1.100 on wlan0.IPv4. Jun 05 15:28:11 volumio dhcpcd[906]: wlan0: adding default route via 192.168.1.1 Jun 05 15:28:11 volumio ntpd[798]: ntpd exiting on signal 15 (Terminated) Jun 05 15:28:11 volumio systemd[1]: Stopping Network Time Service... Jun 05 15:28:11 volumio systemd[1]: ntp.service: Succeeded. Jun 05 15:28:11 volumio systemd[1]: Stopped Network Time Service. Jun 05 15:28:11 volumio systemd[1]: Starting Network Time Service... Jun 05 15:28:11 volumio wireless.js[594]: trying... Jun 05 15:28:11 volumio ntpd[996]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 05 15:28:11 volumio systemd[1]: Started Network Time Service. Jun 05 15:28:11 volumio ntpd[996]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Jun 05 15:28:11 volumio ntpd[1004]: proto: precision = 0.814 usec (-20) Jun 05 15:28:11 volumio sudo[1002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 05 15:28:11 volumio ntpd[1004]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 05 15:28:11 volumio sudo[1002]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:11 volumio ntpd[1004]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 05 15:28:11 volumio ntpd[1004]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 160 days ago Jun 05 15:28:11 volumio ntpd[1004]: Listen and drop on 0 v6wildcard [::]:123 Jun 05 15:28:11 volumio ntpd[1004]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 05 15:28:11 volumio ntpd[1004]: Listen normally on 2 lo 127.0.0.1:123 Jun 05 15:28:11 volumio ntpd[1004]: Listen normally on 3 wlan0 192.168.1.100:123 Jun 05 15:28:11 volumio ntpd[1004]: Listening on routing socket on fd #20 for interface updates Jun 05 15:28:11 volumio ntpd[1004]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 05 15:28:11 volumio ntpd[1004]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 05 15:28:11 volumio sudo[1002]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:11 volumio wireless.js[594]: Connected to: ----🙈 Jun 05 15:28:11 volumio wireless.js[594]: ---- Jun 05 15:28:11 volumio sudo[1008]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 05 15:28:11 volumio sudo[1008]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:11 volumio sudo[1008]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:11 volumio wireless.js[594]: ... joined AP, wlan0 IPv4 is 192.168.1.100, ipV6 is undefined Jun 05 15:28:11 volumio wireless.js[594]: It's done! AP Jun 05 15:28:11 volumio systemd[1]: Started Wireless Services. Jun 05 15:28:11 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 05 15:28:11 volumio systemd[1]: Started Volumio Backend Module. Jun 05 15:28:11 volumio systemd[1]: Started Volumio Cpu Tweaker. Jun 05 15:28:11 volumio volumio-cpu-tweak[1014]: Setting RT Priority for mpd Jun 05 15:28:11 volumio volumio-cpu-tweak[1014]: Setting MPD Affinity Jun 05 15:28:11 volumio volumio-cpu-tweak[1014]: pid 806's current affinity mask: f Jun 05 15:28:11 volumio volumio-cpu-tweak[1014]: pid 806's new affinity mask: 3 Jun 05 15:28:11 volumio volumio-cpu-tweak[1014]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jun 05 15:28:11 volumio volumio-cpu-tweak[1014]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jun 05 15:28:11 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Jun 05 15:28:12 volumio volumio-time-update[592]: volumio-time-update-util: Fetching time from Volumio... Jun 05 15:28:12 volumio ntpd[1004]: Soliciting pool server 129.250.35.250 Jun 05 15:28:12 volumio volumio-time-update[592]: volumio-time-update-util: Setting system time to: 2025-06-05 15:28:42 Jun 05 15:28:12 volumio sudo[1037]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-06-05 15:28:42 Jun 05 15:28:12 volumio sudo[1037]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:12 volumio dbus-daemon[609]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.9' (uid=0 pid=1038 comm="timedatectl set-time 2025-06-05 15:28:42 ") Jun 05 15:28:12 volumio systemd[1]: Starting Time & Date Service... Jun 05 15:28:12 volumio dbus-daemon[609]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 05 15:28:12 volumio systemd[1]: Started Time & Date Service. Jun 05 15:28:42 volumio systemd-timedated[1039]: Changed local time to Thu Jun 5 15:28:42 2025 Jun 05 15:28:42 volumio sudo[1037]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:42 volumio volumio-time-update[592]: volumio-time-update-util: System time updated successfully. Jun 05 15:28:42 volumio systemd[1]: Started Volumio Time Update Utility. Jun 05 15:28:42 volumio volumio[1013]: info: ------------------------------------------- Jun 05 15:28:42 volumio volumio[1013]: info: ----- Volumio3 ---- Jun 05 15:28:42 volumio volumio[1013]: info: ------------------------------------------- Jun 05 15:28:42 volumio volumio[1013]: info: ----- System startup ---- Jun 05 15:28:42 volumio volumio[1013]: info: ------------------------------------------- Jun 05 15:28:42 volumio ntpd[1004]: Soliciting pool server 23.131.160.7 Jun 05 15:28:42 volumio ntpd[1004]: Soliciting pool server 209.51.161.238 Jun 05 15:28:42 volumio volumio[1013]: info: MYVOLUMIO Environment detected Jun 05 15:28:42 volumio volumio[1013]: info: Plugin folders cleanup Jun 05 15:28:42 volumio volumio[1013]: info: Scanning into folder /volumio/app/plugins/ Jun 05 15:28:42 volumio volumio[1013]: info: Scanning category audio_interface Jun 05 15:28:42 volumio volumio[1013]: info: Scanning category miscellanea Jun 05 15:28:42 volumio volumio[1013]: info: Scanning category music_service Jun 05 15:28:42 volumio volumio[1013]: info: Scanning category plugins.json Jun 05 15:28:42 volumio volumio[1013]: info: Scanning category system_controller Jun 05 15:28:42 volumio volumio[1013]: info: Scanning category user_interface Jun 05 15:28:42 volumio volumio[1013]: info: Scanning into folder /data/plugins/ Jun 05 15:28:42 volumio volumio[1013]: info: Plugin folders cleanup completed Jun 05 15:28:42 volumio volumio[1013]: info: ------------------------------------------- Jun 05 15:28:42 volumio volumio[1013]: info: ----- Core plugins startup ---- Jun 05 15:28:42 volumio volumio[1013]: info: ------------------------------------------- Jun 05 15:28:42 volumio volumio[1013]: info: Loading plugins from folder /volumio/app/plugins/ Jun 05 15:28:42 volumio volumio[1013]: info: Adding plugin upnp to MyMusic Plugins Jun 05 15:28:42 volumio volumio[1013]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 05 15:28:42 volumio volumio[1013]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 05 15:28:42 volumio volumio[1013]: info: Loading plugins from folder /data/plugins/ Jun 05 15:28:42 volumio volumio[1013]: info: Loading plugin "system"... Jun 05 15:28:42 volumio volumio[1013]: info: Loading plugin "appearance"... Jun 05 15:28:43 volumio nmbd[761]: [2025/06/05 15:28:43.000431, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 05 15:28:43 volumio nmbd[761]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 05 15:28:43 volumio systemd[1]: Started Samba NMB Daemon. Jun 05 15:28:43 volumio systemd[1]: Starting Samba Winbind Daemon... Jun 05 15:28:43 volumio winbindd[1053]: [2025/06/05 15:28:43.242527, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 05 15:28:43 volumio winbindd[1053]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 05 15:28:43 volumio winbindd[1053]: [2025/06/05 15:28:43.258453, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 05 15:28:43 volumio winbindd[1053]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 05 15:28:43 volumio systemd[1]: Started Samba Winbind Daemon. Jun 05 15:28:43 volumio systemd[1]: Starting Samba SMB Daemon... Jun 05 15:28:43 volumio ntpd[1004]: Soliciting pool server 72.177.12.225 Jun 05 15:28:43 volumio ntpd[1004]: Soliciting pool server 15.204.198.96 Jun 05 15:28:43 volumio ntpd[1004]: Soliciting pool server 23.150.41.122 Jun 05 15:28:43 volumio smbd[1058]: [2025/06/05 15:28:43.662291, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 05 15:28:43 volumio systemd[1]: Started Samba SMB Daemon. Jun 05 15:28:43 volumio smbd[1058]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 05 15:28:43 volumio systemd[1]: Reached target Multi-User System. Jun 05 15:28:43 volumio systemd[1]: Reached target Graphical Interface. Jun 05 15:28:43 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 05 15:28:43 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 05 15:28:43 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 05 15:28:43 volumio systemd[1]: Startup finished in 11.955s (kernel) + 26.345s (userspace) = 38.300s. Jun 05 15:28:43 volumio volumio-remote-updater[618]: [2025-06-05 15:28:43] [connect] Successful connection Jun 05 15:28:44 volumio volumio[1013]: info: Loading plugin "network"... Jun 05 15:28:44 volumio volumio[1013]: info: Refreshing Cached IP Addresses Jun 05 15:28:44 volumio sudo[1068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 05 15:28:44 volumio sudo[1068]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:44 volumio sudo[1070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 05 15:28:44 volumio sudo[1068]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:44 volumio sudo[1070]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:44 volumio sudo[1070]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:44 volumio volumio[1013]: info: Loading plugin "services"... Jun 05 15:28:44 volumio volumio[1013]: info: Loading plugin "alsa_controller"... Jun 05 15:28:44 volumio sudo[1083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 05 15:28:44 volumio sudo[1083]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:44 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 05 15:28:44 volumio volumio[1013]: info: Loading plugin "wizard"... Jun 05 15:28:44 volumio volumio[1013]: info: Loading plugin "networkfs"... Jun 05 15:28:44 volumio volumio[1013]: info: Starting Udev Watcher for removable devices Jun 05 15:28:44 volumio sudo[1096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=wonderdrug,password=Frenz4Ever,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.1.1/Storage/media/music/sacd_dsd /mnt/NAS/Storage Jun 05 15:28:44 volumio sudo[1096]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:44 volumio sudo[1098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=wonderdrug,password=Frenz4Ever,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.1.1/Storage/media/music/sacd_iso /mnt/NAS/Storage Jun 05 15:28:44 volumio sudo[1098]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:44 volumio volumio[1013]: info: Ignoring mount for partition: boot Jun 05 15:28:44 volumio volumio[1013]: info: Ignoring mount for partition: volumio Jun 05 15:28:44 volumio volumio[1013]: info: Ignoring mount for partition: volumio_data Jun 05 15:28:44 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 05 15:28:44 volumio volumio[1013]: info: Loading plugin "volumio_command_line_client"... Jun 05 15:28:44 volumio volumio[1013]: info: Loading plugin "upnp"... Jun 05 15:28:44 volumio volumio[1013]: info: [1749155324342] Starting Upmpd Daemon Jun 05 15:28:44 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 05 15:28:44 volumio volumio[1013]: info: Loading plugin "my_music"... Jun 05 15:28:44 volumio volumio[1013]: info: Loading plugin "mpd"... Jun 05 15:28:44 volumio kernel: Key type cifs.spnego registered Jun 05 15:28:44 volumio kernel: Key type cifs.idmap registered Jun 05 15:28:44 volumio kernel: CIFS: Attempting to mount //192.168.1.1/Storage/media/music/sacd_iso Jun 05 15:28:44 volumio ntpd[1004]: Soliciting pool server 24.229.44.105 Jun 05 15:28:44 volumio ntpd[1004]: Soliciting pool server 23.157.160.168 Jun 05 15:28:44 volumio ntpd[1004]: Soliciting pool server 50.218.103.254 Jun 05 15:28:44 volumio kernel: cryptd: max_cpu_qlen set to 1000 Jun 05 15:28:44 volumio ntpd[1004]: Soliciting pool server 212.227.240.160 Jun 05 15:28:44 volumio volumio[1013]: info: Loading plugin "upnp_browser"... Jun 05 15:28:44 volumio volumio[1013]: info: Loading plugin "alarm-clock"... Jun 05 15:28:44 volumio kernel: CIFS: Attempting to mount //192.168.1.1/Storage/media/music/sacd_dsd Jun 05 15:28:44 volumio sudo[1098]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:45 volumio volumio[1013]: info: Loading plugin "airplay_emulation"... Jun 05 15:28:45 volumio volumio[1013]: info: Starting Shairport Sync Jun 05 15:28:45 volumio volumio[1013]: info: Loading plugin "last_100"... Jun 05 15:28:45 volumio volumio[1013]: info: Loading plugin "webradio"... Jun 05 15:28:45 volumio sudo[1096]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:45 volumio volumio[1013]: info: Loading plugin "i2s_dacs"... Jun 05 15:28:45 volumio volumio[1013]: info: I2S DAC not set, start Auto-detection Jun 05 15:28:45 volumio volumio[1013]: info: Loading plugin "volumiodiscovery"... Jun 05 15:28:45 volumio volumio[1013]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 05 15:28:45 volumio node[1013]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 05 15:28:45 volumio volumio[1013]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 05 15:28:45 volumio volumio[1013]: *** WARNING *** For more information see Jun 05 15:28:45 volumio volumio[1013]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 05 15:28:45 volumio volumio[1013]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 05 15:28:45 volumio volumio[1013]: *** WARNING *** For more information see Jun 05 15:28:45 volumio node[1013]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 05 15:28:45 volumio node[1013]: *** WARNING *** For more information see Jun 05 15:28:45 volumio node[1013]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 05 15:28:45 volumio node[1013]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 05 15:28:45 volumio node[1013]: *** WARNING *** For more information see Jun 05 15:28:45 volumio volumio[1013]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 05 15:28:45 volumio volumio[1013]: info: Discovery: Started advertising with name: Volumio Jun 05 15:28:45 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 05 15:28:45 volumio volumio[1013]: info: Loading plugin "outputs"... Jun 05 15:28:45 volumio volumio[1013]: info: Loading plugin "albumart"... Jun 05 15:28:45 volumio volumio[1013]: info: Plugin example_plugin is not enabled Jun 05 15:28:45 volumio volumio[1013]: info: Loading plugin "inputs"... Jun 05 15:28:45 volumio volumio[1013]: info: Loading plugin "updater_comm"... Jun 05 15:28:45 volumio ntpd[1004]: Soliciting pool server 45.79.111.167 Jun 05 15:28:45 volumio ntpd[1004]: Soliciting pool server 23.186.168.129 Jun 05 15:28:45 volumio ntpd[1004]: Soliciting pool server 23.186.168.132 Jun 05 15:28:45 volumio volumio[1013]: info: Plugin mpdemulation is not enabled Jun 05 15:28:45 volumio volumio[1013]: info: Loading plugin "rest_api"... Jun 05 15:28:45 volumio sshd[1034]: Accepted password for volumio from 192.168.1.161 port 64811 ssh2 Jun 05 15:28:45 volumio sshd[1034]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Jun 05 15:28:45 volumio volumio[1013]: info: Loading plugin "websocket"... Jun 05 15:28:45 volumio volumio[1013]: Forking 3 albumart workers Jun 05 15:28:45 volumio systemd[1]: Created slice User Slice of UID 1000. Jun 05 15:28:45 volumio volumio[1013]: info: Starting Socket.io Server version 2.3.0 Jun 05 15:28:45 volumio systemd[1]: Starting User Runtime Directory /run/user/1000... Jun 05 15:28:45 volumio volumio[1013]: info: Loading i18n strings for locale en Jun 05 15:28:45 volumio volumio[1013]: Updating browse sources language Jun 05 15:28:45 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 15:28:45 volumio systemd-logind[607]: New session 1 of user volumio. Jun 05 15:28:45 volumio systemd[1]: Started User Runtime Directory /run/user/1000. Jun 05 15:28:45 volumio systemd[1]: Starting User Manager for UID 1000... Jun 05 15:28:45 volumio systemd[1174]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Jun 05 15:28:45 volumio systemd[1174]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Jun 05 15:28:45 volumio systemd[1174]: Listening on Sound System. Jun 05 15:28:45 volumio systemd[1174]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Jun 05 15:28:45 volumio systemd[1174]: Listening on GnuPG cryptographic agent and passphrase cache. Jun 05 15:28:45 volumio systemd[1174]: Reached target Paths. Jun 05 15:28:45 volumio systemd[1174]: Reached target Timers. Jun 05 15:28:45 volumio systemd[1174]: Starting D-Bus User Message Bus Socket. Jun 05 15:28:45 volumio systemd[1174]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Jun 05 15:28:45 volumio systemd[1174]: Listening on GnuPG network certificate management daemon. Jun 05 15:28:46 volumio systemd[1174]: Listening on D-Bus User Message Bus Socket. Jun 05 15:28:46 volumio systemd[1174]: Reached target Sockets. Jun 05 15:28:46 volumio systemd[1174]: Reached target Basic System. Jun 05 15:28:46 volumio systemd[1174]: Reached target Default. Jun 05 15:28:46 volumio systemd[1174]: Startup finished in 312ms. Jun 05 15:28:46 volumio systemd[1]: Started User Manager for UID 1000. Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:28:46 volumio systemd[1]: Started Session 1 of user volumio. Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::initPlayerControls Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:28:46 volumio volumio[1013]: Express server listening on port 3000 Jun 05 15:28:46 volumio volumio[1013]: [Metrics] WebUI: 4s 504.93ms Jun 05 15:28:46 volumio volumio[1013]: info: CoreStateMachine::resetVolumioState Jun 05 15:28:46 volumio volumio[1013]: info: CoreStateMachine::getcurrentVolume Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::volumioRetrievevolume Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 15:28:46 volumio volumio[1013]: info: Volumio Network Manager: Network status updated: 2 Jun 05 15:28:46 volumio volumio-remote-updater[618]: [2025-06-05 15:28:46] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749155323 101 Jun 05 15:28:46 volumio volumio[1013]: 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: 1 Jun 05 15:28:46 volumio volumio[1013]: verbose: New Socket.io Connection to 192.168.1.100 from 192.168.1.161 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 2 Jun 05 15:28:46 volumio volumio[1013]: Unable to parse: Jun 05 15:28:46 volumio volumio[1013]: Simple mixer control 'PCM',0 Jun 05 15:28:46 volumio volumio[1013]: Capabilities: pswitch pswitch-joined Jun 05 15:28:46 volumio volumio[1013]: Playback channels: Mono Jun 05 15:28:46 volumio volumio[1013]: Mono: Playback [on] Jun 05 15:28:46 volumio volumio[1013]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jun 05 15:28:46 volumio volumio[1013]: Starting albumart workers Jun 05 15:28:46 volumio volumio[1013]: Starting albumart workers Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 05 15:28:46 volumio volumio[1013]: Starting albumart workers Jun 05 15:28:46 volumio volumio[1013]: info: Reloading queue from file Jun 05 15:28:46 volumio volumio[1013]: info: Setting Device type: Raspberry PI Jun 05 15:28:46 volumio volumio[1013]: info: CoreStateMachine::setRepeat null single undefined Jun 05 15:28:46 volumio volumio[1013]: info: CoreStateMachine::pushState Jun 05 15:28:46 volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jun 05 15:28:46 volumio volumio[1013]: info: CoreStateMachine::setRandom null Jun 05 15:28:46 volumio volumio[1013]: info: CoreStateMachine::pushState Jun 05 15:28:46 volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jun 05 15:28:46 volumio ntpd[1004]: Soliciting pool server 69.89.207.199 Jun 05 15:28:46 volumio ntpd[1004]: Soliciting pool server 45.79.214.107 Jun 05 15:28:46 volumio volumio[1013]: Unable to parse: Jun 05 15:28:46 volumio volumio[1013]: Simple mixer control 'PCM',0 Jun 05 15:28:46 volumio volumio[1013]: Capabilities: pswitch pswitch-joined Jun 05 15:28:46 volumio volumio[1013]: Playback channels: Mono Jun 05 15:28:46 volumio volumio[1013]: Mono: Playback [on] Jun 05 15:28:46 volumio volumio[1013]: info: VolumeController:: Volume=undefined Mute =false Jun 05 15:28:46 volumio volumio[1013]: info: CoreStateMachine::pushState Jun 05 15:28:46 volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jun 05 15:28:46 volumio volumio[1013]: info: CoreStateMachine::updateTrackBlock Jun 05 15:28:46 volumio volumio[1013]: info: CorePlayQueue::getTrackBlock Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::volumioRetrievevolume Jun 05 15:28:46 volumio volumio[1013]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 05 15:28:46 volumio volumio[1013]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Jun 05 15:28:46 volumio volumio[1013]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::volumioGetVisibleSources Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jun 05 15:28:46 volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jun 05 15:28:46 volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 05 15:28:46 volumio volumio[1013]: info: Received Get System Info Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 05 15:28:46 volumio volumio[1013]: info: Discovery: Getting this device information Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jun 05 15:28:46 volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jun 05 15:28:46 volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jun 05 15:28:46 volumio volumio[1013]: info: Listing playlists Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::volumioGetQueue Jun 05 15:28:46 volumio volumio[1013]: info: CoreStateMachine::getQueue Jun 05 15:28:46 volumio volumio[1013]: info: CorePlayQueue::getQueue Jun 05 15:28:46 volumio volumio[1013]: Unable to parse: Jun 05 15:28:46 volumio volumio[1013]: Simple mixer control 'PCM',0 Jun 05 15:28:46 volumio volumio[1013]: Capabilities: pswitch pswitch-joined Jun 05 15:28:46 volumio volumio[1013]: Playback channels: Mono Jun 05 15:28:46 volumio volumio[1013]: Mono: Playback [on] Jun 05 15:28:46 volumio volumio[1013]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jun 05 15:28:46 volumio volumio[1013]: info: Completed loading Core Plugins Jun 05 15:28:46 volumio volumio[1013]: info: Preparing to generate the ALSA configuration file Jun 05 15:28:46 volumio volumio[1013]: Unable to parse: Jun 05 15:28:46 volumio volumio[1013]: Simple mixer control 'PCM',0 Jun 05 15:28:46 volumio volumio[1013]: Capabilities: pswitch pswitch-joined Jun 05 15:28:46 volumio volumio[1013]: Playback channels: Mono Jun 05 15:28:46 volumio volumio[1013]: Mono: Playback [on] Jun 05 15:28:46 volumio volumio[1013]: info: VolumeController:: Volume=undefined Mute =false Jun 05 15:28:46 volumio volumio[1013]: info: CoreStateMachine::pushState Jun 05 15:28:46 volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jun 05 15:28:46 volumio volumio[1013]: info: Discovery: adding a16d0984-4a69-443f-8afb-3e864c0f7d5f Jun 05 15:28:46 volumio volumio[1013]: info: Discovery: Found device Volumio Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jun 05 15:28:46 volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jun 05 15:28:46 volumio volumio[1013]: info: Asound.conf file unchanged, so no further update is needed Jun 05 15:28:46 volumio volumio[1013]: info: Output device has changed, restarting MPD Jun 05 15:28:46 volumio volumio[1013]: info: Output device has changed, restarting Shairport Sync Jun 05 15:28:46 volumio sudo[1225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 15:28:46 volumio sudo[1225]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:46 volumio sudo[1225]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:46 volumio sudo[1227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 05 15:28:46 volumio sudo[1227]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:46 volumio systemd[1]: Stopping Music Player Daemon... Jun 05 15:28:46 volumio volumio[1013]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 05 15:28:46 volumio volumio[1013]: info: ___________ START PLUGINS ___________ Jun 05 15:28:46 volumio volumio[1013]: info: ControllerMpd::onStart: Initializing MPD Jun 05 15:28:46 volumio volumio[1013]: info: Creating MPD Configuration file Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 05 15:28:46 volumio volumio[1013]: info: [1749155326891] CoreMusicLibrary::Adding element Media Servers Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 15:28:46 volumio sudo[1242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 05 15:28:46 volumio sudo[1242]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:46 volumio sudo[1242]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:46 volumio sudo[1244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 05 15:28:46 volumio sudo[1244]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 15:28:46 volumio systemd[1]: mpd.service: Succeeded. Jun 05 15:28:46 volumio systemd[1]: Stopped Music Player Daemon. Jun 05 15:28:46 volumio volumio[1013]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 15:28:46 volumio volumio[1013]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 05 15:28:46 volumio volumio[1013]: info: [1749155326990] CoreMusicLibrary::Adding element Last_100 Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 05 15:28:46 volumio volumio[1013]: info: [1749155326992] CoreMusicLibrary::Adding element Webradio Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 15:28:46 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 05 15:28:47 volumio volumio[1013]: info: Initializing BBC Radios Jun 05 15:28:46 volumio systemd[1]: Starting Music Player Daemon... Jun 05 15:28:47 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 05 15:28:47 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 15:28:47 volumio volumio[1013]: info: Volumio Calling Home Jun 05 15:28:47 volumio sudo[1250]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 05 15:28:47 volumio sudo[1250]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:47 volumio sudo[1250]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:47 volumio volumio[1013]: info: MPD Permissions set Jun 05 15:28:47 volumio volumio[1013]: info: MPD Permissions set Jun 05 15:28:47 volumio volumio[1013]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 05 15:28:47 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:47 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:47 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:47 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:47 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:47 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:47 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:47 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:47 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 15:28:47 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:47 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:47 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:47 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:47 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:47 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:47 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:47 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:47 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:47 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 15:28:47 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:47 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jun 05 15:28:47 volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jun 05 15:28:47 volumio volumio[1013]: info: Starting Shairport Sync Jun 05 15:28:47 volumio volumio[1013]: info: Starting Shairport Sync Jun 05 15:28:47 volumio volumio[1013]: info: Starting Shairport Sync Jun 05 15:28:47 volumio sudo[1278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 05 15:28:47 volumio sudo[1278]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:47 volumio sudo[1280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 05 15:28:47 volumio sudo[1280]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:47 volumio sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 05 15:28:47 volumio sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:47 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 05 15:28:47 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 05 15:28:47 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 05 15:28:47 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 05 15:28:47 volumio sudo[1278]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:47 volumio volumio[1013]: info: Shairport-Sync Started Jun 05 15:28:47 volumio volumio[1013]: Error adding Membership: Error: addMembership EINVAL Jun 05 15:28:47 volumio shairport-sync[1286]: free(): double free detected in tcache 2 Jun 05 15:28:47 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 05 15:28:47 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=6/ABRT Jun 05 15:28:47 volumio systemd[1]: shairport-sync.service: Failed with result 'signal'. Jun 05 15:28:47 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 05 15:28:47 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 05 15:28:47 volumio sudo[1280]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:47 volumio ntpd[1004]: Soliciting pool server 23.141.40.124 Jun 05 15:28:47 volumio ntpd[1004]: Soliciting pool server 2604:2dc0:202:300::2459 Jun 05 15:28:47 volumio sudo[1283]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:47 volumio volumio[1013]: info: Shairport-Sync Started Jun 05 15:28:47 volumio volumio[1013]: info: Shairport-Sync Started Jun 05 15:28:47 volumio volumio[1013]: info: Volumio called home Jun 05 15:28:47 volumio mpd[1267]: Jun 05 15:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 05 15:28:47 volumio systemd[1]: Started Music Player Daemon. Jun 05 15:28:47 volumio sudo[1244]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:47 volumio sudo[1227]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:47 volumio volumio[1013]: info: Completed starting Core Plugins Jun 05 15:28:47 volumio volumio[1013]: info: ------------------------------------------- Jun 05 15:28:47 volumio volumio[1013]: info: ----- MyVolumio plugins startup ---- Jun 05 15:28:47 volumio volumio[1013]: info: ------------------------------------------- Jun 05 15:28:47 volumio volumio[1013]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 05 15:28:47 volumio volumio[1013]: error: MPD error: The expression evaluated to a falsy value: Jun 05 15:28:47 volumio volumio[1013]: assert.ok(self.idling) Jun 05 15:28:47 volumio volumio[1013]: error: The expression evaluated to a falsy value: Jun 05 15:28:47 volumio volumio[1013]: assert.ok(self.idling) Jun 05 15:28:47 volumio volumio[1013]: info: MPD running with PID1267 Jun 05 15:28:47 volumio volumio[1013]: ,establishing connection Jun 05 15:28:47 volumio volumio[1013]: error: updateQueue error: null Jun 05 15:28:47 volumio volumio[1013]: error: updateQueue error: null Jun 05 15:28:48 volumio sudo[1083]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:48 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 05 15:28:49 volumio sudo[1297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 05 15:28:49 volumio sudo[1297]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:49 volumio sudo[1297]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:49 volumio sudo[1299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 05 15:28:49 volumio sudo[1299]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:49 volumio sudo[1299]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:49 volumio volumio[1013]: verbose: New Socket.io Connection to 192.168.1.100 from 192.168.1.161 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 3 Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::volumioGetVisibleSources Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jun 05 15:28:49 volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 05 15:28:49 volumio volumio[1013]: info: Received Get System Info Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 05 15:28:49 volumio volumio[1013]: info: Discovery: Getting this device information Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jun 05 15:28:49 volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jun 05 15:28:49 volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jun 05 15:28:49 volumio volumio[1013]: info: Listing playlists Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::volumioGetBrowseSources Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 05 15:28:49 volumio volumio[1013]: info: VolumeController::SetAlsaVolume0 Jun 05 15:28:49 volumio volumio[1013]: info: CoreStateMachine::pushState Jun 05 15:28:49 volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 15:28:49 volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jun 05 15:28:50 volumio ntpd[1004]: receive: Unexpected origin timestamp 0xebec7c81.72a8bd8a does not match aorg 0000000000.00000000 from server@45.79.214.107 xmt 0xebec7c82.1684789c Jun 05 15:28:50 volumio ntpd[1004]: receive: Unexpected origin timestamp 0xebec7c81.72ae549d does not match aorg 0000000000.00000000 from server@15.204.198.96 xmt 0xebec7c82.18b143a0 Jun 05 15:28:50 volumio ntpd[1004]: receive: Unexpected origin timestamp 0xebec7c81.72afe8c2 does not match aorg 0000000000.00000000 from server@209.51.161.238 xmt 0xebec7c82.18fd9fd4 Jun 05 15:28:50 volumio ntpd[1004]: receive: Unexpected origin timestamp 0xebec7c81.72ad8b6f does not match aorg 0000000000.00000000 from server@23.157.160.168 xmt 0xebec7c82.18e8cc3a Jun 05 15:28:50 volumio ntpd[1004]: receive: Unexpected origin timestamp 0xebec7c81.72af1fa6 does not match aorg 0000000000.00000000 from server@72.177.12.225 xmt 0xebec7c82.15810624 Jun 05 15:28:50 volumio ntpd[1004]: receive: Unexpected origin timestamp 0xebec7c81.72a524b4 does not match aorg 0000000000.00000000 from server@69.89.207.199 xmt 0xebec7c82.18f74ce9 Jun 05 15:28:50 volumio ntpd[1004]: receive: Unexpected origin timestamp 0xebec7c81.72aa72a3 does not match aorg 0000000000.00000000 from server@212.227.240.160 xmt 0xebec7c82.1a11bde2 Jun 05 15:28:50 volumio ntpd[1004]: receive: Unexpected origin timestamp 0xebec7c81.72acb2ad does not match aorg 0000000000.00000000 from server@50.218.103.254 xmt 0xebec7c82.19d3683c Jun 05 15:28:50 volumio ntpd[1004]: receive: Unexpected origin timestamp 0xebec7c81.72aba6ad does not match aorg 0000000000.00000000 from server@24.229.44.105 xmt 0xebec7c82.1a466d3a Jun 05 15:28:50 volumio sudo[1310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 05 15:28:50 volumio sudo[1310]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:50 volumio sudo[1310]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:50 volumio sudo[1312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 05 15:28:50 volumio sudo[1312]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:50 volumio sudo[1312]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:50 volumio volumio[1013]: verbose: New Socket.io Connection to 192.168.1.100 from 192.168.1.161 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 3 Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::volumioGetVisibleSources Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jun 05 15:28:50 volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 05 15:28:50 volumio volumio[1013]: info: Received Get System Info Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 05 15:28:50 volumio volumio[1013]: info: Discovery: Getting this device information Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jun 05 15:28:50 volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jun 05 15:28:50 volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jun 05 15:28:50 volumio volumio[1013]: info: Listing playlists Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::volumioGetBrowseSources Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::volumioGetBrowseSources Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::volumioGetBrowseSources Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 05 15:28:50 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 05 15:28:51 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jun 05 15:28:52 volumio sh[526]: timed out Jun 05 15:28:52 volumio dhcpcd[572]: timed out Jun 05 15:28:52 volumio sh[526]: dhcpcd exited Jun 05 15:28:52 volumio dhcpcd[572]: dhcpcd exited Jun 05 15:28:52 volumio sh[526]: ifup: failed to bring up eth0 Jun 05 15:28:52 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jun 05 15:28:52 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jun 05 15:28:52 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 05 15:28:52 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 05 15:28:52 volumio volumio[1013]: info: Received Get System Info Jun 05 15:28:52 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 05 15:28:52 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 05 15:28:52 volumio volumio[1013]: info: Discovery: Getting this device information Jun 05 15:28:52 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jun 05 15:28:52 volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jun 05 15:28:52 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 15:28:53 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jun 05 15:28:53 volumio volumio[1013]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 05 15:28:53 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jun 05 15:28:53 volumio sudo[1326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/Storage Jun 05 15:28:53 volumio sudo[1326]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:54 volumio sudo[1326]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:54 volumio volumio[1013]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 05 15:28:54 volumio volumio[1013]: Error: EBUSY: resource busy or locked, rmdir '/mnt/NAS/Storage' Jun 05 15:28:54 volumio volumio[1013]: at Object.rmdirSync (fs.js:890:10) Jun 05 15:28:54 volumio volumio[1013]: at /volumio/node_modules/linux-mountutils/mountutils.js:178:12 Jun 05 15:28:54 volumio volumio[1013]: at ChildProcess.exithandler (child_process.js:299:7) Jun 05 15:28:54 volumio volumio[1013]: at ChildProcess.emit (events.js:315:20) Jun 05 15:28:54 volumio volumio[1013]: at maybeClose (internal/child_process.js:1048:16) Jun 05 15:28:54 volumio volumio[1013]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) { Jun 05 15:28:54 volumio volumio[1013]: errno: -16, Jun 05 15:28:54 volumio volumio[1013]: syscall: 'rmdir', Jun 05 15:28:54 volumio volumio[1013]: code: 'EBUSY', Jun 05 15:28:54 volumio volumio[1013]: path: '/mnt/NAS/Storage' Jun 05 15:28:54 volumio volumio[1013]: } Jun 05 15:28:54 volumio volumio[1013]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 05 15:28:54 volumio sudo[1337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-05 15:27 Jun 05 15:28:54 volumio sudo[1337]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:54 volumio sudo[1337]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:55 volumio volumio-remote-updater[618]: [2025-06-05 15:28:55] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 05 15:28:55 volumio volumio-remote-updater[618]: [2025-06-05 15:28:55] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 05 15:28:55 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 05 15:28:55 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 05 15:28:55 volumio systemd[1]: Started dynamicswap service. Jun 05 15:28:55 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 05 15:28:55 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 05 15:28:55 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jun 05 15:28:55 volumio systemd[1]: Started dynamicswap service. Jun 05 15:28:55 volumio systemd[1]: Stopped Volumio Backend Module. Jun 05 15:28:55 volumio systemd[1]: Started Volumio Backend Module. Jun 05 15:28:55 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 05 15:28:56 volumio volumio[1365]: info: ------------------------------------------- Jun 05 15:28:56 volumio volumio[1365]: info: ----- Volumio3 ---- Jun 05 15:28:56 volumio volumio[1365]: info: ------------------------------------------- Jun 05 15:28:56 volumio volumio[1365]: info: ----- System startup ---- Jun 05 15:28:56 volumio volumio[1365]: info: ------------------------------------------- Jun 05 15:28:56 volumio volumio[1365]: info: MYVOLUMIO Environment detected Jun 05 15:28:56 volumio volumio[1365]: info: Plugin folders cleanup Jun 05 15:28:56 volumio volumio[1365]: info: Scanning into folder /volumio/app/plugins/ Jun 05 15:28:56 volumio volumio[1365]: info: Scanning category audio_interface Jun 05 15:28:56 volumio volumio[1365]: info: Scanning category miscellanea Jun 05 15:28:56 volumio volumio[1365]: info: Scanning category music_service Jun 05 15:28:56 volumio volumio[1365]: info: Scanning category plugins.json Jun 05 15:28:56 volumio volumio[1365]: info: Scanning category system_controller Jun 05 15:28:56 volumio volumio[1365]: info: Scanning category user_interface Jun 05 15:28:56 volumio volumio[1365]: info: Scanning into folder /data/plugins/ Jun 05 15:28:56 volumio volumio[1365]: info: Plugin folders cleanup completed Jun 05 15:28:56 volumio volumio[1365]: info: ------------------------------------------- Jun 05 15:28:56 volumio volumio[1365]: info: ----- Core plugins startup ---- Jun 05 15:28:56 volumio volumio[1365]: info: ------------------------------------------- Jun 05 15:28:56 volumio volumio[1365]: info: Loading plugins from folder /volumio/app/plugins/ Jun 05 15:28:56 volumio volumio[1365]: info: Adding plugin upnp to MyMusic Plugins Jun 05 15:28:56 volumio volumio[1365]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 05 15:28:56 volumio volumio[1365]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 05 15:28:56 volumio volumio[1365]: info: Loading plugins from folder /data/plugins/ Jun 05 15:28:56 volumio volumio[1365]: info: Loading plugin "system"... Jun 05 15:28:56 volumio volumio[1365]: info: Loading plugin "appearance"... Jun 05 15:28:57 volumio volumio[1365]: info: Loading plugin "network"... Jun 05 15:28:57 volumio volumio[1365]: info: Refreshing Cached IP Addresses Jun 05 15:28:57 volumio sudo[1391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 05 15:28:57 volumio sudo[1391]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:57 volumio sudo[1391]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:57 volumio sudo[1393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 05 15:28:57 volumio sudo[1393]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:57 volumio sudo[1393]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:57 volumio volumio[1365]: info: Loading plugin "services"... Jun 05 15:28:57 volumio volumio[1365]: info: Loading plugin "alsa_controller"... Jun 05 15:28:57 volumio sudo[1406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 05 15:28:57 volumio sudo[1406]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:57 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 05 15:28:57 volumio volumio[1365]: info: Loading plugin "wizard"... Jun 05 15:28:57 volumio volumio[1365]: info: Loading plugin "networkfs"... Jun 05 15:28:57 volumio volumio[1365]: info: Cannot mount NAS Storage at system boot, trial number 1 ,retrying in 5 seconds Jun 05 15:28:57 volumio volumio[1365]: info: Cannot mount NAS Storage at system boot, trial number 1 ,retrying in 5 seconds Jun 05 15:28:57 volumio volumio[1365]: info: Starting Udev Watcher for removable devices Jun 05 15:28:57 volumio volumio[1365]: info: Ignoring mount for partition: boot Jun 05 15:28:57 volumio volumio[1365]: info: Ignoring mount for partition: volumio Jun 05 15:28:57 volumio volumio[1365]: info: Ignoring mount for partition: volumio_data Jun 05 15:28:57 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 05 15:28:57 volumio volumio[1365]: info: Loading plugin "volumio_command_line_client"... Jun 05 15:28:57 volumio volumio[1365]: info: Loading plugin "upnp"... Jun 05 15:28:57 volumio volumio[1365]: info: [1749155337608] Starting Upmpd Daemon Jun 05 15:28:57 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 05 15:28:57 volumio volumio[1365]: info: Loading plugin "my_music"... Jun 05 15:28:57 volumio volumio[1365]: info: Loading plugin "mpd"... Jun 05 15:28:57 volumio volumio[1365]: info: Loading plugin "upnp_browser"... Jun 05 15:28:57 volumio volumio[1365]: info: Loading plugin "alarm-clock"... Jun 05 15:28:58 volumio volumio[1365]: info: Loading plugin "airplay_emulation"... Jun 05 15:28:58 volumio volumio[1365]: info: Starting Shairport Sync Jun 05 15:28:58 volumio volumio[1365]: info: Loading plugin "last_100"... Jun 05 15:28:58 volumio volumio[1365]: info: Loading plugin "webradio"... Jun 05 15:28:58 volumio volumio[1365]: info: Loading plugin "i2s_dacs"... Jun 05 15:28:58 volumio volumio[1365]: info: I2S DAC not set, start Auto-detection Jun 05 15:28:58 volumio volumio[1365]: info: Loading plugin "volumiodiscovery"... Jun 05 15:28:58 volumio volumio[1365]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 05 15:28:58 volumio volumio[1365]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 05 15:28:58 volumio volumio[1365]: *** WARNING *** For more information see Jun 05 15:28:58 volumio node[1365]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 05 15:28:58 volumio volumio[1365]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 05 15:28:58 volumio volumio[1365]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 05 15:28:58 volumio volumio[1365]: *** WARNING *** For more information see Jun 05 15:28:58 volumio node[1365]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 05 15:28:58 volumio node[1365]: *** WARNING *** For more information see Jun 05 15:28:58 volumio node[1365]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 05 15:28:58 volumio node[1365]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 05 15:28:58 volumio node[1365]: *** WARNING *** For more information see Jun 05 15:28:58 volumio volumio[1365]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 05 15:28:58 volumio volumio[1365]: info: Discovery: Started advertising with name: Volumio Jun 05 15:28:58 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 05 15:28:58 volumio volumio[1365]: info: Loading plugin "outputs"... Jun 05 15:28:58 volumio volumio[1365]: info: Loading plugin "albumart"... Jun 05 15:28:58 volumio volumio[1365]: info: Plugin example_plugin is not enabled Jun 05 15:28:58 volumio volumio[1365]: info: Loading plugin "inputs"... Jun 05 15:28:58 volumio volumio[1365]: info: Loading plugin "updater_comm"... Jun 05 15:28:58 volumio volumio[1365]: info: Plugin mpdemulation is not enabled Jun 05 15:28:58 volumio volumio[1365]: info: Loading plugin "rest_api"... Jun 05 15:28:58 volumio volumio[1365]: info: Loading plugin "websocket"... Jun 05 15:28:58 volumio volumio[1365]: info: Starting Socket.io Server version 2.3.0 Jun 05 15:28:58 volumio volumio[1365]: info: Loading i18n strings for locale en Jun 05 15:28:58 volumio volumio[1365]: Updating browse sources language Jun 05 15:28:58 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 15:28:58 volumio volumio[1365]: Forking 3 albumart workers Jun 05 15:28:58 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:28:58 volumio volumio[1365]: info: CoreCommandRouter::initPlayerControls Jun 05 15:28:58 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:58 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:58 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:58 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:58 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:58 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:58 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:58 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:58 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:28:58 volumio volumio[1365]: Express server listening on port 3000 Jun 05 15:28:58 volumio volumio[1365]: [Metrics] WebUI: 2s 372.84ms Jun 05 15:28:58 volumio volumio[1365]: info: CoreStateMachine::resetVolumioState Jun 05 15:28:58 volumio volumio[1365]: info: CoreStateMachine::getcurrentVolume Jun 05 15:28:58 volumio volumio[1365]: info: CoreCommandRouter::volumioRetrievevolume Jun 05 15:28:58 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 15:28:58 volumio volumio[1365]: info: Volumio Network Manager: Network status updated: 2 Jun 05 15:28:58 volumio volumio[1365]: Unable to parse: Jun 05 15:28:58 volumio volumio[1365]: Simple mixer control 'PCM',0 Jun 05 15:28:58 volumio volumio[1365]: Capabilities: pswitch pswitch-joined Jun 05 15:28:58 volumio volumio[1365]: Playback channels: Mono Jun 05 15:28:58 volumio volumio[1365]: Mono: Playback [on] Jun 05 15:28:58 volumio volumio[1365]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jun 05 15:28:58 volumio volumio[1365]: verbose: New Socket.io Connection to 192.168.1.100 from 192.168.1.161 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 1 Jun 05 15:28:58 volumio volumio[1365]: Unable to parse: Jun 05 15:28:58 volumio volumio[1365]: Simple mixer control 'PCM',0 Jun 05 15:28:58 volumio volumio[1365]: Capabilities: pswitch pswitch-joined Jun 05 15:28:58 volumio volumio[1365]: Playback channels: Mono Jun 05 15:28:58 volumio volumio[1365]: Mono: Playback [on] Jun 05 15:28:58 volumio volumio[1365]: info: VolumeController:: Volume=undefined Mute =false Jun 05 15:28:58 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:28:58 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:28:58 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 15:28:58 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:28:58 volumio volumio[1365]: info: CoreStateMachine::updateTrackBlock Jun 05 15:28:58 volumio volumio[1365]: info: CorePlayQueue::getTrackBlock Jun 05 15:28:58 volumio volumio[1365]: info: CoreCommandRouter::volumioRetrievevolume Jun 05 15:28:58 volumio volumio[1365]: info: Reloading queue from file Jun 05 15:28:58 volumio volumio[1365]: info: CoreStateMachine::setRepeat null single undefined Jun 05 15:28:58 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:28:58 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:28:58 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 15:28:58 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:28:58 volumio volumio[1365]: info: CoreStateMachine::setRandom null Jun 05 15:28:58 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:28:58 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:28:58 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:28:58 volumio volumio[1365]: info: Setting Device type: Raspberry PI Jun 05 15:28:58 volumio volumio[1365]: Unable to parse: Jun 05 15:28:58 volumio volumio[1365]: Simple mixer control 'PCM',0 Jun 05 15:28:58 volumio volumio[1365]: Capabilities: pswitch pswitch-joined Jun 05 15:28:58 volumio volumio[1365]: Playback channels: Mono Jun 05 15:28:58 volumio volumio[1365]: Mono: Playback [on] Jun 05 15:28:58 volumio volumio[1365]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jun 05 15:28:58 volumio volumio[1365]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 05 15:28:58 volumio volumio[1365]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Jun 05 15:28:58 volumio volumio[1365]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 05 15:28:58 volumio volumio[1365]: info: Completed loading Core Plugins Jun 05 15:28:58 volumio volumio[1365]: info: Preparing to generate the ALSA configuration file Jun 05 15:28:58 volumio volumio[1365]: Unable to parse: Jun 05 15:28:58 volumio volumio[1365]: Simple mixer control 'PCM',0 Jun 05 15:28:58 volumio volumio[1365]: Capabilities: pswitch pswitch-joined Jun 05 15:28:58 volumio volumio[1365]: Playback channels: Mono Jun 05 15:28:58 volumio volumio[1365]: Mono: Playback [on] Jun 05 15:28:58 volumio volumio[1365]: info: VolumeController:: Volume=undefined Mute =false Jun 05 15:28:58 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:28:58 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:28:58 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:28:58 volumio volumio[1365]: info: Asound.conf file unchanged, so no further update is needed Jun 05 15:28:58 volumio volumio[1365]: info: Output device has changed, restarting MPD Jun 05 15:28:58 volumio volumio[1365]: info: Output device has changed, restarting Shairport Sync Jun 05 15:28:58 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:58 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 15:28:58 volumio sudo[1460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 05 15:28:58 volumio sudo[1460]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:58 volumio sudo[1460]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:59 volumio sudo[1462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 05 15:28:59 volumio volumio[1365]: Starting albumart workers Jun 05 15:28:59 volumio sudo[1462]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:59 volumio volumio[1365]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 05 15:28:59 volumio volumio[1365]: info: ___________ START PLUGINS ___________ Jun 05 15:28:59 volumio systemd[1]: Stopping Music Player Daemon... Jun 05 15:28:59 volumio volumio[1365]: info: ControllerMpd::onStart: Initializing MPD Jun 05 15:28:59 volumio volumio[1365]: info: Creating MPD Configuration file Jun 05 15:28:59 volumio volumio[1365]: Starting albumart workers Jun 05 15:28:59 volumio systemd[1]: mpd.service: Succeeded. Jun 05 15:28:59 volumio systemd[1]: Stopped Music Player Daemon. Jun 05 15:28:59 volumio sudo[1469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 05 15:28:59 volumio volumio[1365]: info: [1749155339109] CoreMusicLibrary::Adding element Media Servers Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 15:28:59 volumio systemd[1]: Starting Music Player Daemon... Jun 05 15:28:59 volumio sudo[1469]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:59 volumio sudo[1469]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 15:28:59 volumio sudo[1472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 05 15:28:59 volumio sudo[1472]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:59 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 05 15:28:59 volumio systemd[1]: mpd.service: Succeeded. Jun 05 15:28:59 volumio systemd[1]: Stopped Music Player Daemon. Jun 05 15:28:59 volumio systemd[1]: Starting Music Player Daemon... Jun 05 15:28:59 volumio volumio[1365]: Starting albumart workers Jun 05 15:28:59 volumio volumio[1365]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 15:28:59 volumio sudo[1478]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 05 15:28:59 volumio sudo[1478]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:59 volumio sudo[1478]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:59 volumio volumio[1365]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 05 15:28:59 volumio volumio[1365]: info: [1749155339287] CoreMusicLibrary::Adding element Last_100 Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 05 15:28:59 volumio volumio[1365]: info: [1749155339290] CoreMusicLibrary::Adding element Webradio Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 05 15:28:59 volumio volumio[1365]: info: Initializing BBC Radios Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 15:28:59 volumio volumio[1365]: info: Volumio Calling Home Jun 05 15:28:59 volumio volumio[1365]: verbose: New Socket.io Connection to 192.168.1.100 from 192.168.1.161 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 2 Jun 05 15:28:59 volumio volumio[1365]: info: MPD Permissions set Jun 05 15:28:59 volumio volumio[1365]: info: MPD Permissions set Jun 05 15:28:59 volumio volumio[1365]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 15:28:59 volumio volumio[1365]: info: Starting Shairport Sync Jun 05 15:28:59 volumio volumio[1365]: info: Starting Shairport Sync Jun 05 15:28:59 volumio volumio[1365]: info: Starting Shairport Sync Jun 05 15:28:59 volumio sudo[1531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 05 15:28:59 volumio sudo[1531]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:59 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 05 15:28:59 volumio sudo[1533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 05 15:28:59 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 05 15:28:59 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 05 15:28:59 volumio sudo[1533]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:59 volumio sudo[1536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState Jun 05 15:28:59 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:28:59 volumio volumio[1365]: info: Discovery: adding a16d0984-4a69-443f-8afb-3e864c0f7d5f Jun 05 15:28:59 volumio volumio[1365]: info: Discovery: Found device Volumio Jun 05 15:28:59 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState Jun 05 15:28:59 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:28:59 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 05 15:28:59 volumio sudo[1536]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:28:59 volumio sudo[1531]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:59 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 05 15:28:59 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 05 15:28:59 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 05 15:28:59 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 05 15:28:59 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 05 15:28:59 volumio volumio[1365]: info: Volumio called home Jun 05 15:28:59 volumio sudo[1533]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:59 volumio sudo[1536]: pam_unix(sudo:session): session closed for user root Jun 05 15:28:59 volumio volumio[1365]: info: Shairport-Sync Started Jun 05 15:28:59 volumio volumio[1365]: Error adding Membership: Error: addMembership EINVAL Jun 05 15:28:59 volumio volumio[1365]: info: Shairport-Sync Started Jun 05 15:28:59 volumio volumio[1365]: info: Shairport-Sync Started Jun 05 15:29:00 volumio volumio-remote-updater[618]: [2025-06-05 15:29:00] [connect] Successful connection Jun 05 15:29:00 volumio volumio-remote-updater[618]: [2025-06-05 15:29:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749155340 101 Jun 05 15:29:00 volumio volumio[1365]: 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: 4 Jun 05 15:29:00 volumio mpd[1483]: Jun 05 15:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 05 15:29:00 volumio systemd[1]: Started Music Player Daemon. Jun 05 15:29:00 volumio sudo[1462]: pam_unix(sudo:session): session closed for user root Jun 05 15:29:00 volumio sudo[1472]: pam_unix(sudo:session): session closed for user root Jun 05 15:29:00 volumio volumio[1365]: info: Completed starting Core Plugins Jun 05 15:29:00 volumio volumio[1365]: info: ------------------------------------------- Jun 05 15:29:00 volumio volumio[1365]: info: ----- MyVolumio plugins startup ---- Jun 05 15:29:00 volumio volumio[1365]: info: ------------------------------------------- Jun 05 15:29:00 volumio volumio[1365]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 05 15:29:00 volumio volumio[1365]: error: MPD error: The expression evaluated to a falsy value: Jun 05 15:29:00 volumio volumio[1365]: assert.ok(self.idling) Jun 05 15:29:00 volumio volumio[1365]: error: The expression evaluated to a falsy value: Jun 05 15:29:00 volumio volumio[1365]: assert.ok(self.idling) Jun 05 15:29:00 volumio volumio[1365]: error: updateQueue error: null Jun 05 15:29:00 volumio volumio[1365]: info: MPD running with PID1483 Jun 05 15:29:00 volumio volumio[1365]: ,establishing connection Jun 05 15:29:00 volumio volumio[1365]: error: updateQueue error: null Jun 05 15:29:01 volumio sudo[1406]: pam_unix(sudo:session): session closed for user root Jun 05 15:29:02 volumio volumio[1365]: info: Cannot mount NAS Storage at system boot, trial number 2 ,retrying in 5 seconds Jun 05 15:29:02 volumio volumio[1365]: info: Cannot mount NAS Storage at system boot, trial number 2 ,retrying in 5 seconds Jun 05 15:29:03 volumio volumio[1365]: verbose: New Socket.io Connection to 192.168.1.100 from 192.168.1.161 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 5 Jun 05 15:29:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 05 15:29:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 05 15:29:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 05 15:29:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 05 15:29:03 volumio volumio[1365]: info: CoreCommandRouter::volumioGetVisibleSources Jun 05 15:29:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 15:29:03 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState Jun 05 15:29:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 05 15:29:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 05 15:29:03 volumio volumio[1365]: info: Received Get System Info Jun 05 15:29:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 05 15:29:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 05 15:29:03 volumio volumio[1365]: info: Discovery: Getting this device information Jun 05 15:29:03 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState Jun 05 15:29:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 15:29:03 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState Jun 05 15:29:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:03 volumio volumio[1365]: info: Listing playlists Jun 05 15:29:05 volumio volumio[1365]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 05 15:29:05 volumio sudo[1552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 05 15:29:05 volumio sudo[1552]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:29:05 volumio sudo[1552]: pam_unix(sudo:session): session closed for user root Jun 05 15:29:05 volumio sudo[1554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 05 15:29:05 volumio sudo[1554]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:29:05 volumio sudo[1554]: pam_unix(sudo:session): session closed for user root Jun 05 15:29:05 volumio volumio[1365]: verbose: New Socket.io Connection to 192.168.1.100 from 192.168.1.161 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 5 Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::volumioGetVisibleSources Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState Jun 05 15:29:06 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 05 15:29:06 volumio volumio[1365]: info: Received Get System Info Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 05 15:29:06 volumio volumio[1365]: info: Discovery: Getting this device information Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState Jun 05 15:29:06 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState Jun 05 15:29:06 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:06 volumio volumio[1365]: info: Listing playlists Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::volumioGetBrowseSources Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::volumioGetBrowseSources Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::volumioGetBrowseSources Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 05 15:29:06 volumio volumio[1365]: info: VolumeController::SetAlsaVolume0 Jun 05 15:29:06 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:06 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 15:29:06 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:06 volumio sudo[1565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 05 15:29:06 volumio sudo[1565]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:29:06 volumio sudo[1565]: pam_unix(sudo:session): session closed for user root Jun 05 15:29:06 volumio sudo[1567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 05 15:29:06 volumio sudo[1567]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:29:06 volumio sudo[1567]: pam_unix(sudo:session): session closed for user root Jun 05 15:29:06 volumio volumio[1365]: verbose: New Socket.io Connection to 192.168.1.100 from 192.168.1.161 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 5 Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::volumioGetVisibleSources Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState Jun 05 15:29:07 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 05 15:29:07 volumio volumio[1365]: info: Received Get System Info Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 05 15:29:07 volumio volumio[1365]: info: Discovery: Getting this device information Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState Jun 05 15:29:07 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState Jun 05 15:29:07 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:07 volumio volumio[1365]: info: Listing playlists Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::volumioGetBrowseSources Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::volumioGetBrowseSources Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::volumioGetBrowseSources Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 05 15:29:07 volumio volumio[1365]: info: Cannot mount NAS Storage at system boot, trial number 3 ,retrying in 5 seconds Jun 05 15:29:07 volumio volumio[1365]: info: Cannot mount NAS Storage at system boot, trial number 3 ,retrying in 5 seconds Jun 05 15:29:07 volumio sudo[1591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 05 15:29:07 volumio sudo[1591]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:29:07 volumio sudo[1591]: pam_unix(sudo:session): session closed for user root Jun 05 15:29:07 volumio sudo[1593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 05 15:29:07 volumio sudo[1593]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:29:07 volumio sudo[1593]: pam_unix(sudo:session): session closed for user root Jun 05 15:29:07 volumio sudo[1597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 05 15:29:07 volumio sudo[1597]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:29:07 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 05 15:29:07 volumio sudo[1597]: pam_unix(sudo:session): session closed for user root Jun 05 15:29:07 volumio volumio[1365]: info: Upmpdcli Daemon Started Jun 05 15:29:07 volumio volumio[1599]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 05 15:29:07 volumio sudo[1616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 05 15:29:07 volumio sudo[1616]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:29:07 volumio sudo[1616]: pam_unix(sudo:session): session closed for user root Jun 05 15:29:07 volumio sudo[1618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 05 15:29:07 volumio sudo[1618]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:29:07 volumio sudo[1618]: pam_unix(sudo:session): session closed for user root Jun 05 15:29:07 volumio volumio[1365]: verbose: New Socket.io Connection to 192.168.1.100 from 192.168.1.161 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 5 Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::volumioGetVisibleSources Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState Jun 05 15:29:07 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 05 15:29:07 volumio volumio[1365]: info: Received Get System Info Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 05 15:29:07 volumio volumio[1365]: info: Discovery: Getting this device information Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState Jun 05 15:29:07 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState Jun 05 15:29:07 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:07 volumio volumio[1365]: info: Listing playlists Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::volumioGetBrowseSources Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::volumioGetBrowseSources Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::volumioGetBrowseSources Jun 05 15:29:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 05 15:29:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 05 15:29:08 volumio volumio[1365]: info: Adding plugin bluetooth to MyMusic Plugins Jun 05 15:29:08 volumio volumio[1365]: info: Adding plugin multiroom to MyMusic Plugins Jun 05 15:29:08 volumio volumio[1365]: info: Adding plugin metavolumio to MyMusic Plugins Jun 05 15:29:08 volumio volumio[1365]: info: Adding plugin cd_controller to MyMusic Plugins Jun 05 15:29:08 volumio volumio[1365]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 05 15:29:08 volumio volumio[1365]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 05 15:29:08 volumio volumio[1365]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 05 15:29:08 volumio volumio[1365]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 05 15:29:09 volumio volumio[1365]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 05 15:29:09 volumio volumio[1365]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 05 15:29:09 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 15:29:09 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 15:29:09 volumio volumio[1365]: info: Starting MyVolumio Remote Streaming Endpoints Jun 05 15:29:09 volumio volumio[1365]: info: MyVolumio not started Jun 05 15:29:09 volumio volumio[1365]: info: Initializing device activation check Jun 05 15:29:09 volumio volumio[1365]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 05 15:29:09 volumio volumio[1365]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 05 15:29:10 volumio volumio[1365]: info: Starting Streaming Service Transparent Proxy Jun 05 15:29:10 volumio volumio[1365]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 05 15:29:10 volumio volumio[1365]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 05 15:29:10 volumio volumio[1365]: info: Streaming services startup Jun 05 15:29:10 volumio volumio[1365]: info: Starting Streaming Daemon Jun 05 15:29:10 volumio sudo[1634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 05 15:29:10 volumio sudo[1634]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:29:10 volumio volumio[1365]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 05 15:29:10 volumio sudo[1634]: pam_unix(sudo:session): session closed for user root Jun 05 15:29:10 volumio volumio[1365]: error: Cannot start Volumio Streaming Daemon Jun 05 15:29:10 volumio volumio[1365]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 05 15:29:10 volumio volumio[1365]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 05 15:29:10 volumio sudo[1642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 05 15:29:10 volumio sudo[1642]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:29:10 volumio sudo[1642]: pam_unix(sudo:session): session closed for user root Jun 05 15:29:10 volumio sudo[1644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 05 15:29:10 volumio sudo[1644]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:29:10 volumio sudo[1644]: pam_unix(sudo:session): session closed for user root Jun 05 15:29:10 volumio sudo[1648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 05 15:29:10 volumio sudo[1648]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:29:10 volumio sudo[1648]: pam_unix(sudo:session): session closed for user root Jun 05 15:29:10 volumio sudo[1650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 05 15:29:10 volumio sudo[1650]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:29:10 volumio sudo[1650]: pam_unix(sudo:session): session closed for user root Jun 05 15:29:10 volumio volumio[1365]: verbose: New Socket.io Connection to 192.168.1.100 from 192.168.1.161 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 5 Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::volumioGetVisibleSources Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState Jun 05 15:29:10 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 15:29:10 volumio volumio[1365]: info: Retrieving Cloud Streaming UI Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 05 15:29:10 volumio volumio[1365]: info: Received Get System Info Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 05 15:29:10 volumio volumio[1365]: info: Discovery: Getting this device information Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState Jun 05 15:29:10 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState Jun 05 15:29:10 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:10 volumio volumio[1365]: info: Listing playlists Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 05 15:29:10 volumio volumio[1365]: info: Getting Tidal Cloud Configuration Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 15:29:10 volumio volumio[1365]: info: Getting Qobuz Cloud Configuration Jun 05 15:29:10 volumio volumio[1365]: info: Asking plugin for UI Config Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 15:29:10 volumio volumio[1365]: info: Getting Spotify Cloud Configuration Jun 05 15:29:10 volumio volumio[1365]: info: Asking plugin for UI Config Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 15:29:10 volumio volumio[1365]: info: Saving Spotify Acccount Jun 05 15:29:10 volumio volumio[1365]: info: Got Tidal Cloud Configuration Jun 05 15:29:10 volumio volumio[1365]: info: Got it Jun 05 15:29:10 volumio volumio[1365]: info: Got it Jun 05 15:29:10 volumio volumio[1365]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 15:29:10 volumio volumio[1365]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::volumioGetBrowseSources Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::volumioGetBrowseSources Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::volumioGetBrowseSources Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 05 15:29:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 05 15:29:11 volumio volumio[1365]: STREAMING PROXY: Starting server on port 3245 Jun 05 15:29:11 volumio volumio[1365]: Node JS runtime: 14 Jun 05 15:29:12 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 05 15:29:12 volumio volumio[1365]: info: Cannot mount NAS Storage at system boot, trial number 4 ,retrying in 5 seconds Jun 05 15:29:12 volumio volumio[1365]: info: Cannot mount NAS Storage at system boot, trial number 4 ,retrying in 5 seconds Jun 05 15:29:12 volumio systemd[1]: systemd-timedated.service: Succeeded. Jun 05 15:29:12 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 05 15:29:12 volumio volumio[1365]: info: Received Get System Info Jun 05 15:29:12 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 05 15:29:12 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 05 15:29:12 volumio volumio[1365]: info: Discovery: Getting this device information Jun 05 15:29:12 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState Jun 05 15:29:12 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:12 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 15:29:13 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jun 05 15:29:13 volumio volumio[1599]: ............................................................++++ Jun 05 15:29:13 volumio sudo[1662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/Storage Jun 05 15:29:13 volumio sudo[1662]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:29:13 volumio systemd[1]: mnt-NAS-Storage.mount: Succeeded. Jun 05 15:29:13 volumio systemd[1174]: mnt-NAS-Storage.mount: Succeeded. Jun 05 15:29:13 volumio sudo[1662]: pam_unix(sudo:session): session closed for user root Jun 05 15:29:13 volumio volumio[1365]: info: Share Storage successfully unmounted Jun 05 15:29:14 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 05 15:29:14 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Jun 05 15:29:16 volumio volumio[1365]: info: Jun 05 15:29:16 volumio volumio[1365]: ---------------------------- MPD announces state update: update Jun 05 15:29:16 volumio volumio[1365]: info: ControllerMpd::getState Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Jun 05 15:29:16 volumio volumio[1365]: info: Jun 05 15:29:16 volumio volumio[1365]: ---------------------------- MPD announces state update: update Jun 05 15:29:16 volumio volumio[1365]: info: ControllerMpd::getState Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Jun 05 15:29:16 volumio volumio[1365]: info: Jun 05 15:29:16 volumio volumio[1365]: ---------------------------- MPD announces state update: update Jun 05 15:29:16 volumio volumio[1365]: info: ControllerMpd::getState Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Jun 05 15:29:16 volumio volumio[1365]: info: [1749155356412] Database update started Jun 05 15:29:16 volumio volumio[1365]: info: MPD Database updated - AlbumList cache refreshed Jun 05 15:29:16 volumio volumio[1365]: info: Jun 05 15:29:16 volumio volumio[1365]: ---------------------------- MPD announces state update: database Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Jun 05 15:29:16 volumio volumio[1365]: info: Jun 05 15:29:16 volumio volumio[1365]: ---------------------------- MPD announces state update: update Jun 05 15:29:16 volumio volumio[1365]: info: ControllerMpd::getState Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Jun 05 15:29:16 volumio volumio[1365]: info: ControllerMpd::getState Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Jun 05 15:29:16 volumio volumio[1365]: info: MPD Database updated - AlbumList cache refreshed Jun 05 15:29:16 volumio volumio[1365]: info: Jun 05 15:29:16 volumio volumio[1365]: ---------------------------- MPD announces state update: database Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Jun 05 15:29:16 volumio volumio[1365]: info: Jun 05 15:29:16 volumio volumio[1365]: ---------------------------- MPD announces state update: update Jun 05 15:29:16 volumio volumio[1365]: info: ControllerMpd::getState Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Jun 05 15:29:16 volumio volumio[1365]: info: ControllerMpd::getState Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Jun 05 15:29:16 volumio volumio[1365]: info: MPD Database updated - AlbumList cache refreshed Jun 05 15:29:16 volumio volumio[1365]: info: Jun 05 15:29:16 volumio volumio[1365]: ---------------------------- MPD announces state update: database Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Jun 05 15:29:16 volumio volumio[1365]: info: Jun 05 15:29:16 volumio volumio[1365]: ---------------------------- MPD announces state update: update Jun 05 15:29:16 volumio volumio[1365]: info: ControllerMpd::getState Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Jun 05 15:29:16 volumio volumio[1365]: info: ControllerMpd::getState Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Jun 05 15:29:16 volumio volumio[1365]: info: sendMpdCommand status took 75 milliseconds Jun 05 15:29:16 volumio volumio[1365]: info: sendMpdCommand status took 59 milliseconds Jun 05 15:29:16 volumio volumio[1365]: info: sendMpdCommand status took 58 milliseconds Jun 05 15:29:16 volumio volumio[1365]: info: sendMpdCommand status took 57 milliseconds Jun 05 15:29:16 volumio volumio[1365]: info: sendMpdCommand status took 56 milliseconds Jun 05 15:29:16 volumio volumio[1365]: info: sendMpdCommand status took 15 milliseconds Jun 05 15:29:16 volumio volumio[1365]: info: sendMpdCommand status took 14 milliseconds Jun 05 15:29:16 volumio volumio[1365]: info: sendMpdCommand status took 13 milliseconds Jun 05 15:29:16 volumio volumio[1365]: info: sendMpdCommand status took 11 milliseconds Jun 05 15:29:16 volumio volumio[1365]: info: sendMpdCommand status took 10 milliseconds Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::parseState Jun 05 15:29:16 volumio volumio[1365]: info: Command Router : Notfying DB Updatefalse Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::Close All Modals sent Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::parseState Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::parseState Jun 05 15:29:16 volumio volumio[1365]: info: Command Router : Notfying DB Updatefalse Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::Close All Modals sent Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::parseState Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::parseState Jun 05 15:29:16 volumio volumio[1365]: info: Command Router : Notfying DB Updatefalse Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::Close All Modals sent Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::parseState Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::parseState Jun 05 15:29:16 volumio volumio[1365]: info: Command Router : Notfying DB Updatefalse Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::Close All Modals sent Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::parseState Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::parseState Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::parseState Jun 05 15:29:16 volumio volumio[1365]: info: Command Router : Notfying DB Updatefalse Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::Close All Modals sent Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::parseState Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::parseState Jun 05 15:29:16 volumio volumio[1365]: info: ControllerMpd::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: 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} Jun 05 15:29:16 volumio volumio[1365]: verbose: CURRENT POSITION 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::syncState stateService stop Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: No code Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: ControllerMpd::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: 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} Jun 05 15:29:16 volumio volumio[1365]: verbose: CURRENT POSITION 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::syncState stateService stop Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: No code Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: ControllerMpd::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: 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} Jun 05 15:29:16 volumio volumio[1365]: verbose: CURRENT POSITION 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::syncState stateService stop Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: No code Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: ControllerMpd::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: 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} Jun 05 15:29:16 volumio volumio[1365]: verbose: CURRENT POSITION 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::syncState stateService stop Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: No code Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: ControllerMpd::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: 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} Jun 05 15:29:16 volumio volumio[1365]: verbose: CURRENT POSITION 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::syncState stateService stop Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: No code Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: ControllerMpd::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: 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} Jun 05 15:29:16 volumio volumio[1365]: verbose: CURRENT POSITION 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::syncState stateService stop Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: No code Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: ControllerMpd::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: 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} Jun 05 15:29:16 volumio volumio[1365]: verbose: CURRENT POSITION 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::syncState stateService stop Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: No code Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: ------------------------------ 298ms Jun 05 15:29:16 volumio volumio[1365]: info: ------------------------------ 281ms Jun 05 15:29:16 volumio volumio[1365]: info: ------------------------------ 280ms Jun 05 15:29:16 volumio volumio[1365]: info: ------------------------------ 240ms Jun 05 15:29:16 volumio volumio[1365]: info: ------------------------------ 239ms Jun 05 15:29:16 volumio volumio[1365]: info: ------------------------------ 238ms Jun 05 15:29:16 volumio volumio[1365]: info: ------------------------------ 239ms Jun 05 15:29:16 volumio volumio[1365]: info: sendMpdCommand status took 236 milliseconds Jun 05 15:29:16 volumio volumio[1365]: info: sendMpdCommand status took 233 milliseconds Jun 05 15:29:16 volumio volumio[1365]: info: sendMpdCommand status took 233 milliseconds Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::parseState Jun 05 15:29:16 volumio volumio[1365]: info: Command Router : Notfying DB Updatefalse Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::Close All Modals sent Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::parseState Jun 05 15:29:16 volumio volumio[1365]: verbose: ControllerMpd::parseState Jun 05 15:29:16 volumio volumio[1365]: info: ControllerMpd::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: 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} Jun 05 15:29:16 volumio volumio[1365]: verbose: CURRENT POSITION 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::syncState stateService stop Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: No code Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: ControllerMpd::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: 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} Jun 05 15:29:16 volumio volumio[1365]: verbose: CURRENT POSITION 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::syncState stateService stop Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: No code Jun 05 15:29:16 volumio volumio[1365]: info: CoreStateMachine::pushState Jun 05 15:29:16 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Jun 05 15:29:16 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Jun 05 15:29:16 volumio volumio[1365]: info: ------------------------------ 288ms Jun 05 15:29:16 volumio volumio[1365]: info: ------------------------------ 288ms Jun 05 15:29:16 volumio volumio[1599]: .....................................++++ Jun 05 15:29:16 volumio volumio[1599]: e is 65537 (0x010001) Jun 05 15:29:16 volumio volumio[1599]: writing RSA key Jun 05 15:29:17 volumio volumio[1365]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 05 15:29:17 volumio volumio[1365]: TypeError: Cannot read property 'replace' of undefined Jun 05 15:29:17 volumio volumio[1365]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Jun 05 15:29:17 volumio volumio[1365]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Jun 05 15:29:17 volumio volumio[1365]: at listOnTimeout (internal/timers.js:554:17) Jun 05 15:29:17 volumio volumio[1365]: at processTimers (internal/timers.js:497:7) Jun 05 15:29:17 volumio volumio[1365]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 05 15:29:17 volumio sudo[1721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-05 15:28 Jun 05 15:29:17 volumio sudo[1721]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"