-- 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"