-- Logs begin at Thu 2019-02-14 18:11:59 AWST, end at Sun 2025-08-31 16:21:12 AWST. -- Aug 31 16:20:12 volumiopi3bplus kernel: usb usb1: Product: DWC OTG Controller Aug 31 16:20:12 volumiopi3bplus kernel: usb usb1: Manufacturer: Linux 6.6.62-v7+ dwc_otg_hcd Aug 31 16:20:12 volumiopi3bplus kernel: usb usb1: SerialNumber: 3f980000.usb Aug 31 16:20:12 volumiopi3bplus kernel: hub 1-0:1.0: USB hub found Aug 31 16:20:12 volumiopi3bplus kernel: hub 1-0:1.0: 1 port detected Aug 31 16:20:12 volumiopi3bplus kernel: dwc_otg: FIQ enabled Aug 31 16:20:12 volumiopi3bplus kernel: dwc_otg: NAK holdoff enabled Aug 31 16:20:12 volumiopi3bplus kernel: dwc_otg: FIQ split-transaction FSM enabled Aug 31 16:20:12 volumiopi3bplus kernel: Module dwc_common_port init Aug 31 16:20:12 volumiopi3bplus kernel: usbcore: registered new interface driver usb-storage Aug 31 16:20:12 volumiopi3bplus kernel: mousedev: PS/2 mouse device common for all mice Aug 31 16:20:12 volumiopi3bplus kernel: sdhci: Secure Digital Host Controller Interface driver Aug 31 16:20:12 volumiopi3bplus kernel: sdhci: Copyright(c) Pierre Ossman Aug 31 16:20:12 volumiopi3bplus kernel: sdhci-pltfm: SDHCI platform and OF driver helper Aug 31 16:20:12 volumiopi3bplus kernel: ledtrig-cpu: registered to indicate activity on CPUs Aug 31 16:20:12 volumiopi3bplus kernel: hid: raw HID events driver (C) Jiri Kosina Aug 31 16:20:12 volumiopi3bplus kernel: usbcore: registered new interface driver usbhid Aug 31 16:20:12 volumiopi3bplus kernel: usbhid: USB HID core driver Aug 31 16:20:12 volumiopi3bplus kernel: Initializing XFRM netlink socket Aug 31 16:20:12 volumiopi3bplus kernel: NET: Registered PF_PACKET protocol family Aug 31 16:20:12 volumiopi3bplus kernel: Key type dns_resolver registered Aug 31 16:20:12 volumiopi3bplus kernel: Registering SWP/SWPB emulation handler Aug 31 16:20:12 volumiopi3bplus kernel: registered taskstats version 1 Aug 31 16:20:12 volumiopi3bplus kernel: Loading compiled-in X.509 certificates Aug 31 16:20:12 volumiopi3bplus kernel: Key type .fscrypt registered Aug 31 16:20:12 volumiopi3bplus kernel: Key type fscrypt-provisioning registered Aug 31 16:20:12 volumiopi3bplus kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled Aug 31 16:20:12 volumiopi3bplus kernel: 3f201000.serial: ttyAMA1 at MMIO 0x3f201000 (irq = 114, base_baud = 0) is a PL011 rev2 Aug 31 16:20:12 volumiopi3bplus kernel: serial serial0: tty port ttyAMA1 registered Aug 31 16:20:12 volumiopi3bplus kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Aug 31 16:20:12 volumiopi3bplus kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Aug 31 16:20:12 volumiopi3bplus kernel: mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0 Aug 31 16:20:12 volumiopi3bplus kernel: mmc-bcm2835 3f300000.mmcnr: DMA channel allocated Aug 31 16:20:12 volumiopi3bplus kernel: sdhost: log_buf @ 627ec638 (f4113000) Aug 31 16:20:12 volumiopi3bplus kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Aug 31 16:20:12 volumiopi3bplus kernel: of_cfs_init Aug 31 16:20:12 volumiopi3bplus kernel: of_cfs_init: OK Aug 31 16:20:12 volumiopi3bplus kernel: clk: Disabling unused clocks Aug 31 16:20:12 volumiopi3bplus kernel: Freeing unused kernel image (initmem) memory: 1024K Aug 31 16:20:12 volumiopi3bplus kernel: Run /init as init process Aug 31 16:20:12 volumiopi3bplus kernel: with arguments: Aug 31 16:20:12 volumiopi3bplus kernel: /init Aug 31 16:20:12 volumiopi3bplus kernel: splash Aug 31 16:20:12 volumiopi3bplus kernel: nodebug Aug 31 16:20:12 volumiopi3bplus kernel: with environment: Aug 31 16:20:12 volumiopi3bplus kernel: HOME=/ Aug 31 16:20:12 volumiopi3bplus kernel: TERM=linux Aug 31 16:20:12 volumiopi3bplus kernel: imgpart=UUID=7f0c0e47-027d-47ad-bd0c-5159b014252e Aug 31 16:20:12 volumiopi3bplus kernel: imgfile=/volumio_current.sqsh Aug 31 16:20:12 volumiopi3bplus kernel: bootpart=UUID=10D8-1DF5 Aug 31 16:20:12 volumiopi3bplus kernel: datapart=UUID=307f8728-204c-4df1-9dcc-f9fb9dec6fbe Aug 31 16:20:12 volumiopi3bplus kernel: uuidconfig=cmdline.txt Aug 31 16:20:12 volumiopi3bplus kernel: pcie_aspm=off Aug 31 16:20:12 volumiopi3bplus kernel: pci=pcie_bus_safe Aug 31 16:20:12 volumiopi3bplus kernel: bootdelay=7 Aug 31 16:20:12 volumiopi3bplus kernel: use_kmsg=no Aug 31 16:20:12 volumiopi3bplus kernel: Indeed it is in host mode hprt0 = 00021501 Aug 31 16:20:12 volumiopi3bplus kernel: mmc1: new high speed SDIO card at address 0001 Aug 31 16:20:12 volumiopi3bplus kernel: mmc0: host does not support reading read-only switch, assuming write-enable Aug 31 16:20:12 volumiopi3bplus kernel: mmc0: Host Software Queue enabled Aug 31 16:20:12 volumiopi3bplus kernel: mmc0: new high speed SDXC card at address aaaa Aug 31 16:20:12 volumiopi3bplus kernel: mmcblk0: mmc0:aaaa SC128 119 GiB Aug 31 16:20:12 volumiopi3bplus kernel: mmcblk0: p1 p2 p3 Aug 31 16:20:12 volumiopi3bplus kernel: mmcblk0: mmc0:aaaa SC128 119 GiB (quirks 0x0000c000) Aug 31 16:20:12 volumiopi3bplus kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg Aug 31 16:20:12 volumiopi3bplus kernel: Indeed it is in host mode hprt0 = 00001101 Aug 31 16:20:12 volumiopi3bplus kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3 Aug 31 16:20:12 volumiopi3bplus kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Aug 31 16:20:12 volumiopi3bplus kernel: hub 1-1:1.0: USB hub found Aug 31 16:20:12 volumiopi3bplus kernel: hub 1-1:1.0: 4 ports detected Aug 31 16:20:12 volumiopi3bplus kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg Aug 31 16:20:12 volumiopi3bplus kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3 Aug 31 16:20:12 volumiopi3bplus kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Aug 31 16:20:12 volumiopi3bplus kernel: hub 1-1.1:1.0: USB hub found Aug 31 16:20:12 volumiopi3bplus kernel: hub 1-1.1:1.0: 3 ports detected Aug 31 16:20:12 volumiopi3bplus kernel: fuse: init (API version 7.39) Aug 31 16:20:12 volumiopi3bplus kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 31 16:20:12 volumiopi3bplus kernel: usbcore: registered new interface driver uas Aug 31 16:20:12 volumiopi3bplus kernel: usb 1-1.2: new full-speed USB device number 4 using dwc_otg Aug 31 16:20:12 volumiopi3bplus kernel: usb 1-1.2: New USB device found, idVendor=046d, idProduct=c52b, bcdDevice=24.11 Aug 31 16:20:12 volumiopi3bplus kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Aug 31 16:20:12 volumiopi3bplus kernel: usb 1-1.2: Product: USB Receiver Aug 31 16:20:12 volumiopi3bplus kernel: usb 1-1.2: Manufacturer: Logitech Aug 31 16:20:12 volumiopi3bplus kernel: input: Logitech USB Receiver as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/0003:046D:C52B.0001/input/input0 Aug 31 16:20:12 volumiopi3bplus kernel: usb 1-1.1.3: new high-speed USB device number 5 using dwc_otg Aug 31 16:20:12 volumiopi3bplus kernel: usb 1-1.1.3: New USB device found, idVendor=152a, idProduct=889b, bcdDevice= 1.06 Aug 31 16:20:12 volumiopi3bplus kernel: usb 1-1.1.3: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Aug 31 16:20:12 volumiopi3bplus kernel: usb 1-1.1.3: Product: Fosi Audio ZD3 Aug 31 16:20:12 volumiopi3bplus kernel: usb 1-1.1.3: Manufacturer: XMOS Aug 31 16:20:12 volumiopi3bplus kernel: hid-generic 0003:046D:C52B.0001: input,hidraw0: USB HID v1.11 Keyboard [Logitech USB Receiver] on usb-3f980000.usb-1.2/input0 Aug 31 16:20:12 volumiopi3bplus kernel: input: Logitech USB Receiver Mouse as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.1/0003:046D:C52B.0002/input/input1 Aug 31 16:20:12 volumiopi3bplus kernel: input: Logitech USB Receiver Consumer Control as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.1/0003:046D:C52B.0002/input/input2 Aug 31 16:20:12 volumiopi3bplus kernel: input: Logitech USB Receiver System Control as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.1/0003:046D:C52B.0002/input/input3 Aug 31 16:20:12 volumiopi3bplus kernel: hid-generic 0003:046D:C52B.0002: input,hiddev96,hidraw1: USB HID v1.11 Mouse [Logitech USB Receiver] on usb-3f980000.usb-1.2/input1 Aug 31 16:20:12 volumiopi3bplus kernel: hid-generic 0003:046D:C52B.0003: hiddev97,hidraw2: USB HID v1.11 Device [Logitech USB Receiver] on usb-3f980000.usb-1.2/input2 Aug 31 16:20:12 volumiopi3bplus kernel: usb 1-1.1.1: new high-speed USB device number 6 using dwc_otg Aug 31 16:20:12 volumiopi3bplus kernel: usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800, bcdDevice= 3.00 Aug 31 16:20:12 volumiopi3bplus kernel: usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Aug 31 16:20:12 volumiopi3bplus kernel: lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): No External EEPROM. Setting MAC Speed Aug 31 16:20:12 volumiopi3bplus kernel: lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): int urb period 64 Aug 31 16:20:12 volumiopi3bplus kernel: logitech-djreceiver 0003:046D:C52B.0003: hiddev96,hidraw0: USB HID v1.11 Device [Logitech USB Receiver] on usb-3f980000.usb-1.2/input2 Aug 31 16:20:12 volumiopi3bplus kernel: input: Logitech Wireless Device PID:408a Keyboard as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.2/0003:046D:C52B.0003/0003:046D:408A.0004/input/input5 Aug 31 16:20:12 volumiopi3bplus kernel: input: Logitech Wireless Device PID:408a Mouse as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.2/0003:046D:C52B.0003/0003:046D:408A.0004/input/input6 Aug 31 16:20:12 volumiopi3bplus kernel: hid-generic 0003:046D:408A.0004: input,hidraw1: USB HID v1.11 Keyboard [Logitech Wireless Device PID:408a] on usb-3f980000.usb-1.2/input2:1 Aug 31 16:20:12 volumiopi3bplus kernel: input: Logitech Wireless Device PID:406a Keyboard as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.2/0003:046D:C52B.0003/0003:046D:406A.0005/input/input10 Aug 31 16:20:12 volumiopi3bplus kernel: input: Logitech Wireless Device PID:406a Mouse as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.2/0003:046D:C52B.0003/0003:046D:406A.0005/input/input11 Aug 31 16:20:12 volumiopi3bplus kernel: hid-generic 0003:046D:406A.0005: input,hidraw2: USB HID v1.11 Keyboard [Logitech Wireless Device PID:406a] on usb-3f980000.usb-1.2/input2:2 Aug 31 16:20:12 volumiopi3bplus kernel: input: Logitech MX Keys as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.2/0003:046D:C52B.0003/0003:046D:408A.0004/input/input15 Aug 31 16:20:12 volumiopi3bplus kernel: logitech-hidpp-device 0003:046D:408A.0004: input,hidraw1: USB HID v1.11 Keyboard [Logitech MX Keys] on usb-3f980000.usb-1.2/input2:1 Aug 31 16:20:12 volumiopi3bplus kernel: input: Logitech MX Anywhere 2S as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.2/0003:046D:C52B.0003/0003:046D:406A.0005/input/input16 Aug 31 16:20:12 volumiopi3bplus kernel: logitech-hidpp-device 0003:046D:406A.0005: input,hidraw2: USB HID v1.11 Keyboard [Logitech MX Anywhere 2S] on usb-3f980000.usb-1.2/input2:2 Aug 31 16:20:12 volumiopi3bplus kernel: EXT4-fs (mmcblk0p2): recovery complete Aug 31 16:20:12 volumiopi3bplus kernel: EXT4-fs (mmcblk0p2): mounted filesystem 7f0c0e47-027d-47ad-bd0c-5159b014252e r/w with ordered data mode. Quota mode: none. Aug 31 16:20:12 volumiopi3bplus kernel: loop0: detected capacity change from 0 to 1004840 Aug 31 16:20:12 volumiopi3bplus kernel: EXT4-fs (mmcblk0p3): recovery complete Aug 31 16:20:12 volumiopi3bplus kernel: EXT4-fs (mmcblk0p3): mounted filesystem 307f8728-204c-4df1-9dcc-f9fb9dec6fbe r/w with ordered data mode. Quota mode: none. Aug 31 16:20:12 volumiopi3bplus systemd[1]: System time before build time, advancing clock. Aug 31 16:20:12 volumiopi3bplus kernel: NET: Registered PF_INET6 protocol family Aug 31 16:20:12 volumiopi3bplus kernel: Segment Routing with IPv6 Aug 31 16:20:12 volumiopi3bplus kernel: In-situ OAM (IOAM) with IPv6 Aug 31 16:20:12 volumiopi3bplus 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) Aug 31 16:20:12 volumiopi3bplus systemd[1]: Detected architecture arm. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Set hostname to . Aug 31 16:20:12 volumiopi3bplus 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. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Listening on udev Kernel Socket. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Listening on Journal Audit Socket. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Listening on initctl Compatibility Named Pipe. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Created slice system-systemd\x2dfsck.slice. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Listening on Journal Socket (/dev/log). Aug 31 16:20:12 volumiopi3bplus systemd[1]: Created slice system-getty.slice. Aug 31 16:20:12 volumiopi3bplus kernel: i2c_dev: i2c /dev entries driver Aug 31 16:20:12 volumiopi3bplus systemd-journald[295]: Journal started Aug 31 16:20:12 volumiopi3bplus systemd-journald[295]: Runtime journal (/run/log/journal/cd1795b8cab9744ed409ee046884907d) is 7.5M, max 30.0M, 22.5M free. Aug 31 16:20:12 volumiopi3bplus systemd-modules-load[288]: Inserted module 'i2c_dev' Aug 31 16:20:12 volumiopi3bplus fake-hwclock[289]: Sun Aug 31 08:20:12 UTC 2025 Aug 31 16:20:12 volumiopi3bplus systemd[1]: Mounted Kernel Configuration File System. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Mounted FUSE Control File System. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Started Load/Save Random Seed. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Started Apply Kernel Variables. Aug 31 16:20:12 volumiopi3bplus systemd[1]: dynamicswap.service: Succeeded. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Started Create System Users. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Starting Create Static Device Nodes in /dev... Aug 31 16:20:12 volumiopi3bplus systemd[1]: Started udev Coldplug all Devices. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Starting Helper to synchronize boot up for ifupdown... Aug 31 16:20:12 volumiopi3bplus systemd[1]: Started Create Static Device Nodes in /dev. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Reached target Local File Systems (Pre). Aug 31 16:20:12 volumiopi3bplus systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Mounting /var/log... Aug 31 16:20:12 volumiopi3bplus systemd[1]: Mounting /var/spool/cups... Aug 31 16:20:12 volumiopi3bplus systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Mounting /tmp... Aug 31 16:20:12 volumiopi3bplus systemd[1]: Starting udev Kernel Device Manager... Aug 31 16:20:12 volumiopi3bplus systemd[1]: Mounted /var/log. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Mounted /var/spool/cups. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Mounted /tmp. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Mounting /var/spool/cups/tmp... Aug 31 16:20:12 volumiopi3bplus systemd[1]: Starting Flush Journal to Persistent Storage... Aug 31 16:20:12 volumiopi3bplus systemd[1]: Mounted /var/spool/cups/tmp. Aug 31 16:20:12 volumiopi3bplus systemd-udevd[325]: Network interface NamePolicy= disabled on kernel command line, ignoring. Aug 31 16:20:12 volumiopi3bplus systemd-journald[295]: Runtime journal (/run/log/journal/cd1795b8cab9744ed409ee046884907d) is 7.5M, max 30.0M, 22.5M free. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Started Flush Journal to Persistent Storage. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Started udev Kernel Device Manager. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Starting Show Plymouth Boot Screen... Aug 31 16:20:12 volumiopi3bplus systemd[1]: plymouth-start.service: Succeeded. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Started Show Plymouth Boot Screen. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Reached target Local Encrypted Volumes. Aug 31 16:20:12 volumiopi3bplus systemd[1]: Reached target Paths. Aug 31 16:20:12 volumiopi3bplus kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 Aug 31 16:20:12 volumiopi3bplus kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem Aug 31 16:20:12 volumiopi3bplus kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Aug 31 16:20:12 volumiopi3bplus kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Aug 31 16:20:12 volumiopi3bplus kernel: [vc_sm_connected_init]: start Aug 31 16:20:12 volumiopi3bplus kernel: [vc_sm_connected_init]: installed successfully Aug 31 16:20:12 volumiopi3bplus kernel: mc: Linux media interface: v0.10 Aug 31 16:20:13 volumiopi3bplus kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Aug 31 16:20:13 volumiopi3bplus kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Aug 31 16:20:13 volumiopi3bplus kernel: videodev: Linux video capture interface: v2.00 Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Aug 31 16:20:13 volumiopi3bplus kernel: Bluetooth: Core ver 2.22 Aug 31 16:20:13 volumiopi3bplus kernel: NET: Registered PF_BLUETOOTH protocol family Aug 31 16:20:13 volumiopi3bplus kernel: Bluetooth: HCI device and connection manager initialized Aug 31 16:20:13 volumiopi3bplus kernel: Bluetooth: HCI socket layer initialized Aug 31 16:20:13 volumiopi3bplus kernel: Bluetooth: L2CAP socket layer initialized Aug 31 16:20:13 volumiopi3bplus kernel: Bluetooth: SCO socket layer initialized Aug 31 16:20:13 volumiopi3bplus systemd[1]: Found device /dev/disk/by-uuid/10D8-1DF5. Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Aug 31 16:20:13 volumiopi3bplus kernel: Bluetooth: HCI UART driver ver 2.3 Aug 31 16:20:13 volumiopi3bplus kernel: Bluetooth: HCI UART protocol H4 registered Aug 31 16:20:13 volumiopi3bplus kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Aug 31 16:20:13 volumiopi3bplus kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Aug 31 16:20:13 volumiopi3bplus kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Aug 31 16:20:13 volumiopi3bplus kernel: Bluetooth: HCI UART protocol Broadcom registered Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Aug 31 16:20:13 volumiopi3bplus kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Aug 31 16:20:13 volumiopi3bplus kernel: uart-pl011 3f201000.serial: no DMA platform data Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Aug 31 16:20:13 volumiopi3bplus kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Aug 31 16:20:13 volumiopi3bplus kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Aug 31 16:20:13 volumiopi3bplus kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Aug 31 16:20:13 volumiopi3bplus kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Aug 31 16:20:13 volumiopi3bplus kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Aug 31 16:20:13 volumiopi3bplus systemd[1]: Starting File System Check on /dev/disk/by-uuid/10D8-1DF5... Aug 31 16:20:13 volumiopi3bplus systemd[1]: Started File System Check Daemon to report status. Aug 31 16:20:13 volumiopi3bplus kernel: Bluetooth: hci0: BCM: chip id 107 Aug 31 16:20:13 volumiopi3bplus kernel: Bluetooth: hci0: BCM: features 0x2f Aug 31 16:20:13 volumiopi3bplus kernel: Bluetooth: hci0: BCM4345C0 Aug 31 16:20:13 volumiopi3bplus kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Aug 31 16:20:13 volumiopi3bplus systemd-udevd[334]: Using default interface naming scheme 'v240'. Aug 31 16:20:13 volumiopi3bplus kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Aug 31 16:20:13 volumiopi3bplus systemd-udevd[334]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Aug 31 16:20:13 volumiopi3bplus kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Aug 31 16:20:13 volumiopi3bplus kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Aug 31 16:20:13 volumiopi3bplus kernel: usbcore: registered new interface driver brcmfmac Aug 31 16:20:14 volumiopi3bplus systemd-fsck[383]: fsck.fat 4.1 (2017-01-24) Aug 31 16:20:14 volumiopi3bplus systemd-fsck[383]: /dev/mmcblk0p1: 403 files, 166330/184582 clusters Aug 31 16:20:14 volumiopi3bplus kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Aug 31 16:20:14 volumiopi3bplus 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 Aug 31 16:20:14 volumiopi3bplus systemd[1]: Started File System Check on /dev/disk/by-uuid/10D8-1DF5. Aug 31 16:20:14 volumiopi3bplus kernel: usb 1-1.1.3: 1:3 : unsupported format bits 0x100000000 Aug 31 16:20:14 volumiopi3bplus systemd-udevd[328]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 31 16:20:14 volumiopi3bplus systemd-udevd[328]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Aug 31 16:20:14 volumiopi3bplus kernel: usbcore: registered new interface driver snd-usb-audio Aug 31 16:20:14 volumiopi3bplus systemd-udevd[330]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 31 16:20:14 volumiopi3bplus systemd-udevd[338]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 31 16:20:14 volumiopi3bplus systemd-udevd[335]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 31 16:20:14 volumiopi3bplus systemd-udevd[338]: Using default interface naming scheme 'v240'. Aug 31 16:20:14 volumiopi3bplus systemd-udevd[338]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Aug 31 16:20:14 volumiopi3bplus kernel: Bluetooth: hci0: BCM: features 0x2f Aug 31 16:20:14 volumiopi3bplus kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Aug 31 16:20:14 volumiopi3bplus kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Aug 31 16:20:14 volumiopi3bplus kernel: Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac) Aug 31 16:20:15 volumiopi3bplus kernel: alsactl[442]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Aug 31 16:20:15 volumiopi3bplus systemd-udevd[336]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Condition check resulted in 7800 being skipped. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Created slice system-bthelper.slice. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Starting Show Plymouth Boot Screen... Aug 31 16:20:15 volumiopi3bplus systemd[1]: Condition check resulted in Huge Pages File System being skipped. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Mounting /boot... Aug 31 16:20:15 volumiopi3bplus systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Started Helper to synchronize boot up for ifupdown. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Mounted /boot. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Reached target Local File Systems. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Starting Preprocess NFS configuration... Aug 31 16:20:15 volumiopi3bplus systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Starting Create Volatile Files and Directories... Aug 31 16:20:15 volumiopi3bplus systemd[1]: Started ifup for eth0. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Aug 31 16:20:15 volumiopi3bplus systemd[1]: Starting Raise network interfaces... Aug 31 16:20:15 volumiopi3bplus systemd[1]: Started ifup for wlan0. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Starting Load/Save RF Kill Switch Status... Aug 31 16:20:15 volumiopi3bplus systemd[1]: plymouth-start.service: Succeeded. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Started Show Plymouth Boot Screen. Aug 31 16:20:15 volumiopi3bplus systemd[1]: plymouth-read-write.service: Succeeded. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Received SIGRTMIN+20 from PID 181 (plymouthd). Aug 31 16:20:15 volumiopi3bplus systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Aug 31 16:20:15 volumiopi3bplus systemd[1]: nfs-config.service: Succeeded. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Started Preprocess NFS configuration. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Reached target NFS client services. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Started Load/Save RF Kill Switch Status. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Started Create Volatile Files and Directories. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Aug 31 16:20:15 volumiopi3bplus kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Aug 31 16:20:15 volumiopi3bplus systemd[1]: Starting Update UTMP about System Boot/Shutdown... Aug 31 16:20:15 volumiopi3bplus kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Down Aug 31 16:20:15 volumiopi3bplus systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Starting RPC bind portmap service... Aug 31 16:20:15 volumiopi3bplus kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 31 16:20:15 volumiopi3bplus systemd[1]: Started Update UTMP about System Boot/Shutdown. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Reached target System Initialization. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Listening on triggerhappy.socket. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Started Daily apt download activities. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Started Daily apt upgrade and clean activities. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Listening on D-Bus System Message Bus Socket. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Reached target Sockets. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Reached target Basic System. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Starting Configure Bluetooth Modems connected by UART... Aug 31 16:20:15 volumiopi3bplus systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Started Volumio Iptables Module. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Starting triggerhappy global hotkey daemon... Aug 31 16:20:15 volumiopi3bplus systemd[1]: Starting dhcpcd on all interfaces... Aug 31 16:20:15 volumiopi3bplus systemd[1]: Started Manage Sound Card State (restore and store). Aug 31 16:20:15 volumiopi3bplus systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Aug 31 16:20:15 volumiopi3bplus alsactl[581]: alsactl 1.1.8 daemon started Aug 31 16:20:15 volumiopi3bplus systemd[1]: Starting Login Service... Aug 31 16:20:15 volumiopi3bplus systemd[1]: Started Volumio Log Rotation Service. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Started MPD Monitor Service. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Starting Wireless Services... Aug 31 16:20:15 volumiopi3bplus systemd[1]: Started volumio-remote-updater.service. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Starting Volumio Time Update Utility... Aug 31 16:20:15 volumiopi3bplus systemd[1]: Started Daily Cleanup of Temporary Directories. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Reached target Timers. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Starting Save/Restore Sound Card State... Aug 31 16:20:15 volumiopi3bplus systemd[1]: Started UPnP Renderer front-end to MPD. Aug 31 16:20:15 volumiopi3bplus systemd[1]: Started D-Bus System Message Bus. Aug 31 16:20:16 volumiopi3bplus volumio-time-update[591]: volumio-time-update-util: Fetching time from Volumio... Aug 31 16:20:16 volumiopi3bplus mpd_monitor.sh[588]: MPD Monitor Service: Starting MPD Monitor Service Aug 31 16:20:16 volumiopi3bplus systemd[1]: Starting WPA supplicant... Aug 31 16:20:16 volumiopi3bplus systemd[1]: Started RPC bind portmap service. Aug 31 16:20:16 volumiopi3bplus systemd[1]: Reached target Remote File Systems (Pre). Aug 31 16:20:16 volumiopi3bplus systemd[1]: Reached target Remote File Systems. Aug 31 16:20:16 volumiopi3bplus thd[571]: Unable to parse trigger line: Aug 31 16:20:16 volumiopi3bplus thd[571]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Aug 31 16:20:16 volumiopi3bplus thd[571]: Unable to parse trigger line: Aug 31 16:20:16 volumiopi3bplus thd[571]: Found socket passed from systemd Aug 31 16:20:16 volumiopi3bplus systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Aug 31 16:20:16 volumiopi3bplus systemd[1]: Reached target RPC Port Mapper. Aug 31 16:20:16 volumiopi3bplus systemd-logind[583]: Watching system buttons on /dev/input/event1 (Logitech MX Anywhere 2S) Aug 31 16:20:16 volumiopi3bplus systemd-logind[583]: Watching system buttons on /dev/input/event0 (Logitech MX Keys) Aug 31 16:20:16 volumiopi3bplus systemd-logind[583]: New seat seat0. Aug 31 16:20:16 volumiopi3bplus mpd_monitor.sh[588]: MPD error: Connection refused Aug 31 16:20:16 volumiopi3bplus mpd_monitor.sh[588]: MPD Monitor Service: MPD Appears to be inactive, restarting Aug 31 16:20:16 volumiopi3bplus dhcpcd[578]: dev: loaded udev Aug 31 16:20:16 volumiopi3bplus mpd_monitor.sh[588]: mpd: no process found Aug 31 16:20:16 volumiopi3bplus avahi-daemon[582]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Aug 31 16:20:16 volumiopi3bplus avahi-daemon[582]: Successfully dropped root privileges. Aug 31 16:20:16 volumiopi3bplus avahi-daemon[582]: avahi-daemon 0.7 starting up. Aug 31 16:20:16 volumiopi3bplus volumio-remote-updater[590]: Error: No active session Aug 31 16:20:16 volumiopi3bplus volumio-remote-updater[590]: [2025-08-31 16:20:16] [info] asio async_connect error: system:111 (Connection refused) Aug 31 16:20:16 volumiopi3bplus volumio-remote-updater[590]: [2025-08-31 16:20:16] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 31 16:20:16 volumiopi3bplus volumio-remote-updater[590]: [2025-08-31 16:20:16] [error] handle_connect error: Underlying Transport Error Aug 31 16:20:16 volumiopi3bplus kernel: 8021q: 802.1Q VLAN Support v1.8 Aug 31 16:20:16 volumiopi3bplus kernel: 8021q: adding VLAN 0 to HW filter on device eth0 Aug 31 16:20:16 volumiopi3bplus dhcpcd[578]: forked to background, child pid 651 Aug 31 16:20:16 volumiopi3bplus volumio-time-update[591]: volumio-time-update-util: Date not found in response Aug 31 16:20:16 volumiopi3bplus volumio-time-update[591]: volumio-time-update-util: Retrying in 5 seconds... Aug 31 16:20:16 volumiopi3bplus systemd[1]: Started triggerhappy global hotkey daemon. Aug 31 16:20:16 volumiopi3bplus systemd[1]: hciuart.service: Succeeded. Aug 31 16:20:16 volumiopi3bplus systemd[1]: Started Configure Bluetooth Modems connected by UART. Aug 31 16:20:16 volumiopi3bplus systemd[1]: Started dhcpcd on all interfaces. Aug 31 16:20:16 volumiopi3bplus systemd[1]: Started Save/Restore Sound Card State. Aug 31 16:20:16 volumiopi3bplus wpa_supplicant[602]: Successfully initialized wpa_supplicant Aug 31 16:20:16 volumiopi3bplus avahi-daemon[582]: Successfully called chroot(). Aug 31 16:20:16 volumiopi3bplus avahi-daemon[582]: Successfully dropped remaining capabilities. Aug 31 16:20:17 volumiopi3bplus avahi-daemon[582]: Loading service file /services/volumio.service. Aug 31 16:20:17 volumiopi3bplus ifup[476]: ifup: waiting for lock on /run/network/ifstate.wlan0 Aug 31 16:20:17 volumiopi3bplus sh[474]: eth0=eth0 Aug 31 16:20:17 volumiopi3bplus sh[478]: wlan0=wlan0 Aug 31 16:20:17 volumiopi3bplus avahi-daemon[582]: Network interface enumeration completed. Aug 31 16:20:17 volumiopi3bplus avahi-daemon[582]: Server startup complete. Host name is volumiopi3bplus.local. Local service cookie is 173395886. Aug 31 16:20:17 volumiopi3bplus avahi-daemon[582]: Service "VolumioPi3BPlus" (/services/volumio.service) successfully established. Aug 31 16:20:17 volumiopi3bplus volumio[594]: Could not open config: /tmp/upmpdcli.conf Aug 31 16:20:17 volumiopi3bplus systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 31 16:20:17 volumiopi3bplus systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 31 16:20:17 volumiopi3bplus systemd[1]: Started Avahi mDNS/DNS-SD Stack. Aug 31 16:20:17 volumiopi3bplus systemd[1]: Started WPA supplicant. Aug 31 16:20:17 volumiopi3bplus systemd[1]: Started Login Service. Aug 31 16:20:17 volumiopi3bplus systemd[1]: Listening on mpd.socket. Aug 31 16:20:17 volumiopi3bplus systemd[1]: Reached target Sound Card. Aug 31 16:20:17 volumiopi3bplus systemd[1]: Starting Raspberry Pi bluetooth helper... Aug 31 16:20:17 volumiopi3bplus systemd[1]: Started Raise network interfaces. Aug 31 16:20:17 volumiopi3bplus systemd[1]: Reached target Network. Aug 31 16:20:17 volumiopi3bplus dhcpcd-run-hooks[713]: wlan0: starting wpa_supplicant Aug 31 16:20:17 volumiopi3bplus systemd[1]: Starting Permit User Sessions... Aug 31 16:20:17 volumiopi3bplus systemd[1]: Starting Music Player Daemon... Aug 31 16:20:17 volumiopi3bplus systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Aug 31 16:20:17 volumiopi3bplus systemd[1]: Starting Network Time Service... Aug 31 16:20:17 volumiopi3bplus systemd[1]: Reached target Network is Online. Aug 31 16:20:17 volumiopi3bplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 31 16:20:17 volumiopi3bplus systemd[1]: Starting Samba NMB Daemon... Aug 31 16:20:17 volumiopi3bplus systemd[1]: Starting LSB: Brings up/down network automatically... Aug 31 16:20:17 volumiopi3bplus systemd[1]: Starting /etc/rc.local Compatibility... Aug 31 16:20:17 volumiopi3bplus systemd[1]: Started Permit User Sessions. Aug 31 16:20:17 volumiopi3bplus systemd[1]: iptables.service: Succeeded. Aug 31 16:20:17 volumiopi3bplus loadcpufreq[605]: Loading cpufreq kernel modules...done (none). Aug 31 16:20:17 volumiopi3bplus haveged[538]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Aug 31 16:20:17 volumiopi3bplus haveged[538]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Aug 31 16:20:17 volumiopi3bplus haveged[538]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99678 Aug 31 16:20:17 volumiopi3bplus haveged[538]: haveged: fills: 0, generated: 0 Aug 31 16:20:17 volumiopi3bplus systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Aug 31 16:20:17 volumiopi3bplus systemd[1]: Started /etc/rc.local Compatibility. Aug 31 16:20:17 volumiopi3bplus bthelper[714]: Raspberry Pi BDADDR already set Aug 31 16:20:17 volumiopi3bplus dhcpcd[651]: wlan0: connected to Access Point `' Aug 31 16:20:17 volumiopi3bplus systemd[1]: Started Raspberry Pi bluetooth helper. Aug 31 16:20:17 volumiopi3bplus systemd[1]: Starting Bluetooth service... Aug 31 16:20:17 volumiopi3bplus dhcpcd[651]: eth0: waiting for carrier Aug 31 16:20:17 volumiopi3bplus systemd[1]: Starting Terminate Plymouth Boot Screen... Aug 31 16:20:17 volumiopi3bplus systemd[1]: Starting Hold until boot process finishes up... Aug 31 16:20:17 volumiopi3bplus dhcpcd[651]: wlan0: waiting for carrier Aug 31 16:20:17 volumiopi3bplus systemd[1]: Starting LSB: set CPUFreq kernel parameters... Aug 31 16:20:17 volumiopi3bplus systemd[1]: Received SIGRTMIN+21 from PID 181 (plymouthd). Aug 31 16:20:17 volumiopi3bplus ifplugd(eth0)[783]: ifplugd 0.28 initializing. Aug 31 16:20:17 volumiopi3bplus ifplugd(eth0)[783]: Using interface eth0/B8:27:EB:FB:FC:F5 with driver (version: 6.6.62-v7+) Aug 31 16:20:17 volumiopi3bplus ifplugd(eth0)[783]: Using detection mode: SIOCETHTOOL Aug 31 16:20:17 volumiopi3bplus ifplugd(eth0)[783]: Initialization complete, link beat not detected. Aug 31 16:20:17 volumiopi3bplus systemd[1]: Received SIGRTMIN+21 from PID 181 (plymouthd). Aug 31 16:20:17 volumiopi3bplus systemd[1]: plymouth-quit-wait.service: Succeeded. Aug 31 16:20:17 volumiopi3bplus systemd[1]: Started Hold until boot process finishes up. Aug 31 16:20:17 volumiopi3bplus systemd[1]: plymouth-quit.service: Succeeded. Aug 31 16:20:17 volumiopi3bplus systemd[1]: Started Terminate Plymouth Boot Screen. Aug 31 16:20:17 volumiopi3bplus systemd[1]: Started Getty on tty1. Aug 31 16:20:17 volumiopi3bplus systemd[1]: Reached target Login Prompts. Aug 31 16:20:18 volumiopi3bplus ifplugd[731]: Network Interface Plugging Daemon...start eth0...done. Aug 31 16:20:18 volumiopi3bplus systemd[1]: Started LSB: Brings up/down network automatically. Aug 31 16:20:18 volumiopi3bplus ntpd[726]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 31 16:20:18 volumiopi3bplus systemd[1]: Started Network Time Service. Aug 31 16:20:18 volumiopi3bplus ntpd[726]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Aug 31 16:20:18 volumiopi3bplus ntpd[789]: proto: precision = 0.729 usec (-20) Aug 31 16:20:18 volumiopi3bplus ntpd[789]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 31 16:20:18 volumiopi3bplus ntpd[789]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 31 16:20:18 volumiopi3bplus ntpd[789]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 247 days ago Aug 31 16:20:18 volumiopi3bplus ntpd[789]: Listen and drop on 0 v6wildcard [::]:123 Aug 31 16:20:18 volumiopi3bplus ntpd[789]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 31 16:20:18 volumiopi3bplus ntpd[789]: Listen normally on 2 lo 127.0.0.1:123 Aug 31 16:20:18 volumiopi3bplus ntpd[789]: Listening on routing socket on fd #19 for interface updates Aug 31 16:20:18 volumiopi3bplus ntpd[789]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 31 16:20:18 volumiopi3bplus ntpd[789]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 31 16:20:18 volumiopi3bplus cpufrequtils[779]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Aug 31 16:20:18 volumiopi3bplus systemd[1]: Started LSB: set CPUFreq kernel parameters. Aug 31 16:20:18 volumiopi3bplus bluetoothd[763]: Bluetooth daemon 5.50 Aug 31 16:20:18 volumiopi3bplus bluetoothd[763]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Aug 31 16:20:18 volumiopi3bplus systemd[1]: Started Bluetooth service. Aug 31 16:20:18 volumiopi3bplus systemd[1]: Reached target Bluetooth. Aug 31 16:20:18 volumiopi3bplus bluetoothd[763]: Starting SDP server Aug 31 16:20:18 volumiopi3bplus bluetoothd[763]: Excluding (cli) sap Aug 31 16:20:18 volumiopi3bplus kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Aug 31 16:20:18 volumiopi3bplus kernel: Bluetooth: BNEP filters: protocol multicast Aug 31 16:20:18 volumiopi3bplus kernel: Bluetooth: BNEP socket layer initialized Aug 31 16:20:18 volumiopi3bplus bluetoothd[763]: Bluetooth management interface 1.22 initialized Aug 31 16:20:18 volumiopi3bplus dbus-daemon[595]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=763 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Aug 31 16:20:18 volumiopi3bplus kernel: Bluetooth: MGMT ver 1.22 Aug 31 16:20:18 volumiopi3bplus sudo[719]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 16:20:18 volumiopi3bplus sudo[719]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:18 volumiopi3bplus sudo[719]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 31 16:20:18 volumiopi3bplus sudo[719]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:18 volumiopi3bplus bluetoothd[763]: Failed to set privacy: Rejected (0x0b) Aug 31 16:20:18 volumiopi3bplus systemd[1]: Starting Hostname Service... Aug 31 16:20:19 volumiopi3bplus dbus-daemon[595]: [system] Successfully activated service 'org.freedesktop.hostname1' Aug 31 16:20:19 volumiopi3bplus systemd[1]: Started Hostname Service. Aug 31 16:20:19 volumiopi3bplus ntpd[789]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 31 16:20:19 volumiopi3bplus nmbd[749]: [2025/08/31 16:20:19.652667, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Aug 31 16:20:19 volumiopi3bplus nmbd[749]: started asyncdns process 811 Aug 31 16:20:19 volumiopi3bplus nmbd[749]: [2025/08/31 16:20:19.655437, 0] ../lib/util/become_daemon.c:149(daemon_status) Aug 31 16:20:19 volumiopi3bplus nmbd[749]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Aug 31 16:20:19 volumiopi3bplus nmbd[749]: [2025/08/31 16:20:19.655633, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Aug 31 16:20:19 volumiopi3bplus nmbd[749]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Aug 31 16:20:20 volumiopi3bplus wireless.js[589]: Cleaning previous... Aug 31 16:20:20 volumiopi3bplus ntpd[789]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 31 16:20:20 volumiopi3bplus sudo[819]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 31 16:20:20 volumiopi3bplus sudo[819]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:20 volumiopi3bplus sudo[819]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:20 volumiopi3bplus sudo[821]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 31 16:20:20 volumiopi3bplus sudo[821]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:20 volumiopi3bplus systemd[1]: systemd-rfkill.service: Succeeded. Aug 31 16:20:20 volumiopi3bplus sudo[821]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:20 volumiopi3bplus wireless.js[589]: Stopped aP Aug 31 16:20:21 volumiopi3bplus sudo[828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 31 16:20:21 volumiopi3bplus kernel: ieee80211 phy0: brcmf_proto_bcdc_query_dcmd: brcmf_proto_bcdc_msg failed w/status -110 Aug 31 16:20:21 volumiopi3bplus kernel: brcmfmac: brcmf_dump_obss: dump_obss error (-110) Aug 31 16:20:21 volumiopi3bplus sudo[828]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:21 volumiopi3bplus kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 31 16:20:21 volumiopi3bplus sudo[828]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:21 volumiopi3bplus sudo[830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Aug 31 16:20:21 volumiopi3bplus sudo[830]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:21 volumiopi3bplus sudo[830]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:21 volumiopi3bplus sudo[837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 31 16:20:21 volumiopi3bplus sudo[837]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:21 volumiopi3bplus sudo[837]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:21 volumiopi3bplus sudo[839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Aug 31 16:20:21 volumiopi3bplus sudo[839]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:21 volumiopi3bplus ntpd[789]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 31 16:20:21 volumiopi3bplus volumio-remote-updater[590]: [2025-08-31 16:20:21] [info] asio async_connect error: system:111 (Connection refused) Aug 31 16:20:21 volumiopi3bplus volumio-remote-updater[590]: [2025-08-31 16:20:21] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 31 16:20:21 volumiopi3bplus volumio-remote-updater[590]: [2025-08-31 16:20:21] [error] handle_connect error: Underlying Transport Error Aug 31 16:20:21 volumiopi3bplus volumio-time-update[591]: volumio-time-update-util: Fetching time from Volumio... Aug 31 16:20:21 volumiopi3bplus volumio-time-update[591]: volumio-time-update-util: Date not found in response Aug 31 16:20:21 volumiopi3bplus volumio-time-update[591]: volumio-time-update-util: Retrying in 5 seconds... Aug 31 16:20:22 volumiopi3bplus ntpd[789]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 31 16:20:22 volumiopi3bplus mpd[808]: Aug 31 16:20 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 16:20:22 volumiopi3bplus systemd[1]: Started Music Player Daemon. Aug 31 16:20:22 volumiopi3bplus mpd_monitor.sh[588]: MPD Monitor Service: MPD restarted due to no mpc output. Aug 31 16:20:22 volumiopi3bplus bthelper[714]: Changing power off succeeded Aug 31 16:20:22 volumiopi3bplus bthelper[714]: [CHG] Controller B8:27:EB:51:56:5F Class: 0x0000041c Aug 31 16:20:22 volumiopi3bplus bthelper[714]: Changing power on succeeded Aug 31 16:20:23 volumiopi3bplus sudo[839]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:23 volumiopi3bplus wireless.js[589]: SETTING APPROPRIATE REG DOMAIN: US Aug 31 16:20:23 volumiopi3bplus sudo[858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 31 16:20:23 volumiopi3bplus sudo[858]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:23 volumiopi3bplus sudo[858]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:24 volumiopi3bplus sudo[860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Aug 31 16:20:24 volumiopi3bplus sudo[860]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:24 volumiopi3bplus sudo[860]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:24 volumiopi3bplus wireless.js[589]: SUCCESSFULLY SET NEW REGDOMAIN: US Aug 31 16:20:24 volumiopi3bplus wireless.js[589]: Start wireless flow Aug 31 16:20:24 volumiopi3bplus wireless.js[589]: Stopped hotspot (if there).. Aug 31 16:20:24 volumiopi3bplus sudo[865]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 31 16:20:24 volumiopi3bplus sudo[865]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:24 volumiopi3bplus sudo[865]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:24 volumiopi3bplus sudo[867]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 31 16:20:24 volumiopi3bplus sudo[867]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:24 volumiopi3bplus sudo[867]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:24 volumiopi3bplus wireless.js[589]: DHCP IP Aug 31 16:20:24 volumiopi3bplus wireless.js[589]: Start ap Aug 31 16:20:24 volumiopi3bplus wpa_supplicant[869]: Successfully initialized wpa_supplicant Aug 31 16:20:24 volumiopi3bplus kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 31 16:20:24 volumiopi3bplus sudo[870]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Aug 31 16:20:24 volumiopi3bplus sudo[870]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:24 volumiopi3bplus dhcpcd[871]: sending commands to master dhcpcd process Aug 31 16:20:24 volumiopi3bplus dhcpcd[651]: control command: /sbin/dhcpcd Aug 31 16:20:24 volumiopi3bplus sudo[870]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:24 volumiopi3bplus dhcpcd[651]: lo: carrier acquired Aug 31 16:20:24 volumiopi3bplus dhcpcd[651]: DUID 00:01:00:01:30:17:4e:da:b8:27:eb:fb:fc:f5 Aug 31 16:20:24 volumiopi3bplus dhcpcd[651]: lo: IAID 6c:6f:00:00 Aug 31 16:20:24 volumiopi3bplus dhcpcd[651]: lo: ipv6_start: Operation not supported Aug 31 16:20:24 volumiopi3bplus dhcpcd[651]: lo: using static address 127.0.0.1/8 Aug 31 16:20:24 volumiopi3bplus dhcpcd[651]: lo: adding route to 127.0.0.0/8 Aug 31 16:20:24 volumiopi3bplus dhcpcd[651]: arp_announce: Invalid argument Aug 31 16:20:25 volumiopi3bplus wireless.js[589]: trying... Aug 31 16:20:25 volumiopi3bplus sudo[899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 31 16:20:25 volumiopi3bplus sudo[899]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:25 volumiopi3bplus sudo[899]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:26 volumiopi3bplus wireless.js[589]: trying... Aug 31 16:20:26 volumiopi3bplus sudo[902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 31 16:20:26 volumiopi3bplus sudo[902]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:26 volumiopi3bplus sudo[902]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:26 volumiopi3bplus volumio-time-update[591]: volumio-time-update-util: Fetching time from Volumio... Aug 31 16:20:26 volumiopi3bplus volumio-time-update[591]: volumio-time-update-util: Date not found in response Aug 31 16:20:26 volumiopi3bplus volumio-time-update[591]: volumio-time-update-util: Retrying in 5 seconds... Aug 31 16:20:27 volumiopi3bplus wireless.js[589]: trying... Aug 31 16:20:27 volumiopi3bplus sudo[910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 31 16:20:27 volumiopi3bplus sudo[910]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:27 volumiopi3bplus sudo[910]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:28 volumiopi3bplus wireless.js[589]: trying... Aug 31 16:20:28 volumiopi3bplus sudo[913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 31 16:20:28 volumiopi3bplus sudo[913]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:28 volumiopi3bplus sudo[913]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:28 volumiopi3bplus kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Aug 31 16:20:28 volumiopi3bplus kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Aug 31 16:20:29 volumiopi3bplus kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Aug 31 16:20:29 volumiopi3bplus kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Aug 31 16:20:29 volumiopi3bplus volumio-remote-updater[590]: [2025-08-31 16:20:29] [info] asio async_connect error: system:111 (Connection refused) Aug 31 16:20:29 volumiopi3bplus volumio-remote-updater[590]: [2025-08-31 16:20:29] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 31 16:20:29 volumiopi3bplus volumio-remote-updater[590]: [2025-08-31 16:20:29] [error] handle_connect error: Underlying Transport Error Aug 31 16:20:29 volumiopi3bplus wireless.js[589]: trying... Aug 31 16:20:29 volumiopi3bplus sudo[916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 31 16:20:29 volumiopi3bplus sudo[916]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:29 volumiopi3bplus sudo[916]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:30 volumiopi3bplus wireless.js[589]: trying... Aug 31 16:20:30 volumiopi3bplus sudo[919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 31 16:20:30 volumiopi3bplus sudo[919]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:30 volumiopi3bplus sudo[919]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:31 volumiopi3bplus wpa_supplicant[876]: wlan0: Trying to associate with SSID 'wlmg3005' Aug 31 16:20:31 volumiopi3bplus wireless.js[589]: trying... Aug 31 16:20:31 volumiopi3bplus sudo[922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 31 16:20:31 volumiopi3bplus sudo[922]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:31 volumiopi3bplus sudo[922]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:31 volumiopi3bplus wpa_supplicant[876]: wlan0: Associated with 3c:7c:3f:54:51:cc Aug 31 16:20:31 volumiopi3bplus wpa_supplicant[876]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3c:7c:3f:54:51:cc completed [id=0 id_str=] Aug 31 16:20:31 volumiopi3bplus dhcpcd[651]: wlan0: carrier acquired Aug 31 16:20:31 volumiopi3bplus wpa_supplicant[876]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 31 16:20:31 volumiopi3bplus dhcpcd[651]: wlan0: connected to Access Point `wlmg3005' Aug 31 16:20:31 volumiopi3bplus wpa_supplicant[876]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Aug 31 16:20:31 volumiopi3bplus dhcpcd[651]: wlan0: IAID eb:ae:a9:a0 Aug 31 16:20:31 volumiopi3bplus dhcpcd[651]: wlan0: adding address fe80::ba27:ebff:feae:a9a0 Aug 31 16:20:31 volumiopi3bplus dhcpcd[651]: ipv6_addaddr1: Permission denied Aug 31 16:20:31 volumiopi3bplus dhcpcd[651]: wlan0: probing address 10.1.1.247/24 Aug 31 16:20:31 volumiopi3bplus volumio-time-update[591]: volumio-time-update-util: Fetching time from Volumio... Aug 31 16:20:31 volumiopi3bplus volumio-time-update[591]: volumio-time-update-util: Date not found in response Aug 31 16:20:31 volumiopi3bplus volumio-time-update[591]: volumio-time-update-util: Retrying in 5 seconds... Aug 31 16:20:32 volumiopi3bplus dhcpcd[651]: wlan0: soliciting an IPv6 router Aug 31 16:20:32 volumiopi3bplus wireless.js[589]: trying... Aug 31 16:20:32 volumiopi3bplus sudo[932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 31 16:20:32 volumiopi3bplus sudo[932]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:32 volumiopi3bplus sudo[932]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:33 volumiopi3bplus wireless.js[589]: trying... Aug 31 16:20:33 volumiopi3bplus sudo[935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 31 16:20:33 volumiopi3bplus sudo[935]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:33 volumiopi3bplus sudo[935]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:34 volumiopi3bplus wireless.js[589]: trying... Aug 31 16:20:34 volumiopi3bplus sudo[938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 31 16:20:34 volumiopi3bplus sudo[938]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:34 volumiopi3bplus sudo[938]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:35 volumiopi3bplus wireless.js[589]: trying... Aug 31 16:20:35 volumiopi3bplus sudo[941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 31 16:20:35 volumiopi3bplus sudo[941]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:35 volumiopi3bplus sudo[941]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:36 volumiopi3bplus dhcpcd[651]: wlan0: using static address 10.1.1.247/24 Aug 31 16:20:36 volumiopi3bplus avahi-daemon[582]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.1.247. Aug 31 16:20:36 volumiopi3bplus dhcpcd[651]: wlan0: adding route to 10.1.1.0/24 Aug 31 16:20:36 volumiopi3bplus dhcpcd[651]: wlan0: adding default route via 10.1.1.1 Aug 31 16:20:36 volumiopi3bplus avahi-daemon[582]: New relevant interface wlan0.IPv4 for mDNS. Aug 31 16:20:36 volumiopi3bplus avahi-daemon[582]: Registering new address record for 10.1.1.247 on wlan0.IPv4. Aug 31 16:20:36 volumiopi3bplus wireless.js[589]: trying... Aug 31 16:20:36 volumiopi3bplus sudo[963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 31 16:20:36 volumiopi3bplus sudo[963]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:36 volumiopi3bplus sudo[963]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:36 volumiopi3bplus wireless.js[589]: Connected to: ----wlmg3005 Aug 31 16:20:36 volumiopi3bplus wireless.js[589]: ---- Aug 31 16:20:36 volumiopi3bplus sudo[966]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 16:20:36 volumiopi3bplus sudo[966]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:36 volumiopi3bplus sudo[966]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:36 volumiopi3bplus wireless.js[589]: ... joined AP, wlan0 IPv4 is 10.1.1.247, ipV6 is undefined Aug 31 16:20:36 volumiopi3bplus wireless.js[589]: It's done! AP Aug 31 16:20:36 volumiopi3bplus systemd[1]: Started Wireless Services. Aug 31 16:20:36 volumiopi3bplus kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 31 16:20:36 volumiopi3bplus systemd[1]: Started Volumio Backend Module. Aug 31 16:20:36 volumiopi3bplus systemd[1]: Started Volumio Cpu Tweaker. Aug 31 16:20:36 volumiopi3bplus volumio-cpu-tweak[972]: Setting RT Priority for mpd Aug 31 16:20:36 volumiopi3bplus volumio-cpu-tweak[972]: Setting MPD Affinity Aug 31 16:20:36 volumiopi3bplus volumio-cpu-tweak[972]: pid 808's current affinity mask: f Aug 31 16:20:36 volumiopi3bplus volumio-cpu-tweak[972]: pid 808's new affinity mask: 3 Aug 31 16:20:36 volumiopi3bplus volumio-cpu-tweak[972]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Aug 31 16:20:36 volumiopi3bplus volumio-cpu-tweak[972]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Aug 31 16:20:36 volumiopi3bplus volumio-time-update[591]: volumio-time-update-util: Fetching time from Volumio... Aug 31 16:20:36 volumiopi3bplus systemd[1]: volumio_cpu_tweak.service: Succeeded. Aug 31 16:20:37 volumiopi3bplus ntpd[789]: Listen normally on 3 wlan0 10.1.1.247:123 Aug 31 16:20:37 volumiopi3bplus ntpd[789]: new interface(s) found: waking up resolver Aug 31 16:20:37 volumiopi3bplus volumio-time-update[591]: volumio-time-update-util: Setting system time to: 2025-08-31 16:20:58 Aug 31 16:20:37 volumiopi3bplus sudo[993]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-08-31 16:20:58 Aug 31 16:20:37 volumiopi3bplus sudo[993]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:20:37 volumiopi3bplus dbus-daemon[595]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.9' (uid=0 pid=994 comm="timedatectl set-time 2025-08-31 16:20:58 ") Aug 31 16:20:37 volumiopi3bplus systemd[1]: Starting Time & Date Service... Aug 31 16:20:38 volumiopi3bplus dbus-daemon[595]: [system] Successfully activated service 'org.freedesktop.timedate1' Aug 31 16:20:38 volumiopi3bplus systemd[1]: Started Time & Date Service. Aug 31 16:20:58 volumiopi3bplus systemd-timedated[995]: Changed local time to Sun Aug 31 16:20:58 2025 Aug 31 16:20:58 volumiopi3bplus sudo[993]: pam_unix(sudo:session): session closed for user root Aug 31 16:20:58 volumiopi3bplus volumio-time-update[591]: volumio-time-update-util: System time updated successfully. Aug 31 16:20:58 volumiopi3bplus systemd[1]: Started Volumio Time Update Utility. Aug 31 16:20:59 volumiopi3bplus volumio-remote-updater[590]: [2025-08-31 16:20:59] [connect] Successful connection Aug 31 16:20:59 volumiopi3bplus volumio[971]: info: ------------------------------------------- Aug 31 16:20:59 volumiopi3bplus volumio[971]: info: ----- Volumio3 ---- Aug 31 16:20:59 volumiopi3bplus volumio[971]: info: ------------------------------------------- Aug 31 16:20:59 volumiopi3bplus volumio[971]: info: ----- System startup ---- Aug 31 16:20:59 volumiopi3bplus volumio[971]: info: ------------------------------------------- Aug 31 16:20:59 volumiopi3bplus nmbd[749]: [2025/08/31 16:20:59.602891, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 31 16:20:59 volumiopi3bplus nmbd[749]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Aug 31 16:20:59 volumiopi3bplus systemd[1]: Started Samba NMB Daemon. Aug 31 16:20:59 volumiopi3bplus nmbd[749]: [2025/08/31 16:20:59.621304, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 31 16:20:59 volumiopi3bplus nmbd[749]: query_name_response: Multiple (2) responses received for a query on subnet 10.1.1.247 for name WORKGROUP<1d>. Aug 31 16:20:59 volumiopi3bplus nmbd[749]: This response was from IP 10.1.1.40, reporting an IP address of 10.1.1.40. Aug 31 16:20:59 volumiopi3bplus systemd[1]: Starting Samba Winbind Daemon... Aug 31 16:21:00 volumiopi3bplus winbindd[1004]: [2025/08/31 16:21:00.000149, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Aug 31 16:21:00 volumiopi3bplus winbindd[1004]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Aug 31 16:21:00 volumiopi3bplus winbindd[1004]: [2025/08/31 16:21:00.047178, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 31 16:21:00 volumiopi3bplus winbindd[1004]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Aug 31 16:21:00 volumiopi3bplus systemd[1]: Started Samba Winbind Daemon. Aug 31 16:21:00 volumiopi3bplus systemd[1]: Starting Samba SMB Daemon... Aug 31 16:21:00 volumiopi3bplus smbd[1009]: [2025/08/31 16:21:00.659933, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 31 16:21:00 volumiopi3bplus systemd[1]: Started Samba SMB Daemon. Aug 31 16:21:00 volumiopi3bplus smbd[1009]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Aug 31 16:21:00 volumiopi3bplus systemd[1]: Reached target Multi-User System. Aug 31 16:21:00 volumiopi3bplus systemd[1]: Reached target Graphical Interface. Aug 31 16:21:00 volumiopi3bplus systemd[1]: Starting Update UTMP about System Runlevel Changes... Aug 31 16:21:00 volumiopi3bplus systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Aug 31 16:21:00 volumiopi3bplus systemd[1]: Started Update UTMP about System Runlevel Changes. Aug 31 16:21:00 volumiopi3bplus systemd[1]: Startup finished in 13.422s (kernel) + 30.082s (userspace) = 43.504s. Aug 31 16:21:01 volumiopi3bplus volumio[971]: info: MYVOLUMIO Environment detected Aug 31 16:21:01 volumiopi3bplus volumio[971]: info: Plugin folders cleanup Aug 31 16:21:01 volumiopi3bplus volumio[971]: info: Scanning into folder /volumio/app/plugins/ Aug 31 16:21:01 volumiopi3bplus volumio[971]: info: Scanning category audio_interface Aug 31 16:21:01 volumiopi3bplus volumio[971]: info: Scanning category miscellanea Aug 31 16:21:01 volumiopi3bplus volumio[971]: info: Scanning category music_service Aug 31 16:21:01 volumiopi3bplus volumio[971]: info: Scanning category plugins.json Aug 31 16:21:01 volumiopi3bplus volumio[971]: info: Scanning category system_controller Aug 31 16:21:01 volumiopi3bplus volumio[971]: info: Scanning category user_interface Aug 31 16:21:01 volumiopi3bplus volumio[971]: info: Scanning into folder /data/plugins/ Aug 31 16:21:01 volumiopi3bplus volumio[971]: info: Scanning category audio_interface Aug 31 16:21:01 volumiopi3bplus volumio[971]: info: Scanning category system_controller Aug 31 16:21:01 volumiopi3bplus volumio[971]: info: Scanning category user_interface Aug 31 16:21:01 volumiopi3bplus volumio[971]: info: Plugin folders cleanup completed Aug 31 16:21:01 volumiopi3bplus volumio[971]: info: ------------------------------------------- Aug 31 16:21:01 volumiopi3bplus volumio[971]: info: ----- Core plugins startup ---- Aug 31 16:21:01 volumiopi3bplus volumio[971]: info: ------------------------------------------- Aug 31 16:21:01 volumiopi3bplus volumio[971]: info: Loading plugins from folder /volumio/app/plugins/ Aug 31 16:21:01 volumiopi3bplus volumio[971]: info: Adding plugin upnp to MyMusic Plugins Aug 31 16:21:01 volumiopi3bplus volumio[971]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 31 16:21:01 volumiopi3bplus volumio[971]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 31 16:21:01 volumiopi3bplus volumio[971]: info: Loading plugins from folder /data/plugins/ Aug 31 16:21:01 volumiopi3bplus volumio[971]: info: Loading plugin "system"... Aug 31 16:21:01 volumiopi3bplus volumio[971]: info: Loading plugin "appearance"... Aug 31 16:21:02 volumiopi3bplus volumio[971]: info: Loading plugin "network"... Aug 31 16:21:03 volumiopi3bplus volumio[971]: info: Refreshing Cached IP Addresses Aug 31 16:21:03 volumiopi3bplus sudo[1027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 16:21:03 volumiopi3bplus sudo[1027]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:21:03 volumiopi3bplus sudo[1029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 16:21:03 volumiopi3bplus sudo[1027]: pam_unix(sudo:session): session closed for user root Aug 31 16:21:03 volumiopi3bplus sudo[1029]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:21:03 volumiopi3bplus sudo[1029]: pam_unix(sudo:session): session closed for user root Aug 31 16:21:03 volumiopi3bplus volumio[971]: info: Loading plugin "services"... Aug 31 16:21:03 volumiopi3bplus volumio[971]: info: Loading plugin "alsa_controller"... Aug 31 16:21:03 volumiopi3bplus sudo[1038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 31 16:21:03 volumiopi3bplus sudo[1038]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:21:03 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 16:21:03 volumiopi3bplus volumio[971]: info: Loading plugin "wizard"... Aug 31 16:21:03 volumiopi3bplus volumio[971]: info: Loading plugin "networkfs"... Aug 31 16:21:03 volumiopi3bplus volumio[971]: info: Starting Udev Watcher for removable devices Aug 31 16:21:03 volumiopi3bplus sudo[1053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=mhg,password=z0c0lip0p0,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.1.1.9/Music /mnt/NAS/Music Aug 31 16:21:03 volumiopi3bplus sudo[1053]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:21:03 volumiopi3bplus volumio[971]: info: Ignoring mount for partition: boot Aug 31 16:21:03 volumiopi3bplus volumio[971]: info: Ignoring mount for partition: volumio Aug 31 16:21:03 volumiopi3bplus volumio[971]: info: Ignoring mount for partition: volumio_data Aug 31 16:21:03 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 16:21:03 volumiopi3bplus volumio[971]: info: Loading plugin "volumio_command_line_client"... Aug 31 16:21:03 volumiopi3bplus volumio[971]: info: Loading plugin "upnp"... Aug 31 16:21:03 volumiopi3bplus volumio[971]: info: [1756628463362] Starting Upmpd Daemon Aug 31 16:21:03 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 16:21:03 volumiopi3bplus volumio[971]: info: Loading plugin "my_music"... Aug 31 16:21:03 volumiopi3bplus volumio[971]: info: Loading plugin "mpd"... Aug 31 16:21:03 volumiopi3bplus kernel: Key type cifs.spnego registered Aug 31 16:21:03 volumiopi3bplus kernel: Key type cifs.idmap registered Aug 31 16:21:03 volumiopi3bplus kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Aug 31 16:21:03 volumiopi3bplus kernel: CIFS: Attempting to mount //10.1.1.9/Music Aug 31 16:21:03 volumiopi3bplus kernel: cryptd: max_cpu_qlen set to 1000 Aug 31 16:21:04 volumiopi3bplus volumio[971]: info: Loading plugin "upnp_browser"... Aug 31 16:21:04 volumiopi3bplus systemd[1]: systemd-fsckd.service: Succeeded. Aug 31 16:21:04 volumiopi3bplus sudo[1053]: pam_unix(sudo:session): session closed for user root Aug 31 16:21:04 volumiopi3bplus volumio[971]: info: Loading plugin "alarm-clock"... Aug 31 16:21:04 volumiopi3bplus volumio[971]: info: Loading plugin "airplay_emulation"... Aug 31 16:21:04 volumiopi3bplus volumio[971]: info: Starting Shairport Sync Aug 31 16:21:04 volumiopi3bplus volumio[971]: info: Loading plugin "last_100"... Aug 31 16:21:04 volumiopi3bplus volumio[971]: info: Loading plugin "webradio"... Aug 31 16:21:05 volumiopi3bplus volumio[971]: info: Loading plugin "i2s_dacs"... Aug 31 16:21:05 volumiopi3bplus volumio[971]: info: I2S DAC not set, start Auto-detection Aug 31 16:21:05 volumiopi3bplus volumio[971]: info: Loading plugin "volumiodiscovery"... Aug 31 16:21:05 volumiopi3bplus volumio[971]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 31 16:21:05 volumiopi3bplus volumio[971]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 16:21:05 volumiopi3bplus volumio[971]: *** WARNING *** For more information see Aug 31 16:21:05 volumiopi3bplus volumio[971]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 31 16:21:05 volumiopi3bplus volumio[971]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 16:21:05 volumiopi3bplus volumio[971]: *** WARNING *** For more information see Aug 31 16:21:05 volumiopi3bplus node[971]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 31 16:21:05 volumiopi3bplus node[971]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 16:21:05 volumiopi3bplus node[971]: *** WARNING *** For more information see Aug 31 16:21:05 volumiopi3bplus node[971]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 31 16:21:05 volumiopi3bplus node[971]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 16:21:05 volumiopi3bplus node[971]: *** WARNING *** For more information see Aug 31 16:21:05 volumiopi3bplus volumio[971]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 31 16:21:05 volumiopi3bplus volumio[971]: info: Discovery: Started advertising with name: VolumioPi3BPlus Aug 31 16:21:05 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 16:21:05 volumiopi3bplus volumio[971]: info: Loading plugin "outputs"... Aug 31 16:21:05 volumiopi3bplus volumio[971]: info: Loading plugin "albumart"... Aug 31 16:21:05 volumiopi3bplus volumio[971]: info: Plugin example_plugin is not enabled Aug 31 16:21:05 volumiopi3bplus volumio[971]: info: Loading plugin "inputs"... Aug 31 16:21:05 volumiopi3bplus volumio[971]: info: Loading plugin "updater_comm"... Aug 31 16:21:05 volumiopi3bplus volumio[971]: info: Plugin mpdemulation is not enabled Aug 31 16:21:05 volumiopi3bplus volumio[971]: info: Loading plugin "rest_api"... Aug 31 16:21:05 volumiopi3bplus volumio[971]: info: Loading plugin "websocket"... Aug 31 16:21:05 volumiopi3bplus volumio[971]: info: Starting Socket.io Server version 2.3.0 Aug 31 16:21:05 volumiopi3bplus volumio[971]: info: Loading plugin "fusiondsp"... Aug 31 16:21:05 volumiopi3bplus volumio[971]: Forking 3 albumart workers Aug 31 16:21:06 volumiopi3bplus volumio[971]: info: Applying required configuration parameters for plugin fusiondsp Aug 31 16:21:06 volumiopi3bplus volumio[971]: info: Loading plugin "backup_restore"... Aug 31 16:21:06 volumiopi3bplus volumio[971]: Starting albumart workers Aug 31 16:21:06 volumiopi3bplus volumio[971]: Starting albumart workers Aug 31 16:21:06 volumiopi3bplus volumio[971]: Starting albumart workers Aug 31 16:21:07 volumiopi3bplus volumio[971]: info: Applying required configuration parameters for plugin backup_restore Aug 31 16:21:07 volumiopi3bplus volumio[971]: info: Loading plugin "Systeminfo"... Aug 31 16:21:07 volumiopi3bplus sudo[1038]: pam_unix(sudo:session): session closed for user root Aug 31 16:21:07 volumiopi3bplus volumio[971]: info: Loading i18n strings for locale en Aug 31 16:21:08 volumiopi3bplus volumio[971]: Updating browse sources language Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::initPlayerControls Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 16:21:08 volumiopi3bplus volumio[971]: Express server listening on port 3000 Aug 31 16:21:08 volumiopi3bplus volumio[971]: [Metrics] WebUI: 9s 791.82ms Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreStateMachine::resetVolumioState Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreStateMachine::getcurrentVolume Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::volumioRetrievevolume Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreStateMachine::pushState Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CorePlayQueue::getTrack 0 Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::volumioPushState Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: Volumio Network Manager: Network status updated: 2 Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: Reloading queue from file Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: Setting Device type: Raspberry PI Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreStateMachine::setRepeat null single undefined Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreStateMachine::pushState Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CorePlayQueue::getTrack 0 Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::volumioPushState Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreStateMachine::setRandom null Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreStateMachine::pushState Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CorePlayQueue::getTrack 0 Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::volumioPushState Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: Discovery: adding f63bb7dc-28eb-4cb8-bda1-108aca888b44 Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: Discovery: Found device VolumioPi3BPlus Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::volumioGetState Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CorePlayQueue::getTrack 0 Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: Completed loading Core Plugins Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: Preparing to generate the ALSA configuration file Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: Reading ALSA contributions from plugins. Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: Asound.conf file unchanged, so no further update is needed Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: Output device has changed, restarting MPD Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: Output device has changed, restarting Shairport Sync Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 16:21:08 volumiopi3bplus sudo[1153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 16:21:08 volumiopi3bplus sudo[1153]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:21:08 volumiopi3bplus sudo[1153]: pam_unix(sudo:session): session closed for user root Aug 31 16:21:08 volumiopi3bplus sudo[1155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 16:21:08 volumiopi3bplus sudo[1155]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:21:08 volumiopi3bplus systemd[1]: Stopping Music Player Daemon... Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: ___________ START PLUGINS ___________ Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: ControllerMpd::onStart: Initializing MPD Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: Creating MPD Configuration file Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: [1756628468872] CoreMusicLibrary::Adding element Media Servers Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 16:21:08 volumiopi3bplus sudo[1162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 16:21:08 volumiopi3bplus sudo[1162]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:21:08 volumiopi3bplus sudo[1162]: pam_unix(sudo:session): session closed for user root Aug 31 16:21:08 volumiopi3bplus sudo[1164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 16:21:08 volumiopi3bplus sudo[1164]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 16:21:08 volumiopi3bplus systemd[1]: mpd.service: Succeeded. Aug 31 16:21:08 volumiopi3bplus systemd[1]: Stopped Music Player Daemon. Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:08 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 16:21:09 volumiopi3bplus systemd[1]: Starting Music Player Daemon... Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: [1756628469038] CoreMusicLibrary::Adding element Last_100 Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: [1756628469043] CoreMusicLibrary::Adding element Webradio Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: Initializing BBC Radios Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: Loading i18n strings for locale en Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: FusionDsp - mixtype--------------------- None Aug 31 16:21:09 volumiopi3bplus systemd[1]: systemd-hostnamed.service: Succeeded. Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: Volumio Calling Home Aug 31 16:21:09 volumiopi3bplus sudo[1170]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 16:21:09 volumiopi3bplus sudo[1170]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:21:09 volumiopi3bplus sudo[1170]: pam_unix(sudo:session): session closed for user root Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: Preparing to generate the ALSA configuration file Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: Reading ALSA contributions from plugins. Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: MPD Permissions set Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: MPD Permissions set Aug 31 16:21:09 volumiopi3bplus volumio[971]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: Starting Shairport Sync Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: Starting Shairport Sync Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: Starting Shairport Sync Aug 31 16:21:09 volumiopi3bplus sudo[1201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 16:21:09 volumiopi3bplus sudo[1201]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:21:09 volumiopi3bplus sudo[1203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 16:21:09 volumiopi3bplus sudo[1203]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:21:09 volumiopi3bplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 31 16:21:09 volumiopi3bplus sudo[1206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 16:21:09 volumiopi3bplus sudo[1206]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CoreCommandRouter::volumioGetState Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: CorePlayQueue::getTrack 0 Aug 31 16:21:09 volumiopi3bplus systemd[1]: shairport-sync.service: Succeeded. Aug 31 16:21:09 volumiopi3bplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: Asound.conf file unchanged, so no further update is needed Aug 31 16:21:09 volumiopi3bplus volumio[971]: info: Output device has changed, restarting MPD Aug 31 16:21:09 volumiopi3bplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 31 16:21:09 volumiopi3bplus sudo[1201]: pam_unix(sudo:session): session closed for user root Aug 31 16:21:10 volumiopi3bplus sudo[1206]: pam_unix(sudo:session): session closed for user root Aug 31 16:21:10 volumiopi3bplus sudo[1211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 16:21:10 volumiopi3bplus sudo[1211]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:21:10 volumiopi3bplus sudo[1203]: pam_unix(sudo:session): session closed for user root Aug 31 16:21:10 volumiopi3bplus sudo[1211]: pam_unix(sudo:session): session closed for user root Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: Output device has changed, restarting Shairport Sync Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 16:21:10 volumiopi3bplus sudo[1213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 16:21:10 volumiopi3bplus sudo[1213]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:21:10 volumiopi3bplus systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 31 16:21:10 volumiopi3bplus systemd[1]: mpd.service: Succeeded. Aug 31 16:21:10 volumiopi3bplus systemd[1]: Stopped Music Player Daemon. Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 16:21:10 volumiopi3bplus systemd[1]: Starting Music Player Daemon... Aug 31 16:21:10 volumiopi3bplus sudo[1223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 16:21:10 volumiopi3bplus sudo[1223]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:21:10 volumiopi3bplus sudo[1223]: pam_unix(sudo:session): session closed for user root Aug 31 16:21:10 volumiopi3bplus sudo[1225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 16:21:10 volumiopi3bplus sudo[1225]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:21:10 volumiopi3bplus sudo[1225]: pam_unix(sudo:session): session closed for user root Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: Volumio called home Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: MPD Permissions set Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: Shairport-Sync Started Aug 31 16:21:10 volumiopi3bplus volumio[971]: Error adding Membership: Error: addMembership EINVAL Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: Shairport-Sync Started Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: Shairport-Sync Started Aug 31 16:21:10 volumiopi3bplus volumio[971]: verbose: New Socket.io Connection to 10.1.1.247 from 10.1.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:10 volumiopi3bplus sudo[1221]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 16:21:10 volumiopi3bplus sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: Starting Shairport Sync Aug 31 16:21:10 volumiopi3bplus sudo[1221]: pam_unix(sudo:session): session closed for user root Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 16:21:10 volumiopi3bplus sudo[1235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 16:21:10 volumiopi3bplus sudo[1235]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:21:10 volumiopi3bplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 31 16:21:10 volumiopi3bplus systemd[1]: shairport-sync.service: Succeeded. Aug 31 16:21:10 volumiopi3bplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 31 16:21:10 volumiopi3bplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 31 16:21:10 volumiopi3bplus sudo[1235]: pam_unix(sudo:session): session closed for user root Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: Shairport-Sync Started Aug 31 16:21:10 volumiopi3bplus sudo[1239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 16:21:10 volumiopi3bplus sudo[1239]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:21:10 volumiopi3bplus sudo[1239]: pam_unix(sudo:session): session closed for user root Aug 31 16:21:10 volumiopi3bplus sudo[1241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 16:21:10 volumiopi3bplus sudo[1241]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:21:10 volumiopi3bplus sudo[1241]: pam_unix(sudo:session): session closed for user root Aug 31 16:21:10 volumiopi3bplus volumio[971]: verbose: New Socket.io Connection to 10.1.1.247 from 10.1.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::volumioGetVisibleSources Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::volumioGetState Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CorePlayQueue::getTrack 0 Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: Received Get System Info Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: Discovery: Getting this device information Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::volumioGetState Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CorePlayQueue::getTrack 0 Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::volumioGetState Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CorePlayQueue::getTrack 0 Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: Listing playlists Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 16:21:10 volumiopi3bplus volumio[971]: info: FusionDsp - Aug 31 16:21:11 volumiopi3bplus volumio[971]: info: FusionDsp - undefined Aug 31 16:21:11 volumiopi3bplus volumio[971]: info: camilladsp service started and running in background, instance 1 Aug 31 16:21:11 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:21:11 volumiopi3bplus volumio[971]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 31 16:21:11 volumiopi3bplus volumio[971]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Aug 31 16:21:11 volumiopi3bplus volumio[971]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 31 16:21:11 volumiopi3bplus volumio[971]: info: FusionDsp loaded Aug 31 16:21:11 volumiopi3bplus volumio[971]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 16:21:11 volumiopi3bplus sudo[1257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 31 16:21:11 volumiopi3bplus sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:21:11 volumiopi3bplus volumio[971]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 31 16:21:11 volumiopi3bplus volumio[971]: info: Adding Signal Path Element [object Object] Aug 31 16:21:11 volumiopi3bplus volumio[971]: info: Adding fusiondspeq DSP Signal Path Element Aug 31 16:21:11 volumiopi3bplus volumio[971]: info: FusionDsp - ---- installed callbackRead Aug 31 16:21:11 volumiopi3bplus volumio[971]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 16:21:11 volumiopi3bplus sudo[1257]: pam_unix(sudo:session): session closed for user root Aug 31 16:21:11 volumiopi3bplus volumio[971]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 31 16:21:11 volumiopi3bplus volumio[971]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Aug 31 16:21:11 volumiopi3bplus volumio[971]: at onErrorNT (internal/child_process.js:465:16) Aug 31 16:21:11 volumiopi3bplus volumio[971]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 31 16:21:11 volumiopi3bplus volumio[971]: at runNextTicks (internal/process/task_queues.js:62:3) Aug 31 16:21:11 volumiopi3bplus volumio[971]: at listOnTimeout (internal/timers.js:523:9) Aug 31 16:21:11 volumiopi3bplus volumio[971]: at processTimers (internal/timers.js:497:7) { Aug 31 16:21:11 volumiopi3bplus volumio[971]: errno: -2, Aug 31 16:21:11 volumiopi3bplus volumio[971]: code: 'ENOENT', Aug 31 16:21:11 volumiopi3bplus volumio[971]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 31 16:21:11 volumiopi3bplus volumio[971]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 31 16:21:11 volumiopi3bplus volumio[971]: spawnargs: [ Aug 31 16:21:11 volumiopi3bplus volumio[971]: '-p', Aug 31 16:21:11 volumiopi3bplus volumio[971]: 9876, Aug 31 16:21:11 volumiopi3bplus volumio[971]: '-o', Aug 31 16:21:11 volumiopi3bplus volumio[971]: '/tmp/camilladsp.log', Aug 31 16:21:11 volumiopi3bplus volumio[971]: '-l', Aug 31 16:21:11 volumiopi3bplus volumio[971]: 'warn', Aug 31 16:21:11 volumiopi3bplus volumio[971]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 31 16:21:11 volumiopi3bplus volumio[971]: ] Aug 31 16:21:11 volumiopi3bplus volumio[971]: } Aug 31 16:21:11 volumiopi3bplus volumio[971]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 16:21:12 volumiopi3bplus mpd[1234]: Aug 31 16:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 16:21:12 volumiopi3bplus systemd[1]: Started Music Player Daemon. Aug 31 16:21:12 volumiopi3bplus sudo[1213]: pam_unix(sudo:session): session closed for user root Aug 31 16:21:12 volumiopi3bplus sudo[1155]: pam_unix(sudo:session): session closed for user root Aug 31 16:21:12 volumiopi3bplus sudo[1164]: pam_unix(sudo:session): session closed for user root Aug 31 16:21:12 volumiopi3bplus sudo[1270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-31 16:20 Aug 31 16:21:12 volumiopi3bplus sudo[1270]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"