-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Mon 2026-01-26 20:57:32 CET. -- Jan 26 20:56:25 musicbox kernel: Console: switching to colour frame buffer device 80x30 Jan 26 20:56:25 musicbox kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Jan 26 20:56:25 musicbox kernel: iproc-rng200 fe104000.rng: hwrng registered Jan 26 20:56:25 musicbox kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Jan 26 20:56:25 musicbox kernel: brd: module loaded Jan 26 20:56:25 musicbox kernel: loop: module loaded Jan 26 20:56:25 musicbox kernel: Loading iSCSI transport class v2.0-870. Jan 26 20:56:25 musicbox kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Jan 26 20:56:25 musicbox kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Jan 26 20:56:25 musicbox kernel: usbcore: registered new device driver r8152-cfgselector Jan 26 20:56:25 musicbox kernel: usbcore: registered new interface driver r8152 Jan 26 20:56:25 musicbox kernel: usbcore: registered new interface driver lan78xx Jan 26 20:56:25 musicbox kernel: usbcore: registered new interface driver smsc95xx Jan 26 20:56:25 musicbox kernel: xhci_hcd 0000:01:00.0: enabling device (0140 -> 0142) Jan 26 20:56:25 musicbox kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Jan 26 20:56:25 musicbox kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Jan 26 20:56:25 musicbox kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0700240000000890 Jan 26 20:56:25 musicbox kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Jan 26 20:56:25 musicbox kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Jan 26 20:56:25 musicbox kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Jan 26 20:56:25 musicbox kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Jan 26 20:56:25 musicbox kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 26 20:56:25 musicbox kernel: usb usb1: Product: xHCI Host Controller Jan 26 20:56:25 musicbox kernel: usb usb1: Manufacturer: Linux 6.6.62-v7l+ xhci-hcd Jan 26 20:56:25 musicbox kernel: usb usb1: SerialNumber: 0000:01:00.0 Jan 26 20:56:25 musicbox kernel: hub 1-0:1.0: USB hub found Jan 26 20:56:25 musicbox kernel: hub 1-0:1.0: 1 port detected Jan 26 20:56:25 musicbox kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06 Jan 26 20:56:25 musicbox kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 26 20:56:25 musicbox kernel: usb usb2: Product: xHCI Host Controller Jan 26 20:56:25 musicbox kernel: usb usb2: Manufacturer: Linux 6.6.62-v7l+ xhci-hcd Jan 26 20:56:25 musicbox kernel: usb usb2: SerialNumber: 0000:01:00.0 Jan 26 20:56:25 musicbox kernel: hub 2-0:1.0: USB hub found Jan 26 20:56:25 musicbox kernel: hub 2-0:1.0: 4 ports detected Jan 26 20:56:25 musicbox kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Jan 26 20:56:25 musicbox kernel: dwc_otg: FIQ enabled Jan 26 20:56:25 musicbox kernel: dwc_otg: NAK holdoff enabled Jan 26 20:56:25 musicbox kernel: dwc_otg: FIQ split-transaction FSM enabled Jan 26 20:56:25 musicbox kernel: Module dwc_common_port init Jan 26 20:56:25 musicbox kernel: usbcore: registered new interface driver uas Jan 26 20:56:25 musicbox kernel: usbcore: registered new interface driver usb-storage Jan 26 20:56:25 musicbox kernel: mousedev: PS/2 mouse device common for all mice Jan 26 20:56:25 musicbox kernel: sdhci: Secure Digital Host Controller Interface driver Jan 26 20:56:25 musicbox kernel: sdhci: Copyright(c) Pierre Ossman Jan 26 20:56:25 musicbox kernel: sdhci-pltfm: SDHCI platform and OF driver helper Jan 26 20:56:25 musicbox kernel: ledtrig-cpu: registered to indicate activity on CPUs Jan 26 20:56:25 musicbox kernel: hid: raw HID events driver (C) Jiri Kosina Jan 26 20:56:25 musicbox kernel: usbcore: registered new interface driver usbhid Jan 26 20:56:25 musicbox kernel: usbhid: USB HID core driver Jan 26 20:56:25 musicbox kernel: Initializing XFRM netlink socket Jan 26 20:56:25 musicbox kernel: NET: Registered PF_PACKET protocol family Jan 26 20:56:25 musicbox kernel: Key type dns_resolver registered Jan 26 20:56:25 musicbox kernel: Registering SWP/SWPB emulation handler Jan 26 20:56:25 musicbox kernel: registered taskstats version 1 Jan 26 20:56:25 musicbox kernel: Loading compiled-in X.509 certificates Jan 26 20:56:25 musicbox kernel: Key type .fscrypt registered Jan 26 20:56:25 musicbox kernel: Key type fscrypt-provisioning registered Jan 26 20:56:25 musicbox kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Jan 26 20:56:25 musicbox kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Jan 26 20:56:25 musicbox kernel: fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 51, base_baud = 0) is a PL011 rev2 Jan 26 20:56:25 musicbox kernel: serial serial0: tty port ttyAMA1 registered Jan 26 20:56:25 musicbox kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Jan 26 20:56:25 musicbox kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Jan 26 20:56:25 musicbox kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Jan 26 20:56:25 musicbox kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Jan 26 20:56:25 musicbox kernel: of_cfs_init Jan 26 20:56:25 musicbox kernel: of_cfs_init: OK Jan 26 20:56:25 musicbox kernel: clk: Disabling unused clocks Jan 26 20:56:25 musicbox kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Jan 26 20:56:25 musicbox kernel: Freeing unused kernel image (initmem) memory: 2048K Jan 26 20:56:25 musicbox kernel: Run /init as init process Jan 26 20:56:25 musicbox kernel: with arguments: Jan 26 20:56:25 musicbox kernel: /init Jan 26 20:56:25 musicbox kernel: splash Jan 26 20:56:25 musicbox kernel: nodebug Jan 26 20:56:25 musicbox kernel: with environment: Jan 26 20:56:25 musicbox kernel: HOME=/ Jan 26 20:56:25 musicbox kernel: TERM=linux Jan 26 20:56:25 musicbox kernel: numa_policy=interleave Jan 26 20:56:25 musicbox kernel: imgpart=UUID=3d50dda4-5a91-43d8-9944-55c5fa885e3d Jan 26 20:56:25 musicbox kernel: imgfile=/volumio_current.sqsh Jan 26 20:56:25 musicbox kernel: bootpart=UUID=55B9-11AE Jan 26 20:56:25 musicbox kernel: datapart=UUID=b4fb69a6-64f0-46fe-8f28-829741570b9f Jan 26 20:56:25 musicbox kernel: uuidconfig=cmdline.txt Jan 26 20:56:25 musicbox kernel: pcie_aspm=off Jan 26 20:56:25 musicbox kernel: bootdelay=7 Jan 26 20:56:25 musicbox kernel: use_kmsg=no Jan 26 20:56:25 musicbox kernel: mmc1: new high speed SDIO card at address 0001 Jan 26 20:56:25 musicbox kernel: mmc0: new ultra high speed DDR50 SDHC card at address aaaa Jan 26 20:56:25 musicbox kernel: mmcblk0: mmc0:aaaa SB16G 14.8 GiB Jan 26 20:56:25 musicbox kernel: mmcblk0: p1 p2 p3 Jan 26 20:56:25 musicbox kernel: mmcblk0: mmc0:aaaa SB16G 14.8 GiB (quirks 0x0000c000) Jan 26 20:56:25 musicbox kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Jan 26 20:56:25 musicbox kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.20 Jan 26 20:56:25 musicbox kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Jan 26 20:56:25 musicbox kernel: usb 1-1: Product: USB2.0 Hub Jan 26 20:56:25 musicbox kernel: hub 1-1:1.0: USB hub found Jan 26 20:56:25 musicbox kernel: hub 1-1:1.0: 4 ports detected Jan 26 20:56:25 musicbox kernel: fuse: init (API version 7.39) Jan 26 20:56:25 musicbox kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jan 26 20:56:25 musicbox kernel: EXT4-fs (mmcblk0p2): recovery complete Jan 26 20:56:25 musicbox kernel: EXT4-fs (mmcblk0p2): mounted filesystem 3d50dda4-5a91-43d8-9944-55c5fa885e3d r/w with ordered data mode. Quota mode: none. Jan 26 20:56:25 musicbox kernel: loop0: detected capacity change from 0 to 1005568 Jan 26 20:56:25 musicbox kernel: EXT4-fs (mmcblk0p3): recovery complete Jan 26 20:56:25 musicbox kernel: EXT4-fs (mmcblk0p3): mounted filesystem b4fb69a6-64f0-46fe-8f28-829741570b9f r/w with ordered data mode. Quota mode: none. Jan 26 20:56:25 musicbox systemd[1]: System time before build time, advancing clock. Jan 26 20:56:25 musicbox kernel: NET: Registered PF_INET6 protocol family Jan 26 20:56:25 musicbox kernel: Segment Routing with IPv6 Jan 26 20:56:25 musicbox kernel: In-situ OAM (IOAM) with IPv6 Jan 26 20:56:25 musicbox 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) Jan 26 20:56:25 musicbox systemd[1]: Detected architecture arm. Jan 26 20:56:25 musicbox systemd[1]: Set hostname to . Jan 26 20:56:25 musicbox 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. Jan 26 20:56:25 musicbox systemd[1]: Listening on udev Control Socket. Jan 26 20:56:25 musicbox systemd[1]: Listening on Journal Socket (/dev/log). Jan 26 20:56:25 musicbox systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Jan 26 20:56:25 musicbox systemd[1]: Listening on initctl Compatibility Named Pipe. Jan 26 20:56:25 musicbox systemd[1]: Created slice User and Session Slice. Jan 26 20:56:25 musicbox systemd[1]: Reached target Slices. Jan 26 20:56:25 musicbox kernel: i2c_dev: i2c /dev entries driver Jan 26 20:56:25 musicbox systemd-journald[311]: Journal started Jan 26 20:56:25 musicbox systemd-journald[311]: Runtime journal (/run/log/journal/c28a768762e55f60d289ca8f665f5f47) is 7.5M, max 30.0M, 22.5M free. Feb 14 11:11:59 musicbox systemd-modules-load[309]: Inserted module 'i2c_dev' Jan 26 20:56:25 musicbox fake-hwclock[305]: Mon Jan 26 19:56:25 UTC 2026 Jan 26 20:56:25 musicbox systemd[1]: Mounted Kernel Debug File System. Jan 26 20:56:25 musicbox systemd[1]: Starting Create System Users... Jan 26 20:56:25 musicbox systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jan 26 20:56:25 musicbox systemd[1]: Starting Load/Save Random Seed... Jan 26 20:56:25 musicbox systemd[1]: Mounting FUSE Control File System... Jan 26 20:56:25 musicbox systemd[1]: Mounting Kernel Configuration File System... Jan 26 20:56:25 musicbox systemd[1]: Starting Apply Kernel Variables... Jan 26 20:56:25 musicbox systemd[1]: Mounted FUSE Control File System. Jan 26 20:56:25 musicbox systemd[1]: Mounted Kernel Configuration File System. Jan 26 20:56:25 musicbox systemd[1]: dynamicswap.service: Succeeded. Jan 26 20:56:25 musicbox systemd[1]: Started Load/Save Random Seed. Jan 26 20:56:25 musicbox systemd[1]: Started Create System Users. Jan 26 20:56:25 musicbox systemd[1]: Started Apply Kernel Variables. Jan 26 20:56:25 musicbox systemd[1]: Starting Create Static Device Nodes in /dev... Jan 26 20:56:25 musicbox systemd[1]: Started udev Coldplug all Devices. Jan 26 20:56:25 musicbox systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jan 26 20:56:25 musicbox systemd[1]: Started Create Static Device Nodes in /dev. Jan 26 20:56:25 musicbox systemd[1]: Reached target Local File Systems (Pre). Jan 26 20:56:25 musicbox systemd[1]: Mounting /var/spool/cups... Jan 26 20:56:25 musicbox systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jan 26 20:56:25 musicbox systemd[1]: Mounting /tmp... Jan 26 20:56:25 musicbox systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jan 26 20:56:25 musicbox systemd[1]: Mounting /var/log... Jan 26 20:56:25 musicbox systemd[1]: Starting udev Kernel Device Manager... Jan 26 20:56:25 musicbox systemd[1]: Mounted /var/spool/cups. Jan 26 20:56:25 musicbox systemd[1]: Mounted /tmp. Jan 26 20:56:25 musicbox systemd[1]: Mounted /var/log. Jan 26 20:56:25 musicbox systemd[1]: Starting Flush Journal to Persistent Storage... Jan 26 20:56:25 musicbox systemd[1]: Mounting /var/spool/cups/tmp... Jan 26 20:56:25 musicbox systemd[1]: Mounted /var/spool/cups/tmp. Jan 26 20:56:25 musicbox systemd-udevd[339]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jan 26 20:56:25 musicbox systemd-journald[311]: Runtime journal (/run/log/journal/c28a768762e55f60d289ca8f665f5f47) is 7.5M, max 30.0M, 22.5M free. Jan 26 20:56:25 musicbox systemd[1]: Started Flush Journal to Persistent Storage. Jan 26 20:56:25 musicbox systemd[1]: Started udev Kernel Device Manager. Jan 26 20:56:25 musicbox systemd[1]: Starting Show Plymouth Boot Screen... Jan 26 20:56:25 musicbox systemd[1]: plymouth-start.service: Succeeded. Jan 26 20:56:25 musicbox systemd[1]: Started Show Plymouth Boot Screen. Jan 26 20:56:25 musicbox systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 26 20:56:25 musicbox systemd[1]: Reached target Local Encrypted Volumes. Jan 26 20:56:25 musicbox systemd[1]: Reached target Paths. Jan 26 20:56:25 musicbox systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jan 26 20:56:25 musicbox systemd-udevd[352]: Using default interface naming scheme 'v240'. Jan 26 20:56:25 musicbox systemd-udevd[352]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 26 20:56:26 musicbox kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jan 26 20:56:26 musicbox kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jan 26 20:56:26 musicbox kernel: [vc_sm_connected_init]: start Jan 26 20:56:26 musicbox kernel: mc: Linux media interface: v0.10 Jan 26 20:56:26 musicbox kernel: [vc_sm_connected_init]: installed successfully Jan 26 20:56:26 musicbox kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Jan 26 20:56:26 musicbox kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Jan 26 20:56:26 musicbox kernel: videodev: Linux video capture interface: v2.00 Jan 26 20:56:26 musicbox kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 26 20:56:26 musicbox kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jan 26 20:56:26 musicbox kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jan 26 20:56:26 musicbox kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jan 26 20:56:26 musicbox kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jan 26 20:56:26 musicbox kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jan 26 20:56:26 musicbox kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jan 26 20:56:26 musicbox kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jan 26 20:56:26 musicbox kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jan 26 20:56:26 musicbox kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Jan 26 20:56:26 musicbox kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Jan 26 20:56:26 musicbox kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Jan 26 20:56:26 musicbox kernel: rpivid feb10000.codec: Device registered as /dev/video19 Jan 26 20:56:26 musicbox kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jan 26 20:56:26 musicbox kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Jan 26 20:56:26 musicbox kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Jan 26 20:56:26 musicbox kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jan 26 20:56:26 musicbox kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jan 26 20:56:26 musicbox kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jan 26 20:56:26 musicbox kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jan 26 20:56:26 musicbox kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jan 26 20:56:26 musicbox kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jan 26 20:56:26 musicbox kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jan 26 20:56:26 musicbox kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jan 26 20:56:26 musicbox kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Jan 26 20:56:26 musicbox kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Jan 26 20:56:26 musicbox kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Jan 26 20:56:26 musicbox kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Jan 26 20:56:26 musicbox kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jan 26 20:56:26 musicbox kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jan 26 20:56:26 musicbox kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jan 26 20:56:26 musicbox kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jan 26 20:56:26 musicbox kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jan 26 20:56:26 musicbox kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jan 26 20:56:26 musicbox kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Jan 26 20:56:26 musicbox systemd[1]: Found device /dev/disk/by-uuid/55B9-11AE. Jan 26 20:56:26 musicbox kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jan 26 20:56:26 musicbox kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jan 26 20:56:26 musicbox kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jan 26 20:56:26 musicbox kernel: Bluetooth: Core ver 2.22 Jan 26 20:56:26 musicbox kernel: NET: Registered PF_BLUETOOTH protocol family Jan 26 20:56:26 musicbox kernel: Bluetooth: HCI device and connection manager initialized Jan 26 20:56:26 musicbox kernel: Bluetooth: HCI socket layer initialized Jan 26 20:56:26 musicbox kernel: Bluetooth: L2CAP socket layer initialized Jan 26 20:56:26 musicbox kernel: Bluetooth: SCO socket layer initialized Jan 26 20:56:26 musicbox systemd[1]: Starting File System Check on /dev/disk/by-uuid/55B9-11AE... Jan 26 20:56:26 musicbox kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Jan 26 20:56:26 musicbox kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Jan 26 20:56:26 musicbox kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jan 26 20:56:27 musicbox kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jan 26 20:56:27 musicbox systemd[1]: Started File System Check Daemon to report status. Jan 26 20:56:27 musicbox kernel: Bluetooth: HCI UART driver ver 2.3 Jan 26 20:56:27 musicbox kernel: Bluetooth: HCI UART protocol H4 registered Jan 26 20:56:27 musicbox kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jan 26 20:56:27 musicbox kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Jan 26 20:56:27 musicbox kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Jan 26 20:56:27 musicbox kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Jan 26 20:56:27 musicbox kernel: Bluetooth: HCI UART protocol Broadcom registered Jan 26 20:56:27 musicbox kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Jan 26 20:56:27 musicbox kernel: usbcore: registered new interface driver brcmfmac Jan 26 20:56:27 musicbox kernel: uart-pl011 fe201000.serial: no DMA platform data Jan 26 20:56:27 musicbox systemd-udevd[351]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 26 20:56:27 musicbox systemd-fsck[414]: fsck.fat 4.1 (2017-01-24) Jan 26 20:56:27 musicbox systemd-fsck[414]: /dev/mmcblk0p1: 412 files, 41776/46774 clusters Jan 26 20:56:27 musicbox systemd[1]: Started File System Check on /dev/disk/by-uuid/55B9-11AE. Jan 26 20:56:27 musicbox kernel: wm8804 1-003b: revision E Jan 26 20:56:27 musicbox kernel: Bluetooth: hci0: BCM: chip id 107 Jan 26 20:56:27 musicbox kernel: Bluetooth: hci0: BCM: features 0x2f Jan 26 20:56:27 musicbox kernel: Bluetooth: hci0: BCM4345C0 Jan 26 20:56:27 musicbox kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Jan 26 20:56:27 musicbox kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Jan 26 20:56:27 musicbox kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Jan 26 20:56:27 musicbox kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov 1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60 Jan 26 20:56:27 musicbox systemd-udevd[353]: Using default interface naming scheme 'v240'. Jan 26 20:56:27 musicbox systemd-udevd[353]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 26 20:56:27 musicbox systemd[1]: Found device /sys/subsystem/net/devices/eth0. Jan 26 20:56:28 musicbox kernel: alsactl[469]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Jan 26 20:56:28 musicbox systemd[1]: Created slice system-bthelper.slice. Jan 26 20:56:28 musicbox systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jan 26 20:56:28 musicbox systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jan 26 20:56:28 musicbox kernel: Bluetooth: hci0: BCM: features 0x2f Jan 26 20:56:28 musicbox kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Jan 26 20:56:28 musicbox kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Jan 26 20:56:28 musicbox systemd[1]: Starting Show Plymouth Boot Screen... Jan 26 20:56:28 musicbox systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jan 26 20:56:28 musicbox systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jan 26 20:56:28 musicbox systemd[1]: Mounting /boot... Jan 26 20:56:28 musicbox systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Jan 26 20:56:28 musicbox systemd[1]: Mounted /boot. Jan 26 20:56:28 musicbox systemd[1]: Starting Load/Save RF Kill Switch Status... Jan 26 20:56:28 musicbox systemd[1]: Reached target Local File Systems. Jan 26 20:56:28 musicbox systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jan 26 20:56:28 musicbox systemd[1]: Started ifup for eth0. Jan 26 20:56:28 musicbox systemd[1]: Starting Preprocess NFS configuration... Jan 26 20:56:28 musicbox systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jan 26 20:56:28 musicbox systemd[1]: Starting Create Volatile Files and Directories... Jan 26 20:56:28 musicbox systemd[1]: Started ifup for wlan0. Jan 26 20:56:28 musicbox systemd[1]: plymouth-start.service: Succeeded. Jan 26 20:56:28 musicbox systemd[1]: Started Show Plymouth Boot Screen. Jan 26 20:56:28 musicbox systemd[1]: plymouth-read-write.service: Succeeded. Jan 26 20:56:28 musicbox systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jan 26 20:56:28 musicbox systemd[1]: Received SIGRTMIN+20 from PID 194 (plymouthd). Jan 26 20:56:28 musicbox systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 26 20:56:28 musicbox systemd[1]: Started Load/Save RF Kill Switch Status. Jan 26 20:56:28 musicbox systemd[1]: nfs-config.service: Succeeded. Jan 26 20:56:28 musicbox systemd[1]: Started Preprocess NFS configuration. Jan 26 20:56:28 musicbox systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jan 26 20:56:28 musicbox systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jan 26 20:56:28 musicbox systemd[1]: Reached target NFS client services. Jan 26 20:56:28 musicbox kernel: hwmon hwmon1: Undervoltage detected! Jan 26 20:56:28 musicbox systemd[1]: Started Create Volatile Files and Directories. Jan 26 20:56:28 musicbox kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Jan 26 20:56:28 musicbox systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jan 26 20:56:28 musicbox systemd[1]: Starting RPC bind portmap service... Jan 26 20:56:28 musicbox systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jan 26 20:56:28 musicbox systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jan 26 20:56:28 musicbox kernel: 8021q: 802.1Q VLAN Support v1.8 Jan 26 20:56:28 musicbox systemd[1]: Started Update UTMP about System Boot/Shutdown. Jan 26 20:56:28 musicbox systemd[1]: Reached target System Initialization. Jan 26 20:56:28 musicbox systemd[1]: Started Daily apt download activities. Jan 26 20:56:28 musicbox systemd[1]: Listening on triggerhappy.socket. Jan 26 20:56:28 musicbox kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 26 20:56:28 musicbox systemd[1]: Listening on D-Bus System Message Bus Socket. Jan 26 20:56:28 musicbox systemd[1]: Started Daily Cleanup of Temporary Directories. Jan 26 20:56:28 musicbox systemd[1]: Started Daily apt upgrade and clean activities. Jan 26 20:56:28 musicbox systemd[1]: Reached target Timers. Jan 26 20:56:28 musicbox systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jan 26 20:56:28 musicbox systemd[1]: Reached target Sockets. Jan 26 20:56:28 musicbox systemd[1]: Reached target Basic System. Jan 26 20:56:28 musicbox systemd[1]: Starting Volumio Time Update Utility... Jan 26 20:56:28 musicbox systemd[1]: Starting triggerhappy global hotkey daemon... Jan 26 20:56:28 musicbox systemd[1]: Starting Login Service... Jan 26 20:56:28 musicbox systemd[1]: Starting Wireless Services... Jan 26 20:56:29 musicbox systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jan 26 20:56:29 musicbox systemd[1]: Started volumio-remote-updater.service. Jan 26 20:56:29 musicbox volumio-time-update[545]: volumio-time-update-util: Fetching time from Volumio... Jan 26 20:56:29 musicbox systemd[1]: Started Volumio Iptables Module. Jan 26 20:56:29 musicbox systemd[1]: Starting Configure Bluetooth Modems connected by UART... Jan 26 20:56:29 musicbox systemd[1]: Started Manage Sound Card State (restore and store). Jan 26 20:56:29 musicbox systemd[1]: Starting Save/Restore Sound Card State... Jan 26 20:56:29 musicbox thd[552]: Unable to parse trigger line: Jan 26 20:56:29 musicbox thd[552]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jan 26 20:56:29 musicbox thd[552]: Unable to parse trigger line: Jan 26 20:56:29 musicbox alsactl[563]: alsactl 1.1.8 daemon started Jan 26 20:56:29 musicbox thd[552]: Unable to parse trigger line: Jan 26 20:56:29 musicbox thd[552]: Found socket passed from systemd Jan 26 20:56:29 musicbox systemd[1]: Started D-Bus System Message Bus. Jan 26 20:56:29 musicbox systemd[1]: Starting WPA supplicant... Jan 26 20:56:29 musicbox systemd[1]: Started UPnP Renderer front-end to MPD. Jan 26 20:56:29 musicbox systemd[1]: Started Volumio Log Rotation Service. Jan 26 20:56:29 musicbox systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jan 26 20:56:29 musicbox systemd[1]: Starting dhcpcd on all interfaces... Jan 26 20:56:29 musicbox systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jan 26 20:56:29 musicbox systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jan 26 20:56:29 musicbox systemd[1]: Started RPC bind portmap service. Jan 26 20:56:29 musicbox systemd[1]: Started triggerhappy global hotkey daemon. Jan 26 20:56:29 musicbox systemd[1]: Started Helper to synchronize boot up for ifupdown. Jan 26 20:56:29 musicbox systemd[1]: Started Save/Restore Sound Card State. Jan 26 20:56:29 musicbox systemd[1]: Reached target Sound Card. Jan 26 20:56:29 musicbox systemd[1]: Starting Raise network interfaces... Jan 26 20:56:29 musicbox systemd-logind[554]: New seat seat0. Jan 26 20:56:29 musicbox systemd[1]: Reached target RPC Port Mapper. Jan 26 20:56:29 musicbox systemd[1]: Reached target Remote File Systems (Pre). Jan 26 20:56:29 musicbox systemd[1]: Reached target Remote File Systems. Jan 26 20:56:29 musicbox systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jan 26 20:56:29 musicbox dhcpcd[580]: Not running dhcpcd because /etc/network/interfaces Jan 26 20:56:29 musicbox dhcpcd[580]: defines some interfaces that will use a Jan 26 20:56:29 musicbox dhcpcd[580]: DHCP client or static address Jan 26 20:56:29 musicbox systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Jan 26 20:56:29 musicbox systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Jan 26 20:56:29 musicbox systemd[1]: Failed to start dhcpcd on all interfaces. Jan 26 20:56:29 musicbox sh[502]: wlan0=wlan0 Jan 26 20:56:29 musicbox avahi-daemon[581]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jan 26 20:56:29 musicbox avahi-daemon[581]: Successfully dropped root privileges. Jan 26 20:56:29 musicbox avahi-daemon[581]: avahi-daemon 0.7 starting up. Jan 26 20:56:29 musicbox sh[496]: eth0: waiting for carrier Jan 26 20:56:29 musicbox kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Jan 26 20:56:29 musicbox kernel: bcmgenet fd580000.ethernet eth0: Link is Down Jan 26 20:56:29 musicbox dhcpcd[533]: eth0: waiting for carrier Jan 26 20:56:30 musicbox avahi-daemon[581]: Successfully called chroot(). Jan 26 20:56:30 musicbox avahi-daemon[581]: Successfully dropped remaining capabilities. Jan 26 20:56:30 musicbox systemd[1]: Started Login Service. Jan 26 20:56:30 musicbox avahi-daemon[581]: Loading service file /services/volumio.service. Jan 26 20:56:30 musicbox systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jan 26 20:56:30 musicbox wpa_supplicant[575]: Successfully initialized wpa_supplicant Jan 26 20:56:30 musicbox systemd[1]: hciuart.service: Succeeded. Jan 26 20:56:30 musicbox systemd[1]: Started Configure Bluetooth Modems connected by UART. Jan 26 20:56:30 musicbox avahi-daemon[581]: Network interface enumeration completed. Jan 26 20:56:30 musicbox avahi-daemon[581]: Server startup complete. Host name is musicbox.local. Local service cookie is 2812318354. Jan 26 20:56:30 musicbox avahi-daemon[581]: Service "Musicbox" (/services/volumio.service) successfully established. Jan 26 20:56:30 musicbox systemd[1]: Started WPA supplicant. Jan 26 20:56:30 musicbox volumio-remote-updater[558]: Error: No active session Jan 26 20:56:30 musicbox volumio-remote-updater[558]: [2026-01-26 20:56:30] [info] asio async_connect error: system:111 (Connection refused) Jan 26 20:56:30 musicbox volumio-remote-updater[558]: [2026-01-26 20:56:30] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 26 20:56:30 musicbox volumio-remote-updater[558]: [2026-01-26 20:56:30] [error] handle_connect error: Underlying Transport Error Jan 26 20:56:30 musicbox systemd[1]: Starting Raspberry Pi bluetooth helper... Jan 26 20:56:30 musicbox volumio[577]: Could not open config: /tmp/upmpdcli.conf Jan 26 20:56:30 musicbox systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 26 20:56:30 musicbox systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 26 20:56:30 musicbox volumio-time-update[545]: volumio-time-update-util: Date not found in response Jan 26 20:56:30 musicbox volumio-time-update[545]: volumio-time-update-util: Retrying in 5 seconds... Jan 26 20:56:30 musicbox systemd[1]: Started Raise network interfaces. Jan 26 20:56:30 musicbox systemd[1]: Reached target Network. Jan 26 20:56:30 musicbox systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jan 26 20:56:30 musicbox systemd[1]: Starting Network Time Service... Jan 26 20:56:30 musicbox systemd[1]: Reached target Network is Online. Jan 26 20:56:30 musicbox systemd[1]: Starting LSB: Brings up/down network automatically... Jan 26 20:56:30 musicbox systemd[1]: Starting /etc/rc.local Compatibility... Jan 26 20:56:30 musicbox systemd[1]: Starting Permit User Sessions... Jan 26 20:56:30 musicbox systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 26 20:56:30 musicbox bthelper[661]: Raspberry Pi BDADDR already set Jan 26 20:56:30 musicbox systemd[1]: Starting Samba NMB Daemon... Jan 26 20:56:30 musicbox systemd[1]: Started Raspberry Pi bluetooth helper. Jan 26 20:56:30 musicbox systemd[1]: iptables.service: Succeeded. Jan 26 20:56:30 musicbox systemd[1]: Started /etc/rc.local Compatibility. Jan 26 20:56:31 musicbox systemd[1]: Starting Bluetooth service... Jan 26 20:56:31 musicbox loadcpufreq[589]: Loading cpufreq kernel modules...done (none). Jan 26 20:56:31 musicbox systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jan 26 20:56:31 musicbox systemd[1]: Started Permit User Sessions. Jan 26 20:56:31 musicbox systemd[1]: Starting Terminate Plymouth Boot Screen... Jan 26 20:56:31 musicbox systemd[1]: Starting Hold until boot process finishes up... Jan 26 20:56:31 musicbox systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jan 26 20:56:31 musicbox systemd[1]: Received SIGRTMIN+21 from PID 194 (plymouthd). Jan 26 20:56:31 musicbox systemd[1]: plymouth-quit.service: Succeeded. Jan 26 20:56:31 musicbox systemd[1]: Started Terminate Plymouth Boot Screen. Jan 26 20:56:31 musicbox systemd[1]: plymouth-quit-wait.service: Succeeded. Jan 26 20:56:31 musicbox systemd[1]: Started Hold until boot process finishes up. Jan 26 20:56:31 musicbox systemd[1]: Received SIGRTMIN+21 from PID 194 (n/a). Jan 26 20:56:31 musicbox ifplugd(eth0)[748]: ifplugd 0.28 initializing. Jan 26 20:56:31 musicbox systemd[1]: Started Getty on tty1. Jan 26 20:56:31 musicbox ifplugd(eth0)[748]: Using interface eth0/DC:A6:32:5B:31:9F with driver (version: 6.6.62-v7l+) Jan 26 20:56:31 musicbox ifplugd(eth0)[748]: Using detection mode: SIOCETHTOOL Jan 26 20:56:31 musicbox ifplugd(eth0)[748]: Initialization complete, link beat not detected. Jan 26 20:56:31 musicbox systemd[1]: Reached target Login Prompts. Jan 26 20:56:31 musicbox ifplugd[709]: Network Interface Plugging Daemon...start eth0...done. Jan 26 20:56:31 musicbox systemd[1]: Started LSB: Brings up/down network automatically. Jan 26 20:56:31 musicbox bluetoothd[726]: Bluetooth daemon 5.83 Jan 26 20:56:31 musicbox ntpd[708]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 26 20:56:31 musicbox ntpd[708]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jan 26 20:56:31 musicbox systemd[1]: Started Network Time Service. Jan 26 20:56:31 musicbox bluetoothd[726]: src/main.c:check_options() Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jan 26 20:56:31 musicbox ntpd[761]: proto: precision = 2.037 usec (-19) Jan 26 20:56:31 musicbox systemd[1]: Started Bluetooth service. Jan 26 20:56:31 musicbox systemd[1]: Reached target Bluetooth. Jan 26 20:56:31 musicbox ntpd[761]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 26 20:56:31 musicbox ntpd[761]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 26 20:56:31 musicbox ntpd[761]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 395 days ago Jan 26 20:56:31 musicbox ntpd[761]: Listen and drop on 0 v6wildcard [::]:123 Jan 26 20:56:31 musicbox ntpd[761]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 26 20:56:31 musicbox bluetoothd[726]: Starting SDP server Jan 26 20:56:31 musicbox bluetoothd[726]: Excluding (cli) hostname Jan 26 20:56:31 musicbox bluetoothd[726]: Excluding (cli) scanparam Jan 26 20:56:31 musicbox bluetoothd[726]: Excluding (cli) battery Jan 26 20:56:31 musicbox ntpd[761]: Listen normally on 2 lo 127.0.0.1:123 Jan 26 20:56:31 musicbox ntpd[761]: Listening on routing socket on fd #19 for interface updates Jan 26 20:56:31 musicbox ntpd[761]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 26 20:56:31 musicbox ntpd[761]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 26 20:56:31 musicbox cpufrequtils[732]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jan 26 20:56:31 musicbox systemd[1]: Started LSB: set CPUFreq kernel parameters. Jan 26 20:56:31 musicbox bluetoothd[726]: Bluetooth management interface 1.22 initialized Jan 26 20:56:31 musicbox bluetoothd[726]: Battery Provider Manager created Jan 26 20:56:31 musicbox kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jan 26 20:56:31 musicbox kernel: Bluetooth: BNEP filters: protocol multicast Jan 26 20:56:31 musicbox kernel: Bluetooth: BNEP socket layer initialized Jan 26 20:56:31 musicbox kernel: Bluetooth: MGMT ver 1.22 Jan 26 20:56:31 musicbox kernel: NET: Registered PF_ALG protocol family Jan 26 20:56:31 musicbox kernel: cryptd: max_cpu_qlen set to 1000 Jan 26 20:56:32 musicbox bluetoothd[726]: Failed to set privacy: Rejected (0x0b) Jan 26 20:56:32 musicbox haveged[540]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jan 26 20:56:32 musicbox haveged[540]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jan 26 20:56:32 musicbox haveged[540]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.0005 Jan 26 20:56:32 musicbox haveged[540]: haveged: fills: 0, generated: 0 Jan 26 20:56:32 musicbox ntpd[761]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 26 20:56:32 musicbox nmbd[725]: [2026/01/26 20:56:32.701658, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 26 20:56:32 musicbox nmbd[725]: started asyncdns process 786 Jan 26 20:56:32 musicbox nmbd[725]: [2026/01/26 20:56:32.705131, 0] ../lib/util/become_daemon.c:149(daemon_status) Jan 26 20:56:32 musicbox nmbd[725]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jan 26 20:56:32 musicbox nmbd[725]: [2026/01/26 20:56:32.705400, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jan 26 20:56:32 musicbox nmbd[725]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jan 26 20:56:32 musicbox sh[496]: eth0: carrier acquired Jan 26 20:56:32 musicbox dhcpcd[533]: eth0: carrier acquired Jan 26 20:56:32 musicbox kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Jan 26 20:56:32 musicbox sh[496]: DUID 00:01:00:01:2d:f2:1d:9c:dc:a6:32:5b:31:9f Jan 26 20:56:32 musicbox sh[496]: eth0: IAID 32:5b:31:9f Jan 26 20:56:32 musicbox sh[496]: eth0: adding address fe80::dea6:32ff:fe5b:319f Jan 26 20:56:32 musicbox sh[496]: ipv6_addaddr1: Permission denied Jan 26 20:56:32 musicbox dhcpcd[533]: DUID 00:01:00:01:2d:f2:1d:9c:dc:a6:32:5b:31:9f Jan 26 20:56:32 musicbox dhcpcd[533]: eth0: IAID 32:5b:31:9f Jan 26 20:56:32 musicbox dhcpcd[533]: eth0: adding address fe80::dea6:32ff:fe5b:319f Jan 26 20:56:32 musicbox dhcpcd[533]: ipv6_addaddr1: Permission denied Jan 26 20:56:33 musicbox wireless.js[555]: Cleaning previous... Jan 26 20:56:33 musicbox sh[496]: eth0: soliciting an IPv6 router Jan 26 20:56:33 musicbox dhcpcd[533]: eth0: soliciting an IPv6 router Jan 26 20:56:33 musicbox sudo[795]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 26 20:56:33 musicbox sudo[795]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:56:33 musicbox ifplugd(eth0)[748]: Link beat detected. Jan 26 20:56:33 musicbox sudo[795]: pam_unix(sudo:session): session closed for user root Jan 26 20:56:33 musicbox sudo[797]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 26 20:56:33 musicbox sudo[797]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:56:33 musicbox ifplugd(eth0)[748]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jan 26 20:56:33 musicbox ntpd[761]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 26 20:56:33 musicbox systemd[1]: systemd-rfkill.service: Succeeded. Jan 26 20:56:33 musicbox sh[496]: eth0: soliciting a DHCP lease Jan 26 20:56:33 musicbox dhcpcd[533]: eth0: soliciting a DHCP lease Jan 26 20:56:33 musicbox ifplugd(eth0)[748]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Jan 26 20:56:33 musicbox sudo[797]: pam_unix(sudo:session): session closed for user root Jan 26 20:56:34 musicbox wireless.js[555]: Stopped aP Jan 26 20:56:34 musicbox wireless.js[555]: Wireless Networking DISABLED, not starting wireless flow Jan 26 20:56:34 musicbox systemd[1]: wireless.service: Succeeded. Jan 26 20:56:34 musicbox systemd[1]: Started Wireless Services. Jan 26 20:56:34 musicbox systemd[1]: Started Volumio Backend Module. Jan 26 20:56:34 musicbox systemd[1]: Started Volumio Cpu Tweaker. Jan 26 20:56:34 musicbox volumio-cpu-tweak[811]: Setting RT Priority for mpd Jan 26 20:56:34 musicbox volumio-cpu-tweak[811]: pid 35's current scheduling policy: SCHED_OTHER Jan 26 20:56:34 musicbox volumio-cpu-tweak[811]: pid 35's current scheduling priority: 0 Jan 26 20:56:34 musicbox volumio-cpu-tweak[811]: Setting MPD Affinity Jan 26 20:56:34 musicbox volumio-cpu-tweak[811]: pid 3's current affinity mask: f Jan 26 20:56:34 musicbox volumio-cpu-tweak[811]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jan 26 20:56:34 musicbox volumio-cpu-tweak[811]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jan 26 20:56:34 musicbox systemd[1]: volumio_cpu_tweak.service: Succeeded. Jan 26 20:56:34 musicbox ntpd[761]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 26 20:56:35 musicbox ntpd[761]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 26 20:56:35 musicbox volumio-remote-updater[558]: [2026-01-26 20:56:35] [info] asio async_connect error: system:111 (Connection refused) Jan 26 20:56:35 musicbox volumio-remote-updater[558]: [2026-01-26 20:56:35] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 26 20:56:35 musicbox volumio-remote-updater[558]: [2026-01-26 20:56:35] [error] handle_connect error: Underlying Transport Error Jan 26 20:56:35 musicbox volumio-time-update[545]: volumio-time-update-util: Fetching time from Volumio... Jan 26 20:56:35 musicbox volumio-time-update[545]: volumio-time-update-util: Date not found in response Jan 26 20:56:35 musicbox volumio-time-update[545]: volumio-time-update-util: Retrying in 5 seconds... Jan 26 20:56:36 musicbox bluetoothd[726]: src/adv_monitor.c:btd_adv_monitor_power_down() Unexpected NULL btd_adv_monitor_manager object upon power down Jan 26 20:56:36 musicbox bthelper[661]: [NEW] Media /org/bluez/hci0 Jan 26 20:56:36 musicbox bthelper[661]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Jan 26 20:56:36 musicbox bthelper[661]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Jan 26 20:56:36 musicbox bthelper[661]: hci0 class of device changed: 0x000000 Jan 26 20:56:36 musicbox bthelper[661]: hci0 new_settings: ssp br/edr le secure-conn Jan 26 20:56:36 musicbox bthelper[661]: [CHG] Controller DC:A6:32:5B:31:A1 PowerState: on-disabling Jan 26 20:56:36 musicbox bthelper[661]: Changing power off succeeded Jan 26 20:56:36 musicbox bthelper[661]: [CHG] Controller DC:A6:32:5B:31:A1 PowerState: off Jan 26 20:56:36 musicbox bthelper[661]: [CHG] Controller DC:A6:32:5B:31:A1 Powered: no Jan 26 20:56:36 musicbox bthelper[661]: [CHG] Controller DC:A6:32:5B:31:A1 Discovering: no Jan 26 20:56:36 musicbox bthelper[661]: [CHG] Controller DC:A6:32:5B:31:A1 Class: 0x00000000 (0) Jan 26 20:56:36 musicbox bthelper[661]: [NEW] Media /org/bluez/hci0 Jan 26 20:56:36 musicbox bthelper[661]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Jan 26 20:56:36 musicbox bthelper[661]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Jan 26 20:56:36 musicbox bthelper[661]: [CHG] Controller DC:A6:32:5B:31:A1 PowerState: off-enabling Jan 26 20:56:36 musicbox bthelper[661]: hci0 class of device changed: 0x00041c Jan 26 20:56:36 musicbox bthelper[661]: [CHG] Controller DC:A6:32:5B:31:A1 Class: 0x0000041c (1052) Jan 26 20:56:36 musicbox bthelper[661]: hci0 new_settings: powered ssp br/edr le secure-conn Jan 26 20:56:36 musicbox bthelper[661]: Changing power on succeeded Jan 26 20:56:37 musicbox volumio[810]: info: ------------------------------------------- Jan 26 20:56:37 musicbox volumio[810]: info: ----- Volumio3 ---- Jan 26 20:56:37 musicbox volumio[810]: info: ------------------------------------------- Jan 26 20:56:37 musicbox volumio[810]: info: ----- System startup ---- Jan 26 20:56:37 musicbox volumio[810]: info: ------------------------------------------- Jan 26 20:56:38 musicbox sh[496]: eth0: offered 192.168.0.231 from 192.168.0.1 Jan 26 20:56:38 musicbox dhcpcd[533]: eth0: offered 192.168.0.231 from 192.168.0.1 Jan 26 20:56:38 musicbox sh[496]: eth0: probing address 192.168.0.231/24 Jan 26 20:56:38 musicbox dhcpcd[533]: eth0: probing address 192.168.0.231/24 Jan 26 20:56:39 musicbox volumio[810]: info: MYVOLUMIO Environment detected Jan 26 20:56:39 musicbox volumio[810]: info: Plugin folders cleanup Jan 26 20:56:39 musicbox volumio[810]: info: Scanning into folder /volumio/app/plugins/ Jan 26 20:56:39 musicbox volumio[810]: info: Scanning category audio_interface Jan 26 20:56:39 musicbox volumio[810]: info: Scanning category miscellanea Jan 26 20:56:39 musicbox volumio[810]: info: Scanning category music_service Jan 26 20:56:39 musicbox volumio[810]: info: Scanning category plugins.json Jan 26 20:56:39 musicbox volumio[810]: info: Scanning category system_controller Jan 26 20:56:39 musicbox volumio[810]: info: Scanning category user_interface Jan 26 20:56:39 musicbox volumio[810]: info: Scanning into folder /data/plugins/ Jan 26 20:56:39 musicbox volumio[810]: info: Plugin folders cleanup completed Jan 26 20:56:39 musicbox volumio[810]: info: ------------------------------------------- Jan 26 20:56:39 musicbox volumio[810]: info: ----- Core plugins startup ---- Jan 26 20:56:39 musicbox volumio[810]: info: ------------------------------------------- Jan 26 20:56:39 musicbox volumio[810]: info: Loading plugins from folder /volumio/app/plugins/ Jan 26 20:56:39 musicbox volumio[810]: info: Adding plugin upnp to MyMusic Plugins Jan 26 20:56:39 musicbox volumio[810]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 26 20:56:39 musicbox volumio[810]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 26 20:56:39 musicbox volumio[810]: info: Loading plugins from folder /data/plugins/ Jan 26 20:56:39 musicbox volumio[810]: info: Loading plugin "system"... Jan 26 20:56:39 musicbox volumio[810]: info: Loading plugin "appearance"... Jan 26 20:56:40 musicbox volumio-time-update[545]: volumio-time-update-util: Fetching time from Volumio... Jan 26 20:56:40 musicbox volumio-time-update[545]: volumio-time-update-util: Date not found in response Jan 26 20:56:40 musicbox volumio-time-update[545]: volumio-time-update-util: Retrying in 5 seconds... Jan 26 20:56:41 musicbox volumio[810]: info: Loading plugin "network"... Jan 26 20:56:41 musicbox volumio[810]: info: Refreshing Cached IP Addresses Jan 26 20:56:41 musicbox sudo[852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 20:56:41 musicbox sudo[852]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:56:41 musicbox sudo[852]: pam_unix(sudo:session): session closed for user root Jan 26 20:56:41 musicbox sudo[854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 20:56:41 musicbox sudo[854]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:56:41 musicbox volumio[810]: info: Loading plugin "services"... Jan 26 20:56:41 musicbox volumio[810]: info: Loading plugin "alsa_controller"... Jan 26 20:56:41 musicbox sudo[854]: pam_unix(sudo:session): session closed for user root Jan 26 20:56:41 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 20:56:41 musicbox volumio[810]: info: Loading plugin "wizard"... Jan 26 20:56:41 musicbox volumio[810]: info: Loading plugin "networkfs"... Jan 26 20:56:41 musicbox volumio[810]: info: Starting Udev Watcher for removable devices Jan 26 20:56:41 musicbox sudo[875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Berger_2000,password=ThomasBerger2025!!!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,Vers=2.0 //192.168.0.16/Music /mnt/NAS/Diskstation Jan 26 20:56:41 musicbox sudo[875]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:56:41 musicbox volumio[810]: info: Ignoring mount for partition: boot Jan 26 20:56:41 musicbox volumio[810]: info: Ignoring mount for partition: volumio Jan 26 20:56:41 musicbox volumio[810]: info: Ignoring mount for partition: volumio_data Jan 26 20:56:41 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 20:56:41 musicbox volumio[810]: info: Loading plugin "volumio_command_line_client"... Jan 26 20:56:41 musicbox volumio[810]: info: Loading plugin "upnp"... Jan 26 20:56:41 musicbox volumio[810]: info: [1769457401940] Starting Upmpd Daemon Jan 26 20:56:41 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 20:56:41 musicbox volumio[810]: info: Loading plugin "my_music"... Jan 26 20:56:41 musicbox volumio[810]: info: Loading plugin "mpd"... Jan 26 20:56:42 musicbox sudo[875]: pam_unix(sudo:session): session closed for user root Jan 26 20:56:42 musicbox kernel: Key type cifs.spnego registered Jan 26 20:56:42 musicbox kernel: Key type cifs.idmap registered Jan 26 20:56:42 musicbox kernel: cifs: Unknown parameter 'Vers' Jan 26 20:56:42 musicbox volumio[810]: info: Loading plugin "upnp_browser"... Jan 26 20:56:43 musicbox volumio-remote-updater[558]: [2026-01-26 20:56:43] [connect] Successful connection Jan 26 20:56:43 musicbox volumio[810]: info: Starting UPNP Browser Jan 26 20:56:43 musicbox volumio[810]: info: Loading plugin "alarm-clock"... Jan 26 20:56:43 musicbox volumio[810]: info: Loading plugin "airplay_emulation"... Jan 26 20:56:43 musicbox volumio[810]: info: Starting Shairport Sync Jan 26 20:56:43 musicbox volumio[810]: info: Loading plugin "last_100"... Jan 26 20:56:43 musicbox volumio[810]: info: Loading plugin "webradio"... Jan 26 20:56:43 musicbox sh[496]: eth0: leased 192.168.0.231 for 86400 seconds Jan 26 20:56:43 musicbox sh[496]: eth0: adding route to 192.168.0.0/24 Jan 26 20:56:43 musicbox sh[496]: eth0: adding default route via 192.168.0.1 Jan 26 20:56:43 musicbox dhcpcd[533]: eth0: leased 192.168.0.231 for 86400 seconds Jan 26 20:56:43 musicbox dhcpcd[533]: eth0: adding route to 192.168.0.0/24 Jan 26 20:56:43 musicbox dhcpcd[533]: eth0: adding default route via 192.168.0.1 Jan 26 20:56:43 musicbox avahi-daemon[581]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.231. Jan 26 20:56:43 musicbox avahi-daemon[581]: New relevant interface eth0.IPv4 for mDNS. Jan 26 20:56:43 musicbox avahi-daemon[581]: Registering new address record for 192.168.0.231 on eth0.IPv4. Jan 26 20:56:43 musicbox sh[496]: forked to background, child pid 911 Jan 26 20:56:43 musicbox dhcpcd[533]: forked to background, child pid 911 Jan 26 20:56:43 musicbox volumio[810]: info: Loading plugin "i2s_dacs"... Jan 26 20:56:43 musicbox volumio[810]: info: Loading plugin "volumiodiscovery"... Jan 26 20:56:43 musicbox ifplugd(eth0)[748]: client: ifup: interface eth0 already configured Jan 26 20:56:43 musicbox volumio[810]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 26 20:56:43 musicbox volumio[810]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 20:56:43 musicbox volumio[810]: *** WARNING *** For more information see Jan 26 20:56:43 musicbox sh[496]: eth0=eth0 Jan 26 20:56:43 musicbox node[810]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 26 20:56:43 musicbox volumio[810]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 26 20:56:43 musicbox volumio[810]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 20:56:43 musicbox volumio[810]: *** WARNING *** For more information see Jan 26 20:56:43 musicbox node[810]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 20:56:43 musicbox node[810]: *** WARNING *** For more information see Jan 26 20:56:43 musicbox node[810]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 26 20:56:43 musicbox node[810]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 20:56:43 musicbox node[810]: *** WARNING *** For more information see Jan 26 20:56:43 musicbox volumio[810]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 26 20:56:43 musicbox volumio[810]: info: Discovery: Started advertising with name: Musicbox Jan 26 20:56:43 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 20:56:43 musicbox volumio[810]: info: Loading plugin "outputs"... Jan 26 20:56:43 musicbox volumio[810]: info: Loading plugin "albumart"... Jan 26 20:56:43 musicbox ifplugd(eth0)[748]: Program executed successfully. Jan 26 20:56:43 musicbox volumio[810]: info: Plugin example_plugin is not enabled Jan 26 20:56:43 musicbox volumio[810]: info: Loading plugin "inputs"... Jan 26 20:56:43 musicbox volumio[810]: info: Loading plugin "updater_comm"... Jan 26 20:56:44 musicbox volumio[810]: info: Plugin mpdemulation is not enabled Jan 26 20:56:44 musicbox volumio[810]: info: Loading plugin "rest_api"... Jan 26 20:56:44 musicbox volumio[810]: info: Loading plugin "websocket"... Jan 26 20:56:44 musicbox volumio[810]: info: Starting Socket.io Server version 2.3.0 Jan 26 20:56:44 musicbox volumio[810]: Forking 3 albumart workers Jan 26 20:56:44 musicbox volumio[810]: info: Loading i18n strings for locale da Jan 26 20:56:44 musicbox volumio[810]: Updating browse sources language Jan 26 20:56:44 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 20:56:44 musicbox ntpd[761]: Listen normally on 3 eth0 192.168.0.231:123 Jan 26 20:56:44 musicbox ntpd[761]: new interface(s) found: waking up resolver Jan 26 20:56:44 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 20:56:44 musicbox volumio[810]: info: CoreCommandRouter::initPlayerControls Jan 26 20:56:44 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:56:44 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:56:44 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:56:44 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:56:44 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:56:44 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:56:44 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:56:44 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:56:44 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 20:56:44 musicbox volumio[810]: Express server listening on port 3000 Jan 26 20:56:44 musicbox volumio[810]: [Metrics] WebUI: 8s 557.58ms Jan 26 20:56:45 musicbox volumio[810]: info: CoreStateMachine::resetVolumioState Jan 26 20:56:45 musicbox volumio[810]: info: CoreStateMachine::getcurrentVolume Jan 26 20:56:45 musicbox volumio[810]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 20:56:45 musicbox volumio[810]: info: CoreStateMachine::pushState Jan 26 20:56:45 musicbox volumio[810]: info: CorePlayQueue::getTrack 0 Jan 26 20:56:45 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 20:56:45 musicbox volumio[810]: info: CoreCommandRouter::volumioPushState Jan 26 20:56:45 musicbox volumio[810]: info: Volumio Network Manager: Network status updated: 0 Jan 26 20:56:45 musicbox volumio[810]: info: Cannot mount NAS Diskstation at system boot, trial number 1 ,retrying in 5 seconds Jan 26 20:56:45 musicbox volumio-remote-updater[558]: [2026-01-26 20:56:45] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1769457403 101 Jan 26 20:56:45 musicbox volumio[810]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jan 26 20:56:45 musicbox volumio[810]: verbose: New Socket.io Connection to 192.168.0.231:3000 from 192.168.0.226 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Jan 26 20:56:45 musicbox volumio[810]: verbose: New Socket.io Connection to 192.168.0.231:3000 from 192.168.0.226 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Jan 26 20:56:45 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:56:45 musicbox volumio[810]: info: Reloading queue from file Jan 26 20:56:45 musicbox volumio[810]: info: CoreStateMachine::setRepeat null single undefined Jan 26 20:56:45 musicbox volumio[810]: info: CoreStateMachine::pushState Jan 26 20:56:45 musicbox volumio[810]: info: CorePlayQueue::getTrack 0 Jan 26 20:56:45 musicbox volumio[810]: info: CoreCommandRouter::volumioPushState Jan 26 20:56:45 musicbox volumio[810]: info: CoreStateMachine::setRandom true Jan 26 20:56:45 musicbox volumio[810]: info: CoreStateMachine::pushState Jan 26 20:56:45 musicbox volumio[810]: info: CorePlayQueue::getTrack 0 Jan 26 20:56:45 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 20:56:45 musicbox volumio[810]: info: CoreCommandRouter::volumioPushState Jan 26 20:56:45 musicbox volumio[810]: info: Setting Device type: Raspberry PI Jan 26 20:56:45 musicbox volumio[810]: info: Completed loading Core Plugins Jan 26 20:56:45 musicbox volumio[810]: info: Preparing to generate the ALSA configuration file Jan 26 20:56:45 musicbox volumio[810]: Starting albumart workers Jan 26 20:56:45 musicbox volumio[810]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 26 20:56:45 musicbox volumio[810]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 Jan 26 20:56:45 musicbox volumio[810]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 26 20:56:45 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 20:56:45 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 20:56:45 musicbox volumio[810]: info: Discovery: Getting this device information Jan 26 20:56:45 musicbox volumio[810]: info: CoreCommandRouter::volumioGetState Jan 26 20:56:45 musicbox volumio[810]: info: CorePlayQueue::getTrack 0 Jan 26 20:56:45 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 20:56:45 musicbox volumio[810]: info: Asound.conf file unchanged, so no further update is needed Jan 26 20:56:45 musicbox volumio[810]: info: Output device has changed, restarting MPD Jan 26 20:56:45 musicbox volumio[810]: info: Output device has changed, restarting Shairport Sync Jan 26 20:56:45 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:56:45 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:56:45 musicbox sudo[976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 20:56:45 musicbox sudo[976]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:56:45 musicbox sudo[976]: pam_unix(sudo:session): session closed for user root Jan 26 20:56:45 musicbox sudo[978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 20:56:45 musicbox volumio[810]: Starting albumart workers Jan 26 20:56:45 musicbox sudo[978]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:56:45 musicbox volumio[810]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 20:56:45 musicbox volumio[810]: info: ___________ START PLUGINS ___________ Jan 26 20:56:45 musicbox volumio[810]: Starting albumart workers Jan 26 20:56:45 musicbox volumio[810]: info: ControllerMpd::onStart: Initializing MPD Jan 26 20:56:45 musicbox volumio[810]: info: Creating MPD Configuration file Jan 26 20:56:45 musicbox volumio-time-update[545]: volumio-time-update-util: Fetching time from Volumio... Jan 26 20:56:45 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 20:56:46 musicbox volumio[810]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 20:56:46 musicbox volumio[810]: info: [1769457405946] CoreMusicLibrary::Adding element Medieservere Jan 26 20:56:46 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 20:56:46 musicbox volumio[810]: info: UPNP Browser: Client initialized successfully Jan 26 20:56:46 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:56:46 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:56:45 musicbox sudo[991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 20:56:46 musicbox systemd[1]: Listening on mpd.socket. Jan 26 20:56:45 musicbox sudo[991]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:56:45 musicbox sudo[992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 20:56:46 musicbox sudo[991]: pam_unix(sudo:session): session closed for user root Jan 26 20:56:46 musicbox sudo[992]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:56:46 musicbox systemd[1]: Starting Music Player Daemon... Jan 26 20:56:46 musicbox systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 26 20:56:46 musicbox systemd[1]: mpd.service: Succeeded. Jan 26 20:56:46 musicbox systemd[1]: Stopped Music Player Daemon. Jan 26 20:56:46 musicbox systemd[1]: Starting Music Player Daemon... Jan 26 20:56:46 musicbox volumio[810]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 20:56:46 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:56:46 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:56:46 musicbox volumio[810]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 20:56:46 musicbox volumio[810]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 20:56:46 musicbox volumio[810]: info: [1769457406189] CoreMusicLibrary::Adding element Last_100 Jan 26 20:56:46 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 20:56:46 musicbox volumio-time-update[545]: volumio-time-update-util: Setting system time to: 2026-01-26 20:57:15 Jan 26 20:56:46 musicbox volumio[810]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 20:56:46 musicbox volumio[810]: info: [1769457406194] CoreMusicLibrary::Adding element Webradio Jan 26 20:56:46 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 20:56:46 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 20:56:46 musicbox volumio[810]: info: Initializing BBC Radios Jan 26 20:56:46 musicbox sudo[999]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 26 20:56:46 musicbox sudo[999]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:56:46 musicbox sudo[1004]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2026-01-26 20:57:15 Jan 26 20:56:46 musicbox sudo[1004]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:56:46 musicbox sudo[999]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 26 20:56:46 musicbox sudo[999]: pam_unix(sudo:session): session closed for user root Jan 26 20:56:46 musicbox dbus-daemon[568]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.11' (uid=0 pid=1008 comm="timedatectl set-time 2026-01-26 20:57:15 ") Jan 26 20:56:46 musicbox systemd[1]: Starting Time & Date Service... Jan 26 20:56:46 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 20:56:46 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:56:46 musicbox volumio[810]: info: Volumio Calling Home Jan 26 20:56:46 musicbox dbus-daemon[568]: [system] Successfully activated service 'org.freedesktop.timedate1' Jan 26 20:56:46 musicbox systemd[1]: Started Time & Date Service. Jan 26 20:57:15 musicbox systemd-timedated[1014]: Changed local time to Mon Jan 26 20:57:15 2026 Jan 26 20:57:15 musicbox sudo[1004]: pam_unix(sudo:session): session closed for user root Jan 26 20:57:15 musicbox volumio-time-update[545]: volumio-time-update-util: System time updated successfully. Jan 26 20:57:15 musicbox systemd[1]: Started Volumio Time Update Utility. Jan 26 20:57:15 musicbox volumio[810]: info: Discovery: adding f9957265-435d-479e-af9d-5f4c5d5ce27f Jan 26 20:57:15 musicbox volumio[810]: info: Discovery: Found device Musicbox Jan 26 20:57:15 musicbox volumio[810]: info: CoreCommandRouter::volumioGetState Jan 26 20:57:15 musicbox volumio[810]: info: CorePlayQueue::getTrack 0 Jan 26 20:57:15 musicbox volumio[810]: info: MPD Permissions set Jan 26 20:57:15 musicbox volumio[810]: info: MPD Permissions set Jan 26 20:57:15 musicbox volumio[810]: info: Volumio called home Jan 26 20:57:15 musicbox volumio[810]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 26 20:57:15 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:57:15 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:57:15 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:57:15 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:57:15 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:57:15 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:57:15 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:57:15 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:57:15 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:57:15 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:57:15 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:57:15 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:57:15 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:57:15 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:57:15 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:57:15 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:57:15 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:57:15 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:57:15 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:57:15 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:57:15 musicbox volumio[810]: info: CoreCommandRouter::volumioGetState Jan 26 20:57:15 musicbox volumio[810]: info: CorePlayQueue::getTrack 0 Jan 26 20:57:15 musicbox volumio[810]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.226 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Jan 26 20:57:15 musicbox volumio[810]: info: Starting Shairport Sync Jan 26 20:57:15 musicbox volumio[810]: info: Starting Shairport Sync Jan 26 20:57:15 musicbox volumio[810]: info: Starting Shairport Sync Jan 26 20:57:15 musicbox sudo[1055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 20:57:15 musicbox sudo[1055]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:57:15 musicbox sudo[1057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 20:57:15 musicbox sudo[1057]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:57:15 musicbox systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 26 20:57:15 musicbox systemd[1]: shairport-sync.service: Succeeded. Jan 26 20:57:15 musicbox systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 26 20:57:15 musicbox sudo[1060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 20:57:15 musicbox sudo[1060]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:57:16 musicbox nmbd[725]: [2026/01/26 20:57:16.019717, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 26 20:57:16 musicbox nmbd[725]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 26 20:57:16 musicbox systemd[1]: Started Samba NMB Daemon. Jan 26 20:57:16 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 20:57:16 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 20:57:16 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 20:57:16 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 20:57:16 musicbox volumio[810]: info: CoreCommandRouter::volumioGetState Jan 26 20:57:16 musicbox volumio[810]: info: CorePlayQueue::getTrack 0 Jan 26 20:57:16 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 26 20:57:16 musicbox volumio[810]: info: Listing playlists Jan 26 20:57:16 musicbox systemd[1]: Starting Samba Winbind Daemon... Jan 26 20:57:16 musicbox systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 26 20:57:16 musicbox sudo[1055]: pam_unix(sudo:session): session closed for user root Jan 26 20:57:16 musicbox sudo[1057]: pam_unix(sudo:session): session closed for user root Jan 26 20:57:16 musicbox sudo[1060]: pam_unix(sudo:session): session closed for user root Jan 26 20:57:16 musicbox volumio[810]: info: Shairport-Sync Started Jan 26 20:57:16 musicbox volumio[810]: Error adding Membership: Error: addMembership EINVAL Jan 26 20:57:16 musicbox volumio[810]: info: Shairport-Sync Started Jan 26 20:57:16 musicbox sudo[1070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 20:57:16 musicbox sudo[1070]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:57:16 musicbox sudo[1072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 20:57:16 musicbox sudo[1070]: pam_unix(sudo:session): session closed for user root Jan 26 20:57:16 musicbox sudo[1072]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:57:16 musicbox sudo[1072]: pam_unix(sudo:session): session closed for user root Jan 26 20:57:16 musicbox volumio[810]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.226 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Jan 26 20:57:16 musicbox winbindd[1064]: [2026/01/26 20:57:16.756020, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 26 20:57:16 musicbox winbindd[1064]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 26 20:57:16 musicbox winbindd[1064]: [2026/01/26 20:57:16.795866, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 26 20:57:16 musicbox systemd[1]: Started Samba Winbind Daemon. Jan 26 20:57:16 musicbox winbindd[1064]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 26 20:57:16 musicbox systemd[1]: Starting Samba SMB Daemon... Jan 26 20:57:17 musicbox sudo[1080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 20:57:17 musicbox sudo[1080]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:57:17 musicbox sudo[1080]: pam_unix(sudo:session): session closed for user root Jan 26 20:57:17 musicbox sudo[1082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 20:57:17 musicbox sudo[1082]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:57:17 musicbox sudo[1082]: pam_unix(sudo:session): session closed for user root Jan 26 20:57:17 musicbox volumio[810]: verbose: New Socket.io Connection to 192.168.0.231 from 192.168.0.226 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Jan 26 20:57:17 musicbox volumio[810]: info: CoreCommandRouter::volumioGetState Jan 26 20:57:17 musicbox volumio[810]: info: CorePlayQueue::getTrack 0 Jan 26 20:57:17 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 26 20:57:17 musicbox volumio[810]: info: Listing playlists Jan 26 20:57:17 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 26 20:57:17 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 26 20:57:17 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 26 20:57:17 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 26 20:57:17 musicbox smbd[1078]: [2026/01/26 20:57:17.674334, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 26 20:57:17 musicbox smbd[1078]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 26 20:57:17 musicbox systemd[1]: Started Samba SMB Daemon. Jan 26 20:57:17 musicbox systemd[1]: Reached target Multi-User System. Jan 26 20:57:17 musicbox systemd[1]: Reached target Graphical Interface. Jan 26 20:57:17 musicbox systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 26 20:57:17 musicbox systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 26 20:57:17 musicbox systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 26 20:57:18 musicbox sudo[1094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Berger_2000,password=ThomasBerger2025!!!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,Vers=2.0 //192.168.0.16/Music /mnt/NAS/Diskstation Jan 26 20:57:18 musicbox sudo[1094]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:57:18 musicbox sudo[1094]: pam_unix(sudo:session): session closed for user root Jan 26 20:57:18 musicbox kernel: cifs: Unknown parameter 'Vers' Jan 26 20:57:18 musicbox volumio[810]: info: Cannot mount NAS Diskstation at system boot, trial number 2 ,retrying in 5 seconds Jan 26 20:57:18 musicbox mpd[1009]: Jan 26 20:57 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 26 20:57:18 musicbox systemd[1]: Started Music Player Daemon. Jan 26 20:57:18 musicbox sudo[992]: pam_unix(sudo:session): session closed for user root Jan 26 20:57:18 musicbox sudo[978]: pam_unix(sudo:session): session closed for user root Jan 26 20:57:18 musicbox systemd[1]: Startup finished in 13.902s (kernel) + 27.313s (userspace) = 41.216s. Jan 26 20:57:18 musicbox volumio[810]: info: Completed starting Core Plugins Jan 26 20:57:18 musicbox volumio[810]: info: ------------------------------------------- Jan 26 20:57:18 musicbox volumio[810]: info: ----- MyVolumio plugins startup ---- Jan 26 20:57:18 musicbox volumio[810]: info: ------------------------------------------- Jan 26 20:57:18 musicbox volumio[810]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 26 20:57:19 musicbox volumio[810]: error: MPD error: The expression evaluated to a falsy value: Jan 26 20:57:19 musicbox volumio[810]: assert.ok(self.idling) Jan 26 20:57:19 musicbox volumio[810]: error: The expression evaluated to a falsy value: Jan 26 20:57:19 musicbox volumio[810]: assert.ok(self.idling) Jan 26 20:57:19 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 26 20:57:19 musicbox volumio[810]: info: Received Get System Info Jan 26 20:57:19 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 20:57:19 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 20:57:19 musicbox volumio[810]: info: Discovery: Getting this device information Jan 26 20:57:19 musicbox volumio[810]: info: CoreCommandRouter::volumioGetState Jan 26 20:57:19 musicbox volumio[810]: info: CorePlayQueue::getTrack 0 Jan 26 20:57:19 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 20:57:19 musicbox volumio[810]: info: MPD running with PID1009 Jan 26 20:57:19 musicbox volumio[810]: ,establishing connection Jan 26 20:57:19 musicbox volumio[810]: error: updateQueue error: null Jan 26 20:57:19 musicbox volumio[810]: error: updateQueue error: null Jan 26 20:57:19 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 26 20:57:20 musicbox volumio[810]: info: Volumio Network Manager: Network status updated: 1 Jan 26 20:57:20 musicbox volumio[810]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 20:57:20 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 26 20:57:20 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 26 20:57:20 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 26 20:57:20 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 26 20:57:20 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 20:57:20 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 20:57:20 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 20:57:20 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 20:57:20 musicbox volumio[810]: info: CoreCommandRouter::volumioGetBrowseSources Jan 26 20:57:20 musicbox volumio[810]: info: CoreCommandRouter::volumioGetBrowseSources Jan 26 20:57:20 musicbox volumio[810]: info: CoreCommandRouter::volumioGetBrowseSources Jan 26 20:57:20 musicbox sudo[1117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 20:57:20 musicbox sudo[1117]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:57:20 musicbox sudo[1117]: pam_unix(sudo:session): session closed for user root Jan 26 20:57:20 musicbox sudo[1119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 20:57:20 musicbox sudo[1119]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:57:20 musicbox sudo[1119]: pam_unix(sudo:session): session closed for user root Jan 26 20:57:20 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 20:57:20 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 26 20:57:20 musicbox sudo[1123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 26 20:57:20 musicbox sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:57:20 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 26 20:57:20 musicbox volumio[810]: info: Received Get System Info Jan 26 20:57:20 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 20:57:20 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 20:57:20 musicbox volumio[810]: info: Discovery: Getting this device information Jan 26 20:57:20 musicbox volumio[810]: info: CoreCommandRouter::volumioGetState Jan 26 20:57:20 musicbox volumio[810]: info: CorePlayQueue::getTrack 0 Jan 26 20:57:20 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 20:57:20 musicbox systemd[1]: Started UPnP Renderer front-end to MPD. Jan 26 20:57:20 musicbox sudo[1123]: pam_unix(sudo:session): session closed for user root Jan 26 20:57:20 musicbox volumio[810]: info: Upmpdcli Daemon Started Jan 26 20:57:20 musicbox volumio[1128]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 26 20:57:20 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 26 20:57:21 musicbox sudo[1145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 26 20:57:21 musicbox sudo[1145]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:57:21 musicbox sudo[1147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 26 20:57:21 musicbox sudo[1147]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:57:22 musicbox systemd[1]: Started MPD Monitor Service. Jan 26 20:57:22 musicbox sudo[1145]: pam_unix(sudo:session): session closed for user root Jan 26 20:57:22 musicbox volumio[810]: info: Successfully started MPD Monitor Jan 26 20:57:22 musicbox systemd[1]: Stopping MPD Monitor Service... Jan 26 20:57:22 musicbox systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 26 20:57:22 musicbox systemd[1]: mpd_monitor.service: Succeeded. Jan 26 20:57:22 musicbox systemd[1]: Stopped MPD Monitor Service. Jan 26 20:57:22 musicbox systemd[1]: Started MPD Monitor Service. Jan 26 20:57:22 musicbox sudo[1147]: pam_unix(sudo:session): session closed for user root Jan 26 20:57:22 musicbox volumio[810]: info: Successfully started MPD Monitor Jan 26 20:57:22 musicbox mpd_monitor.sh[1151]: MPD Monitor Service: Starting MPD Monitor Service Jan 26 20:57:23 musicbox sudo[1156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Berger_2000,password=ThomasBerger2025!!!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,Vers=2.0 //192.168.0.16/Music /mnt/NAS/Diskstation Jan 26 20:57:23 musicbox sudo[1156]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:57:23 musicbox sudo[1156]: pam_unix(sudo:session): session closed for user root Jan 26 20:57:23 musicbox volumio[810]: info: Cannot mount NAS Diskstation at system boot, trial number 3 ,retrying in 5 seconds Jan 26 20:57:23 musicbox kernel: cifs: Unknown parameter 'Vers' Jan 26 20:57:23 musicbox volumio[810]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 26 20:57:24 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 20:57:25 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jan 26 20:57:25 musicbox systemd[1]: systemd-fsckd.service: Succeeded. Jan 26 20:57:25 musicbox volumio[1128]: .......................++++ Jan 26 20:57:26 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 26 20:57:27 musicbox volumio[810]: info: Adding plugin bluetooth to MyMusic Plugins Jan 26 20:57:27 musicbox volumio[810]: info: Adding plugin multiroom to MyMusic Plugins Jan 26 20:57:27 musicbox volumio[810]: info: Adding plugin metavolumio to MyMusic Plugins Jan 26 20:57:27 musicbox volumio[810]: info: Adding plugin cd_controller to MyMusic Plugins Jan 26 20:57:27 musicbox volumio[810]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 26 20:57:27 musicbox volumio[810]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 26 20:57:27 musicbox volumio[810]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 26 20:57:27 musicbox volumio[810]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 26 20:57:29 musicbox volumio[810]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 26 20:57:29 musicbox volumio[810]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 26 20:57:29 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:57:29 musicbox volumio[810]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:57:29 musicbox volumio[810]: info: Starting MyVolumio Remote Streaming Endpoints Jan 26 20:57:29 musicbox volumio[810]: info: MyVolumio login type: Token Jan 26 20:57:29 musicbox volumio[810]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 26 20:57:29 musicbox volumio[810]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 26 20:57:31 musicbox volumio[810]: info: Starting Streaming Service Transparent Proxy Jan 26 20:57:31 musicbox volumio[810]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 26 20:57:31 musicbox volumio[810]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 26 20:57:31 musicbox volumio[810]: info: Streaming services startup Jan 26 20:57:31 musicbox volumio[810]: info: Starting Streaming Daemon Jan 26 20:57:31 musicbox sudo[1183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 26 20:57:31 musicbox sudo[1183]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 20:57:31 musicbox volumio[810]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 26 20:57:31 musicbox sudo[1183]: pam_unix(sudo:session): session closed for user root Jan 26 20:57:31 musicbox volumio[810]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 20:57:31 musicbox volumio[810]: TypeError: Cannot read property 'replace' of undefined Jan 26 20:57:31 musicbox volumio[810]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Jan 26 20:57:31 musicbox volumio[810]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Jan 26 20:57:31 musicbox volumio[810]: at listOnTimeout (internal/timers.js:554:17) Jan 26 20:57:31 musicbox volumio[810]: at processTimers (internal/timers.js:497:7) Jan 26 20:57:31 musicbox volumio[810]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 20:57:32 musicbox sudo[1199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-26 20:56 Jan 26 20:57:32 musicbox sudo[1199]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"