-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sat 2023-10-07 11:43:27 CEST. -- Oct 07 11:42:03 el-ampli fake-hwclock[190]: Sat Oct 7 09:42:03 UTC 2023 Oct 07 11:42:03 el-ampli systemd[1]: Started Restore / save the current clock. Oct 07 11:42:03 el-ampli systemd[1]: Started Load/Save Random Seed. Oct 07 11:42:03 el-ampli systemd[1]: dynamicswap.service: Succeeded. Oct 07 11:42:03 el-ampli systemd[1]: Started Create System Users. Oct 07 11:42:03 el-ampli systemd[1]: Starting Create Static Device Nodes in /dev... Oct 07 11:42:03 el-ampli systemd[1]: Started Create Static Device Nodes in /dev. Oct 07 11:42:03 el-ampli systemd[1]: Reached target Local File Systems (Pre). Oct 07 11:42:03 el-ampli systemd[1]: Mounting /var/spool/cups... Oct 07 11:42:03 el-ampli systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Oct 07 11:42:03 el-ampli systemd[1]: Mounting /tmp... Oct 07 11:42:03 el-ampli systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Oct 07 11:42:03 el-ampli systemd[1]: Mounting /var/log... Oct 07 11:42:03 el-ampli systemd[1]: Starting udev Kernel Device Manager... Oct 07 11:42:03 el-ampli systemd[1]: Mounted /var/spool/cups. Oct 07 11:42:03 el-ampli systemd[1]: Mounted /tmp. Oct 07 11:42:03 el-ampli systemd[1]: Mounted /var/log. Oct 07 11:42:03 el-ampli systemd[1]: Started udev Coldplug all Devices. Oct 07 11:42:03 el-ampli systemd[1]: Starting Helper to synchronize boot up for ifupdown... Oct 07 11:42:03 el-ampli systemd[1]: Starting Flush Journal to Persistent Storage... Oct 07 11:42:03 el-ampli systemd[1]: Mounting /var/spool/cups/tmp... Oct 07 11:42:03 el-ampli systemd[1]: Mounted /var/spool/cups/tmp. Oct 07 11:42:03 el-ampli systemd-udevd[212]: Network interface NamePolicy= disabled on kernel command line, ignoring. Oct 07 11:42:03 el-ampli systemd-journald[179]: Runtime journal (/run/log/journal/6c9b1d414d94da048f39d3e06336abc2) is 7.5M, max 30.0M, 22.5M free. Oct 07 11:42:03 el-ampli systemd[1]: Started Flush Journal to Persistent Storage. Oct 07 11:42:03 el-ampli systemd[1]: Started udev Kernel Device Manager. Oct 07 11:42:03 el-ampli systemd[1]: Starting Show Plymouth Boot Screen... Oct 07 11:42:03 el-ampli systemd[1]: Received SIGRTMIN+20 from PID 229 (plymouthd). Oct 07 11:42:03 el-ampli systemd[1]: Started Show Plymouth Boot Screen. Oct 07 11:42:03 el-ampli systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Oct 07 11:42:03 el-ampli systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Oct 07 11:42:03 el-ampli systemd[1]: Reached target Local Encrypted Volumes. Oct 07 11:42:03 el-ampli systemd[1]: Reached target Paths. Oct 07 11:42:04 el-ampli kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Oct 07 11:42:04 el-ampli kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Oct 07 11:42:04 el-ampli kernel: [vc_sm_connected_init]: start Oct 07 11:42:04 el-ampli kernel: [vc_sm_connected_init]: installed successfully Oct 07 11:42:04 el-ampli kernel: mc: Linux media interface: v0.10 Oct 07 11:42:04 el-ampli kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Oct 07 11:42:04 el-ampli systemd[1]: Found device /dev/ttyAMA0. Oct 07 11:42:04 el-ampli kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Oct 07 11:42:04 el-ampli kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Oct 07 11:42:05 el-ampli kernel: videodev: Linux video capture interface: v2.00 Oct 07 11:42:05 el-ampli kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Oct 07 11:42:05 el-ampli kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Oct 07 11:42:05 el-ampli kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Oct 07 11:42:05 el-ampli kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Oct 07 11:42:05 el-ampli kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Oct 07 11:42:05 el-ampli kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Oct 07 11:42:05 el-ampli kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Oct 07 11:42:05 el-ampli kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Oct 07 11:42:05 el-ampli kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Oct 07 11:42:05 el-ampli kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Oct 07 11:42:05 el-ampli kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Oct 07 11:42:05 el-ampli kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Oct 07 11:42:05 el-ampli kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Oct 07 11:42:05 el-ampli kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Oct 07 11:42:05 el-ampli kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Oct 07 11:42:05 el-ampli kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Oct 07 11:42:05 el-ampli kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Oct 07 11:42:05 el-ampli kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Oct 07 11:42:05 el-ampli kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Oct 07 11:42:05 el-ampli kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Oct 07 11:42:05 el-ampli kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Oct 07 11:42:05 el-ampli kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Oct 07 11:42:05 el-ampli kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Oct 07 11:42:05 el-ampli kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Oct 07 11:42:05 el-ampli kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Oct 07 11:42:05 el-ampli kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Oct 07 11:42:05 el-ampli kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Oct 07 11:42:05 el-ampli kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Oct 07 11:42:05 el-ampli kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Oct 07 11:42:05 el-ampli kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Oct 07 11:42:05 el-ampli systemd-udevd[223]: Using default interface naming scheme 'v240'. Oct 07 11:42:05 el-ampli systemd-udevd[227]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 07 11:42:05 el-ampli systemd-udevd[223]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 07 11:42:06 el-ampli systemd[1]: Found device /dev/mmcblk0p1. Oct 07 11:42:07 el-ampli systemd-udevd[225]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 0' failed with exit code 17. Oct 07 11:42:07 el-ampli systemd[1]: Started Helper to synchronize boot up for ifupdown. Oct 07 11:42:08 el-ampli systemd[1]: Condition check resulted in SMSC9512/9514 Fast Ethernet Adapter being skipped. Oct 07 11:42:08 el-ampli systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 07 11:42:08 el-ampli systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Oct 07 11:42:08 el-ampli systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Oct 07 11:42:08 el-ampli systemd[1]: Condition check resulted in Huge Pages File System being skipped. Oct 07 11:42:08 el-ampli systemd[1]: Condition check resulted in FUSE Control File System being skipped. Oct 07 11:42:08 el-ampli systemd[1]: Starting File System Check on /dev/mmcblk0p1... Oct 07 11:42:08 el-ampli systemd[1]: Started File System Check Daemon to report status. Oct 07 11:42:08 el-ampli systemd-fsck[308]: fsck.fat 4.1 (2017-01-24) Oct 07 11:42:08 el-ampli systemd-fsck[308]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Oct 07 11:42:08 el-ampli systemd-fsck[308]: Automatically removing dirty bit. Oct 07 11:42:08 el-ampli systemd-fsck[308]: Performing changes. Oct 07 11:42:08 el-ampli systemd-fsck[308]: /dev/mmcblk0p1: 294 files, 27484/46774 clusters Oct 07 11:42:08 el-ampli systemd[1]: Started File System Check on /dev/mmcblk0p1. Oct 07 11:42:08 el-ampli systemd[1]: Mounting /boot... Oct 07 11:42:08 el-ampli systemd[1]: Mounted /boot. Oct 07 11:42:08 el-ampli systemd[1]: Reached target Local File Systems. Oct 07 11:42:08 el-ampli systemd[1]: Started ifup for eth0. Oct 07 11:42:08 el-ampli systemd[1]: Starting Preprocess NFS configuration... Oct 07 11:42:08 el-ampli systemd[1]: Starting Raise network interfaces... Oct 07 11:42:08 el-ampli systemd[1]: Starting Create Volatile Files and Directories... Oct 07 11:42:08 el-ampli systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Oct 07 11:42:08 el-ampli systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Oct 07 11:42:08 el-ampli systemd[1]: Received SIGRTMIN+20 from PID 229 (plymouthd). Oct 07 11:42:08 el-ampli systemd[1]: nfs-config.service: Succeeded. Oct 07 11:42:08 el-ampli systemd[1]: Started Preprocess NFS configuration. Oct 07 11:42:09 el-ampli systemd[1]: plymouth-read-write.service: Succeeded. Oct 07 11:42:09 el-ampli systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Oct 07 11:42:09 el-ampli systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Oct 07 11:42:09 el-ampli systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Oct 07 11:42:09 el-ampli systemd[1]: Reached target NFS client services. Oct 07 11:42:09 el-ampli systemd[1]: Started Create Volatile Files and Directories. Oct 07 11:42:09 el-ampli systemd[1]: Starting RPC bind portmap service... Oct 07 11:42:09 el-ampli systemd[1]: Starting Update UTMP about System Boot/Shutdown... Oct 07 11:42:09 el-ampli systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Oct 07 11:42:09 el-ampli systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Oct 07 11:42:09 el-ampli kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Oct 07 11:42:09 el-ampli systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Oct 07 11:42:09 el-ampli systemd[1]: Started Update UTMP about System Boot/Shutdown. Oct 07 11:42:09 el-ampli systemd[1]: Reached target System Initialization. Oct 07 11:42:09 el-ampli systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Oct 07 11:42:09 el-ampli systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 07 11:42:09 el-ampli kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Oct 07 11:42:09 el-ampli systemd[1]: apt-daily.timer: Not using persistent file timestamp Wed 2024-06-19 13:27:33 CEST as it is in the future. Oct 07 11:42:09 el-ampli systemd[1]: Started Daily apt download activities. Oct 07 11:42:09 el-ampli systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 07 11:42:09 el-ampli systemd[1]: Listening on mpd.socket. Oct 07 11:42:09 el-ampli systemd[1]: Listening on triggerhappy.socket. Oct 07 11:42:09 el-ampli systemd[1]: Reached target Sockets. Oct 07 11:42:09 el-ampli systemd[1]: Reached target Basic System. Oct 07 11:42:09 el-ampli systemd[1]: Started Volumio Iptables Module. Oct 07 11:42:09 el-ampli kernel: cfg80211: loaded regulatory.db is malformed or signature is missing/invalid Oct 07 11:42:09 el-ampli sh[313]: command failed: No such device (-19) Oct 07 11:42:09 el-ampli ifup[316]: command failed: No such device (-19) Oct 07 11:42:09 el-ampli systemd[1]: Starting Login Service... Oct 07 11:42:09 el-ampli systemd[1]: Started UPnP Renderer front-end to MPD. Oct 07 11:42:09 el-ampli systemd[1]: Condition check resulted in Configure Bluetooth Modems connected by UART being skipped. Oct 07 11:42:09 el-ampli systemd[1]: Started D-Bus System Message Bus. Oct 07 11:42:09 el-ampli systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Oct 07 11:42:09 el-ampli systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Oct 07 11:42:09 el-ampli systemd[1]: Started Manage Sound Card State (restore and store). Oct 07 11:42:09 el-ampli alsactl[363]: alsactl 1.1.8 daemon started Oct 07 11:42:09 el-ampli systemd[1]: Starting Save/Restore Sound Card State... Oct 07 11:42:09 el-ampli systemd[1]: Starting Wireless Services... Oct 07 11:42:09 el-ampli systemd[1]: Starting WPA supplicant... Oct 07 11:42:09 el-ampli systemd[1]: Starting dhcpcd on all interfaces... Oct 07 11:42:09 el-ampli systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Oct 07 11:42:09 el-ampli systemd[1]: Starting triggerhappy global hotkey daemon... Oct 07 11:42:09 el-ampli systemd[1]: Started volumio-remote-updater.service. Oct 07 11:42:09 el-ampli systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Oct 07 11:42:09 el-ampli systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Wed 2024-06-19 13:27:33 CEST as it is in the future. Oct 07 11:42:09 el-ampli systemd[1]: Started Daily apt upgrade and clean activities. Oct 07 11:42:09 el-ampli systemd[1]: Reached target Timers. Oct 07 11:42:09 el-ampli systemd[1]: Started RPC bind portmap service. Oct 07 11:42:10 el-ampli systemd[1]: Reached target Remote File Systems (Pre). Oct 07 11:42:10 el-ampli systemd[1]: Reached target Remote File Systems. Oct 07 11:42:10 el-ampli dhcpcd[370]: Not running dhcpcd because /etc/network/interfaces Oct 07 11:42:10 el-ampli dhcpcd[370]: defines some interfaces that will use a Oct 07 11:42:10 el-ampli dhcpcd[370]: DHCP client or static address Oct 07 11:42:10 el-ampli systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Oct 07 11:42:10 el-ampli systemd[1]: Reached target RPC Port Mapper. Oct 07 11:42:10 el-ampli systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Oct 07 11:42:10 el-ampli systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Oct 07 11:42:10 el-ampli thd[372]: Unable to parse trigger line: Oct 07 11:42:10 el-ampli thd[372]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Oct 07 11:42:10 el-ampli thd[372]: Unable to parse trigger line: Oct 07 11:42:10 el-ampli thd[372]: Found socket passed from systemd Oct 07 11:42:10 el-ampli systemd[1]: Failed to start dhcpcd on all interfaces. Oct 07 11:42:10 el-ampli systemd[1]: Started Save/Restore Sound Card State. Oct 07 11:42:10 el-ampli systemd[1]: Started triggerhappy global hotkey daemon. Oct 07 11:42:10 el-ampli systemd[1]: Reached target Sound Card. Oct 07 11:42:10 el-ampli systemd-logind[356]: New seat seat0. Oct 07 11:42:10 el-ampli avahi-daemon[371]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Oct 07 11:42:10 el-ampli avahi-daemon[371]: Successfully dropped root privileges. Oct 07 11:42:10 el-ampli avahi-daemon[371]: avahi-daemon 0.7 starting up. Oct 07 11:42:10 el-ampli ifup[316]: command failed: No such device (-19) Oct 07 11:42:10 el-ampli avahi-daemon[371]: Successfully called chroot(). Oct 07 11:42:10 el-ampli avahi-daemon[371]: Successfully dropped remaining capabilities. Oct 07 11:42:10 el-ampli avahi-daemon[371]: Loading service file /services/volumio.service. Oct 07 11:42:10 el-ampli avahi-daemon[371]: Network interface enumeration completed. Oct 07 11:42:10 el-ampli avahi-daemon[371]: Server startup complete. Host name is el-ampli.local. Local service cookie is 476284324. Oct 07 11:42:10 el-ampli avahi-daemon[371]: Service "El Ampli" (/services/volumio.service) successfully established. Oct 07 11:42:10 el-ampli systemd[1]: Started Login Service. Oct 07 11:42:10 el-ampli systemd[1]: Started Avahi mDNS/DNS-SD Stack. Oct 07 11:42:11 el-ampli kernel: 8021q: 802.1Q VLAN Support v1.8 Oct 07 11:42:11 el-ampli loadcpufreq[382]: Loading cpufreq kernel modules...done (none). Oct 07 11:42:11 el-ampli systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Oct 07 11:42:11 el-ampli systemd[1]: Starting LSB: set CPUFreq kernel parameters... Oct 07 11:42:12 el-ampli ifup[316]: command failed: No such device (-19) Oct 07 11:42:12 el-ampli cpufrequtils[437]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Oct 07 11:42:12 el-ampli systemd[1]: Started LSB: set CPUFreq kernel parameters. Oct 07 11:42:12 el-ampli sh[313]: eth0: waiting for carrier Oct 07 11:42:12 el-ampli dhcpcd[381]: eth0: waiting for carrier Oct 07 11:42:12 el-ampli kernel: SMSC LAN8700 usb-001:003:01: attached PHY driver [SMSC LAN8700] (mii_bus:phy_addr=usb-001:003:01, irq=POLL) Oct 07 11:42:12 el-ampli kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Oct 07 11:42:12 el-ampli kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Oct 07 11:42:12 el-ampli haveged[338]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Oct 07 11:42:12 el-ampli haveged[338]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Oct 07 11:42:12 el-ampli haveged[338]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.0016 Oct 07 11:42:12 el-ampli haveged[338]: haveged: fills: 0, generated: 0 Oct 07 11:42:13 el-ampli systemd[1]: Started Raise network interfaces. Oct 07 11:42:13 el-ampli kernel: random: crng init done Oct 07 11:42:13 el-ampli kernel: random: 7 urandom warning(s) missed due to ratelimiting Oct 07 11:42:13 el-ampli systemd-udevd[223]: Process '/sbin/crda' failed with exit code 249. Oct 07 11:42:13 el-ampli systemd[1]: iptables.service: Succeeded. Oct 07 11:42:14 el-ampli systemd[1]: Started WPA supplicant. Oct 07 11:42:14 el-ampli systemd[1]: Reached target Network. Oct 07 11:42:14 el-ampli wpa_supplicant[368]: Successfully initialized wpa_supplicant Oct 07 11:42:14 el-ampli systemd[1]: Starting Network Time Service... Oct 07 11:42:14 el-ampli systemd[1]: Starting Permit User Sessions... Oct 07 11:42:14 el-ampli systemd[1]: Reached target Network is Online. Oct 07 11:42:14 el-ampli systemd[1]: Starting LSB: Brings up/down network automatically... Oct 07 11:42:14 el-ampli systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Oct 07 11:42:14 el-ampli systemd[1]: Starting /etc/rc.local Compatibility... Oct 07 11:42:14 el-ampli systemd[1]: Starting Music Player Daemon... Oct 07 11:42:14 el-ampli systemd[1]: Starting Samba NMB Daemon... Oct 07 11:42:14 el-ampli systemd[1]: Starting OpenBSD Secure Shell server... Oct 07 11:42:14 el-ampli systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 11:42:14 el-ampli sh[313]: eth0: carrier acquired Oct 07 11:42:14 el-ampli dhcpcd[381]: eth0: carrier acquired Oct 07 11:42:14 el-ampli kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Oct 07 11:42:14 el-ampli systemd[1]: Started Permit User Sessions. Oct 07 11:42:14 el-ampli sh[313]: DUID 00:01:00:01:2a:c9:69:f5:b8:27:eb:03:2f:84 Oct 07 11:42:14 el-ampli sh[313]: eth0: IAID eb:37:5a:03 Oct 07 11:42:14 el-ampli sh[313]: eth0: adding address fe80::ba27:ebff:fe37:5a03 Oct 07 11:42:14 el-ampli sh[313]: ipv6_addaddr1: Permission denied Oct 07 11:42:14 el-ampli dhcpcd[381]: DUID 00:01:00:01:2a:c9:69:f5:b8:27:eb:03:2f:84 Oct 07 11:42:14 el-ampli dhcpcd[381]: eth0: IAID eb:37:5a:03 Oct 07 11:42:14 el-ampli dhcpcd[381]: eth0: adding address fe80::ba27:ebff:fe37:5a03 Oct 07 11:42:14 el-ampli dhcpcd[381]: ipv6_addaddr1: Permission denied Oct 07 11:42:14 el-ampli systemd[1]: Started /etc/rc.local Compatibility. Oct 07 11:42:14 el-ampli sh[313]: eth0: soliciting an IPv6 router Oct 07 11:42:14 el-ampli dhcpcd[381]: eth0: soliciting an IPv6 router Oct 07 11:42:15 el-ampli systemd[1]: Starting Terminate Plymouth Boot Screen... Oct 07 11:42:15 el-ampli systemd[1]: Starting Hold until boot process finishes up... Oct 07 11:42:15 el-ampli systemd[1]: plymouth-start.service: Succeeded. Oct 07 11:42:15 el-ampli systemd[1]: plymouth-quit.service: Succeeded. Oct 07 11:42:15 el-ampli systemd[1]: Started Terminate Plymouth Boot Screen. Oct 07 11:42:15 el-ampli systemd[1]: plymouth-quit-wait.service: Succeeded. Oct 07 11:42:15 el-ampli systemd[1]: Started Hold until boot process finishes up. Oct 07 11:42:15 el-ampli systemd[1]: Received SIGRTMIN+21 from PID 229 (n/a). Oct 07 11:42:15 el-ampli systemd[1]: Received SIGRTMIN+21 from PID 229 (n/a). Oct 07 11:42:15 el-ampli systemd[1]: Started Serial Getty on ttyAMA0. Oct 07 11:42:15 el-ampli systemd[1]: Started Getty on tty1. Oct 07 11:42:15 el-ampli systemd[1]: Reached target Login Prompts. Oct 07 11:42:15 el-ampli ifplugd(eth0)[573]: ifplugd 0.28 initializing. Oct 07 11:42:15 el-ampli ifplugd(eth0)[573]: Using interface eth0/B8:27:EB:37:5A:03 with driver (version: 5.10.92-v7+) Oct 07 11:42:15 el-ampli ifplugd(eth0)[573]: Using detection mode: SIOCETHTOOL Oct 07 11:42:15 el-ampli ifplugd(eth0)[573]: Initialization complete, link beat detected. Oct 07 11:42:15 el-ampli ntpd[542]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 07 11:42:15 el-ampli ntpd[542]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Oct 07 11:42:15 el-ampli systemd[1]: Started Network Time Service. Oct 07 11:42:15 el-ampli ifplugd(eth0)[573]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Oct 07 11:42:15 el-ampli sh[313]: eth0: rebinding lease of 192.168.0.249 Oct 07 11:42:15 el-ampli dhcpcd[381]: eth0: rebinding lease of 192.168.0.249 Oct 07 11:42:15 el-ampli ifplugd(eth0)[573]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Oct 07 11:42:15 el-ampli ntpd[574]: proto: precision = 1.094 usec (-20) Oct 07 11:42:15 el-ampli volumio-remote-updater[373]: Error: No active session Oct 07 11:42:15 el-ampli sh[313]: eth0: NAK: from 192.168.0.1 Oct 07 11:42:15 el-ampli dhcpcd[381]: eth0: NAK: from 192.168.0.1 Oct 07 11:42:15 el-ampli ntpd[574]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 07 11:42:15 el-ampli ntpd[574]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2023-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 07 11:42:15 el-ampli ntpd[574]: Listen and drop on 0 v6wildcard [::]:123 Oct 07 11:42:15 el-ampli ntpd[574]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 07 11:42:15 el-ampli ntpd[574]: Listen normally on 2 lo 127.0.0.1:123 Oct 07 11:42:15 el-ampli ntpd[574]: Listening on routing socket on fd #19 for interface updates Oct 07 11:42:15 el-ampli ntpd[574]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 07 11:42:15 el-ampli ntpd[574]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 07 11:42:15 el-ampli volumio-remote-updater[373]: [2023-10-07 11:42:15] [info] asio async_connect error: system:111 (Connection refused) Oct 07 11:42:15 el-ampli volumio-remote-updater[373]: [2023-10-07 11:42:15] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 07 11:42:15 el-ampli volumio-remote-updater[373]: [2023-10-07 11:42:15] [error] handle_connect error: Underlying Transport Error Oct 07 11:42:15 el-ampli sh[313]: eth0: soliciting a DHCP lease Oct 07 11:42:15 el-ampli dhcpcd[381]: eth0: soliciting a DHCP lease Oct 07 11:42:16 el-ampli sshd[595]: Server listening on 0.0.0.0 port 22. Oct 07 11:42:16 el-ampli sshd[595]: Server listening on :: port 22. Oct 07 11:42:16 el-ampli systemd[1]: Started OpenBSD Secure Shell server. Oct 07 11:42:16 el-ampli volumio[359]: Could not open config: /tmp/upmpdcli.conf Oct 07 11:42:16 el-ampli systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 07 11:42:16 el-ampli systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 07 11:42:16 el-ampli ntpd[574]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 07 11:42:16 el-ampli kernel: cam1-reg: disabling Oct 07 11:42:16 el-ampli kernel: cam-dummy-reg: disabling Oct 07 11:42:17 el-ampli ntpd[574]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 07 11:42:17 el-ampli sh[313]: eth0: offered 192.168.0.152 from 192.168.0.1 Oct 07 11:42:17 el-ampli dhcpcd[381]: eth0: offered 192.168.0.152 from 192.168.0.1 Oct 07 11:42:17 el-ampli sh[313]: eth0: probing address 192.168.0.152/24 Oct 07 11:42:17 el-ampli dhcpcd[381]: eth0: probing address 192.168.0.152/24 Oct 07 11:42:18 el-ampli ntpd[574]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 07 11:42:18 el-ampli nmbd[558]: [2023/10/07 11:42:18.514431, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Oct 07 11:42:18 el-ampli nmbd[558]: started asyncdns process 601 Oct 07 11:42:18 el-ampli nmbd[558]: [2023/10/07 11:42:18.519647, 0] ../lib/util/become_daemon.c:149(daemon_status) Oct 07 11:42:18 el-ampli nmbd[558]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Oct 07 11:42:18 el-ampli nmbd[558]: [2023/10/07 11:42:18.520097, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Oct 07 11:42:18 el-ampli nmbd[558]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Oct 07 11:42:19 el-ampli ntpd[574]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 07 11:42:20 el-ampli wireless.js[366]: WIRELESS: No wireless interface, exiting Oct 07 11:42:20 el-ampli volumio-remote-updater[373]: [2023-10-07 11:42:20] [info] asio async_connect error: system:111 (Connection refused) Oct 07 11:42:20 el-ampli volumio-remote-updater[373]: [2023-10-07 11:42:20] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 07 11:42:20 el-ampli volumio-remote-updater[373]: [2023-10-07 11:42:20] [error] handle_connect error: Underlying Transport Error Oct 07 11:42:20 el-ampli iw[607]: command failed: No such device (-19) Oct 07 11:42:20 el-ampli iwconfig[610]: Error for wireless request "Set Power Management" (8B2C) : Oct 07 11:42:20 el-ampli iwconfig[610]: SET failed on device wlan0 ; No such device. Oct 07 11:42:20 el-ampli systemd[1]: wireless.service: Succeeded. Oct 07 11:42:20 el-ampli systemd[1]: Started Wireless Services. Oct 07 11:42:20 el-ampli systemd[1]: Started Volumio Backend Module. Oct 07 11:42:21 el-ampli mpd[578]: Oct 07 11:42 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 07 11:42:22 el-ampli mpd[578]: Oct 07 11:42 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Oct 07 11:42:22 el-ampli mpd[578]: Oct 07 11:42 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Oct 07 11:42:22 el-ampli systemd[1]: Started Music Player Daemon. Oct 07 11:42:22 el-ampli sh[313]: eth0: leased 192.168.0.152 for 3600 seconds Oct 07 11:42:22 el-ampli sh[313]: eth0: adding route to 192.168.0.0/24 Oct 07 11:42:22 el-ampli sh[313]: eth0: adding default route via 192.168.0.1 Oct 07 11:42:22 el-ampli dhcpcd[381]: eth0: leased 192.168.0.152 for 3600 seconds Oct 07 11:42:22 el-ampli avahi-daemon[371]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.152. Oct 07 11:42:22 el-ampli avahi-daemon[371]: New relevant interface eth0.IPv4 for mDNS. Oct 07 11:42:22 el-ampli dhcpcd[381]: eth0: adding route to 192.168.0.0/24 Oct 07 11:42:22 el-ampli avahi-daemon[371]: Registering new address record for 192.168.0.152 on eth0.IPv4. Oct 07 11:42:22 el-ampli dhcpcd[381]: eth0: adding default route via 192.168.0.1 Oct 07 11:42:22 el-ampli sh[313]: forked to background, child pid 643 Oct 07 11:42:22 el-ampli dhcpcd[381]: forked to background, child pid 643 Oct 07 11:42:23 el-ampli ifplugd(eth0)[573]: client: ifup: interface eth0 already configured Oct 07 11:42:23 el-ampli sh[313]: eth0=eth0 Oct 07 11:42:23 el-ampli ifplugd(eth0)[573]: Program executed successfully. Oct 07 11:42:23 el-ampli ifplugd[544]: Network Interface Plugging Daemon...start eth0...done. Oct 07 11:42:23 el-ampli systemd[1]: Started LSB: Brings up/down network automatically. Oct 07 11:42:23 el-ampli nmbd[558]: [2023/10/07 11:42:23.536677, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 07 11:42:23 el-ampli systemd[1]: Started Samba NMB Daemon. Oct 07 11:42:23 el-ampli nmbd[558]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 07 11:42:23 el-ampli systemd[1]: Starting Samba Winbind Daemon... Oct 07 11:42:23 el-ampli nmbd[558]: [2023/10/07 11:42:23.575040, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 07 11:42:23 el-ampli nmbd[558]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.152 for name WORKGROUP<1d>. Oct 07 11:42:23 el-ampli nmbd[558]: This response was from IP 192.168.0.154, reporting an IP address of 192.168.0.154. Oct 07 11:42:24 el-ampli winbindd[669]: [2023/10/07 11:42:24.121002, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Oct 07 11:42:24 el-ampli winbindd[669]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 07 11:42:24 el-ampli winbindd[669]: [2023/10/07 11:42:24.203606, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 07 11:42:24 el-ampli winbindd[669]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 07 11:42:24 el-ampli systemd[1]: Started Samba Winbind Daemon. Oct 07 11:42:24 el-ampli systemd[1]: Starting Samba SMB Daemon... Oct 07 11:42:24 el-ampli ntpd[574]: Listen normally on 3 eth0 192.168.0.152:123 Oct 07 11:42:24 el-ampli ntpd[574]: new interface(s) found: waking up resolver Oct 07 11:42:25 el-ampli winbindd[669]: [2023/10/07 11:42:25.265089, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Oct 07 11:42:25 el-ampli winbindd[669]: res_names->count = 3, expected 4 Oct 07 11:42:25 el-ampli smbd[674]: [2023/10/07 11:42:25.325176, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 07 11:42:25 el-ampli systemd[1]: Started Samba SMB Daemon. Oct 07 11:42:25 el-ampli smbd[674]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 07 11:42:25 el-ampli systemd[1]: Reached target Multi-User System. Oct 07 11:42:25 el-ampli systemd[1]: Reached target Graphical Interface. Oct 07 11:42:25 el-ampli systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 07 11:42:25 el-ampli systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 07 11:42:25 el-ampli systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 07 11:42:25 el-ampli systemd[1]: Startup finished in 17.661s (kernel) + 25.929s (userspace) = 43.591s. Oct 07 11:42:26 el-ampli volumio[613]: info: ------------------------------------------- Oct 07 11:42:26 el-ampli volumio[613]: info: ----- Volumio3 ---- Oct 07 11:42:26 el-ampli volumio[613]: info: ------------------------------------------- Oct 07 11:42:26 el-ampli volumio[613]: info: ----- System startup ---- Oct 07 11:42:26 el-ampli volumio[613]: info: ------------------------------------------- Oct 07 11:42:28 el-ampli volumio-remote-updater[373]: [2023-10-07 11:42:28] [connect] Successful connection Oct 07 11:42:28 el-ampli volumio[613]: info: MYVOLUMIO Environment detected Oct 07 11:42:28 el-ampli volumio[613]: info: Plugin folders cleanup Oct 07 11:42:28 el-ampli volumio[613]: info: Scanning into folder /volumio/app/plugins/ Oct 07 11:42:28 el-ampli volumio[613]: info: Scanning category audio_interface Oct 07 11:42:28 el-ampli volumio[613]: info: Scanning category miscellanea Oct 07 11:42:28 el-ampli volumio[613]: info: Scanning category music_service Oct 07 11:42:28 el-ampli volumio[613]: info: Scanning category plugins.json Oct 07 11:42:28 el-ampli volumio[613]: info: Scanning category system_controller Oct 07 11:42:28 el-ampli volumio[613]: info: Scanning category user_interface Oct 07 11:42:28 el-ampli volumio[613]: info: Scanning into folder /data/plugins/ Oct 07 11:42:28 el-ampli volumio[613]: info: Scanning category audio_interface Oct 07 11:42:28 el-ampli volumio[613]: info: Scanning category music_service Oct 07 11:42:28 el-ampli volumio[613]: info: Scanning category system_hardware Oct 07 11:42:28 el-ampli volumio[613]: info: Plugin folders cleanup completed Oct 07 11:42:28 el-ampli volumio[613]: info: ------------------------------------------- Oct 07 11:42:28 el-ampli volumio[613]: info: ----- Core plugins startup ---- Oct 07 11:42:28 el-ampli volumio[613]: info: ------------------------------------------- Oct 07 11:42:28 el-ampli volumio[613]: info: Loading plugins from folder /volumio/app/plugins/ Oct 07 11:42:28 el-ampli volumio[613]: info: Adding plugin upnp to MyMusic Plugins Oct 07 11:42:28 el-ampli volumio[613]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 07 11:42:28 el-ampli volumio[613]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 07 11:42:28 el-ampli volumio[613]: info: Loading plugins from folder /data/plugins/ Oct 07 11:42:28 el-ampli volumio[613]: info: Loading plugin "system"... Oct 07 11:42:29 el-ampli volumio[613]: info: Loading plugin "appearance"... Oct 07 11:42:31 el-ampli volumio[613]: info: Loading plugin "network"... Oct 07 11:42:31 el-ampli volumio[613]: info: Refreshing Cached IP Addresses Oct 07 11:42:31 el-ampli volumio[613]: info: Loading plugin "services"... Oct 07 11:42:31 el-ampli volumio[613]: info: Loading plugin "alsa_controller"... Oct 07 11:42:32 el-ampli sudo[696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 07 11:42:32 el-ampli sudo[694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 07 11:42:32 el-ampli sudo[694]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:42:32 el-ampli sudo[696]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:42:32 el-ampli sudo[694]: pam_unix(sudo:session): session closed for user root Oct 07 11:42:32 el-ampli sudo[696]: pam_unix(sudo:session): session closed for user root Oct 07 11:42:32 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 11:42:32 el-ampli volumio[613]: info: Loading plugin "wizard"... Oct 07 11:42:32 el-ampli volumio[613]: info: Loading plugin "volumio_command_line_client"... Oct 07 11:42:32 el-ampli volumio[613]: info: Loading plugin "upnp"... Oct 07 11:42:32 el-ampli volumio[613]: info: [1696671752132] Starting Upmpd Daemon Oct 07 11:42:32 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 11:42:32 el-ampli volumio[613]: info: Loading plugin "my_music"... Oct 07 11:42:32 el-ampli volumio[613]: info: Loading plugin "mpd"... Oct 07 11:42:33 el-ampli volumio[613]: info: Creating MPD Configuration file Oct 07 11:42:33 el-ampli volumio[613]: info: Loading plugin "upnp_browser"... Oct 07 11:42:33 el-ampli sudo[704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 11:42:33 el-ampli sudo[704]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:42:33 el-ampli sudo[704]: pam_unix(sudo:session): session closed for user root Oct 07 11:42:33 el-ampli sudo[706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 11:42:33 el-ampli sudo[706]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:42:33 el-ampli systemd[1]: Stopping Music Player Daemon... Oct 07 11:42:33 el-ampli systemd[1]: mpd.service: Succeeded. Oct 07 11:42:33 el-ampli systemd[1]: Stopped Music Player Daemon. Oct 07 11:42:33 el-ampli systemd[1]: Starting Music Player Daemon... Oct 07 11:42:34 el-ampli volumio[613]: info: Loading plugin "networkfs"... Oct 07 11:42:35 el-ampli volumio[613]: info: Starting Udev Watcher for removable devices Oct 07 11:42:35 el-ampli sudo[714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=MANUSAN,password=70700635,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.10/MUSICA /mnt/NAS/MUZIC Oct 07 11:42:35 el-ampli sudo[714]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:42:35 el-ampli kernel: FS-Cache: Netfs 'cifs' registered for caching Oct 07 11:42:35 el-ampli kernel: Key type cifs.spnego registered Oct 07 11:42:35 el-ampli kernel: Key type cifs.idmap registered Oct 07 11:42:35 el-ampli kernel: CIFS: Attempting to mount //192.168.0.10/MUSICA Oct 07 11:42:35 el-ampli kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Oct 07 11:42:35 el-ampli volumio[613]: info: Ignoring mount for partition: boot Oct 07 11:42:35 el-ampli volumio[613]: info: Ignoring mount for partition: volumio Oct 07 11:42:35 el-ampli volumio[613]: info: Ignoring mount for partition: volumio_data Oct 07 11:42:35 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 11:42:35 el-ampli volumio[613]: info: Loading plugin "alarm-clock"... Oct 07 11:42:36 el-ampli mpd[711]: Oct 07 11:42 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 07 11:42:36 el-ampli volumio[613]: info: Loading plugin "airplay_emulation"... Oct 07 11:42:36 el-ampli mpd[711]: Oct 07 11:42 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Oct 07 11:42:36 el-ampli mpd[711]: Oct 07 11:42 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Oct 07 11:42:36 el-ampli systemd[1]: Started Music Player Daemon. Oct 07 11:42:36 el-ampli sudo[706]: pam_unix(sudo:session): session closed for user root Oct 07 11:42:36 el-ampli volumio[613]: info: Starting Shairport Sync Oct 07 11:42:36 el-ampli volumio[613]: info: Loading plugin "last_100"... Oct 07 11:42:36 el-ampli volumio[613]: info: Loading plugin "webradio"... Oct 07 11:42:36 el-ampli volumio[613]: info: Loading plugin "i2s_dacs"... Oct 07 11:42:36 el-ampli volumio[613]: info: Loading plugin "volumiodiscovery"... Oct 07 11:42:36 el-ampli volumio[613]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 07 11:42:36 el-ampli volumio[613]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 11:42:36 el-ampli volumio[613]: *** WARNING *** For more information see Oct 07 11:42:36 el-ampli volumio[613]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 07 11:42:36 el-ampli volumio[613]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 11:42:36 el-ampli volumio[613]: *** WARNING *** For more information see Oct 07 11:42:36 el-ampli node[613]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 07 11:42:36 el-ampli node[613]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 11:42:36 el-ampli node[613]: *** WARNING *** For more information see Oct 07 11:42:36 el-ampli node[613]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 07 11:42:36 el-ampli node[613]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 11:42:36 el-ampli node[613]: *** WARNING *** For more information see Oct 07 11:42:36 el-ampli volumio[613]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 07 11:42:36 el-ampli volumio[613]: info: Discovery: Started advertising with name: El Ampli Oct 07 11:42:36 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 11:42:36 el-ampli volumio[613]: info: Loading plugin "spop"... Oct 07 11:42:38 el-ampli systemd[1]: systemd-fsckd.service: Succeeded. Oct 07 11:42:39 el-ampli volumio[613]: info: Loading plugin "ytmusic"... Oct 07 11:42:41 el-ampli sudo[714]: pam_unix(sudo:session): session closed for user root Oct 07 11:42:41 el-ampli kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 07 11:42:41 el-ampli kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 07 11:42:43 el-ampli volumio-remote-updater[373]: [2023-10-07 11:42:43] [connect] Successful connection Oct 07 11:42:44 el-ampli volumio[613]: info: Loading plugin "gpio-buttons"... Oct 07 11:42:45 el-ampli volumio[613]: info: GPIO-Buttons initialized Oct 07 11:42:45 el-ampli volumio[613]: info: Loading plugin "outputs"... Oct 07 11:42:45 el-ampli volumio[613]: info: Loading plugin "albumart"... Oct 07 11:42:45 el-ampli volumio[613]: info: Plugin example_plugin is not enabled Oct 07 11:42:45 el-ampli volumio[613]: info: Loading plugin "inputs"... Oct 07 11:42:45 el-ampli volumio[613]: info: Loading plugin "updater_comm"... Oct 07 11:42:46 el-ampli volumio[613]: info: Plugin mpdemulation is not enabled Oct 07 11:42:46 el-ampli volumio[613]: info: Loading plugin "rest_api"... Oct 07 11:42:46 el-ampli volumio[613]: info: Loading plugin "websocket"... Oct 07 11:42:46 el-ampli volumio[613]: info: Loading plugin "fusiondsp"... Oct 07 11:42:46 el-ampli volumio[613]: Forking 3 albumart workers Oct 07 11:42:47 el-ampli volumio[613]: info: Applying required configuration parameters for plugin fusiondsp Oct 07 11:42:47 el-ampli volumio[613]: info: Loading plugin "80s80s"... Oct 07 11:42:48 el-ampli volumio[613]: info: Applying required configuration parameters for plugin 80s80s Oct 07 11:42:48 el-ampli volumio[613]: info: [1696671768282] [80s80s] API delay: 30 Oct 07 11:42:48 el-ampli volumio[613]: info: Loading plugin "hotelradio"... Oct 07 11:42:48 el-ampli volumio[613]: Starting albumart workers Oct 07 11:42:48 el-ampli volumio[613]: Starting albumart workers Oct 07 11:42:48 el-ampli volumio[613]: Starting albumart workers Oct 07 11:42:51 el-ampli volumio[613]: info: Loading plugin "minidlna"... Oct 07 11:42:51 el-ampli volumio[613]: info: Applying required configuration parameters for plugin minidlna Oct 07 11:42:52 el-ampli volumio[613]: info: Loading plugin "motherearthradio"... Oct 07 11:42:52 el-ampli volumio[613]: info: Applying required configuration parameters for plugin motherearthradio Oct 07 11:42:52 el-ampli volumio[613]: info: [1696671772777] [MotherEarth] API delay: 5 Oct 07 11:42:52 el-ampli volumio[613]: info: Loading plugin "podcast"... Oct 07 11:42:53 el-ampli volumio[613]: info: ControllerPodcast::constructor Oct 07 11:42:53 el-ampli volumio[613]: info: Loading plugin "gpio_control"... Oct 07 11:42:54 el-ampli volumio[613]: info: Applying required configuration parameters for plugin gpio_control Oct 07 11:42:54 el-ampli volumio[613]: info: [GPIO_Control] Detected Raspberry Pi 2 Model B Rev 1.1 Oct 07 11:42:54 el-ampli volumio[613]: info: [GPIO_Control] 40 GPIOs: true Oct 07 11:42:54 el-ampli volumio[613]: info: [GPIO_Control] Initialized Oct 07 11:42:54 el-ampli volumio[613]: info: Loading i18n strings for locale es Oct 07 11:42:54 el-ampli volumio[613]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 07 11:42:54 el-ampli volumio[613]: Updating browse sources language Oct 07 11:42:55 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 11:42:55 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 11:42:55 el-ampli volumio[613]: info: CoreCommandRouter::initPlayerControls Oct 07 11:42:55 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:55 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:55 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:55 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:55 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:55 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:55 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:55 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:55 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 11:42:55 el-ampli volumio[613]: Express server listening on port 3000 Oct 07 11:42:55 el-ampli volumio[613]: [Metrics] WebUI: 30s 948.81ms Oct 07 11:42:55 el-ampli volumio[613]: info: CoreStateMachine::resetVolumioState Oct 07 11:42:55 el-ampli volumio[613]: info: CoreStateMachine::getcurrentVolume Oct 07 11:42:55 el-ampli volumio[613]: info: CoreCommandRouter::volumioRetrievevolume Oct 07 11:42:55 el-ampli volumio[613]: info: CoreStateMachine::pushState Oct 07 11:42:55 el-ampli volumio[613]: info: CorePlayQueue::getTrack 0 Oct 07 11:42:55 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 11:42:55 el-ampli volumio[613]: info: CoreCommandRouter::volumioPushState Oct 07 11:42:55 el-ampli sudo[792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 07 11:42:55 el-ampli sudo[792]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:42:55 el-ampli sudo[792]: pam_unix(sudo:session): session closed for user root Oct 07 11:42:55 el-ampli sudo[794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 07 11:42:55 el-ampli sudo[794]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:42:55 el-ampli sudo[794]: pam_unix(sudo:session): session closed for user root Oct 07 11:42:55 el-ampli volumio[613]: info: Cannot mount NAS MUZIC at system boot, trial number 1 ,retrying in 5 seconds Oct 07 11:42:55 el-ampli volumio[613]: info: MPD Permissions set Oct 07 11:42:56 el-ampli volumio[613]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Oct 07 11:42:56 el-ampli volumio[613]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Oct 07 11:42:56 el-ampli volumio[613]: info: MPD running with PID711 Oct 07 11:42:56 el-ampli volumio[613]: ,establishing connection Oct 07 11:42:56 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:56 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:56 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:56 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:56 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:56 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:56 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:56 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:56 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 11:42:56 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:56 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 11:42:56 el-ampli volumio[613]: info: Reloading queue from file Oct 07 11:42:56 el-ampli volumio[613]: error: updateQueue error: null Oct 07 11:42:56 el-ampli volumio[613]: info: CoreStateMachine::setRepeat null single undefined Oct 07 11:42:56 el-ampli volumio[613]: info: CoreStateMachine::pushState Oct 07 11:42:56 el-ampli volumio[613]: info: CorePlayQueue::getTrack 0 Oct 07 11:42:56 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 11:42:56 el-ampli volumio[613]: info: CoreCommandRouter::volumioPushState Oct 07 11:42:56 el-ampli volumio[613]: info: CoreStateMachine::setRandom null Oct 07 11:42:56 el-ampli volumio[613]: info: CoreStateMachine::pushState Oct 07 11:42:56 el-ampli volumio[613]: info: CorePlayQueue::getTrack 0 Oct 07 11:42:56 el-ampli volumio[613]: info: CoreCommandRouter::volumioPushState Oct 07 11:42:56 el-ampli volumio[613]: info: Setting Device type: Raspberry PI Oct 07 11:42:56 el-ampli volumio[613]: info: Completed loading Core Plugins Oct 07 11:42:56 el-ampli volumio[613]: info: Preparing to generate the ALSA configuration file Oct 07 11:42:56 el-ampli volumio[613]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 07 11:42:56 el-ampli volumio[613]: info: Reading ALSA contributions from plugins. Oct 07 11:42:56 el-ampli volumio[613]: error: updateQueue error: null Oct 07 11:42:56 el-ampli sudo[811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 07 11:42:56 el-ampli sudo[811]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:42:56 el-ampli volumio[613]: info: Discovery: adding e6ac89a0-cf6c-4bff-9cab-b953bf795841 Oct 07 11:42:56 el-ampli volumio[613]: info: Discovery: Found device El Ampli Oct 07 11:42:56 el-ampli volumio[613]: info: CoreCommandRouter::volumioGetState Oct 07 11:42:56 el-ampli volumio[613]: info: CorePlayQueue::getTrack 0 Oct 07 11:42:56 el-ampli systemd[1]: Started UPnP Renderer front-end to MPD. Oct 07 11:42:56 el-ampli sudo[811]: pam_unix(sudo:session): session closed for user root Oct 07 11:42:56 el-ampli volumio[613]: info: Upmpdcli Daemon Started Oct 07 11:42:56 el-ampli volumio[613]: info: Asound.conf file unchanged, so no further update is needed Oct 07 11:42:56 el-ampli volumio[613]: info: Output device has changed, restarting MPD Oct 07 11:42:57 el-ampli volumio[613]: info: Output device has changed, restarting Shairport Sync Oct 07 11:42:57 el-ampli sudo[816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 11:42:57 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:57 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 11:42:57 el-ampli sudo[816]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:42:57 el-ampli sudo[816]: pam_unix(sudo:session): session closed for user root Oct 07 11:42:57 el-ampli sudo[818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 11:42:57 el-ampli sudo[818]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:42:57 el-ampli systemd[1]: Stopping Music Player Daemon... Oct 07 11:42:57 el-ampli volumio[613]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 11:42:57 el-ampli volumio[613]: info: ___________ START PLUGINS ___________ Oct 07 11:42:57 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 11:42:57 el-ampli volumio[613]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 11:42:57 el-ampli volumio[613]: info: [1696671777180] CoreMusicLibrary::Adding element Media Servers Oct 07 11:42:57 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 11:42:57 el-ampli systemd[1]: mpd.service: Succeeded. Oct 07 11:42:57 el-ampli systemd[1]: Stopped Music Player Daemon. Oct 07 11:42:57 el-ampli systemd[1]: Starting Music Player Daemon... Oct 07 11:42:57 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:57 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 11:42:57 el-ampli volumio[613]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 11:42:57 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:57 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 11:42:57 el-ampli volumio[613]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 11:42:57 el-ampli volumio[613]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 11:42:57 el-ampli volumio[613]: info: [1696671777462] CoreMusicLibrary::Adding element Last_100 Oct 07 11:42:57 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 11:42:57 el-ampli volumio[613]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 11:42:57 el-ampli volumio[613]: info: [1696671777469] CoreMusicLibrary::Adding element Webradio Oct 07 11:42:57 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 11:42:57 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 11:42:57 el-ampli volumio[613]: info: Checking for old spotify connect plugin installed Oct 07 11:42:57 el-ampli volumio[613]: info: GPIO-Buttons: Reading config and creating triggers... Oct 07 11:42:57 el-ampli volumio[613]: info: Loading i18n strings for locale es Oct 07 11:42:57 el-ampli volumio[613]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 07 11:42:57 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 07 11:42:57 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:57 el-ampli volumio[613]: info: mixtype--------------------- None Oct 07 11:42:57 el-ampli volumio[613]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 11:42:57 el-ampli volumio[613]: info: [1696671777782] CoreMusicLibrary::Adding element 80s80s Radio Oct 07 11:42:57 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 11:42:57 el-ampli volumio[613]: Cannot find translation for source 80s80s Radio Oct 07 11:42:57 el-ampli volumio[613]: info: Stopping AccessToken refresher cron for Hotel Radio Oct 07 11:42:57 el-ampli volumio[613]: info: AccessToken refresher cron started for Hotel Radio Oct 07 11:42:57 el-ampli volumio[613]: info: Loading i18n strings for locale es Oct 07 11:42:57 el-ampli volumio[613]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 07 11:42:58 el-ampli volumio[613]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 11:42:58 el-ampli volumio[613]: info: [1696671778001] CoreMusicLibrary::Adding element Mother Earth Radio Oct 07 11:42:58 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 11:42:58 el-ampli volumio[613]: Cannot find translation for source 80s80s Radio Oct 07 11:42:58 el-ampli volumio[613]: Cannot find translation for source Mother Earth Radio Oct 07 11:42:58 el-ampli volumio[613]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 11:42:58 el-ampli volumio[613]: info: [1696671778037] CoreMusicLibrary::Adding element Podcast Oct 07 11:42:58 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 11:42:58 el-ampli volumio[613]: Cannot find translation for source 80s80s Radio Oct 07 11:42:58 el-ampli volumio[613]: Cannot find translation for source Mother Earth Radio Oct 07 11:42:58 el-ampli volumio[613]: Cannot find translation for source Podcast Oct 07 11:42:58 el-ampli volumio[613]: info: [GPIO_Control] Reading config and creating GPIOs Oct 07 11:42:58 el-ampli volumio[613]: info: Volumio Calling Home Oct 07 11:42:58 el-ampli volumio-remote-updater[373]: [2023-10-07 11:42:58] [connect] Successful connection Oct 07 11:42:58 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 11:42:58 el-ampli volumio[613]: [SpotifyConnect] Creating VLS config file Oct 07 11:42:58 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:58 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:58 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:58 el-ampli volumio[613]: [SpotifyConnect] Starting metadata listener Oct 07 11:42:58 el-ampli volumio[613]: info: GPIO-Buttons started Oct 07 11:42:58 el-ampli volumio[613]: info: Preparing to generate the ALSA configuration file Oct 07 11:42:58 el-ampli sudo[839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Oct 07 11:42:58 el-ampli sudo[839]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:42:58 el-ampli volumio[613]: info: [GPIO_Control] GPIOs created Oct 07 11:42:58 el-ampli systemd[1]: Started Volspotconnect2 Daemon. Oct 07 11:42:58 el-ampli sudo[839]: pam_unix(sudo:session): session closed for user root Oct 07 11:42:58 el-ampli volumio[613]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 07 11:42:58 el-ampli volumio[613]: info: Reading ALSA contributions from plugins. Oct 07 11:42:58 el-ampli volumio[613]: info: CoreCommandRouter::volumioGetState Oct 07 11:42:58 el-ampli volumio[613]: info: CorePlayQueue::getTrack 0 Oct 07 11:42:58 el-ampli volumio[613]: [SpotifyConnect] Vollibrespot Daemon service started! Oct 07 11:42:58 el-ampli volumio[613]: [Metrics] SpotifyConnect: 0s 341.91ms Oct 07 11:42:58 el-ampli volumio[613]: info: minidlna: Found miniDLNA version 1.2.1 Oct 07 11:42:58 el-ampli volumio[613]: info: minidlna: Starting minidlna.service Oct 07 11:42:58 el-ampli volumio[841]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Oct 07 11:42:58 el-ampli volumio[841]: Reading Config from "/tmp/volspotify.toml" Oct 07 11:42:58 el-ampli volumio[613]: info: MPD Permissions set Oct 07 11:42:58 el-ampli volumio-remote-updater[373]: [2023-10-07 11:42:58] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1696671778 101 Oct 07 11:42:58 el-ampli sudo[844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Oct 07 11:42:58 el-ampli volumio[841]: [Vollibrespot] : Using Alsa backend with device: volumio Oct 07 11:42:58 el-ampli volumio[613]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Oct 07 11:42:58 el-ampli sudo[844]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:42:58 el-ampli volumio[613]: info: Volumio called home Oct 07 11:42:58 el-ampli systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Oct 07 11:42:58 el-ampli sudo[844]: pam_unix(sudo:session): session closed for user root Oct 07 11:42:58 el-ampli volumio[841]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Oct 07 11:42:59 el-ampli volumio[613]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Oct 07 11:42:59 el-ampli volumio[613]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Oct 07 11:42:59 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:59 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:59 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:59 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:59 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:59 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:59 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:59 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:59 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 11:42:59 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:42:59 el-ampli volumio[613]: info: minidlna: systemctl start minidlna.service succeeded. Oct 07 11:42:59 el-ampli volumio[613]: info: Starting Shairport Sync Oct 07 11:42:59 el-ampli volumio[613]: info: Starting Shairport Sync Oct 07 11:42:59 el-ampli minidlnad[846]: [2023/10/07 11:42:59] minidlna.c:631: error: Media directory "A,/mnt/USB/MUSICA/MUSICA" not accessible [No such file or directory] Oct 07 11:42:59 el-ampli sudo[852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 11:42:59 el-ampli sudo[852]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:42:59 el-ampli volumio[613]: info: Starting Shairport Sync Oct 07 11:42:59 el-ampli systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 11:42:59 el-ampli systemd[1]: shairport-sync.service: Succeeded. Oct 07 11:42:59 el-ampli systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 11:42:59 el-ampli sudo[856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 11:42:59 el-ampli sudo[856]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:42:59 el-ampli systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 11:42:59 el-ampli sudo[852]: pam_unix(sudo:session): session closed for user root Oct 07 11:42:59 el-ampli sudo[859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 11:42:59 el-ampli sudo[859]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:42:59 el-ampli systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 11:42:59 el-ampli systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 07 11:42:59 el-ampli systemd[1]: shairport-sync.service: Succeeded. Oct 07 11:42:59 el-ampli systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 11:42:59 el-ampli systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 11:42:59 el-ampli sudo[856]: pam_unix(sudo:session): session closed for user root Oct 07 11:42:59 el-ampli sudo[859]: pam_unix(sudo:session): session closed for user root Oct 07 11:42:59 el-ampli volumio[613]: info: Shairport-Sync Started Oct 07 11:42:59 el-ampli volumio[613]: Error adding Membership: Error: addMembership EINVAL Oct 07 11:42:59 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:43:00 el-ampli mpd[832]: Oct 07 11:43 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 07 11:43:00 el-ampli volumio[613]: info: AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Oct 07 11:43:00 el-ampli volumio[613]: info: FusionDsp loaded Oct 07 11:43:00 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 11:43:00 el-ampli sudo[874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 07 11:43:00 el-ampli sudo[874]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:43:00 el-ampli volumio[613]: info: Asound.conf file unchanged, so no further update is needed Oct 07 11:43:00 el-ampli volumio[613]: info: Output device has changed, restarting MPD Oct 07 11:43:00 el-ampli volumio[613]: info: Output device has changed, restarting Shairport Sync Oct 07 11:43:00 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:43:00 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 11:43:00 el-ampli sudo[877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 11:43:00 el-ampli sudo[877]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:43:00 el-ampli mpd[832]: Oct 07 11:43 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Oct 07 11:43:00 el-ampli mpd[832]: Oct 07 11:43 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Oct 07 11:43:00 el-ampli sudo[877]: pam_unix(sudo:session): session closed for user root Oct 07 11:43:00 el-ampli sudo[879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 11:43:00 el-ampli systemd[1]: Started FusionDsp Daemon. Oct 07 11:43:00 el-ampli systemd[1]: Started Music Player Daemon. Oct 07 11:43:00 el-ampli sudo[879]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:43:00 el-ampli sudo[818]: pam_unix(sudo:session): session closed for user root Oct 07 11:43:00 el-ampli sudo[874]: pam_unix(sudo:session): session closed for user root Oct 07 11:43:00 el-ampli systemd[1]: Stopping Music Player Daemon... Oct 07 11:43:00 el-ampli volumio[613]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 11:43:00 el-ampli volumio[613]: [SpotifyConnect] Creating VLS config file Oct 07 11:43:00 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:43:00 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:43:00 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:43:00 el-ampli volumio[613]: [SpotifyConnect] Restarting Vollibrespot Daemon Oct 07 11:43:00 el-ampli systemd[1]: mpd.service: Succeeded. Oct 07 11:43:00 el-ampli systemd[1]: Stopped Music Player Daemon. Oct 07 11:43:00 el-ampli systemd[1]: Starting Music Player Daemon... Oct 07 11:43:00 el-ampli sudo[890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Oct 07 11:43:00 el-ampli sudo[890]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:43:00 el-ampli volumio[613]: info: Shairport-Sync Started Oct 07 11:43:00 el-ampli volumio[613]: info: Shairport-Sync Started Oct 07 11:43:00 el-ampli systemd[1]: Stopping Volspotconnect2 Daemon... Oct 07 11:43:00 el-ampli systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Oct 07 11:43:00 el-ampli systemd[1]: volspotconnect.service: Succeeded. Oct 07 11:43:00 el-ampli systemd[1]: Stopped Volspotconnect2 Daemon. Oct 07 11:43:00 el-ampli systemd[1]: Started Volspotconnect2 Daemon. Oct 07 11:43:00 el-ampli sudo[890]: pam_unix(sudo:session): session closed for user root Oct 07 11:43:00 el-ampli volumio[892]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Oct 07 11:43:00 el-ampli volumio[892]: Reading Config from "/tmp/volspotify.toml" Oct 07 11:43:00 el-ampli volumio[892]: [Vollibrespot] : Using Alsa backend with device: volumio Oct 07 11:43:00 el-ampli volumio[613]: info: MPD Permissions set Oct 07 11:43:00 el-ampli volumio[613]: error: Upnp client error: Error: This socket has been ended by the other party Oct 07 11:43:00 el-ampli volumio[613]: info: CoreCommandRouter::volumioGetState Oct 07 11:43:00 el-ampli volumio[613]: info: CorePlayQueue::getTrack 0 Oct 07 11:43:00 el-ampli volumio[892]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Oct 07 11:43:00 el-ampli volumio[613]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Oct 07 11:43:00 el-ampli volumio[613]: [SpotifyConnect] Vollibrespot Daemon service restarted! Oct 07 11:43:00 el-ampli volumio[613]: error: File size not found in array! Oct 07 11:43:00 el-ampli volumio[613]: info: eq1: Oct 07 11:43:00 el-ampli volumio[613]: type: Biquad Oct 07 11:43:00 el-ampli volumio[613]: parameters: Oct 07 11:43:00 el-ampli volumio[613]: type: Peaking Oct 07 11:43:00 el-ampli volumio[613]: freq: 25 Oct 07 11:43:00 el-ampli volumio[613]: q: 1.85 Oct 07 11:43:00 el-ampli volumio[613]: gain: 0 Oct 07 11:43:00 el-ampli volumio[613]: eq2: Oct 07 11:43:00 el-ampli volumio[613]: type: Biquad Oct 07 11:43:00 el-ampli volumio[613]: parameters: Oct 07 11:43:00 el-ampli volumio[613]: type: Peaking Oct 07 11:43:00 el-ampli volumio[613]: freq: 40 Oct 07 11:43:00 el-ampli volumio[613]: q: 1.85 Oct 07 11:43:00 el-ampli volumio[613]: gain: 0 Oct 07 11:43:00 el-ampli volumio[613]: eq3: Oct 07 11:43:00 el-ampli volumio[613]: type: Biquad Oct 07 11:43:00 el-ampli volumio[613]: parameters: Oct 07 11:43:00 el-ampli volumio[613]: type: Peaking Oct 07 11:43:00 el-ampli volumio[613]: freq: 63 Oct 07 11:43:00 el-ampli volumio[613]: q: 1.85 Oct 07 11:43:00 el-ampli volumio[613]: gain: 0 Oct 07 11:43:00 el-ampli volumio[613]: eq4: Oct 07 11:43:00 el-ampli volumio[613]: type: Biquad Oct 07 11:43:00 el-ampli volumio[613]: parameters: Oct 07 11:43:00 el-ampli volumio[613]: type: Peaking Oct 07 11:43:00 el-ampli volumio[613]: freq: 100 Oct 07 11:43:00 el-ampli volumio[613]: q: 1.85 Oct 07 11:43:00 el-ampli volumio[613]: gain: 0 Oct 07 11:43:00 el-ampli volumio[613]: eq5: Oct 07 11:43:00 el-ampli volumio[613]: type: Biquad Oct 07 11:43:00 el-ampli volumio[613]: parameters: Oct 07 11:43:00 el-ampli volumio[613]: type: Peaking Oct 07 11:43:00 el-ampli volumio[613]: freq: 160 Oct 07 11:43:00 el-ampli volumio[613]: q: 1.85 Oct 07 11:43:00 el-ampli volumio[613]: gain: 0 Oct 07 11:43:00 el-ampli volumio[613]: eq6: Oct 07 11:43:00 el-ampli volumio[613]: type: Biquad Oct 07 11:43:00 el-ampli volumio[613]: parameters: Oct 07 11:43:00 el-ampli volumio[613]: type: Peaking Oct 07 11:43:00 el-ampli volumio[613]: freq: 250 Oct 07 11:43:00 el-ampli volumio[613]: q: 1.85 Oct 07 11:43:00 el-ampli volumio[613]: gain: 0 Oct 07 11:43:00 el-ampli volumio[613]: eq7: Oct 07 11:43:00 el-ampli volumio[613]: type: Biquad Oct 07 11:43:00 el-ampli volumio[613]: parameters: Oct 07 11:43:00 el-ampli volumio[613]: type: Peaking Oct 07 11:43:00 el-ampli volumio[613]: freq: 400 Oct 07 11:43:00 el-ampli volumio[613]: q: 1.85 Oct 07 11:43:00 el-ampli volumio[613]: gain: 0 Oct 07 11:43:00 el-ampli volumio[613]: eq8: Oct 07 11:43:00 el-ampli volumio[613]: type: Biquad Oct 07 11:43:00 el-ampli volumio[613]: parameters: Oct 07 11:43:00 el-ampli volumio[613]: type: Peaking Oct 07 11:43:00 el-ampli volumio[613]: freq: 630 Oct 07 11:43:00 el-ampli volumio[613]: q: 1.85 Oct 07 11:43:00 el-ampli volumio[613]: gain: 0 Oct 07 11:43:00 el-ampli volumio[613]: eq9: Oct 07 11:43:00 el-ampli volumio[613]: type: Biquad Oct 07 11:43:00 el-ampli volumio[613]: parameters: Oct 07 11:43:00 el-ampli volumio[613]: type: Peaking Oct 07 11:43:00 el-ampli volumio[613]: freq: 1000 Oct 07 11:43:00 el-ampli volumio[613]: q: 1.85 Oct 07 11:43:00 el-ampli volumio[613]: gain: 0 Oct 07 11:43:00 el-ampli volumio[613]: eq10: Oct 07 11:43:00 el-ampli volumio[613]: type: Biquad Oct 07 11:43:00 el-ampli volumio[613]: parameters: Oct 07 11:43:00 el-ampli volumio[613]: type: Peaking Oct 07 11:43:00 el-ampli volumio[613]: freq: 1600 Oct 07 11:43:00 el-ampli volumio[613]: q: 1.85 Oct 07 11:43:00 el-ampli volumio[613]: gain: 0 Oct 07 11:43:00 el-ampli volumio[613]: eq11: Oct 07 11:43:00 el-ampli volumio[613]: type: Biquad Oct 07 11:43:00 el-ampli volumio[613]: parameters: Oct 07 11:43:00 el-ampli volumio[613]: type: Peaking Oct 07 11:43:00 el-ampli volumio[613]: freq: 2500 Oct 07 11:43:00 el-ampli volumio[613]: q: 1.85 Oct 07 11:43:00 el-ampli volumio[613]: gain: 0 Oct 07 11:43:00 el-ampli volumio[613]: eq12: Oct 07 11:43:00 el-ampli volumio[613]: type: Biquad Oct 07 11:43:00 el-ampli volumio[613]: parameters: Oct 07 11:43:00 el-ampli volumio[613]: type: Peaking Oct 07 11:43:00 el-ampli volumio[613]: freq: 4000 Oct 07 11:43:00 el-ampli volumio[613]: q: 1.85 Oct 07 11:43:00 el-ampli volumio[613]: gain: 0 Oct 07 11:43:00 el-ampli volumio[613]: eq13: Oct 07 11:43:00 el-ampli volumio[613]: type: Biquad Oct 07 11:43:00 el-ampli volumio[613]: parameters: Oct 07 11:43:00 el-ampli volumio[613]: type: Peaking Oct 07 11:43:00 el-ampli volumio[613]: freq: 6300 Oct 07 11:43:00 el-ampli volumio[613]: q: 1.85 Oct 07 11:43:00 el-ampli volumio[613]: gain: 0 Oct 07 11:43:00 el-ampli volumio[613]: eq14: Oct 07 11:43:00 el-ampli volumio[613]: type: Biquad Oct 07 11:43:00 el-ampli volumio[613]: parameters: Oct 07 11:43:00 el-ampli volumio[613]: type: Peaking Oct 07 11:43:00 el-ampli volumio[613]: freq: 10000 Oct 07 11:43:00 el-ampli volumio[613]: q: 1.85 Oct 07 11:43:00 el-ampli volumio[613]: gain: 0 Oct 07 11:43:00 el-ampli volumio[613]: eq15: Oct 07 11:43:00 el-ampli volumio[613]: type: Biquad Oct 07 11:43:00 el-ampli volumio[613]: parameters: Oct 07 11:43:00 el-ampli volumio[613]: type: Peaking Oct 07 11:43:00 el-ampli volumio[613]: freq: 16000 Oct 07 11:43:00 el-ampli volumio[613]: q: 1.85 Oct 07 11:43:00 el-ampli volumio[613]: gain: 0 Oct 07 11:43:00 el-ampli sudo[896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=MANUSAN,password=70700635,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.10/MUSICA /mnt/NAS/MUZIC Oct 07 11:43:00 el-ampli sudo[896]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:43:01 el-ampli kernel: CIFS: Attempting to mount //192.168.0.10/MUSICA Oct 07 11:43:01 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:43:01 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:43:01 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:43:01 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 11:43:01 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 11:43:01 el-ampli volumio[613]: error: MPD error: The expression evaluated to a falsy value: Oct 07 11:43:01 el-ampli volumio[613]: assert.ok(self.idling) Oct 07 11:43:01 el-ampli volumio[613]: error: The expression evaluated to a falsy value: Oct 07 11:43:01 el-ampli volumio[613]: assert.ok(self.idling) Oct 07 11:43:01 el-ampli volumio[613]: error: MPD error: The expression evaluated to a falsy value: Oct 07 11:43:01 el-ampli volumio[613]: assert.ok(self.idling) Oct 07 11:43:01 el-ampli volumio[613]: error: The expression evaluated to a falsy value: Oct 07 11:43:01 el-ampli volumio[613]: assert.ok(self.idling) Oct 07 11:43:01 el-ampli volumio[613]: error: WebSocket error: [object Object] Oct 07 11:43:01 el-ampli volumio[613]: info: Starting Shairport Sync Oct 07 11:43:01 el-ampli sudo[906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 11:43:01 el-ampli sudo[906]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:43:01 el-ampli systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 11:43:01 el-ampli systemd[1]: shairport-sync.service: Succeeded. Oct 07 11:43:01 el-ampli systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 11:43:01 el-ampli systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 11:43:01 el-ampli sudo[906]: pam_unix(sudo:session): session closed for user root Oct 07 11:43:01 el-ampli volumio[613]: info: Shairport-Sync Started Oct 07 11:43:02 el-ampli volumio[613]: info: Checking Spotify Web API Oct 07 11:43:03 el-ampli mpd[900]: Oct 07 11:43 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 07 11:43:03 el-ampli mpd[900]: Oct 07 11:43 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Oct 07 11:43:03 el-ampli mpd[900]: Oct 07 11:43 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Oct 07 11:43:03 el-ampli systemd[1]: Started Music Player Daemon. Oct 07 11:43:03 el-ampli sudo[879]: pam_unix(sudo:session): session closed for user root Oct 07 11:43:03 el-ampli volumio[613]: error: updateQueue error: null Oct 07 11:43:03 el-ampli volumio[613]: error: updateQueue error: null Oct 07 11:43:03 el-ampli volumio[613]: error: Upnp client error: Error: This socket has been ended by the other party Oct 07 11:43:03 el-ampli volumio[813]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 07 11:43:06 el-ampli sudo[926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 07 11:43:06 el-ampli sudo[926]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:43:06 el-ampli sudo[926]: pam_unix(sudo:session): session closed for user root Oct 07 11:43:06 el-ampli sudo[928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 07 11:43:06 el-ampli sudo[928]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:43:06 el-ampli sudo[928]: pam_unix(sudo:session): session closed for user root Oct 07 11:43:06 el-ampli volumio[613]: verbose: New Socket.io Connection to 192.168.0.152 from 192.168.0.20 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Total Clients: 7 Oct 07 11:43:06 el-ampli volumio[613]: info: Checking Spotify Web API Oct 07 11:43:07 el-ampli sudo[896]: pam_unix(sudo:session): session closed for user root Oct 07 11:43:07 el-ampli kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 07 11:43:07 el-ampli kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 07 11:43:07 el-ampli volumio[613]: info: Cannot mount NAS MUZIC at system boot, trial number 2 ,retrying in 5 seconds Oct 07 11:43:07 el-ampli sudo[934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 07 11:43:07 el-ampli sudo[934]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:43:07 el-ampli sudo[934]: pam_unix(sudo:session): session closed for user root Oct 07 11:43:07 el-ampli sudo[936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 07 11:43:07 el-ampli sudo[936]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:43:07 el-ampli sudo[936]: pam_unix(sudo:session): session closed for user root Oct 07 11:43:07 el-ampli volumio[613]: verbose: New Socket.io Connection to 192.168.0.152 from 192.168.0.20 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Total Clients: 8 Oct 07 11:43:07 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 07 11:43:07 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 07 11:43:07 el-ampli volumio[613]: info: CoreCommandRouter::volumioGetVisibleSources Oct 07 11:43:07 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 11:43:07 el-ampli volumio[613]: info: CoreCommandRouter::volumioGetState Oct 07 11:43:07 el-ampli volumio[613]: info: CorePlayQueue::getTrack 0 Oct 07 11:43:07 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 07 11:43:07 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 07 11:43:07 el-ampli volumio[613]: info: Received Get System Info Oct 07 11:43:07 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 11:43:07 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 11:43:07 el-ampli volumio[613]: info: Discovery: Getting this device information Oct 07 11:43:07 el-ampli volumio[613]: info: CoreCommandRouter::volumioGetState Oct 07 11:43:07 el-ampli volumio[613]: info: CorePlayQueue::getTrack 0 Oct 07 11:43:07 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 11:43:07 el-ampli volumio[613]: info: CoreCommandRouter::volumioGetState Oct 07 11:43:07 el-ampli volumio[613]: info: CorePlayQueue::getTrack 0 Oct 07 11:43:07 el-ampli volumio[613]: info: Listing playlists Oct 07 11:43:07 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 07 11:43:07 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 07 11:43:08 el-ampli volumio[613]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 11:43:08 el-ampli volumio[613]: info: effect true Oct 07 11:43:08 el-ampli volumio[613]: info: Dsp mode set is EQ15 Oct 07 11:43:08 el-ampli volumio[613]: info: << convolution filters enabled Oct 07 11:43:08 el-ampli volumio[613]: info: tools file to play : Oct 07 11:43:08 el-ampli volumio[613]: info: CoreCommandRouter::volumioGetState Oct 07 11:43:08 el-ampli volumio[613]: info: CorePlayQueue::getTrack 0 Oct 07 11:43:09 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 07 11:43:09 el-ampli volumio[613]: info: Received Get System Info Oct 07 11:43:09 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 11:43:09 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 11:43:09 el-ampli volumio[613]: info: Discovery: Getting this device information Oct 07 11:43:09 el-ampli volumio[613]: info: CoreCommandRouter::volumioGetState Oct 07 11:43:09 el-ampli volumio[613]: info: CorePlayQueue::getTrack 0 Oct 07 11:43:09 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 11:43:12 el-ampli sudo[942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=MANUSAN,password=70700635,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.10/MUSICA /mnt/NAS/MUZIC Oct 07 11:43:12 el-ampli sudo[942]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:43:12 el-ampli kernel: CIFS: Attempting to mount //192.168.0.10/MUSICA Oct 07 11:43:14 el-ampli volumio[613]: info: CoreCommandRouter::volumioGetQueue Oct 07 11:43:14 el-ampli volumio[613]: info: CoreStateMachine::getQueue Oct 07 11:43:14 el-ampli volumio[613]: info: CorePlayQueue::getQueue Oct 07 11:43:18 el-ampli sudo[942]: pam_unix(sudo:session): session closed for user root Oct 07 11:43:18 el-ampli kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 07 11:43:18 el-ampli kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 07 11:43:18 el-ampli volumio[613]: info: Cannot mount NAS MUZIC at system boot, trial number 3 ,retrying in 5 seconds Oct 07 11:43:19 el-ampli volumio[613]: info: CoreCommandRouter::volumioGetState Oct 07 11:43:19 el-ampli volumio[613]: info: CorePlayQueue::getTrack 0 Oct 07 11:43:20 el-ampli ntpd[574]: Soliciting pool server 193.149.0.221 Oct 07 11:43:20 el-ampli volumio[813]: .......................................................................++++ Oct 07 11:43:21 el-ampli ntpd[574]: Soliciting pool server 194.0.5.123 Oct 07 11:43:21 el-ampli ntpd[574]: Soliciting pool server 217.71.200.210 Oct 07 11:43:22 el-ampli ntpd[574]: Soliciting pool server 178.215.228.24 Oct 07 11:43:22 el-ampli ntpd[574]: Soliciting pool server 178.32.88.247 Oct 07 11:43:23 el-ampli ntpd[574]: Soliciting pool server 162.159.200.123 Oct 07 11:43:23 el-ampli ntpd[574]: Soliciting pool server 65.20.99.74 Oct 07 11:43:23 el-ampli volumio[613]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 11:43:23 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 07 11:43:23 el-ampli sudo[947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=MANUSAN,password=70700635,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.10/MUSICA /mnt/NAS/MUZIC Oct 07 11:43:23 el-ampli sudo[947]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 11:43:23 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 07 11:43:23 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 07 11:43:23 el-ampli kernel: CIFS: Attempting to mount //192.168.0.10/MUSICA Oct 07 11:43:23 el-ampli volumio[613]: info: Received Get System Version Oct 07 11:43:23 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 11:43:24 el-ampli ntpd[574]: Soliciting pool server 89.140.186.3 Oct 07 11:43:25 el-ampli ntpd[574]: Soliciting pool server 217.160.115.110 Oct 07 11:43:25 el-ampli ntpd[574]: Soliciting pool server 208.85.20.220 Oct 07 11:43:25 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 07 11:43:25 el-ampli volumio[613]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 07 11:43:25 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 07 11:43:25 el-ampli volumio[613]: info: Received Get System Version Oct 07 11:43:25 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 11:43:25 el-ampli volumio[613]: info: Received Get System Info Oct 07 11:43:25 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 11:43:25 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 11:43:25 el-ampli volumio[613]: info: Discovery: Getting this device information Oct 07 11:43:25 el-ampli volumio[613]: info: CoreCommandRouter::volumioGetState Oct 07 11:43:25 el-ampli volumio[613]: info: CorePlayQueue::getTrack 0 Oct 07 11:43:25 el-ampli volumio[613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 11:43:25 el-ampli volumio[613]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 11:43:25 el-ampli volumio[613]: Error: certificate is not yet valid Oct 07 11:43:25 el-ampli volumio[613]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Oct 07 11:43:25 el-ampli volumio[613]: at TLSSocket.emit (events.js:315:20) Oct 07 11:43:25 el-ampli volumio[613]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Oct 07 11:43:25 el-ampli volumio[613]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Oct 07 11:43:25 el-ampli volumio[613]: code: 'CERT_NOT_YET_VALID' Oct 07 11:43:25 el-ampli volumio[613]: } Oct 07 11:43:26 el-ampli volumio[613]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 11:43:26 el-ampli ntpd[574]: Soliciting pool server 185.198.109.227 Oct 07 11:43:26 el-ampli ntpd[574]: Soliciting pool server 90.68.206.60 Oct 07 11:43:27 el-ampli sudo[960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-10-07 11:42 Oct 07 11:43:27 el-ampli sudo[960]: 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="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"