-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Wed 2024-07-03 18:55:39 CEST. -- Jul 03 18:54:56 gartenradio kernel: usb usb1: SerialNumber: 3f980000.usb Jul 03 18:54:56 gartenradio kernel: hub 1-0:1.0: USB hub found Jul 03 18:54:56 gartenradio kernel: hub 1-0:1.0: 1 port detected Jul 03 18:54:56 gartenradio kernel: dwc_otg: FIQ enabled Jul 03 18:54:56 gartenradio kernel: dwc_otg: NAK holdoff enabled Jul 03 18:54:56 gartenradio kernel: dwc_otg: FIQ split-transaction FSM enabled Jul 03 18:54:56 gartenradio kernel: Module dwc_common_port init Jul 03 18:54:56 gartenradio kernel: usbcore: registered new interface driver usb-storage Jul 03 18:54:56 gartenradio kernel: mousedev: PS/2 mouse device common for all mice Jul 03 18:54:56 gartenradio kernel: sdhci: Secure Digital Host Controller Interface driver Jul 03 18:54:56 gartenradio kernel: sdhci: Copyright(c) Pierre Ossman Jul 03 18:54:56 gartenradio kernel: sdhci-pltfm: SDHCI platform and OF driver helper Jul 03 18:54:56 gartenradio kernel: ledtrig-cpu: registered to indicate activity on CPUs Jul 03 18:54:56 gartenradio kernel: hid: raw HID events driver (C) Jiri Kosina Jul 03 18:54:56 gartenradio kernel: usbcore: registered new interface driver usbhid Jul 03 18:54:56 gartenradio kernel: usbhid: USB HID core driver Jul 03 18:54:56 gartenradio kernel: Initializing XFRM netlink socket Jul 03 18:54:56 gartenradio kernel: NET: Registered PF_PACKET protocol family Jul 03 18:54:56 gartenradio kernel: Key type dns_resolver registered Jul 03 18:54:56 gartenradio kernel: Registering SWP/SWPB emulation handler Jul 03 18:54:56 gartenradio kernel: registered taskstats version 1 Jul 03 18:54:56 gartenradio kernel: Loading compiled-in X.509 certificates Jul 03 18:54:56 gartenradio kernel: Key type .fscrypt registered Jul 03 18:54:56 gartenradio kernel: Key type fscrypt-provisioning registered Jul 03 18:54:56 gartenradio kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled Jul 03 18:54:56 gartenradio kernel: 3f201000.serial: ttyAMA1 at MMIO 0x3f201000 (irq = 114, base_baud = 0) is a PL011 rev2 Jul 03 18:54:56 gartenradio kernel: serial serial0: tty port ttyAMA1 registered Jul 03 18:54:56 gartenradio kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Jul 03 18:54:56 gartenradio kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Jul 03 18:54:56 gartenradio kernel: mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0 Jul 03 18:54:56 gartenradio kernel: mmc-bcm2835 3f300000.mmcnr: DMA channel allocated Jul 03 18:54:56 gartenradio kernel: sdhost: log_buf @ f311c1f0 (f4107000) Jul 03 18:54:56 gartenradio kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Jul 03 18:54:56 gartenradio kernel: of_cfs_init Jul 03 18:54:56 gartenradio kernel: of_cfs_init: OK Jul 03 18:54:56 gartenradio kernel: Freeing unused kernel image (initmem) memory: 1024K Jul 03 18:54:56 gartenradio kernel: Indeed it is in host mode hprt0 = 00021501 Jul 03 18:54:56 gartenradio kernel: mmc0: host does not support reading read-only switch, assuming write-enable Jul 03 18:54:56 gartenradio kernel: mmc0: new high speed SDXC card at address 59b4 Jul 03 18:54:56 gartenradio kernel: mmcblk0: mmc0:59b4 SD64G 59.4 GiB Jul 03 18:54:56 gartenradio kernel: mmcblk0: p1 p2 p3 Jul 03 18:54:56 gartenradio kernel: mmcblk0: mmc0:59b4 SD64G 59.4 GiB (quirks 0x00004000) Jul 03 18:54:56 gartenradio kernel: mmc1: new high speed SDIO card at address 0001 Jul 03 18:54:56 gartenradio kernel: Run /init as init process Jul 03 18:54:56 gartenradio kernel: with arguments: Jul 03 18:54:56 gartenradio kernel: /init Jul 03 18:54:56 gartenradio kernel: splash Jul 03 18:54:56 gartenradio kernel: nodebug Jul 03 18:54:56 gartenradio kernel: with environment: Jul 03 18:54:56 gartenradio kernel: HOME=/ Jul 03 18:54:56 gartenradio kernel: TERM=linux Jul 03 18:54:56 gartenradio kernel: imgpart=UUID=826680f7-a407-4ab9-8bd5-1855bda526fd Jul 03 18:54:56 gartenradio kernel: imgfile=/volumio_current.sqsh Jul 03 18:54:56 gartenradio kernel: bootpart=UUID=FD90-ED9E Jul 03 18:54:56 gartenradio kernel: datapart=UUID=fecb0823-2e5b-4bba-9498-dd37b5084148 Jul 03 18:54:56 gartenradio kernel: uuidconfig=cmdline.txt Jul 03 18:54:56 gartenradio kernel: pcie_aspm=off Jul 03 18:54:56 gartenradio kernel: pci=pcie_bus_safe Jul 03 18:54:56 gartenradio kernel: bootdelay=7 Jul 03 18:54:56 gartenradio kernel: use_kmsg=no Jul 03 18:54:56 gartenradio kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg Jul 03 18:54:56 gartenradio kernel: Indeed it is in host mode hprt0 = 00001101 Jul 03 18:54:56 gartenradio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Jul 03 18:54:56 gartenradio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 03 18:54:56 gartenradio kernel: hub 1-1:1.0: USB hub found Jul 03 18:54:56 gartenradio kernel: hub 1-1:1.0: 5 ports detected Jul 03 18:54:56 gartenradio kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg Jul 03 18:54:56 gartenradio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Jul 03 18:54:56 gartenradio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 03 18:54:56 gartenradio kernel: smsc95xx v2.0.0 Jul 03 18:54:56 gartenradio kernel: SMSC LAN8700 usb-001:003:01: attached PHY driver (mii_bus:phy_addr=usb-001:003:01, irq=199) Jul 03 18:54:56 gartenradio kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:e3:e3:5c Jul 03 18:54:56 gartenradio kernel: fuse: init (API version 7.37) Jul 03 18:54:56 gartenradio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jul 03 18:54:56 gartenradio kernel: usbcore: registered new interface driver uas Jul 03 18:54:56 gartenradio kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jul 03 18:54:56 gartenradio kernel: EXT4-fs (mmcblk0p2): recovery complete Jul 03 18:54:56 gartenradio kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none. Jul 03 18:54:56 gartenradio kernel: loop0: detected capacity change from 0 to 979240 Jul 03 18:54:56 gartenradio kernel: EXT4-fs (mmcblk0p3): recovery complete Jul 03 18:54:56 gartenradio kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Quota mode: none. Jul 03 18:54:56 gartenradio systemd[1]: System time before build time, advancing clock. Jul 03 18:54:56 gartenradio kernel: NET: Registered PF_INET6 protocol family Jul 03 18:54:56 gartenradio kernel: Segment Routing with IPv6 Jul 03 18:54:56 gartenradio kernel: In-situ OAM (IOAM) with IPv6 Jul 03 18:54:56 gartenradio 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 03 18:54:56 gartenradio systemd[1]: Detected architecture arm. Jul 03 18:54:56 gartenradio systemd[1]: Set hostname to . Jul 03 18:54:56 gartenradio 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 03 18:54:56 gartenradio systemd[1]: Listening on fsck to fsckd communication Socket. Jul 03 18:54:56 gartenradio systemd[1]: Listening on initctl Compatibility Named Pipe. Jul 03 18:54:56 gartenradio systemd[1]: Listening on Journal Audit Socket. Jul 03 18:54:56 gartenradio systemd[1]: Listening on Journal Socket (/dev/log). Jul 03 18:54:56 gartenradio systemd[1]: Listening on udev Control Socket. Jul 03 18:54:56 gartenradio systemd[1]: Listening on Journal Socket. Jul 03 18:54:56 gartenradio kernel: i2c_dev: i2c /dev entries driver Jul 03 18:54:56 gartenradio systemd-journald[323]: Journal started Jul 03 18:54:56 gartenradio systemd-journald[323]: Runtime journal (/run/log/journal/c28a768762e55f60d289ca8f665f5f47) is 7.5M, max 30.0M, 22.5M free. Jul 03 18:54:56 gartenradio systemd-modules-load[313]: Inserted module 'i2c_dev' Jul 03 18:54:56 gartenradio fake-hwclock[312]: Wed Jul 3 16:54:56 UTC 2024 Jul 03 18:54:56 gartenradio systemd[1]: Starting Create System Users... Jul 03 18:54:56 gartenradio systemd[1]: Starting Load/Save Random Seed... Jul 03 18:54:56 gartenradio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jul 03 18:54:56 gartenradio systemd[1]: Mounting FUSE Control File System... Jul 03 18:54:56 gartenradio systemd[1]: Mounting Kernel Configuration File System... Jul 03 18:54:56 gartenradio systemd[1]: Starting Apply Kernel Variables... Jul 03 18:54:56 gartenradio systemd[1]: Started Load/Save Random Seed. Jul 03 18:54:56 gartenradio systemd[1]: dynamicswap.service: Succeeded. Jul 03 18:54:56 gartenradio systemd[1]: Mounted FUSE Control File System. Jul 03 18:54:56 gartenradio systemd[1]: Mounted Kernel Configuration File System. Jul 03 18:54:56 gartenradio systemd[1]: Started Apply Kernel Variables. Jul 03 18:54:56 gartenradio systemd[1]: Started Create System Users. Jul 03 18:54:56 gartenradio systemd[1]: Starting Create Static Device Nodes in /dev... Jul 03 18:54:56 gartenradio systemd[1]: Started udev Coldplug all Devices. Jul 03 18:54:56 gartenradio systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jul 03 18:54:56 gartenradio systemd[1]: Started Helper to synchronize boot up for ifupdown. Jul 03 18:54:56 gartenradio systemd[1]: Started Create Static Device Nodes in /dev. Jul 03 18:54:56 gartenradio systemd[1]: Reached target Local File Systems (Pre). Jul 03 18:54:56 gartenradio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jul 03 18:54:56 gartenradio systemd[1]: Mounting /var/log... Jul 03 18:54:56 gartenradio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jul 03 18:54:56 gartenradio systemd[1]: Mounting /tmp... Jul 03 18:54:56 gartenradio systemd[1]: Mounting /var/spool/cups... Jul 03 18:54:56 gartenradio systemd[1]: Starting udev Kernel Device Manager... Jul 03 18:54:56 gartenradio systemd[1]: Mounted /var/log. Jul 03 18:54:56 gartenradio systemd[1]: Mounted /tmp. Jul 03 18:54:56 gartenradio systemd[1]: Starting Flush Journal to Persistent Storage... Jul 03 18:54:56 gartenradio systemd[1]: Mounted /var/spool/cups. Jul 03 18:54:56 gartenradio systemd[1]: Mounting /var/spool/cups/tmp... Jul 03 18:54:56 gartenradio systemd[1]: Mounted /var/spool/cups/tmp. Jul 03 18:54:56 gartenradio systemd-udevd[368]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jul 03 18:54:56 gartenradio systemd-journald[323]: Runtime journal (/run/log/journal/c28a768762e55f60d289ca8f665f5f47) is 7.5M, max 30.0M, 22.5M free. Jul 03 18:54:56 gartenradio systemd[1]: Started Flush Journal to Persistent Storage. Jul 03 18:54:56 gartenradio systemd[1]: Started udev Kernel Device Manager. Jul 03 18:54:56 gartenradio systemd[1]: Starting Show Plymouth Boot Screen... Jul 03 18:54:56 gartenradio systemd[1]: plymouth-start.service: Succeeded. Jul 03 18:54:56 gartenradio systemd[1]: Started Show Plymouth Boot Screen. Jul 03 18:54:56 gartenradio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jul 03 18:54:56 gartenradio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jul 03 18:54:56 gartenradio systemd[1]: Reached target Paths. Jul 03 18:54:56 gartenradio systemd[1]: Reached target Local Encrypted Volumes. Jul 03 18:54:57 gartenradio kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jul 03 18:54:57 gartenradio kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jul 03 18:54:57 gartenradio kernel: [vc_sm_connected_init]: start Jul 03 18:54:57 gartenradio kernel: [vc_sm_connected_init]: installed successfully Jul 03 18:54:57 gartenradio kernel: mc: Linux media interface: v0.10 Jul 03 18:54:57 gartenradio kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 Jul 03 18:54:57 gartenradio kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem Jul 03 18:54:57 gartenradio kernel: videodev: Linux video capture interface: v2.00 Jul 03 18:54:57 gartenradio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jul 03 18:54:57 gartenradio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jul 03 18:54:57 gartenradio kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jul 03 18:54:57 gartenradio kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jul 03 18:54:57 gartenradio kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jul 03 18:54:57 gartenradio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jul 03 18:54:57 gartenradio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jul 03 18:54:57 gartenradio kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jul 03 18:54:57 gartenradio kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jul 03 18:54:57 gartenradio kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jul 03 18:54:57 gartenradio kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jul 03 18:54:57 gartenradio kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jul 03 18:54:57 gartenradio kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jul 03 18:54:57 gartenradio kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jul 03 18:54:57 gartenradio kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jul 03 18:54:57 gartenradio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jul 03 18:54:57 gartenradio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jul 03 18:54:57 gartenradio kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Jul 03 18:54:57 gartenradio kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Jul 03 18:54:57 gartenradio kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Jul 03 18:54:57 gartenradio kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Jul 03 18:54:57 gartenradio kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jul 03 18:54:57 gartenradio kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jul 03 18:54:57 gartenradio kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jul 03 18:54:57 gartenradio kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jul 03 18:54:57 gartenradio kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jul 03 18:54:57 gartenradio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jul 03 18:54:57 gartenradio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jul 03 18:54:57 gartenradio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Jul 03 18:54:57 gartenradio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Jul 03 18:54:57 gartenradio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Jul 03 18:54:57 gartenradio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Jul 03 18:54:57 gartenradio kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jul 03 18:54:57 gartenradio kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Jul 03 18:54:57 gartenradio systemd[1]: Found device /dev/disk/by-uuid/FD90-ED9E. Jul 03 18:54:57 gartenradio kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jul 03 18:54:57 gartenradio kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jul 03 18:54:58 gartenradio kernel: Bluetooth: Core ver 2.22 Jul 03 18:54:58 gartenradio kernel: NET: Registered PF_BLUETOOTH protocol family Jul 03 18:54:58 gartenradio kernel: Bluetooth: HCI device and connection manager initialized Jul 03 18:54:58 gartenradio kernel: Bluetooth: HCI socket layer initialized Jul 03 18:54:58 gartenradio kernel: Bluetooth: L2CAP socket layer initialized Jul 03 18:54:58 gartenradio kernel: Bluetooth: SCO socket layer initialized Jul 03 18:54:58 gartenradio systemd[1]: Starting File System Check on /dev/disk/by-uuid/FD90-ED9E... Jul 03 18:54:58 gartenradio systemd-udevd[381]: Using default interface naming scheme 'v240'. Jul 03 18:54:58 gartenradio systemd[1]: Started File System Check Daemon to report status. Jul 03 18:54:58 gartenradio systemd-udevd[381]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 03 18:54:58 gartenradio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jul 03 18:54:58 gartenradio kernel: Bluetooth: HCI UART driver ver 2.3 Jul 03 18:54:58 gartenradio kernel: Bluetooth: HCI UART protocol H4 registered Jul 03 18:54:58 gartenradio kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jul 03 18:54:58 gartenradio kernel: Bluetooth: HCI UART protocol Broadcom registered Jul 03 18:54:58 gartenradio kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Jul 03 18:54:58 gartenradio kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Jul 03 18:54:58 gartenradio kernel: uart-pl011 3f201000.serial: no DMA platform data Jul 03 18:54:58 gartenradio kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Jul 03 18:54:58 gartenradio kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Jul 03 18:54:58 gartenradio kernel: cfg80211: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jul 03 18:54:58 gartenradio kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jul 03 18:54:58 gartenradio kernel: Bluetooth: hci0: BCM: chip id 94 Jul 03 18:54:58 gartenradio kernel: Bluetooth: hci0: BCM: features 0x2e Jul 03 18:54:58 gartenradio kernel: Bluetooth: hci0: BCM43430A1 Jul 03 18:54:58 gartenradio kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0000 Jul 03 18:54:58 gartenradio kernel: Bluetooth: hci0: BCM43430A1 'brcm/BCM43430A1.hcd' Patch Jul 03 18:54:58 gartenradio systemd-fsck[457]: fsck.fat 4.1 (2017-01-24) Jul 03 18:54:58 gartenradio systemd-fsck[457]: 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Jul 03 18:54:58 gartenradio systemd-fsck[457]: Automatically removing dirty bit. Jul 03 18:54:58 gartenradio systemd-fsck[457]: Performing changes. Jul 03 18:54:58 gartenradio systemd-fsck[457]: /dev/mmcblk0p1: 366 files, 134023/184582 clusters Jul 03 18:54:58 gartenradio systemd-udevd[373]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 03 18:54:58 gartenradio systemd[1]: Started File System Check on /dev/disk/by-uuid/FD90-ED9E. Jul 03 18:54:58 gartenradio kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6 Jul 03 18:54:58 gartenradio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Jul 03 18:54:58 gartenradio kernel: usbcore: registered new interface driver brcmfmac Jul 03 18:54:59 gartenradio 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 03 18:54:59 gartenradio systemd[1]: Condition check resulted in SMSC9512/9514 Fast Ethernet Adapter being skipped. Jul 03 18:54:59 gartenradio systemd-udevd[375]: Using default interface naming scheme 'v240'. Jul 03 18:54:59 gartenradio systemd-udevd[375]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 03 18:54:59 gartenradio kernel: Bluetooth: hci0: BCM: features 0x2e Jul 03 18:54:59 gartenradio kernel: Bluetooth: hci0: BCM43438A1 37.4MHz Raspberry Pi 3-0141 Jul 03 18:54:59 gartenradio kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0508 Jul 03 18:55:00 gartenradio systemd[1]: Created slice system-bthelper.slice. Jul 03 18:55:00 gartenradio systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jul 03 18:55:00 gartenradio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jul 03 18:55:00 gartenradio systemd[1]: Starting Show Plymouth Boot Screen... Jul 03 18:55:00 gartenradio systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jul 03 18:55:00 gartenradio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jul 03 18:55:00 gartenradio systemd[1]: Mounting /boot... Jul 03 18:55:00 gartenradio systemd[1]: Mounted /boot. Jul 03 18:55:00 gartenradio systemd[1]: Reached target Local File Systems. Jul 03 18:55:00 gartenradio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jul 03 18:55:00 gartenradio systemd[1]: Starting Raise network interfaces... Jul 03 18:55:00 gartenradio systemd[1]: Starting Preprocess NFS configuration... Jul 03 18:55:00 gartenradio systemd[1]: Started ifup for eth0. Jul 03 18:55:00 gartenradio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jul 03 18:55:00 gartenradio systemd[1]: Starting Create Volatile Files and Directories... Jul 03 18:55:00 gartenradio systemd[1]: Starting Load/Save RF Kill Switch Status... Jul 03 18:55:00 gartenradio systemd[1]: plymouth-start.service: Succeeded. Jul 03 18:55:00 gartenradio systemd[1]: Started Show Plymouth Boot Screen. Jul 03 18:55:00 gartenradio systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Jul 03 18:55:00 gartenradio systemd[1]: Started Load/Save RF Kill Switch Status. Jul 03 18:55:00 gartenradio systemd[1]: plymouth-read-write.service: Succeeded. Jul 03 18:55:00 gartenradio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jul 03 18:55:00 gartenradio systemd[1]: nfs-config.service: Succeeded. Jul 03 18:55:00 gartenradio systemd[1]: Started Preprocess NFS configuration. Jul 03 18:55:00 gartenradio systemd[1]: Started Create Volatile Files and Directories. Jul 03 18:55:00 gartenradio systemd[1]: Received SIGRTMIN+20 from PID 212 (plymouthd). Jul 03 18:55:00 gartenradio systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jul 03 18:55:00 gartenradio systemd[1]: Starting RPC bind portmap service... Jul 03 18:55:00 gartenradio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jul 03 18:55:00 gartenradio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jul 03 18:55:00 gartenradio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jul 03 18:55:00 gartenradio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jul 03 18:55:00 gartenradio systemd[1]: Reached target NFS client services. Jul 03 18:55:00 gartenradio systemd[1]: Started ifup for wlan0. Jul 03 18:55:00 gartenradio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jul 03 18:55:00 gartenradio kernel: 8021q: 802.1Q VLAN Support v1.8 Jul 03 18:55:00 gartenradio systemd[1]: Started Update UTMP about System Boot/Shutdown. Jul 03 18:55:00 gartenradio systemd[1]: Reached target System Initialization. Jul 03 18:55:00 gartenradio systemd[1]: apt-daily.timer: Not using persistent file timestamp Fri 2024-07-05 15:24:15 CEST as it is in the future. Jul 03 18:55:00 gartenradio systemd[1]: Started Daily apt download activities. Jul 03 18:55:00 gartenradio systemd[1]: Started Daily Cleanup of Temporary Directories. Jul 03 18:55:00 gartenradio systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Fri 2024-07-05 15:24:15 CEST as it is in the future. Jul 03 18:55:00 gartenradio systemd[1]: Started Daily apt upgrade and clean activities. Jul 03 18:55:00 gartenradio systemd[1]: Reached target Timers. Jul 03 18:55:00 gartenradio systemd[1]: Listening on triggerhappy.socket. Jul 03 18:55:00 gartenradio systemd[1]: Listening on D-Bus System Message Bus Socket. Jul 03 18:55:00 gartenradio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jul 03 18:55:00 gartenradio systemd[1]: Reached target Sockets. Jul 03 18:55:00 gartenradio systemd[1]: Reached target Basic System. Jul 03 18:55:00 gartenradio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jul 03 18:55:00 gartenradio systemd[1]: Started D-Bus System Message Bus. Jul 03 18:55:00 gartenradio systemd[1]: Starting WPA supplicant... Jul 03 18:55:00 gartenradio systemd[1]: Started UPnP Renderer front-end to MPD. Jul 03 18:55:00 gartenradio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jul 03 18:55:00 gartenradio systemd[1]: Started volumio-remote-updater.service. Jul 03 18:55:00 gartenradio systemd[1]: Starting Wireless Services... Jul 03 18:55:00 gartenradio systemd[1]: Started Volumio Iptables Module. Jul 03 18:55:00 gartenradio systemd[1]: Started Volumio Log Rotation Service. Jul 03 18:55:00 gartenradio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 03 18:55:00 gartenradio systemd[1]: Starting Configure Bluetooth Modems connected by UART... Jul 03 18:55:01 gartenradio systemd[1]: Starting Login Service... Jul 03 18:55:01 gartenradio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jul 03 18:55:01 gartenradio systemd[1]: Started Manage Sound Card State (restore and store). Jul 03 18:55:01 gartenradio alsactl[647]: alsactl 1.1.8 daemon started Jul 03 18:55:01 gartenradio systemd[1]: Starting Save/Restore Sound Card State... Jul 03 18:55:01 gartenradio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jul 03 18:55:01 gartenradio systemd[1]: Starting dhcpcd on all interfaces... Jul 03 18:55:01 gartenradio systemd[1]: Starting triggerhappy global hotkey daemon... Jul 03 18:55:01 gartenradio systemd[1]: Started RPC bind portmap service. Jul 03 18:55:01 gartenradio systemd[1]: Reached target Remote File Systems (Pre). Jul 03 18:55:01 gartenradio systemd[1]: Reached target Remote File Systems. Jul 03 18:55:01 gartenradio dhcpcd[660]: Not running dhcpcd because /etc/network/interfaces Jul 03 18:55:01 gartenradio dhcpcd[660]: defines some interfaces that will use a Jul 03 18:55:01 gartenradio dhcpcd[660]: DHCP client or static address Jul 03 18:55:01 gartenradio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jul 03 18:55:01 gartenradio systemd[1]: Reached target RPC Port Mapper. Jul 03 18:55:01 gartenradio systemd[1]: Started Save/Restore Sound Card State. Jul 03 18:55:01 gartenradio systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Jul 03 18:55:01 gartenradio thd[662]: Unable to parse trigger line: Jul 03 18:55:01 gartenradio thd[662]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jul 03 18:55:01 gartenradio thd[662]: Unable to parse trigger line: Jul 03 18:55:01 gartenradio thd[662]: Found socket passed from systemd Jul 03 18:55:01 gartenradio systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Jul 03 18:55:01 gartenradio systemd[1]: Failed to start dhcpcd on all interfaces. Jul 03 18:55:01 gartenradio systemd[1]: Started triggerhappy global hotkey daemon. Jul 03 18:55:01 gartenradio systemd[1]: Reached target Sound Card. Jul 03 18:55:01 gartenradio sh[589]: wlan0=wlan0 Jul 03 18:55:01 gartenradio systemd-logind[638]: New seat seat0. Jul 03 18:55:01 gartenradio avahi-daemon[639]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jul 03 18:55:01 gartenradio avahi-daemon[639]: Successfully dropped root privileges. Jul 03 18:55:01 gartenradio avahi-daemon[639]: avahi-daemon 0.7 starting up. Jul 03 18:55:01 gartenradio sh[553]: eth0: waiting for carrier Jul 03 18:55:01 gartenradio dhcpcd[584]: eth0: waiting for carrier Jul 03 18:55:01 gartenradio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jul 03 18:55:01 gartenradio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jul 03 18:55:02 gartenradio systemd[1]: Started Login Service. Jul 03 18:55:02 gartenradio systemd[1]: hciuart.service: Succeeded. Jul 03 18:55:02 gartenradio systemd[1]: Started Configure Bluetooth Modems connected by UART. Jul 03 18:55:02 gartenradio avahi-daemon[639]: Successfully called chroot(). Jul 03 18:55:02 gartenradio avahi-daemon[639]: Successfully dropped remaining capabilities. Jul 03 18:55:02 gartenradio wpa_supplicant[624]: Successfully initialized wpa_supplicant Jul 03 18:55:02 gartenradio avahi-daemon[639]: Loading service file /services/volumio.service. Jul 03 18:55:02 gartenradio avahi-daemon[639]: Network interface enumeration completed. Jul 03 18:55:02 gartenradio avahi-daemon[639]: Server startup complete. Host name is gartenradio.local. Local service cookie is 3878879338. Jul 03 18:55:02 gartenradio avahi-daemon[639]: Service "Gartenradio" (/services/volumio.service) successfully established. Jul 03 18:55:02 gartenradio systemd[1]: Started Raise network interfaces. Jul 03 18:55:02 gartenradio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jul 03 18:55:02 gartenradio systemd[1]: Started WPA supplicant. Jul 03 18:55:02 gartenradio systemd[1]: Reached target Network. Jul 03 18:55:02 gartenradio volumio-remote-updater[626]: Error: No active session Jul 03 18:55:02 gartenradio systemd[1]: Starting Network Time Service... Jul 03 18:55:02 gartenradio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jul 03 18:55:02 gartenradio systemd[1]: Starting Permit User Sessions... Jul 03 18:55:02 gartenradio systemd[1]: Reached target Network is Online. Jul 03 18:55:02 gartenradio systemd[1]: Starting /etc/rc.local Compatibility... Jul 03 18:55:02 gartenradio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 03 18:55:02 gartenradio systemd[1]: Starting LSB: Brings up/down network automatically... Jul 03 18:55:02 gartenradio volumio-remote-updater[626]: [2024-07-03 18:55:02] [info] asio async_connect error: system:111 (Connection refused) Jul 03 18:55:02 gartenradio volumio-remote-updater[626]: [2024-07-03 18:55:02] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 03 18:55:02 gartenradio volumio-remote-updater[626]: [2024-07-03 18:55:02] [error] handle_connect error: Underlying Transport Error Jul 03 18:55:02 gartenradio systemd[1]: Starting Samba NMB Daemon... Jul 03 18:55:02 gartenradio systemd[1]: Starting Raspberry Pi bluetooth helper... Jul 03 18:55:02 gartenradio systemd[1]: Started /etc/rc.local Compatibility. Jul 03 18:55:02 gartenradio volumio[625]: Could not open config: /tmp/upmpdcli.conf Jul 03 18:55:02 gartenradio systemd[1]: Started Permit User Sessions. Jul 03 18:55:03 gartenradio systemd[1]: Starting Hold until boot process finishes up... Jul 03 18:55:03 gartenradio systemd[1]: Starting Terminate Plymouth Boot Screen... Jul 03 18:55:03 gartenradio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jul 03 18:55:03 gartenradio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jul 03 18:55:03 gartenradio bthelper[751]: Raspberry Pi BDADDR already set Jul 03 18:55:03 gartenradio systemd[1]: Started Raspberry Pi bluetooth helper. Jul 03 18:55:03 gartenradio systemd[1]: Received SIGRTMIN+21 from PID 212 (plymouthd). Jul 03 18:55:03 gartenradio ntpd[741]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 03 18:55:03 gartenradio ntpd[741]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jul 03 18:55:03 gartenradio systemd[1]: Started Network Time Service. Jul 03 18:55:03 gartenradio systemd[1]: iptables.service: Succeeded. Jul 03 18:55:03 gartenradio systemd[1]: plymouth-quit-wait.service: Succeeded. Jul 03 18:55:03 gartenradio systemd[1]: Started Hold until boot process finishes up. Jul 03 18:55:03 gartenradio systemd[1]: plymouth-quit.service: Succeeded. Jul 03 18:55:03 gartenradio ntpd[799]: proto: precision = 1.458 usec (-19) Jul 03 18:55:03 gartenradio systemd[1]: Started Terminate Plymouth Boot Screen. Jul 03 18:55:03 gartenradio systemd[1]: Received SIGRTMIN+21 from PID 212 (n/a). Jul 03 18:55:03 gartenradio ifplugd(eth0)[810]: ifplugd 0.28 initializing. Jul 03 18:55:03 gartenradio ifplugd(eth0)[810]: Using interface eth0/B8:27:EB:E3:E3:5C with driver (version: 6.1.77-v7+) Jul 03 18:55:03 gartenradio ifplugd(eth0)[810]: Using detection mode: SIOCETHTOOL Jul 03 18:55:03 gartenradio ifplugd(eth0)[810]: Initialization complete, link beat not detected. Jul 03 18:55:03 gartenradio ntpd[799]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 03 18:55:03 gartenradio ntpd[799]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 03 18:55:03 gartenradio ntpd[799]: Listen and drop on 0 v6wildcard [::]:123 Jul 03 18:55:03 gartenradio ntpd[799]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 03 18:55:03 gartenradio ntpd[799]: Listen normally on 2 lo 127.0.0.1:123 Jul 03 18:55:03 gartenradio ntpd[799]: Listening on routing socket on fd #19 for interface updates Jul 03 18:55:03 gartenradio ntpd[799]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 03 18:55:03 gartenradio ntpd[799]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 03 18:55:03 gartenradio loadcpufreq[678]: Loading cpufreq kernel modules...done (none). Jul 03 18:55:03 gartenradio systemd[1]: Started Getty on tty1. Jul 03 18:55:03 gartenradio systemd[1]: Reached target Login Prompts. Jul 03 18:55:03 gartenradio ifplugd[748]: Network Interface Plugging Daemon...start eth0...done. Jul 03 18:55:03 gartenradio systemd[1]: Starting Bluetooth service... Jul 03 18:55:03 gartenradio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jul 03 18:55:03 gartenradio systemd[1]: Started LSB: Brings up/down network automatically. Jul 03 18:55:03 gartenradio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jul 03 18:55:03 gartenradio cpufrequtils[815]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jul 03 18:55:03 gartenradio systemd[1]: Started LSB: set CPUFreq kernel parameters. Jul 03 18:55:03 gartenradio bluetoothd[814]: Bluetooth daemon 5.50 Jul 03 18:55:03 gartenradio bluetoothd[814]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jul 03 18:55:03 gartenradio systemd[1]: Started Bluetooth service. Jul 03 18:55:03 gartenradio systemd[1]: Reached target Bluetooth. Jul 03 18:55:03 gartenradio bluetoothd[814]: Starting SDP server Jul 03 18:55:03 gartenradio bluetoothd[814]: Excluding (cli) sap Jul 03 18:55:03 gartenradio haveged[585]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jul 03 18:55:03 gartenradio haveged[585]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jul 03 18:55:03 gartenradio haveged[585]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99942 Jul 03 18:55:03 gartenradio haveged[585]: haveged: fills: 0, generated: 0 Jul 03 18:55:04 gartenradio bluetoothd[814]: Bluetooth management interface 1.22 initialized Jul 03 18:55:04 gartenradio dbus-daemon[623]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=814 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jul 03 18:55:04 gartenradio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jul 03 18:55:04 gartenradio kernel: Bluetooth: BNEP filters: protocol multicast Jul 03 18:55:04 gartenradio kernel: Bluetooth: BNEP socket layer initialized Jul 03 18:55:04 gartenradio kernel: Bluetooth: MGMT ver 1.22 Jul 03 18:55:04 gartenradio bluetoothd[814]: Failed to set privacy: Rejected (0x0b) Jul 03 18:55:04 gartenradio systemd[1]: Starting Hostname Service... Jul 03 18:55:04 gartenradio ntpd[799]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 03 18:55:04 gartenradio dbus-daemon[623]: [system] Successfully activated service 'org.freedesktop.hostname1' Jul 03 18:55:04 gartenradio systemd[1]: Started Hostname Service. Jul 03 18:55:04 gartenradio nmbd[755]: [2024/07/03 18:55:04.631468, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jul 03 18:55:04 gartenradio nmbd[755]: started asyncdns process 836 Jul 03 18:55:04 gartenradio nmbd[755]: [2024/07/03 18:55:04.633781, 0] ../lib/util/become_daemon.c:149(daemon_status) Jul 03 18:55:04 gartenradio nmbd[755]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jul 03 18:55:04 gartenradio nmbd[755]: [2024/07/03 18:55:04.634012, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jul 03 18:55:04 gartenradio nmbd[755]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jul 03 18:55:05 gartenradio wireless.js[627]: Cleaning previous... Jul 03 18:55:05 gartenradio sudo[844]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 03 18:55:05 gartenradio sudo[844]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:05 gartenradio sudo[844]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:05 gartenradio sudo[846]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 03 18:55:05 gartenradio sudo[846]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:05 gartenradio ntpd[799]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jul 03 18:55:05 gartenradio systemd[1]: systemd-rfkill.service: Succeeded. Jul 03 18:55:05 gartenradio sudo[846]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:05 gartenradio wireless.js[627]: Stopped aP Jul 03 18:55:05 gartenradio sudo[853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 03 18:55:05 gartenradio sudo[853]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:05 gartenradio sudo[853]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:05 gartenradio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 03 18:55:06 gartenradio sudo[855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jul 03 18:55:06 gartenradio sudo[855]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:06 gartenradio sudo[855]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:06 gartenradio sudo[862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 03 18:55:06 gartenradio sudo[862]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:06 gartenradio sudo[862]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:06 gartenradio sudo[864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jul 03 18:55:06 gartenradio sudo[864]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:06 gartenradio ntpd[799]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jul 03 18:55:06 gartenradio sudo[864]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:06 gartenradio wireless.js[627]: SETTING APPROPRIATE REG DOMAIN: DE Jul 03 18:55:06 gartenradio sudo[869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 03 18:55:06 gartenradio sudo[869]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:06 gartenradio sudo[869]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:06 gartenradio sudo[871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Jul 03 18:55:06 gartenradio sudo[871]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:06 gartenradio sudo[871]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:06 gartenradio wireless.js[627]: SUCCESSFULLY SET NEW REGDOMAIN: DE Jul 03 18:55:06 gartenradio wireless.js[627]: Start wireless flow Jul 03 18:55:06 gartenradio wireless.js[627]: Stopped hotspot (if there).. Jul 03 18:55:06 gartenradio sudo[876]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 03 18:55:06 gartenradio sudo[876]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:06 gartenradio sudo[876]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:06 gartenradio sudo[878]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 03 18:55:06 gartenradio sudo[878]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:07 gartenradio ntpd[799]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 03 18:55:07 gartenradio sudo[878]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:07 gartenradio wireless.js[627]: DHCP IP Jul 03 18:55:07 gartenradio wireless.js[627]: Start ap Jul 03 18:55:07 gartenradio wpa_supplicant[880]: Successfully initialized wpa_supplicant Jul 03 18:55:07 gartenradio sudo[881]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jul 03 18:55:07 gartenradio sudo[881]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:07 gartenradio dhcpcd[882]: dev: loaded udev Jul 03 18:55:07 gartenradio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 03 18:55:07 gartenradio dhcpcd-run-hooks[900]: wlan0: starting wpa_supplicant Jul 03 18:55:07 gartenradio dhcpcd-run-hooks[905]: wlan0: failed to start wpa_supplicant Jul 03 18:55:07 gartenradio volumio-remote-updater[626]: [2024-07-03 18:55:07] [info] asio async_connect error: system:111 (Connection refused) Jul 03 18:55:07 gartenradio volumio-remote-updater[626]: [2024-07-03 18:55:07] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 03 18:55:07 gartenradio volumio-remote-updater[626]: [2024-07-03 18:55:07] [error] handle_connect error: Underlying Transport Error Jul 03 18:55:07 gartenradio dhcpcd-run-hooks[906]: 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 03 18:55:07 gartenradio dhcpcd[882]: wlan0: connected to Access Point `' Jul 03 18:55:07 gartenradio dhcpcd[882]: no interfaces have a carrier Jul 03 18:55:07 gartenradio dhcpcd[882]: forked to background, child pid 916 Jul 03 18:55:07 gartenradio dhcpcd[916]: eth0: waiting for carrier Jul 03 18:55:07 gartenradio dhcpcd[916]: wlan0: waiting for carrier Jul 03 18:55:07 gartenradio sudo[881]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:08 gartenradio bthelper[751]: Changing power off succeeded Jul 03 18:55:08 gartenradio wpa_supplicant[904]: wlan0: Trying to associate with SSID 'Schleuse_7_FritzBox' Jul 03 18:55:08 gartenradio wireless.js[627]: trying... Jul 03 18:55:08 gartenradio sudo[919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 18:55:08 gartenradio sudo[919]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:08 gartenradio sudo[919]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:08 gartenradio bthelper[751]: [CHG] Controller B8:27:EB:49:49:F6 Class: 0x0000041c Jul 03 18:55:08 gartenradio bthelper[751]: Changing power on succeeded Jul 03 18:55:08 gartenradio dhcpcd[916]: wlan0: carrier acquired Jul 03 18:55:08 gartenradio dhcpcd[916]: wlan0: connected to Access Point `Schleuse_7_FritzBox' Jul 03 18:55:08 gartenradio wpa_supplicant[904]: wlan0: Associated with 5c:49:79:9b:cc:73 Jul 03 18:55:08 gartenradio wpa_supplicant[904]: wlan0: CTRL-EVENT-CONNECTED - Connection to 5c:49:79:9b:cc:73 completed [id=0 id_str=] Jul 03 18:55:09 gartenradio wpa_supplicant[904]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 03 18:55:09 gartenradio wpa_supplicant[904]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Jul 03 18:55:09 gartenradio dhcpcd[916]: DUID 00:01:00:01:2d:f2:1e:14:b8:27:eb:b6:b6:09 Jul 03 18:55:09 gartenradio dhcpcd[916]: wlan0: IAID eb:b6:b6:09 Jul 03 18:55:09 gartenradio dhcpcd[916]: wlan0: adding address fe80::1e9:ae10:f088:f94e Jul 03 18:55:09 gartenradio dhcpcd[916]: ipv6_addaddr1: Permission denied Jul 03 18:55:09 gartenradio dhcpcd[916]: wlan0: carrier lost Jul 03 18:55:09 gartenradio dhcpcd[916]: wlan0: carrier acquired Jul 03 18:55:09 gartenradio dhcpcd[916]: wlan0: IAID eb:b6:b6:09 Jul 03 18:55:09 gartenradio dhcpcd[916]: wlan0: soliciting an IPv6 router Jul 03 18:55:09 gartenradio wireless.js[627]: trying... Jul 03 18:55:09 gartenradio sudo[940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 18:55:09 gartenradio sudo[940]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:09 gartenradio sudo[940]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:10 gartenradio dhcpcd[916]: wlan0: rebinding lease of 192.168.2.141 Jul 03 18:55:10 gartenradio dhcpcd[916]: wlan0: probing address 192.168.2.141/24 Jul 03 18:55:10 gartenradio wireless.js[627]: trying... Jul 03 18:55:10 gartenradio sudo[943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 18:55:10 gartenradio sudo[943]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:10 gartenradio sudo[943]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:11 gartenradio wireless.js[627]: trying... Jul 03 18:55:11 gartenradio sudo[946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 18:55:11 gartenradio sudo[946]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:11 gartenradio sudo[946]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:12 gartenradio wireless.js[627]: trying... Jul 03 18:55:12 gartenradio sudo[949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 18:55:12 gartenradio sudo[949]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:12 gartenradio sudo[949]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:13 gartenradio wireless.js[627]: trying... Jul 03 18:55:13 gartenradio sudo[952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 18:55:13 gartenradio sudo[952]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:13 gartenradio sudo[952]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:14 gartenradio wireless.js[627]: trying... Jul 03 18:55:14 gartenradio sudo[955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 18:55:14 gartenradio sudo[955]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:14 gartenradio sudo[955]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:14 gartenradio dhcpcd[916]: wlan0: leased 192.168.2.141 for 864000 seconds Jul 03 18:55:14 gartenradio avahi-daemon[639]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.141. Jul 03 18:55:14 gartenradio dhcpcd[916]: wlan0: adding route to 192.168.2.0/24 Jul 03 18:55:14 gartenradio avahi-daemon[639]: New relevant interface wlan0.IPv4 for mDNS. Jul 03 18:55:14 gartenradio dhcpcd[916]: wlan0: adding default route via 192.168.2.1 Jul 03 18:55:14 gartenradio avahi-daemon[639]: Registering new address record for 192.168.2.141 on wlan0.IPv4. Jul 03 18:55:15 gartenradio volumio-remote-updater[626]: [2024-07-03 18:55:15] [info] asio async_connect error: system:111 (Connection refused) Jul 03 18:55:15 gartenradio volumio-remote-updater[626]: [2024-07-03 18:55:15] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 03 18:55:15 gartenradio volumio-remote-updater[626]: [2024-07-03 18:55:15] [error] handle_connect error: Underlying Transport Error Jul 03 18:55:15 gartenradio wireless.js[627]: trying... Jul 03 18:55:15 gartenradio sudo[979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 18:55:15 gartenradio sudo[979]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:15 gartenradio sudo[979]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:15 gartenradio wireless.js[627]: Connected to: ----Schleuse_7_FritzBox Jul 03 18:55:15 gartenradio wireless.js[627]: ---- Jul 03 18:55:15 gartenradio sudo[982]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 18:55:15 gartenradio sudo[982]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:15 gartenradio sudo[982]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:15 gartenradio wireless.js[627]: ... joined AP, wlan0 IPv4 is 192.168.2.141, ipV6 is undefined Jul 03 18:55:15 gartenradio wireless.js[627]: It's done! AP Jul 03 18:55:15 gartenradio systemd[1]: Started Wireless Services. Jul 03 18:55:15 gartenradio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 03 18:55:15 gartenradio systemd[1]: Started Volumio Backend Module. Jul 03 18:55:15 gartenradio systemd[1]: Started Volumio Cpu Tweaker. Jul 03 18:55:15 gartenradio volumio-cpu-tweak[988]: Setting RT Priority for mpd Jul 03 18:55:15 gartenradio volumio-cpu-tweak[988]: pid 35's current scheduling policy: SCHED_OTHER Jul 03 18:55:15 gartenradio volumio-cpu-tweak[988]: pid 35's current scheduling priority: 0 Jul 03 18:55:15 gartenradio volumio-cpu-tweak[988]: Setting MPD Affinity Jul 03 18:55:15 gartenradio volumio-cpu-tweak[988]: pid 3's current affinity mask: f Jul 03 18:55:15 gartenradio volumio-cpu-tweak[988]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jul 03 18:55:15 gartenradio volumio-cpu-tweak[988]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jul 03 18:55:15 gartenradio systemd[1]: volumio_cpu_tweak.service: Succeeded. Jul 03 18:55:16 gartenradio ntpd[799]: Listen normally on 3 wlan0 192.168.2.141:123 Jul 03 18:55:16 gartenradio ntpd[799]: new interface(s) found: waking up resolver Jul 03 18:55:18 gartenradio volumio[987]: info: ------------------------------------------- Jul 03 18:55:18 gartenradio volumio[987]: info: ----- Volumio3 ---- Jul 03 18:55:18 gartenradio volumio[987]: info: ------------------------------------------- Jul 03 18:55:18 gartenradio volumio[987]: info: ----- System startup ---- Jul 03 18:55:18 gartenradio volumio[987]: info: ------------------------------------------- Jul 03 18:55:19 gartenradio volumio[987]: info: MYVOLUMIO Environment detected Jul 03 18:55:19 gartenradio nmbd[755]: [2024/07/03 18:55:19.639225, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 03 18:55:19 gartenradio nmbd[755]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jul 03 18:55:19 gartenradio systemd[1]: Started Samba NMB Daemon. Jul 03 18:55:19 gartenradio systemd[1]: Starting Samba Winbind Daemon... Jul 03 18:55:19 gartenradio volumio[987]: info: Plugin folders cleanup Jul 03 18:55:19 gartenradio volumio[987]: info: Scanning into folder /volumio/app/plugins/ Jul 03 18:55:19 gartenradio volumio[987]: info: Scanning category audio_interface Jul 03 18:55:19 gartenradio volumio[987]: info: Scanning category miscellanea Jul 03 18:55:19 gartenradio volumio[987]: info: Scanning category music_service Jul 03 18:55:19 gartenradio volumio[987]: info: Scanning category plugins.json Jul 03 18:55:19 gartenradio volumio[987]: info: Scanning category system_controller Jul 03 18:55:19 gartenradio volumio[987]: info: Scanning category user_interface Jul 03 18:55:19 gartenradio volumio[987]: info: Scanning into folder /data/plugins/ Jul 03 18:55:19 gartenradio volumio[987]: info: Scanning category audio_interface Jul 03 18:55:19 gartenradio volumio[987]: info: Scanning category music_service Jul 03 18:55:19 gartenradio volumio[987]: info: Scanning category system_controller Jul 03 18:55:19 gartenradio volumio[987]: info: Scanning category user_interface Jul 03 18:55:19 gartenradio volumio[987]: info: Plugin folders cleanup completed Jul 03 18:55:19 gartenradio volumio[987]: info: ------------------------------------------- Jul 03 18:55:19 gartenradio volumio[987]: info: ----- Core plugins startup ---- Jul 03 18:55:19 gartenradio volumio[987]: info: ------------------------------------------- Jul 03 18:55:19 gartenradio volumio[987]: info: Loading plugins from folder /volumio/app/plugins/ Jul 03 18:55:19 gartenradio volumio[987]: info: Adding plugin upnp to MyMusic Plugins Jul 03 18:55:19 gartenradio volumio[987]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 03 18:55:19 gartenradio volumio[987]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 03 18:55:19 gartenradio volumio[987]: info: Loading plugins from folder /data/plugins/ Jul 03 18:55:19 gartenradio volumio[987]: info: Loading plugin "system"... Jul 03 18:55:19 gartenradio volumio[987]: info: Loading plugin "appearance"... Jul 03 18:55:20 gartenradio winbindd[1014]: [2024/07/03 18:55:20.095711, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jul 03 18:55:20 gartenradio winbindd[1014]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jul 03 18:55:20 gartenradio winbindd[1014]: [2024/07/03 18:55:20.131644, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 03 18:55:20 gartenradio winbindd[1014]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jul 03 18:55:20 gartenradio systemd[1]: Started Samba Winbind Daemon. Jul 03 18:55:20 gartenradio systemd[1]: Starting Samba SMB Daemon... Jul 03 18:55:20 gartenradio winbindd[1014]: [2024/07/03 18:55:20.817808, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Jul 03 18:55:20 gartenradio winbindd[1014]: res_names->count = 3, expected 4 Jul 03 18:55:20 gartenradio smbd[1022]: [2024/07/03 18:55:20.852563, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 03 18:55:20 gartenradio smbd[1022]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 03 18:55:20 gartenradio systemd[1]: Started Samba SMB Daemon. Jul 03 18:55:20 gartenradio systemd[1]: Reached target Multi-User System. Jul 03 18:55:20 gartenradio systemd[1]: Reached target Graphical Interface. Jul 03 18:55:20 gartenradio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 03 18:55:20 gartenradio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 03 18:55:20 gartenradio systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 03 18:55:20 gartenradio systemd[1]: Startup finished in 17.096s (kernel) + 27.122s (userspace) = 44.219s. Jul 03 18:55:21 gartenradio volumio[987]: info: Loading plugin "network"... Jul 03 18:55:21 gartenradio volumio[987]: info: Refreshing Cached IP Addresses Jul 03 18:55:21 gartenradio sudo[1032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 03 18:55:21 gartenradio sudo[1032]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:21 gartenradio volumio[987]: info: Loading plugin "services"... Jul 03 18:55:21 gartenradio sudo[1032]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:21 gartenradio sudo[1034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 18:55:21 gartenradio sudo[1034]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:21 gartenradio volumio[987]: info: Loading plugin "alsa_controller"... Jul 03 18:55:21 gartenradio sudo[1034]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:21 gartenradio sudo[1037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 03 18:55:21 gartenradio sudo[1037]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:21 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 18:55:21 gartenradio volumio[987]: info: Loading plugin "wizard"... Jul 03 18:55:21 gartenradio volumio[987]: info: Loading plugin "networkfs"... Jul 03 18:55:22 gartenradio volumio[987]: info: Starting Udev Watcher for removable devices Jul 03 18:55:22 gartenradio sudo[1045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=michael,password=Apoptygma#88,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.34/Musik/ /mnt/NAS/Musik-NAS Jul 03 18:55:22 gartenradio sudo[1045]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:22 gartenradio volumio[987]: info: Ignoring mount for partition: boot Jul 03 18:55:22 gartenradio volumio[987]: info: Ignoring mount for partition: volumio Jul 03 18:55:22 gartenradio volumio[987]: info: Ignoring mount for partition: volumio_data Jul 03 18:55:22 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 18:55:22 gartenradio volumio[987]: info: Loading plugin "volumio_command_line_client"... Jul 03 18:55:22 gartenradio volumio[987]: info: Loading plugin "upnp"... Jul 03 18:55:22 gartenradio volumio[987]: info: [1720025722078] Starting Upmpd Daemon Jul 03 18:55:22 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 18:55:22 gartenradio volumio[987]: info: Loading plugin "my_music"... Jul 03 18:55:22 gartenradio volumio[987]: info: Loading plugin "mpd"... Jul 03 18:55:22 gartenradio kernel: Key type cifs.spnego registered Jul 03 18:55:22 gartenradio kernel: Key type cifs.idmap registered Jul 03 18:55:22 gartenradio 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 03 18:55:22 gartenradio kernel: CIFS: Attempting to mount \\192.168.2.34\Musik Jul 03 18:55:22 gartenradio volumio[987]: info: Loading plugin "upnp_browser"... Jul 03 18:55:22 gartenradio sudo[1045]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:22 gartenradio sudo[1037]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:23 gartenradio volumio[987]: info: Loading plugin "alarm-clock"... Jul 03 18:55:23 gartenradio volumio[987]: info: Loading plugin "airplay_emulation"... Jul 03 18:55:23 gartenradio volumio[987]: info: Starting Shairport Sync Jul 03 18:55:23 gartenradio volumio[987]: info: Loading plugin "last_100"... Jul 03 18:55:23 gartenradio volumio[987]: info: Loading plugin "webradio"... Jul 03 18:55:23 gartenradio volumio[987]: info: Loading plugin "i2s_dacs"... Jul 03 18:55:23 gartenradio volumio[987]: info: Loading plugin "volumiodiscovery"... Jul 03 18:55:23 gartenradio volumio[987]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 03 18:55:23 gartenradio volumio[987]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 18:55:23 gartenradio volumio[987]: *** WARNING *** For more information see Jul 03 18:55:23 gartenradio volumio[987]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 03 18:55:23 gartenradio node[987]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 03 18:55:23 gartenradio volumio[987]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 18:55:23 gartenradio volumio[987]: *** WARNING *** For more information see Jul 03 18:55:23 gartenradio node[987]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 18:55:23 gartenradio node[987]: *** WARNING *** For more information see Jul 03 18:55:23 gartenradio node[987]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 03 18:55:23 gartenradio node[987]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 18:55:23 gartenradio node[987]: *** WARNING *** For more information see Jul 03 18:55:23 gartenradio volumio[987]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 03 18:55:23 gartenradio volumio[987]: info: Discovery: Started advertising with name: Gartenradio Jul 03 18:55:23 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 18:55:23 gartenradio volumio[987]: info: Loading plugin "spop"... Jul 03 18:55:25 gartenradio volumio-remote-updater[626]: [2024-07-03 18:55:25] [connect] Successful connection Jul 03 18:55:25 gartenradio volumio[987]: info: Loading plugin "now_playing"... Jul 03 18:55:27 gartenradio volumio[987]: info: Loading plugin "outputs"... Jul 03 18:55:27 gartenradio volumio[987]: info: Loading plugin "albumart"... Jul 03 18:55:27 gartenradio volumio[987]: info: Plugin example_plugin is not enabled Jul 03 18:55:27 gartenradio volumio[987]: info: Loading plugin "inputs"... Jul 03 18:55:27 gartenradio volumio[987]: info: Loading plugin "updater_comm"... Jul 03 18:55:27 gartenradio volumio[987]: info: Plugin mpdemulation is not enabled Jul 03 18:55:27 gartenradio volumio[987]: info: Loading plugin "rest_api"... Jul 03 18:55:27 gartenradio volumio[987]: info: Loading plugin "websocket"... Jul 03 18:55:27 gartenradio volumio[987]: info: Starting Socket.io Server version 2.3.0 Jul 03 18:55:27 gartenradio volumio[987]: info: Loading plugin "fusiondsp"... Jul 03 18:55:27 gartenradio volumio[987]: Forking 3 albumart workers Jul 03 18:55:28 gartenradio volumio[987]: info: Applying required configuration parameters for plugin fusiondsp Jul 03 18:55:28 gartenradio volumio[987]: info: Loading plugin "backup_restore"... Jul 03 18:55:28 gartenradio systemd[1]: systemd-fsckd.service: Succeeded. Jul 03 18:55:28 gartenradio volumio[987]: Starting albumart workers Jul 03 18:55:28 gartenradio volumio[987]: info: Applying required configuration parameters for plugin backup_restore Jul 03 18:55:28 gartenradio volumio[987]: Starting albumart workers Jul 03 18:55:28 gartenradio volumio[987]: info: Loading i18n strings for locale de Jul 03 18:55:28 gartenradio volumio[987]: Updating browse sources language Jul 03 18:55:28 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 18:55:29 gartenradio volumio[987]: Starting albumart workers Jul 03 18:55:29 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 18:55:29 gartenradio volumio[987]: info: CoreCommandRouter::initPlayerControls Jul 03 18:55:29 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:29 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:29 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:29 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:29 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:29 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:29 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:29 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:29 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 18:55:29 gartenradio volumio[987]: Express server listening on port 3000 Jul 03 18:55:29 gartenradio volumio[987]: [Metrics] WebUI: 11s 829.15ms Jul 03 18:55:29 gartenradio volumio[987]: info: CoreStateMachine::resetVolumioState Jul 03 18:55:29 gartenradio volumio[987]: info: CoreStateMachine::getcurrentVolume Jul 03 18:55:29 gartenradio volumio[987]: info: CoreCommandRouter::volumioRetrievevolume Jul 03 18:55:29 gartenradio volumio-remote-updater[626]: [2024-07-03 18:55:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1720025725 101 Jul 03 18:55:29 gartenradio volumio[987]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jul 03 18:55:29 gartenradio volumio[987]: info: CoreCommandRouter::volumioGetState Jul 03 18:55:29 gartenradio volumio[987]: info: CorePlayQueue::getTrack 0 Jul 03 18:55:29 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 18:55:29 gartenradio volumio[987]: info: Reloading queue from file Jul 03 18:55:29 gartenradio volumio[987]: info: VolumeController:: Volume=53 Mute =false Jul 03 18:55:29 gartenradio volumio[987]: info: CoreStateMachine::pushState Jul 03 18:55:29 gartenradio volumio[987]: info: CorePlayQueue::getTrack 0 Jul 03 18:55:29 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 18:55:29 gartenradio volumio[987]: info: CoreCommandRouter::volumioPushState Jul 03 18:55:29 gartenradio volumio[987]: info: CoreStateMachine::updateTrackBlock Jul 03 18:55:29 gartenradio volumio[987]: info: CorePlayQueue::getTrackBlock Jul 03 18:55:29 gartenradio volumio[987]: info: CoreCommandRouter::volumioRetrievevolume Jul 03 18:55:29 gartenradio volumio[987]: info: CoreStateMachine::setRepeat null single undefined Jul 03 18:55:29 gartenradio volumio[987]: info: CoreStateMachine::pushState Jul 03 18:55:29 gartenradio volumio[987]: info: CorePlayQueue::getTrack 0 Jul 03 18:55:29 gartenradio volumio[987]: info: CoreCommandRouter::volumioPushState Jul 03 18:55:29 gartenradio volumio[987]: info: CoreStateMachine::setRandom null Jul 03 18:55:29 gartenradio volumio[987]: info: CoreStateMachine::pushState Jul 03 18:55:29 gartenradio volumio[987]: info: CorePlayQueue::getTrack 0 Jul 03 18:55:29 gartenradio volumio[987]: info: CoreCommandRouter::volumioPushState Jul 03 18:55:29 gartenradio volumio[987]: info: Setting Device type: Raspberry PI Jul 03 18:55:29 gartenradio volumio[987]: info: Completed loading Core Plugins Jul 03 18:55:29 gartenradio volumio[987]: info: Preparing to generate the ALSA configuration file Jul 03 18:55:29 gartenradio volumio[987]: info: Listing playlists Jul 03 18:55:29 gartenradio volumio[987]: info: Listing playlists Jul 03 18:55:29 gartenradio volumio[987]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 03 18:55:29 gartenradio volumio[987]: info: Reading ALSA contributions from plugins. Jul 03 18:55:29 gartenradio volumio[987]: info: Asound.conf file unchanged, so no further update is needed Jul 03 18:55:29 gartenradio volumio[987]: info: Output device has changed, restarting MPD Jul 03 18:55:29 gartenradio volumio[987]: info: Output device has changed, restarting Shairport Sync Jul 03 18:55:29 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:29 gartenradio sudo[1137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 03 18:55:29 gartenradio sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:29 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 18:55:29 gartenradio sudo[1137]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:29 gartenradio sudo[1139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 03 18:55:29 gartenradio sudo[1139]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:30 gartenradio volumio[987]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 18:55:30 gartenradio volumio[987]: info: ___________ START PLUGINS ___________ Jul 03 18:55:30 gartenradio volumio[987]: info: ControllerMpd::onStart: Initializing MPD Jul 03 18:55:30 gartenradio volumio[987]: info: Creating MPD Configuration file Jul 03 18:55:30 gartenradio sudo[1147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 03 18:55:30 gartenradio sudo[1147]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 18:55:30 gartenradio volumio[987]: info: [1720025730057] CoreMusicLibrary::Adding element Medienserver Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 18:55:30 gartenradio sudo[1147]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:30 gartenradio sudo[1149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 03 18:55:30 gartenradio sudo[1149]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:30 gartenradio systemd[1]: Listening on mpd.socket. Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 18:55:30 gartenradio systemd[1]: Starting Music Player Daemon... Jul 03 18:55:30 gartenradio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 03 18:55:30 gartenradio systemd[1]: mpd.service: Succeeded. Jul 03 18:55:30 gartenradio systemd[1]: Stopped Music Player Daemon. Jul 03 18:55:30 gartenradio systemd[1]: Starting Music Player Daemon... Jul 03 18:55:30 gartenradio volumio[987]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 18:55:30 gartenradio volumio[987]: info: [1720025730202] CoreMusicLibrary::Adding element Last_100 Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 18:55:30 gartenradio volumio[987]: info: [1720025730206] CoreMusicLibrary::Adding element Webradio Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 18:55:30 gartenradio volumio[987]: info: Initializing BBC Radios Jul 03 18:55:30 gartenradio sudo[1156]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 03 18:55:30 gartenradio sudo[1156]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:30 gartenradio sudo[1156]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jul 03 18:55:30 gartenradio sudo[1156]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: Creating Spotify config file Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: [now-playing] ConfigUpdater: config is up to date. Jul 03 18:55:30 gartenradio volumio[987]: info: Loading i18n strings for locale de Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: FusionDsp - mixtype--------------------- Hardware Jul 03 18:55:30 gartenradio volumio[987]: info: Volumio Calling Home Jul 03 18:55:30 gartenradio volumio[987]: info: Preparing to generate the ALSA configuration file Jul 03 18:55:30 gartenradio volumio[987]: info: [now-playing] Fetching API key... Jul 03 18:55:30 gartenradio volumio[987]: info: [now-playing] App is listening on port 4004. Jul 03 18:55:30 gartenradio volumio[987]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jul 03 18:55:30 gartenradio volumio[987]: info: Discovery: adding 6500665b-a32a-4390-8816-a17991088099 Jul 03 18:55:30 gartenradio volumio[987]: info: Discovery: Found device Gartenradio Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::volumioGetState Jul 03 18:55:30 gartenradio volumio[987]: info: CorePlayQueue::getTrack 0 Jul 03 18:55:30 gartenradio volumio[987]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 03 18:55:30 gartenradio volumio[987]: info: Reading ALSA contributions from plugins. Jul 03 18:55:30 gartenradio volumio[987]: info: MPD Permissions set Jul 03 18:55:30 gartenradio volumio[987]: info: MPD Permissions set Jul 03 18:55:30 gartenradio volumio[987]: info: VolumeController:: Volume=53 Mute =false Jul 03 18:55:30 gartenradio volumio[987]: info: CoreStateMachine::pushState Jul 03 18:55:30 gartenradio volumio[987]: info: CorePlayQueue::getTrack 0 Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::volumioPushState Jul 03 18:55:30 gartenradio volumio[987]: info: Volumio called home Jul 03 18:55:30 gartenradio volumio[987]: info: Spotify config file written Jul 03 18:55:30 gartenradio sudo[1176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 03 18:55:30 gartenradio sudo[1176]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:30 gartenradio volumio[987]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:30 gartenradio systemd[1]: Started go-librespot Daemon. Jul 03 18:55:30 gartenradio go-librespot[1182]: Librespot-go daemon starting... Jul 03 18:55:30 gartenradio sudo[1176]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 18:55:30 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:31 gartenradio volumio[987]: info: Starting Shairport Sync Jul 03 18:55:31 gartenradio volumio[987]: info: Starting Shairport Sync Jul 03 18:55:31 gartenradio volumio[987]: info: Starting Shairport Sync Jul 03 18:55:31 gartenradio sudo[1194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 03 18:55:31 gartenradio sudo[1194]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:31 gartenradio sudo[1196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 03 18:55:31 gartenradio sudo[1196]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:31 gartenradio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 03 18:55:31 gartenradio systemd[1]: shairport-sync.service: Succeeded. Jul 03 18:55:31 gartenradio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 03 18:55:31 gartenradio volumio[987]: info: CoreCommandRouter::volumioGetState Jul 03 18:55:31 gartenradio volumio[987]: info: CorePlayQueue::getTrack 0 Jul 03 18:55:31 gartenradio sudo[1199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 03 18:55:31 gartenradio sudo[1199]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:31 gartenradio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 03 18:55:31 gartenradio sudo[1194]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:31 gartenradio volumio[987]: info: Asound.conf file unchanged, so no further update is needed Jul 03 18:55:31 gartenradio volumio[987]: info: Output device has changed, restarting MPD Jul 03 18:55:31 gartenradio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 03 18:55:31 gartenradio systemd[1]: shairport-sync.service: Succeeded. Jul 03 18:55:31 gartenradio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 03 18:55:31 gartenradio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 03 18:55:31 gartenradio sudo[1196]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:31 gartenradio sudo[1199]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:31 gartenradio volumio[987]: info: Output device has changed, restarting Shairport Sync Jul 03 18:55:31 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:31 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 18:55:31 gartenradio sudo[1206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 03 18:55:31 gartenradio sudo[1206]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:31 gartenradio sudo[1209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 03 18:55:31 gartenradio sudo[1209]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:31 gartenradio sudo[1206]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:31 gartenradio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 03 18:55:31 gartenradio systemd[1]: mpd.service: Succeeded. Jul 03 18:55:31 gartenradio systemd[1]: Stopped Music Player Daemon. Jul 03 18:55:31 gartenradio go-librespot[1182]: time="2024-07-03T18:55:31+02:00" level=info msg="generated new device id: 046b6b050295cc922f598e58ca57e20879cd9f62" Jul 03 18:55:31 gartenradio go-librespot[1182]: time="2024-07-03T18:55:31+02:00" level=debug msg="stored credentials found for mluckau" Jul 03 18:55:31 gartenradio volumio[987]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 18:55:31 gartenradio volumio[987]: info: Shairport-Sync Started Jul 03 18:55:31 gartenradio volumio[987]: Error adding Membership: Error: addMembership EINVAL Jul 03 18:55:31 gartenradio systemd[1]: Starting Music Player Daemon... Jul 03 18:55:31 gartenradio volumio[987]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 03 18:55:31 gartenradio volumio[987]: SPOTIFY: BQAWnIWcYV3SdgyXCDre1V5mRoXLoKZ0TvOacpbXt-JNdipKjYjeMRNaOW-mnciySE2EwCLyUh0CRTb3WevKXFIfZQ9JCRVC_WYU3QQYQucWoj-k7SDem0dNeUq649FAgVvDbJwD309l2GBmlvXO7-L_m-7-LfLHCvDjLsQeI0Nn4aP_sdT3_gss51rDW5bF781MpxP_zGxBzU8Huy0y7NXP2sRgNkfmDjRyT5Y0gRqyJnw Jul 03 18:55:31 gartenradio volumio[987]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 03 18:55:31 gartenradio volumio[987]: info: New Spotify access token = BQAWnIWcYV3SdgyXCDre1V5mRoXLoKZ0TvOacpbXt-JNdipKjYjeMRNaOW-mnciySE2EwCLyUh0CRTb3WevKXFIfZQ9JCRVC_WYU3QQYQucWoj-k7SDem0dNeUq649FAgVvDbJwD309l2GBmlvXO7-L_m-7-LfLHCvDjLsQeI0Nn4aP_sdT3_gss51rDW5bF781MpxP_zGxBzU8Huy0y7NXP2sRgNkfmDjRyT5Y0gRqyJnw Jul 03 18:55:31 gartenradio volumio[987]: info: Spotify credentials grant success - running version from March 24, 2019 Jul 03 18:55:31 gartenradio volumio[987]: info: MPD Permissions set Jul 03 18:55:31 gartenradio volumio[987]: info: Shairport-Sync Started Jul 03 18:55:31 gartenradio volumio[987]: info: Shairport-Sync Started Jul 03 18:55:31 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:31 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:31 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:31 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:31 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:31 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:31 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:31 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:31 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 18:55:31 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:31 gartenradio sudo[1217]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 03 18:55:31 gartenradio sudo[1217]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:31 gartenradio sudo[1217]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jul 03 18:55:31 gartenradio sudo[1217]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:31 gartenradio volumio[987]: info: Starting Shairport Sync Jul 03 18:55:31 gartenradio sh[553]: timed out Jul 03 18:55:31 gartenradio sh[553]: dhcpcd exited Jul 03 18:55:31 gartenradio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jul 03 18:55:31 gartenradio dhcpcd[584]: timed out Jul 03 18:55:31 gartenradio sh[553]: ifup: failed to bring up eth0 Jul 03 18:55:31 gartenradio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jul 03 18:55:31 gartenradio sudo[1226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 03 18:55:31 gartenradio dhcpcd[584]: dhcpcd exited Jul 03 18:55:31 gartenradio sudo[1226]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:31 gartenradio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 03 18:55:31 gartenradio systemd[1]: shairport-sync.service: Succeeded. Jul 03 18:55:31 gartenradio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 03 18:55:31 gartenradio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 03 18:55:31 gartenradio sudo[1226]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:31 gartenradio volumio[987]: info: Shairport-Sync Started Jul 03 18:55:31 gartenradio volumio[987]: SPOTIFY: User informations: {"display_name":"Inkognito","external_urls":{"spotify":"https://open.spotify.com/user/mluckau"},"href":"https://api.spotify.com/v1/users/mluckau","id":"mluckau","images":[],"type":"user","uri":"spotify:user:mluckau","followers":{"href":null,"total":1},"country":"DE","product":"premium","explicit_content":{"filter_enabled":false,"filter_locked":false},"email":"mluckau@outlook.com"} Jul 03 18:55:31 gartenradio volumio[987]: info: Spotify Successfully logged in Jul 03 18:55:31 gartenradio volumio[987]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 18:55:31 gartenradio volumio[987]: info: [1720025731968] CoreMusicLibrary::Adding element Spotify Jul 03 18:55:31 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 18:55:31 gartenradio volumio[987]: Cannot find translation for source Spotify Jul 03 18:55:32 gartenradio sudo[1234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 03 18:55:32 gartenradio sudo[1234]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:32 gartenradio sudo[1234]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:32 gartenradio sudo[1236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 18:55:32 gartenradio sudo[1236]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:32 gartenradio sudo[1236]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:32 gartenradio go-librespot[1182]: time="2024-07-03T18:55:32+02:00" level=debug msg="obtained new client token: AACNNjxZc6C3rs8ACG86jYbA06atNK1dYPP2QQvHWZAiItRkMp2tOuX/D+Bk1VS2vX3xqz/Rcrp155s/MHOLuXa0r4uRpXFMxHeUm+jqOZBBkCXun+F0WBlTz1SP1p8409FVUH0lUAd3H6/CXkjtQkNsPZt2mEmCbdWoAXL4GTfyCF3Ufgbj30D/YbN/1UqZ14ewLUCJUUEsjhl2SX3kCzMeOeDb6rQ6RIP0uXyeXyvQUTx1uJC/Vw7DS15Lp6c=" Jul 03 18:55:32 gartenradio sudo[1240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 03 18:55:32 gartenradio sudo[1240]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:32 gartenradio volumio[987]: info: [now-playing] OpenWeatherMap API key obtained. Jul 03 18:55:32 gartenradio systemd[1]: Started UPnP Renderer front-end to MPD. Jul 03 18:55:32 gartenradio sudo[1240]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:32 gartenradio volumio[987]: info: Upmpdcli Daemon Started Jul 03 18:55:32 gartenradio go-librespot[1182]: time="2024-07-03T18:55:32+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 03 18:55:32 gartenradio volumio[987]: info: camilladsp spawned new process with pid 1249, instance 1, run: true Jul 03 18:55:32 gartenradio volumio[987]: info: camilladsp service started and running in background, instance 1 Jul 03 18:55:32 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 18:55:32 gartenradio go-librespot[1182]: time="2024-07-03T18:55:32+02:00" level=debug msg="completed keyexchange" Jul 03 18:55:32 gartenradio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jul 03 18:55:32 gartenradio volumio[987]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Jul 03 18:55:32 gartenradio volumio[987]: info: FusionDsp loaded Jul 03 18:55:32 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 18:55:32 gartenradio sudo[1253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 03 18:55:32 gartenradio sudo[1253]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:32 gartenradio volumio[987]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 03 18:55:32 gartenradio volumio[987]: info: Adding Signal Path Element [object Object] Jul 03 18:55:32 gartenradio volumio[987]: info: Adding fusiondspeq DSP Signal Path Element Jul 03 18:55:32 gartenradio volumio[987]: info: FusionDsp - ---- installed callbackRead Jul 03 18:55:32 gartenradio volumio[987]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 03 18:55:32 gartenradio volumio[987]: info: FusionDsp - Loudness is ON true Jul 03 18:55:32 gartenradio systemd[1]: Started FusionDsp Daemon. Jul 03 18:55:32 gartenradio sudo[1253]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:32 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 18:55:32 gartenradio volumio[987]: info: Discovery: Getting this device information Jul 03 18:55:32 gartenradio volumio[987]: info: CoreCommandRouter::volumioGetState Jul 03 18:55:32 gartenradio volumio[987]: info: CorePlayQueue::getTrack 0 Jul 03 18:55:32 gartenradio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 18:55:32 gartenradio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jul 03 18:55:32 gartenradio volumio[987]: error: FusionDsp - WebSocket error: [object Object] Jul 03 18:55:32 gartenradio volumio[987]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jul 03 18:55:33 gartenradio go-librespot[1182]: time="2024-07-03T18:55:33+02:00" level=debug msg="completed challenge" Jul 03 18:55:33 gartenradio go-librespot[1182]: time="2024-07-03T18:55:33+02:00" level=debug msg="authenticated as mluckau" Jul 03 18:55:33 gartenradio go-librespot[1182]: time="2024-07-03T18:55:33+02:00" level=debug msg="authenticated as mluckau" Jul 03 18:55:33 gartenradio go-librespot[1182]: time="2024-07-03T18:55:33+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jul 03 18:55:33 gartenradio go-librespot[1182]: time="2024-07-03T18:55:33+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jul 03 18:55:33 gartenradio go-librespot[1182]: time="2024-07-03T18:55:33+02:00" level=debug msg="dealer connection opened" Jul 03 18:55:33 gartenradio go-librespot[1182]: time="2024-07-03T18:55:33+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 03 18:55:33 gartenradio go-librespot[1182]: time="2024-07-03T18:55:33+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 03 18:55:33 gartenradio go-librespot[1182]: time="2024-07-03T18:55:33+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 03 18:55:33 gartenradio go-librespot[1182]: time="2024-07-03T18:55:33+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 03 18:55:33 gartenradio go-librespot[1182]: time="2024-07-03T18:55:33+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513" Jul 03 18:55:33 gartenradio go-librespot[1182]: time="2024-07-03T18:55:33+02:00" level=debug msg="received connection id: MjRmZGY1YmMtNjQ4Ny00MTgzLTg2MmEtZGFkMWE5NjU5MjFjK2RlYWxlcit0Y3A6Ly9nZXc0LWRlYWxlci1hLXFndDIuZ2V3NC5zcG90aWZ5Lm5ldDo1NzAwKzFCMDJCNzQ0QjMxNDIzMjk2NDdGNDdCRTQwQUFCMDZCOTU3REVFQ0UwQUIzQTgzOTE5MkM1Qjc4REM1QUE0OTU=" Jul 03 18:55:34 gartenradio go-librespot[1182]: time="2024-07-03T18:55:34+02:00" level=debug msg="put connect state because NEW_DEVICE" Jul 03 18:55:34 gartenradio volumio[987]: info: go-librespot daemon successfully initialized Jul 03 18:55:34 gartenradio systemd[1]: systemd-hostnamed.service: Succeeded. Jul 03 18:55:34 gartenradio mpd[1224]: Jul 03 18:55 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 03 18:55:34 gartenradio volumio[987]: info: CoreCommandRouter::volumioGetState Jul 03 18:55:34 gartenradio volumio[987]: info: CorePlayQueue::getTrack 0 Jul 03 18:55:35 gartenradio systemd[1]: Started Music Player Daemon. Jul 03 18:55:35 gartenradio sudo[1149]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:35 gartenradio volumio[987]: Upnp client error: Error: This socket has been ended by the other party Jul 03 18:55:35 gartenradio sudo[1139]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:35 gartenradio sudo[1209]: pam_unix(sudo:session): session closed for user root Jul 03 18:55:35 gartenradio volumio[987]: info: Completed starting Core Plugins Jul 03 18:55:35 gartenradio volumio[987]: info: ------------------------------------------- Jul 03 18:55:35 gartenradio volumio[987]: info: ----- MyVolumio plugins startup ---- Jul 03 18:55:35 gartenradio volumio[987]: info: ------------------------------------------- Jul 03 18:55:35 gartenradio volumio[987]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 03 18:55:35 gartenradio volumio[987]: error: MPD error: The expression evaluated to a falsy value: Jul 03 18:55:35 gartenradio volumio[987]: assert.ok(self.idling) Jul 03 18:55:35 gartenradio volumio[987]: error: The expression evaluated to a falsy value: Jul 03 18:55:35 gartenradio volumio[987]: assert.ok(self.idling) Jul 03 18:55:35 gartenradio volumio[987]: error: MPD error: The expression evaluated to a falsy value: Jul 03 18:55:35 gartenradio volumio[987]: assert.ok(self.idling) Jul 03 18:55:35 gartenradio volumio[987]: error: The expression evaluated to a falsy value: Jul 03 18:55:35 gartenradio volumio[987]: assert.ok(self.idling) Jul 03 18:55:35 gartenradio volumio[987]: info: MPD running with PID1224 Jul 03 18:55:35 gartenradio volumio[987]: ,establishing connection Jul 03 18:55:35 gartenradio volumio[987]: error: MPD error: The expression evaluated to a falsy value: Jul 03 18:55:35 gartenradio volumio[987]: assert.ok(self.idling) Jul 03 18:55:35 gartenradio volumio[987]: error: The expression evaluated to a falsy value: Jul 03 18:55:35 gartenradio volumio[987]: assert.ok(self.idling) Jul 03 18:55:35 gartenradio volumio[987]: error: updateQueue error: null Jul 03 18:55:36 gartenradio volumio[1242]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 03 18:55:37 gartenradio volumio[987]: info: Initializing connection to go-librespot Websocket Jul 03 18:55:37 gartenradio go-librespot[1182]: time="2024-07-03T18:55:37+02:00" level=debug msg="new websocket client" Jul 03 18:55:37 gartenradio volumio[987]: info: Connection to go-librespot Websocket established Jul 03 18:55:37 gartenradio go-librespot[1182]: time="2024-07-03T18:55:37+02:00" level=debug msg="handling transfer player command from d04f53a2f81d078d04b41d7ae319bbe65a5893d3" Jul 03 18:55:38 gartenradio go-librespot[1182]: time="2024-07-03T18:55:38+02:00" level=trace msg="fetched new page 0 with 426 items (list: 426)" Jul 03 18:55:38 gartenradio go-librespot[1182]: time="2024-07-03T18:55:38+02:00" level=debug msg="loading track spotify:track:4rGKCr6pj3tKNWJvICbOaZ (paused: false, position: -160282407ms)" Jul 03 18:55:38 gartenradio go-librespot[1182]: time="2024-07-03T18:55:38+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 03 18:55:38 gartenradio go-librespot[1182]: time="2024-07-03T18:55:38+02:00" level=trace msg="emitting websocket event: will_play" Jul 03 18:55:38 gartenradio volumio[987]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4rGKCr6pj3tKNWJvICbOaZ","play_origin":"collection-songs"}} Jul 03 18:55:38 gartenradio go-librespot[1182]: time="2024-07-03T18:55:38+02:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:4rGKCr6pj3tKNWJvICbOaZ" Jul 03 18:55:38 gartenradio go-librespot[1182]: time="2024-07-03T18:55:38+02:00" level=debug msg="requested aes key for file 8906ba64257e115e718412bb3625c0830abe4170, gid: 4rGKCr6pj3tKNWJvICbOaZ" Jul 03 18:55:38 gartenradio go-librespot[1182]: time="2024-07-03T18:55:38+02:00" level=debug msg="fetched first chunk of 16, total size is 7874140 bytes" Jul 03 18:55:38 gartenradio go-librespot[1182]: time="2024-07-03T18:55:38+02:00" level=trace msg="seek to -160282407ms (diff: -160282407ms, samples: -7068454148, bytes: 0)" Jul 03 18:55:38 gartenradio volumio[987]: info: FusionDsp - ---- read samplerate, raw: Jul 03 18:55:38 gartenradio volumio[987]: error: FusionDsp - invalid sample rate Jul 03 18:55:38 gartenradio volumio[987]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 03 18:55:38 gartenradio volumio[987]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 03 18:55:38 gartenradio volumio[987]: info: camilladsp stopping service pid 1249... Jul 03 18:55:38 gartenradio go-librespot[1182]: time="2024-07-03T18:55:38+02:00" level=info msg="loaded track \"Moment\" (uri: spotify:track:4rGKCr6pj3tKNWJvICbOaZ, paused: false, position: -160282407ms, duration: 204000ms)" Jul 03 18:55:38 gartenradio volumio[987]: info: camilladsp service terminated, instance 1 Jul 03 18:55:38 gartenradio volumio[987]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 03 18:55:38 gartenradio volumio[987]: info: FusionDsp - Loudness is ON true Jul 03 18:55:38 gartenradio volumio[987]: info: camilladsp service started and running in background, instance 1 Jul 03 18:55:39 gartenradio volumio[987]: info: camilladsp spawned new process with pid 1288, instance 1, run: true Jul 03 18:55:39 gartenradio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jul 03 18:55:39 gartenradio go-librespot[1182]: time="2024-07-03T18:55:39+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 03 18:55:39 gartenradio go-librespot[1182]: time="2024-07-03T18:55:39+02:00" level=trace msg="emitting websocket event: metadata" Jul 03 18:55:39 gartenradio go-librespot[1182]: time="2024-07-03T18:55:39+02:00" level=trace msg="emitting websocket event: active" Jul 03 18:55:39 gartenradio go-librespot[1182]: time="2024-07-03T18:55:39+02:00" level=debug msg="sending successful reply for delaer request" Jul 03 18:55:39 gartenradio volumio[987]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4rGKCr6pj3tKNWJvICbOaZ","name":"Moment","artist_names":["Nicolas Binder"],"album_name":"Moment","album_cover_url":"https://i.scdn.co/image/ab67616d00001e029f473b8e106b9467947ef10e","position":-160282407,"duration":204000,"release_date":"year:2021 month:5 day:16","track_number":1,"disc_number":1}} Jul 03 18:55:39 gartenradio volumio[987]: SPOTIFY: received: {"type":"active","data":null} Jul 03 18:55:39 gartenradio volumio[987]: info: Aligning Spotify Volume to Volumio Volume Jul 03 18:55:39 gartenradio volumio[987]: info: CoreCommandRouter::volumioGetState Jul 03 18:55:39 gartenradio volumio[987]: info: CorePlayQueue::getTrack 0 Jul 03 18:55:39 gartenradio volumio[987]: info: Setting Spotify Volume from Volumio: 53 Jul 03 18:55:39 gartenradio go-librespot[1182]: time="2024-07-03T18:55:39+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 03 18:55:39 gartenradio go-librespot[1182]: time="2024-07-03T18:55:39+02:00" level=trace msg="emitting websocket event: playing" Jul 03 18:55:39 gartenradio volumio[987]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:4rGKCr6pj3tKNWJvICbOaZ","play_origin":"collection-songs"}} Jul 03 18:55:39 gartenradio volumio[987]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 18:55:39 gartenradio volumio[987]: TypeError: Cannot read property 'service' of undefined Jul 03 18:55:39 gartenradio volumio[987]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:336:50) Jul 03 18:55:39 gartenradio volumio[987]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:262:18) Jul 03 18:55:39 gartenradio volumio[987]: at WebSocket.message (/data/plugins/music_service/spop/index.js:189:14) Jul 03 18:55:39 gartenradio volumio[987]: at WebSocket.emit (events.js:315:20) Jul 03 18:55:39 gartenradio volumio[987]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1209:20) Jul 03 18:55:39 gartenradio volumio[987]: at Receiver.emit (events.js:315:20) Jul 03 18:55:39 gartenradio volumio[987]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:594:14) Jul 03 18:55:39 gartenradio volumio[987]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Jul 03 18:55:39 gartenradio volumio[987]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Jul 03 18:55:39 gartenradio volumio[987]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Jul 03 18:55:39 gartenradio volumio[987]: at writeOrBuffer (internal/streams/writable.js:358:12) Jul 03 18:55:39 gartenradio volumio[987]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Jul 03 18:55:39 gartenradio volumio[987]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1303:35) Jul 03 18:55:39 gartenradio volumio[987]: at Socket.emit (events.js:315:20) Jul 03 18:55:39 gartenradio volumio[987]: at addChunk (internal/streams/readable.js:309:12) Jul 03 18:55:39 gartenradio volumio[987]: at readableAddChunk (internal/streams/readable.js:284:9) Jul 03 18:55:39 gartenradio volumio[987]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 18:55:39 gartenradio go-librespot[1182]: time="2024-07-03T18:55:39+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jul 03 18:55:39 gartenradio go-librespot[1182]: time="2024-07-03T18:55:39+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Jul 03 18:55:39 gartenradio go-librespot[1182]: time="2024-07-03T18:55:39+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1597" Jul 03 18:55:39 gartenradio go-librespot[1182]: time="2024-07-03T18:55:39+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 03 18:55:39 gartenradio go-librespot[1182]: time="2024-07-03T18:55:39+02:00" level=debug msg="fetched chunk 1/15, size: 524288" Jul 03 18:55:39 gartenradio go-librespot[1182]: time="2024-07-03T18:55:39+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 03 18:55:39 gartenradio go-librespot[1182]: time="2024-07-03T18:55:39+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1375" Jul 03 18:55:39 gartenradio go-librespot[1182]: time="2024-07-03T18:55:39+02:00" level=debug msg="fetched chunk 3/15, size: 524288" Jul 03 18:55:39 gartenradio sudo[1304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-03 18:54 Jul 03 18:55:39 gartenradio sudo[1304]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 18:55:39 gartenradio go-librespot[1182]: time="2024-07-03T18:55:39+02:00" level=debug msg="fetched chunk 2/15, size: 524288" 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"