-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2023-12-02 17:18:13 CET. -- Dec 02 17:17:57 volumior222 kernel: hub 1-1:1.0: 4 ports detected Dec 02 17:17:57 volumior222 kernel: EXT4-fs (mmcblk0p2): recovery complete Dec 02 17:17:57 volumior222 kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none. Dec 02 17:17:57 volumior222 initramfs: Doing a 5 second delay here to give kernel load a headstart Dec 02 17:17:57 volumior222 initramfs: Checking for USB updates Dec 02 17:17:57 volumior222 initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Dec 02 17:17:57 volumior222 kernel: loop0: detected capacity change from 0 to 962048 Dec 02 17:17:57 volumior222 kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Dec 02 17:17:57 volumior222 kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Dec 02 17:17:57 volumior222 kernel: EXT4-fs (mmcblk0p3): recovery complete Dec 02 17:17:57 volumior222 kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Quota mode: none. Dec 02 17:17:57 volumior222 initramfs: With Option: Dec 02 17:17:57 volumior222 initramfs: VOLUMIO_VERSION="3.569" Dec 02 17:17:57 volumior222 initramfs: Finish initramfs, continue booting Volumio Dec 02 17:17:57 volumior222 systemd[1]: System time before build time, advancing clock. Dec 02 17:17:57 volumior222 kernel: NET: Registered PF_INET6 protocol family Dec 02 17:17:57 volumior222 kernel: Segment Routing with IPv6 Dec 02 17:17:57 volumior222 kernel: In-situ OAM (IOAM) with IPv6 Dec 02 17:17:57 volumior222 systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Dec 02 17:17:57 volumior222 systemd[1]: Detected architecture arm. Dec 02 17:17:57 volumior222 systemd[1]: Set hostname to . Dec 02 17:17:57 volumior222 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 02 17:17:57 volumior222 systemd[1]: Reached target System Time Synchronized. Dec 02 17:17:57 volumior222 systemd[1]: Listening on udev Control Socket. Dec 02 17:17:57 volumior222 systemd[1]: Listening on RPCbind Server Activation Socket. Dec 02 17:17:57 volumior222 systemd[1]: Listening on fsck to fsckd communication Socket. Dec 02 17:17:57 volumior222 systemd[1]: Listening on udev Kernel Socket. Dec 02 17:17:57 volumior222 systemd[1]: Listening on Journal Socket (/dev/log). Dec 02 17:17:57 volumior222 kernel: i2c_dev: i2c /dev entries driver Dec 02 17:17:57 volumior222 systemd-journald[201]: Journal started Dec 02 17:17:57 volumior222 systemd-journald[201]: Runtime journal (/run/log/journal/4ceeebcde4a5f04b591e77826532825c) is 7.5M, max 30.0M, 22.5M free. Feb 14 11:11:59 volumior222 systemd-modules-load[204]: Inserted module 'i2c_dev' Dec 02 17:17:57 volumior222 fake-hwclock[210]: Sat Dec 2 16:17:57 UTC 2023 Dec 02 17:17:57 volumior222 systemd[1]: Started Restore / save the current clock. Dec 02 17:17:57 volumior222 systemd[1]: Started Apply Kernel Variables. Dec 02 17:17:57 volumior222 systemd[1]: Started Load/Save Random Seed. Dec 02 17:17:57 volumior222 systemd[1]: dynamicswap.service: Succeeded. Dec 02 17:17:57 volumior222 systemd[1]: Started Create System Users. Dec 02 17:17:57 volumior222 systemd[1]: Starting Create Static Device Nodes in /dev... Dec 02 17:17:57 volumior222 systemd[1]: Started Create Static Device Nodes in /dev. Dec 02 17:17:57 volumior222 systemd[1]: Starting udev Kernel Device Manager... Dec 02 17:17:57 volumior222 systemd[1]: Reached target Local File Systems (Pre). Dec 02 17:17:57 volumior222 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Dec 02 17:17:57 volumior222 systemd[1]: Mounting /var/log... Dec 02 17:17:57 volumior222 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Dec 02 17:17:57 volumior222 systemd[1]: Mounting /tmp... Dec 02 17:17:57 volumior222 systemd[1]: Mounting /var/spool/cups... Dec 02 17:17:57 volumior222 systemd[1]: Started udev Coldplug all Devices. Dec 02 17:17:57 volumior222 systemd[1]: Mounted /var/log. Dec 02 17:17:57 volumior222 systemd[1]: Mounted /tmp. Dec 02 17:17:57 volumior222 systemd[1]: Mounted /var/spool/cups. Dec 02 17:17:57 volumior222 systemd[1]: Mounting /var/spool/cups/tmp... Dec 02 17:17:57 volumior222 systemd[1]: Starting Flush Journal to Persistent Storage... Dec 02 17:17:57 volumior222 systemd-udevd[240]: Network interface NamePolicy= disabled on kernel command line, ignoring. Dec 02 17:17:57 volumior222 systemd[1]: Starting Helper to synchronize boot up for ifupdown... Dec 02 17:17:57 volumior222 systemd[1]: Mounted /var/spool/cups/tmp. Dec 02 17:17:57 volumior222 systemd-journald[201]: Runtime journal (/run/log/journal/4ceeebcde4a5f04b591e77826532825c) is 7.5M, max 30.0M, 22.5M free. Dec 02 17:17:57 volumior222 systemd[1]: Started Flush Journal to Persistent Storage. Dec 02 17:17:57 volumior222 systemd[1]: Started udev Kernel Device Manager. Dec 02 17:17:57 volumior222 systemd[1]: Starting Show Plymouth Boot Screen... Dec 02 17:17:57 volumior222 systemd[1]: Received SIGRTMIN+20 from PID 260 (plymouthd). Dec 02 17:17:57 volumior222 systemd[1]: Started Show Plymouth Boot Screen. Dec 02 17:17:57 volumior222 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Dec 02 17:17:57 volumior222 systemd[1]: Reached target Local Encrypted Volumes. Dec 02 17:17:57 volumior222 systemd[1]: Reached target Paths. Dec 02 17:17:57 volumior222 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Dec 02 17:17:57 volumior222 systemd-udevd[259]: Using default interface naming scheme 'v240'. Dec 02 17:17:57 volumior222 systemd-udevd[259]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 02 17:17:57 volumior222 kernel: mc: Linux media interface: v0.10 Dec 02 17:17:57 volumior222 kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Dec 02 17:17:57 volumior222 kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Dec 02 17:17:57 volumior222 kernel: [vc_sm_connected_init]: start Dec 02 17:17:57 volumior222 kernel: [vc_sm_connected_init]: installed successfully Dec 02 17:17:57 volumior222 kernel: videodev: Linux video capture interface: v2.00 Dec 02 17:17:58 volumior222 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Dec 02 17:17:58 volumior222 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Dec 02 17:17:58 volumior222 kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Dec 02 17:17:58 volumior222 kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Dec 02 17:17:58 volumior222 kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Dec 02 17:17:58 volumior222 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Dec 02 17:17:58 volumior222 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Dec 02 17:17:58 volumior222 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Dec 02 17:17:58 volumior222 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Dec 02 17:17:58 volumior222 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Dec 02 17:17:58 volumior222 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Dec 02 17:17:58 volumior222 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Dec 02 17:17:58 volumior222 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Dec 02 17:17:58 volumior222 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Dec 02 17:17:58 volumior222 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Dec 02 17:17:58 volumior222 kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Dec 02 17:17:58 volumior222 kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Dec 02 17:17:58 volumior222 kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Dec 02 17:17:58 volumior222 kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Dec 02 17:17:58 volumior222 kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Dec 02 17:17:58 volumior222 kernel: rpivid feb10000.codec: Device registered as /dev/video19 Dec 02 17:17:58 volumior222 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Dec 02 17:17:58 volumior222 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Dec 02 17:17:58 volumior222 systemd[1]: Found device /dev/mmcblk0p1. Dec 02 17:17:58 volumior222 systemd[1]: Starting File System Check on /dev/mmcblk0p1... Dec 02 17:17:58 volumior222 systemd[1]: Started File System Check Daemon to report status. Dec 02 17:17:58 volumior222 kernel: Bluetooth: Core ver 2.22 Dec 02 17:17:58 volumior222 kernel: NET: Registered PF_BLUETOOTH protocol family Dec 02 17:17:58 volumior222 kernel: Bluetooth: HCI device and connection manager initialized Dec 02 17:17:58 volumior222 kernel: Bluetooth: HCI socket layer initialized Dec 02 17:17:58 volumior222 kernel: Bluetooth: L2CAP socket layer initialized Dec 02 17:17:58 volumior222 kernel: Bluetooth: SCO socket layer initialized Dec 02 17:17:58 volumior222 systemd-fsck[323]: fsck.fat 4.1 (2017-01-24) Dec 02 17:17:58 volumior222 systemd-fsck[323]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Dec 02 17:17:58 volumior222 systemd-fsck[323]: Automatically removing dirty bit. Dec 02 17:17:58 volumior222 systemd-fsck[323]: Performing changes. Dec 02 17:17:58 volumior222 systemd-fsck[323]: /dev/mmcblk0p1: 363 files, 33267/46774 clusters Dec 02 17:17:58 volumior222 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Dec 02 17:17:58 volumior222 kernel: Bluetooth: HCI UART driver ver 2.3 Dec 02 17:17:58 volumior222 kernel: Bluetooth: HCI UART protocol H4 registered Dec 02 17:17:58 volumior222 kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Dec 02 17:17:58 volumior222 kernel: Bluetooth: HCI UART protocol Broadcom registered Dec 02 17:17:58 volumior222 kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Dec 02 17:17:58 volumior222 kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Dec 02 17:17:58 volumior222 systemd[1]: Started File System Check on /dev/mmcblk0p1. Dec 02 17:17:58 volumior222 kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Dec 02 17:17:58 volumior222 kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Dec 02 17:17:58 volumior222 kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Dec 02 17:17:58 volumior222 systemd-udevd[252]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 02 17:17:59 volumior222 kernel: uart-pl011 fe201000.serial: no DMA platform data Dec 02 17:17:59 volumior222 systemd[1]: Found device /sys/subsystem/net/devices/eth0. Dec 02 17:17:59 volumior222 kernel: Bluetooth: hci0: BCM: chip id 107 Dec 02 17:17:59 volumior222 kernel: Bluetooth: hci0: BCM: features 0x2f Dec 02 17:17:59 volumior222 kernel: Bluetooth: hci0: BCM4345C0 Dec 02 17:17:59 volumior222 kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Dec 02 17:17:59 volumior222 kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Dec 02 17:17:59 volumior222 kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Dec 02 17:17:59 volumior222 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Dec 02 17:17:59 volumior222 kernel: usbcore: registered new interface driver brcmfmac Dec 02 17:17:59 volumior222 kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov 1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60 Dec 02 17:17:59 volumior222 systemd-udevd[258]: Using default interface naming scheme 'v240'. Dec 02 17:17:59 volumior222 systemd-udevd[258]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 02 17:18:00 volumior222 systemd-udevd[250]: Process '/sbin/crda' failed with exit code 255. Dec 02 17:18:00 volumior222 kernel: Bluetooth: hci0: BCM: features 0x2f Dec 02 17:18:00 volumior222 kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Dec 02 17:18:00 volumior222 kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Dec 02 17:18:00 volumior222 systemd[1]: Created slice system-bthelper.slice. Dec 02 17:18:00 volumior222 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Dec 02 17:18:00 volumior222 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Dec 02 17:18:00 volumior222 systemd[1]: Condition check resulted in Huge Pages File System being skipped. Dec 02 17:18:00 volumior222 systemd[1]: Condition check resulted in FUSE Control File System being skipped. Dec 02 17:18:00 volumior222 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Dec 02 17:18:00 volumior222 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Dec 02 17:18:00 volumior222 systemd[1]: Mounting /boot... Dec 02 17:18:00 volumior222 systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Dec 02 17:18:00 volumior222 systemd[1]: Mounted /boot. Dec 02 17:18:00 volumior222 systemd[1]: Starting Load/Save RF Kill Switch Status... Dec 02 17:18:00 volumior222 systemd[1]: Reached target Local File Systems. Dec 02 17:18:00 volumior222 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Dec 02 17:18:00 volumior222 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Dec 02 17:18:00 volumior222 systemd[1]: Starting Preprocess NFS configuration... Dec 02 17:18:00 volumior222 systemd[1]: Started ifup for eth0. Dec 02 17:18:00 volumior222 systemd[1]: Starting Create Volatile Files and Directories... Dec 02 17:18:00 volumior222 systemd[1]: Started ifup for wlan0. Dec 02 17:18:00 volumior222 systemd[1]: Received SIGRTMIN+20 from PID 260 (plymouthd). Dec 02 17:18:00 volumior222 systemd[1]: plymouth-read-write.service: Succeeded. Dec 02 17:18:00 volumior222 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Dec 02 17:18:00 volumior222 systemd[1]: Started Load/Save RF Kill Switch Status. Dec 02 17:18:00 volumior222 systemd[1]: nfs-config.service: Succeeded. Dec 02 17:18:00 volumior222 systemd[1]: Started Preprocess NFS configuration. Dec 02 17:18:00 volumior222 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Dec 02 17:18:00 volumior222 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Dec 02 17:18:00 volumior222 systemd[1]: Reached target NFS client services. Dec 02 17:18:00 volumior222 systemd[1]: Started Create Volatile Files and Directories. Dec 02 17:18:00 volumior222 systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Dec 02 17:18:00 volumior222 systemd[1]: Starting RPC bind portmap service... Dec 02 17:18:00 volumior222 systemd[1]: Starting Update UTMP about System Boot/Shutdown... Dec 02 17:18:00 volumior222 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Dec 02 17:18:00 volumior222 systemd[1]: Started Helper to synchronize boot up for ifupdown. Dec 02 17:18:00 volumior222 systemd[1]: Starting Raise network interfaces... Dec 02 17:18:00 volumior222 systemd[1]: Started Update UTMP about System Boot/Shutdown. Dec 02 17:18:00 volumior222 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 02 17:18:00 volumior222 kernel: 8021q: 802.1Q VLAN Support v1.8 Dec 02 17:18:00 volumior222 systemd[1]: Reached target System Initialization. Dec 02 17:18:00 volumior222 systemd[1]: Listening on mpd.socket. Dec 02 17:18:00 volumior222 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Dec 02 17:18:00 volumior222 systemd[1]: Started Daily Cleanup of Temporary Directories. Dec 02 17:18:00 volumior222 systemd[1]: Listening on D-Bus System Message Bus Socket. Dec 02 17:18:00 volumior222 systemd[1]: Listening on triggerhappy.socket. Dec 02 17:18:00 volumior222 systemd[1]: Reached target Sockets. Dec 02 17:18:00 volumior222 systemd[1]: Reached target Basic System. Dec 02 17:18:00 volumior222 systemd[1]: Started UPnP Renderer front-end to MPD. Dec 02 17:18:00 volumior222 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Dec 02 17:18:00 volumior222 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Dec 02 17:18:00 volumior222 systemd[1]: Started Volumio Iptables Module. Dec 02 17:18:00 volumior222 systemd[1]: Starting triggerhappy global hotkey daemon... Dec 02 17:18:00 volumior222 systemd[1]: Started D-Bus System Message Bus. Dec 02 17:18:00 volumior222 systemd[1]: Starting Configure Bluetooth Modems connected by UART... Dec 02 17:18:00 volumior222 systemd[1]: Starting Login Service... Dec 02 17:18:00 volumior222 systemd[1]: Started volumio-remote-updater.service. Dec 02 17:18:01 volumior222 systemd[1]: Starting WPA supplicant... Dec 02 17:18:01 volumior222 systemd[1]: Starting Wireless Services... Dec 02 17:18:01 volumior222 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Dec 02 17:18:01 volumior222 systemd[1]: Starting dhcpcd on all interfaces... Dec 02 17:18:01 volumior222 thd[460]: Unable to parse trigger line: Dec 02 17:18:01 volumior222 thd[460]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Dec 02 17:18:01 volumior222 thd[460]: Unable to parse trigger line: Dec 02 17:18:01 volumior222 thd[460]: Found socket passed from systemd Dec 02 17:18:01 volumior222 systemd[1]: apt-daily.timer: Not using persistent file timestamp Sun 2026-02-08 12:57:37 CET as it is in the future. Dec 02 17:18:01 volumior222 systemd[1]: Started Daily apt download activities. Dec 02 17:18:01 volumior222 systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Sun 2026-02-08 12:57:37 CET as it is in the future. Dec 02 17:18:01 volumior222 systemd[1]: Started Daily apt upgrade and clean activities. Dec 02 17:18:01 volumior222 systemd[1]: Reached target Timers. Dec 02 17:18:01 volumior222 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Dec 02 17:18:01 volumior222 dhcpcd[483]: Not running dhcpcd because /etc/network/interfaces Dec 02 17:18:01 volumior222 dhcpcd[483]: defines some interfaces that will use a Dec 02 17:18:01 volumior222 dhcpcd[483]: DHCP client or static address Dec 02 17:18:01 volumior222 systemd[1]: Started Manage Sound Card State (restore and store). Dec 02 17:18:01 volumior222 alsactl[502]: alsactl 1.1.8 daemon started Dec 02 17:18:01 volumior222 systemd[1]: Starting Save/Restore Sound Card State... Dec 02 17:18:01 volumior222 systemd[1]: Started triggerhappy global hotkey daemon. Dec 02 17:18:01 volumior222 systemd[1]: Started RPC bind portmap service. Dec 02 17:18:01 volumior222 systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Dec 02 17:18:01 volumior222 systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Dec 02 17:18:01 volumior222 systemd[1]: Failed to start dhcpcd on all interfaces. Dec 02 17:18:01 volumior222 systemd-logind[468]: New seat seat0. Dec 02 17:18:01 volumior222 systemd[1]: hciuart.service: Succeeded. Dec 02 17:18:01 volumior222 systemd[1]: Started Configure Bluetooth Modems connected by UART. Dec 02 17:18:01 volumior222 sh[396]: wlan0=wlan0 Dec 02 17:18:01 volumior222 systemd[1]: Started Save/Restore Sound Card State. Dec 02 17:18:01 volumior222 systemd[1]: Reached target Sound Card. Dec 02 17:18:01 volumior222 avahi-daemon[493]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Dec 02 17:18:01 volumior222 kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Dec 02 17:18:01 volumior222 kernel: bcmgenet fd580000.ethernet eth0: Link is Down Dec 02 17:18:01 volumior222 sh[394]: eth0: waiting for carrier Dec 02 17:18:01 volumior222 avahi-daemon[493]: Successfully dropped root privileges. Dec 02 17:18:01 volumior222 systemd[1]: Starting Raspberry Pi bluetooth helper... Dec 02 17:18:01 volumior222 avahi-daemon[493]: avahi-daemon 0.7 starting up. Dec 02 17:18:01 volumior222 systemd[1]: Reached target Remote File Systems (Pre). Dec 02 17:18:01 volumior222 dhcpcd[430]: eth0: waiting for carrier Dec 02 17:18:01 volumior222 systemd[1]: Reached target Remote File Systems. Dec 02 17:18:01 volumior222 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Dec 02 17:18:01 volumior222 systemd[1]: Reached target RPC Port Mapper. Dec 02 17:18:01 volumior222 bthelper[529]: Raspberry Pi BDADDR already set Dec 02 17:18:01 volumior222 systemd[1]: Started Login Service. Dec 02 17:18:01 volumior222 wpa_supplicant[479]: Successfully initialized wpa_supplicant Dec 02 17:18:01 volumior222 systemd[1]: Started Raspberry Pi bluetooth helper. Dec 02 17:18:01 volumior222 avahi-daemon[493]: Successfully called chroot(). Dec 02 17:18:01 volumior222 avahi-daemon[493]: Successfully dropped remaining capabilities. Dec 02 17:18:01 volumior222 avahi-daemon[493]: Loading service file /services/volumio.service. Dec 02 17:18:01 volumior222 avahi-daemon[493]: Network interface enumeration completed. Dec 02 17:18:01 volumior222 avahi-daemon[493]: Server startup complete. Host name is volumior222.local. Local service cookie is 1607854088. Dec 02 17:18:01 volumior222 avahi-daemon[493]: Service "Volumior222" (/services/volumio.service) successfully established. Dec 02 17:18:01 volumior222 systemd[1]: Started Raise network interfaces. Dec 02 17:18:02 volumior222 systemd[1]: Started WPA supplicant. Dec 02 17:18:02 volumior222 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Dec 02 17:18:02 volumior222 systemd[1]: Reached target Network. Dec 02 17:18:02 volumior222 systemd[1]: Starting Permit User Sessions... Dec 02 17:18:02 volumior222 systemd[1]: Starting Music Player Daemon... Dec 02 17:18:02 volumior222 systemd[1]: Starting Network Time Service... Dec 02 17:18:02 volumior222 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Dec 02 17:18:02 volumior222 systemd[1]: Reached target Network is Online. Dec 02 17:18:02 volumior222 systemd[1]: Starting Samba NMB Daemon... Dec 02 17:18:02 volumior222 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 02 17:18:02 volumior222 systemd[1]: Starting LSB: Brings up/down network automatically... Dec 02 17:18:02 volumior222 systemd[1]: Starting /etc/rc.local Compatibility... Dec 02 17:18:02 volumior222 systemd[1]: Starting Bluetooth service... Dec 02 17:18:02 volumior222 systemd[1]: Started /etc/rc.local Compatibility. Dec 02 17:18:02 volumior222 systemd[1]: Started Permit User Sessions. Dec 02 17:18:02 volumior222 systemd[1]: Starting Terminate Plymouth Boot Screen... Dec 02 17:18:02 volumior222 systemd[1]: Starting Hold until boot process finishes up... Dec 02 17:18:02 volumior222 volumio-remote-updater[469]: Error: No active session Dec 02 17:18:02 volumior222 systemd[1]: Received SIGRTMIN+21 from PID 260 (plymouthd). Dec 02 17:18:02 volumior222 volumio-remote-updater[469]: [2023-12-02 17:18:02] [info] asio async_connect error: system:111 (Connection refused) Dec 02 17:18:02 volumior222 volumio-remote-updater[469]: [2023-12-02 17:18:02] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 02 17:18:02 volumior222 volumio-remote-updater[469]: [2023-12-02 17:18:02] [error] handle_connect error: Underlying Transport Error Dec 02 17:18:02 volumior222 systemd[1]: plymouth-start.service: Succeeded. Dec 02 17:18:02 volumior222 systemd[1]: plymouth-quit.service: Succeeded. Dec 02 17:18:02 volumior222 systemd[1]: Started Terminate Plymouth Boot Screen. Dec 02 17:18:02 volumior222 systemd[1]: plymouth-quit-wait.service: Succeeded. Dec 02 17:18:02 volumior222 systemd[1]: Started Hold until boot process finishes up. Dec 02 17:18:02 volumior222 systemd[1]: Received SIGRTMIN+21 from PID 260 (n/a). Dec 02 17:18:02 volumior222 volumio[458]: Could not open config: /tmp/upmpdcli.conf Dec 02 17:18:02 volumior222 systemd[1]: Started Getty on tty1. Dec 02 17:18:02 volumior222 systemd[1]: Reached target Login Prompts. Dec 02 17:18:02 volumior222 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 02 17:18:02 volumior222 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 02 17:18:02 volumior222 ifplugd(eth0)[623]: ifplugd 0.28 initializing. Dec 02 17:18:02 volumior222 ifplugd(eth0)[623]: Using interface eth0/DC:A6:32:2E:35:F8 with driver (version: 6.1.58-v7l+) Dec 02 17:18:02 volumior222 ifplugd(eth0)[623]: Using detection mode: SIOCETHTOOL Dec 02 17:18:02 volumior222 ifplugd(eth0)[623]: Initialization complete, link beat not detected. Dec 02 17:18:02 volumior222 systemd[1]: iptables.service: Succeeded. Dec 02 17:18:02 volumior222 loadcpufreq[540]: Loading cpufreq kernel modules...done (none). Dec 02 17:18:02 volumior222 bluetoothd[582]: Bluetooth daemon 5.50 Dec 02 17:18:02 volumior222 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Dec 02 17:18:02 volumior222 bluetoothd[582]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Dec 02 17:18:02 volumior222 systemd[1]: Starting LSB: set CPUFreq kernel parameters... Dec 02 17:18:02 volumior222 systemd[1]: Started Bluetooth service. Dec 02 17:18:02 volumior222 systemd[1]: Reached target Bluetooth. Dec 02 17:18:02 volumior222 ifplugd[577]: Network Interface Plugging Daemon...start eth0...done. Dec 02 17:18:02 volumior222 ntpd[571]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 02 17:18:02 volumior222 bluetoothd[582]: Starting SDP server Dec 02 17:18:02 volumior222 systemd[1]: Started LSB: Brings up/down network automatically. Dec 02 17:18:02 volumior222 bluetoothd[582]: Excluding (cli) sap Dec 02 17:18:02 volumior222 ntpd[571]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Dec 02 17:18:02 volumior222 systemd[1]: Started Network Time Service. Dec 02 17:18:02 volumior222 ntpd[631]: proto: precision = 2.111 usec (-19) Dec 02 17:18:02 volumior222 ntpd[631]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 02 17:18:02 volumior222 ntpd[631]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2023-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 02 17:18:02 volumior222 ntpd[631]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): will expire in less than 26 days Dec 02 17:18:02 volumior222 ntpd[631]: Listen and drop on 0 v6wildcard [::]:123 Dec 02 17:18:02 volumior222 ntpd[631]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 02 17:18:02 volumior222 ntpd[631]: Listen normally on 2 lo 127.0.0.1:123 Dec 02 17:18:02 volumior222 ntpd[631]: Listening on routing socket on fd #19 for interface updates Dec 02 17:18:02 volumior222 ntpd[631]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 02 17:18:02 volumior222 ntpd[631]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 02 17:18:03 volumior222 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Dec 02 17:18:03 volumior222 kernel: Bluetooth: BNEP filters: protocol multicast Dec 02 17:18:03 volumior222 kernel: Bluetooth: BNEP socket layer initialized Dec 02 17:18:03 volumior222 bluetoothd[582]: Bluetooth management interface 1.22 initialized Dec 02 17:18:03 volumior222 kernel: Bluetooth: MGMT ver 1.22 Dec 02 17:18:03 volumior222 dbus-daemon[461]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=582 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Dec 02 17:18:03 volumior222 cpufrequtils[628]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Dec 02 17:18:03 volumior222 bluetoothd[582]: Failed to set privacy: Rejected (0x0b) Dec 02 17:18:03 volumior222 systemd[1]: Started LSB: set CPUFreq kernel parameters. Dec 02 17:18:03 volumior222 systemd[1]: Starting Hostname Service... Dec 02 17:18:03 volumior222 dbus-daemon[461]: [system] Successfully activated service 'org.freedesktop.hostname1' Dec 02 17:18:03 volumior222 systemd[1]: Started Hostname Service. Dec 02 17:18:03 volumior222 haveged[437]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Dec 02 17:18:03 volumior222 haveged[437]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Dec 02 17:18:03 volumior222 haveged[437]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.9977 Dec 02 17:18:03 volumior222 haveged[437]: haveged: fills: 0, generated: 0 Dec 02 17:18:03 volumior222 nmbd[584]: [2023/12/02 17:18:03.813799, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 02 17:18:03 volumior222 nmbd[584]: started asyncdns process 652 Dec 02 17:18:03 volumior222 nmbd[584]: [2023/12/02 17:18:03.815785, 0] ../lib/util/become_daemon.c:149(daemon_status) Dec 02 17:18:03 volumior222 nmbd[584]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Dec 02 17:18:03 volumior222 nmbd[584]: [2023/12/02 17:18:03.816075, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Dec 02 17:18:03 volumior222 nmbd[584]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Dec 02 17:18:03 volumior222 ntpd[631]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 02 17:18:04 volumior222 wireless.js[482]: Cleaning previous... Dec 02 17:18:04 volumior222 sudo[660]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 02 17:18:04 volumior222 sudo[660]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:04 volumior222 sudo[660]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:04 volumior222 sudo[662]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 02 17:18:04 volumior222 sudo[662]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:04 volumior222 mpd[636]: Dec 02 17:18 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 02 17:18:04 volumior222 ntpd[631]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 02 17:18:04 volumior222 sudo[662]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:04 volumior222 mpd[636]: Dec 02 17:18 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 02 17:18:04 volumior222 mpd[636]: Dec 02 17:18 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 02 17:18:04 volumior222 systemd[1]: Started Music Player Daemon. Dec 02 17:18:04 volumior222 wireless.js[482]: Stopped aP Dec 02 17:18:04 volumior222 wireless.js[482]: Wireless Networking DISABLED, not starting wireless flow Dec 02 17:18:05 volumior222 systemd[1]: wireless.service: Succeeded. Dec 02 17:18:05 volumior222 systemd[1]: Started Wireless Services. Dec 02 17:18:05 volumior222 systemd[1]: Started Volumio Backend Module. Dec 02 17:18:05 volumior222 systemd[1]: Started Volumio Cpu Tweaker. Dec 02 17:18:05 volumior222 volumio-cpu-tweak[673]: Setting RT Priority for mpd Dec 02 17:18:05 volumior222 volumio-cpu-tweak[673]: Setting MPD Affinity Dec 02 17:18:05 volumior222 volumio-cpu-tweak[673]: pid 636's current affinity mask: f Dec 02 17:18:05 volumior222 volumio-cpu-tweak[673]: pid 636's new affinity mask: 3 Dec 02 17:18:05 volumior222 volumio-cpu-tweak[673]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Dec 02 17:18:05 volumior222 volumio-cpu-tweak[673]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Dec 02 17:18:05 volumior222 systemd[1]: volumio_cpu_tweak.service: Succeeded. Dec 02 17:18:05 volumior222 systemd[1]: systemd-rfkill.service: Succeeded. Dec 02 17:18:05 volumior222 sh[394]: eth0: carrier acquired Dec 02 17:18:05 volumior222 dhcpcd[430]: eth0: carrier acquired Dec 02 17:18:05 volumior222 sh[394]: DUID 00:01:00:01:2c:c5:40:e8:dc:a6:32:2e:35:f8 Dec 02 17:18:05 volumior222 sh[394]: eth0: IAID 32:2e:35:f8 Dec 02 17:18:05 volumior222 sh[394]: eth0: adding address fe80::dea6:32ff:fe2e:35f8 Dec 02 17:18:05 volumior222 sh[394]: ipv6_addaddr1: Permission denied Dec 02 17:18:05 volumior222 dhcpcd[430]: DUID 00:01:00:01:2c:c5:40:e8:dc:a6:32:2e:35:f8 Dec 02 17:18:05 volumior222 dhcpcd[430]: eth0: IAID 32:2e:35:f8 Dec 02 17:18:05 volumior222 dhcpcd[430]: eth0: adding address fe80::dea6:32ff:fe2e:35f8 Dec 02 17:18:05 volumior222 dhcpcd[430]: ipv6_addaddr1: Permission denied Dec 02 17:18:05 volumior222 kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Dec 02 17:18:05 volumior222 ntpd[631]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 02 17:18:05 volumior222 ifplugd(eth0)[623]: Link beat detected. Dec 02 17:18:05 volumior222 ifplugd(eth0)[623]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Dec 02 17:18:06 volumior222 ifplugd(eth0)[623]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Dec 02 17:18:06 volumior222 sh[394]: eth0: rebinding lease of 192.168.0.52 Dec 02 17:18:06 volumior222 dhcpcd[430]: eth0: rebinding lease of 192.168.0.52 Dec 02 17:18:06 volumior222 sh[394]: eth0: probing address 192.168.0.52/24 Dec 02 17:18:06 volumior222 dhcpcd[430]: eth0: probing address 192.168.0.52/24 Dec 02 17:18:06 volumior222 sh[394]: eth0: soliciting an IPv6 router Dec 02 17:18:06 volumior222 dhcpcd[430]: eth0: soliciting an IPv6 router Dec 02 17:18:06 volumior222 volumio[672]: info: ------------------------------------------- Dec 02 17:18:06 volumior222 volumio[672]: info: ----- Volumio3 ---- Dec 02 17:18:06 volumior222 volumio[672]: info: ------------------------------------------- Dec 02 17:18:06 volumior222 volumio[672]: info: ----- System startup ---- Dec 02 17:18:06 volumior222 volumio[672]: info: ------------------------------------------- Dec 02 17:18:06 volumior222 bthelper[529]: Changing power off succeeded Dec 02 17:18:06 volumior222 ntpd[631]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 02 17:18:06 volumior222 bthelper[529]: [CHG] Controller DC:A6:32:2E:35:FF Class: 0x0000041c Dec 02 17:18:06 volumior222 bthelper[529]: Changing power on succeeded Dec 02 17:18:07 volumior222 volumio-remote-updater[469]: [2023-12-02 17:18:07] [connect] Successful connection Dec 02 17:18:07 volumior222 volumio[672]: info: MYVOLUMIO Environment detected Dec 02 17:18:07 volumior222 volumio[672]: info: Plugin folders cleanup Dec 02 17:18:07 volumior222 volumio[672]: info: Scanning into folder /volumio/app/plugins/ Dec 02 17:18:07 volumior222 volumio[672]: info: Scanning category audio_interface Dec 02 17:18:07 volumior222 volumio[672]: info: Scanning category miscellanea Dec 02 17:18:07 volumior222 volumio[672]: info: Scanning category music_service Dec 02 17:18:07 volumior222 volumio[672]: info: Scanning category plugins.json Dec 02 17:18:07 volumior222 volumio[672]: info: Scanning category system_controller Dec 02 17:18:07 volumior222 volumio[672]: info: Scanning category user_interface Dec 02 17:18:07 volumior222 volumio[672]: info: Scanning into folder /data/plugins/ Dec 02 17:18:07 volumior222 volumio[672]: info: Plugin folders cleanup completed Dec 02 17:18:07 volumior222 volumio[672]: info: ------------------------------------------- Dec 02 17:18:07 volumior222 volumio[672]: info: ----- Core plugins startup ---- Dec 02 17:18:07 volumior222 volumio[672]: info: ------------------------------------------- Dec 02 17:18:07 volumior222 volumio[672]: info: Loading plugins from folder /volumio/app/plugins/ Dec 02 17:18:07 volumior222 volumio[672]: info: Adding plugin upnp to MyMusic Plugins Dec 02 17:18:07 volumior222 volumio[672]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 02 17:18:07 volumior222 volumio[672]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 02 17:18:07 volumior222 volumio[672]: info: Loading plugins from folder /data/plugins/ Dec 02 17:18:07 volumior222 volumio[672]: info: Loading plugin "system"... Dec 02 17:18:07 volumior222 volumio[672]: info: Loading plugin "appearance"... Dec 02 17:18:08 volumior222 volumio[672]: info: Loading plugin "network"... Dec 02 17:18:08 volumior222 volumio[672]: info: Refreshing Cached IP Addresses Dec 02 17:18:08 volumior222 sudo[712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 02 17:18:08 volumior222 sudo[712]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:08 volumior222 volumio[672]: info: Loading plugin "services"... Dec 02 17:18:08 volumior222 volumio[672]: info: Loading plugin "alsa_controller"... Dec 02 17:18:08 volumior222 sudo[712]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:08 volumior222 sudo[714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 02 17:18:08 volumior222 sudo[714]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:08 volumior222 sudo[714]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:08 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 02 17:18:08 volumior222 volumio[672]: info: Loading plugin "wizard"... Dec 02 17:18:08 volumior222 volumio[672]: info: Loading plugin "volumio_command_line_client"... Dec 02 17:18:08 volumior222 volumio[672]: info: Loading plugin "upnp"... Dec 02 17:18:08 volumior222 volumio[672]: info: [1701533888585] Starting Upmpd Daemon Dec 02 17:18:08 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 02 17:18:08 volumior222 volumio[672]: info: Loading plugin "my_music"... Dec 02 17:18:08 volumior222 volumio[672]: info: Loading plugin "mpd"... Dec 02 17:18:08 volumior222 volumio[672]: info: Creating MPD Configuration file Dec 02 17:18:08 volumior222 volumio[672]: info: Loading plugin "upnp_browser"... Dec 02 17:18:08 volumior222 sudo[720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 02 17:18:08 volumior222 sudo[720]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:08 volumior222 sudo[720]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:08 volumior222 sudo[722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 02 17:18:08 volumior222 sudo[722]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:08 volumior222 systemd[1]: Stopping Music Player Daemon... Dec 02 17:18:09 volumior222 systemd[1]: mpd.service: Succeeded. Dec 02 17:18:09 volumior222 systemd[1]: Stopped Music Player Daemon. Dec 02 17:18:09 volumior222 systemd[1]: Starting Music Player Daemon... Dec 02 17:18:09 volumior222 volumio[672]: info: Loading plugin "networkfs"... Dec 02 17:18:09 volumior222 volumio[672]: info: Starting Udev Watcher for removable devices Dec 02 17:18:09 volumior222 sudo[730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Radek,password=Myszka_1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.22/Multimedia /mnt/NAS/Qnap222 Dec 02 17:18:09 volumior222 sudo[730]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:09 volumior222 kernel: Key type cifs.spnego registered Dec 02 17:18:09 volumior222 kernel: Key type cifs.idmap registered Dec 02 17:18:09 volumior222 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. Dec 02 17:18:09 volumior222 kernel: CIFS: Attempting to mount \\192.168.0.22\Multimedia Dec 02 17:18:09 volumior222 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Dec 02 17:18:09 volumior222 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Dec 02 17:18:09 volumior222 sudo[730]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:09 volumior222 volumio[672]: info: Ignoring mount for partition: boot Dec 02 17:18:09 volumior222 volumio[672]: info: Ignoring mount for partition: volumio Dec 02 17:18:09 volumior222 volumio[672]: info: Ignoring mount for partition: volumio_data Dec 02 17:18:09 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 02 17:18:09 volumior222 volumio[672]: info: Loading plugin "alarm-clock"... Dec 02 17:18:09 volumior222 mpd[727]: Dec 02 17:18 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 02 17:18:09 volumior222 mpd[727]: Dec 02 17:18 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 02 17:18:09 volumior222 mpd[727]: Dec 02 17:18 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 02 17:18:09 volumior222 systemd[1]: Started Music Player Daemon. Dec 02 17:18:09 volumior222 sudo[722]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:09 volumior222 volumio[672]: info: Loading plugin "airplay_emulation"... Dec 02 17:18:09 volumior222 volumio[672]: info: Starting Shairport Sync Dec 02 17:18:09 volumior222 volumio[672]: info: Loading plugin "last_100"... Dec 02 17:18:09 volumior222 volumio[672]: info: Loading plugin "webradio"... Dec 02 17:18:10 volumior222 volumio[672]: info: Loading plugin "i2s_dacs"... Dec 02 17:18:10 volumior222 volumio[672]: info: Loading plugin "volumiodiscovery"... Dec 02 17:18:10 volumior222 volumio[672]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 02 17:18:10 volumior222 volumio[672]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 02 17:18:10 volumior222 volumio[672]: *** WARNING *** For more information see Dec 02 17:18:10 volumior222 node[672]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 02 17:18:10 volumior222 volumio[672]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 02 17:18:10 volumior222 volumio[672]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 02 17:18:10 volumior222 volumio[672]: *** WARNING *** For more information see Dec 02 17:18:10 volumior222 node[672]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 02 17:18:10 volumior222 node[672]: *** WARNING *** For more information see Dec 02 17:18:10 volumior222 node[672]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 02 17:18:10 volumior222 node[672]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 02 17:18:10 volumior222 node[672]: *** WARNING *** For more information see Dec 02 17:18:10 volumior222 volumio[672]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 02 17:18:10 volumior222 volumio[672]: info: Discovery: Started advertising with name: Volumior222 Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 02 17:18:10 volumior222 volumio[672]: info: Loading plugin "outputs"... Dec 02 17:18:10 volumior222 volumio[672]: info: Loading plugin "albumart"... Dec 02 17:18:10 volumior222 volumio[672]: info: Plugin example_plugin is not enabled Dec 02 17:18:10 volumior222 volumio[672]: info: Loading plugin "inputs"... Dec 02 17:18:10 volumior222 volumio[672]: info: Loading plugin "updater_comm"... Dec 02 17:18:10 volumior222 volumio[672]: info: Plugin mpdemulation is not enabled Dec 02 17:18:10 volumior222 volumio[672]: info: Loading plugin "rest_api"... Dec 02 17:18:10 volumior222 volumio[672]: info: Loading plugin "websocket"... Dec 02 17:18:10 volumior222 volumio[672]: info: Loading i18n strings for locale pl Dec 02 17:18:10 volumior222 volumio[672]: Updating browse sources language Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 17:18:10 volumior222 volumio[672]: Forking 3 albumart workers Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::initPlayerControls Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: Express server listening on port 3000 Dec 02 17:18:10 volumior222 volumio[672]: [Metrics] WebUI: 4s 423.83ms Dec 02 17:18:10 volumior222 volumio[672]: info: CoreStateMachine::resetVolumioState Dec 02 17:18:10 volumior222 volumio[672]: info: CoreStateMachine::getcurrentVolume Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::volumioRetrievevolume Dec 02 17:18:10 volumior222 volumio[672]: info: CoreStateMachine::pushState Dec 02 17:18:10 volumior222 volumio[672]: info: CorePlayQueue::getTrack 0 Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::volumioPushState Dec 02 17:18:10 volumior222 volumio[672]: info: Cannot mount NAS Qnap222 at system boot, trial number 1 ,retrying in 5 seconds Dec 02 17:18:10 volumior222 volumio[672]: info: MPD Permissions set Dec 02 17:18:10 volumior222 volumio-remote-updater[469]: [2023-12-02 17:18:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1701533887 101 Dec 02 17:18:10 volumior222 volumio[672]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Dec 02 17:18:10 volumior222 volumio[672]: info: MPD running with PID727 Dec 02 17:18:10 volumior222 volumio[672]: ,establishing connection Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: info: Reloading queue from file Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: error: updateQueue error: null Dec 02 17:18:10 volumior222 volumio[672]: info: CoreStateMachine::setRepeat false single undefined Dec 02 17:18:10 volumior222 volumio[672]: info: CoreStateMachine::pushState Dec 02 17:18:10 volumior222 volumio[672]: info: CorePlayQueue::getTrack 0 Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::volumioPushState Dec 02 17:18:10 volumior222 volumio[672]: info: CoreStateMachine::setRandom null Dec 02 17:18:10 volumior222 volumio[672]: info: CoreStateMachine::pushState Dec 02 17:18:10 volumior222 volumio[672]: info: CorePlayQueue::getTrack 0 Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::volumioPushState Dec 02 17:18:10 volumior222 volumio[672]: error: updateQueue error: null Dec 02 17:18:10 volumior222 volumio[672]: info: Setting Device type: Raspberry PI Dec 02 17:18:10 volumior222 volumio[672]: info: Completed loading Core Plugins Dec 02 17:18:10 volumior222 volumio[672]: info: Preparing to generate the ALSA configuration file Dec 02 17:18:10 volumior222 volumio[672]: info: Asound.conf file unchanged, so no further update is needed Dec 02 17:18:10 volumior222 volumio[672]: info: Output device has changed, restarting MPD Dec 02 17:18:10 volumior222 volumio[672]: info: Output device has changed, restarting Shairport Sync Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: Starting albumart workers Dec 02 17:18:10 volumior222 sudo[788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 02 17:18:10 volumior222 sudo[788]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:10 volumior222 sudo[788]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:10 volumior222 sudo[791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 02 17:18:10 volumior222 sudo[791]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:10 volumior222 volumio[672]: Starting albumart workers Dec 02 17:18:10 volumior222 systemd[1]: Stopping Music Player Daemon... Dec 02 17:18:10 volumior222 volumio[672]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 02 17:18:10 volumior222 volumio[672]: info: ___________ START PLUGINS ___________ Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 02 17:18:10 volumior222 volumio[672]: info: [1701533890961] CoreMusicLibrary::Adding element Media Servers Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 17:18:10 volumior222 systemd[1]: mpd.service: Succeeded. Dec 02 17:18:10 volumior222 volumio[672]: Starting albumart workers Dec 02 17:18:10 volumior222 systemd[1]: Stopped Music Player Daemon. Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:18:11 volumior222 systemd[1]: Starting Music Player Daemon... Dec 02 17:18:11 volumior222 volumio[672]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 02 17:18:11 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:11 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:18:11 volumior222 volumio[672]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 02 17:18:11 volumior222 volumio[672]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 02 17:18:11 volumior222 volumio[672]: info: [1701533891174] CoreMusicLibrary::Adding element Last_100 Dec 02 17:18:11 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 17:18:11 volumior222 volumio[672]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 02 17:18:11 volumior222 volumio[672]: info: [1701533891176] CoreMusicLibrary::Adding element Webradio Dec 02 17:18:11 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 17:18:11 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 02 17:18:11 volumior222 volumio[672]: info: Volumio Calling Home Dec 02 17:18:11 volumior222 volumio[672]: info: Completed starting Core Plugins Dec 02 17:18:11 volumior222 volumio[672]: info: ------------------------------------------- Dec 02 17:18:11 volumior222 volumio[672]: info: ----- MyVolumio plugins startup ---- Dec 02 17:18:11 volumior222 volumio[672]: info: ------------------------------------------- Dec 02 17:18:11 volumior222 volumio[672]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 02 17:18:11 volumior222 volumio[672]: Unhandled rejection Error: No sockets available, cannot start. Dec 02 17:18:11 volumior222 volumio[672]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 02 17:18:11 volumior222 volumio[672]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 02 17:18:11 volumior222 volumio[672]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 02 17:18:11 volumior222 volumio[672]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 02 17:18:11 volumior222 volumio[672]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 02 17:18:11 volumior222 volumio[672]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 02 17:18:11 volumior222 volumio[672]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 02 17:18:11 volumior222 volumio[672]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 02 17:18:11 volumior222 volumio[672]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 02 17:18:11 volumior222 volumio[672]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 02 17:18:11 volumior222 volumio[672]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 02 17:18:11 volumior222 volumio[672]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 02 17:18:11 volumior222 volumio[672]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 02 17:18:11 volumior222 volumio[672]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 02 17:18:11 volumior222 volumio[672]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 02 17:18:11 volumior222 volumio[672]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 02 17:18:11 volumior222 volumio[672]: info: MPD Permissions set Dec 02 17:18:11 volumior222 volumio[672]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Dec 02 17:18:11 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:11 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:11 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:11 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:11 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:11 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:11 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:11 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:11 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:18:11 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:11 volumior222 volumio[672]: info: Starting Shairport Sync Dec 02 17:18:11 volumior222 volumio[672]: info: Starting Shairport Sync Dec 02 17:18:11 volumior222 volumio[672]: info: Starting Shairport Sync Dec 02 17:18:11 volumior222 sudo[836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 02 17:18:11 volumior222 sudo[836]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:11 volumior222 sudo[838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 02 17:18:11 volumior222 sudo[838]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:11 volumior222 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 02 17:18:11 volumior222 systemd[1]: shairport-sync.service: Succeeded. Dec 02 17:18:11 volumior222 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 02 17:18:11 volumior222 sudo[842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 02 17:18:11 volumior222 sudo[842]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:11 volumior222 volumio[672]: info: CoreCommandRouter::volumioGetState Dec 02 17:18:11 volumior222 volumio[672]: info: CorePlayQueue::getTrack 0 Dec 02 17:18:11 volumior222 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 02 17:18:11 volumior222 sudo[836]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:11 volumior222 volumio[672]: info: Shairport-Sync Started Dec 02 17:18:11 volumior222 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 02 17:18:11 volumior222 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 02 17:18:11 volumior222 systemd[1]: shairport-sync.service: Succeeded. Dec 02 17:18:11 volumior222 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 02 17:18:11 volumior222 volumio[672]: Error adding Membership: Error: addMembership EINVAL Dec 02 17:18:11 volumior222 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 02 17:18:11 volumior222 sudo[838]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:11 volumior222 sudo[842]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:11 volumior222 volumio[672]: info: Shairport-Sync Started Dec 02 17:18:11 volumior222 volumio[672]: info: Shairport-Sync Started Dec 02 17:18:11 volumior222 mpd[803]: Dec 02 17:18 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 02 17:18:11 volumior222 mpd[803]: Dec 02 17:18 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 02 17:18:11 volumior222 mpd[803]: Dec 02 17:18 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 02 17:18:11 volumior222 systemd[1]: Started Music Player Daemon. Dec 02 17:18:11 volumior222 sudo[791]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:11 volumior222 volumio[672]: error: updateQueue error: null Dec 02 17:18:12 volumior222 sh[394]: eth0: leased 192.168.0.52 for 86400 seconds Dec 02 17:18:12 volumior222 dhcpcd[430]: eth0: leased 192.168.0.52 for 86400 seconds Dec 02 17:18:12 volumior222 sh[394]: eth0: adding route to 192.168.0.0/24 Dec 02 17:18:12 volumior222 sh[394]: eth0: adding default route via 192.168.0.1 Dec 02 17:18:12 volumior222 avahi-daemon[493]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.52. Dec 02 17:18:12 volumior222 avahi-daemon[493]: New relevant interface eth0.IPv4 for mDNS. Dec 02 17:18:12 volumior222 dhcpcd[430]: eth0: adding route to 192.168.0.0/24 Dec 02 17:18:12 volumior222 dhcpcd[430]: eth0: adding default route via 192.168.0.1 Dec 02 17:18:12 volumior222 avahi-daemon[493]: Registering new address record for 192.168.0.52 on eth0.IPv4. Dec 02 17:18:12 volumior222 sh[394]: forked to background, child pid 870 Dec 02 17:18:12 volumior222 dhcpcd[430]: forked to background, child pid 870 Dec 02 17:18:12 volumior222 ifplugd(eth0)[623]: client: ifup: interface eth0 already configured Dec 02 17:18:12 volumior222 sh[394]: eth0=eth0 Dec 02 17:18:12 volumior222 volumio[672]: verbose: New Socket.io Connection to 192.168.0.52:3000 from 192.168.0.228 UA: Dart/3.10 (dart:io) Total Clients: 3 Dec 02 17:18:12 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 17:18:12 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 17:18:12 volumior222 volumio[672]: info: Discovery: Getting this device information Dec 02 17:18:12 volumior222 volumio[672]: info: CoreCommandRouter::volumioGetState Dec 02 17:18:12 volumior222 volumio[672]: info: CorePlayQueue::getTrack 0 Dec 02 17:18:12 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 17:18:12 volumior222 ifplugd(eth0)[623]: Program executed successfully. Dec 02 17:18:12 volumior222 volumio[672]: verbose: New Socket.io Connection to 192.168.0.52 from 192.168.0.228 UA: Mozilla/5.0 (Linux; Android 16; Pixel 9 Build/BP4A.260205.002.A1; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.45 Mobile Safari/537.36 Total Clients: 4 Dec 02 17:18:12 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 02 17:18:12 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 02 17:18:12 volumior222 volumio[672]: info: CoreCommandRouter::volumioGetVisibleSources Dec 02 17:18:12 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 17:18:12 volumior222 volumio[672]: info: CoreCommandRouter::volumioGetState Dec 02 17:18:12 volumior222 volumio[672]: info: CorePlayQueue::getTrack 0 Dec 02 17:18:12 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 02 17:18:12 volumior222 volumio[672]: info: CoreCommandRouter::volumioGetQueue Dec 02 17:18:12 volumior222 volumio[672]: info: CoreStateMachine::getQueue Dec 02 17:18:12 volumior222 volumio[672]: info: CorePlayQueue::getQueue Dec 02 17:18:12 volumior222 volumio[672]: info: Listing playlists Dec 02 17:18:12 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 02 17:18:12 volumior222 volumio[672]: info: Received Get System Info Dec 02 17:18:12 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 17:18:12 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 17:18:12 volumior222 volumio[672]: info: Discovery: Getting this device information Dec 02 17:18:12 volumior222 volumio[672]: info: CoreCommandRouter::volumioGetState Dec 02 17:18:12 volumior222 volumio[672]: info: CorePlayQueue::getTrack 0 Dec 02 17:18:12 volumior222 volumio[672]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 17:18:12 volumior222 volumio[672]: info: CoreCommandRouter::volumioGetState Dec 02 17:18:12 volumior222 volumio[672]: info: CorePlayQueue::getTrack 0 Dec 02 17:18:12 volumior222 volumio[672]: verbose: New Socket.io Connection to 192.168.0.52 from 192.168.0.228 UA: Mozilla/5.0 (Linux; Android 16; Pixel 9 Build/BP4A.260205.002.A1; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.45 Mobile Safari/537.36 Total Clients: 5 Dec 02 17:18:13 volumior222 volumio[672]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 17:18:13 volumior222 volumio[672]: Error: certificate is not yet valid Dec 02 17:18:13 volumior222 volumio[672]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Dec 02 17:18:13 volumior222 volumio[672]: at TLSSocket.emit (events.js:315:20) Dec 02 17:18:13 volumior222 volumio[672]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Dec 02 17:18:13 volumior222 volumio[672]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Dec 02 17:18:13 volumior222 volumio[672]: code: 'CERT_NOT_YET_VALID' Dec 02 17:18:13 volumior222 volumio[672]: } Dec 02 17:18:13 volumior222 volumio[672]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 17:18:13 volumior222 sudo[906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-12-02 17:17 Dec 02 17:18:13 volumior222 sudo[906]: 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="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 03:38:28 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4163d3756b55b3bf7c480d7285f68954"