-- Logs begin at Fri 2025-07-11 16:42:55 CEST, end at Fri 2025-07-11 19:42:34 CEST. -- Jul 11 19:42:10 volumio-taverna systemd-timedated[1002]: Changed local time to Fri Jul 11 19:42:10 2025 Jul 11 19:42:10 volumio-taverna sudo[1000]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:10 volumio-taverna volumio-time-update[521]: volumio-time-update-util: System time updated successfully. Jul 11 19:42:10 volumio-taverna systemd[1]: Started Volumio Time Update Utility. Jul 11 19:42:10 volumio-taverna ntpd[876]: Soliciting pool server 185.221.173.236 Jul 11 19:42:10 volumio-taverna ntpd[876]: Soliciting pool server 89.46.74.148 Jul 11 19:42:10 volumio-taverna wireless.js[526]: trying... Jul 11 19:42:10 volumio-taverna sudo[1009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 19:42:10 volumio-taverna sudo[1009]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:10 volumio-taverna sudo[1009]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:11 volumio-taverna ntpd[876]: Soliciting pool server 185.157.229.254 Jul 11 19:42:11 volumio-taverna volumio-remote-updater[534]: [2025-07-11 19:42:11] [info] asio async_connect error: system:111 (Connection refused) Jul 11 19:42:11 volumio-taverna volumio-remote-updater[534]: [2025-07-11 19:42:11] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 11 19:42:11 volumio-taverna volumio-remote-updater[534]: [2025-07-11 19:42:11] [error] handle_connect error: Underlying Transport Error Jul 11 19:42:11 volumio-taverna wireless.js[526]: trying... Jul 11 19:42:11 volumio-taverna sudo[1013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 19:42:11 volumio-taverna sudo[1013]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:11 volumio-taverna sudo[1013]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:12 volumio-taverna wpa_supplicant[921]: wlan0: Trying to associate with SSID 'ringhio' Jul 11 19:42:12 volumio-taverna dhcpcd[988]: wlan0: carrier acquired Jul 11 19:42:12 volumio-taverna wpa_supplicant[921]: wlan0: Associated with 60:32:b1:04:55:86 Jul 11 19:42:12 volumio-taverna wpa_supplicant[921]: wlan0: CTRL-EVENT-CONNECTED - Connection to 60:32:b1:04:55:86 completed [id=0 id_str=] Jul 11 19:42:12 volumio-taverna wpa_supplicant[921]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 11 19:42:12 volumio-taverna wpa_supplicant[921]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Jul 11 19:42:12 volumio-taverna dhcpcd[988]: wlan0: IAID eb:2a:39:fc Jul 11 19:42:12 volumio-taverna dhcpcd[988]: wlan0: adding address fe80::ba27:ebff:fe2a:39fc Jul 11 19:42:12 volumio-taverna dhcpcd[988]: ipv6_addaddr1: Permission denied Jul 11 19:42:12 volumio-taverna dhcpcd[988]: wlan0: carrier lost Jul 11 19:42:12 volumio-taverna dhcpcd[988]: wlan0: carrier acquired Jul 11 19:42:12 volumio-taverna dhcpcd[988]: wlan0: connected to Access Point `ringhio' Jul 11 19:42:12 volumio-taverna dhcpcd[988]: wlan0: IAID eb:2a:39:fc Jul 11 19:42:12 volumio-taverna wpa_supplicant[921]: RRM: Ignoring radio measurement request: Not RRM network Jul 11 19:42:12 volumio-taverna dhcpcd[988]: wlan0: rebinding lease of 192.168.178.85 Jul 11 19:42:12 volumio-taverna dhcpcd[988]: wlan0: probing address 192.168.178.85/24 Jul 11 19:42:12 volumio-taverna wireless.js[526]: trying... Jul 11 19:42:12 volumio-taverna sudo[1041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 19:42:12 volumio-taverna sudo[1041]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:12 volumio-taverna sudo[1041]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:13 volumio-taverna dhcpcd[988]: wlan0: soliciting an IPv6 router Jul 11 19:42:13 volumio-taverna wireless.js[526]: trying... Jul 11 19:42:13 volumio-taverna sudo[1044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 19:42:13 volumio-taverna sudo[1044]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:13 volumio-taverna sudo[1044]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:14 volumio-taverna wireless.js[526]: trying... Jul 11 19:42:14 volumio-taverna sudo[1047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 19:42:14 volumio-taverna sudo[1047]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:14 volumio-taverna sudo[1047]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:15 volumio-taverna wireless.js[526]: trying... Jul 11 19:42:15 volumio-taverna sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 19:42:15 volumio-taverna sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:15 volumio-taverna sudo[1050]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:17 volumio-taverna ntpd[876]: receive: Unexpected origin timestamp 0xec1bcb78.5c6eb7ab does not match aorg 0000000000.00000000 from server@185.19.184.35 xmt 0xec1bcb79.7a51e990 Jul 11 19:42:17 volumio-taverna ntpd[876]: receive: Unexpected origin timestamp 0xec1bcb78.5c70b5a2 does not match aorg 0000000000.00000000 from server@195.32.70.195 xmt 0xec1bcb79.79d883ba Jul 11 19:42:17 volumio-taverna ntpd[876]: receive: Unexpected origin timestamp 0xec1bcb78.5c6db263 does not match aorg 0000000000.00000000 from server@31.14.133.122 xmt 0xec1bcb79.7a99027b Jul 11 19:42:17 volumio-taverna ntpd[876]: receive: Unexpected origin timestamp 0xec1bcb78.5c6c7e1c does not match aorg 0000000000.00000000 from server@185.221.173.236 xmt 0xec1bcb79.7e050860 Jul 11 19:42:18 volumio-taverna wireless.js[526]: trying... Jul 11 19:42:18 volumio-taverna sudo[1053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 19:42:18 volumio-taverna sudo[1053]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:18 volumio-taverna sudo[1053]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:19 volumio-taverna wireless.js[526]: trying... Jul 11 19:42:19 volumio-taverna sudo[1056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 19:42:19 volumio-taverna sudo[1056]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:19 volumio-taverna sudo[1056]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:19 volumio-taverna dhcpcd[988]: wlan0: leased 192.168.178.85 for 864000 seconds Jul 11 19:42:19 volumio-taverna avahi-daemon[537]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.85. Jul 11 19:42:19 volumio-taverna avahi-daemon[537]: New relevant interface wlan0.IPv4 for mDNS. Jul 11 19:42:19 volumio-taverna avahi-daemon[537]: Registering new address record for 192.168.178.85 on wlan0.IPv4. Jul 11 19:42:19 volumio-taverna dhcpcd[988]: wlan0: adding route to 192.168.178.0/24 Jul 11 19:42:19 volumio-taverna dhcpcd[988]: wlan0: adding default route via 192.168.178.1 Jul 11 19:42:19 volumio-taverna ntpd[876]: ntpd exiting on signal 15 (Terminated) Jul 11 19:42:19 volumio-taverna systemd[1]: Stopping Network Time Service... Jul 11 19:42:19 volumio-taverna ntpd[876]: 192.168.178.1 local addr 192.168.178.27 -> Jul 11 19:42:19 volumio-taverna ntpd[876]: 195.32.70.195 local addr 192.168.178.27 -> Jul 11 19:42:19 volumio-taverna ntpd[876]: 212.45.144.206 local addr 192.168.178.27 -> Jul 11 19:42:19 volumio-taverna ntpd[876]: 185.19.184.35 local addr 192.168.178.27 -> Jul 11 19:42:19 volumio-taverna ntpd[876]: 31.14.133.122 local addr 192.168.178.27 -> Jul 11 19:42:19 volumio-taverna ntpd[876]: 81.56.230.156 local addr 192.168.178.27 -> Jul 11 19:42:19 volumio-taverna ntpd[876]: 95.110.254.234 local addr 192.168.178.27 -> Jul 11 19:42:19 volumio-taverna ntpd[876]: 93.94.88.50 local addr 192.168.178.27 -> Jul 11 19:42:19 volumio-taverna systemd[1]: ntp.service: Succeeded. Jul 11 19:42:19 volumio-taverna ntpd[876]: 212.45.144.3 local addr 192.168.178.27 -> Jul 11 19:42:19 volumio-taverna ntpd[876]: 89.46.74.148 local addr 192.168.178.27 -> Jul 11 19:42:19 volumio-taverna ntpd[876]: 185.221.173.236 local addr 192.168.178.27 -> Jul 11 19:42:19 volumio-taverna ntpd[876]: 185.157.229.254 local addr 192.168.178.27 -> Jul 11 19:42:19 volumio-taverna systemd[1]: Stopped Network Time Service. Jul 11 19:42:19 volumio-taverna systemd[1]: Starting Network Time Service... Jul 11 19:42:19 volumio-taverna ntpd[1090]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 11 19:42:19 volumio-taverna ntpd[1090]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Jul 11 19:42:19 volumio-taverna systemd[1]: Started Network Time Service. Jul 11 19:42:19 volumio-taverna ntpd[1096]: proto: precision = 0.625 usec (-21) Jul 11 19:42:19 volumio-taverna ntpd[1096]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 11 19:42:19 volumio-taverna ntpd[1096]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 11 19:42:19 volumio-taverna ntpd[1096]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 196 days ago Jul 11 19:42:19 volumio-taverna ntpd[1096]: Listen and drop on 0 v6wildcard [::]:123 Jul 11 19:42:19 volumio-taverna ntpd[1096]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 11 19:42:19 volumio-taverna ntpd[1096]: Listen normally on 2 lo 127.0.0.1:123 Jul 11 19:42:19 volumio-taverna ntpd[1096]: Listen normally on 3 eth0 192.168.178.27:123 Jul 11 19:42:19 volumio-taverna ntpd[1096]: Listen normally on 4 wlan0 192.168.178.85:123 Jul 11 19:42:19 volumio-taverna ntpd[1096]: Listening on routing socket on fd #21 for interface updates Jul 11 19:42:19 volumio-taverna ntpd[1096]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Jul 11 19:42:19 volumio-taverna ntpd[1096]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Jul 11 19:42:19 volumio-taverna wpa_supplicant[921]: RRM: Ignoring radio measurement request: Not RRM network Jul 11 19:42:20 volumio-taverna wireless.js[526]: trying... Jul 11 19:42:20 volumio-taverna sudo[1099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 19:42:20 volumio-taverna sudo[1099]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:20 volumio-taverna sudo[1099]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:20 volumio-taverna wireless.js[526]: Connected to: ----ringhio Jul 11 19:42:20 volumio-taverna wireless.js[526]: ---- Jul 11 19:42:20 volumio-taverna sudo[1102]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 11 19:42:20 volumio-taverna sudo[1102]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:20 volumio-taverna sudo[1102]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:20 volumio-taverna wireless.js[526]: ... joined AP, wlan0 IPv4 is 192.168.178.85, ipV6 is undefined Jul 11 19:42:20 volumio-taverna wireless.js[526]: It's done! AP Jul 11 19:42:20 volumio-taverna systemd[1]: Started Wireless Services. Jul 11 19:42:20 volumio-taverna systemd[1]: Started Volumio Backend Module. Jul 11 19:42:20 volumio-taverna kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 11 19:42:20 volumio-taverna systemd[1]: Started Volumio Cpu Tweaker. Jul 11 19:42:20 volumio-taverna systemd[1]: Reached target Multi-User System. Jul 11 19:42:20 volumio-taverna systemd[1]: Reached target Graphical Interface. Jul 11 19:42:20 volumio-taverna systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 11 19:42:20 volumio-taverna volumio-cpu-tweak[1108]: Setting RT Priority for mpd Jul 11 19:42:20 volumio-taverna systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 11 19:42:20 volumio-taverna systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 11 19:42:20 volumio-taverna systemd[1]: Startup finished in 13.231s (kernel) + 25.077s (userspace) = 38.308s. Jul 11 19:42:20 volumio-taverna volumio-cpu-tweak[1108]: Setting MPD Affinity Jul 11 19:42:20 volumio-taverna volumio-cpu-tweak[1108]: pid 782's current affinity mask: f Jul 11 19:42:20 volumio-taverna volumio-cpu-tweak[1108]: pid 782's new affinity mask: 3 Jul 11 19:42:20 volumio-taverna volumio-cpu-tweak[1108]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jul 11 19:42:20 volumio-taverna volumio-cpu-tweak[1108]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jul 11 19:42:20 volumio-taverna systemd[1]: volumio_cpu_tweak.service: Succeeded. Jul 11 19:42:20 volumio-taverna ntpd[1096]: Soliciting pool server 195.32.70.195 Jul 11 19:42:21 volumio-taverna ntpd[1096]: Soliciting pool server 212.45.144.206 Jul 11 19:42:21 volumio-taverna ntpd[1096]: Soliciting pool server 185.19.184.35 Jul 11 19:42:22 volumio-taverna ntpd[1096]: Soliciting pool server 81.56.230.156 Jul 11 19:42:22 volumio-taverna ntpd[1096]: Soliciting pool server 31.14.133.122 Jul 11 19:42:22 volumio-taverna ntpd[1096]: Soliciting pool server 95.231.207.178 Jul 11 19:42:22 volumio-taverna volumio[1107]: info: ------------------------------------------- Jul 11 19:42:22 volumio-taverna volumio[1107]: info: ----- Volumio3 ---- Jul 11 19:42:22 volumio-taverna volumio[1107]: info: ------------------------------------------- Jul 11 19:42:22 volumio-taverna volumio[1107]: info: ----- System startup ---- Jul 11 19:42:22 volumio-taverna volumio[1107]: info: ------------------------------------------- Jul 11 19:42:23 volumio-taverna volumio-remote-updater[534]: [2025-07-11 19:42:23] [connect] Successful connection Jul 11 19:42:23 volumio-taverna ntpd[1096]: Soliciting pool server 95.110.254.234 Jul 11 19:42:23 volumio-taverna ntpd[1096]: Soliciting pool server 93.94.88.50 Jul 11 19:42:23 volumio-taverna ntpd[1096]: Soliciting pool server 212.45.144.3 Jul 11 19:42:23 volumio-taverna volumio[1107]: info: MYVOLUMIO Environment detected Jul 11 19:42:23 volumio-taverna volumio[1107]: info: Plugin folders cleanup Jul 11 19:42:23 volumio-taverna volumio[1107]: info: Scanning into folder /volumio/app/plugins/ Jul 11 19:42:23 volumio-taverna volumio[1107]: info: Scanning category audio_interface Jul 11 19:42:23 volumio-taverna volumio[1107]: info: Scanning category miscellanea Jul 11 19:42:23 volumio-taverna volumio[1107]: info: Scanning category music_service Jul 11 19:42:23 volumio-taverna volumio[1107]: info: Scanning category plugins.json Jul 11 19:42:23 volumio-taverna volumio[1107]: info: Scanning category system_controller Jul 11 19:42:23 volumio-taverna volumio[1107]: info: Scanning category user_interface Jul 11 19:42:23 volumio-taverna volumio[1107]: info: Scanning into folder /data/plugins/ Jul 11 19:42:23 volumio-taverna volumio[1107]: info: Scanning category audio_interface Jul 11 19:42:23 volumio-taverna volumio[1107]: info: Scanning category music_service Jul 11 19:42:23 volumio-taverna volumio[1107]: info: Scanning category system_controller Jul 11 19:42:23 volumio-taverna volumio[1107]: info: Plugin folders cleanup completed Jul 11 19:42:23 volumio-taverna volumio[1107]: info: ------------------------------------------- Jul 11 19:42:23 volumio-taverna volumio[1107]: info: ----- Core plugins startup ---- Jul 11 19:42:23 volumio-taverna volumio[1107]: info: ------------------------------------------- Jul 11 19:42:23 volumio-taverna volumio[1107]: info: Loading plugins from folder /volumio/app/plugins/ Jul 11 19:42:23 volumio-taverna volumio[1107]: info: Adding plugin upnp to MyMusic Plugins Jul 11 19:42:23 volumio-taverna volumio[1107]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 11 19:42:23 volumio-taverna volumio[1107]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 11 19:42:23 volumio-taverna volumio[1107]: info: Loading plugins from folder /data/plugins/ Jul 11 19:42:23 volumio-taverna volumio[1107]: info: Loading plugin "system"... Jul 11 19:42:23 volumio-taverna volumio[1107]: info: Loading plugin "appearance"... Jul 11 19:42:24 volumio-taverna ntpd[1096]: Soliciting pool server 185.221.173.236 Jul 11 19:42:24 volumio-taverna ntpd[1096]: Soliciting pool server 89.46.74.148 Jul 11 19:42:25 volumio-taverna ntpd[1096]: Soliciting pool server 185.157.229.254 Jul 11 19:42:25 volumio-taverna volumio[1107]: info: Loading plugin "network"... Jul 11 19:42:25 volumio-taverna volumio[1107]: info: Refreshing Cached IP Addresses Jul 11 19:42:25 volumio-taverna sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 11 19:42:25 volumio-taverna sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:25 volumio-taverna sudo[1142]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:25 volumio-taverna sudo[1144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 11 19:42:25 volumio-taverna sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:25 volumio-taverna sudo[1144]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:25 volumio-taverna volumio[1107]: info: Loading plugin "services"... Jul 11 19:42:25 volumio-taverna volumio[1107]: info: Loading plugin "alsa_controller"... Jul 11 19:42:25 volumio-taverna sudo[1153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 11 19:42:25 volumio-taverna sudo[1153]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:25 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 11 19:42:25 volumio-taverna volumio[1107]: info: Loading plugin "wizard"... Jul 11 19:42:25 volumio-taverna volumio[1107]: info: Loading plugin "networkfs"... Jul 11 19:42:25 volumio-taverna volumio[1107]: info: Starting Udev Watcher for removable devices Jul 11 19:42:25 volumio-taverna sudo[1170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=nas,password=seinetta4611,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.24/Music /mnt/NAS/NAS Jul 11 19:42:25 volumio-taverna sudo[1170]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:25 volumio-taverna volumio[1107]: info: Ignoring mount for partition: boot Jul 11 19:42:25 volumio-taverna volumio[1107]: info: Ignoring mount for partition: volumio Jul 11 19:42:25 volumio-taverna volumio[1107]: info: Ignoring mount for partition: volumio_data Jul 11 19:42:25 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 11 19:42:25 volumio-taverna volumio[1107]: info: Loading plugin "volumio_command_line_client"... Jul 11 19:42:25 volumio-taverna volumio[1107]: info: Loading plugin "upnp"... Jul 11 19:42:25 volumio-taverna volumio[1107]: info: [1752255745726] Starting Upmpd Daemon Jul 11 19:42:25 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 11 19:42:25 volumio-taverna volumio[1107]: info: Loading plugin "my_music"... Jul 11 19:42:25 volumio-taverna volumio[1107]: info: Loading plugin "mpd"... Jul 11 19:42:25 volumio-taverna kernel: Key type cifs.spnego registered Jul 11 19:42:25 volumio-taverna kernel: Key type cifs.idmap registered Jul 11 19:42:25 volumio-taverna kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jul 11 19:42:25 volumio-taverna kernel: CIFS: Attempting to mount //192.168.178.24/Music Jul 11 19:42:26 volumio-taverna wpa_supplicant[921]: RRM: Ignoring radio measurement request: Not RRM network Jul 11 19:42:26 volumio-taverna kernel: cryptd: max_cpu_qlen set to 1000 Jul 11 19:42:26 volumio-taverna sudo[1170]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:26 volumio-taverna volumio[1107]: info: Loading plugin "upnp_browser"... Jul 11 19:42:26 volumio-taverna volumio[1107]: info: Loading plugin "alarm-clock"... Jul 11 19:42:26 volumio-taverna volumio[1107]: info: Loading plugin "airplay_emulation"... Jul 11 19:42:26 volumio-taverna volumio[1107]: info: Starting Shairport Sync Jul 11 19:42:26 volumio-taverna volumio[1107]: info: Loading plugin "last_100"... Jul 11 19:42:26 volumio-taverna volumio[1107]: info: Loading plugin "webradio"... Jul 11 19:42:27 volumio-taverna volumio[1107]: info: Loading plugin "i2s_dacs"... Jul 11 19:42:27 volumio-taverna volumio[1107]: info: Loading plugin "volumiodiscovery"... Jul 11 19:42:27 volumio-taverna volumio[1107]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 11 19:42:27 volumio-taverna volumio[1107]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 11 19:42:27 volumio-taverna volumio[1107]: *** WARNING *** For more information see Jul 11 19:42:27 volumio-taverna node[1107]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 11 19:42:27 volumio-taverna volumio[1107]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 11 19:42:27 volumio-taverna volumio[1107]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 11 19:42:27 volumio-taverna volumio[1107]: *** WARNING *** For more information see Jul 11 19:42:27 volumio-taverna node[1107]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 11 19:42:27 volumio-taverna node[1107]: *** WARNING *** For more information see Jul 11 19:42:27 volumio-taverna node[1107]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 11 19:42:27 volumio-taverna node[1107]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 11 19:42:27 volumio-taverna node[1107]: *** WARNING *** For more information see Jul 11 19:42:27 volumio-taverna volumio[1107]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 11 19:42:27 volumio-taverna volumio[1107]: info: Discovery: Started advertising with name: Volumio taverna Jul 11 19:42:27 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 11 19:42:27 volumio-taverna volumio[1107]: info: Loading plugin "spop"... Jul 11 19:42:28 volumio-taverna systemd[1]: systemd-fsckd.service: Succeeded. Jul 11 19:42:28 volumio-taverna volumio[1107]: info: Loading plugin "outputs"... Jul 11 19:42:28 volumio-taverna volumio[1107]: info: Loading plugin "albumart"... Jul 11 19:42:28 volumio-taverna volumio[1107]: info: Plugin example_plugin is not enabled Jul 11 19:42:28 volumio-taverna volumio[1107]: info: Loading plugin "inputs"... Jul 11 19:42:28 volumio-taverna volumio[1107]: info: Loading plugin "updater_comm"... Jul 11 19:42:29 volumio-taverna sudo[1153]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:29 volumio-taverna volumio[1107]: info: Plugin mpdemulation is not enabled Jul 11 19:42:29 volumio-taverna volumio[1107]: info: Loading plugin "rest_api"... Jul 11 19:42:29 volumio-taverna volumio[1107]: info: Loading plugin "websocket"... Jul 11 19:42:29 volumio-taverna volumio[1107]: info: Starting Socket.io Server version 2.3.0 Jul 11 19:42:29 volumio-taverna volumio[1107]: info: Loading plugin "fusiondsp"... Jul 11 19:42:29 volumio-taverna volumio[1107]: Forking 3 albumart workers Jul 11 19:42:29 volumio-taverna volumio[1107]: info: Applying required configuration parameters for plugin fusiondsp Jul 11 19:42:29 volumio-taverna volumio[1107]: info: Loading plugin "backup_restore"... Jul 11 19:42:30 volumio-taverna volumio[1107]: Starting albumart workers Jul 11 19:42:30 volumio-taverna volumio[1107]: Starting albumart workers Jul 11 19:42:30 volumio-taverna volumio[1107]: Starting albumart workers Jul 11 19:42:30 volumio-taverna volumio[1107]: info: Applying required configuration parameters for plugin backup_restore Jul 11 19:42:30 volumio-taverna volumio[1107]: info: Loading i18n strings for locale it Jul 11 19:42:30 volumio-taverna volumio[1107]: Updating browse sources language Jul 11 19:42:30 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 19:42:30 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 19:42:30 volumio-taverna volumio[1107]: info: CoreCommandRouter::initPlayerControls Jul 11 19:42:30 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:30 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:30 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:30 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:30 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 19:42:30 volumio-taverna volumio[1107]: Express server listening on port 3000 Jul 11 19:42:30 volumio-taverna volumio[1107]: [Metrics] WebUI: 9s 97.98ms Jul 11 19:42:30 volumio-taverna volumio[1107]: info: CoreStateMachine::resetVolumioState Jul 11 19:42:30 volumio-taverna volumio[1107]: info: CoreStateMachine::getcurrentVolume Jul 11 19:42:30 volumio-taverna volumio[1107]: info: CoreCommandRouter::volumioRetrievevolume Jul 11 19:42:31 volumio-taverna volumio[1107]: info: Volumio Network Manager: Network status updated: 3 Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 19:42:31 volumio-taverna volumio[1107]: info: Reloading queue from file Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreStateMachine::setRepeat false single undefined Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreStateMachine::pushState Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CorePlayQueue::getTrack 0 Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::volumioPushState Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreStateMachine::setRandom null Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreStateMachine::pushState Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CorePlayQueue::getTrack 0 Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::volumioPushState Jul 11 19:42:31 volumio-taverna volumio[1107]: info: Setting Device type: Raspberry PI Jul 11 19:42:31 volumio-taverna volumio[1107]: info: Completed loading Core Plugins Jul 11 19:42:31 volumio-taverna volumio[1107]: info: Preparing to generate the ALSA configuration file Jul 11 19:42:31 volumio-taverna volumio[1107]: info: VolumeController:: Volume=46 Mute =false Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreStateMachine::pushState Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CorePlayQueue::getTrack 0 Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::volumioPushState Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreStateMachine::updateTrackBlock Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CorePlayQueue::getTrackBlock Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::volumioRetrievevolume Jul 11 19:42:31 volumio-taverna volumio[1107]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 11 19:42:31 volumio-taverna volumio[1107]: info: Reading ALSA contributions from plugins. Jul 11 19:42:31 volumio-taverna volumio[1107]: info: Discovery: adding 60ac0205-743d-4b45-8a29-faa5ba2140df Jul 11 19:42:31 volumio-taverna volumio[1107]: info: Discovery: Found device Volumio taverna Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::volumioGetState Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CorePlayQueue::getTrack 0 Jul 11 19:42:31 volumio-taverna volumio[1107]: info: Discovery: this is already registered, 60ac0205-743d-4b45-8a29-faa5ba2140df Jul 11 19:42:31 volumio-taverna volumio[1107]: info: Discovery: Found device Volumio taverna Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::volumioGetState Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CorePlayQueue::getTrack 0 Jul 11 19:42:31 volumio-taverna volumio[1107]: info: Asound.conf file unchanged, so no further update is needed Jul 11 19:42:31 volumio-taverna volumio[1107]: info: Output device has changed, restarting MPD Jul 11 19:42:31 volumio-taverna volumio[1107]: info: Output device has changed, restarting Shairport Sync Jul 11 19:42:31 volumio-taverna sudo[1276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 11 19:42:31 volumio-taverna sudo[1276]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 19:42:31 volumio-taverna sudo[1276]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:31 volumio-taverna sudo[1279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 11 19:42:31 volumio-taverna sudo[1279]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:31 volumio-taverna systemd[1]: Stopping Music Player Daemon... Jul 11 19:42:31 volumio-taverna volumio[1107]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 11 19:42:31 volumio-taverna volumio[1107]: info: ___________ START PLUGINS ___________ Jul 11 19:42:31 volumio-taverna volumio[1107]: info: ControllerMpd::onStart: Initializing MPD Jul 11 19:42:31 volumio-taverna volumio[1107]: info: Creating MPD Configuration file Jul 11 19:42:31 volumio-taverna systemd[1]: systemd-hostnamed.service: Succeeded. Jul 11 19:42:31 volumio-taverna sudo[1285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 11 19:42:31 volumio-taverna sudo[1285]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 11 19:42:31 volumio-taverna volumio[1107]: info: [1752255751536] CoreMusicLibrary::Adding element Server multimediali Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 19:42:31 volumio-taverna sudo[1285]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:31 volumio-taverna sudo[1289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 11 19:42:31 volumio-taverna sudo[1289]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 19:42:31 volumio-taverna volumio[1107]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 19:42:31 volumio-taverna systemd[1]: mpd.service: Succeeded. Jul 11 19:42:31 volumio-taverna systemd[1]: Stopped Music Player Daemon. Jul 11 19:42:31 volumio-taverna volumio[1107]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 11 19:42:31 volumio-taverna volumio[1107]: info: [1752255751684] CoreMusicLibrary::Adding element Last_100 Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 11 19:42:31 volumio-taverna volumio[1107]: info: [1752255751687] CoreMusicLibrary::Adding element Webradio Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 11 19:42:31 volumio-taverna volumio[1107]: info: Initializing BBC Radios Jul 11 19:42:31 volumio-taverna systemd[1]: Starting Music Player Daemon... Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 19:42:31 volumio-taverna volumio[1107]: info: Creating Spotify config file Jul 11 19:42:31 volumio-taverna sudo[1298]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 11 19:42:31 volumio-taverna sudo[1298]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:31 volumio-taverna sudo[1298]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:31 volumio-taverna volumio[1107]: info: Loading i18n strings for locale it Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 11 19:42:31 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:31 volumio-taverna volumio[1107]: info: FusionDsp - mixtype--------------------- Hardware Jul 11 19:42:31 volumio-taverna volumio[1107]: info: Volumio Calling Home Jul 11 19:42:31 volumio-taverna volumio[1107]: info: Preparing to generate the ALSA configuration file Jul 11 19:42:32 volumio-taverna wpa_supplicant[921]: RRM: Ignoring radio measurement request: Not RRM network Jul 11 19:42:32 volumio-taverna volumio[1107]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 11 19:42:32 volumio-taverna volumio[1107]: info: Reading ALSA contributions from plugins. Jul 11 19:42:32 volumio-taverna volumio[1107]: info: MPD Permissions set Jul 11 19:42:32 volumio-taverna volumio[1107]: info: MPD Permissions set Jul 11 19:42:32 volumio-taverna volumio[1107]: info: VolumeController:: Volume=46 Mute =false Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreStateMachine::pushState Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CorePlayQueue::getTrack 0 Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::volumioPushState Jul 11 19:42:32 volumio-taverna volumio[1107]: info: Spotify config file written Jul 11 19:42:32 volumio-taverna sudo[1316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 11 19:42:32 volumio-taverna sudo[1316]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:32 volumio-taverna volumio[1107]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna systemd[1]: Started go-librespot Daemon. Jul 11 19:42:32 volumio-taverna go-librespot[1318]: Librespot-go daemon starting... Jul 11 19:42:32 volumio-taverna sudo[1316]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:32 volumio-taverna volumio[1107]: info: Volumio called home Jul 11 19:42:32 volumio-taverna volumio[1107]: info: Starting Shairport Sync Jul 11 19:42:32 volumio-taverna volumio[1107]: info: Starting Shairport Sync Jul 11 19:42:32 volumio-taverna volumio[1107]: info: Starting Shairport Sync Jul 11 19:42:32 volumio-taverna sudo[1334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 11 19:42:32 volumio-taverna sudo[1334]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:32 volumio-taverna sudo[1336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 11 19:42:32 volumio-taverna sudo[1336]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:32 volumio-taverna sudo[1339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 11 19:42:32 volumio-taverna sudo[1339]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:32 volumio-taverna systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 11 19:42:32 volumio-taverna systemd[1]: shairport-sync.service: Succeeded. Jul 11 19:42:32 volumio-taverna systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::volumioGetState Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CorePlayQueue::getTrack 0 Jul 11 19:42:32 volumio-taverna systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 11 19:42:32 volumio-taverna sudo[1334]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:32 volumio-taverna sudo[1336]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:32 volumio-taverna systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 11 19:42:32 volumio-taverna systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 11 19:42:32 volumio-taverna systemd[1]: shairport-sync.service: Succeeded. Jul 11 19:42:32 volumio-taverna systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 11 19:42:32 volumio-taverna systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 11 19:42:32 volumio-taverna sudo[1339]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:32 volumio-taverna volumio[1107]: info: Asound.conf file unchanged, so no further update is needed Jul 11 19:42:32 volumio-taverna volumio[1107]: info: Output device has changed, restarting MPD Jul 11 19:42:32 volumio-taverna volumio[1107]: info: Output device has changed, restarting Shairport Sync Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 19:42:32 volumio-taverna sudo[1347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 11 19:42:32 volumio-taverna sudo[1347]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:32 volumio-taverna sudo[1347]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:32 volumio-taverna sudo[1349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 11 19:42:32 volumio-taverna sudo[1349]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:32 volumio-taverna systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 11 19:42:32 volumio-taverna systemd[1]: mpd.service: Succeeded. Jul 11 19:42:32 volumio-taverna volumio[1107]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 11 19:42:32 volumio-taverna systemd[1]: Stopped Music Player Daemon. Jul 11 19:42:32 volumio-taverna go-librespot[1318]: time="2025-07-11T19:42:32+02:00" level=info msg="generated new device id: 7d417182ac0f7b70c72e5e389dc0bfc5e13f809b" Jul 11 19:42:32 volumio-taverna go-librespot[1318]: time="2025-07-11T19:42:32+02:00" level=debug msg="stored credentials found for mugello46" Jul 11 19:42:32 volumio-taverna volumio[1107]: info: Shairport-Sync Started Jul 11 19:42:32 volumio-taverna volumio[1107]: Error adding Membership: Error: addMembership EINVAL Jul 11 19:42:32 volumio-taverna volumio[1107]: info: Shairport-Sync Started Jul 11 19:42:32 volumio-taverna volumio[1107]: info: Shairport-Sync Started Jul 11 19:42:32 volumio-taverna volumio[1107]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 11 19:42:32 volumio-taverna volumio[1107]: SPOTIFY: BQBK-TKQbb2rrUNGIHzx348Q5XJtB6_zxHiGIGINtWSUwwGmU06v2yZgZSoV2Lyip9QQEP9Exu5wMIAuX0tW4t96EgcVJF_Ym---VCLlkvt93BTv6C2gfB4hNpC4BZ7UzbcM8Wc4_myQNCyc0opXCM5PA1L1b3vQg_YVqDpbjGW9zWY-jAyqnIAWBsutE74Gr3cO8NkVfwiwjfSz5CVC9POtPPFbpXJD-IYN1GYCTZQdJWA1lcP4zHa_k5gAXHRAK2bt4q9m4DQOF6LkY6j-NO_mMQ Jul 11 19:42:32 volumio-taverna volumio[1107]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 11 19:42:32 volumio-taverna volumio[1107]: info: New Spotify access token = BQBK-TKQbb2rrUNGIHzx348Q5XJtB6_zxHiGIGINtWSUwwGmU06v2yZgZSoV2Lyip9QQEP9Exu5wMIAuX0tW4t96EgcVJF_Ym---VCLlkvt93BTv6C2gfB4hNpC4BZ7UzbcM8Wc4_myQNCyc0opXCM5PA1L1b3vQg_YVqDpbjGW9zWY-jAyqnIAWBsutE74Gr3cO8NkVfwiwjfSz5CVC9POtPPFbpXJD-IYN1GYCTZQdJWA1lcP4zHa_k5gAXHRAK2bt4q9m4DQOF6LkY6j-NO_mMQ Jul 11 19:42:32 volumio-taverna volumio[1107]: info: Spotify credentials grant success - running version from March 24, 2019 Jul 11 19:42:32 volumio-taverna systemd[1]: Starting Music Player Daemon... Jul 11 19:42:32 volumio-taverna volumio[1107]: info: MPD Permissions set Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:32 volumio-taverna volumio[1107]: info: Starting Shairport Sync Jul 11 19:42:32 volumio-taverna sudo[1355]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 11 19:42:32 volumio-taverna sudo[1362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 11 19:42:32 volumio-taverna sudo[1355]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:32 volumio-taverna sudo[1362]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:32 volumio-taverna sudo[1355]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:32 volumio-taverna volumio[1107]: SPOTIFY: User informations: {"country":"IT","display_name":"mugello46","email":"alberto.vergani@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/mugello46"},"followers":{"href":null,"total":3},"href":"https://api.spotify.com/v1/users/mugello46","id":"mugello46","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee85356f0135ecf6a2b476f89d0a","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b82356f0135ecf6a2b476f89d0a","width":64}],"product":"premium","type":"user","uri":"spotify:user:mugello46"} Jul 11 19:42:32 volumio-taverna volumio[1107]: info: Spotify Successfully logged in Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 11 19:42:32 volumio-taverna volumio[1107]: info: [1752255752974] CoreMusicLibrary::Adding element Spotify Jul 11 19:42:32 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 19:42:32 volumio-taverna volumio[1107]: Cannot find translation for source Spotify Jul 11 19:42:33 volumio-taverna systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 11 19:42:33 volumio-taverna systemd[1]: shairport-sync.service: Succeeded. Jul 11 19:42:33 volumio-taverna systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 11 19:42:33 volumio-taverna systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 11 19:42:33 volumio-taverna sudo[1362]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:33 volumio-taverna volumio[1107]: info: Shairport-Sync Started Jul 11 19:42:33 volumio-taverna go-librespot[1318]: time="2025-07-11T19:42:33+02:00" level=debug msg="obtained new client token: AADeUaycDKaC8aezxpZpUzE5pHKVrZsjkOK+iRZakmlemXIKHlLMq5BbU5j5/d1Bef9rKqR/3apGBX/29UK5vbMw9nFb2pKMqjJNdRvn2Vi8BuSt8PlLbQa7/wRa6lnCq1XiANKTvb2YBymMQ0WNMHPxmvdPoqZzmzOnUAxWRt6I5DKdJbt/Uzgi4oQeGXdyckXAUpSD+H67a7pzey+aFWnLLMTqFo7jNjU93b5UvhtGrt64M383yL+Q0Hs=" Jul 11 19:42:33 volumio-taverna go-librespot[1318]: time="2025-07-11T19:42:33+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 11 19:42:33 volumio-taverna go-librespot[1318]: time="2025-07-11T19:42:33+02:00" level=debug msg="completed keyexchange" Jul 11 19:42:33 volumio-taverna go-librespot[1318]: time="2025-07-11T19:42:33+02:00" level=debug msg="completed challenge" Jul 11 19:42:33 volumio-taverna go-librespot[1318]: time="2025-07-11T19:42:33+02:00" level=debug msg="authenticated as mugello46" Jul 11 19:42:33 volumio-taverna volumio[1107]: info: camilladsp service started and running in background, instance 1 Jul 11 19:42:33 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 19:42:33 volumio-taverna volumio[1107]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 11 19:42:33 volumio-taverna volumio[1107]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 11 19:42:33 volumio-taverna volumio[1107]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 11 19:42:33 volumio-taverna volumio[1107]: info: FusionDsp loaded Jul 11 19:42:33 volumio-taverna volumio[1107]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 19:42:34 volumio-taverna sudo[1381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 11 19:42:34 volumio-taverna sudo[1381]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 19:42:34 volumio-taverna volumio[1107]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 11 19:42:34 volumio-taverna volumio[1107]: info: Adding Signal Path Element [object Object] Jul 11 19:42:34 volumio-taverna volumio[1107]: info: Adding fusiondspeq DSP Signal Path Element Jul 11 19:42:34 volumio-taverna volumio[1107]: info: FusionDsp - ---- installed callbackRead Jul 11 19:42:34 volumio-taverna volumio[1107]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 19:42:34 volumio-taverna volumio[1107]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 11 19:42:34 volumio-taverna volumio[1107]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 11 19:42:34 volumio-taverna volumio[1107]: at onErrorNT (internal/child_process.js:465:16) Jul 11 19:42:34 volumio-taverna volumio[1107]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Jul 11 19:42:34 volumio-taverna volumio[1107]: errno: -2, Jul 11 19:42:34 volumio-taverna volumio[1107]: code: 'ENOENT', Jul 11 19:42:34 volumio-taverna volumio[1107]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 11 19:42:34 volumio-taverna volumio[1107]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 11 19:42:34 volumio-taverna volumio[1107]: spawnargs: [ Jul 11 19:42:34 volumio-taverna volumio[1107]: '-p', Jul 11 19:42:34 volumio-taverna volumio[1107]: 9876, Jul 11 19:42:34 volumio-taverna volumio[1107]: '-o', Jul 11 19:42:34 volumio-taverna volumio[1107]: '/tmp/camilladsp.log', Jul 11 19:42:34 volumio-taverna volumio[1107]: '-l', Jul 11 19:42:34 volumio-taverna volumio[1107]: 'warn', Jul 11 19:42:34 volumio-taverna volumio[1107]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 11 19:42:34 volumio-taverna volumio[1107]: ] Jul 11 19:42:34 volumio-taverna volumio[1107]: } Jul 11 19:42:34 volumio-taverna volumio[1107]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 19:42:34 volumio-taverna go-librespot[1318]: time="2025-07-11T19:42:34+02:00" level=debug msg="authenticated as mugello46" Jul 11 19:42:34 volumio-taverna go-librespot[1318]: time="2025-07-11T19:42:34+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jul 11 19:42:34 volumio-taverna systemd[1]: Started FusionDsp Daemon. Jul 11 19:42:34 volumio-taverna go-librespot[1318]: time="2025-07-11T19:42:34+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jul 11 19:42:34 volumio-taverna sudo[1381]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:34 volumio-taverna go-librespot[1318]: time="2025-07-11T19:42:34+02:00" level=debug msg="dealer connection opened" Jul 11 19:42:34 volumio-taverna go-librespot[1318]: time="2025-07-11T19:42:34+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 11 19:42:34 volumio-taverna go-librespot[1318]: time="2025-07-11T19:42:34+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 11 19:42:34 volumio-taverna go-librespot[1318]: time="2025-07-11T19:42:34+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 11 19:42:34 volumio-taverna go-librespot[1318]: time="2025-07-11T19:42:34+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 11 19:42:34 volumio-taverna go-librespot[1318]: time="2025-07-11T19:42:34+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jul 11 19:42:34 volumio-taverna go-librespot[1318]: time="2025-07-11T19:42:34+02:00" level=debug msg="received connection id: MTMwMTU3OTctMWZjNS00OGQzLTg4YzUtYTgyYWFlNjk0MDc1K2RlYWxlcit0Y3A6Ly8wYWIxNTFmNi5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArNEYxNDU1NDVEODdFNDUzQUYyRjM3OEVERkUyMUM2Qjk3OUQ4RjZDQzE2MjlFQkVCMDRFQzgwNzJGRUI4NkEzRQ==" Jul 11 19:42:34 volumio-taverna mpd[1365]: Jul 11 19:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 11 19:42:34 volumio-taverna go-librespot[1318]: time="2025-07-11T19:42:34+02:00" level=debug msg="put connect state because NEW_DEVICE" Jul 11 19:42:34 volumio-taverna volumio[1383]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Jul 11 19:42:34 volumio-taverna systemd[1]: Started Music Player Daemon. Jul 11 19:42:34 volumio-taverna sudo[1289]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:34 volumio-taverna sudo[1279]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:34 volumio-taverna sudo[1349]: pam_unix(sudo:session): session closed for user root Jul 11 19:42:34 volumio-taverna systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Jul 11 19:42:34 volumio-taverna systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 11 19:42:34 volumio-taverna sudo[1395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-11 19:41 Jul 11 19:42:34 volumio-taverna sudo[1395]: 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"