-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2025-04-20 11:40:11 CEST. -- Apr 20 11:39:27 minidsp-shd kernel: ohci-platform 1c1a400.usb: new USB bus registered, assigned bus number 2 Apr 20 11:39:27 minidsp-shd kernel: ohci-platform 1c1a400.usb: irq 13, io mem 0x01c1a400 Apr 20 11:39:27 minidsp-shd kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0001 Apr 20 11:39:27 minidsp-shd kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Apr 20 11:39:27 minidsp-shd kernel: usb usb2: Product: Generic Platform OHCI controller Apr 20 11:39:27 minidsp-shd kernel: usb usb2: Manufacturer: Linux 4.14.0 ohci_hcd Apr 20 11:39:27 minidsp-shd kernel: usb usb2: SerialNumber: 1c1a400.usb Apr 20 11:39:27 minidsp-shd kernel: hub 2-0:1.0: USB hub found Apr 20 11:39:27 minidsp-shd kernel: hub 2-0:1.0: 1 port detected Apr 20 11:39:27 minidsp-shd kernel: usbcore: registered new interface driver usb-storage Apr 20 11:39:27 minidsp-shd kernel: mousedev: PS/2 mouse device common for all mice Apr 20 11:39:27 minidsp-shd kernel: sun6i-rtc 1f00000.rtc: rtc core: registered rtc-sun6i as rtc0 Apr 20 11:39:27 minidsp-shd kernel: sun6i-rtc 1f00000.rtc: RTC enabled Apr 20 11:39:27 minidsp-shd kernel: i2c /dev entries driver Apr 20 11:39:27 minidsp-shd kernel: sunxi-wdt 1c20ca0.watchdog: Watchdog enabled (timeout=16 sec, nowayout=0) Apr 20 11:39:27 minidsp-shd kernel: sdhci: Secure Digital Host Controller Interface driver Apr 20 11:39:27 minidsp-shd kernel: sdhci: Copyright(c) Pierre Ossman Apr 20 11:39:27 minidsp-shd kernel: Synopsys Designware Multimedia Card Interface Driver Apr 20 11:39:27 minidsp-shd kernel: sunxi-mmc 1c0f000.mmc: base:0xffff000008ecd000 irq:10 Apr 20 11:39:27 minidsp-shd kernel: sdhci-pltfm: SDHCI platform and OF driver helper Apr 20 11:39:27 minidsp-shd kernel: ledtrig-cpu: registered to indicate activity on CPUs Apr 20 11:39:27 minidsp-shd kernel: hidraw: raw HID events driver (C) Jiri Kosina Apr 20 11:39:27 minidsp-shd kernel: usbcore: registered new interface driver usbhid Apr 20 11:39:27 minidsp-shd kernel: usbhid: USB HID core driver Apr 20 11:39:27 minidsp-shd kernel: ip_tables: (C) 2000-2006 Netfilter Core Team Apr 20 11:39:27 minidsp-shd kernel: NET: Registered protocol family 10 Apr 20 11:39:27 minidsp-shd kernel: Segment Routing with IPv6 Apr 20 11:39:27 minidsp-shd kernel: NET: Registered protocol family 17 Apr 20 11:39:27 minidsp-shd kernel: 8021q: 802.1Q VLAN Support v1.8 Apr 20 11:39:27 minidsp-shd kernel: 9pnet: Installing 9P2000 support Apr 20 11:39:27 minidsp-shd kernel: Key type dns_resolver registered Apr 20 11:39:27 minidsp-shd kernel: registered taskstats version 1 Apr 20 11:39:27 minidsp-shd kernel: zswap: loaded using pool lzo/zbud Apr 20 11:39:27 minidsp-shd kernel: Btrfs loaded, crc32c=crc32c-arm64-ce Apr 20 11:39:27 minidsp-shd kernel: mmc0: host does not support reading read-only switch, assuming write-enable Apr 20 11:39:27 minidsp-shd kernel: Key type encrypted registered Apr 20 11:39:27 minidsp-shd kernel: dwmac-sun8i 1c30000.ethernet: PTP uses main clock Apr 20 11:39:27 minidsp-shd kernel: dwmac-sun8i 1c30000.ethernet: Will use external PHY Apr 20 11:39:27 minidsp-shd kernel: mmc0: new high speed SDHC card at address aaaa Apr 20 11:39:27 minidsp-shd kernel: mmcblk0: mmc0:aaaa SB16G 14.8 GiB Apr 20 11:39:27 minidsp-shd kernel: mmcblk0: p1 p2 p3 Apr 20 11:39:27 minidsp-shd kernel: dwmac-sun8i 1c30000.ethernet: Current syscon value is not the default 50006 (expect 58000) Apr 20 11:39:27 minidsp-shd kernel: dwmac-sun8i 1c30000.ethernet: Chain mode enabled Apr 20 11:39:27 minidsp-shd kernel: dwmac-sun8i 1c30000.ethernet: No HW DMA feature register supported Apr 20 11:39:27 minidsp-shd kernel: dwmac-sun8i 1c30000.ethernet: Normal descriptors Apr 20 11:39:27 minidsp-shd kernel: dwmac-sun8i 1c30000.ethernet: RX Checksum Offload Engine supported Apr 20 11:39:27 minidsp-shd kernel: dwmac-sun8i 1c30000.ethernet: COE Type 2 Apr 20 11:39:27 minidsp-shd kernel: dwmac-sun8i 1c30000.ethernet: TX Checksum insertion supported Apr 20 11:39:27 minidsp-shd kernel: libphy: stmmac: probed Apr 20 11:39:27 minidsp-shd kernel: ehci-platform 1c1b000.usb: EHCI Host Controller Apr 20 11:39:27 minidsp-shd kernel: ehci-platform 1c1b000.usb: new USB bus registered, assigned bus number 3 Apr 20 11:39:27 minidsp-shd kernel: ehci-platform 1c1b000.usb: irq 14, io mem 0x01c1b000 Apr 20 11:39:27 minidsp-shd kernel: ehci-platform 1c1b000.usb: USB 2.0 started, EHCI 1.00 Apr 20 11:39:27 minidsp-shd kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0002 Apr 20 11:39:27 minidsp-shd kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Apr 20 11:39:27 minidsp-shd kernel: usb usb3: Product: EHCI Host Controller Apr 20 11:39:27 minidsp-shd kernel: usb usb3: Manufacturer: Linux 4.14.0 ehci_hcd Apr 20 11:39:27 minidsp-shd kernel: usb usb3: SerialNumber: 1c1b000.usb Apr 20 11:39:27 minidsp-shd kernel: hub 3-0:1.0: USB hub found Apr 20 11:39:27 minidsp-shd kernel: hub 3-0:1.0: 1 port detected Apr 20 11:39:27 minidsp-shd kernel: ehci-platform 1c1c000.usb: EHCI Host Controller Apr 20 11:39:27 minidsp-shd kernel: ehci-platform 1c1c000.usb: new USB bus registered, assigned bus number 4 Apr 20 11:39:27 minidsp-shd kernel: ehci-platform 1c1c000.usb: irq 16, io mem 0x01c1c000 Apr 20 11:39:27 minidsp-shd kernel: ehci-platform 1c1c000.usb: USB 2.0 started, EHCI 1.00 Apr 20 11:39:27 minidsp-shd kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0002 Apr 20 11:39:27 minidsp-shd kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Apr 20 11:39:27 minidsp-shd kernel: usb usb4: Product: EHCI Host Controller Apr 20 11:39:27 minidsp-shd kernel: usb usb4: Manufacturer: Linux 4.14.0 ehci_hcd Apr 20 11:39:27 minidsp-shd kernel: usb usb4: SerialNumber: 1c1c000.usb Apr 20 11:39:27 minidsp-shd kernel: hub 4-0:1.0: USB hub found Apr 20 11:39:27 minidsp-shd kernel: hub 4-0:1.0: 1 port detected Apr 20 11:39:27 minidsp-shd kernel: ehci-platform 1c1d000.usb: EHCI Host Controller Apr 20 11:39:27 minidsp-shd kernel: ehci-platform 1c1d000.usb: new USB bus registered, assigned bus number 5 Apr 20 11:39:27 minidsp-shd kernel: ehci-platform 1c1d000.usb: irq 18, io mem 0x01c1d000 Apr 20 11:39:27 minidsp-shd kernel: ehci-platform 1c1d000.usb: USB 2.0 started, EHCI 1.00 Apr 20 11:39:27 minidsp-shd kernel: usb usb5: New USB device found, idVendor=1d6b, idProduct=0002 Apr 20 11:39:27 minidsp-shd kernel: usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Apr 20 11:39:27 minidsp-shd kernel: usb usb5: Product: EHCI Host Controller Apr 20 11:39:27 minidsp-shd kernel: usb usb5: Manufacturer: Linux 4.14.0 ehci_hcd Apr 20 11:39:27 minidsp-shd kernel: usb usb5: SerialNumber: 1c1d000.usb Apr 20 11:39:27 minidsp-shd kernel: hub 5-0:1.0: USB hub found Apr 20 11:39:27 minidsp-shd kernel: hub 5-0:1.0: 1 port detected Apr 20 11:39:27 minidsp-shd kernel: ohci-platform 1c1b400.usb: Generic Platform OHCI controller Apr 20 11:39:27 minidsp-shd kernel: ohci-platform 1c1b400.usb: new USB bus registered, assigned bus number 6 Apr 20 11:39:27 minidsp-shd kernel: ohci-platform 1c1b400.usb: irq 15, io mem 0x01c1b400 Apr 20 11:39:27 minidsp-shd kernel: usb usb6: New USB device found, idVendor=1d6b, idProduct=0001 Apr 20 11:39:27 minidsp-shd kernel: usb usb6: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Apr 20 11:39:27 minidsp-shd kernel: usb usb6: Product: Generic Platform OHCI controller Apr 20 11:39:27 minidsp-shd kernel: usb usb6: Manufacturer: Linux 4.14.0 ohci_hcd Apr 20 11:39:27 minidsp-shd kernel: usb usb6: SerialNumber: 1c1b400.usb Apr 20 11:39:27 minidsp-shd kernel: hub 6-0:1.0: USB hub found Apr 20 11:39:27 minidsp-shd kernel: hub 6-0:1.0: 1 port detected Apr 20 11:39:27 minidsp-shd kernel: ohci-platform 1c1c400.usb: Generic Platform OHCI controller Apr 20 11:39:27 minidsp-shd kernel: ohci-platform 1c1c400.usb: new USB bus registered, assigned bus number 7 Apr 20 11:39:27 minidsp-shd kernel: ohci-platform 1c1c400.usb: irq 17, io mem 0x01c1c400 Apr 20 11:39:27 minidsp-shd kernel: usb usb7: New USB device found, idVendor=1d6b, idProduct=0001 Apr 20 11:39:27 minidsp-shd kernel: usb usb7: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Apr 20 11:39:27 minidsp-shd kernel: usb usb7: Product: Generic Platform OHCI controller Apr 20 11:39:27 minidsp-shd kernel: usb usb7: Manufacturer: Linux 4.14.0 ohci_hcd Apr 20 11:39:27 minidsp-shd kernel: usb usb7: SerialNumber: 1c1c400.usb Apr 20 11:39:27 minidsp-shd kernel: hub 7-0:1.0: USB hub found Apr 20 11:39:27 minidsp-shd kernel: hub 7-0:1.0: 1 port detected Apr 20 11:39:27 minidsp-shd kernel: ohci-platform 1c1d400.usb: Generic Platform OHCI controller Apr 20 11:39:27 minidsp-shd kernel: ohci-platform 1c1d400.usb: new USB bus registered, assigned bus number 8 Apr 20 11:39:27 minidsp-shd kernel: ohci-platform 1c1d400.usb: irq 19, io mem 0x01c1d400 Apr 20 11:39:27 minidsp-shd kernel: usb usb8: New USB device found, idVendor=1d6b, idProduct=0001 Apr 20 11:39:27 minidsp-shd kernel: usb usb8: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Apr 20 11:39:27 minidsp-shd kernel: usb usb8: Product: Generic Platform OHCI controller Apr 20 11:39:27 minidsp-shd kernel: usb usb8: Manufacturer: Linux 4.14.0 ohci_hcd Apr 20 11:39:27 minidsp-shd kernel: usb usb8: SerialNumber: 1c1d400.usb Apr 20 11:39:27 minidsp-shd kernel: hub 8-0:1.0: USB hub found Apr 20 11:39:27 minidsp-shd kernel: hub 8-0:1.0: 1 port detected Apr 20 11:39:27 minidsp-shd kernel: input: r-gpio-keys as /devices/platform/r-gpio-keys/input/input0 Apr 20 11:39:27 minidsp-shd kernel: usb0-vbus: disabling Apr 20 11:39:27 minidsp-shd kernel: ALSA device list: Apr 20 11:39:27 minidsp-shd kernel: No soundcards found. Apr 20 11:39:27 minidsp-shd kernel: Freeing unused kernel memory: 448K Apr 20 11:39:27 minidsp-shd initramfs: Booting Volumio for nanopi-neo2 Apr 20 11:39:27 minidsp-shd initramfs: This script mounts rootfs RO with an overlay RW layer. Apr 20 11:39:27 minidsp-shd kernel: fuse init (API version 7.26) Apr 20 11:39:27 minidsp-shd initramfs: IMGPART=/dev/mmcblk0p2 Apr 20 11:39:27 minidsp-shd initramfs: IMGFILE=/volumio_current.sqsh Apr 20 11:39:27 minidsp-shd initramfs: DATAPART=/dev/mmcblk0p3 Apr 20 11:39:27 minidsp-shd initramfs: BOOTPART=/dev/mmcblk0p1 Apr 20 11:39:27 minidsp-shd initramfs: BOOTCONFIG=empty Apr 20 11:39:27 minidsp-shd initramfs: BOOTDELAY=5 Apr 20 11:39:27 minidsp-shd kernel: EXT4-fs (mmcblk0p2): recovery complete Apr 20 11:39:27 minidsp-shd kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Apr 20 11:39:27 minidsp-shd initramfs: Checking for USB updates if you did not boot from USB... Apr 20 11:39:27 minidsp-shd initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Apr 20 11:39:27 minidsp-shd kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Apr 20 11:39:27 minidsp-shd kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Apr 20 11:39:27 minidsp-shd kernel: EXT4-fs (mmcblk0p3): recovery complete Apr 20 11:39:27 minidsp-shd kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Apr 20 11:39:27 minidsp-shd initramfs: With Option: Apr 20 11:39:27 minidsp-shd initramfs: found fstab entry with device /dev/mmcblk0p1 for /boot Apr 20 11:39:27 minidsp-shd initramfs: VOLUMIO_VERSION="3.608" Apr 20 11:39:27 minidsp-shd initramfs: Finish initramfs, continue booting Volumio Apr 20 11:39:27 minidsp-shd systemd[1]: System time before build time, advancing clock. Apr 20 11:39:27 minidsp-shd 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) Apr 20 11:39:27 minidsp-shd systemd[1]: Detected architecture arm64. Apr 20 11:39:27 minidsp-shd systemd[1]: Set hostname to . Apr 20 11:39:27 minidsp-shd systemd[1]: File /lib/systemd/system/systemd-journald.service:12 configures an IP firewall (IPAddressDeny=any), but the local system does not support BPF/cgroup based firewalling. Apr 20 11:39:27 minidsp-shd systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.) Apr 20 11:39:27 minidsp-shd 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. Apr 20 11:39:27 minidsp-shd systemd[1]: Listening on initctl Compatibility Named Pipe. Apr 20 11:39:27 minidsp-shd systemd[1]: Created slice system-getty.slice. Apr 20 11:39:27 minidsp-shd systemd[1]: Listening on fsck to fsckd communication Socket. Apr 20 11:39:27 minidsp-shd systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Apr 20 11:39:27 minidsp-shd systemd-journald[293]: Journal started Apr 20 11:39:27 minidsp-shd systemd-journald[293]: Runtime journal (/run/log/journal/db6dce07be25c6dca1f861d565390872) is 7.5M, max 30.0M, 22.5M free. Apr 20 11:39:27 minidsp-shd fake-hwclock[288]: Sun Apr 20 09:39:27 UTC 2025 Apr 20 11:39:27 minidsp-shd systemd[1]: Mounted Kernel Debug File System. Apr 20 11:39:27 minidsp-shd systemd[1]: Started Load Kernel Modules. Apr 20 11:39:27 minidsp-shd systemd[1]: dynamicswap.service: Succeeded. Apr 20 11:39:27 minidsp-shd systemd[1]: Mounted POSIX Message Queue File System. Apr 20 11:39:27 minidsp-shd systemd[1]: Mounted Huge Pages File System. Apr 20 11:39:27 minidsp-shd systemd[1]: Started Remount Root and Kernel File Systems. Apr 20 11:39:27 minidsp-shd systemd[1]: Mounted RPC Pipe File System. Apr 20 11:39:27 minidsp-shd systemd[1]: Starting Create System Users... Apr 20 11:39:27 minidsp-shd systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Apr 20 11:39:27 minidsp-shd systemd[1]: Starting Load/Save Random Seed... Apr 20 11:39:27 minidsp-shd systemd[1]: Mounting FUSE Control File System... Apr 20 11:39:27 minidsp-shd systemd[1]: Mounting Kernel Configuration File System... Apr 20 11:39:27 minidsp-shd systemd[1]: Starting Apply Kernel Variables... Apr 20 11:39:27 minidsp-shd systemd[1]: Started Load/Save Random Seed. Apr 20 11:39:27 minidsp-shd systemd[1]: Started Create System Users. Apr 20 11:39:27 minidsp-shd systemd[1]: Mounted FUSE Control File System. Apr 20 11:39:27 minidsp-shd kernel: Removed cp15_barrier emulation handler Apr 20 11:39:27 minidsp-shd kernel: Enabled cp15_barrier support Apr 20 11:39:27 minidsp-shd systemd[1]: Mounted Kernel Configuration File System. Apr 20 11:39:27 minidsp-shd systemd[1]: Started udev Coldplug all Devices. Apr 20 11:39:27 minidsp-shd systemd[1]: Started Apply Kernel Variables. Apr 20 11:39:27 minidsp-shd systemd[1]: Starting Helper to synchronize boot up for ifupdown... Apr 20 11:39:27 minidsp-shd systemd[1]: Starting Create Static Device Nodes in /dev... Apr 20 11:39:27 minidsp-shd systemd[1]: Started Create Static Device Nodes in /dev. Apr 20 11:39:27 minidsp-shd systemd[1]: Starting udev Kernel Device Manager... Apr 20 11:39:27 minidsp-shd systemd[1]: Reached target Local File Systems (Pre). Apr 20 11:39:27 minidsp-shd systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Apr 20 11:39:27 minidsp-shd systemd[1]: Mounting /var/log... Apr 20 11:39:27 minidsp-shd systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Apr 20 11:39:27 minidsp-shd systemd[1]: Mounting /tmp... Apr 20 11:39:27 minidsp-shd systemd-udevd[321]: Network interface NamePolicy= disabled on kernel command line, ignoring. Apr 20 11:39:27 minidsp-shd systemd[1]: Mounting /var/spool/cups... Apr 20 11:39:27 minidsp-shd systemd[1]: Mounted /var/log. Apr 20 11:39:27 minidsp-shd systemd[1]: Started udev Kernel Device Manager. Apr 20 11:39:27 minidsp-shd systemd[1]: Mounted /tmp. Apr 20 11:39:27 minidsp-shd systemd[1]: Mounted /var/spool/cups. Apr 20 11:39:27 minidsp-shd systemd[1]: Mounting /var/spool/cups/tmp... Apr 20 11:39:27 minidsp-shd systemd[1]: Starting Show Plymouth Boot Screen... Apr 20 11:39:27 minidsp-shd systemd[1]: Starting Flush Journal to Persistent Storage... Apr 20 11:39:27 minidsp-shd systemd[1]: Mounted /var/spool/cups/tmp. Apr 20 11:39:27 minidsp-shd systemd[1]: Received SIGRTMIN+20 from PID 340 (plymouthd). Apr 20 11:39:27 minidsp-shd systemd-journald[293]: Runtime journal (/run/log/journal/db6dce07be25c6dca1f861d565390872) is 7.5M, max 30.0M, 22.5M free. Apr 20 11:39:27 minidsp-shd systemd[1]: Started Show Plymouth Boot Screen. Apr 20 11:39:27 minidsp-shd systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Apr 20 11:39:27 minidsp-shd systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Apr 20 11:39:27 minidsp-shd systemd[1]: Reached target Paths. Apr 20 11:39:27 minidsp-shd systemd[1]: Reached target Local Encrypted Volumes. Apr 20 11:39:27 minidsp-shd systemd[1]: Started Flush Journal to Persistent Storage. Apr 20 11:39:27 minidsp-shd kernel: usb_phy_generic usb_phy_generic.0.auto: usb_phy_generic.0.auto supply vcc not found, using dummy regulator Apr 20 11:39:27 minidsp-shd kernel: musb-hdrc musb-hdrc.1.auto: MUSB HDRC host driver Apr 20 11:39:27 minidsp-shd kernel: musb-hdrc musb-hdrc.1.auto: new USB bus registered, assigned bus number 9 Apr 20 11:39:27 minidsp-shd kernel: usb usb9: New USB device found, idVendor=1d6b, idProduct=0002 Apr 20 11:39:27 minidsp-shd kernel: usb usb9: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Apr 20 11:39:27 minidsp-shd kernel: usb usb9: Product: MUSB HDRC host driver Apr 20 11:39:27 minidsp-shd kernel: usb usb9: Manufacturer: Linux 4.14.0 musb-hcd Apr 20 11:39:27 minidsp-shd kernel: usb usb9: SerialNumber: musb-hdrc.1.auto Apr 20 11:39:27 minidsp-shd kernel: hub 9-0:1.0: USB hub found Apr 20 11:39:27 minidsp-shd kernel: hub 9-0:1.0: 1 port detected Apr 20 11:39:27 minidsp-shd kernel: asoc-simple-card sound_i2s: pcm5102a-hifi <-> 1c22000.i2s mapping ok Apr 20 11:39:28 minidsp-shd systemd-udevd[332]: Using default interface naming scheme 'v240'. Apr 20 11:39:28 minidsp-shd systemd[1]: Found device /dev/ttyS0. Apr 20 11:39:28 minidsp-shd systemd-udevd[332]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 20 11:39:28 minidsp-shd systemd-udevd[332]: link_config: could not get ethtool features for eth0 Apr 20 11:39:28 minidsp-shd systemd-udevd[332]: Could not set offload features of eth0: Device or resource busy Apr 20 11:39:28 minidsp-shd systemd-udevd[328]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 20 11:39:28 minidsp-shd kernel: [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). Apr 20 11:39:28 minidsp-shd kernel: [drm] No driver support for vblank timestamp query. Apr 20 11:39:28 minidsp-shd kernel: sun4i-drm display-engine: bound 1100000.mixer (ops sun8i_mixer_platform_driver_exit [sun8i_mixer]) Apr 20 11:39:28 minidsp-shd kernel: sun4i-drm display-engine: No panel or bridge found... RGB output disabled Apr 20 11:39:28 minidsp-shd kernel: sun4i-drm display-engine: bound 1c0c000.lcd-controller (ops sun4i_tcon_platform_driver_exit [sun4i_tcon]) Apr 20 11:39:28 minidsp-shd systemd-udevd[335]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 20 11:39:28 minidsp-shd kernel: sun8i-dw-hdmi 1ee0000.hdmi: Detected HDMI TX controller v1.32a with HDCP (sun8i_dw_hdmi_phy) Apr 20 11:39:28 minidsp-shd kernel: sun8i-dw-hdmi 1ee0000.hdmi: registered DesignWare HDMI I2C bus driver Apr 20 11:39:28 minidsp-shd kernel: sun4i-drm display-engine: bound 1ee0000.hdmi (ops sun8i_dw_hdmi_ops [sun8i_dw_hdmi]) Apr 20 11:39:28 minidsp-shd kernel: [drm] Cannot find any crtc or sizes - going 1920x1080 Apr 20 11:39:28 minidsp-shd kernel: Console: switching to colour frame buffer device 240x67 Apr 20 11:39:28 minidsp-shd kernel: sun4i-drm display-engine: fb0: frame buffer device Apr 20 11:39:28 minidsp-shd kernel: [drm] Initialized sun4i-drm 1.0.0 20150629 for display-engine on minor 0 Apr 20 11:39:28 minidsp-shd kernel: Registered IR keymap rc-cec Apr 20 11:39:28 minidsp-shd kernel: rc rc0: RC for dw_hdmi as /devices/platform/soc/1ee0000.hdmi/rc/rc0 Apr 20 11:39:28 minidsp-shd kernel: input: RC for dw_hdmi as /devices/platform/soc/1ee0000.hdmi/rc/rc0/input1 Apr 20 11:39:28 minidsp-shd systemd[1]: Found device /dev/mmcblk0p1. Apr 20 11:39:28 minidsp-shd systemd-udevd[333]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 20 11:39:28 minidsp-shd systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Apr 20 11:39:28 minidsp-shd systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Apr 20 11:39:28 minidsp-shd systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Apr 20 11:39:28 minidsp-shd systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Apr 20 11:39:28 minidsp-shd systemd[1]: Starting File System Check on /dev/mmcblk0p1... Apr 20 11:39:28 minidsp-shd systemd[1]: Started File System Check Daemon to report status. Apr 20 11:39:28 minidsp-shd systemd-fsck[387]: fsck.fat 4.1 (2017-01-24) Apr 20 11:39:28 minidsp-shd systemd-fsck[387]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Apr 20 11:39:28 minidsp-shd systemd-fsck[387]: Automatically removing dirty bit. Apr 20 11:39:28 minidsp-shd systemd-fsck[387]: Performing changes. Apr 20 11:39:28 minidsp-shd systemd-fsck[387]: /dev/mmcblk0p1: 39 files, 11275/30651 clusters Apr 20 11:39:28 minidsp-shd systemd[1]: Started File System Check on /dev/mmcblk0p1. Apr 20 11:39:28 minidsp-shd systemd[1]: Mounting /boot... Apr 20 11:39:29 minidsp-shd systemd[1]: Mounted /boot. Apr 20 11:39:29 minidsp-shd systemd[1]: Reached target Local File Systems. Apr 20 11:39:29 minidsp-shd systemd[1]: Starting Create Volatile Files and Directories... Apr 20 11:39:29 minidsp-shd systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Apr 20 11:39:29 minidsp-shd systemd[1]: Starting Preprocess NFS configuration... Apr 20 11:39:29 minidsp-shd systemd[1]: Started ifup for eth0. Apr 20 11:39:29 minidsp-shd systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Apr 20 11:39:29 minidsp-shd systemd[1]: plymouth-read-write.service: Succeeded. Apr 20 11:39:29 minidsp-shd systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Apr 20 11:39:29 minidsp-shd systemd[1]: Received SIGRTMIN+20 from PID 340 (plymouthd). Apr 20 11:39:29 minidsp-shd systemd[1]: nfs-config.service: Succeeded. Apr 20 11:39:29 minidsp-shd systemd[1]: Started Preprocess NFS configuration. Apr 20 11:39:29 minidsp-shd systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Apr 20 11:39:29 minidsp-shd systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Apr 20 11:39:29 minidsp-shd systemd[1]: Reached target NFS client services. Apr 20 11:39:29 minidsp-shd systemd[1]: Started Create Volatile Files and Directories. Apr 20 11:39:29 minidsp-shd systemd[1]: Starting RPC bind portmap service... Apr 20 11:39:29 minidsp-shd systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Apr 20 11:39:29 minidsp-shd systemd[1]: Starting Update UTMP about System Boot/Shutdown... Apr 20 11:39:29 minidsp-shd systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Apr 20 11:39:29 minidsp-shd systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Apr 20 11:39:29 minidsp-shd systemd[1]: Started Update UTMP about System Boot/Shutdown. Apr 20 11:39:29 minidsp-shd systemd[1]: Reached target System Initialization. Apr 20 11:39:29 minidsp-shd systemd[1]: Started Volumio Splash. Apr 20 11:39:29 minidsp-shd systemd[1]: Listening on triggerhappy.socket. Apr 20 11:39:29 minidsp-shd systemd[413]: volumiosplash.service: Failed to execute command: No such file or directory Apr 20 11:39:29 minidsp-shd systemd[1]: Listening on D-Bus System Message Bus Socket. Apr 20 11:39:29 minidsp-shd systemd[413]: volumiosplash.service: Failed at step EXEC spawning /bin/volumiosplash.sh: No such file or directory Apr 20 11:39:29 minidsp-shd systemd[1]: Started Daily apt download activities. Apr 20 11:39:29 minidsp-shd systemd[1]: Started Daily apt upgrade and clean activities. Apr 20 11:39:29 minidsp-shd systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Apr 20 11:39:29 minidsp-shd systemd[1]: Reached target Sockets. Apr 20 11:39:29 minidsp-shd systemd[1]: Reached target Basic System. Apr 20 11:39:29 minidsp-shd sh[395]: command failed: No such device (-19) Apr 20 11:39:29 minidsp-shd systemd[1]: Started volumio-remote-updater.service. Apr 20 11:39:29 minidsp-shd systemd[1]: Started Volumio Iptables Module. Apr 20 11:39:29 minidsp-shd systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Apr 20 11:39:29 minidsp-shd systemd[1]: Started Volumio Log Rotation Service. Apr 20 11:39:29 minidsp-shd systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Apr 20 11:39:29 minidsp-shd systemd[1]: Started Manage Sound Card State (restore and store). Apr 20 11:39:29 minidsp-shd alsactl[421]: alsactl 1.1.8 daemon started Apr 20 11:39:29 minidsp-shd systemd[1]: Started UPnP Renderer front-end to MPD. Apr 20 11:39:29 minidsp-shd systemd[1]: Starting Reset Realtek 8821CU Dongle USB Port (one-shot)... Apr 20 11:39:29 minidsp-shd systemd[1]: Starting Login Service... Apr 20 11:39:29 minidsp-shd systemd[1]: Starting triggerhappy global hotkey daemon... Apr 20 11:39:29 minidsp-shd systemd[1]: Started D-Bus System Message Bus. Apr 20 11:39:29 minidsp-shd systemd[1]: Starting WPA supplicant... Apr 20 11:39:29 minidsp-shd systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Apr 20 11:39:29 minidsp-shd systemd[1]: Starting DHCP Client Daemon... Apr 20 11:39:29 minidsp-shd systemd[1]: Started Daily Cleanup of Temporary Directories. Apr 20 11:39:29 minidsp-shd thd[426]: Unable to parse trigger line: Apr 20 11:39:29 minidsp-shd thd[426]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Apr 20 11:39:29 minidsp-shd thd[426]: Unable to parse trigger line: Apr 20 11:39:29 minidsp-shd thd[426]: Found socket passed from systemd Apr 20 11:39:29 minidsp-shd systemd[1]: Reached target Timers. Apr 20 11:39:29 minidsp-shd systemd[1]: Starting Wireless Services... Apr 20 11:39:29 minidsp-shd systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Apr 20 11:39:29 minidsp-shd systemd[1]: Starting Save/Restore Sound Card State... Apr 20 11:39:29 minidsp-shd systemd[1]: Started RPC bind portmap service. Apr 20 11:39:29 minidsp-shd systemd[1]: Started triggerhappy global hotkey daemon. Apr 20 11:39:29 minidsp-shd systemd[1]: volumiosplash.service: Main process exited, code=exited, status=203/EXEC Apr 20 11:39:29 minidsp-shd systemd[1]: volumiosplash.service: Failed with result 'exit-code'. Apr 20 11:39:29 minidsp-shd systemd[1]: Reached target RPC Port Mapper. Apr 20 11:39:29 minidsp-shd systemd[1]: Reached target Remote File Systems (Pre). Apr 20 11:39:29 minidsp-shd systemd[1]: Reached target Remote File Systems. Apr 20 11:39:29 minidsp-shd systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Apr 20 11:39:29 minidsp-shd kernel: Netfilter messages via NETLINK v0.30. Apr 20 11:39:29 minidsp-shd kernel: nf_tables: (c) 2007-2009 Patrick McHardy Apr 20 11:39:29 minidsp-shd kernel: nf_tables_compat: (c) 2012 Pablo Neira Ayuso Apr 20 11:39:29 minidsp-shd dhcpcd[432]: no interfaces have a carrier Apr 20 11:39:29 minidsp-shd dhcpcd[432]: no interfaces have a carrier Apr 20 11:39:29 minidsp-shd dhcpcd[432]: forked to background, child pid 466 Apr 20 11:39:29 minidsp-shd dhcpcd[432]: forked to background, child pid 466 Apr 20 11:39:29 minidsp-shd kernel: RTL8211E Gigabit Ethernet stmmac-0:00: attached PHY driver [RTL8211E Gigabit Ethernet] (mii_bus:phy_addr=stmmac-0:00, irq=POLL) Apr 20 11:39:29 minidsp-shd kernel: dwmac-sun8i 1c30000.ethernet eth0: No MAC Management Counters available Apr 20 11:39:29 minidsp-shd kernel: dwmac-sun8i 1c30000.ethernet eth0: PTP not supported by HW Apr 20 11:39:29 minidsp-shd dhcpcd[466]: eth0: waiting for carrier Apr 20 11:39:29 minidsp-shd dhcpcd[466]: eth0: carrier acquired Apr 20 11:39:29 minidsp-shd dhcpcd[466]: DUID 00:01:00:01:2c:cb:c5:62:02:01:fc:9b:3e:61 Apr 20 11:39:29 minidsp-shd dhcpcd[466]: eth0: IAID fc:9b:3e:61 Apr 20 11:39:29 minidsp-shd dhcpcd[466]: eth0: adding address fe80::1:fcff:fe9b:3e61 Apr 20 11:39:29 minidsp-shd dhcpcd[466]: ipv6_addaddr1: Permission denied Apr 20 11:39:29 minidsp-shd dhcpcd[466]: eth0: probing address 192.168.178.37/24 Apr 20 11:39:29 minidsp-shd dhcpcd[466]: eth0: carrier lost Apr 20 11:39:29 minidsp-shd systemd-logind[425]: New seat seat0. Apr 20 11:39:29 minidsp-shd reset_8821cu_nic_usb_port.sh[440]: Realtek 8821CU Dongle not present. Apr 20 11:39:29 minidsp-shd kernel: nf_conntrack version 0.5.0 (8192 buckets, 32768 max) Apr 20 11:39:29 minidsp-shd systemd-logind[425]: Watching system buttons on /dev/input/event0 (r-gpio-keys) Apr 20 11:39:29 minidsp-shd systemd-logind[425]: Watching system buttons on /dev/input/event1 (RC for dw_hdmi) Apr 20 11:39:29 minidsp-shd systemd[1]: Started DHCP Client Daemon. Apr 20 11:39:29 minidsp-shd systemd[1]: Started Save/Restore Sound Card State. Apr 20 11:39:29 minidsp-shd systemd[1]: Started Reset Realtek 8821CU Dongle USB Port (one-shot). Apr 20 11:39:29 minidsp-shd systemd[1]: Started Login Service. Apr 20 11:39:29 minidsp-shd systemd[1]: Reached target Sound Card. Apr 20 11:39:29 minidsp-shd sh[395]: eth0=eth0 Apr 20 11:39:29 minidsp-shd avahi-daemon[419]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). Apr 20 11:39:29 minidsp-shd avahi-daemon[419]: Successfully dropped root privileges. Apr 20 11:39:29 minidsp-shd avahi-daemon[419]: avahi-daemon 0.7 starting up. Apr 20 11:39:29 minidsp-shd systemd[1]: Started Avahi mDNS/DNS-SD Stack. Apr 20 11:39:29 minidsp-shd avahi-daemon[419]: Successfully called chroot(). Apr 20 11:39:29 minidsp-shd avahi-daemon[419]: Successfully dropped remaining capabilities. Apr 20 11:39:29 minidsp-shd avahi-daemon[419]: No service file found in /etc/avahi/services. Apr 20 11:39:29 minidsp-shd avahi-daemon[419]: Network interface enumeration completed. Apr 20 11:39:29 minidsp-shd avahi-daemon[419]: Server startup complete. Host name is minidsp-shd.local. Local service cookie is 1206297548. Apr 20 11:39:30 minidsp-shd loadcpufreq[439]: Loading cpufreq kernel modules...done (none). Apr 20 11:39:30 minidsp-shd systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Apr 20 11:39:30 minidsp-shd systemd[1]: Starting LSB: set CPUFreq kernel parameters... Apr 20 11:39:30 minidsp-shd kernel: i2c i2c-0: mv64xxx: I2C bus locked, block: 1, time_left: 0 Apr 20 11:39:30 minidsp-shd kernel: rtc-ds1307: probe of 0-0068 failed with error -110 Apr 20 11:39:30 minidsp-shd systemd[1]: Started Helper to synchronize boot up for ifupdown. Apr 20 11:39:30 minidsp-shd systemd[1]: Starting Raise network interfaces... Apr 20 11:39:30 minidsp-shd systemd[1]: Started WPA supplicant. Apr 20 11:39:30 minidsp-shd wpa_supplicant[429]: Successfully initialized wpa_supplicant Apr 20 11:39:30 minidsp-shd ifup[543]: command failed: No such device (-19) Apr 20 11:39:30 minidsp-shd cpufrequtils[540]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Apr 20 11:39:30 minidsp-shd systemd[1]: Started LSB: set CPUFreq kernel parameters. Apr 20 11:39:30 minidsp-shd ifup[543]: command failed: No such device (-19) Apr 20 11:39:30 minidsp-shd ifup[543]: command failed: No such device (-19) Apr 20 11:39:30 minidsp-shd volumio-remote-updater[415]: Error: No active session Apr 20 11:39:30 minidsp-shd volumio-remote-updater[415]: [2025-04-20 11:39:30] [info] asio async_connect error: system:111 (Connection refused) Apr 20 11:39:30 minidsp-shd volumio-remote-updater[415]: [2025-04-20 11:39:30] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 20 11:39:30 minidsp-shd volumio-remote-updater[415]: [2025-04-20 11:39:30] [error] handle_connect error: Underlying Transport Error Apr 20 11:39:30 minidsp-shd systemd[1]: Started Raise network interfaces. Apr 20 11:39:30 minidsp-shd systemd[1]: Reached target Network. Apr 20 11:39:30 minidsp-shd systemd[1]: Starting Network Time Service... Apr 20 11:39:30 minidsp-shd systemd[1]: Starting Permit User Sessions... Apr 20 11:39:30 minidsp-shd systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Apr 20 11:39:30 minidsp-shd systemd[1]: Reached target Network is Online. Apr 20 11:39:30 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 20 11:39:30 minidsp-shd systemd[1]: Starting Samba NMB Daemon... Apr 20 11:39:30 minidsp-shd systemd[1]: Starting /etc/rc.local Compatibility... Apr 20 11:39:30 minidsp-shd volumio[422]: Could not open config: /tmp/upmpdcli.conf Apr 20 11:39:30 minidsp-shd systemd[1]: Starting LSB: Brings up/down network automatically... Apr 20 11:39:30 minidsp-shd systemd[1]: Started Permit User Sessions. Apr 20 11:39:30 minidsp-shd systemd[1]: Started /etc/rc.local Compatibility. Apr 20 11:39:30 minidsp-shd systemd[1]: Starting Terminate Plymouth Boot Screen... Apr 20 11:39:30 minidsp-shd systemd[1]: Starting Hold until boot process finishes up... Apr 20 11:39:30 minidsp-shd systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 20 11:39:30 minidsp-shd systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 20 11:39:30 minidsp-shd systemd[1]: Received SIGRTMIN+21 from PID 340 (plymouthd). Apr 20 11:39:30 minidsp-shd systemd[1]: plymouth-start.service: Succeeded. Apr 20 11:39:30 minidsp-shd systemd[1]: plymouth-quit.service: Succeeded. Apr 20 11:39:30 minidsp-shd systemd[1]: Started Terminate Plymouth Boot Screen. Apr 20 11:39:30 minidsp-shd systemd[1]: plymouth-quit-wait.service: Succeeded. Apr 20 11:39:30 minidsp-shd systemd[1]: Started Hold until boot process finishes up. Apr 20 11:39:30 minidsp-shd systemd[1]: Received SIGRTMIN+21 from PID 340 (n/a). Apr 20 11:39:30 minidsp-shd systemd[1]: Started Serial Getty on ttyS0. Apr 20 11:39:30 minidsp-shd systemd[1]: Started Getty on tty1. Apr 20 11:39:30 minidsp-shd systemd[1]: Reached target Login Prompts. Apr 20 11:39:30 minidsp-shd ifplugd(eth0)[690]: ifplugd 0.28 initializing. Apr 20 11:39:30 minidsp-shd ifplugd(eth0)[690]: Using interface eth0/02:01:FC:9B:3E:61 with driver (version: Jan_2016) Apr 20 11:39:30 minidsp-shd ifplugd(eth0)[690]: Using detection mode: SIOCETHTOOL Apr 20 11:39:30 minidsp-shd ifplugd(eth0)[690]: Initialization complete, link beat not detected. Apr 20 11:39:30 minidsp-shd ntpd[664]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 20 11:39:30 minidsp-shd ntpd[664]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 20 11:39:30 minidsp-shd systemd[1]: Started Network Time Service. Apr 20 11:39:30 minidsp-shd ntpd[694]: proto: precision = 1.250 usec (-20) Apr 20 11:39:30 minidsp-shd ifplugd[669]: Network Interface Plugging Daemon...start eth0...done. Apr 20 11:39:30 minidsp-shd systemd[1]: Started LSB: Brings up/down network automatically. Apr 20 11:39:30 minidsp-shd ntpd[694]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 20 11:39:30 minidsp-shd ntpd[694]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 20 11:39:30 minidsp-shd ntpd[694]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 845 days ago Apr 20 11:39:30 minidsp-shd ntpd[694]: Listen and drop on 0 v6wildcard [::]:123 Apr 20 11:39:30 minidsp-shd ntpd[694]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 20 11:39:30 minidsp-shd ntpd[694]: Listen normally on 2 lo 127.0.0.1:123 Apr 20 11:39:30 minidsp-shd ntpd[694]: Listening on routing socket on fd #19 for interface updates Apr 20 11:39:30 minidsp-shd ntpd[694]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 20 11:39:30 minidsp-shd ntpd[694]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 20 11:39:31 minidsp-shd systemd[1]: iptables.service: Succeeded. Apr 20 11:39:31 minidsp-shd haveged[408]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.3.0 CTV); collect: 128K Apr 20 11:39:31 minidsp-shd haveged[408]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 12/40; sz: 15012/57848 Apr 20 11:39:31 minidsp-shd haveged[408]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99837 Apr 20 11:39:31 minidsp-shd haveged[408]: haveged: fills: 0, generated: 0 Apr 20 11:39:31 minidsp-shd kernel: random: crng init done Apr 20 11:39:31 minidsp-shd nmbd[674]: [2025/04/20 11:39:31.769633, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Apr 20 11:39:31 minidsp-shd nmbd[674]: started asyncdns process 702 Apr 20 11:39:31 minidsp-shd nmbd[674]: [2025/04/20 11:39:31.772634, 0] ../lib/util/become_daemon.c:149(daemon_status) Apr 20 11:39:31 minidsp-shd nmbd[674]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Apr 20 11:39:31 minidsp-shd nmbd[674]: [2025/04/20 11:39:31.772848, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Apr 20 11:39:31 minidsp-shd nmbd[674]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Apr 20 11:39:31 minidsp-shd ntpd[694]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 20 11:39:32 minidsp-shd dhcpcd[466]: eth0: carrier acquired Apr 20 11:39:32 minidsp-shd kernel: dwmac-sun8i 1c30000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx Apr 20 11:39:32 minidsp-shd dhcpcd[466]: eth0: IAID fc:9b:3e:61 Apr 20 11:39:32 minidsp-shd dhcpcd[466]: eth0: probing address 192.168.178.37/24 Apr 20 11:39:32 minidsp-shd wireless.js[433]: WIRELESS: No wireless interface, exiting Apr 20 11:39:32 minidsp-shd iw[709]: command failed: No such device (-19) Apr 20 11:39:32 minidsp-shd iwconfig[712]: Error for wireless request "Set Power Management" (8B2C) : Apr 20 11:39:32 minidsp-shd iwconfig[712]: SET failed on device wlan0 ; No such device. Apr 20 11:39:32 minidsp-shd systemd[1]: wireless.service: Succeeded. Apr 20 11:39:32 minidsp-shd systemd[1]: Started Wireless Services. Apr 20 11:39:32 minidsp-shd systemd[1]: Started Volumio Backend Module. Apr 20 11:39:32 minidsp-shd systemd[1]: Started Volumio Cpu Tweaker. Apr 20 11:39:32 minidsp-shd ntpd[694]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 20 11:39:32 minidsp-shd volumio-cpu-tweak[716]: Setting RT Priority for mpd Apr 20 11:39:32 minidsp-shd volumio-cpu-tweak[716]: pid 35's current scheduling policy: SCHED_OTHER Apr 20 11:39:32 minidsp-shd volumio-cpu-tweak[716]: pid 35's current scheduling priority: 0 Apr 20 11:39:32 minidsp-shd ifplugd(eth0)[690]: Link beat detected. Apr 20 11:39:32 minidsp-shd volumio-cpu-tweak[716]: Setting MPD Affinity Apr 20 11:39:32 minidsp-shd volumio-cpu-tweak[716]: pid 3's current affinity mask: 1 Apr 20 11:39:32 minidsp-shd volumio-cpu-tweak[716]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Apr 20 11:39:32 minidsp-shd volumio-cpu-tweak[716]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Apr 20 11:39:33 minidsp-shd systemd[1]: volumio_cpu_tweak.service: Succeeded. Apr 20 11:39:33 minidsp-shd ifplugd(eth0)[690]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Apr 20 11:39:33 minidsp-shd ifplugd(eth0)[690]: client: ifup: interface eth0 already configured Apr 20 11:39:33 minidsp-shd ifplugd(eth0)[690]: Program executed successfully. Apr 20 11:39:33 minidsp-shd dhcpcd[466]: eth0: soliciting an IPv6 router Apr 20 11:39:33 minidsp-shd ntpd[694]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 20 11:39:34 minidsp-shd ntpd[694]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 20 11:39:35 minidsp-shd volumio-remote-updater[415]: [2025-04-20 11:39:35] [connect] Successful connection Apr 20 11:39:36 minidsp-shd volumio[715]: info: ------------------------------------------- Apr 20 11:39:36 minidsp-shd volumio[715]: info: ----- Volumio3 ---- Apr 20 11:39:36 minidsp-shd volumio[715]: info: ------------------------------------------- Apr 20 11:39:36 minidsp-shd volumio[715]: info: ----- System startup ---- Apr 20 11:39:36 minidsp-shd volumio[715]: info: ------------------------------------------- Apr 20 11:39:37 minidsp-shd dhcpcd[466]: eth0: using static address 192.168.178.37/24 Apr 20 11:39:37 minidsp-shd dhcpcd[466]: eth0: adding route to 192.168.178.0/24 Apr 20 11:39:37 minidsp-shd avahi-daemon[419]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.178.37. Apr 20 11:39:37 minidsp-shd dhcpcd[466]: eth0: adding default route via 192.168.178.1 Apr 20 11:39:37 minidsp-shd avahi-daemon[419]: New relevant interface eth0.IPv4 for mDNS. Apr 20 11:39:37 minidsp-shd avahi-daemon[419]: Registering new address record for 192.168.178.37 on eth0.IPv4. Apr 20 11:39:37 minidsp-shd volumio[715]: info: MYVOLUMIO Environment detected Apr 20 11:39:38 minidsp-shd volumio[715]: info: Plugin folders cleanup Apr 20 11:39:38 minidsp-shd volumio[715]: info: Scanning into folder /volumio/app/plugins/ Apr 20 11:39:38 minidsp-shd volumio[715]: info: Scanning category audio_interface Apr 20 11:39:38 minidsp-shd volumio[715]: info: Scanning category miscellanea Apr 20 11:39:38 minidsp-shd volumio[715]: info: Scanning category music_service Apr 20 11:39:38 minidsp-shd volumio[715]: info: Scanning category plugins.json Apr 20 11:39:38 minidsp-shd volumio[715]: info: Scanning category system_controller Apr 20 11:39:38 minidsp-shd volumio[715]: info: Scanning category user_interface Apr 20 11:39:38 minidsp-shd volumio[715]: info: Scanning into folder /data/plugins/ Apr 20 11:39:38 minidsp-shd volumio[715]: info: Scanning category music_service Apr 20 11:39:38 minidsp-shd volumio[715]: info: Plugin folders cleanup completed Apr 20 11:39:38 minidsp-shd volumio[715]: info: ------------------------------------------- Apr 20 11:39:38 minidsp-shd volumio[715]: info: ----- Core plugins startup ---- Apr 20 11:39:38 minidsp-shd volumio[715]: info: ------------------------------------------- Apr 20 11:39:38 minidsp-shd volumio[715]: info: Loading plugins from folder /volumio/app/plugins/ Apr 20 11:39:38 minidsp-shd volumio[715]: info: Adding plugin bluetooth to MyMusic Plugins Apr 20 11:39:38 minidsp-shd volumio[715]: info: Adding plugin multiroom to MyMusic Plugins Apr 20 11:39:38 minidsp-shd volumio[715]: info: Adding plugin upnp to MyMusic Plugins Apr 20 11:39:38 minidsp-shd volumio[715]: info: Adding plugin metavolumio to MyMusic Plugins Apr 20 11:39:38 minidsp-shd volumio[715]: info: Adding plugin ms_surface_dial to MyMusic Plugins Apr 20 11:39:38 minidsp-shd volumio[715]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 20 11:39:38 minidsp-shd volumio[715]: info: Adding plugin cd_controller to MyMusic Plugins Apr 20 11:39:38 minidsp-shd volumio[715]: info: Adding plugin raat to MyMusic Plugins Apr 20 11:39:38 minidsp-shd volumio[715]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 20 11:39:38 minidsp-shd volumio[715]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 20 11:39:38 minidsp-shd volumio[715]: info: Loading plugins from folder /data/plugins/ Apr 20 11:39:38 minidsp-shd volumio[715]: info: Loading plugin "system"... Apr 20 11:39:38 minidsp-shd volumio[715]: info: Loading plugin "appearance"... Apr 20 11:39:38 minidsp-shd ntpd[694]: Listen normally on 3 eth0 192.168.178.37:123 Apr 20 11:39:38 minidsp-shd ntpd[694]: new interface(s) found: waking up resolver Apr 20 11:39:39 minidsp-shd volumio[715]: info: Loading plugin "network"... Apr 20 11:39:40 minidsp-shd volumio[715]: info: Refreshing Cached IP Addresses Apr 20 11:39:40 minidsp-shd volumio[715]: info: Loading plugin "services"... Apr 20 11:39:40 minidsp-shd volumio[715]: info: Loading plugin "alsa_controller"... Apr 20 11:39:40 minidsp-shd sudo[771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 11:39:40 minidsp-shd sudo[769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 11:39:40 minidsp-shd sudo[771]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 11:39:40 minidsp-shd sudo[769]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 11:39:40 minidsp-shd sudo[769]: pam_unix(sudo:session): session closed for user root Apr 20 11:39:40 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 11:39:40 minidsp-shd volumio[715]: info: Loading plugin "wizard"... Apr 20 11:39:40 minidsp-shd sudo[771]: pam_unix(sudo:session): session closed for user root Apr 20 11:39:40 minidsp-shd volumio[715]: info: Loading plugin "networkfs"... Apr 20 11:39:40 minidsp-shd volumio[715]: info: Starting Udev Watcher for removable devices Apr 20 11:39:40 minidsp-shd sudo[779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=musicshare4,password=12345678,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //DISKStation/music /mnt/NAS/Mukke Apr 20 11:39:40 minidsp-shd sudo[779]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 11:39:40 minidsp-shd kernel: FS-Cache: Loaded Apr 20 11:39:40 minidsp-shd volumio[715]: info: Ignoring mount for partition: BOOT Apr 20 11:39:40 minidsp-shd volumio[715]: info: Ignoring mount for partition: volumio Apr 20 11:39:40 minidsp-shd volumio[715]: info: Ignoring mount for partition: volumio_data Apr 20 11:39:40 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 11:39:40 minidsp-shd volumio[715]: info: Loading plugin "volumio_command_line_client"... Apr 20 11:39:40 minidsp-shd volumio[715]: info: Loading plugin "upnp"... Apr 20 11:39:40 minidsp-shd kernel: FS-Cache: Netfs 'cifs' registered for caching Apr 20 11:39:40 minidsp-shd kernel: Key type cifs.idmap registered Apr 20 11:39:40 minidsp-shd kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Apr 20 11:39:40 minidsp-shd volumio[715]: info: [1745141980366] Starting Upmpd Daemon Apr 20 11:39:40 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 11:39:40 minidsp-shd volumio[715]: info: Loading plugin "my_music"... Apr 20 11:39:40 minidsp-shd volumio[715]: info: Loading plugin "mpd"... Apr 20 11:39:40 minidsp-shd sudo[779]: pam_unix(sudo:session): session closed for user root Apr 20 11:39:40 minidsp-shd volumio[715]: info: Loading plugin "upnp_browser"... Apr 20 11:39:41 minidsp-shd volumio[715]: info: Loading plugin "bluetooth"... Apr 20 11:39:41 minidsp-shd nmbd[674]: [2025/04/20 11:39:41.776931, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 20 11:39:41 minidsp-shd nmbd[674]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 20 11:39:41 minidsp-shd systemd[1]: Started Samba NMB Daemon. Apr 20 11:39:41 minidsp-shd systemd[1]: Starting Samba Winbind Daemon... Apr 20 11:39:41 minidsp-shd volumio[715]: info: [1745141981879] Starting BluetoothController Apr 20 11:39:42 minidsp-shd volumio[715]: info: Loading plugin "alarm-clock"... Apr 20 11:39:42 minidsp-shd winbindd[805]: [2025/04/20 11:39:42.244450, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 20 11:39:42 minidsp-shd winbindd[805]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 20 11:39:42 minidsp-shd winbindd[805]: [2025/04/20 11:39:42.274097, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 20 11:39:42 minidsp-shd winbindd[805]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 20 11:39:42 minidsp-shd systemd[1]: Started Samba Winbind Daemon. Apr 20 11:39:42 minidsp-shd systemd[1]: Starting Samba SMB Daemon... Apr 20 11:39:42 minidsp-shd volumio[715]: info: Loading plugin "manifestui"... Apr 20 11:39:42 minidsp-shd volumio[715]: info: Loading plugin "metavolumio"... Apr 20 11:39:42 minidsp-shd winbindd[805]: [2025/04/20 11:39:42.914512, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Apr 20 11:39:42 minidsp-shd winbindd[805]: res_names->count = 3, expected 4 Apr 20 11:39:42 minidsp-shd smbd[810]: [2025/04/20 11:39:42.963429, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 20 11:39:42 minidsp-shd smbd[810]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 20 11:39:42 minidsp-shd systemd[1]: Started Samba SMB Daemon. Apr 20 11:39:42 minidsp-shd systemd[1]: Reached target Multi-User System. Apr 20 11:39:42 minidsp-shd systemd[1]: Reached target Graphical Interface. Apr 20 11:39:42 minidsp-shd systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 20 11:39:43 minidsp-shd systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 20 11:39:43 minidsp-shd systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 20 11:39:43 minidsp-shd systemd[1]: Startup finished in 8.938s (kernel) + 17.125s (userspace) = 26.063s. Apr 20 11:39:44 minidsp-shd volumio[715]: info: Plugin airplay_emulation is not enabled Apr 20 11:39:44 minidsp-shd volumio[715]: info: Plugin cd_controller is not enabled Apr 20 11:39:44 minidsp-shd volumio[715]: info: Loading plugin "last_100"... Apr 20 11:39:44 minidsp-shd volumio[715]: info: Plugin raat is not enabled Apr 20 11:39:44 minidsp-shd volumio[715]: info: Loading plugin "streaming_services"... Apr 20 11:39:46 minidsp-shd volumio[715]: info: Starting Streaming Service Transparent Proxy Apr 20 11:39:46 minidsp-shd volumio[715]: info: Loading plugin "tidalconnect"... Apr 20 11:39:46 minidsp-shd volumio[715]: info: Loading plugin "webradio"... Apr 20 11:39:46 minidsp-shd volumio[715]: info: Loading plugin "i2s_dacs"... Apr 20 11:39:46 minidsp-shd volumio[715]: info: I2S DAC not set, start Auto-detection Apr 20 11:39:46 minidsp-shd volumio[715]: info: Loading plugin "volumiodiscovery"... Apr 20 11:39:47 minidsp-shd volumio[715]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 20 11:39:47 minidsp-shd volumio[715]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 11:39:47 minidsp-shd volumio[715]: *** WARNING *** For more information see Apr 20 11:39:47 minidsp-shd volumio[715]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 20 11:39:47 minidsp-shd volumio[715]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 11:39:47 minidsp-shd volumio[715]: *** WARNING *** For more information see Apr 20 11:39:47 minidsp-shd node[715]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 20 11:39:47 minidsp-shd node[715]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 11:39:47 minidsp-shd node[715]: *** WARNING *** For more information see Apr 20 11:39:47 minidsp-shd node[715]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 20 11:39:47 minidsp-shd node[715]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 11:39:47 minidsp-shd node[715]: *** WARNING *** For more information see Apr 20 11:39:47 minidsp-shd volumio[715]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 20 11:39:47 minidsp-shd volumio[715]: info: Discovery: Started advertising with name: miniDSP SHD Apr 20 11:39:47 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 11:39:47 minidsp-shd volumio[715]: info: Loading plugin "spop"... Apr 20 11:39:48 minidsp-shd volumio[715]: STREAMING PROXY: Starting server on port 3245 Apr 20 11:39:48 minidsp-shd volumio[715]: Node JS runtime: 14 Apr 20 11:39:48 minidsp-shd volumio-remote-updater[415]: [2025-04-20 11:39:48] [connect] Successful connection Apr 20 11:39:49 minidsp-shd volumio[715]: info: Plugin youtube2 is not enabled Apr 20 11:39:49 minidsp-shd volumio[715]: info: Plugin ytmusic is not enabled Apr 20 11:39:49 minidsp-shd volumio[715]: info: Plugin multiroom is not enabled Apr 20 11:39:49 minidsp-shd volumio[715]: info: Loading plugin "outputs"... Apr 20 11:39:49 minidsp-shd volumio[715]: info: Loading plugin "albumart"... Apr 20 11:39:49 minidsp-shd volumio[715]: info: Plugin ms_surface_dial is not enabled Apr 20 11:39:49 minidsp-shd volumio[715]: info: Plugin example_plugin is not enabled Apr 20 11:39:49 minidsp-shd volumio[715]: info: Loading plugin "hi_res_audio"... Apr 20 11:39:49 minidsp-shd volumio[715]: Forking 3 albumart workers Apr 20 11:39:51 minidsp-shd volumio[715]: Starting albumart workers Apr 20 11:39:51 minidsp-shd volumio[715]: Starting albumart workers Apr 20 11:39:51 minidsp-shd volumio[715]: Starting albumart workers Apr 20 11:39:51 minidsp-shd volumio[715]: info: Applying required configuration parameters for plugin hi_res_audio Apr 20 11:39:51 minidsp-shd volumio[715]: info: Loading plugin "inputs"... Apr 20 11:39:52 minidsp-shd volumio[715]: info: Loading plugin "qobuz"... Apr 20 11:39:54 minidsp-shd volumio[715]: info: Loading plugin "tidal"... Apr 20 11:39:56 minidsp-shd volumio[715]: info: Loading plugin "oem_helper"... Apr 20 11:39:57 minidsp-shd volumio[715]: info: Applying required configuration parameters for plugin oem_helper Apr 20 11:39:57 minidsp-shd volumio[715]: info: Loading plugin "updater_comm"... Apr 20 11:39:58 minidsp-shd volumio[715]: info: Plugin mpdemulation is not enabled Apr 20 11:39:58 minidsp-shd volumio[715]: info: Loading plugin "rest_api"... Apr 20 11:39:58 minidsp-shd volumio[715]: info: Loading plugin "websocket"... Apr 20 11:39:58 minidsp-shd volumio[715]: info: Loading i18n strings for locale de Apr 20 11:39:58 minidsp-shd volumio[715]: Updating browse sources language Apr 20 11:39:58 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 11:39:58 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 11:39:58 minidsp-shd volumio[715]: info: CoreCommandRouter::initPlayerControls Apr 20 11:39:58 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:39:58 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:39:58 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:39:58 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:39:58 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:39:58 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:39:58 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:39:58 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:39:58 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 11:39:58 minidsp-shd volumio[715]: Express server listening on port 3000 Apr 20 11:39:58 minidsp-shd volumio[715]: [Metrics] WebUI: 23s 259.08ms Apr 20 11:39:58 minidsp-shd volumio[715]: info: CoreStateMachine::resetVolumioState Apr 20 11:39:58 minidsp-shd volumio[715]: info: CoreStateMachine::getcurrentVolume Apr 20 11:39:58 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 11:39:58 minidsp-shd volumio[715]: info: CoreStateMachine::pushState Apr 20 11:39:58 minidsp-shd volumio[715]: info: CorePlayQueue::getTrack 0 Apr 20 11:39:58 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 11:39:58 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioPushState Apr 20 11:39:58 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 11:39:58 minidsp-shd sudo[885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 11:39:58 minidsp-shd sudo[885]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 11:39:58 minidsp-shd sudo[885]: pam_unix(sudo:session): session closed for user root Apr 20 11:39:58 minidsp-shd volumio[715]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 20 11:39:58 minidsp-shd sudo[888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 11:39:58 minidsp-shd sudo[888]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 11:39:58 minidsp-shd volumio[715]: info: Completed loading Core Plugins Apr 20 11:39:58 minidsp-shd volumio[715]: info: Preparing to generate the ALSA configuration file Apr 20 11:39:58 minidsp-shd sudo[888]: pam_unix(sudo:session): session closed for user root Apr 20 11:39:58 minidsp-shd systemd[1]: systemd-fsckd.service: Succeeded. Apr 20 11:39:58 minidsp-shd volumio[715]: verbose: New Socket.io Connection to 192.168.178.37 from 192.168.178.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:128.0) Gecko/20100101 Firefox/128.0 Total Clients: 1 Apr 20 11:39:58 minidsp-shd volumio[715]: verbose: New Socket.io Connection to 192.168.178.37 from 192.168.178.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:128.0) Gecko/20100101 Firefox/128.0 Total Clients: 2 Apr 20 11:39:58 minidsp-shd volumio[715]: verbose: New Socket.io Connection to 192.168.178.37 from 192.168.178.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:128.0) Gecko/20100101 Firefox/128.0 Total Clients: 3 Apr 20 11:39:59 minidsp-shd volumio[715]: verbose: New Socket.io Connection to 192.168.178.37 from 192.168.178.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:128.0) Gecko/20100101 Firefox/128.0 Total Clients: 4 Apr 20 11:39:59 minidsp-shd volumio[715]: verbose: New Socket.io Connection to 192.168.178.37 from 192.168.178.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:128.0) Gecko/20100101 Firefox/128.0 Total Clients: 5 Apr 20 11:39:59 minidsp-shd volumio[715]: verbose: New Socket.io Connection to 192.168.178.37 from 192.168.178.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:128.0) Gecko/20100101 Firefox/128.0 Total Clients: 6 Apr 20 11:39:59 minidsp-shd volumio[715]: verbose: New Socket.io Connection to 192.168.178.37 from 192.168.178.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:128.0) Gecko/20100101 Firefox/128.0 Total Clients: 7 Apr 20 11:39:59 minidsp-shd volumio[715]: verbose: New Socket.io Connection to 192.168.178.37 from 192.168.178.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:128.0) Gecko/20100101 Firefox/128.0 Total Clients: 8 Apr 20 11:39:59 minidsp-shd volumio[715]: info: Asound.conf file unchanged, so no further update is needed Apr 20 11:39:59 minidsp-shd volumio[715]: info: Output device has changed, restarting MPD Apr 20 11:39:59 minidsp-shd sudo[898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 11:39:59 minidsp-shd sudo[898]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 11:39:59 minidsp-shd sudo[898]: pam_unix(sudo:session): session closed for user root Apr 20 11:39:59 minidsp-shd volumio[715]: info: ___________ START PLUGINS ___________ Apr 20 11:39:59 minidsp-shd volumio[715]: info: ControllerMpd::onStart: Initializing MPD Apr 20 11:39:59 minidsp-shd sudo[901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 11:39:59 minidsp-shd sudo[901]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 11:39:59 minidsp-shd volumio[715]: info: Creating MPD Configuration file Apr 20 11:39:59 minidsp-shd systemd[1]: Listening on mpd.socket. Apr 20 11:39:59 minidsp-shd sudo[904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 11:39:59 minidsp-shd sudo[904]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 11:39:59 minidsp-shd systemd[1]: Starting Music Player Daemon... Apr 20 11:39:59 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 11:39:59 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 11:39:59 minidsp-shd volumio[715]: info: [1745141999268] CoreMusicLibrary::Adding element Medienserver Apr 20 11:39:59 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 11:39:59 minidsp-shd sudo[904]: pam_unix(sudo:session): session closed for user root Apr 20 11:39:59 minidsp-shd sudo[908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 11:39:59 minidsp-shd sudo[908]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 11:39:59 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 11:39:59 minidsp-shd volumio[715]: info: Adding Manifest REST API Endpoints Apr 20 11:39:59 minidsp-shd volumio[715]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Apr 20 11:39:59 minidsp-shd volumio[715]: info: Adding METAVOLUMIO REST API Endpoints Apr 20 11:39:59 minidsp-shd volumio[715]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Apr 20 11:39:59 minidsp-shd volumio[715]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Apr 20 11:39:59 minidsp-shd volumio[715]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Apr 20 11:39:59 minidsp-shd volumio[715]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Apr 20 11:39:59 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 11:39:59 minidsp-shd volumio[715]: info: [1745141999344] CoreMusicLibrary::Adding element Last_100 Apr 20 11:39:59 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 11:39:59 minidsp-shd sudo[911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name miniDSP SHD Apr 20 11:39:59 minidsp-shd sudo[911]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 11:39:59 minidsp-shd volumio[715]: info: Streaming services startup Apr 20 11:39:59 minidsp-shd volumio[715]: info: Starting Streaming Daemon Apr 20 11:39:59 minidsp-shd systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 20 11:39:59 minidsp-shd systemd[1]: mpd.service: Succeeded. Apr 20 11:39:59 minidsp-shd systemd[1]: Stopped Music Player Daemon. Apr 20 11:39:59 minidsp-shd systemd[1]: Starting Music Player Daemon... Apr 20 11:39:59 minidsp-shd kernel: Bluetooth: Core ver 2.22 Apr 20 11:39:59 minidsp-shd kernel: NET: Registered protocol family 31 Apr 20 11:39:59 minidsp-shd kernel: Bluetooth: HCI device and connection manager initialized Apr 20 11:39:59 minidsp-shd kernel: Bluetooth: HCI socket layer initialized Apr 20 11:39:59 minidsp-shd kernel: Bluetooth: L2CAP socket layer initialized Apr 20 11:39:59 minidsp-shd kernel: Bluetooth: SCO socket layer initialized Apr 20 11:39:59 minidsp-shd sudo[916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 20 11:39:59 minidsp-shd sudo[916]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 11:39:59 minidsp-shd sudo[911]: pam_unix(sudo:session): session closed for user root Apr 20 11:39:59 minidsp-shd sudo[916]: pam_unix(sudo:session): session closed for user root Apr 20 11:39:59 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 11:39:59 minidsp-shd volumio[715]: info: [1745141999508] CoreMusicLibrary::Adding element Webradio Apr 20 11:39:59 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 11:39:59 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 11:39:59 minidsp-shd sudo[927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 20 11:39:59 minidsp-shd sudo[927]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 11:39:59 minidsp-shd volumio[715]: info: Creating Spotify config file Apr 20 11:39:59 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:39:59 minidsp-shd sudo[927]: pam_unix(sudo:session): session closed for user root Apr 20 11:39:59 minidsp-shd volumio[715]: error: Hi Res Audio Failed Login: Missing Login Data Apr 20 11:39:59 minidsp-shd volumio[715]: info: Adding HIGHRESAUDIO REST API Endpoints Apr 20 11:39:59 minidsp-shd volumio[715]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Apr 20 11:39:59 minidsp-shd volumio[715]: info: Refreshing TIDAL token Apr 20 11:39:59 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 11:39:59 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 11:39:59 minidsp-shd volumio[715]: info: Volumio Calling Home Apr 20 11:39:59 minidsp-shd volumio[715]: info: Stopping AccessToken refresher cron for QOBUZ Apr 20 11:40:00 minidsp-shd volumio[715]: info: AccessToken refresher cron started for QOBUZ Apr 20 11:40:00 minidsp-shd volumio[715]: info: Adding QOBUZ REST API Endpoints Apr 20 11:40:00 minidsp-shd volumio[715]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Apr 20 11:40:00 minidsp-shd volumio[715]: info: Setting Device type: NanoPi NEO 2 Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreStateMachine::setRepeat true single undefined Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreStateMachine::pushState Apr 20 11:40:00 minidsp-shd volumio[715]: info: CorePlayQueue::getTrack 0 Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioPushState Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreStateMachine::setRandom null Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreStateMachine::pushState Apr 20 11:40:00 minidsp-shd volumio[715]: info: CorePlayQueue::getTrack 0 Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioPushState Apr 20 11:40:00 minidsp-shd volumio[715]: info: Serial port opened successfully Apr 20 11:40:00 minidsp-shd volumio[715]: error: Cannot start Volumio Streaming Daemon Apr 20 11:40:00 minidsp-shd volumio[715]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 20 11:40:00 minidsp-shd volumio[715]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 20 11:40:00 minidsp-shd volumio[715]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'miniDSP SHD' Apr 20 11:40:00 minidsp-shd volumio[715]: Can't get device info: No such device Apr 20 11:40:00 minidsp-shd volumio[715]: info: MPD Permissions set Apr 20 11:40:00 minidsp-shd volumio[715]: info: MPD Permissions set Apr 20 11:40:00 minidsp-shd sudo[944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 20 11:40:00 minidsp-shd sudo[944]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 11:40:00 minidsp-shd volumio[715]: info: Spotify config file written Apr 20 11:40:00 minidsp-shd systemd[1]: Started UPnP Renderer front-end to MPD. Apr 20 11:40:00 minidsp-shd sudo[944]: pam_unix(sudo:session): session closed for user root Apr 20 11:40:00 minidsp-shd sudo[948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 20 11:40:00 minidsp-shd sudo[948]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 11:40:00 minidsp-shd systemd[1]: Started go-librespot Daemon. Apr 20 11:40:00 minidsp-shd volumio[715]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 20 11:40:00 minidsp-shd sudo[948]: pam_unix(sudo:session): session closed for user root Apr 20 11:40:00 minidsp-shd volumio[715]: info: Volumio called home Apr 20 11:40:00 minidsp-shd volumio[715]: info: No need to fix Spotify hosts Apr 20 11:40:00 minidsp-shd go-librespot[950]: go-librespot daemon starting... Apr 20 11:40:00 minidsp-shd volumio[715]: info: Discovery: adding 37c72e1a-a5b6-447a-afd2-964225ad7bb9 Apr 20 11:40:00 minidsp-shd volumio[715]: info: Discovery: Found device miniDSP SHD Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioGetState Apr 20 11:40:00 minidsp-shd volumio[715]: info: CorePlayQueue::getTrack 0 Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: raat , reconfigureAndRestartRaat Apr 20 11:40:00 minidsp-shd volumio[715]: info: Adding MINIDSP Inputs Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 11:40:00 minidsp-shd volumio[715]: info: [1745142000685] CoreMusicLibrary::Adding element Inputs Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 11:40:00 minidsp-shd volumio[715]: info: [1745142000689] CoreMusicLibrary::Adding element Presets Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 11:40:00 minidsp-shd volumio[715]: Cannot find translation for source Presets Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 20 11:40:00 minidsp-shd volumio[715]: info: Upmpdcli Daemon Started Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioGetVisibleSources Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioGetState Apr 20 11:40:00 minidsp-shd volumio[715]: info: CorePlayQueue::getTrack 0 Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioGetState Apr 20 11:40:00 minidsp-shd volumio[715]: info: CorePlayQueue::getTrack 0 Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Apr 20 11:40:00 minidsp-shd volumio[715]: info: Received Get System Info Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 20 11:40:00 minidsp-shd volumio[715]: info: Discovery: Getting this device information Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioGetState Apr 20 11:40:00 minidsp-shd volumio[715]: info: CorePlayQueue::getTrack 0 Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 20 11:40:00 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioGetState Apr 20 11:40:00 minidsp-shd volumio[715]: info: CorePlayQueue::getTrack 0 Apr 20 11:40:00 minidsp-shd volumio[715]: info: Listing playlists Apr 20 11:40:01 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:01+02:00" level=info msg="running go-librespot 0.2.0" Apr 20 11:40:01 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:01+02:00" level=debug msg="app state loaded" Apr 20 11:40:01 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:01+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 20 11:40:01 minidsp-shd volumio[715]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Apr 20 11:40:01 minidsp-shd volumio[715]: SPOTIFY: BQCZHqw2y-5n72hBZbK3ncE_KY3HgsLADueVqMv1Rl5O73TEg1wDBgFcUJyNsQaa5qspSOS0jQsGmBZToh-xaGpi_DgPv3wBBjImey-WMu3nXc8PEhUcKq9JDCaHkzX4PFlfPTCzrIPrA6VBkmArluooN8tV5GD9UPtZR_Ws1iVqY85hhKwIJL9sTJXVz3a7T2fuXFcEiI6lF18He0uw0dx6AYDaiJPBHg40gRXK9Tc1gq2pi0NdUG3Uw0TSfQrJLi2ypCzjWLmRUC0QzObYHibRtYiwxtpVSz2tTigmRK3Ux20 Apr 20 11:40:01 minidsp-shd volumio[715]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Apr 20 11:40:01 minidsp-shd volumio[715]: info: New Spotify access token = BQCZHqw2y-5n72hBZbK3ncE_KY3HgsLADueVqMv1Rl5O73TEg1wDBgFcUJyNsQaa5qspSOS0jQsGmBZToh-xaGpi_DgPv3wBBjImey-WMu3nXc8PEhUcKq9JDCaHkzX4PFlfPTCzrIPrA6VBkmArluooN8tV5GD9UPtZR_Ws1iVqY85hhKwIJL9sTJXVz3a7T2fuXFcEiI6lF18He0uw0dx6AYDaiJPBHg40gRXK9Tc1gq2pi0NdUG3Uw0TSfQrJLi2ypCzjWLmRUC0QzObYHibRtYiwxtpVSz2tTigmRK3Ux20 Apr 20 11:40:01 minidsp-shd volumio[715]: info: Spotify credentials grant success - running version from March 24, 2019 Apr 20 11:40:01 minidsp-shd volumio[715]: info: Access Token successfully retrieved Apr 20 11:40:01 minidsp-shd volumio[715]: info: CoreCommandRouter::volumiosetSourceActiveno-source Apr 20 11:40:01 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 11:40:01 minidsp-shd volumio[715]: Cannot find translation for source Presets Apr 20 11:40:01 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:01+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Apr 20 11:40:01 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:01+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]" Apr 20 11:40:01 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:01+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Apr 20 11:40:01 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:01+02:00" level=info msg="zeroconf server listening on port 40147" Apr 20 11:40:01 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:01+02:00" level=debug msg="obtained new client token: AADAOIplrbYgUE6PbLO/HFTiU3RT2To2248Dw4zv57//5daWMUVBD8G3Pf613iMGJ3AemQnPDcEMBzjuRrm60Pq59jSBy8eWyAReFvigEB2nkehE1XbGLXb//aYeTEDeRau6cXi2fYRHfSS6NcH2YMzV1GG8U68VD9CRgv6RnKCFLdA4344CF3szptBccveIC5/RBz096TjTSzBEWvvj1TfeHIdubtuEZS81usTJu5P6ywPJafx9uySCFQ==" Apr 20 11:40:01 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:01+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Apr 20 11:40:01 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:01+02:00" level=debug msg="completed keyexchange" Apr 20 11:40:01 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:01+02:00" level=debug msg="completed challenge" Apr 20 11:40:01 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioGetState Apr 20 11:40:01 minidsp-shd volumio[715]: info: CorePlayQueue::getTrack 0 Apr 20 11:40:02 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:02+02:00" level=info msg="authenticated AP as 314pxbgb6flcekzco6ale7vxzesy" Apr 20 11:40:02 minidsp-shd volumio[715]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Apr 20 11:40:02 minidsp-shd volumio[715]: info: Successfully retrieved User Session From TIDAL Apr 20 11:40:02 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:02+02:00" level=info msg="authenticated Login5 as 314pxbgb6flcekzco6ale7vxzesy" Apr 20 11:40:02 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:02+02:00" level=debug msg="initializing zeroconf session, username: 314pxbgb6flcekzco6ale7vxzesy" Apr 20 11:40:02 minidsp-shd volumio[715]: SPOTIFY: User informations: {"country":"DE","display_name":"Kalambori","email":"kalambori@web.de","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/314pxbgb6flcekzco6ale7vxzesy"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/314pxbgb6flcekzco6ale7vxzesy","id":"314pxbgb6flcekzco6ale7vxzesy","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee8567f3d3fb18665b2d46025316","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b8267f3d3fb18665b2d46025316","width":64}],"product":"premium","type":"user","uri":"spotify:user:314pxbgb6flcekzco6ale7vxzesy"} Apr 20 11:40:02 minidsp-shd volumio[715]: info: Spotify Successfully logged in Apr 20 11:40:02 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 11:40:02 minidsp-shd volumio[715]: info: [1745142002251] CoreMusicLibrary::Adding element Spotify Apr 20 11:40:02 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 11:40:02 minidsp-shd volumio[715]: Cannot find translation for source Presets Apr 20 11:40:02 minidsp-shd volumio[715]: Cannot find translation for source Spotify Apr 20 11:40:02 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:02+02:00" level=debug msg="dealer connection opened" Apr 20 11:40:02 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:02+02:00" level=trace msg="starting accesspoint recv loop" Apr 20 11:40:02 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:02+02:00" level=trace msg="starting dealer recv loop" Apr 20 11:40:02 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:02+02:00" level=trace msg="received accesspoint ping" Apr 20 11:40:02 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:02+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 20 11:40:02 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:02+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 20 11:40:02 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:02+02:00" level=debug msg="received connection id: NGFjMGViOGMtNjMwMC00YjkyLWJlMmUtZTI0YzBmYTdlYTQ3K2RlYWxlcit0Y3A6Ly8wYWNhNThmNi5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArRjcxNUFCM0REMTdCMjJBMDk1NjI2QjRCNDRCQjZCOEFGQkQ2NkEyQjVDMDQ4QzY2MjUyODIyNTYzODRDQkRDMA==" Apr 20 11:40:02 minidsp-shd sudo[972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Apr 20 11:40:02 minidsp-shd sudo[972]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 11:40:02 minidsp-shd volumio[715]: info: Successfully retrieved User Subscription From TIDAL Apr 20 11:40:02 minidsp-shd volumio[715]: info: Adding TIDAL to Browse Sources Apr 20 11:40:02 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 11:40:02 minidsp-shd volumio[715]: info: [1745142002393] CoreMusicLibrary::Adding element TIDAL Apr 20 11:40:02 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 11:40:02 minidsp-shd volumio[715]: Cannot find translation for source Presets Apr 20 11:40:02 minidsp-shd volumio[715]: Cannot find translation for source Spotify Apr 20 11:40:02 minidsp-shd volumio[715]: Cannot find translation for source TIDAL Apr 20 11:40:02 minidsp-shd volumio[715]: info: Stopping AccessToken refresher cron Apr 20 11:40:02 minidsp-shd systemd[1]: Started Volumio Bluetooth Module. Apr 20 11:40:02 minidsp-shd sudo[972]: pam_unix(sudo:session): session closed for user root Apr 20 11:40:02 minidsp-shd sudo[975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Apr 20 11:40:02 minidsp-shd sudo[975]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 11:40:02 minidsp-shd volumiobt[974]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Apr 20 11:40:02 minidsp-shd sudo[975]: pam_unix(sudo:session): session closed for user root Apr 20 11:40:02 minidsp-shd volumio[715]: info: AccessToken refresher cron started Apr 20 11:40:02 minidsp-shd volumio[715]: info: Adding TIDAL REST API Endpoints Apr 20 11:40:02 minidsp-shd volumio[715]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Apr 20 11:40:02 minidsp-shd volumio[715]: info: Volumio BT Module successfully started Apr 20 11:40:02 minidsp-shd volumio[715]: info: TidalConnect service stoped! Apr 20 11:40:02 minidsp-shd volumio[715]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 20 11:40:02 minidsp-shd volumio[715]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 20 11:40:02 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 20 11:40:02 minidsp-shd volumio[715]: info: Enabling external Volume Control Apr 20 11:40:02 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:40:02 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 11:40:02 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:02+02:00" level=debug msg="put connect state because NEW_DEVICE" Apr 20 11:40:02 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:02+02:00" level=debug msg="update volume to 65535/65535" Apr 20 11:40:02 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:02+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Apr 20 11:40:02 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:02+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Apr 20 11:40:02 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:02+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Apr 20 11:40:02 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:02+02:00" level=trace msg="received accesspoint pong ack" Apr 20 11:40:02 minidsp-shd sudo[987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 20 11:40:02 minidsp-shd sudo[987]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 11:40:02 minidsp-shd systemd[1]: Started Volumio Tidal Connect Service. Apr 20 11:40:02 minidsp-shd sudo[987]: pam_unix(sudo:session): session closed for user root Apr 20 11:40:03 minidsp-shd volumio-remote-updater[415]: [2025-04-20 11:40:03] [connect] Successful connection Apr 20 11:40:03 minidsp-shd volumio-remote-updater[415]: [2025-04-20 11:40:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745142003 101 Apr 20 11:40:03 minidsp-shd volumio[715]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 11 Apr 20 11:40:03 minidsp-shd volumio[715]: info: Executing endpoint tc_getconfig Apr 20 11:40:03 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 20 11:40:03 minidsp-shd vtcs[989]: STARTING TidalConnect services, version: 1.3.0.19 Apr 20 11:40:03 minidsp-shd sudo[993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Apr 20 11:40:03 minidsp-shd sudo[993]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 11:40:03 minidsp-shd volumiobt[974]: pulseaudio: no process found Apr 20 11:40:03 minidsp-shd sudo[993]: pam_unix(sudo:session): session closed for user root Apr 20 11:40:03 minidsp-shd vtcs[989]: STARTED TidalConnect services. Apr 20 11:40:03 minidsp-shd volumio[715]: info: Executing endpoint tc_connect Apr 20 11:40:03 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 20 11:40:03 minidsp-shd volumio[715]: info: Connecting to TidalConnect Apr 20 11:40:03 minidsp-shd dbus-daemon[428]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.11' (uid=1000 pid=1002 comm="/usr/bin/pulseaudio --high-priority=true ") Apr 20 11:40:03 minidsp-shd volumio[715]: info: CoreCommandRouter::servicePushState Apr 20 11:40:03 minidsp-shd volumio[715]: info: CoreStateMachine::pushState Apr 20 11:40:03 minidsp-shd volumio[715]: info: CorePlayQueue::getTrack 0 Apr 20 11:40:03 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioPushState Apr 20 11:40:03 minidsp-shd volumio[715]: info: CorePlayQueue::getTrack 0 Apr 20 11:40:03 minidsp-shd volumio[715]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Apr 20 11:40:03 minidsp-shd volumio[715]: verbose: CURRENT POSITION 0 Apr 20 11:40:03 minidsp-shd volumio[715]: info: CoreStateMachine::syncState stateService stop Apr 20 11:40:03 minidsp-shd volumio[715]: info: CoreStateMachine::syncState currentStatus stop Apr 20 11:40:03 minidsp-shd volumio[715]: info: CoreStateMachine::pushState Apr 20 11:40:03 minidsp-shd volumio[715]: info: CorePlayQueue::getTrack 0 Apr 20 11:40:03 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 11:40:03 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioPushState Apr 20 11:40:03 minidsp-shd volumio[715]: info: No code Apr 20 11:40:03 minidsp-shd volumio[715]: info: CoreStateMachine::pushState Apr 20 11:40:03 minidsp-shd volumio[715]: info: CorePlayQueue::getTrack 0 Apr 20 11:40:03 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioPushState Apr 20 11:40:03 minidsp-shd systemd[1]: Starting RealtimeKit Scheduling Policy Service... Apr 20 11:40:03 minidsp-shd volumio[715]: info: CoreCommandRouter::servicePushState Apr 20 11:40:03 minidsp-shd volumio[715]: info: CoreStateMachine::pushState Apr 20 11:40:03 minidsp-shd volumio[715]: info: CorePlayQueue::getTrack 0 Apr 20 11:40:03 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioPushState Apr 20 11:40:03 minidsp-shd volumio[715]: info: CorePlayQueue::getTrack 0 Apr 20 11:40:03 minidsp-shd volumio[715]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Apr 20 11:40:03 minidsp-shd volumio[715]: verbose: CURRENT POSITION 0 Apr 20 11:40:03 minidsp-shd volumio[715]: info: CoreStateMachine::syncState stateService stop Apr 20 11:40:03 minidsp-shd volumio[715]: info: CoreStateMachine::syncState currentStatus stop Apr 20 11:40:03 minidsp-shd volumio[715]: info: CoreStateMachine::pushState Apr 20 11:40:03 minidsp-shd volumio[715]: info: CorePlayQueue::getTrack 0 Apr 20 11:40:03 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioPushState Apr 20 11:40:03 minidsp-shd volumio[715]: info: No code Apr 20 11:40:03 minidsp-shd volumio[715]: info: CoreStateMachine::pushState Apr 20 11:40:03 minidsp-shd volumio[715]: info: CorePlayQueue::getTrack 0 Apr 20 11:40:03 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioPushState Apr 20 11:40:03 minidsp-shd dbus-daemon[428]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Apr 20 11:40:03 minidsp-shd systemd[1]: Started RealtimeKit Scheduling Policy Service. Apr 20 11:40:03 minidsp-shd rtkit-daemon[1005]: Successfully called chroot. Apr 20 11:40:03 minidsp-shd rtkit-daemon[1005]: Successfully dropped privileges. Apr 20 11:40:03 minidsp-shd rtkit-daemon[1005]: Successfully limited resources. Apr 20 11:40:03 minidsp-shd rtkit-daemon[1005]: Running. Apr 20 11:40:03 minidsp-shd rtkit-daemon[1005]: Watchdog thread running. Apr 20 11:40:03 minidsp-shd dbus-daemon[428]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.13' (uid=0 pid=1005 comm="/usr/lib/rtkit/rtkit-daemon ") Apr 20 11:40:03 minidsp-shd rtkit-daemon[1005]: Canary thread running. Apr 20 11:40:03 minidsp-shd systemd[1]: Starting Authorization Manager... Apr 20 11:40:03 minidsp-shd volumio[715]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 11:40:03 minidsp-shd volumio[715]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 11:40:03 minidsp-shd volumio[715]: info: CoreStateMachine::pushState Apr 20 11:40:03 minidsp-shd volumio[715]: info: CorePlayQueue::getTrack 0 Apr 20 11:40:03 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 11:40:03 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioPushState Apr 20 11:40:03 minidsp-shd polkitd[1015]: started daemon version 0.105 using authority implementation `local' version `0.105' Apr 20 11:40:03 minidsp-shd dbus-daemon[428]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Apr 20 11:40:03 minidsp-shd systemd[1]: Started Authorization Manager. Apr 20 11:40:03 minidsp-shd volumio[715]: info: go-librespot daemon successfully initialized Apr 20 11:40:03 minidsp-shd mpd[913]: Apr 20 11:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 20 11:40:04 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:04+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Apr 20 11:40:04 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:04+02:00" level=trace msg="emitting websocket event: volume" Apr 20 11:40:04 minidsp-shd systemd[1]: Started Music Player Daemon. Apr 20 11:40:04 minidsp-shd sudo[901]: pam_unix(sudo:session): session closed for user root Apr 20 11:40:04 minidsp-shd sudo[908]: pam_unix(sudo:session): session closed for user root Apr 20 11:40:04 minidsp-shd volumio[715]: Upnp client error: Error: This socket has been ended by the other party Apr 20 11:40:04 minidsp-shd dbus-daemon[428]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.33' (uid=1000 pid=1002 comm="/usr/bin/pulseaudio --high-priority=true ") Apr 20 11:40:04 minidsp-shd systemd[1]: Starting Bluetooth service... Apr 20 11:40:04 minidsp-shd bluetoothd[1024]: Bluetooth daemon 5.23 Apr 20 11:40:04 minidsp-shd dbus-daemon[428]: [system] Successfully activated service 'org.bluez' Apr 20 11:40:04 minidsp-shd bluetoothd[1024]: Starting SDP server Apr 20 11:40:04 minidsp-shd systemd[1]: Started Bluetooth service. Apr 20 11:40:04 minidsp-shd volumio[715]: error: MPD error: The expression evaluated to a falsy value: Apr 20 11:40:04 minidsp-shd volumio[715]: assert.ok(self.idling) Apr 20 11:40:04 minidsp-shd volumio[715]: error: The expression evaluated to a falsy value: Apr 20 11:40:04 minidsp-shd volumio[715]: assert.ok(self.idling) Apr 20 11:40:04 minidsp-shd volumio[715]: info: MPD running with PID913 Apr 20 11:40:04 minidsp-shd volumio[715]: ,establishing connection Apr 20 11:40:04 minidsp-shd volumio[715]: error: updateQueue error: null Apr 20 11:40:04 minidsp-shd pulseaudio[1002]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Apr 20 11:40:04 minidsp-shd kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Apr 20 11:40:04 minidsp-shd kernel: Bluetooth: BNEP filters: protocol multicast Apr 20 11:40:04 minidsp-shd kernel: Bluetooth: BNEP socket layer initialized Apr 20 11:40:04 minidsp-shd pulseaudio[1002]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Apr 20 11:40:04 minidsp-shd volumiobt[974]: Applying permissions Apr 20 11:40:04 minidsp-shd volumiobt[974]: Setting BT discoverable and pairable Apr 20 11:40:04 minidsp-shd dbus-daemon[428]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.34' (uid=0 pid=1024 comm="/usr/local/libexec/bluetooth/bluetoothd ") Apr 20 11:40:04 minidsp-shd bluetoothd[1024]: Bluetooth management interface 1.14 initialized Apr 20 11:40:04 minidsp-shd volumio[715]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 11:40:04 minidsp-shd pulseaudio[1002]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Apr 20 11:40:04 minidsp-shd systemd[1]: Starting Hostname Service... Apr 20 11:40:04 minidsp-shd volumio[715]: error: updateQueue error: null Apr 20 11:40:04 minidsp-shd volumiobt[974]: [38B blob data] Apr 20 11:40:04 minidsp-shd volumiobt[974]: Invalid command Apr 20 11:40:04 minidsp-shd volumiobt[974]: [bluetooth]# agent on Apr 20 11:40:04 minidsp-shd volumiobt[974]: [bluetooth]# discoverable on Apr 20 11:40:04 minidsp-shd volumiobt[974]: No default controller available Apr 20 11:40:04 minidsp-shd volumiobt[974]: [bluetooth]# pairable on Apr 20 11:40:04 minidsp-shd volumiobt[974]: No default controller available Apr 20 11:40:04 minidsp-shd volumiobt[974]: [bluetooth]# agent NoInputNoOutput Apr 20 11:40:04 minidsp-shd volumiobt[974]: Failed to register agent object Apr 20 11:40:04 minidsp-shd volumiobt[974]: [bluetooth]# default-agent Apr 20 11:40:04 minidsp-shd volumiobt[974]: No agent is registered Apr 20 11:40:04 minidsp-shd volumiobt[974]: [bluetooth]# quit Apr 20 11:40:04 minidsp-shd volumiobt[974]: [61B blob data] Apr 20 11:40:04 minidsp-shd volumio[715]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 11:40:04 minidsp-shd volumiobt[974]: [62B blob data] Apr 20 11:40:04 minidsp-shd volumio[947]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 20 11:40:04 minidsp-shd dbus-daemon[428]: [system] Successfully activated service 'org.freedesktop.hostname1' Apr 20 11:40:04 minidsp-shd systemd[1]: Started Hostname Service. Apr 20 11:40:04 minidsp-shd volumio[715]: info: CoreStateMachine::pushState Apr 20 11:40:04 minidsp-shd volumio[715]: info: CorePlayQueue::getTrack 0 Apr 20 11:40:04 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 11:40:04 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioPushState Apr 20 11:40:04 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Apr 20 11:40:05 minidsp-shd volumio[715]: info: TidalConnect service started! Apr 20 11:40:05 minidsp-shd volumio[715]: info: Completed starting Core Plugins Apr 20 11:40:05 minidsp-shd volumio[715]: info: ------------------------------------------- Apr 20 11:40:05 minidsp-shd volumio[715]: info: ----- MyVolumio plugins startup ---- Apr 20 11:40:05 minidsp-shd volumio[715]: info: ------------------------------------------- Apr 20 11:40:05 minidsp-shd volumio[715]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 20 11:40:06 minidsp-shd volumio[715]: info: Initializing connection to go-librespot Websocket Apr 20 11:40:06 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:06+02:00" level=debug msg="new websocket client" Apr 20 11:40:06 minidsp-shd volumio[715]: info: Connection to go-librespot Websocket established Apr 20 11:40:09 minidsp-shd volumio[715]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 20 11:40:09 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:09+02:00" level=debug msg="handling transfer player command from 75c65fd70fdf5231b6f70daafbcbb971b6253786" Apr 20 11:40:09 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:09+02:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1DZ06evO3v7wbM" Apr 20 11:40:09 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:09+02:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:playlist:37i9dQZF1DZ06evO3v7wbM" Apr 20 11:40:09 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:09+02:00" level=debug msg="loading track (paused: true, position: 1162ms)" uri="spotify:track:1aFJJrP1R0xIoypSRaA8dQ" Apr 20 11:40:09 minidsp-shd volumio[715]: info: Getting Spotify volume Apr 20 11:40:09 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:09+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 20 11:40:09 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:09+02:00" level=trace msg="emitting websocket event: will_play" Apr 20 11:40:10 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:10+02:00" level=debug msg="selected format OGG_VORBIS_320 (a6ea83b97d561fe2b321c7f5642b4986a9e1c60c)" uri="spotify:track:1aFJJrP1R0xIoypSRaA8dQ" Apr 20 11:40:10 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:10+02:00" level=debug msg="requested aes key for file a6ea83b97d561fe2b321c7f5642b4986a9e1c60c, gid: 1aFJJrP1R0xIoypSRaA8dQ" Apr 20 11:40:10 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Apr 20 11:40:10 minidsp-shd volumio[715]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1aFJJrP1R0xIoypSRaA8dQ","play_origin":"playlist"}} Apr 20 11:40:10 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:10+02:00" level=debug msg="fetched first chunk of 20, total size is 9999588 bytes" uri="spotify:track:1aFJJrP1R0xIoypSRaA8dQ" Apr 20 11:40:10 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:10+02:00" level=trace msg="seek to 1162ms (diff: 318ms, samples: 51244, bytes: 16050)" uri="spotify:track:1aFJJrP1R0xIoypSRaA8dQ" Apr 20 11:40:10 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:10+02:00" level=debug msg="created new output device" Apr 20 11:40:10 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:10+02:00" level=info msg="loaded track \"After Life\" (paused: true, position: 1162ms, duration: 203893ms, prefetched: false)" uri="spotify:track:1aFJJrP1R0xIoypSRaA8dQ" Apr 20 11:40:10 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:10+02:00" level=debug msg="fetched chunk 1/19, size: 524288" uri="spotify:track:1aFJJrP1R0xIoypSRaA8dQ" Apr 20 11:40:10 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:10+02:00" level=debug msg="fetched chunk 3/19, size: 524288" uri="spotify:track:1aFJJrP1R0xIoypSRaA8dQ" Apr 20 11:40:10 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:10+02:00" level=debug msg="fetched chunk 2/19, size: 524288" uri="spotify:track:1aFJJrP1R0xIoypSRaA8dQ" Apr 20 11:40:10 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:10+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 20 11:40:10 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:10+02:00" level=trace msg="emitting websocket event: metadata" Apr 20 11:40:10 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:10+02:00" level=trace msg="emitting websocket event: active" Apr 20 11:40:10 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:10+02:00" level=debug msg="sending successful reply for dealer request" Apr 20 11:40:10 minidsp-shd volumio[715]: Cannot compose Albumart path Apr 20 11:40:11 minidsp-shd volumio[715]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12 Apr 20 11:40:11 minidsp-shd volumio[715]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1aFJJrP1R0xIoypSRaA8dQ","name":"After Life","artist_names":["BAND-MAID"],"album_name":"Unseen World","album_cover_url":"https://i.scdn.co/image/ab67616d00001e029895e76d19c8bd3bb148d0b4","position":1162,"duration":203893,"release_date":"year:2021 month:1 day:13","track_number":3,"disc_number":1}} Apr 20 11:40:11 minidsp-shd volumio[715]: SPOTIFY: received: {"type":"active","data":null} Apr 20 11:40:11 minidsp-shd volumio[715]: info: Aligning Spotify Volume to Volumio Volume Apr 20 11:40:11 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioGetState Apr 20 11:40:11 minidsp-shd volumio[715]: info: CorePlayQueue::getTrack 0 Apr 20 11:40:11 minidsp-shd volumio[715]: info: Setting Spotify Volume from Volumio: 75 Apr 20 11:40:11 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:11+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 20 11:40:11 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:11+02:00" level=trace msg="emitting websocket event: paused" Apr 20 11:40:11 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:11+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 242" Apr 20 11:40:11 minidsp-shd go-librespot[950]: time="2025-04-20T11:40:11+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Apr 20 11:40:11 minidsp-shd volumio[715]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:1aFJJrP1R0xIoypSRaA8dQ","play_origin":"playlist"}} Apr 20 11:40:11 minidsp-shd volumio[715]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 11:40:11 minidsp-shd volumio[715]: TypeError: Cannot read property 'service' of undefined Apr 20 11:40:11 minidsp-shd volumio[715]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:340:50) Apr 20 11:40:11 minidsp-shd volumio[715]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:274:18) Apr 20 11:40:11 minidsp-shd volumio[715]: at WebSocket.message (/data/plugins/music_service/spop/index.js:193:14) Apr 20 11:40:11 minidsp-shd volumio[715]: at WebSocket.emit (events.js:400:28) Apr 20 11:40:11 minidsp-shd volumio[715]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Apr 20 11:40:11 minidsp-shd volumio[715]: at Receiver.emit (events.js:400:28) Apr 20 11:40:11 minidsp-shd volumio[715]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Apr 20 11:40:11 minidsp-shd volumio[715]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Apr 20 11:40:11 minidsp-shd volumio[715]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Apr 20 11:40:11 minidsp-shd volumio[715]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Apr 20 11:40:11 minidsp-shd volumio[715]: at writeOrBuffer (internal/streams/writable.js:358:12) Apr 20 11:40:11 minidsp-shd volumio[715]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Apr 20 11:40:11 minidsp-shd volumio[715]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Apr 20 11:40:11 minidsp-shd volumio[715]: at Socket.emit (events.js:400:28) Apr 20 11:40:11 minidsp-shd volumio[715]: at addChunk (internal/streams/readable.js:293:12) Apr 20 11:40:11 minidsp-shd volumio[715]: at readableAddChunk (internal/streams/readable.js:267:9) Apr 20 11:40:11 minidsp-shd volumio[715]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 11:40:11 minidsp-shd sudo[1056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-20 11:39 Apr 20 11:40:11 minidsp-shd sudo[1056]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:00:28 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo2" VOLUMIO_DEVICENAME="NanoPi Neo2" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="29ccfe8a9cff19911db4b736879ac117"