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