-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2024-07-10 20:29:19 CEST. -- Jul 10 20:28:19 volumiolego kernel: hub 1-1:1.0: USB hub found Jul 10 20:28:19 volumiolego kernel: hub 1-1:1.0: 5 ports detected Jul 10 20:28:19 volumiolego initramfs: /dev/mmcblk0p2: Jul 10 20:28:19 volumiolego kernel: EXT4-fs (mmcblk0p2): recovery complete Jul 10 20:28:19 volumiolego kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none. Jul 10 20:28:19 volumiolego initramfs: Doing a 5 second delay here to give kernel load a headstart Jul 10 20:28:19 volumiolego kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg Jul 10 20:28:19 volumiolego kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Jul 10 20:28:19 volumiolego kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 10 20:28:19 volumiolego kernel: smsc95xx v2.0.0 Jul 10 20:28:19 volumiolego kernel: SMSC LAN8700 usb-001:003:01: attached PHY driver (mii_bus:phy_addr=usb-001:003:01, irq=199) Jul 10 20:28:19 volumiolego kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:32:7f:69 Jul 10 20:28:19 volumiolego initramfs: Checking for USB updates Jul 10 20:28:19 volumiolego initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Jul 10 20:28:19 volumiolego kernel: loop0: detected capacity change from 0 to 962048 Jul 10 20:28:19 volumiolego kernel: EXT4-fs (mmcblk0p3): recovery complete Jul 10 20:28:19 volumiolego kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Quota mode: none. Jul 10 20:28:19 volumiolego initramfs: With Option: Jul 10 20:28:19 volumiolego initramfs: VOLUMIO_VERSION="3.569" Jul 10 20:28:19 volumiolego initramfs: Finish initramfs, continue booting Volumio Jul 10 20:28:19 volumiolego systemd[1]: System time before build time, advancing clock. Jul 10 20:28:19 volumiolego kernel: NET: Registered PF_INET6 protocol family Jul 10 20:28:19 volumiolego kernel: Segment Routing with IPv6 Jul 10 20:28:19 volumiolego kernel: In-situ OAM (IOAM) with IPv6 Jul 10 20:28:19 volumiolego 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) Jul 10 20:28:19 volumiolego systemd[1]: Detected architecture arm. Jul 10 20:28:19 volumiolego systemd[1]: Set hostname to . Jul 10 20:28:19 volumiolego 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. Jul 10 20:28:19 volumiolego systemd[1]: Created slice User and Session Slice. Jul 10 20:28:19 volumiolego systemd[1]: Created slice system-systemd\x2dfsck.slice. Jul 10 20:28:19 volumiolego systemd[1]: Reached target Slices. Jul 10 20:28:19 volumiolego systemd[1]: Listening on Journal Socket. Jul 10 20:28:19 volumiolego systemd[1]: Started dynamicswap service. Jul 10 20:28:19 volumiolego systemd[1]: Mounting Kernel Debug File System... Jul 10 20:28:19 volumiolego systemd-journald[200]: Journal started Jul 10 20:28:19 volumiolego systemd-journald[200]: Runtime journal (/run/log/journal/4ceeebcde4a5f04b591e77826532825c) is 7.5M, max 30.0M, 22.5M free. Jul 10 20:28:19 volumiolego fake-hwclock[198]: Wed Jul 10 18:28:19 UTC 2024 Jul 10 20:28:19 volumiolego systemd[1]: Mounted Kernel Debug File System. Jul 10 20:28:19 volumiolego systemd[1]: Mounted POSIX Message Queue File System. Jul 10 20:28:19 volumiolego systemd[1]: Started Remount Root and Kernel File Systems. Jul 10 20:28:19 volumiolego systemd[1]: Started Create list of required static device nodes for the current kernel. Jul 10 20:28:19 volumiolego systemd[1]: dynamicswap.service: Succeeded. Jul 10 20:28:19 volumiolego systemd[1]: Mounted RPC Pipe File System. Jul 10 20:28:19 volumiolego systemd[1]: Started Restore / save the current clock. Jul 10 20:28:19 volumiolego systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jul 10 20:28:19 volumiolego systemd[1]: Starting Load/Save Random Seed... Jul 10 20:28:19 volumiolego systemd[1]: Starting Create System Users... Jul 10 20:28:19 volumiolego systemd-modules-load[202]: Inserted module 'i2c_dev' Jul 10 20:28:19 volumiolego kernel: i2c_dev: i2c /dev entries driver Jul 10 20:28:19 volumiolego systemd[1]: Started Load/Save Random Seed. Jul 10 20:28:19 volumiolego systemd[1]: Started Load Kernel Modules. Jul 10 20:28:19 volumiolego systemd[1]: Condition check resulted in FUSE Control File System being skipped. Jul 10 20:28:19 volumiolego systemd[1]: Mounting Kernel Configuration File System... Jul 10 20:28:19 volumiolego systemd[1]: Starting Apply Kernel Variables... Jul 10 20:28:19 volumiolego systemd[1]: Mounted Kernel Configuration File System. Jul 10 20:28:19 volumiolego systemd[1]: Started Create System Users. Jul 10 20:28:19 volumiolego systemd[1]: Starting Create Static Device Nodes in /dev... Jul 10 20:28:19 volumiolego systemd[1]: Started udev Coldplug all Devices. Jul 10 20:28:19 volumiolego systemd[1]: Started Apply Kernel Variables. Jul 10 20:28:19 volumiolego systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jul 10 20:28:19 volumiolego systemd[1]: Started Helper to synchronize boot up for ifupdown. Jul 10 20:28:19 volumiolego systemd[1]: Started Create Static Device Nodes in /dev. Jul 10 20:28:19 volumiolego systemd[1]: Starting udev Kernel Device Manager... Jul 10 20:28:19 volumiolego systemd[1]: Reached target Local File Systems (Pre). Jul 10 20:28:19 volumiolego systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jul 10 20:28:19 volumiolego systemd[1]: Mounting /var/log... Jul 10 20:28:19 volumiolego systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jul 10 20:28:19 volumiolego systemd[1]: Mounting /tmp... Jul 10 20:28:19 volumiolego systemd[1]: Mounting /var/spool/cups... Jul 10 20:28:19 volumiolego systemd[1]: Mounted /var/log. Jul 10 20:28:19 volumiolego systemd[1]: Mounted /tmp. Jul 10 20:28:19 volumiolego systemd[1]: Starting Flush Journal to Persistent Storage... Jul 10 20:28:19 volumiolego systemd[1]: Mounted /var/spool/cups. Jul 10 20:28:19 volumiolego systemd[1]: Mounting /var/spool/cups/tmp... Jul 10 20:28:19 volumiolego systemd-udevd[222]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jul 10 20:28:19 volumiolego systemd[1]: Mounted /var/spool/cups/tmp. Jul 10 20:28:19 volumiolego systemd-journald[200]: Runtime journal (/run/log/journal/4ceeebcde4a5f04b591e77826532825c) is 7.5M, max 30.0M, 22.5M free. Jul 10 20:28:19 volumiolego systemd[1]: Started Flush Journal to Persistent Storage. Jul 10 20:28:19 volumiolego systemd[1]: Started udev Kernel Device Manager. Jul 10 20:28:19 volumiolego systemd[1]: Starting Show Plymouth Boot Screen... Jul 10 20:28:19 volumiolego systemd[1]: Received SIGRTMIN+20 from PID 239 (plymouthd). Jul 10 20:28:19 volumiolego systemd[1]: Started Show Plymouth Boot Screen. Jul 10 20:28:19 volumiolego systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jul 10 20:28:19 volumiolego systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jul 10 20:28:19 volumiolego systemd[1]: Reached target Paths. Jul 10 20:28:19 volumiolego systemd[1]: Reached target Local Encrypted Volumes. Jul 10 20:28:20 volumiolego kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 Jul 10 20:28:20 volumiolego kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem Jul 10 20:28:20 volumiolego kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jul 10 20:28:20 volumiolego kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jul 10 20:28:20 volumiolego kernel: [vc_sm_connected_init]: start Jul 10 20:28:20 volumiolego kernel: [vc_sm_connected_init]: installed successfully Jul 10 20:28:20 volumiolego kernel: mc: Linux media interface: v0.10 Jul 10 20:28:20 volumiolego kernel: videodev: Linux video capture interface: v2.00 Jul 10 20:28:20 volumiolego kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jul 10 20:28:20 volumiolego kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jul 10 20:28:20 volumiolego kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jul 10 20:28:20 volumiolego kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jul 10 20:28:20 volumiolego kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jul 10 20:28:20 volumiolego kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jul 10 20:28:20 volumiolego kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jul 10 20:28:20 volumiolego kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jul 10 20:28:20 volumiolego kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jul 10 20:28:20 volumiolego kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jul 10 20:28:20 volumiolego kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jul 10 20:28:20 volumiolego kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jul 10 20:28:20 volumiolego kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jul 10 20:28:20 volumiolego kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jul 10 20:28:20 volumiolego kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Jul 10 20:28:20 volumiolego kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jul 10 20:28:20 volumiolego kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jul 10 20:28:20 volumiolego kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Jul 10 20:28:20 volumiolego kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Jul 10 20:28:20 volumiolego kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jul 10 20:28:20 volumiolego kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jul 10 20:28:20 volumiolego kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Jul 10 20:28:20 volumiolego kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Jul 10 20:28:20 volumiolego kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Jul 10 20:28:20 volumiolego kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Jul 10 20:28:20 volumiolego kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Jul 10 20:28:20 volumiolego kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jul 10 20:28:20 volumiolego kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jul 10 20:28:20 volumiolego kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jul 10 20:28:20 volumiolego kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jul 10 20:28:20 volumiolego kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jul 10 20:28:20 volumiolego kernel: Bluetooth: Core ver 2.22 Jul 10 20:28:20 volumiolego kernel: NET: Registered PF_BLUETOOTH protocol family Jul 10 20:28:20 volumiolego kernel: Bluetooth: HCI device and connection manager initialized Jul 10 20:28:20 volumiolego kernel: Bluetooth: HCI socket layer initialized Jul 10 20:28:20 volumiolego kernel: Bluetooth: L2CAP socket layer initialized Jul 10 20:28:20 volumiolego kernel: Bluetooth: SCO socket layer initialized Jul 10 20:28:20 volumiolego systemd[1]: Found device /dev/mmcblk0p1. Jul 10 20:28:20 volumiolego systemd-udevd[237]: Using default interface naming scheme 'v240'. Jul 10 20:28:21 volumiolego kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jul 10 20:28:21 volumiolego kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Jul 10 20:28:21 volumiolego kernel: bcm2835_audio bcm2835_audio: card created with 8 channels Jul 10 20:28:21 volumiolego systemd-udevd[237]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 10 20:28:21 volumiolego kernel: input: raspberrypi-ts as /devices/platform/soc/soc:firmware/soc:firmware:touchscreen/input/input0 Jul 10 20:28:21 volumiolego kernel: Bluetooth: HCI UART driver ver 2.3 Jul 10 20:28:21 volumiolego kernel: Bluetooth: HCI UART protocol H4 registered Jul 10 20:28:21 volumiolego kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jul 10 20:28:21 volumiolego kernel: Bluetooth: HCI UART protocol Broadcom registered Jul 10 20:28:21 volumiolego kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Jul 10 20:28:21 volumiolego kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Jul 10 20:28:21 volumiolego kernel: uart-pl011 3f201000.serial: no DMA platform data Jul 10 20:28:21 volumiolego kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jul 10 20:28:21 volumiolego systemd[1]: Starting File System Check on /dev/mmcblk0p1... Jul 10 20:28:21 volumiolego kernel: Bluetooth: hci0: BCM: chip id 94 Jul 10 20:28:21 volumiolego kernel: Bluetooth: hci0: BCM: features 0x2e Jul 10 20:28:21 volumiolego kernel: Bluetooth: hci0: BCM43430A1 Jul 10 20:28:21 volumiolego kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0000 Jul 10 20:28:21 volumiolego kernel: Bluetooth: hci0: BCM43430A1 'brcm/BCM43430A1.hcd' Patch Jul 10 20:28:21 volumiolego systemd-udevd[232]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 10 20:28:21 volumiolego kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Jul 10 20:28:21 volumiolego kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Jul 10 20:28:21 volumiolego kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jul 10 20:28:21 volumiolego systemd-fsck[334]: fsck.fat 4.1 (2017-01-24) Jul 10 20:28:21 volumiolego systemd-fsck[334]: /dev/mmcblk0p1: 360 files, 33264/46774 clusters Jul 10 20:28:21 volumiolego systemd[1]: Started File System Check on /dev/mmcblk0p1. Jul 10 20:28:22 volumiolego kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6 Jul 10 20:28:22 volumiolego kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Jul 10 20:28:22 volumiolego kernel: usbcore: registered new interface driver brcmfmac Jul 10 20:28:22 volumiolego kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Jul 19 2021 03:24:18 version 7.45.98 (TOB) (56df937 CY) FWID 01-8e14b897 Jul 10 20:28:22 volumiolego systemd-udevd[236]: Using default interface naming scheme 'v240'. Jul 10 20:28:22 volumiolego systemd-udevd[236]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 10 20:28:22 volumiolego kernel: Bluetooth: hci0: BCM: features 0x2e Jul 10 20:28:22 volumiolego kernel: Bluetooth: hci0: BCM43438A1 37.4MHz Raspberry Pi 3-0141 Jul 10 20:28:22 volumiolego kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0508 Jul 10 20:28:22 volumiolego systemd-udevd[233]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 10 20:28:23 volumiolego systemd-udevd[229]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 10 20:28:23 volumiolego systemd[1]: Condition check resulted in SMSC9512/9514 Fast Ethernet Adapter being skipped. Jul 10 20:28:23 volumiolego systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Jul 10 20:28:23 volumiolego systemd[1]: Created slice system-bthelper.slice. Jul 10 20:28:23 volumiolego systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jul 10 20:28:23 volumiolego systemd[1]: Condition check resulted in FUSE Control File System being skipped. Jul 10 20:28:23 volumiolego systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jul 10 20:28:23 volumiolego systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jul 10 20:28:23 volumiolego systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jul 10 20:28:23 volumiolego systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jul 10 20:28:23 volumiolego systemd[1]: Created slice system-systemd\x2dbacklight.slice. Jul 10 20:28:23 volumiolego systemd[1]: Starting Load/Save Screen Backlight Brightness of backlight:rpi_backlight... Jul 10 20:28:23 volumiolego systemd[1]: Mounting /boot... Jul 10 20:28:23 volumiolego systemd[1]: Started File System Check Daemon to report status. Jul 10 20:28:23 volumiolego systemd[1]: Starting Load/Save RF Kill Switch Status... Jul 10 20:28:23 volumiolego systemd[1]: Mounted /boot. Jul 10 20:28:23 volumiolego systemd[1]: Reached target Local File Systems. Jul 10 20:28:23 volumiolego systemd[1]: Started ifup for wlan0. Jul 10 20:28:23 volumiolego systemd[1]: Starting Raise network interfaces... Jul 10 20:28:23 volumiolego systemd[1]: Starting Create Volatile Files and Directories... Jul 10 20:28:23 volumiolego systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jul 10 20:28:23 volumiolego systemd[1]: Started ifup for eth0. Jul 10 20:28:23 volumiolego systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jul 10 20:28:23 volumiolego systemd[1]: Starting Preprocess NFS configuration... Jul 10 20:28:23 volumiolego systemd[1]: Started Load/Save RF Kill Switch Status. Jul 10 20:28:23 volumiolego systemd[1]: Started Load/Save Screen Backlight Brightness of backlight:rpi_backlight. Jul 10 20:28:23 volumiolego systemd[1]: plymouth-read-write.service: Succeeded. Jul 10 20:28:23 volumiolego systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jul 10 20:28:23 volumiolego systemd[1]: Received SIGRTMIN+20 from PID 239 (plymouthd). Jul 10 20:28:23 volumiolego systemd[1]: nfs-config.service: Succeeded. Jul 10 20:28:23 volumiolego systemd[1]: Started Preprocess NFS configuration. Jul 10 20:28:23 volumiolego systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jul 10 20:28:23 volumiolego systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jul 10 20:28:23 volumiolego systemd[1]: Reached target NFS client services. Jul 10 20:28:23 volumiolego systemd[1]: Started Create Volatile Files and Directories. Jul 10 20:28:23 volumiolego systemd[1]: Starting RPC bind portmap service... Jul 10 20:28:23 volumiolego systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jul 10 20:28:24 volumiolego systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jul 10 20:28:24 volumiolego systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jul 10 20:28:24 volumiolego kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 10 20:28:24 volumiolego systemd[1]: Started Update UTMP about System Boot/Shutdown. Jul 10 20:28:24 volumiolego systemd[1]: Reached target System Initialization. Jul 10 20:28:24 volumiolego systemd[1]: Started Daily apt download activities. Jul 10 20:28:24 volumiolego systemd[1]: Started Daily apt upgrade and clean activities. Jul 10 20:28:24 volumiolego systemd[1]: Started Daily man-db regeneration. Jul 10 20:28:24 volumiolego kernel: 8021q: 802.1Q VLAN Support v1.8 Jul 10 20:28:24 volumiolego systemd[1]: Started Daily Cleanup of Temporary Directories. Jul 10 20:28:24 volumiolego systemd[1]: Reached target Timers. Jul 10 20:28:24 volumiolego systemd[1]: Listening on mpd.socket. Jul 10 20:28:24 volumiolego systemd[1]: Listening on triggerhappy.socket. Jul 10 20:28:24 volumiolego systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jul 10 20:28:24 volumiolego systemd[1]: Listening on D-Bus System Message Bus Socket. Jul 10 20:28:24 volumiolego systemd[1]: Reached target Sockets. Jul 10 20:28:24 volumiolego systemd[1]: Reached target Basic System. Jul 10 20:28:24 volumiolego systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jul 10 20:28:24 volumiolego systemd[1]: Starting Wireless Services... Jul 10 20:28:24 volumiolego systemd[1]: Started UPnP Renderer front-end to MPD. Jul 10 20:28:24 volumiolego systemd[1]: Starting Configure Bluetooth Modems connected by UART... Jul 10 20:28:24 volumiolego systemd[1]: Starting triggerhappy global hotkey daemon... Jul 10 20:28:24 volumiolego systemd[1]: Started Volumio Iptables Module. Jul 10 20:28:24 volumiolego systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jul 10 20:28:24 volumiolego systemd[1]: Started volumio-remote-updater.service. Jul 10 20:28:24 volumiolego systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jul 10 20:28:24 volumiolego systemd[1]: Starting dhcpcd on all interfaces... Jul 10 20:28:24 volumiolego systemd[1]: Started D-Bus System Message Bus. Jul 10 20:28:24 volumiolego systemd[1]: Starting WPA supplicant... Jul 10 20:28:24 volumiolego systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jul 10 20:28:24 volumiolego systemd[1]: Started Manage Sound Card State (restore and store). Jul 10 20:28:24 volumiolego systemd[1]: Starting Save/Restore Sound Card State... Jul 10 20:28:24 volumiolego alsactl[527]: alsactl 1.1.8 daemon started Jul 10 20:28:24 volumiolego systemd[1]: Starting Login Service... Jul 10 20:28:24 volumiolego thd[516]: Unable to parse trigger line: Jul 10 20:28:24 volumiolego thd[516]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jul 10 20:28:24 volumiolego thd[516]: Unable to parse trigger line: Jul 10 20:28:24 volumiolego thd[516]: Unable to parse trigger line: Jul 10 20:28:24 volumiolego thd[516]: Found socket passed from systemd Jul 10 20:28:24 volumiolego systemd[1]: Started RPC bind portmap service. Jul 10 20:28:24 volumiolego systemd[1]: Started triggerhappy global hotkey daemon. Jul 10 20:28:24 volumiolego dhcpcd[520]: Not running dhcpcd because /etc/network/interfaces Jul 10 20:28:24 volumiolego dhcpcd[520]: defines some interfaces that will use a Jul 10 20:28:24 volumiolego dhcpcd[520]: DHCP client or static address Jul 10 20:28:24 volumiolego systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Jul 10 20:28:24 volumiolego systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Jul 10 20:28:24 volumiolego systemd[1]: Failed to start dhcpcd on all interfaces. Jul 10 20:28:24 volumiolego systemd[1]: Started Save/Restore Sound Card State. Jul 10 20:28:24 volumiolego systemd[1]: Reached target Sound Card. Jul 10 20:28:24 volumiolego systemd[1]: Reached target RPC Port Mapper. Jul 10 20:28:24 volumiolego systemd[1]: Reached target Remote File Systems (Pre). Jul 10 20:28:24 volumiolego systemd[1]: Reached target Remote File Systems. Jul 10 20:28:24 volumiolego sh[413]: wlan0=wlan0 Jul 10 20:28:24 volumiolego systemd-logind[531]: New seat seat0. Jul 10 20:28:24 volumiolego systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jul 10 20:28:24 volumiolego avahi-daemon[526]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jul 10 20:28:24 volumiolego avahi-daemon[526]: Successfully dropped root privileges. Jul 10 20:28:24 volumiolego avahi-daemon[526]: avahi-daemon 0.7 starting up. Jul 10 20:28:25 volumiolego sh[420]: eth0: waiting for carrier Jul 10 20:28:25 volumiolego dhcpcd[463]: eth0: waiting for carrier Jul 10 20:28:25 volumiolego kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jul 10 20:28:25 volumiolego kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jul 10 20:28:25 volumiolego systemd[1]: hciuart.service: Succeeded. Jul 10 20:28:25 volumiolego systemd[1]: Started Configure Bluetooth Modems connected by UART. Jul 10 20:28:25 volumiolego avahi-daemon[526]: Successfully called chroot(). Jul 10 20:28:25 volumiolego avahi-daemon[526]: Successfully dropped remaining capabilities. Jul 10 20:28:25 volumiolego wpa_supplicant[523]: Successfully initialized wpa_supplicant Jul 10 20:28:25 volumiolego avahi-daemon[526]: Loading service file /services/volumio.service. Jul 10 20:28:25 volumiolego avahi-daemon[526]: Network interface enumeration completed. Jul 10 20:28:25 volumiolego avahi-daemon[526]: Server startup complete. Host name is volumiolego.local. Local service cookie is 545002335. Jul 10 20:28:25 volumiolego avahi-daemon[526]: Service "VolumioLEGO" (/services/volumio.service) successfully established. Jul 10 20:28:25 volumiolego systemd[1]: Started Raise network interfaces. Jul 10 20:28:25 volumiolego systemd[1]: Started Login Service. Jul 10 20:28:25 volumiolego systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jul 10 20:28:25 volumiolego systemd[1]: Started WPA supplicant. Jul 10 20:28:25 volumiolego systemd[1]: Reached target Network. Jul 10 20:28:25 volumiolego systemd[1]: Starting OpenBSD Secure Shell server... Jul 10 20:28:25 volumiolego systemd[1]: Starting Music Player Daemon... Jul 10 20:28:25 volumiolego systemd[1]: Starting Permit User Sessions... Jul 10 20:28:25 volumiolego systemd[1]: Starting Network Time Service... Jul 10 20:28:25 volumiolego systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jul 10 20:28:25 volumiolego systemd[1]: Reached target Network is Online. Jul 10 20:28:25 volumiolego systemd[1]: Starting LSB: Brings up/down network automatically... Jul 10 20:28:25 volumiolego systemd[1]: Starting /etc/rc.local Compatibility... Jul 10 20:28:26 volumiolego systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 10 20:28:26 volumiolego systemd[1]: Starting Samba NMB Daemon... Jul 10 20:28:26 volumiolego systemd[1]: Starting Raspberry Pi bluetooth helper... Jul 10 20:28:26 volumiolego systemd[1]: Started /etc/rc.local Compatibility. Jul 10 20:28:26 volumiolego systemd[1]: Started Permit User Sessions. Jul 10 20:28:26 volumiolego systemd[1]: Starting Terminate Plymouth Boot Screen... Jul 10 20:28:26 volumiolego systemd[1]: Starting Hold until boot process finishes up... Jul 10 20:28:26 volumiolego volumio-remote-updater[518]: Error: No active session Jul 10 20:28:26 volumiolego volumio-remote-updater[518]: [2024-07-10 20:28:26] [info] asio async_connect error: system:111 (Connection refused) Jul 10 20:28:26 volumiolego volumio-remote-updater[518]: [2024-07-10 20:28:26] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 10 20:28:26 volumiolego volumio-remote-updater[518]: [2024-07-10 20:28:26] [error] handle_connect error: Underlying Transport Error Jul 10 20:28:26 volumiolego systemd[1]: plymouth-start.service: Succeeded. Jul 10 20:28:26 volumiolego bthelper[613]: Raspberry Pi BDADDR already set Jul 10 20:28:26 volumiolego systemd[1]: Started Raspberry Pi bluetooth helper. Jul 10 20:28:26 volumiolego systemd[1]: plymouth-quit.service: Succeeded. Jul 10 20:28:26 volumiolego systemd[1]: Started Terminate Plymouth Boot Screen. Jul 10 20:28:26 volumiolego systemd[1]: plymouth-quit-wait.service: Succeeded. Jul 10 20:28:26 volumiolego systemd[1]: Started Hold until boot process finishes up. Jul 10 20:28:26 volumiolego systemd[1]: Received SIGRTMIN+21 from PID 239 (n/a). Jul 10 20:28:26 volumiolego systemd[1]: Received SIGRTMIN+21 from PID 239 (n/a). Jul 10 20:28:26 volumiolego systemd[1]: Started Getty on tty1. Jul 10 20:28:26 volumiolego loadcpufreq[552]: Loading cpufreq kernel modules...done (none). Jul 10 20:28:26 volumiolego systemd[1]: Reached target Login Prompts. Jul 10 20:28:26 volumiolego systemd[1]: Starting Bluetooth service... Jul 10 20:28:26 volumiolego volumio[514]: Could not open config: /tmp/upmpdcli.conf Jul 10 20:28:26 volumiolego systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jul 10 20:28:26 volumiolego systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jul 10 20:28:26 volumiolego systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jul 10 20:28:26 volumiolego systemd[1]: iptables.service: Succeeded. Jul 10 20:28:26 volumiolego ntpd[599]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 10 20:28:26 volumiolego ntpd[599]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jul 10 20:28:26 volumiolego systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jul 10 20:28:26 volumiolego ntpd[655]: proto: precision = 1.458 usec (-19) Jul 10 20:28:26 volumiolego systemd[1]: Started Network Time Service. Jul 10 20:28:26 volumiolego ntpd[655]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 10 20:28:26 volumiolego ntpd[655]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2023-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 10 20:28:26 volumiolego ntpd[655]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 196 days ago Jul 10 20:28:26 volumiolego ntpd[655]: Listen and drop on 0 v6wildcard [::]:123 Jul 10 20:28:26 volumiolego ntpd[655]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 10 20:28:26 volumiolego ntpd[655]: Listen normally on 2 lo 127.0.0.1:123 Jul 10 20:28:26 volumiolego ntpd[655]: Listening on routing socket on fd #19 for interface updates Jul 10 20:28:26 volumiolego ntpd[655]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 10 20:28:26 volumiolego ntpd[655]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 10 20:28:27 volumiolego ifplugd(eth0)[672]: ifplugd 0.28 initializing. Jul 10 20:28:27 volumiolego ifplugd(eth0)[672]: Using interface eth0/B8:27:EB:32:7F:69 with driver (version: 6.1.58-v7+) Jul 10 20:28:27 volumiolego ifplugd(eth0)[672]: Using detection mode: SIOCETHTOOL Jul 10 20:28:27 volumiolego ifplugd(eth0)[672]: Initialization complete, link beat not detected. Jul 10 20:28:27 volumiolego ifplugd[603]: Network Interface Plugging Daemon...start eth0...done. Jul 10 20:28:27 volumiolego systemd[1]: Started LSB: Brings up/down network automatically. Jul 10 20:28:27 volumiolego bluetoothd[650]: Bluetooth daemon 5.50 Jul 10 20:28:27 volumiolego sshd[673]: Server listening on 0.0.0.0 port 22. Jul 10 20:28:27 volumiolego sshd[673]: Server listening on :: port 22. Jul 10 20:28:27 volumiolego systemd[1]: Started OpenBSD Secure Shell server. Jul 10 20:28:27 volumiolego bluetoothd[650]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jul 10 20:28:27 volumiolego systemd[1]: Started Bluetooth service. Jul 10 20:28:27 volumiolego systemd[1]: Reached target Bluetooth. Jul 10 20:28:27 volumiolego bluetoothd[650]: Starting SDP server Jul 10 20:28:27 volumiolego bluetoothd[650]: Excluding (cli) sap Jul 10 20:28:27 volumiolego cpufrequtils[654]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jul 10 20:28:27 volumiolego systemd[1]: Started LSB: set CPUFreq kernel parameters. Jul 10 20:28:27 volumiolego kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jul 10 20:28:27 volumiolego kernel: Bluetooth: BNEP filters: protocol multicast Jul 10 20:28:27 volumiolego kernel: Bluetooth: BNEP socket layer initialized Jul 10 20:28:27 volumiolego bluetoothd[650]: Bluetooth management interface 1.22 initialized Jul 10 20:28:27 volumiolego dbus-daemon[521]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=650 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jul 10 20:28:27 volumiolego haveged[480]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jul 10 20:28:27 volumiolego haveged[480]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jul 10 20:28:27 volumiolego haveged[480]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00166 Jul 10 20:28:27 volumiolego haveged[480]: haveged: fills: 0, generated: 0 Jul 10 20:28:27 volumiolego kernel: Bluetooth: MGMT ver 1.22 Jul 10 20:28:27 volumiolego bluetoothd[650]: Failed to set privacy: Rejected (0x0b) Jul 10 20:28:27 volumiolego systemd[1]: Starting Hostname Service... Jul 10 20:28:27 volumiolego dbus-daemon[521]: [system] Successfully activated service 'org.freedesktop.hostname1' Jul 10 20:28:27 volumiolego systemd[1]: Started Hostname Service. Jul 10 20:28:27 volumiolego ntpd[655]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 10 20:28:28 volumiolego nmbd[620]: [2024/07/10 20:28:28.467981, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jul 10 20:28:28 volumiolego nmbd[620]: started asyncdns process 692 Jul 10 20:28:28 volumiolego nmbd[620]: [2024/07/10 20:28:28.470433, 0] ../lib/util/become_daemon.c:149(daemon_status) Jul 10 20:28:28 volumiolego nmbd[620]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jul 10 20:28:28 volumiolego nmbd[620]: [2024/07/10 20:28:28.470610, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jul 10 20:28:28 volumiolego nmbd[620]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jul 10 20:28:28 volumiolego systemd[1]: systemd-rfkill.service: Succeeded. Jul 10 20:28:28 volumiolego ntpd[655]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jul 10 20:28:29 volumiolego wireless.js[513]: Cleaning previous... Jul 10 20:28:29 volumiolego sudo[700]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 10 20:28:29 volumiolego sudo[700]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:29 volumiolego sudo[700]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:29 volumiolego sudo[702]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 10 20:28:29 volumiolego sudo[702]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:29 volumiolego sudo[702]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:29 volumiolego wireless.js[513]: Stopped aP Jul 10 20:28:29 volumiolego ntpd[655]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jul 10 20:28:29 volumiolego sudo[709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 10 20:28:29 volumiolego sudo[709]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:29 volumiolego sudo[709]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:29 volumiolego kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 10 20:28:29 volumiolego sudo[711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jul 10 20:28:29 volumiolego sudo[711]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:29 volumiolego sudo[711]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:29 volumiolego sudo[718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 10 20:28:29 volumiolego sudo[718]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:29 volumiolego sudo[718]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:30 volumiolego sudo[720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jul 10 20:28:30 volumiolego sudo[720]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:30 volumiolego mpd[674]: Jul 10 20:28 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 10 20:28:30 volumiolego mpd[674]: Jul 10 20:28 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 10 20:28:30 volumiolego mpd[674]: Jul 10 20:28 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 10 20:28:30 volumiolego systemd[1]: Started Music Player Daemon. Jul 10 20:28:30 volumiolego sudo[720]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:30 volumiolego wireless.js[513]: SETTING APPROPRIATE REG DOMAIN: DE Jul 10 20:28:30 volumiolego sudo[727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 10 20:28:30 volumiolego sudo[727]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:30 volumiolego sudo[727]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:30 volumiolego sudo[729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Jul 10 20:28:30 volumiolego sudo[729]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:30 volumiolego sudo[729]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:30 volumiolego wireless.js[513]: SUCCESSFULLY SET NEW REGDOMAIN: DE Jul 10 20:28:30 volumiolego wireless.js[513]: Start wireless flow Jul 10 20:28:30 volumiolego wireless.js[513]: Stopped hotspot (if there).. Jul 10 20:28:30 volumiolego sudo[734]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 10 20:28:30 volumiolego sudo[734]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:30 volumiolego sudo[734]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:30 volumiolego sudo[736]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 10 20:28:30 volumiolego sudo[736]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:30 volumiolego ntpd[655]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 10 20:28:31 volumiolego sudo[736]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:31 volumiolego wireless.js[513]: DHCP IP Jul 10 20:28:31 volumiolego wireless.js[513]: Start ap Jul 10 20:28:31 volumiolego wpa_supplicant[738]: Successfully initialized wpa_supplicant Jul 10 20:28:31 volumiolego sudo[739]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jul 10 20:28:31 volumiolego sudo[739]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:31 volumiolego kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 10 20:28:31 volumiolego dhcpcd[740]: dev: loaded udev Jul 10 20:28:31 volumiolego dhcpcd-run-hooks[758]: wlan0: starting wpa_supplicant Jul 10 20:28:31 volumiolego volumio-remote-updater[518]: [2024-07-10 20:28:31] [info] asio async_connect error: system:111 (Connection refused) Jul 10 20:28:31 volumiolego volumio-remote-updater[518]: [2024-07-10 20:28:31] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 10 20:28:31 volumiolego volumio-remote-updater[518]: [2024-07-10 20:28:31] [error] handle_connect error: Underlying Transport Error Jul 10 20:28:31 volumiolego dhcpcd-run-hooks[763]: wlan0: failed to start wpa_supplicant Jul 10 20:28:31 volumiolego dhcpcd-run-hooks[764]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Jul 10 20:28:31 volumiolego dhcpcd[740]: wlan0: connected to Access Point `' Jul 10 20:28:31 volumiolego dhcpcd[740]: no interfaces have a carrier Jul 10 20:28:31 volumiolego dhcpcd[740]: forked to background, child pid 775 Jul 10 20:28:31 volumiolego dhcpcd[775]: eth0: waiting for carrier Jul 10 20:28:31 volumiolego dhcpcd[775]: wlan0: waiting for carrier Jul 10 20:28:31 volumiolego sudo[739]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:31 volumiolego bthelper[613]: Changing power off succeeded Jul 10 20:28:31 volumiolego bthelper[613]: [CHG] Controller B8:27:EB:98:D5:C3 Powered: no Jul 10 20:28:31 volumiolego bthelper[613]: [CHG] Controller B8:27:EB:98:D5:C3 Discovering: no Jul 10 20:28:31 volumiolego bthelper[613]: [CHG] Controller B8:27:EB:98:D5:C3 Class: 0x00000000 Jul 10 20:28:32 volumiolego bthelper[613]: [CHG] Controller B8:27:EB:98:D5:C3 Class: 0x0000041c Jul 10 20:28:32 volumiolego bthelper[613]: Changing power on succeeded Jul 10 20:28:32 volumiolego wpa_supplicant[762]: wlan0: Trying to associate with SSID 'Compagel' Jul 10 20:28:32 volumiolego wireless.js[513]: trying... Jul 10 20:28:32 volumiolego sudo[784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 20:28:32 volumiolego sudo[784]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:32 volumiolego sudo[784]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:32 volumiolego dhcpcd[775]: wlan0: carrier acquired Jul 10 20:28:32 volumiolego dhcpcd[775]: wlan0: connected to Access Point `Compagel' Jul 10 20:28:32 volumiolego wpa_supplicant[762]: wlan0: Associated with b0:f2:08:bb:3d:55 Jul 10 20:28:32 volumiolego wpa_supplicant[762]: wlan0: CTRL-EVENT-CONNECTED - Connection to b0:f2:08:bb:3d:55 completed [id=0 id_str=] Jul 10 20:28:32 volumiolego wpa_supplicant[762]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 10 20:28:32 volumiolego wpa_supplicant[762]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Jul 10 20:28:32 volumiolego dhcpcd[775]: DUID 00:01:00:01:2c:c5:41:55:b8:27:eb:67:2a:3c Jul 10 20:28:32 volumiolego dhcpcd[775]: wlan0: IAID eb:67:2a:3c Jul 10 20:28:32 volumiolego dhcpcd[775]: wlan0: adding address fe80::cd27:3b97:8a4f:6e3f Jul 10 20:28:32 volumiolego dhcpcd[775]: ipv6_addaddr1: Permission denied Jul 10 20:28:32 volumiolego dhcpcd[775]: wlan0: carrier lost Jul 10 20:28:32 volumiolego dhcpcd[775]: wlan0: carrier acquired Jul 10 20:28:32 volumiolego dhcpcd[775]: wlan0: IAID eb:67:2a:3c Jul 10 20:28:33 volumiolego dhcpcd[775]: wlan0: soliciting an IPv6 router Jul 10 20:28:33 volumiolego wireless.js[513]: trying... Jul 10 20:28:33 volumiolego sudo[799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 20:28:33 volumiolego sudo[799]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:33 volumiolego sudo[799]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:33 volumiolego dhcpcd[775]: wlan0: rebinding lease of 192.168.0.28 Jul 10 20:28:34 volumiolego wireless.js[513]: trying... Jul 10 20:28:34 volumiolego sudo[802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 20:28:34 volumiolego sudo[802]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:34 volumiolego sudo[802]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:35 volumiolego wireless.js[513]: trying... Jul 10 20:28:35 volumiolego sudo[805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 20:28:35 volumiolego sudo[805]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:35 volumiolego sudo[805]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:36 volumiolego wireless.js[513]: trying... Jul 10 20:28:36 volumiolego sudo[808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 20:28:36 volumiolego sudo[808]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:36 volumiolego sudo[808]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:37 volumiolego wireless.js[513]: trying... Jul 10 20:28:37 volumiolego sudo[811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 20:28:37 volumiolego sudo[811]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:37 volumiolego sudo[811]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:38 volumiolego wireless.js[513]: trying... Jul 10 20:28:38 volumiolego sudo[814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 20:28:38 volumiolego sudo[814]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:38 volumiolego sudo[814]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:38 volumiolego dhcpcd[775]: wlan0: probing for an IPv4LL address Jul 10 20:28:38 volumiolego dhcpcd[775]: wlan0: DHCP lease expired Jul 10 20:28:38 volumiolego dhcpcd[775]: wlan0: soliciting a DHCP lease Jul 10 20:28:38 volumiolego volumio-remote-updater[518]: [2024-07-10 20:28:38] [info] asio async_connect error: system:111 (Connection refused) Jul 10 20:28:38 volumiolego volumio-remote-updater[518]: [2024-07-10 20:28:38] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 10 20:28:38 volumiolego volumio-remote-updater[518]: [2024-07-10 20:28:38] [error] handle_connect error: Underlying Transport Error Jul 10 20:28:39 volumiolego wireless.js[513]: trying... Jul 10 20:28:39 volumiolego sudo[826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 20:28:39 volumiolego sudo[826]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:39 volumiolego sudo[826]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:40 volumiolego wireless.js[513]: trying... Jul 10 20:28:40 volumiolego sudo[829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 20:28:40 volumiolego sudo[829]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:40 volumiolego sudo[829]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:41 volumiolego wireless.js[513]: trying... Jul 10 20:28:41 volumiolego sudo[832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 20:28:41 volumiolego sudo[832]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:41 volumiolego sudo[832]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:42 volumiolego wireless.js[513]: trying... Jul 10 20:28:42 volumiolego sudo[835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 20:28:42 volumiolego sudo[835]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:42 volumiolego sudo[835]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:43 volumiolego wireless.js[513]: trying... Jul 10 20:28:43 volumiolego sudo[838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 20:28:43 volumiolego sudo[838]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:43 volumiolego sudo[838]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:44 volumiolego dhcpcd[775]: wlan0: using IPv4LL address 169.254.100.138 Jul 10 20:28:44 volumiolego avahi-daemon[526]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.100.138. Jul 10 20:28:44 volumiolego dhcpcd[775]: wlan0: adding route to 169.254.0.0/16 Jul 10 20:28:44 volumiolego dhcpcd[775]: wlan0: adding default route Jul 10 20:28:44 volumiolego avahi-daemon[526]: New relevant interface wlan0.IPv4 for mDNS. Jul 10 20:28:44 volumiolego avahi-daemon[526]: Registering new address record for 169.254.100.138 on wlan0.IPv4. Jul 10 20:28:44 volumiolego wireless.js[513]: trying... Jul 10 20:28:44 volumiolego sudo[852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 20:28:44 volumiolego sudo[852]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:44 volumiolego sudo[852]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:44 volumiolego wireless.js[513]: Connected to: ----Compagel Jul 10 20:28:44 volumiolego wireless.js[513]: ---- Jul 10 20:28:44 volumiolego sudo[855]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 10 20:28:44 volumiolego sudo[855]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:44 volumiolego sudo[855]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:44 volumiolego wireless.js[513]: ... joined AP, wlan0 IPv4 is 169.254.100.138, ipV6 is undefined Jul 10 20:28:44 volumiolego wireless.js[513]: It's done! AP Jul 10 20:28:44 volumiolego kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 10 20:28:44 volumiolego systemd[1]: Started Wireless Services. Jul 10 20:28:44 volumiolego systemd[1]: Started Volumio Backend Module. Jul 10 20:28:44 volumiolego systemd[1]: Started Volumio Cpu Tweaker. Jul 10 20:28:44 volumiolego volumio-cpu-tweak[861]: Setting RT Priority for mpd Jul 10 20:28:44 volumiolego volumio-cpu-tweak[861]: Setting MPD Affinity Jul 10 20:28:44 volumiolego volumio-cpu-tweak[861]: pid 674's current affinity mask: f Jul 10 20:28:44 volumiolego volumio-cpu-tweak[861]: pid 674's new affinity mask: 3 Jul 10 20:28:44 volumiolego volumio-cpu-tweak[861]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jul 10 20:28:44 volumiolego volumio-cpu-tweak[861]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jul 10 20:28:44 volumiolego systemd[1]: volumio_cpu_tweak.service: Succeeded. Jul 10 20:28:45 volumiolego ntpd[655]: Listen normally on 3 wlan0 169.254.100.138:123 Jul 10 20:28:45 volumiolego ntpd[655]: new interface(s) found: waking up resolver Jul 10 20:28:47 volumiolego volumio[860]: info: ------------------------------------------- Jul 10 20:28:47 volumiolego volumio[860]: info: ----- Volumio3 ---- Jul 10 20:28:47 volumiolego volumio[860]: info: ------------------------------------------- Jul 10 20:28:47 volumiolego volumio[860]: info: ----- System startup ---- Jul 10 20:28:47 volumiolego volumio[860]: info: ------------------------------------------- Jul 10 20:28:48 volumiolego nmbd[620]: [2024/07/10 20:28:48.478760, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 10 20:28:48 volumiolego systemd[1]: Started Samba NMB Daemon. Jul 10 20:28:48 volumiolego nmbd[620]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jul 10 20:28:48 volumiolego systemd[1]: Starting Samba Winbind Daemon... Jul 10 20:28:48 volumiolego volumio[860]: info: MYVOLUMIO Environment detected Jul 10 20:28:48 volumiolego volumio[860]: info: Plugin folders cleanup Jul 10 20:28:48 volumiolego volumio[860]: info: Scanning into folder /volumio/app/plugins/ Jul 10 20:28:48 volumiolego volumio[860]: info: Scanning category audio_interface Jul 10 20:28:48 volumiolego volumio[860]: info: Scanning category miscellanea Jul 10 20:28:48 volumiolego volumio[860]: info: Scanning category music_service Jul 10 20:28:48 volumiolego volumio[860]: info: Scanning category plugins.json Jul 10 20:28:48 volumiolego volumio[860]: info: Scanning category system_controller Jul 10 20:28:48 volumiolego volumio[860]: info: Scanning category user_interface Jul 10 20:28:48 volumiolego volumio[860]: info: Scanning into folder /data/plugins/ Jul 10 20:28:48 volumiolego volumio[860]: info: Scanning category music_service Jul 10 20:28:48 volumiolego volumio[860]: info: Scanning category system_hardware Jul 10 20:28:48 volumiolego volumio[860]: info: Scanning category user_interface Jul 10 20:28:48 volumiolego volumio[860]: info: Plugin folders cleanup completed Jul 10 20:28:48 volumiolego volumio[860]: info: ------------------------------------------- Jul 10 20:28:48 volumiolego volumio[860]: info: ----- Core plugins startup ---- Jul 10 20:28:48 volumiolego volumio[860]: info: ------------------------------------------- Jul 10 20:28:48 volumiolego volumio[860]: info: Loading plugins from folder /volumio/app/plugins/ Jul 10 20:28:48 volumiolego volumio[860]: info: Adding plugin upnp to MyMusic Plugins Jul 10 20:28:48 volumiolego volumio[860]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 10 20:28:48 volumiolego volumio[860]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 10 20:28:48 volumiolego volumio[860]: info: Loading plugins from folder /data/plugins/ Jul 10 20:28:48 volumiolego volumio[860]: info: Loading plugin "system"... Jul 10 20:28:48 volumiolego winbindd[885]: [2024/07/10 20:28:48.887593, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jul 10 20:28:48 volumiolego winbindd[885]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jul 10 20:28:48 volumiolego winbindd[885]: [2024/07/10 20:28:48.917776, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 10 20:28:48 volumiolego volumio[860]: info: Loading plugin "appearance"... Jul 10 20:28:48 volumiolego systemd[1]: Started Samba Winbind Daemon. Jul 10 20:28:48 volumiolego winbindd[885]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jul 10 20:28:48 volumiolego volumio-remote-updater[518]: [2024-07-10 20:28:48] [connect] Successful connection Jul 10 20:28:49 volumiolego systemd[1]: Starting Samba SMB Daemon... Jul 10 20:28:49 volumiolego smbd[892]: [2024/07/10 20:28:49.559193, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 10 20:28:49 volumiolego systemd[1]: Started Samba SMB Daemon. Jul 10 20:28:49 volumiolego smbd[892]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 10 20:28:49 volumiolego systemd[1]: Reached target Multi-User System. Jul 10 20:28:49 volumiolego systemd[1]: Reached target Graphical Interface. Jul 10 20:28:49 volumiolego systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 10 20:28:49 volumiolego systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 10 20:28:49 volumiolego systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 10 20:28:49 volumiolego systemd[1]: Startup finished in 10.226s (kernel) + 32.691s (userspace) = 42.918s. Jul 10 20:28:50 volumiolego volumio[860]: info: Loading plugin "network"... Jul 10 20:28:50 volumiolego volumio[860]: info: Refreshing Cached IP Addresses Jul 10 20:28:50 volumiolego sudo[902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 10 20:28:50 volumiolego sudo[902]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:50 volumiolego sudo[902]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:50 volumiolego volumio[860]: info: Loading plugin "services"... Jul 10 20:28:50 volumiolego sudo[904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 10 20:28:50 volumiolego sudo[904]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:50 volumiolego volumio[860]: info: Loading plugin "alsa_controller"... Jul 10 20:28:50 volumiolego sudo[904]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:50 volumiolego sudo[907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 10 20:28:50 volumiolego sudo[907]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:50 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 10 20:28:50 volumiolego volumio[860]: info: Loading plugin "wizard"... Jul 10 20:28:50 volumiolego volumio[860]: info: Loading plugin "volumio_command_line_client"... Jul 10 20:28:50 volumiolego volumio[860]: info: Plugin upnp is not enabled Jul 10 20:28:50 volumiolego volumio[860]: info: Loading plugin "my_music"... Jul 10 20:28:50 volumiolego volumio[860]: info: Loading plugin "mpd"... Jul 10 20:28:51 volumiolego volumio[860]: info: Creating MPD Configuration file Jul 10 20:28:51 volumiolego volumio[860]: info: Loading plugin "upnp_browser"... Jul 10 20:28:51 volumiolego sudo[915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 10 20:28:51 volumiolego sudo[915]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:51 volumiolego sudo[915]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:51 volumiolego sudo[917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 10 20:28:51 volumiolego sudo[917]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:51 volumiolego systemd[1]: Stopping Music Player Daemon... Jul 10 20:28:51 volumiolego sudo[907]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:51 volumiolego systemd[1]: mpd.service: Succeeded. Jul 10 20:28:51 volumiolego systemd[1]: Stopped Music Player Daemon. Jul 10 20:28:51 volumiolego systemd[1]: Starting Music Player Daemon... Jul 10 20:28:52 volumiolego volumio[860]: info: Loading plugin "networkfs"... Jul 10 20:28:52 volumiolego volumio[860]: info: Starting Udev Watcher for removable devices Jul 10 20:28:52 volumiolego sudo[925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=tom,password=Tkl60318!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.69/Multimedia/Music/Kinder /mnt/NAS/Kinder Jul 10 20:28:52 volumiolego sudo[925]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:28:52 volumiolego kernel: Key type cifs.spnego registered Jul 10 20:28:52 volumiolego kernel: Key type cifs.idmap registered Jul 10 20:28:52 volumiolego kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jul 10 20:28:52 volumiolego kernel: CIFS: Attempting to mount \\192.168.0.69\Multimedia Jul 10 20:28:52 volumiolego volumio[860]: info: Ignoring mount for partition: boot Jul 10 20:28:52 volumiolego volumio[860]: info: Ignoring mount for partition: volumio Jul 10 20:28:52 volumiolego volumio[860]: info: Ignoring mount for partition: volumio_data Jul 10 20:28:52 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 10 20:28:52 volumiolego volumio[860]: info: Loading plugin "alarm-clock"... Jul 10 20:28:53 volumiolego volumio[860]: info: Loading plugin "airplay_emulation"... Jul 10 20:28:53 volumiolego volumio[860]: info: Starting Shairport Sync Jul 10 20:28:53 volumiolego volumio[860]: info: Loading plugin "last_100"... Jul 10 20:28:53 volumiolego volumio[860]: info: Loading plugin "webradio"... Jul 10 20:28:53 volumiolego mpd[922]: Jul 10 20:28 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 10 20:28:53 volumiolego mpd[922]: Jul 10 20:28 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 10 20:28:53 volumiolego mpd[922]: Jul 10 20:28 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 10 20:28:53 volumiolego systemd[1]: Started Music Player Daemon. Jul 10 20:28:53 volumiolego sudo[917]: pam_unix(sudo:session): session closed for user root Jul 10 20:28:53 volumiolego volumio[860]: info: Loading plugin "i2s_dacs"... Jul 10 20:28:53 volumiolego volumio[860]: info: Loading plugin "volumiodiscovery"... Jul 10 20:28:53 volumiolego volumio[860]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 10 20:28:53 volumiolego volumio[860]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 10 20:28:53 volumiolego volumio[860]: *** WARNING *** For more information see Jul 10 20:28:53 volumiolego volumio[860]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 10 20:28:53 volumiolego node[860]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 10 20:28:53 volumiolego volumio[860]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 10 20:28:53 volumiolego volumio[860]: *** WARNING *** For more information see Jul 10 20:28:53 volumiolego node[860]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 10 20:28:53 volumiolego node[860]: *** WARNING *** For more information see Jul 10 20:28:53 volumiolego node[860]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 10 20:28:53 volumiolego node[860]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 10 20:28:53 volumiolego node[860]: *** WARNING *** For more information see Jul 10 20:28:53 volumiolego volumio[860]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 10 20:28:53 volumiolego volumio[860]: info: Discovery: Started advertising with name: VolumioLEGO Jul 10 20:28:53 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 10 20:28:53 volumiolego volumio[860]: info: Loading plugin "spop"... Jul 10 20:28:53 volumiolego systemd[1]: systemd-fsckd.service: Succeeded. Jul 10 20:28:55 volumiolego sh[420]: timed out Jul 10 20:28:55 volumiolego dhcpcd[463]: timed out Jul 10 20:28:55 volumiolego sh[420]: dhcpcd exited Jul 10 20:28:55 volumiolego dhcpcd[463]: dhcpcd exited Jul 10 20:28:55 volumiolego sh[420]: ifup: failed to bring up eth0 Jul 10 20:28:55 volumiolego systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jul 10 20:28:55 volumiolego systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jul 10 20:28:55 volumiolego volumio[860]: info: Loading plugin "outputs"... Jul 10 20:28:55 volumiolego volumio[860]: info: Loading plugin "albumart"... Jul 10 20:28:55 volumiolego volumio[860]: info: Plugin example_plugin is not enabled Jul 10 20:28:55 volumiolego volumio[860]: info: Loading plugin "inputs"... Jul 10 20:28:55 volumiolego volumio[860]: info: Loading plugin "updater_comm"... Jul 10 20:28:55 volumiolego volumio[860]: info: Plugin mpdemulation is not enabled Jul 10 20:28:55 volumiolego volumio[860]: info: Loading plugin "rest_api"... Jul 10 20:28:55 volumiolego volumio[860]: info: Loading plugin "websocket"... Jul 10 20:28:55 volumiolego volumio[860]: info: Loading plugin "rotaryencoder2"... Jul 10 20:28:55 volumiolego volumio[860]: Forking 3 albumart workers Jul 10 20:28:56 volumiolego volumio[860]: info: Loading plugin "touch_display"... Jul 10 20:28:56 volumiolego volumio[860]: Starting albumart workers Jul 10 20:28:56 volumiolego volumio[860]: Starting albumart workers Jul 10 20:28:56 volumiolego volumio[860]: Starting albumart workers Jul 10 20:28:57 volumiolego volumio[860]: info: Applying required configuration parameters for plugin touch_display Jul 10 20:28:57 volumiolego volumio[860]: info: Loading i18n strings for locale de Jul 10 20:28:57 volumiolego volumio[860]: Updating browse sources language Jul 10 20:28:57 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 20:28:57 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 20:28:57 volumiolego volumio[860]: info: CoreCommandRouter::initPlayerControls Jul 10 20:28:57 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:28:57 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:28:57 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:28:57 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:28:57 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:28:57 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:28:57 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:28:57 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:28:57 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 20:28:57 volumiolego volumio[860]: Express server listening on port 3000 Jul 10 20:28:57 volumiolego volumio[860]: [Metrics] WebUI: 11s 414.00ms Jul 10 20:28:57 volumiolego volumio[860]: info: CoreStateMachine::resetVolumioState Jul 10 20:28:57 volumiolego volumio[860]: info: CoreStateMachine::getcurrentVolume Jul 10 20:28:57 volumiolego volumio[860]: info: CoreCommandRouter::volumioRetrievevolume Jul 10 20:28:57 volumiolego systemd[1]: systemd-hostnamed.service: Succeeded. Jul 10 20:28:58 volumiolego volumio[860]: info: MPD Permissions set Jul 10 20:28:58 volumiolego volumio[860]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Jul 10 20:28:58 volumiolego volumio[860]: info: MPD running with PID922 Jul 10 20:28:58 volumiolego volumio[860]: ,establishing connection Jul 10 20:28:58 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:28:58 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:28:58 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:28:58 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:28:58 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:28:58 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:28:58 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:28:58 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:28:58 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 20:28:58 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:28:58 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 20:28:58 volumiolego volumio[860]: info: Reloading queue from file Jul 10 20:28:58 volumiolego volumio[860]: error: updateQueue error: null Jul 10 20:28:58 volumiolego volumio[860]: info: VolumeController:: Volume=12 Mute =false Jul 10 20:28:58 volumiolego volumio[860]: info: CoreStateMachine::pushState Jul 10 20:28:58 volumiolego volumio[860]: info: CorePlayQueue::getTrack 0 Jul 10 20:28:58 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 20:28:58 volumiolego volumio[860]: info: CoreCommandRouter::volumioPushState Jul 10 20:28:58 volumiolego volumio[860]: info: CoreStateMachine::updateTrackBlock Jul 10 20:28:58 volumiolego volumio[860]: info: CorePlayQueue::getTrackBlock Jul 10 20:28:58 volumiolego volumio[860]: info: CoreCommandRouter::volumioRetrievevolume Jul 10 20:28:58 volumiolego volumio[860]: info: Setting Device type: Raspberry PI Jul 10 20:28:58 volumiolego volumio[860]: info: CoreStateMachine::setRepeat null single undefined Jul 10 20:28:58 volumiolego volumio[860]: info: CoreStateMachine::pushState Jul 10 20:28:58 volumiolego volumio[860]: info: CorePlayQueue::getTrack 0 Jul 10 20:28:58 volumiolego volumio[860]: info: CoreCommandRouter::volumioPushState Jul 10 20:28:58 volumiolego volumio[860]: info: CoreStateMachine::setRandom false Jul 10 20:28:58 volumiolego volumio[860]: info: CoreStateMachine::pushState Jul 10 20:28:58 volumiolego volumio[860]: info: CorePlayQueue::getTrack 0 Jul 10 20:28:58 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 20:28:58 volumiolego volumio[860]: info: CoreCommandRouter::volumioPushState Jul 10 20:28:58 volumiolego volumio[860]: error: updateQueue error: null Jul 10 20:28:58 volumiolego volumio[860]: info: VolumeController:: Volume=12 Mute =false Jul 10 20:28:58 volumiolego volumio[860]: info: CoreStateMachine::pushState Jul 10 20:28:58 volumiolego volumio[860]: info: CorePlayQueue::getTrack 0 Jul 10 20:28:58 volumiolego volumio[860]: info: CoreCommandRouter::volumioPushState Jul 10 20:29:02 volumiolego sudo[925]: pam_unix(sudo:session): session closed for user root Jul 10 20:29:02 volumiolego volumio[860]: info: Cannot mount NAS Kinder at system boot, trial number 1 ,retrying in 5 seconds Jul 10 20:29:02 volumiolego volumio[860]: info: Completed loading Core Plugins Jul 10 20:29:02 volumiolego kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jul 10 20:29:02 volumiolego kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Jul 10 20:29:02 volumiolego volumio[860]: info: Preparing to generate the ALSA configuration file Jul 10 20:29:02 volumiolego volumio[860]: info: Asound.conf file unchanged, so no further update is needed Jul 10 20:29:02 volumiolego volumio[860]: info: Output device has changed, restarting MPD Jul 10 20:29:02 volumiolego volumio[860]: info: Output device has changed, restarting Shairport Sync Jul 10 20:29:02 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:29:02 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 20:29:02 volumiolego sudo[1018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 10 20:29:02 volumiolego sudo[1018]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:29:02 volumiolego sudo[1018]: pam_unix(sudo:session): session closed for user root Jul 10 20:29:02 volumiolego sudo[1020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 10 20:29:02 volumiolego sudo[1020]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:29:02 volumiolego systemd[1]: Stopping Music Player Daemon... Jul 10 20:29:02 volumiolego volumio[860]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 10 20:29:02 volumiolego volumio[860]: info: ___________ START PLUGINS ___________ Jul 10 20:29:02 volumiolego systemd[1]: mpd.service: Succeeded. Jul 10 20:29:02 volumiolego systemd[1]: Stopped Music Player Daemon. Jul 10 20:29:02 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 20:29:02 volumiolego volumio[860]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 10 20:29:02 volumiolego volumio[860]: info: [1720636142990] CoreMusicLibrary::Adding element Media Servers Jul 10 20:29:02 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 20:29:03 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:29:03 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 20:29:03 volumiolego systemd[1]: Starting Music Player Daemon... Jul 10 20:29:03 volumiolego volumio[860]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 10 20:29:03 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:29:03 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 20:29:03 volumiolego volumio[860]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 10 20:29:03 volumiolego volumio[860]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 10 20:29:03 volumiolego volumio[860]: info: [1720636143112] CoreMusicLibrary::Adding element Last_100 Jul 10 20:29:03 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 20:29:03 volumiolego volumio[860]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 10 20:29:03 volumiolego volumio[860]: info: [1720636143118] CoreMusicLibrary::Adding element Webradio Jul 10 20:29:03 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 20:29:03 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 10 20:29:03 volumiolego volumio[860]: info: Creating Spotify config file Jul 10 20:29:03 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:29:03 volumiolego volumio[860]: info: Loading i18n strings for locale de Jul 10 20:29:03 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 10 20:29:03 volumiolego volumio[860]: info: Volumio Calling Home Jul 10 20:29:03 volumiolego sudo[1042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=22 pin_b=27 relative_axis=true steps-per-period=1 Jul 10 20:29:03 volumiolego sudo[1042]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:29:03 volumiolego sudo[1044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 10 20:29:03 volumiolego sudo[1044]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:29:03 volumiolego systemd[1]: Reloading. Jul 10 20:29:03 volumiolego volumio[860]: error: MPD error: Error: write EPIPE Jul 10 20:29:03 volumiolego volumio[860]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jul 10 20:29:03 volumiolego sudo[1042]: pam_unix(sudo:session): session closed for user root Jul 10 20:29:03 volumiolego kernel: rotary-encoder rotary@16: gray Jul 10 20:29:03 volumiolego kernel: input: rotary@16 as /devices/platform/rotary@16/input/input1 Jul 10 20:29:03 volumiolego volumio[860]: info: MPD Permissions set Jul 10 20:29:03 volumiolego volumio[860]: verbose: New Socket.io Connection to 169.254.100.138:3000 from 192.168.0.58 UA: node-XMLHttpRequest Total Clients: 2 Jul 10 20:29:03 volumiolego volumio[860]: info: Spotify config file written Jul 10 20:29:03 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:29:03 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:29:03 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:29:03 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:29:03 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:29:03 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:29:03 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:29:03 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:29:03 volumiolego sudo[1073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 10 20:29:03 volumiolego sudo[1073]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:29:03 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 20:29:03 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:29:03 volumiolego volumio[860]: info: touch_display: Raspberry Pi Foundation touch screen detected. Jul 10 20:29:03 volumiolego volumio[860]: info: touch_display: Backlight module of a Raspberry Pi Foundation touch screen detected. Jul 10 20:29:03 volumiolego systemd-udevd[1051]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 10 20:29:03 volumiolego volumio[860]: info: Starting Shairport Sync Jul 10 20:29:03 volumiolego volumio[860]: info: Starting Shairport Sync Jul 10 20:29:03 volumiolego volumio[860]: info: Starting Shairport Sync Jul 10 20:29:03 volumiolego sudo[1081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 10 20:29:03 volumiolego sudo[1081]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:29:03 volumiolego sudo[1086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 10 20:29:03 volumiolego sudo[1084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 10 20:29:03 volumiolego sudo[1084]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:29:03 volumiolego 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. Jul 10 20:29:03 volumiolego sudo[1086]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:29:03 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jul 10 20:29:03 volumiolego volumio-remote-updater[518]: [2024-07-10 20:29:03] [connect] Successful connection Jul 10 20:29:04 volumiolego volumio-remote-updater[518]: [2024-07-10 20:29:04] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1720636143 101 Jul 10 20:29:04 volumiolego volumio[860]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Jul 10 20:29:04 volumiolego sudo[1090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/devices/platform/rpi_backlight/backlight/rpi_backlight/brightness Jul 10 20:29:04 volumiolego sudo[1090]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:29:04 volumiolego sudo[1090]: pam_unix(sudo:session): session closed for user root Jul 10 20:29:04 volumiolego volumio[860]: info: CoreCommandRouter::volumioGetState Jul 10 20:29:04 volumiolego volumio[860]: info: CorePlayQueue::getTrack 0 Jul 10 20:29:04 volumiolego volumio[860]: info: touch_display: File permissions for backlight brightness control set. Jul 10 20:29:04 volumiolego systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jul 10 20:29:04 volumiolego dhcpcd[775]: wlan0: offered 192.168.0.28 from 192.168.0.1 Jul 10 20:29:04 volumiolego dhcpcd[775]: wlan0: probing address 192.168.0.28/24 Jul 10 20:29:04 volumiolego sudo[1044]: pam_unix(sudo:session): session closed for user root Jul 10 20:29:04 volumiolego volumio[860]: info: touch_display: systemctl daemon-reload succeeded. Jul 10 20:29:04 volumiolego sudo[1094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jul 10 20:29:04 volumiolego sudo[1094]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:29:04 volumiolego systemd[1]: Started Volumio Kiosk. Jul 10 20:29:04 volumiolego systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 10 20:29:04 volumiolego systemd[1]: Started go-librespot Daemon. Jul 10 20:29:04 volumiolego go-librespot[1097]: Librespot-go daemon starting... Jul 10 20:29:04 volumiolego sudo[1073]: pam_unix(sudo:session): session closed for user root Jul 10 20:29:04 volumiolego systemd[1]: Starting Daily apt download activities... Jul 10 20:29:04 volumiolego systemd[1]: shairport-sync.service: Succeeded. Jul 10 20:29:04 volumiolego systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 10 20:29:04 volumiolego sudo[1094]: pam_unix(sudo:session): session closed for user root Jul 10 20:29:04 volumiolego systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 10 20:29:04 volumiolego sudo[1081]: pam_unix(sudo:session): session closed for user root Jul 10 20:29:04 volumiolego sudo[1086]: pam_unix(sudo:session): session closed for user root Jul 10 20:29:04 volumiolego sudo[1084]: pam_unix(sudo:session): session closed for user root Jul 10 20:29:04 volumiolego mpd[1037]: Jul 10 20:29 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 10 20:29:04 volumiolego volumio[860]: info: Shairport-Sync Started Jul 10 20:29:04 volumiolego volumio[860]: Error adding Membership: Error: addMembership EINVAL Jul 10 20:29:04 volumiolego volumio[860]: info: Shairport-Sync Started Jul 10 20:29:04 volumiolego volumio[860]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jul 10 20:29:04 volumiolego volumio[860]: info: touch_display: Volumio Kiosk started Jul 10 20:29:04 volumiolego volumio[860]: info: CoreCommandRouter::volumioGetState Jul 10 20:29:04 volumiolego volumio[860]: info: CorePlayQueue::getTrack 0 Jul 10 20:29:04 volumiolego mpd[1037]: Jul 10 20:29 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 10 20:29:04 volumiolego mpd[1037]: Jul 10 20:29 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 10 20:29:04 volumiolego systemd[1]: Started Music Player Daemon. Jul 10 20:29:04 volumiolego volumio[860]: info: Completed starting Core Plugins Jul 10 20:29:04 volumiolego volumio[860]: info: ------------------------------------------- Jul 10 20:29:04 volumiolego volumio[860]: info: ----- MyVolumio plugins startup ---- Jul 10 20:29:04 volumiolego sudo[1020]: pam_unix(sudo:session): session closed for user root Jul 10 20:29:04 volumiolego volumio[860]: info: ------------------------------------------- Jul 10 20:29:04 volumiolego volumio[860]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 10 20:29:04 volumiolego volumio[860]: error: updateQueue error: null Jul 10 20:29:04 volumiolego volumio[860]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jul 10 20:29:05 volumiolego volumio[860]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jul 10 20:29:05 volumiolego volumio[860]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jul 10 20:29:05 volumiolego startx[1096]: X.Org X Server 1.20.4 Jul 10 20:29:05 volumiolego startx[1096]: X Protocol Version 11, Revision 0 Jul 10 20:29:05 volumiolego startx[1096]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Jul 10 20:29:05 volumiolego startx[1096]: Current Operating System: Linux volumiolego 6.1.58-v7+ #1690 SMP Tue Oct 17 15:35:01 BST 2023 armv7l Jul 10 20:29:05 volumiolego startx[1096]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 Jul 10 20:29:05 volumiolego startx[1096]: Build Date: 04 April 2023 07:50:56AM Jul 10 20:29:05 volumiolego startx[1096]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Jul 10 20:29:05 volumiolego startx[1096]: Current version of pixman: 0.36.0 Jul 10 20:29:05 volumiolego startx[1096]: Before reporting problems, check http://wiki.x.org Jul 10 20:29:05 volumiolego startx[1096]: to make sure that you have the latest version. Jul 10 20:29:05 volumiolego startx[1096]: Markers: (--) probed, (**) from config file, (==) default setting, Jul 10 20:29:05 volumiolego startx[1096]: (++) from command line, (!!) notice, (II) informational, Jul 10 20:29:05 volumiolego startx[1096]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jul 10 20:29:05 volumiolego startx[1096]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Jul 10 20:29:05 2024 Jul 10 20:29:05 volumiolego startx[1096]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jul 10 20:29:05 volumiolego startx[1096]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jul 10 20:29:05 volumiolego volumio[860]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jul 10 20:29:05 volumiolego go-librespot[1097]: time="2024-07-10T20:29:05+02:00" level=info msg="generated new device id: 8c03be561bced156035fa9039580a87e8167fbe3" Jul 10 20:29:05 volumiolego go-librespot[1097]: time="2024-07-10T20:29:05+02:00" level=debug msg="stored credentials found for 31zxbbvzkrp7nw3jq6mlbhwvrhkm" Jul 10 20:29:07 volumiolego volumio[860]: info: go-librespot daemon successfully initialized Jul 10 20:29:07 volumiolego sudo[1176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=tom,password=Tkl60318!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.69/Multimedia/Music/Kinder /mnt/NAS/Kinder Jul 10 20:29:07 volumiolego sudo[1176]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:29:07 volumiolego kernel: CIFS: Attempting to mount \\192.168.0.69\Multimedia Jul 10 20:29:08 volumiolego sudo[1176]: pam_unix(sudo:session): session closed for user root Jul 10 20:29:08 volumiolego volumio[860]: info: touch_display: Setting screensaver timeout to 120 seconds. Jul 10 20:29:08 volumiolego systemd[1]: apt-daily.service: Succeeded. Jul 10 20:29:08 volumiolego systemd[1]: Started Daily apt download activities. Jul 10 20:29:09 volumiolego dhcpcd[775]: wlan0: leased 192.168.0.28 for 864000 seconds Jul 10 20:29:09 volumiolego avahi-daemon[526]: Registering new address record for 192.168.0.28 on wlan0.IPv4. Jul 10 20:29:09 volumiolego dhcpcd[775]: wlan0: adding route to 192.168.0.0/24 Jul 10 20:29:09 volumiolego dhcpcd[775]: wlan0: changing default route via 192.168.0.1 Jul 10 20:29:09 volumiolego avahi-daemon[526]: Withdrawing address record for 169.254.100.138 on wlan0. Jul 10 20:29:09 volumiolego avahi-daemon[526]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.100.138. Jul 10 20:29:09 volumiolego dhcpcd[775]: wlan0: deleting route to 169.254.0.0/16 Jul 10 20:29:09 volumiolego avahi-daemon[526]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.28. Jul 10 20:29:10 volumiolego dhcpcd[775]: wlan0: pid 775 deleted default route via 192.168.0.1 Jul 10 20:29:10 volumiolego volumio[860]: info: Initializing connection to go-librespot Websocket Jul 10 20:29:10 volumiolego go-librespot[1097]: time="2024-07-10T20:29:10+02:00" level=debug msg="obtained new client token: AABuaLa/0+LEWRorEhBjy6jA/1Gr9qKMTTdNKE7fZKt0AyPTSkWHJAkisJD4N25kvegQuAz2xFaSjQk4YlvWBGAt6U4yRNMzwzrx+7yYohnNFW4EIv8SJs137+U2oXXtj0TfZhmRyc58scU3ozntUWpjXGv6awZlHMkLwlMDFMAJoYErEKgr4sMqmNopOMeN2rnjgfjHDjX2Bq655YE7RjvlE6xfyfcnsHiHx5uvg+yvwPtmYkp9pfI7Acidcl4=" Jul 10 20:29:11 volumiolego go-librespot[1097]: time="2024-07-10T20:29:11+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 10 20:29:11 volumiolego go-librespot[1097]: time="2024-07-10T20:29:11+02:00" level=debug msg="completed keyexchange" Jul 10 20:29:11 volumiolego go-librespot[1097]: time="2024-07-10T20:29:11+02:00" level=debug msg="completed challenge" Jul 10 20:29:11 volumiolego go-librespot[1097]: time="2024-07-10T20:29:11+02:00" level=debug msg="authenticated as 31zxbbvzkrp7nw3jq6mlbhwvrhkm" Jul 10 20:29:11 volumiolego ntpd[655]: Listen normally on 4 wlan0 192.168.0.28:123 Jul 10 20:29:11 volumiolego ntpd[655]: Deleting interface #3 wlan0, 169.254.100.138#123, interface stats: received=0, sent=0, dropped=0, active_time=26 secs Jul 10 20:29:11 volumiolego ntpd[655]: new interface(s) found: waking up resolver Jul 10 20:29:11 volumiolego go-librespot[1097]: time="2024-07-10T20:29:11+02:00" level=debug msg="authenticated as 31zxbbvzkrp7nw3jq6mlbhwvrhkm" Jul 10 20:29:11 volumiolego go-librespot[1097]: time="2024-07-10T20:29:11+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jul 10 20:29:11 volumiolego go-librespot[1097]: time="2024-07-10T20:29:11+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jul 10 20:29:12 volumiolego go-librespot[1097]: time="2024-07-10T20:29:12+02:00" level=debug msg="dealer connection opened" Jul 10 20:29:12 volumiolego go-librespot[1097]: time="2024-07-10T20:29:12+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 10 20:29:12 volumiolego go-librespot[1097]: time="2024-07-10T20:29:12+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 10 20:29:12 volumiolego go-librespot[1097]: time="2024-07-10T20:29:12+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 10 20:29:12 volumiolego go-librespot[1097]: time="2024-07-10T20:29:12+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 10 20:29:12 volumiolego go-librespot[1097]: time="2024-07-10T20:29:12+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513" Jul 10 20:29:12 volumiolego go-librespot[1097]: time="2024-07-10T20:29:12+02:00" level=debug msg="received connection id: MzQzYmMyNDMtN2E1MS00NzY2LWFhZDQtMzRhZTkyMWIxMWYxK2RlYWxlcit0Y3A6Ly9nZXc0LWRlYWxlci1hLW5nZnYuZ2V3NC5zcG90aWZ5Lm5ldDo1NzAwKzcxODk0MDRDNzVFNjM5NkQ4MUFCQzBDRDZGN0JCRTlFNEJBMzdEOEQzNUY4MTJBRDE2NzhDQThEMEJBRUQ2MkM=" Jul 10 20:29:12 volumiolego go-librespot[1097]: time="2024-07-10T20:29:12+02:00" level=debug msg="put connect state because NEW_DEVICE" Jul 10 20:29:13 volumiolego volumio[860]: info: Discovery: adding ea1df565-33e9-40ba-a5d3-f6aca87d787f Jul 10 20:29:13 volumiolego volumio[860]: info: Discovery: Found device VolumioLEGO Jul 10 20:29:13 volumiolego volumio[860]: info: CoreCommandRouter::volumioGetState Jul 10 20:29:13 volumiolego volumio[860]: info: CorePlayQueue::getTrack 0 Jul 10 20:29:13 volumiolego volumio[860]: info: Volumio called home Jul 10 20:29:13 volumiolego volumio[860]: info: Discovery: adding 70e7b88f-a82e-481f-83ea-a65ef210eea0 Jul 10 20:29:13 volumiolego volumio[860]: info: Discovery: Found device VolumioDG Jul 10 20:29:13 volumiolego volumio[860]: info: Discovery: Connecting to remote: 192.168.0.58 Jul 10 20:29:13 volumiolego volumio[860]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Jul 10 20:29:13 volumiolego volumio[860]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Jul 10 20:29:13 volumiolego go-librespot[1097]: time="2024-07-10T20:29:13+02:00" level=debug msg="new websocket client" Jul 10 20:29:13 volumiolego volumio[860]: info: Connection to go-librespot Websocket established Jul 10 20:29:13 volumiolego volumio[860]: info: Discovery: Connected to remote: 192.168.0.58 Jul 10 20:29:13 volumiolego volumio[860]: info: CoreCommandRouter::volumioGetState Jul 10 20:29:13 volumiolego volumio[860]: info: CorePlayQueue::getTrack 0 Jul 10 20:29:13 volumiolego volumio[860]: info: CoreCommandRouter::volumioGetState Jul 10 20:29:13 volumiolego volumio[860]: info: CorePlayQueue::getTrack 0 Jul 10 20:29:14 volumiolego volumio[860]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 10 20:29:14 volumiolego volumio[860]: SPOTIFY: BQC0WyQGo299JIUJJr7986LWtJ_IHuK8OrTo-jkKHLrww_M_RuDb562ir-QgJCcv-QpldJ9jnteTvpozdD5i3_ViZjJT2S3ja9GSqXqUPgsDv08P3D6TB617Z9F_OjinLrCJi6hkG9m3ROlX1OjQXPalqFRke7KjHN3MYhcLqh-LvCaBLP7HCnNfK1AEts6xmEZ4PQuYmb2Oeo0JDrtfhV0BnQVUBT6PPDO0byFpsVuo8CJ09HQBbUFW5H5zG-YXDmgQkU5I0rI Jul 10 20:29:14 volumiolego volumio[860]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 10 20:29:14 volumiolego volumio[860]: info: New Spotify access token = BQC0WyQGo299JIUJJr7986LWtJ_IHuK8OrTo-jkKHLrww_M_RuDb562ir-QgJCcv-QpldJ9jnteTvpozdD5i3_ViZjJT2S3ja9GSqXqUPgsDv08P3D6TB617Z9F_OjinLrCJi6hkG9m3ROlX1OjQXPalqFRke7KjHN3MYhcLqh-LvCaBLP7HCnNfK1AEts6xmEZ4PQuYmb2Oeo0JDrtfhV0BnQVUBT6PPDO0byFpsVuo8CJ09HQBbUFW5H5zG-YXDmgQkU5I0rI Jul 10 20:29:14 volumiolego volumio[860]: info: Spotify credentials grant success - running version from March 24, 2019 Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 10 20:29:14 volumiolego volumio[860]: info: Adding plugin bluetooth to MyMusic Plugins Jul 10 20:29:14 volumiolego volumio[860]: info: Adding plugin multiroom to MyMusic Plugins Jul 10 20:29:14 volumiolego volumio[860]: info: Adding plugin metavolumio to MyMusic Plugins Jul 10 20:29:14 volumiolego volumio[860]: info: Adding plugin cd_controller to MyMusic Plugins Jul 10 20:29:14 volumiolego volumio[860]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 10 20:29:14 volumiolego volumio[860]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 10 20:29:14 volumiolego volumio[860]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 10 20:29:14 volumiolego go-librespot[1097]: time="2024-07-10T20:29:14+02:00" level=debug msg="handling transfer player command from 4936bf2880db2cb735952aec809e9d338859b682" Jul 10 20:29:14 volumiolego go-librespot[1097]: time="2024-07-10T20:29:14+02:00" level=trace msg="fetched new page 0 with 13 items (list: 13)" Jul 10 20:29:14 volumiolego go-librespot[1097]: time="2024-07-10T20:29:14+02:00" level=debug msg="loading track spotify:track:2y3IgyRMTSi3sQfRfa3ZpL (paused: true, position: 5789ms)" Jul 10 20:29:14 volumiolego go-librespot[1097]: time="2024-07-10T20:29:14+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 10 20:29:14 volumiolego go-librespot[1097]: time="2024-07-10T20:29:14+02:00" level=trace msg="emitting websocket event: will_play" Jul 10 20:29:14 volumiolego go-librespot[1097]: time="2024-07-10T20:29:14+02:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:2y3IgyRMTSi3sQfRfa3ZpL" Jul 10 20:29:14 volumiolego go-librespot[1097]: time="2024-07-10T20:29:14+02:00" level=debug msg="requested aes key for file 54ca206672322cf00b378594eba5a0a5a50d96cc, gid: 2y3IgyRMTSi3sQfRfa3ZpL" Jul 10 20:29:15 volumiolego go-librespot[1097]: time="2024-07-10T20:29:15+02:00" level=debug msg="fetched first chunk of 17, total size is 8532764 bytes" Jul 10 20:29:15 volumiolego go-librespot[1097]: time="2024-07-10T20:29:15+02:00" level=warning msg="reducing track normalisation factor to prevent clipping, please add negative pregain to avoid" Jul 10 20:29:15 volumiolego go-librespot[1097]: time="2024-07-10T20:29:15+02:00" level=trace msg="seek to 5789ms (diff: 82ms, samples: 255294, bytes: 210724)" Jul 10 20:29:15 volumiolego kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jul 10 20:29:15 volumiolego go-librespot[1097]: time="2024-07-10T20:29:15+02:00" level=info msg="loaded track \"Conni im Krankenhaus - Teil 01\" (uri: spotify:track:2y3IgyRMTSi3sQfRfa3ZpL, paused: true, position: 5789ms, duration: 193051ms)" Jul 10 20:29:15 volumiolego go-librespot[1097]: time="2024-07-10T20:29:15+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 10 20:29:15 volumiolego go-librespot[1097]: time="2024-07-10T20:29:15+02:00" level=trace msg="emitting websocket event: metadata" Jul 10 20:29:15 volumiolego go-librespot[1097]: time="2024-07-10T20:29:15+02:00" level=trace msg="emitting websocket event: active" Jul 10 20:29:15 volumiolego go-librespot[1097]: time="2024-07-10T20:29:15+02:00" level=debug msg="sending successful reply for delaer request" Jul 10 20:29:15 volumiolego volumio[860]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 10 20:29:15 volumiolego volumio[860]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 10 20:29:15 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 20:29:15 volumiolego volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 20:29:15 volumiolego volumio[860]: info: Starting MyVolumio Remote Streaming Endpoints Jul 10 20:29:15 volumiolego volumio[860]: info: MyVolumio login type: Token Jul 10 20:29:15 volumiolego volumio[860]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 10 20:29:15 volumiolego volumio[860]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 10 20:29:15 volumiolego go-librespot[1097]: time="2024-07-10T20:29:15+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 10 20:29:15 volumiolego go-librespot[1097]: time="2024-07-10T20:29:15+02:00" level=trace msg="emitting websocket event: paused" Jul 10 20:29:15 volumiolego go-librespot[1097]: time="2024-07-10T20:29:15+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jul 10 20:29:15 volumiolego go-librespot[1097]: time="2024-07-10T20:29:15+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Jul 10 20:29:15 volumiolego go-librespot[1097]: time="2024-07-10T20:29:15+02:00" level=debug msg="fetched chunk 1/16, size: 524288" Jul 10 20:29:15 volumiolego go-librespot[1097]: time="2024-07-10T20:29:15+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 10 20:29:15 volumiolego go-librespot[1097]: time="2024-07-10T20:29:15+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2291" Jul 10 20:29:16 volumiolego go-librespot[1097]: time="2024-07-10T20:29:16+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 10 20:29:16 volumiolego go-librespot[1097]: time="2024-07-10T20:29:16+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1722" Jul 10 20:29:16 volumiolego go-librespot[1097]: time="2024-07-10T20:29:16+02:00" level=debug msg="fetched chunk 2/16, size: 524288" Jul 10 20:29:16 volumiolego go-librespot[1097]: time="2024-07-10T20:29:16+02:00" level=debug msg="fetched chunk 3/16, size: 524288" Jul 10 20:29:18 volumiolego volumio[860]: info: Starting Streaming Service Transparent Proxy Jul 10 20:29:18 volumiolego volumio[860]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 10 20:29:18 volumiolego volumio[860]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 10 20:29:18 volumiolego volumio[860]: info: Streaming services startup Jul 10 20:29:18 volumiolego volumio[860]: info: Starting Streaming Daemon Jul 10 20:29:18 volumiolego sudo[1413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 10 20:29:18 volumiolego sudo[1413]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:29:18 volumiolego volumio[860]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 10 20:29:18 volumiolego sudo[1413]: pam_unix(sudo:session): session closed for user root Jul 10 20:29:18 volumiolego volumio[860]: info: Getting Spotify volume Jul 10 20:29:18 volumiolego volumio[860]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2y3IgyRMTSi3sQfRfa3ZpL","play_origin":"playlist"}} Jul 10 20:29:18 volumiolego volumio[860]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2y3IgyRMTSi3sQfRfa3ZpL","name":"Conni im Krankenhaus - Teil 01","artist_names":["Conni"],"album_name":"Conni im Krankenhaus / Conni tanzt","album_cover_url":"https://i.scdn.co/image/ab67616d00001e021bb325a05be0a95ffc5cd933","position":5789,"duration":193051,"release_date":"year:2003 month:1 day:1","track_number":2,"disc_number":1}} Jul 10 20:29:18 volumiolego volumio[860]: SPOTIFY: received: {"type":"active","data":null} Jul 10 20:29:18 volumiolego volumio[860]: info: Aligning Spotify Volume to Volumio Volume Jul 10 20:29:18 volumiolego volumio[860]: info: CoreCommandRouter::volumioGetState Jul 10 20:29:18 volumiolego volumio[860]: info: CorePlayQueue::getTrack 0 Jul 10 20:29:18 volumiolego volumio[860]: info: Setting Spotify Volume from Volumio: 12 Jul 10 20:29:18 volumiolego volumio[860]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:2y3IgyRMTSi3sQfRfa3ZpL","play_origin":"playlist"}} Jul 10 20:29:18 volumiolego volumio[860]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 20:29:18 volumiolego volumio[860]: TypeError: Cannot read property 'service' of undefined Jul 10 20:29:18 volumiolego volumio[860]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:336:50) Jul 10 20:29:18 volumiolego volumio[860]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:270:18) Jul 10 20:29:18 volumiolego volumio[860]: at WebSocket.message (/data/plugins/music_service/spop/index.js:189:14) Jul 10 20:29:18 volumiolego volumio[860]: at WebSocket.emit (events.js:315:20) Jul 10 20:29:18 volumiolego volumio[860]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1203:20) Jul 10 20:29:18 volumiolego volumio[860]: at Receiver.emit (events.js:315:20) Jul 10 20:29:18 volumiolego volumio[860]: at /data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:608:16 Jul 10 20:29:18 volumiolego volumio[860]: at internal/process/task_queues.js:149:7 Jul 10 20:29:18 volumiolego volumio[860]: at AsyncResource.runInAsyncScope (async_hooks.js:197:9) Jul 10 20:29:18 volumiolego volumio[860]: at AsyncResource.runMicrotask (internal/process/task_queues.js:146:8) Jul 10 20:29:18 volumiolego volumio[860]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 10 20:29:18 volumiolego volumio[860]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 20:29:19 volumiolego sudo[1441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-10 20:28 Jul 10 20:29:19 volumiolego sudo[1441]: 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"