-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Tue 2024-03-26 20:31:08 CET. -- Mar 26 20:30:06 volumio-pi systemd[1]: Started Forward Password Requests to Wall Directory Watch. Mar 26 20:30:06 volumio-pi systemd[1]: Listening on RPCbind Server Activation Socket. Mar 26 20:30:06 volumio-pi systemd[1]: Created slice User and Session Slice. Mar 26 20:30:06 volumio-pi systemd[1]: Reached target Slices. Mar 26 20:30:06 volumio-pi systemd[1]: Listening on udev Kernel Socket. Mar 26 20:30:06 volumio-pi systemd[1]: Listening on Journal Audit Socket. Mar 26 20:30:06 volumio-pi kernel: i2c_dev: i2c /dev entries driver Mar 26 20:30:06 volumio-pi systemd-journald[193]: Journal started Mar 26 20:30:06 volumio-pi systemd-journald[193]: Runtime journal (/run/log/journal/4ceeebcde4a5f04b591e77826532825c) is 7.5M, max 30.0M, 22.5M free. Mar 26 20:30:06 volumio-pi systemd-modules-load[191]: Inserted module 'i2c_dev' Mar 26 20:30:06 volumio-pi fake-hwclock[197]: Tue Mar 26 19:30:06 UTC 2024 Mar 26 20:30:06 volumio-pi systemd[1]: Started Restore / save the current clock. Mar 26 20:30:06 volumio-pi systemd[1]: Mounted Kernel Configuration File System. Mar 26 20:30:06 volumio-pi systemd[1]: Started Load/Save Random Seed. Mar 26 20:30:06 volumio-pi systemd[1]: dynamicswap.service: Succeeded. Mar 26 20:30:06 volumio-pi systemd[1]: Started Apply Kernel Variables. Mar 26 20:30:06 volumio-pi systemd[1]: Started Create System Users. Mar 26 20:30:06 volumio-pi systemd[1]: Starting Create Static Device Nodes in /dev... Mar 26 20:30:06 volumio-pi systemd[1]: Started udev Coldplug all Devices. Mar 26 20:30:06 volumio-pi systemd[1]: Starting Helper to synchronize boot up for ifupdown... Mar 26 20:30:06 volumio-pi systemd[1]: Started Create Static Device Nodes in /dev. Mar 26 20:30:06 volumio-pi systemd[1]: Reached target Local File Systems (Pre). Mar 26 20:30:06 volumio-pi systemd[1]: Mounting /var/spool/cups... Mar 26 20:30:06 volumio-pi systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Mar 26 20:30:06 volumio-pi systemd[1]: Mounting /var/log... Mar 26 20:30:06 volumio-pi systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Mar 26 20:30:06 volumio-pi systemd[1]: Mounting /tmp... Mar 26 20:30:06 volumio-pi systemd[1]: Starting udev Kernel Device Manager... Mar 26 20:30:06 volumio-pi systemd[1]: Started Helper to synchronize boot up for ifupdown. Mar 26 20:30:06 volumio-pi systemd[1]: Mounted /var/spool/cups. Mar 26 20:30:06 volumio-pi systemd[1]: Mounted /var/log. Mar 26 20:30:06 volumio-pi systemd[1]: Mounted /tmp. Mar 26 20:30:06 volumio-pi systemd[1]: Starting Flush Journal to Persistent Storage... Mar 26 20:30:06 volumio-pi systemd[1]: Mounting /var/spool/cups/tmp... Mar 26 20:30:06 volumio-pi systemd[1]: Mounted /var/spool/cups/tmp. Mar 26 20:30:06 volumio-pi systemd-udevd[229]: Network interface NamePolicy= disabled on kernel command line, ignoring. Mar 26 20:30:06 volumio-pi systemd-journald[193]: Runtime journal (/run/log/journal/4ceeebcde4a5f04b591e77826532825c) is 7.5M, max 30.0M, 22.5M free. Mar 26 20:30:06 volumio-pi systemd[1]: Started udev Kernel Device Manager. Mar 26 20:30:06 volumio-pi systemd[1]: Started Flush Journal to Persistent Storage. Mar 26 20:30:06 volumio-pi systemd[1]: Starting Show Plymouth Boot Screen... Mar 26 20:30:06 volumio-pi systemd[1]: Received SIGRTMIN+20 from PID 244 (plymouthd). Mar 26 20:30:07 volumio-pi systemd[1]: Started Show Plymouth Boot Screen. Mar 26 20:30:07 volumio-pi systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Mar 26 20:30:07 volumio-pi systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Mar 26 20:30:07 volumio-pi systemd[1]: Reached target Local Encrypted Volumes. Mar 26 20:30:07 volumio-pi systemd[1]: Reached target Paths. Mar 26 20:30:07 volumio-pi kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 Mar 26 20:30:07 volumio-pi kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem Mar 26 20:30:07 volumio-pi kernel: mc: Linux media interface: v0.10 Mar 26 20:30:07 volumio-pi kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Mar 26 20:30:07 volumio-pi kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Mar 26 20:30:07 volumio-pi kernel: [vc_sm_connected_init]: start Mar 26 20:30:07 volumio-pi kernel: [vc_sm_connected_init]: installed successfully Mar 26 20:30:07 volumio-pi kernel: videodev: Linux video capture interface: v2.00 Mar 26 20:30:07 volumio-pi kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Mar 26 20:30:07 volumio-pi kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Mar 26 20:30:07 volumio-pi kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Mar 26 20:30:07 volumio-pi kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Mar 26 20:30:07 volumio-pi kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Mar 26 20:30:07 volumio-pi kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Mar 26 20:30:07 volumio-pi kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Mar 26 20:30:07 volumio-pi kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Mar 26 20:30:07 volumio-pi kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Mar 26 20:30:07 volumio-pi kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Mar 26 20:30:07 volumio-pi kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Mar 26 20:30:07 volumio-pi kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Mar 26 20:30:07 volumio-pi kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Mar 26 20:30:07 volumio-pi kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Mar 26 20:30:07 volumio-pi kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Mar 26 20:30:07 volumio-pi kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Mar 26 20:30:07 volumio-pi kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Mar 26 20:30:07 volumio-pi kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Mar 26 20:30:07 volumio-pi kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Mar 26 20:30:07 volumio-pi systemd[1]: Found device /dev/ttyAMA0. Mar 26 20:30:07 volumio-pi kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Mar 26 20:30:07 volumio-pi kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Mar 26 20:30:07 volumio-pi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Mar 26 20:30:07 volumio-pi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Mar 26 20:30:08 volumio-pi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Mar 26 20:30:08 volumio-pi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Mar 26 20:30:08 volumio-pi systemd-udevd[240]: Using default interface naming scheme 'v240'. Mar 26 20:30:08 volumio-pi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Mar 26 20:30:08 volumio-pi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Mar 26 20:30:08 volumio-pi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Mar 26 20:30:08 volumio-pi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Mar 26 20:30:08 volumio-pi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Mar 26 20:30:08 volumio-pi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Mar 26 20:30:08 volumio-pi kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Mar 26 20:30:08 volumio-pi kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Mar 26 20:30:08 volumio-pi systemd-udevd[240]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 26 20:30:08 volumio-pi kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Mar 26 20:30:08 volumio-pi kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Mar 26 20:30:08 volumio-pi systemd-udevd[236]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 26 20:30:08 volumio-pi systemd[1]: Found device /dev/mmcblk0p1. Mar 26 20:30:09 volumio-pi kernel: wm8804 1-003b: revision E Mar 26 20:30:09 volumio-pi systemd-udevd[233]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 0' failed with exit code 17. Mar 26 20:30:10 volumio-pi systemd[1]: Condition check resulted in SMSC9512/9514 Fast Ethernet Adapter being skipped. Mar 26 20:30:10 volumio-pi systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Mar 26 20:30:10 volumio-pi systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Mar 26 20:30:10 volumio-pi systemd[1]: Condition check resulted in FUSE Control File System being skipped. Mar 26 20:30:10 volumio-pi systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Mar 26 20:30:10 volumio-pi systemd[1]: Condition check resulted in Huge Pages File System being skipped. Mar 26 20:30:10 volumio-pi systemd[1]: Starting File System Check on /dev/mmcblk0p1... Mar 26 20:30:10 volumio-pi systemd[1]: Started File System Check Daemon to report status. Mar 26 20:30:11 volumio-pi systemd-fsck[334]: fsck.fat 4.1 (2017-01-24) Mar 26 20:30:11 volumio-pi systemd-fsck[334]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Mar 26 20:30:11 volumio-pi systemd-fsck[334]: Automatically removing dirty bit. Mar 26 20:30:11 volumio-pi systemd-fsck[334]: Performing changes. Mar 26 20:30:11 volumio-pi systemd-fsck[334]: /dev/mmcblk0p1: 367 files, 33349/46774 clusters Mar 26 20:30:11 volumio-pi systemd[1]: Started File System Check on /dev/mmcblk0p1. Mar 26 20:30:11 volumio-pi systemd[1]: Mounting /boot... Mar 26 20:30:11 volumio-pi systemd[1]: Mounted /boot. Mar 26 20:30:11 volumio-pi systemd[1]: Reached target Local File Systems. Mar 26 20:30:11 volumio-pi systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Mar 26 20:30:11 volumio-pi systemd[1]: Starting Create Volatile Files and Directories... Mar 26 20:30:11 volumio-pi systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Mar 26 20:30:11 volumio-pi systemd[1]: Starting Raise network interfaces... Mar 26 20:30:11 volumio-pi systemd[1]: Started ifup for eth0. Mar 26 20:30:11 volumio-pi systemd[1]: Starting Preprocess NFS configuration... Mar 26 20:30:11 volumio-pi systemd[1]: plymouth-read-write.service: Succeeded. Mar 26 20:30:11 volumio-pi systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Mar 26 20:30:11 volumio-pi systemd[1]: Received SIGRTMIN+20 from PID 244 (plymouthd). Mar 26 20:30:11 volumio-pi systemd[1]: nfs-config.service: Succeeded. Mar 26 20:30:11 volumio-pi systemd[1]: Started Preprocess NFS configuration. Mar 26 20:30:11 volumio-pi systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Mar 26 20:30:11 volumio-pi systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Mar 26 20:30:11 volumio-pi systemd[1]: Reached target NFS client services. Mar 26 20:30:11 volumio-pi systemd[1]: Started Create Volatile Files and Directories. Mar 26 20:30:11 volumio-pi systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Mar 26 20:30:11 volumio-pi systemd[1]: Starting Update UTMP about System Boot/Shutdown... Mar 26 20:30:11 volumio-pi systemd[1]: Starting RPC bind portmap service... Mar 26 20:30:11 volumio-pi systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Mar 26 20:30:11 volumio-pi kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Mar 26 20:30:11 volumio-pi systemd[1]: Started RPC bind portmap service. Mar 26 20:30:11 volumio-pi systemd[1]: Reached target Remote File Systems (Pre). Mar 26 20:30:11 volumio-pi systemd[1]: Reached target Remote File Systems. Mar 26 20:30:11 volumio-pi systemd[1]: Reached target RPC Port Mapper. Mar 26 20:30:11 volumio-pi systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Mar 26 20:30:11 volumio-pi systemd[1]: Started Update UTMP about System Boot/Shutdown. Mar 26 20:30:11 volumio-pi systemd[1]: Reached target System Initialization. Mar 26 20:30:11 volumio-pi systemd[1]: Listening on triggerhappy.socket. Mar 26 20:30:11 volumio-pi kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Mar 26 20:30:11 volumio-pi kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Mar 26 20:30:11 volumio-pi kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Mar 26 20:30:11 volumio-pi systemd[1]: Started Volumio Splash. Mar 26 20:30:11 volumio-pi systemd[1]: Started Daily Cleanup of Temporary Directories. Mar 26 20:30:11 volumio-pi systemd[374]: volumiosplash.service: Failed to execute command: No such file or directory Mar 26 20:30:11 volumio-pi systemd[374]: volumiosplash.service: Failed at step EXEC spawning /bin/volumiosplash.sh: No such file or directory Mar 26 20:30:11 volumio-pi systemd[1]: Started Daily apt download activities. Mar 26 20:30:11 volumio-pi systemd[1]: Started Daily apt upgrade and clean activities. Mar 26 20:30:11 volumio-pi systemd[1]: Reached target Timers. Mar 26 20:30:11 volumio-pi systemd[1]: Listening on D-Bus System Message Bus Socket. Mar 26 20:30:11 volumio-pi systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Mar 26 20:30:11 volumio-pi systemd[1]: Reached target Sockets. Mar 26 20:30:11 volumio-pi systemd[1]: Reached target Basic System. Mar 26 20:30:11 volumio-pi systemd[1]: Started UPnP Renderer front-end to MPD. Mar 26 20:30:11 volumio-pi systemd[1]: Started volumio-remote-updater.service. Mar 26 20:30:11 volumio-pi systemd[1]: Starting triggerhappy global hotkey daemon... Mar 26 20:30:11 volumio-pi ifup[343]: command failed: No such device (-19) Mar 26 20:30:11 volumio-pi systemd[1]: Started Manage Sound Card State (restore and store). Mar 26 20:30:11 volumio-pi systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Mar 26 20:30:11 volumio-pi systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Mar 26 20:30:11 volumio-pi sh[344]: command failed: No such device (-19) Mar 26 20:30:12 volumio-pi alsactl[389]: alsactl 1.1.8 daemon started Mar 26 20:30:12 volumio-pi systemd[1]: Starting dhcpcd on all interfaces... Mar 26 20:30:12 volumio-pi systemd[1]: Condition check resulted in Configure Bluetooth Modems connected by UART being skipped. Mar 26 20:30:12 volumio-pi systemd[1]: Started Volumio Iptables Module. Mar 26 20:30:12 volumio-pi thd[388]: Unable to parse trigger line: Mar 26 20:30:12 volumio-pi thd[388]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Mar 26 20:30:12 volumio-pi thd[388]: Unable to parse trigger line: Mar 26 20:30:12 volumio-pi thd[388]: Found socket passed from systemd Mar 26 20:30:12 volumio-pi systemd[1]: Started Volumio Log Rotation Service. Mar 26 20:30:12 volumio-pi dhcpcd[392]: Not running dhcpcd because /etc/network/interfaces Mar 26 20:30:12 volumio-pi dhcpcd[392]: defines some interfaces that will use a Mar 26 20:30:12 volumio-pi dhcpcd[392]: DHCP client or static address Mar 26 20:30:12 volumio-pi systemd[1]: Started D-Bus System Message Bus. Mar 26 20:30:12 volumio-pi systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Mar 26 20:30:12 volumio-pi systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Mar 26 20:30:12 volumio-pi systemd[1]: Starting Save/Restore Sound Card State... Mar 26 20:30:12 volumio-pi systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Mar 26 20:30:12 volumio-pi systemd[1]: Starting WPA supplicant... Mar 26 20:30:12 volumio-pi systemd[1]: Starting Login Service... Mar 26 20:30:12 volumio-pi systemd[1]: Starting Wireless Services... Mar 26 20:30:12 volumio-pi systemd[1]: Started triggerhappy global hotkey daemon. Mar 26 20:30:12 volumio-pi systemd[1]: volumiosplash.service: Main process exited, code=exited, status=203/EXEC Mar 26 20:30:12 volumio-pi systemd[1]: volumiosplash.service: Failed with result 'exit-code'. Mar 26 20:30:12 volumio-pi systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Mar 26 20:30:12 volumio-pi systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Mar 26 20:30:12 volumio-pi systemd[1]: Failed to start dhcpcd on all interfaces. Mar 26 20:30:12 volumio-pi ifup[343]: command failed: No such device (-19) Mar 26 20:30:12 volumio-pi systemd-logind[417]: New seat seat0. Mar 26 20:30:13 volumio-pi avahi-daemon[404]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Mar 26 20:30:13 volumio-pi avahi-daemon[404]: Successfully dropped root privileges. Mar 26 20:30:13 volumio-pi avahi-daemon[404]: avahi-daemon 0.7 starting up. Mar 26 20:30:13 volumio-pi kernel: 8021q: 802.1Q VLAN Support v1.8 Mar 26 20:30:13 volumio-pi systemd[1]: Started Login Service. Mar 26 20:30:13 volumio-pi avahi-daemon[404]: Successfully called chroot(). Mar 26 20:30:13 volumio-pi avahi-daemon[404]: Successfully dropped remaining capabilities. Mar 26 20:30:13 volumio-pi systemd[1]: Started Save/Restore Sound Card State. Mar 26 20:30:13 volumio-pi wpa_supplicant[413]: Successfully initialized wpa_supplicant Mar 26 20:30:13 volumio-pi avahi-daemon[404]: Loading service file /services/volumio.service. Mar 26 20:30:13 volumio-pi avahi-daemon[404]: Network interface enumeration completed. Mar 26 20:30:13 volumio-pi avahi-daemon[404]: Server startup complete. Host name is volumio-pi.local. Local service cookie is 3031763447. Mar 26 20:30:13 volumio-pi avahi-daemon[404]: Service "Volumio-PI" (/services/volumio.service) successfully established. Mar 26 20:30:14 volumio-pi systemd[1]: Started Avahi mDNS/DNS-SD Stack. Mar 26 20:30:14 volumio-pi volumio-remote-updater[386]: Error: No active session Mar 26 20:30:14 volumio-pi systemd[1]: Started WPA supplicant. Mar 26 20:30:14 volumio-pi systemd[1]: Reached target Sound Card. Mar 26 20:30:14 volumio-pi volumio-remote-updater[386]: [2024-03-26 20:30:14] [info] asio async_connect error: system:111 (Connection refused) Mar 26 20:30:14 volumio-pi volumio-remote-updater[386]: [2024-03-26 20:30:14] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 26 20:30:14 volumio-pi volumio-remote-updater[386]: [2024-03-26 20:30:14] [error] handle_connect error: Underlying Transport Error Mar 26 20:30:14 volumio-pi volumio[384]: Could not open config: /tmp/upmpdcli.conf Mar 26 20:30:14 volumio-pi kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Mar 26 20:30:14 volumio-pi kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Mar 26 20:30:14 volumio-pi sh[344]: eth0: waiting for carrier Mar 26 20:30:14 volumio-pi sh[344]: eth0: carrier acquired Mar 26 20:30:14 volumio-pi dhcpcd[416]: eth0: waiting for carrier Mar 26 20:30:14 volumio-pi sh[344]: DUID 00:01:00:01:2c:c5:40:e8:b8:27:eb:ed:9d:54 Mar 26 20:30:14 volumio-pi sh[344]: eth0: IAID eb:ed:9d:54 Mar 26 20:30:14 volumio-pi sh[344]: eth0: adding address fe80::ce9f:8e5f:ed59:bb02 Mar 26 20:30:14 volumio-pi sh[344]: ipv6_addaddr1: Permission denied Mar 26 20:30:14 volumio-pi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 26 20:30:14 volumio-pi dhcpcd[416]: eth0: carrier acquired Mar 26 20:30:14 volumio-pi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 26 20:30:14 volumio-pi dhcpcd[416]: DUID 00:01:00:01:2c:c5:40:e8:b8:27:eb:ed:9d:54 Mar 26 20:30:14 volumio-pi dhcpcd[416]: eth0: IAID eb:ed:9d:54 Mar 26 20:30:14 volumio-pi dhcpcd[416]: eth0: adding address fe80::ce9f:8e5f:ed59:bb02 Mar 26 20:30:14 volumio-pi dhcpcd[416]: ipv6_addaddr1: Permission denied Mar 26 20:30:14 volumio-pi systemd[1]: iptables.service: Succeeded. Mar 26 20:30:14 volumio-pi systemd[1]: Started Raise network interfaces. Mar 26 20:30:14 volumio-pi systemd[1]: Reached target Network. Mar 26 20:30:14 volumio-pi systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Mar 26 20:30:14 volumio-pi sh[344]: eth0: rebinding lease of 192.168.0.150 Mar 26 20:30:14 volumio-pi dhcpcd[416]: eth0: rebinding lease of 192.168.0.150 Mar 26 20:30:14 volumio-pi systemd[1]: Starting Network Time Service... Mar 26 20:30:14 volumio-pi systemd[1]: Starting Permit User Sessions... Mar 26 20:30:14 volumio-pi systemd[1]: Reached target Network is Online. Mar 26 20:30:14 volumio-pi systemd[1]: Starting LSB: Brings up/down network automatically... Mar 26 20:30:14 volumio-pi systemd[1]: Starting /etc/rc.local Compatibility... Mar 26 20:30:14 volumio-pi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 26 20:30:14 volumio-pi loadcpufreq[410]: Loading cpufreq kernel modules...done (none). Mar 26 20:30:14 volumio-pi systemd[1]: Starting Samba NMB Daemon... Mar 26 20:30:14 volumio-pi systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Mar 26 20:30:14 volumio-pi sh[344]: eth0: probing address 192.168.0.150/24 Mar 26 20:30:14 volumio-pi dhcpcd[416]: eth0: probing address 192.168.0.150/24 Mar 26 20:30:14 volumio-pi systemd[1]: Started /etc/rc.local Compatibility. Mar 26 20:30:14 volumio-pi systemd[1]: Starting LSB: set CPUFreq kernel parameters... Mar 26 20:30:14 volumio-pi systemd[1]: Started Permit User Sessions. Mar 26 20:30:15 volumio-pi sh[344]: eth0: soliciting an IPv6 router Mar 26 20:30:15 volumio-pi dhcpcd[416]: eth0: soliciting an IPv6 router Mar 26 20:30:15 volumio-pi ntpd[520]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 26 20:30:15 volumio-pi ntpd[520]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Mar 26 20:30:15 volumio-pi systemd[1]: Starting Terminate Plymouth Boot Screen... Mar 26 20:30:15 volumio-pi systemd[1]: Starting Hold until boot process finishes up... Mar 26 20:30:15 volumio-pi ntpd[552]: proto: precision = 1.666 usec (-19) Mar 26 20:30:15 volumio-pi systemd[1]: Started Network Time Service. Mar 26 20:30:15 volumio-pi systemd[1]: Received SIGRTMIN+21 from PID 244 (plymouthd). Mar 26 20:30:15 volumio-pi ntpd[552]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 26 20:30:15 volumio-pi ntpd[552]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 26 20:30:15 volumio-pi cpufrequtils[535]: CPUFreq Utilities: Setting ondemand CPUFreq governor...error: unexpectedly disconnected from boot status daemon Mar 26 20:30:15 volumio-pi systemd[1]: plymouth-start.service: Succeeded. Mar 26 20:30:15 volumio-pi ntpd[552]: Listen and drop on 0 v6wildcard [::]:123 Mar 26 20:30:15 volumio-pi ntpd[552]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 26 20:30:15 volumio-pi ntpd[552]: Listen normally on 2 lo 127.0.0.1:123 Mar 26 20:30:15 volumio-pi ntpd[552]: Listening on routing socket on fd #19 for interface updates Mar 26 20:30:15 volumio-pi ntpd[552]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 26 20:30:15 volumio-pi ntpd[552]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 26 20:30:15 volumio-pi systemd[1]: plymouth-quit.service: Succeeded. Mar 26 20:30:15 volumio-pi systemd[1]: Started Terminate Plymouth Boot Screen. Mar 26 20:30:15 volumio-pi haveged[367]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Mar 26 20:30:15 volumio-pi haveged[367]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Mar 26 20:30:15 volumio-pi haveged[367]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00071 Mar 26 20:30:15 volumio-pi haveged[367]: haveged: fills: 0, generated: 0 Mar 26 20:30:15 volumio-pi systemd[1]: plymouth-quit-wait.service: Succeeded. Mar 26 20:30:15 volumio-pi ifplugd(eth0)[571]: ifplugd 0.28 initializing. Mar 26 20:30:15 volumio-pi ifplugd(eth0)[571]: Using interface eth0/B8:27:EB:ED:9D:54 with driver (version: 6.1.69-v7+) Mar 26 20:30:15 volumio-pi systemd[1]: Started Hold until boot process finishes up. Mar 26 20:30:15 volumio-pi ifplugd(eth0)[571]: Using detection mode: SIOCETHTOOL Mar 26 20:30:15 volumio-pi ifplugd(eth0)[571]: Initialization complete, link beat detected. Mar 26 20:30:15 volumio-pi systemd[1]: Received SIGRTMIN+21 from PID 244 (n/a). Mar 26 20:30:15 volumio-pi cpufrequtils[535]: CPU0...CPU1...CPU2...CPU3...done. Mar 26 20:30:15 volumio-pi systemd[1]: Started Serial Getty on ttyAMA0. Mar 26 20:30:15 volumio-pi systemd[1]: Started Getty on tty1. Mar 26 20:30:15 volumio-pi systemd[1]: Reached target Login Prompts. Mar 26 20:30:15 volumio-pi systemd[1]: Started LSB: set CPUFreq kernel parameters. Mar 26 20:30:15 volumio-pi ifplugd(eth0)[571]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Mar 26 20:30:15 volumio-pi ifplugd(eth0)[571]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Mar 26 20:30:16 volumio-pi ntpd[552]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 26 20:30:16 volumio-pi nmbd[537]: [2024/03/26 20:30:16.538973, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Mar 26 20:30:16 volumio-pi nmbd[537]: started asyncdns process 594 Mar 26 20:30:16 volumio-pi nmbd[537]: [2024/03/26 20:30:16.546276, 0] ../lib/util/become_daemon.c:149(daemon_status) Mar 26 20:30:16 volumio-pi nmbd[537]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Mar 26 20:30:16 volumio-pi nmbd[537]: [2024/03/26 20:30:16.546605, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Mar 26 20:30:16 volumio-pi nmbd[537]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Mar 26 20:30:17 volumio-pi ntpd[552]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 26 20:30:17 volumio-pi wireless.js[418]: WIRELESS: No wireless interface, exiting Mar 26 20:30:17 volumio-pi iw[599]: command failed: No such device (-19) Mar 26 20:30:17 volumio-pi iwconfig[602]: Error for wireless request "Set Power Management" (8B2C) : Mar 26 20:30:17 volumio-pi iwconfig[602]: SET failed on device wlan0 ; No such device. Mar 26 20:30:17 volumio-pi systemd[1]: wireless.service: Succeeded. Mar 26 20:30:17 volumio-pi systemd[1]: Started Wireless Services. Mar 26 20:30:17 volumio-pi systemd[1]: Started Volumio Backend Module. Mar 26 20:30:17 volumio-pi systemd[1]: Started Volumio Cpu Tweaker. Mar 26 20:30:17 volumio-pi volumio-cpu-tweak[606]: Setting RT Priority for mpd Mar 26 20:30:17 volumio-pi volumio-cpu-tweak[606]: pid 35's current scheduling policy: SCHED_OTHER Mar 26 20:30:17 volumio-pi volumio-cpu-tweak[606]: pid 35's current scheduling priority: 0 Mar 26 20:30:17 volumio-pi volumio-cpu-tweak[606]: Setting MPD Affinity Mar 26 20:30:17 volumio-pi volumio-cpu-tweak[606]: pid 3's current affinity mask: f Mar 26 20:30:17 volumio-pi volumio-cpu-tweak[606]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Mar 26 20:30:17 volumio-pi volumio-cpu-tweak[606]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Mar 26 20:30:17 volumio-pi systemd[1]: volumio_cpu_tweak.service: Succeeded. Mar 26 20:30:18 volumio-pi ntpd[552]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 26 20:30:19 volumio-pi volumio-remote-updater[386]: [2024-03-26 20:30:19] [info] asio async_connect error: system:111 (Connection refused) Mar 26 20:30:19 volumio-pi volumio-remote-updater[386]: [2024-03-26 20:30:19] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 26 20:30:19 volumio-pi volumio-remote-updater[386]: [2024-03-26 20:30:19] [error] handle_connect error: Underlying Transport Error Mar 26 20:30:19 volumio-pi ntpd[552]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 26 20:30:20 volumio-pi sh[344]: eth0: leased 192.168.0.150 for 604800 seconds Mar 26 20:30:20 volumio-pi sh[344]: eth0: adding route to 192.168.0.0/24 Mar 26 20:30:20 volumio-pi sh[344]: eth0: adding default route via 192.168.0.1 Mar 26 20:30:20 volumio-pi dhcpcd[416]: eth0: leased 192.168.0.150 for 604800 seconds Mar 26 20:30:20 volumio-pi avahi-daemon[404]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.150. Mar 26 20:30:20 volumio-pi dhcpcd[416]: eth0: adding route to 192.168.0.0/24 Mar 26 20:30:20 volumio-pi avahi-daemon[404]: New relevant interface eth0.IPv4 for mDNS. Mar 26 20:30:20 volumio-pi avahi-daemon[404]: Registering new address record for 192.168.0.150 on eth0.IPv4. Mar 26 20:30:20 volumio-pi dhcpcd[416]: eth0: adding default route via 192.168.0.1 Mar 26 20:30:20 volumio-pi sh[344]: forked to background, child pid 643 Mar 26 20:30:20 volumio-pi dhcpcd[416]: forked to background, child pid 643 Mar 26 20:30:20 volumio-pi ifplugd(eth0)[571]: client: ifup: interface eth0 already configured Mar 26 20:30:20 volumio-pi sh[344]: eth0=eth0 Mar 26 20:30:20 volumio-pi ifplugd(eth0)[571]: Program executed successfully. Mar 26 20:30:20 volumio-pi ifplugd[528]: Network Interface Plugging Daemon...start eth0...done. Mar 26 20:30:20 volumio-pi systemd[1]: Started LSB: Brings up/down network automatically. Mar 26 20:30:21 volumio-pi nmbd[537]: [2024/03/26 20:30:21.559042, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 26 20:30:21 volumio-pi systemd[1]: Started Samba NMB Daemon. Mar 26 20:30:21 volumio-pi nmbd[537]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 26 20:30:21 volumio-pi systemd[1]: Starting Samba Winbind Daemon... Mar 26 20:30:22 volumio-pi winbindd[669]: [2024/03/26 20:30:22.213297, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 26 20:30:22 volumio-pi winbindd[669]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 26 20:30:22 volumio-pi winbindd[669]: [2024/03/26 20:30:22.260929, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 26 20:30:22 volumio-pi winbindd[669]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 26 20:30:22 volumio-pi systemd[1]: Started Samba Winbind Daemon. Mar 26 20:30:22 volumio-pi ntpd[552]: Listen normally on 3 eth0 192.168.0.150:123 Mar 26 20:30:22 volumio-pi ntpd[552]: new interface(s) found: waking up resolver Mar 26 20:30:22 volumio-pi systemd[1]: Starting Samba SMB Daemon... Mar 26 20:30:23 volumio-pi volumio[605]: info: ------------------------------------------- Mar 26 20:30:23 volumio-pi volumio[605]: info: ----- Volumio3 ---- Mar 26 20:30:23 volumio-pi volumio[605]: info: ------------------------------------------- Mar 26 20:30:23 volumio-pi volumio[605]: info: ----- System startup ---- Mar 26 20:30:23 volumio-pi volumio[605]: info: ------------------------------------------- Mar 26 20:30:23 volumio-pi smbd[678]: [2024/03/26 20:30:23.260529, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 26 20:30:23 volumio-pi systemd[1]: Started Samba SMB Daemon. Mar 26 20:30:23 volumio-pi smbd[678]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 26 20:30:23 volumio-pi systemd[1]: Reached target Multi-User System. Mar 26 20:30:23 volumio-pi systemd[1]: Reached target Graphical Interface. Mar 26 20:30:23 volumio-pi systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 26 20:30:23 volumio-pi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 26 20:30:23 volumio-pi systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 26 20:30:23 volumio-pi systemd[1]: Startup finished in 10.711s (kernel) + 20.029s (userspace) = 30.740s. Mar 26 20:30:25 volumio-pi volumio[605]: info: MYVOLUMIO Environment detected Mar 26 20:30:25 volumio-pi volumio[605]: info: Plugin folders cleanup Mar 26 20:30:25 volumio-pi volumio[605]: info: Scanning into folder /volumio/app/plugins/ Mar 26 20:30:25 volumio-pi volumio[605]: info: Scanning category audio_interface Mar 26 20:30:25 volumio-pi volumio[605]: info: Scanning category miscellanea Mar 26 20:30:25 volumio-pi volumio[605]: info: Scanning category music_service Mar 26 20:30:25 volumio-pi volumio[605]: info: Scanning category plugins.json Mar 26 20:30:25 volumio-pi volumio[605]: info: Scanning category system_controller Mar 26 20:30:25 volumio-pi volumio[605]: info: Scanning category user_interface Mar 26 20:30:25 volumio-pi volumio[605]: info: Scanning into folder /data/plugins/ Mar 26 20:30:25 volumio-pi volumio[605]: info: Scanning category music_service Mar 26 20:30:25 volumio-pi volumio[605]: info: Scanning category system_controller Mar 26 20:30:25 volumio-pi volumio[605]: info: Scanning category user_interface Mar 26 20:30:25 volumio-pi volumio[605]: info: Plugin folders cleanup completed Mar 26 20:30:25 volumio-pi volumio[605]: info: ------------------------------------------- Mar 26 20:30:25 volumio-pi volumio[605]: info: ----- Core plugins startup ---- Mar 26 20:30:25 volumio-pi volumio[605]: info: ------------------------------------------- Mar 26 20:30:25 volumio-pi volumio[605]: info: Loading plugins from folder /volumio/app/plugins/ Mar 26 20:30:25 volumio-pi volumio[605]: info: Adding plugin upnp to MyMusic Plugins Mar 26 20:30:25 volumio-pi volumio[605]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 26 20:30:25 volumio-pi volumio[605]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 26 20:30:25 volumio-pi volumio[605]: info: Loading plugins from folder /data/plugins/ Mar 26 20:30:25 volumio-pi volumio[605]: info: Loading plugin "system"... Mar 26 20:30:25 volumio-pi volumio[605]: info: Loading plugin "appearance"... Mar 26 20:30:26 volumio-pi volumio-remote-updater[386]: [2024-03-26 20:30:26] [connect] Successful connection Mar 26 20:30:28 volumio-pi volumio[605]: info: Loading plugin "network"... Mar 26 20:30:28 volumio-pi volumio[605]: info: Refreshing Cached IP Addresses Mar 26 20:30:28 volumio-pi volumio[605]: info: Loading plugin "services"... Mar 26 20:30:28 volumio-pi volumio[605]: info: Loading plugin "alsa_controller"... Mar 26 20:30:28 volumio-pi sudo[699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 20:30:28 volumio-pi sudo[701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 26 20:30:28 volumio-pi sudo[699]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 26 20:30:28 volumio-pi sudo[701]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 26 20:30:28 volumio-pi sudo[697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 20:30:28 volumio-pi sudo[697]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 26 20:30:28 volumio-pi sudo[697]: pam_unix(sudo:session): session closed for user root Mar 26 20:30:28 volumio-pi sudo[701]: pam_unix(sudo:session): session closed for user root Mar 26 20:30:28 volumio-pi sudo[699]: pam_unix(sudo:session): session closed for user root Mar 26 20:30:28 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 20:30:28 volumio-pi volumio[605]: info: Loading plugin "wizard"... Mar 26 20:30:28 volumio-pi volumio[605]: info: Loading plugin "networkfs"... Mar 26 20:30:28 volumio-pi volumio[605]: info: Starting Udev Watcher for removable devices Mar 26 20:30:28 volumio-pi sudo[712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=domownik,password=kruszynka,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.111/MUSIC /mnt/NAS/JacaNas Mar 26 20:30:28 volumio-pi sudo[712]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 26 20:30:29 volumio-pi volumio[605]: info: Ignoring mount for partition: boot Mar 26 20:30:29 volumio-pi volumio[605]: info: Ignoring mount for partition: volumio Mar 26 20:30:29 volumio-pi volumio[605]: info: Ignoring mount for partition: volumio_data Mar 26 20:30:29 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 26 20:30:29 volumio-pi volumio[605]: info: Loading plugin "volumio_command_line_client"... Mar 26 20:30:29 volumio-pi volumio[605]: info: Loading plugin "upnp"... Mar 26 20:30:29 volumio-pi volumio[605]: info: [1711481429057] Starting Upmpd Daemon Mar 26 20:30:29 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 26 20:30:29 volumio-pi volumio[605]: info: Loading plugin "my_music"... Mar 26 20:30:29 volumio-pi volumio[605]: info: Loading plugin "mpd"... Mar 26 20:30:29 volumio-pi kernel: Key type cifs.spnego registered Mar 26 20:30:29 volumio-pi kernel: Key type cifs.idmap registered Mar 26 20:30:29 volumio-pi 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. Mar 26 20:30:29 volumio-pi kernel: CIFS: Attempting to mount \\192.168.0.111\MUSIC Mar 26 20:30:30 volumio-pi volumio[605]: info: Loading plugin "upnp_browser"... Mar 26 20:30:30 volumio-pi sudo[712]: pam_unix(sudo:session): session closed for user root Mar 26 20:30:30 volumio-pi volumio[605]: info: Loading plugin "alarm-clock"... Mar 26 20:30:31 volumio-pi volumio[605]: info: Loading plugin "airplay_emulation"... Mar 26 20:30:31 volumio-pi volumio[605]: info: Starting Shairport Sync Mar 26 20:30:31 volumio-pi volumio[605]: info: Loading plugin "last_100"... Mar 26 20:30:31 volumio-pi volumio[605]: info: Loading plugin "webradio"... Mar 26 20:30:31 volumio-pi volumio[605]: info: Loading plugin "i2s_dacs"... Mar 26 20:30:31 volumio-pi volumio[605]: info: Loading plugin "volumiodiscovery"... Mar 26 20:30:31 volumio-pi volumio[605]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 26 20:30:31 volumio-pi volumio[605]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 26 20:30:31 volumio-pi volumio[605]: *** WARNING *** For more information see Mar 26 20:30:31 volumio-pi node[605]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 26 20:30:31 volumio-pi volumio[605]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 26 20:30:31 volumio-pi volumio[605]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 26 20:30:31 volumio-pi volumio[605]: *** WARNING *** For more information see Mar 26 20:30:31 volumio-pi node[605]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 26 20:30:31 volumio-pi node[605]: *** WARNING *** For more information see Mar 26 20:30:31 volumio-pi node[605]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 26 20:30:31 volumio-pi node[605]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 26 20:30:31 volumio-pi node[605]: *** WARNING *** For more information see Mar 26 20:30:31 volumio-pi volumio[605]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 26 20:30:31 volumio-pi volumio[605]: info: Discovery: Started advertising with name: Volumio-PI Mar 26 20:30:31 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 26 20:30:31 volumio-pi volumio[605]: info: Loading plugin "bandcamp"... Mar 26 20:30:34 volumio-pi volumio[605]: info: Loading plugin "spop"... Mar 26 20:30:37 volumio-pi volumio[605]: info: Loading plugin "outputs"... Mar 26 20:30:37 volumio-pi volumio[605]: info: Loading plugin "albumart"... Mar 26 20:30:37 volumio-pi volumio[605]: info: Plugin example_plugin is not enabled Mar 26 20:30:37 volumio-pi volumio[605]: info: Loading plugin "inputs"... Mar 26 20:30:37 volumio-pi volumio[605]: info: Loading plugin "updater_comm"... Mar 26 20:30:37 volumio-pi volumio[605]: info: Plugin mpdemulation is not enabled Mar 26 20:30:37 volumio-pi volumio[605]: info: Loading plugin "rest_api"... Mar 26 20:30:37 volumio-pi volumio[605]: info: Loading plugin "websocket"... Mar 26 20:30:37 volumio-pi volumio[605]: info: Loading plugin "rad357pl"... Mar 26 20:30:38 volumio-pi volumio[605]: Forking 3 albumart workers Mar 26 20:30:38 volumio-pi volumio[605]: info: Applying required configuration parameters for plugin rad357pl Mar 26 20:30:38 volumio-pi volumio[605]: info: [1711481438606] [rad357pl] API delay: 5 Mar 26 20:30:38 volumio-pi volumio[605]: info: Loading plugin "radio_paradise"... Mar 26 20:30:39 volumio-pi volumio[605]: info: Applying required configuration parameters for plugin radio_paradise Mar 26 20:30:39 volumio-pi volumio[605]: info: [1711481439347] [RadioParadise] API delay: 5 Mar 26 20:30:39 volumio-pi volumio[605]: info: Loading plugin "backup_restore"... Mar 26 20:30:40 volumio-pi volumio[605]: info: Applying required configuration parameters for plugin backup_restore Mar 26 20:30:40 volumio-pi volumio[605]: Starting albumart workers Mar 26 20:30:40 volumio-pi volumio[605]: info: Loading plugin "Systeminfo"... Mar 26 20:30:40 volumio-pi volumio[605]: Starting albumart workers Mar 26 20:30:40 volumio-pi volumio[605]: Starting albumart workers Mar 26 20:30:41 volumio-pi systemd[1]: systemd-fsckd.service: Succeeded. Mar 26 20:30:41 volumio-pi volumio[605]: info: Loading i18n strings for locale pl Mar 26 20:30:41 volumio-pi volumio[605]: Updating browse sources language Mar 26 20:30:41 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 20:30:41 volumio-pi volumio-remote-updater[386]: [2024-03-26 20:30:41] [connect] Successful connection Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreCommandRouter::initPlayerControls Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 20:30:42 volumio-pi volumio[605]: Express server listening on port 3000 Mar 26 20:30:42 volumio-pi volumio[605]: [Metrics] WebUI: 21s 6.72ms Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreStateMachine::resetVolumioState Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreStateMachine::getcurrentVolume Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreCommandRouter::volumioRetrievevolume Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreStateMachine::pushState Mar 26 20:30:42 volumio-pi volumio[605]: info: CorePlayQueue::getTrack 0 Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreCommandRouter::volumioPushState Mar 26 20:30:42 volumio-pi sudo[800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 20:30:42 volumio-pi sudo[800]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 26 20:30:42 volumio-pi sudo[800]: pam_unix(sudo:session): session closed for user root Mar 26 20:30:42 volumio-pi sudo[802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 20:30:42 volumio-pi sudo[802]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 26 20:30:42 volumio-pi sudo[802]: pam_unix(sudo:session): session closed for user root Mar 26 20:30:42 volumio-pi volumio[605]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 26 20:30:42 volumio-pi volumio[605]: wlan0 Interface doesn't support scanning. Mar 26 20:30:42 volumio-pi volumio[605]: info: Cannot use regular scanning, forcing with ap-force Mar 26 20:30:42 volumio-pi sudo[808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 26 20:30:42 volumio-pi sudo[808]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 26 20:30:42 volumio-pi sudo[808]: pam_unix(sudo:session): session closed for user root Mar 26 20:30:42 volumio-pi volumio[605]: command failed: No such device (-19) Mar 26 20:30:42 volumio-pi volumio[605]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Mar 26 20:30:42 volumio-pi volumio[605]: command failed: No such device (-19) Mar 26 20:30:42 volumio-pi volumio-remote-updater[386]: [2024-03-26 20:30:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1711481441 101 Mar 26 20:30:42 volumio-pi volumio[605]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 20:30:42 volumio-pi volumio[605]: info: Reloading queue from file Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreStateMachine::setRepeat null single undefined Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreStateMachine::pushState Mar 26 20:30:42 volumio-pi volumio[605]: info: CorePlayQueue::getTrack 0 Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreCommandRouter::volumioPushState Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreStateMachine::setRandom null Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreStateMachine::pushState Mar 26 20:30:42 volumio-pi volumio[605]: info: CorePlayQueue::getTrack 0 Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreCommandRouter::volumioPushState Mar 26 20:30:42 volumio-pi volumio[605]: info: Setting Device type: Raspberry PI Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 20:30:42 volumio-pi sudo[818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 26 20:30:42 volumio-pi sudo[818]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 26 20:30:42 volumio-pi volumio[605]: info: Discovery: adding 6a031533-204e-4f8f-ad4f-5c119d7a7e4c Mar 26 20:30:42 volumio-pi volumio[605]: info: Discovery: Found device Volumio-PI Mar 26 20:30:42 volumio-pi volumio[605]: info: CoreCommandRouter::volumioGetState Mar 26 20:30:43 volumio-pi volumio[605]: info: CorePlayQueue::getTrack 0 Mar 26 20:30:43 volumio-pi systemd[1]: Started UPnP Renderer front-end to MPD. Mar 26 20:30:43 volumio-pi sudo[818]: pam_unix(sudo:session): session closed for user root Mar 26 20:30:43 volumio-pi volumio[605]: info: Upmpdcli Daemon Started Mar 26 20:30:43 volumio-pi volumio[605]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Mar 26 20:30:47 volumio-pi volumio[605]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Mar 26 20:30:52 volumio-pi volumio[605]: info: CoreCommandRouter::volumioGetState Mar 26 20:30:52 volumio-pi volumio[605]: info: CorePlayQueue::getTrack 0 Mar 26 20:30:52 volumio-pi volumio[605]: info: Listing playlists Mar 26 20:30:52 volumio-pi volumio[605]: info: Listing playlists Mar 26 20:30:53 volumio-pi volumio[605]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Mar 26 20:31:02 volumio-pi volumio[605]: info: CoreCommandRouter::volumioGetState Mar 26 20:31:02 volumio-pi volumio[605]: info: CorePlayQueue::getTrack 0 Mar 26 20:31:03 volumio-pi volumio[605]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Mar 26 20:31:03 volumio-pi sudo[822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 20:31:03 volumio-pi sudo[822]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 26 20:31:03 volumio-pi sudo[822]: pam_unix(sudo:session): session closed for user root Mar 26 20:31:03 volumio-pi sudo[824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 20:31:03 volumio-pi sudo[824]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 26 20:31:03 volumio-pi sudo[824]: pam_unix(sudo:session): session closed for user root Mar 26 20:31:03 volumio-pi volumio[605]: verbose: New Socket.io Connection to 192.168.0.150 from 192.168.0.214 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Mobile Safari/537.36 Total Clients: 2 Mar 26 20:31:04 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 26 20:31:04 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 26 20:31:04 volumio-pi volumio[605]: info: CoreCommandRouter::volumioGetVisibleSources Mar 26 20:31:04 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 20:31:04 volumio-pi volumio[605]: info: CoreCommandRouter::volumioGetState Mar 26 20:31:04 volumio-pi volumio[605]: info: CorePlayQueue::getTrack 0 Mar 26 20:31:04 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 26 20:31:04 volumio-pi volumio[605]: info: CoreCommandRouter::volumioGetQueue Mar 26 20:31:04 volumio-pi volumio[605]: info: CoreStateMachine::getQueue Mar 26 20:31:04 volumio-pi volumio[605]: info: CorePlayQueue::getQueue Mar 26 20:31:04 volumio-pi volumio[605]: info: Listing playlists Mar 26 20:31:04 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 26 20:31:04 volumio-pi volumio[605]: info: Received Get System Info Mar 26 20:31:04 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 20:31:04 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 20:31:04 volumio-pi volumio[605]: info: Discovery: Getting this device information Mar 26 20:31:04 volumio-pi volumio[605]: info: CoreCommandRouter::volumioGetState Mar 26 20:31:04 volumio-pi volumio[605]: info: CorePlayQueue::getTrack 0 Mar 26 20:31:04 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 20:31:04 volumio-pi volumio[605]: info: CoreCommandRouter::volumioGetState Mar 26 20:31:04 volumio-pi volumio[605]: info: CorePlayQueue::getTrack 0 Mar 26 20:31:04 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 26 20:31:04 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 26 20:31:05 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 20:31:05 volumio-pi volumio[605]: info: Received Get System Info Mar 26 20:31:05 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 20:31:05 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 20:31:05 volumio-pi volumio[605]: info: Discovery: Getting this device information Mar 26 20:31:05 volumio-pi volumio[605]: info: CoreCommandRouter::volumioGetState Mar 26 20:31:05 volumio-pi volumio[605]: info: CorePlayQueue::getTrack 0 Mar 26 20:31:05 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 20:31:06 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Mar 26 20:31:07 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 20:31:07 volumio-pi volumio[605]: info: Received Get System Info Mar 26 20:31:07 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 20:31:07 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 20:31:07 volumio-pi volumio[605]: info: Discovery: Getting this device information Mar 26 20:31:07 volumio-pi volumio[605]: info: CoreCommandRouter::volumioGetState Mar 26 20:31:07 volumio-pi volumio[605]: info: CorePlayQueue::getTrack 0 Mar 26 20:31:07 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 20:31:07 volumio-pi volumio[605]: info: Preload queue cleared Mar 26 20:31:07 volumio-pi volumio[605]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 20:31:07 volumio-pi volumio[605]: info: CoreStateMachine::ClearQueue Mar 26 20:31:07 volumio-pi volumio[605]: info: CoreStateMachine::stop Mar 26 20:31:07 volumio-pi volumio[605]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 20:31:07 volumio-pi volumio[605]: info: CorePlayQueue::clearPlayQueue Mar 26 20:31:07 volumio-pi volumio[605]: info: CorePlayQueue::saveQueue Mar 26 20:31:07 volumio-pi volumio[605]: info: CoreCommandRouter::volumioPushQueue Mar 26 20:31:07 volumio-pi volumio[605]: info: CoreStateMachine::addQueueItems Mar 26 20:31:07 volumio-pi volumio[605]: info: CorePlayQueue::addQueueItems Mar 26 20:31:07 volumio-pi volumio[605]: info: Preload queue cleared Mar 26 20:31:07 volumio-pi volumio[605]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s87828 Mar 26 20:31:07 volumio-pi volumio[605]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 26 20:31:07 volumio-pi volumio[605]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 20:31:07 volumio-pi volumio[605]: TypeError: Cannot read property 'tune_radio' of undefined Mar 26 20:31:07 volumio-pi volumio[605]: at ControllerWebradio.explodeUri (/volumio/app/plugins/music_service/webradio/index.js:539:33) Mar 26 20:31:07 volumio-pi volumio[605]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) Mar 26 20:31:07 volumio-pi volumio[605]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:179:33) Mar 26 20:31:07 volumio-pi volumio[605]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Mar 26 20:31:07 volumio-pi volumio[605]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Mar 26 20:31:07 volumio-pi volumio[605]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:507:23) Mar 26 20:31:07 volumio-pi volumio[605]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:477:17) Mar 26 20:31:07 volumio-pi volumio[605]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:118:33) Mar 26 20:31:07 volumio-pi volumio[605]: at Socket.emit (events.js:315:20) Mar 26 20:31:07 volumio-pi volumio[605]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Mar 26 20:31:07 volumio-pi volumio[605]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Mar 26 20:31:07 volumio-pi volumio[605]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 20:31:08 volumio-pi sudo[838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-26 20:30 Mar 26 20:31:08 volumio-pi sudo[838]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"