-- Logs begin at Thu 2019-02-14 18:11:58 +08, end at Thu 2025-09-11 19:08:34 +08. -- Sep 11 19:07:41 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:41 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 11 19:07:41 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 11 19:07:41 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 11 19:07:41 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 11 19:07:41 volumiok kernel: sda: unable to read partition table Sep 11 19:07:41 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:41 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Sep 11 19:07:41 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 1d cf ff 80 00 00 08 00 Sep 11 19:07:41 volumiok kernel: I/O error, dev sda, sector 500170624 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Sep 11 19:07:41 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:41 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:41 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:41 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:41 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:41 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:41 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=4s Sep 11 19:07:41 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 1d cf ff 80 00 00 08 00 Sep 11 19:07:41 volumiok kernel: I/O error, dev sda, sector 500170624 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 11 19:07:41 volumiok kernel: Buffer I/O error on dev sda, logical block 62521328, async page read Sep 11 19:07:41 volumiok systemd[1]: System time before build time, advancing clock. Sep 11 19:07:41 volumiok kernel: NET: Registered PF_INET6 protocol family Sep 11 19:07:41 volumiok kernel: Segment Routing with IPv6 Sep 11 19:07:41 volumiok kernel: In-situ OAM (IOAM) with IPv6 Sep 11 19:07:41 volumiok 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) Sep 11 19:07:41 volumiok systemd[1]: Detected architecture arm. Sep 11 19:07:41 volumiok systemd[1]: Set hostname to . Sep 11 19:07:41 volumiok 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. Sep 11 19:07:41 volumiok systemd[1]: Listening on Journal Audit Socket. Sep 11 19:07:41 volumiok systemd[1]: Created slice system-systemd\x2dfsck.slice. Sep 11 19:07:41 volumiok systemd[1]: Listening on fsck to fsckd communication Socket. Sep 11 19:07:41 volumiok systemd[1]: Started Forward Password Requests to Wall Directory Watch. Sep 11 19:07:41 volumiok systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Sep 11 19:07:41 volumiok systemd[1]: Listening on initctl Compatibility Named Pipe. Sep 11 19:07:41 volumiok systemd-journald[332]: Journal started Sep 11 19:07:41 volumiok systemd-journald[332]: Runtime journal (/run/log/journal/eea64a7df36cabb6e36ce31068484641) is 7.5M, max 30.0M, 22.5M free. Sep 11 19:07:41 volumiok fake-hwclock[331]: Thu Sep 11 11:07:41 UTC 2025 Sep 11 19:07:41 volumiok systemd[1]: Started Restore / save the current clock. Sep 11 19:07:41 volumiok systemd-modules-load[335]: Inserted module 'i2c_dev' Sep 11 19:07:41 volumiok kernel: i2c_dev: i2c /dev entries driver Sep 11 19:07:41 volumiok systemd[1]: Started Load Kernel Modules. Sep 11 19:07:41 volumiok systemd[1]: Mounting Kernel Configuration File System... Sep 11 19:07:41 volumiok systemd[1]: Mounting FUSE Control File System... Sep 11 19:07:41 volumiok systemd[1]: Starting Apply Kernel Variables... Sep 11 19:07:41 volumiok systemd[1]: Started Load/Save Random Seed. Sep 11 19:07:41 volumiok systemd[1]: Mounted Kernel Configuration File System. Sep 11 19:07:41 volumiok systemd[1]: Mounted FUSE Control File System. Sep 11 19:07:41 volumiok systemd[1]: Started Apply Kernel Variables. Sep 11 19:07:41 volumiok systemd[1]: Started Create System Users. Sep 11 19:07:41 volumiok systemd[1]: Starting Create Static Device Nodes in /dev... Sep 11 19:07:41 volumiok systemd[1]: dynamicswap.service: Succeeded. Sep 11 19:07:41 volumiok systemd[1]: Started udev Coldplug all Devices. Sep 11 19:07:41 volumiok systemd[1]: Starting Helper to synchronize boot up for ifupdown... Sep 11 19:07:41 volumiok systemd[1]: Started Create Static Device Nodes in /dev. Sep 11 19:07:41 volumiok systemd[1]: Reached target Local File Systems (Pre). Sep 11 19:07:41 volumiok systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Sep 11 19:07:41 volumiok systemd[1]: Mounting /tmp... Sep 11 19:07:41 volumiok systemd[1]: Mounting /var/spool/cups... Sep 11 19:07:41 volumiok systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Sep 11 19:07:41 volumiok systemd[1]: Mounting /var/log... Sep 11 19:07:41 volumiok systemd[1]: Starting udev Kernel Device Manager... Sep 11 19:07:41 volumiok systemd[1]: Mounted /tmp. Sep 11 19:07:41 volumiok systemd[1]: Mounted /var/spool/cups. Sep 11 19:07:41 volumiok systemd[1]: Mounted /var/log. Sep 11 19:07:41 volumiok systemd[1]: Starting Flush Journal to Persistent Storage... Sep 11 19:07:41 volumiok systemd[1]: Mounting /var/spool/cups/tmp... Sep 11 19:07:41 volumiok systemd[1]: Mounted /var/spool/cups/tmp. Sep 11 19:07:41 volumiok systemd-udevd[363]: Network interface NamePolicy= disabled on kernel command line, ignoring. Sep 11 19:07:41 volumiok systemd-journald[332]: Runtime journal (/run/log/journal/eea64a7df36cabb6e36ce31068484641) is 7.5M, max 30.0M, 22.5M free. Sep 11 19:07:41 volumiok systemd[1]: Started udev Kernel Device Manager. Sep 11 19:07:41 volumiok systemd[1]: Starting Show Plymouth Boot Screen... Sep 11 19:07:41 volumiok systemd[1]: Started Flush Journal to Persistent Storage. Sep 11 19:07:41 volumiok systemd[1]: plymouth-start.service: Succeeded. Sep 11 19:07:41 volumiok systemd[1]: Started Show Plymouth Boot Screen. Sep 11 19:07:41 volumiok systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Sep 11 19:07:41 volumiok systemd[1]: Reached target Local Encrypted Volumes. Sep 11 19:07:41 volumiok systemd[1]: Reached target Paths. Sep 11 19:07:41 volumiok systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Sep 11 19:07:41 volumiok kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Sep 11 19:07:41 volumiok kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Sep 11 19:07:41 volumiok kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Sep 11 19:07:41 volumiok systemd-udevd[380]: Using default interface naming scheme 'v240'. Sep 11 19:07:41 volumiok kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Sep 11 19:07:41 volumiok kernel: [vc_sm_connected_init]: start Sep 11 19:07:41 volumiok kernel: mc: Linux media interface: v0.10 Sep 11 19:07:41 volumiok kernel: [vc_sm_connected_init]: installed successfully Sep 11 19:07:41 volumiok systemd-udevd[386]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Sep 11 19:07:41 volumiok systemd-udevd[380]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Sep 11 19:07:41 volumiok kernel: videodev: Linux video capture interface: v2.00 Sep 11 19:07:41 volumiok kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Sep 11 19:07:41 volumiok kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Sep 11 19:07:41 volumiok kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Sep 11 19:07:41 volumiok kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Sep 11 19:07:41 volumiok kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Sep 11 19:07:41 volumiok kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Sep 11 19:07:41 volumiok kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Sep 11 19:07:41 volumiok kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Sep 11 19:07:41 volumiok kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Sep 11 19:07:41 volumiok kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Sep 11 19:07:41 volumiok kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Sep 11 19:07:41 volumiok kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Sep 11 19:07:41 volumiok kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Sep 11 19:07:41 volumiok kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Sep 11 19:07:41 volumiok kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Sep 11 19:07:41 volumiok kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Sep 11 19:07:41 volumiok kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Sep 11 19:07:41 volumiok kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Sep 11 19:07:41 volumiok kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Sep 11 19:07:41 volumiok kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Sep 11 19:07:41 volumiok kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Sep 11 19:07:41 volumiok kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Sep 11 19:07:41 volumiok kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Sep 11 19:07:41 volumiok kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Sep 11 19:07:41 volumiok kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Sep 11 19:07:41 volumiok kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Sep 11 19:07:41 volumiok kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Sep 11 19:07:41 volumiok kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Sep 11 19:07:41 volumiok kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Sep 11 19:07:41 volumiok kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Sep 11 19:07:41 volumiok kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Sep 11 19:07:41 volumiok kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Sep 11 19:07:41 volumiok kernel: rpivid feb10000.codec: Device registered as /dev/video19 Sep 11 19:07:41 volumiok kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Sep 11 19:07:41 volumiok kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Sep 11 19:07:41 volumiok kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Sep 11 19:07:41 volumiok kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Sep 11 19:07:42 volumiok kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Sep 11 19:07:42 volumiok kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Sep 11 19:07:42 volumiok kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Sep 11 19:07:42 volumiok kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Sep 11 19:07:42 volumiok kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Sep 11 19:07:42 volumiok kernel: Bluetooth: Core ver 2.22 Sep 11 19:07:42 volumiok kernel: NET: Registered PF_BLUETOOTH protocol family Sep 11 19:07:42 volumiok kernel: Bluetooth: HCI device and connection manager initialized Sep 11 19:07:42 volumiok kernel: Bluetooth: HCI socket layer initialized Sep 11 19:07:42 volumiok kernel: Bluetooth: L2CAP socket layer initialized Sep 11 19:07:42 volumiok kernel: Bluetooth: SCO socket layer initialized Sep 11 19:07:42 volumiok kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Sep 11 19:07:42 volumiok kernel: Bluetooth: HCI UART driver ver 2.3 Sep 11 19:07:42 volumiok kernel: Bluetooth: HCI UART protocol H4 registered Sep 11 19:07:42 volumiok kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Sep 11 19:07:42 volumiok kernel: Bluetooth: HCI UART protocol Broadcom registered Sep 11 19:07:42 volumiok kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Sep 11 19:07:42 volumiok kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Sep 11 19:07:42 volumiok kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Sep 11 19:07:42 volumiok kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Sep 11 19:07:42 volumiok kernel: usbcore: registered new interface driver brcmfmac Sep 11 19:07:42 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:42 volumiok kernel: uart-pl011 fe201000.serial: no DMA platform data Sep 11 19:07:42 volumiok kernel: usb 1-1.1: 1:3 : unsupported format bits 0x100000000 Sep 11 19:07:42 volumiok kernel: Bluetooth: hci0: BCM: chip id 107 Sep 11 19:07:42 volumiok kernel: Bluetooth: hci0: BCM: features 0x2f Sep 11 19:07:42 volumiok kernel: Bluetooth: hci0: BCM4345C0 Sep 11 19:07:42 volumiok kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Sep 11 19:07:42 volumiok kernel: usbcore: registered new interface driver snd-usb-audio Sep 11 19:07:42 volumiok kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Sep 11 19:07:42 volumiok kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Sep 11 19:07:42 volumiok 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 Sep 11 19:07:42 volumiok systemd-udevd[389]: Using default interface naming scheme 'v240'. Sep 11 19:07:42 volumiok systemd-udevd[389]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Sep 11 19:07:42 volumiok kernel: alsactl[507]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Sep 11 19:07:42 volumiok systemd-udevd[367]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 0' failed with exit code 17. Sep 11 19:07:42 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:43 volumiok systemd-udevd[372]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Sep 11 19:07:43 volumiok systemd[1]: Found device /dev/disk/by-uuid/25F2-3254. Sep 11 19:07:43 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:43 volumiok kernel: Bluetooth: hci0: BCM: features 0x2f Sep 11 19:07:43 volumiok kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Sep 11 19:07:43 volumiok kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Sep 11 19:07:43 volumiok systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Sep 11 19:07:43 volumiok systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Sep 11 19:07:43 volumiok systemd[1]: Created slice system-bthelper.slice. Sep 11 19:07:43 volumiok systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Sep 11 19:07:43 volumiok systemd[1]: Starting Show Plymouth Boot Screen... Sep 11 19:07:43 volumiok systemd[1]: Condition check resulted in Huge Pages File System being skipped. Sep 11 19:07:43 volumiok systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Sep 11 19:07:43 volumiok systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Sep 11 19:07:43 volumiok systemd[1]: Starting File System Check on /dev/disk/by-uuid/25F2-3254... Sep 11 19:07:43 volumiok systemd[1]: Starting Load/Save RF Kill Switch Status... Sep 11 19:07:43 volumiok systemd[1]: plymouth-start.service: Succeeded. Sep 11 19:07:43 volumiok systemd[1]: Started Show Plymouth Boot Screen. Sep 11 19:07:43 volumiok systemd[1]: Started File System Check Daemon to report status. Sep 11 19:07:43 volumiok systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Sep 11 19:07:43 volumiok systemd[1]: Started Load/Save RF Kill Switch Status. Sep 11 19:07:43 volumiok systemd-fsck[523]: fsck.fat 4.1 (2017-01-24) Sep 11 19:07:43 volumiok systemd-fsck[523]: /dev/mmcblk0p1: 401 files, 166329/184572 clusters Sep 11 19:07:43 volumiok systemd[1]: Started File System Check on /dev/disk/by-uuid/25F2-3254. Sep 11 19:07:43 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:43 volumiok kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Sep 11 19:07:43 volumiok systemd[1]: Mounting /boot... Sep 11 19:07:43 volumiok alsactl[576]: alsactl 1.1.8 daemon started Sep 11 19:07:43 volumiok systemd[1]: Mounted /boot. Sep 11 19:07:43 volumiok systemd[1]: Reached target Local File Systems. Sep 11 19:07:43 volumiok systemd[1]: Started ifup for eth0. Sep 11 19:07:43 volumiok systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Sep 11 19:07:43 volumiok systemd[1]: Starting Preprocess NFS configuration... Sep 11 19:07:43 volumiok systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Sep 11 19:07:43 volumiok systemd[1]: Starting Create Volatile Files and Directories... Sep 11 19:07:43 volumiok systemd[1]: Started ifup for wlan0. Sep 11 19:07:43 volumiok systemd[1]: plymouth-read-write.service: Succeeded. Sep 11 19:07:43 volumiok systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Sep 11 19:07:43 volumiok systemd[1]: Received SIGRTMIN+20 from PID 208 (plymouthd). Sep 11 19:07:43 volumiok systemd[1]: nfs-config.service: Succeeded. Sep 11 19:07:43 volumiok systemd[1]: Started Preprocess NFS configuration. Sep 11 19:07:43 volumiok systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Sep 11 19:07:43 volumiok systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Sep 11 19:07:43 volumiok systemd[1]: Reached target NFS client services. Sep 11 19:07:43 volumiok systemd[1]: Started Create Volatile Files and Directories. Sep 11 19:07:43 volumiok systemd[1]: Starting Update UTMP about System Boot/Shutdown... Sep 11 19:07:43 volumiok systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Sep 11 19:07:43 volumiok systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Sep 11 19:07:43 volumiok systemd[1]: Starting RPC bind portmap service... Sep 11 19:07:43 volumiok systemd[1]: Started Update UTMP about System Boot/Shutdown. Sep 11 19:07:43 volumiok systemd[1]: Reached target System Initialization. Sep 11 19:07:43 volumiok systemd[1]: Started Daily Cleanup of Temporary Directories. Sep 11 19:07:43 volumiok systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Sep 11 19:07:43 volumiok systemd[1]: Listening on triggerhappy.socket. Sep 11 19:07:43 volumiok systemd[1]: Listening on D-Bus System Message Bus Socket. Sep 11 19:07:43 volumiok systemd[1]: Reached target Sockets. Sep 11 19:07:43 volumiok systemd[1]: Reached target Basic System. Sep 11 19:07:43 volumiok systemd[1]: Starting Configure Bluetooth Modems connected by UART... Sep 11 19:07:43 volumiok systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Sep 11 19:07:43 volumiok systemd[1]: Started Manage Sound Card State (restore and store). Sep 11 19:07:43 volumiok systemd[1]: Starting Login Service... Sep 11 19:07:43 volumiok systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Sep 11 19:07:43 volumiok systemd[1]: Starting dhcpcd on all interfaces... Sep 11 19:07:43 volumiok systemd[1]: Started UPnP Renderer front-end to MPD. Sep 11 19:07:43 volumiok systemd[1]: Started Volumio Log Rotation Service. Sep 11 19:07:43 volumiok systemd[1]: Starting Wireless Services... Sep 11 19:07:43 volumiok systemd[1]: Started Volumio Iptables Module. Sep 11 19:07:43 volumiok systemd[1]: Starting triggerhappy global hotkey daemon... Sep 11 19:07:43 volumiok systemd[1]: Started volumio-remote-updater.service. Sep 11 19:07:43 volumiok systemd[1]: Started D-Bus System Message Bus. Sep 11 19:07:43 volumiok systemd[1]: Starting WPA supplicant... Sep 11 19:07:43 volumiok systemd[1]: Starting Volumio Time Update Utility... Sep 11 19:07:43 volumiok systemd[1]: Started Daily apt download activities. Sep 11 19:07:43 volumiok systemd[1]: Started Daily apt upgrade and clean activities. Sep 11 19:07:43 volumiok systemd[1]: Reached target Timers. Sep 11 19:07:43 volumiok systemd[1]: Starting Save/Restore Sound Card State... Sep 11 19:07:43 volumiok systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Sep 11 19:07:43 volumiok systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Sep 11 19:07:43 volumiok systemd[1]: Started MPD Monitor Service. Sep 11 19:07:43 volumiok volumio-time-update[587]: volumio-time-update-util: Fetching time from Volumio... Sep 11 19:07:43 volumiok mpd_monitor.sh[590]: MPD Monitor Service: Starting MPD Monitor Service Sep 11 19:07:43 volumiok dhcpcd[578]: Not running dhcpcd because /etc/network/interfaces Sep 11 19:07:43 volumiok dhcpcd[578]: defines some interfaces that will use a Sep 11 19:07:43 volumiok dhcpcd[578]: DHCP client or static address Sep 11 19:07:43 volumiok systemd-logind[577]: New seat seat0. Sep 11 19:07:44 volumiok thd[583]: Unable to parse trigger line: Sep 11 19:07:44 volumiok thd[583]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Sep 11 19:07:44 volumiok thd[583]: Unable to parse trigger line: Sep 11 19:07:44 volumiok thd[583]: Found socket passed from systemd Sep 11 19:07:44 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:44 volumiok kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 11 19:07:44 volumiok kernel: 8021q: 802.1Q VLAN Support v1.8 Sep 11 19:07:44 volumiok mpd_monitor.sh[590]: MPD error: Connection refused Sep 11 19:07:44 volumiok mpd_monitor.sh[590]: MPD Monitor Service: MPD Appears to be inactive, restarting Sep 11 19:07:44 volumiok mpd_monitor.sh[590]: mpd: no process found Sep 11 19:07:44 volumiok avahi-daemon[589]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Sep 11 19:07:44 volumiok avahi-daemon[589]: Successfully dropped root privileges. Sep 11 19:07:44 volumiok avahi-daemon[589]: avahi-daemon 0.7 starting up. Sep 11 19:07:44 volumiok volumio-remote-updater[584]: Error: No active session Sep 11 19:07:44 volumiok volumio-remote-updater[584]: [2025-09-11 19:07:44] [info] asio async_connect error: system:111 (Connection refused) Sep 11 19:07:44 volumiok volumio-remote-updater[584]: [2025-09-11 19:07:44] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 11 19:07:44 volumiok volumio-remote-updater[584]: [2025-09-11 19:07:44] [error] handle_connect error: Underlying Transport Error Sep 11 19:07:44 volumiok volumio-time-update[587]: volumio-time-update-util: Date not found in response Sep 11 19:07:44 volumiok volumio-time-update[587]: volumio-time-update-util: Retrying in 5 seconds... Sep 11 19:07:44 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:44 volumiok kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Sep 11 19:07:44 volumiok kernel: bcmgenet fd580000.ethernet eth0: Link is Down Sep 11 19:07:44 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 11 19:07:44 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 11 19:07:44 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 11 19:07:44 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 11 19:07:44 volumiok sh[531]: eth0: waiting for carrier Sep 11 19:07:44 volumiok dbus-daemon[585]: [system] Successfully activated service 'org.freedesktop.systemd1' Sep 11 19:07:44 volumiok sh[537]: wlan0=wlan0 Sep 11 19:07:44 volumiok volumio[579]: Could not open config: /tmp/upmpdcli.conf Sep 11 19:07:44 volumiok systemd[1]: Started WPA supplicant. Sep 11 19:07:44 volumiok avahi-daemon[589]: Successfully called chroot(). Sep 11 19:07:44 volumiok systemd[1]: Started Login Service. Sep 11 19:07:44 volumiok avahi-daemon[589]: Successfully dropped remaining capabilities. Sep 11 19:07:44 volumiok systemd[1]: Started RPC bind portmap service. Sep 11 19:07:44 volumiok wpa_supplicant[586]: Successfully initialized wpa_supplicant Sep 11 19:07:44 volumiok systemd[1]: Started triggerhappy global hotkey daemon. Sep 11 19:07:44 volumiok avahi-daemon[589]: Loading service file /services/volumio.service. Sep 11 19:07:44 volumiok systemd[1]: hciuart.service: Succeeded. Sep 11 19:07:44 volumiok avahi-daemon[589]: Network interface enumeration completed. Sep 11 19:07:44 volumiok systemd[1]: Started Configure Bluetooth Modems connected by UART. Sep 11 19:07:44 volumiok avahi-daemon[589]: Server startup complete. Host name is volumiok.local. Local service cookie is 2680749560. Sep 11 19:07:44 volumiok systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Sep 11 19:07:44 volumiok avahi-daemon[589]: Service "Volumiok" (/services/volumio.service) successfully established. Sep 11 19:07:44 volumiok systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Sep 11 19:07:44 volumiok dhcpcd[572]: eth0: waiting for carrier Sep 11 19:07:44 volumiok systemd[1]: Failed to start dhcpcd on all interfaces. Sep 11 19:07:44 volumiok systemd[1]: Started Save/Restore Sound Card State. Sep 11 19:07:44 volumiok systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Sep 11 19:07:44 volumiok systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Sep 11 19:07:44 volumiok systemd[1]: Started Avahi mDNS/DNS-SD Stack. Sep 11 19:07:44 volumiok systemd[1]: Listening on mpd.socket. Sep 11 19:07:44 volumiok systemd[1]: Reached target Sound Card. Sep 11 19:07:44 volumiok systemd[1]: Starting Raspberry Pi bluetooth helper... Sep 11 19:07:44 volumiok systemd[1]: Reached target Remote File Systems (Pre). Sep 11 19:07:44 volumiok systemd[1]: Reached target Remote File Systems. Sep 11 19:07:44 volumiok systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Sep 11 19:07:44 volumiok systemd[1]: Reached target RPC Port Mapper. Sep 11 19:07:44 volumiok bthelper[665]: Raspberry Pi BDADDR already set Sep 11 19:07:44 volumiok systemd[1]: Started Raspberry Pi bluetooth helper. Sep 11 19:07:44 volumiok systemd[1]: Starting Bluetooth service... Sep 11 19:07:44 volumiok systemd[1]: iptables.service: Succeeded. Sep 11 19:07:44 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:45 volumiok kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Sep 11 19:07:45 volumiok kernel: Bluetooth: BNEP filters: protocol multicast Sep 11 19:07:45 volumiok kernel: Bluetooth: BNEP socket layer initialized Sep 11 19:07:45 volumiok kernel: Bluetooth: MGMT ver 1.22 Sep 11 19:07:45 volumiok haveged[562]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Sep 11 19:07:45 volumiok haveged[562]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Sep 11 19:07:45 volumiok haveged[562]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99804 Sep 11 19:07:45 volumiok haveged[562]: haveged: fills: 0, generated: 0 Sep 11 19:07:45 volumiok loadcpufreq[667]: Loading cpufreq kernel modules...done (none). Sep 11 19:07:45 volumiok bluetoothd[679]: Bluetooth daemon 5.50 Sep 11 19:07:45 volumiok systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Sep 11 19:07:45 volumiok bluetoothd[679]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Sep 11 19:07:45 volumiok systemd[1]: Starting LSB: set CPUFreq kernel parameters... Sep 11 19:07:45 volumiok bluetoothd[679]: Starting SDP server Sep 11 19:07:45 volumiok systemd[1]: Started Bluetooth service. Sep 11 19:07:45 volumiok bluetoothd[679]: Excluding (cli) sap Sep 11 19:07:45 volumiok systemd[1]: Reached target Bluetooth. Sep 11 19:07:45 volumiok bluetoothd[679]: Bluetooth management interface 1.22 initialized Sep 11 19:07:45 volumiok dbus-daemon[585]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=679 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Sep 11 19:07:45 volumiok bluetoothd[679]: Failed to set privacy: Rejected (0x0b) Sep 11 19:07:45 volumiok cpufrequtils[708]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Sep 11 19:07:45 volumiok systemd[1]: Starting Hostname Service... Sep 11 19:07:45 volumiok systemd[1]: Started LSB: set CPUFreq kernel parameters. Sep 11 19:07:45 volumiok dbus-daemon[585]: [system] Successfully activated service 'org.freedesktop.hostname1' Sep 11 19:07:45 volumiok systemd[1]: Started Hostname Service. Sep 11 19:07:45 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:45 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:46 volumiok wireless.js[581]: Cleaning previous... Sep 11 19:07:46 volumiok sudo[736]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 11 19:07:46 volumiok sudo[736]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:07:46 volumiok sudo[736]: pam_unix(sudo:session): session closed for user root Sep 11 19:07:46 volumiok sudo[738]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 11 19:07:46 volumiok sudo[738]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:07:46 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:46 volumiok sudo[738]: pam_unix(sudo:session): session closed for user root Sep 11 19:07:46 volumiok wireless.js[581]: Stopped aP Sep 11 19:07:46 volumiok sudo[745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 11 19:07:46 volumiok sudo[745]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:07:46 volumiok sudo[745]: pam_unix(sudo:session): session closed for user root Sep 11 19:07:46 volumiok kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 11 19:07:46 volumiok sudo[747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Sep 11 19:07:46 volumiok sudo[747]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:07:46 volumiok sudo[747]: pam_unix(sudo:session): session closed for user root Sep 11 19:07:46 volumiok sudo[754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 11 19:07:46 volumiok sudo[754]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:07:46 volumiok sudo[754]: pam_unix(sudo:session): session closed for user root Sep 11 19:07:46 volumiok sudo[756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Sep 11 19:07:46 volumiok sudo[756]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:07:46 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:47 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:47 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 11 19:07:47 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 11 19:07:47 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 11 19:07:47 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 11 19:07:47 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:48 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:48 volumiok systemd[1]: systemd-rfkill.service: Succeeded. Sep 11 19:07:48 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:48 volumiok kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Sep 11 19:07:48 volumiok dhcpcd[572]: eth0: carrier acquired Sep 11 19:07:48 volumiok sh[531]: eth0: carrier acquired Sep 11 19:07:48 volumiok sh[531]: DUID 00:01:00:01:2f:db:05:16:dc:a6:32:f1:71:bf Sep 11 19:07:48 volumiok sh[531]: eth0: IAID 32:48:45:e2 Sep 11 19:07:48 volumiok sh[531]: eth0: adding address fe80::dea6:32ff:fe48:45e2 Sep 11 19:07:48 volumiok sh[531]: ipv6_addaddr1: Permission denied Sep 11 19:07:48 volumiok dhcpcd[572]: DUID 00:01:00:01:2f:db:05:16:dc:a6:32:f1:71:bf Sep 11 19:07:48 volumiok dhcpcd[572]: eth0: IAID 32:48:45:e2 Sep 11 19:07:48 volumiok dhcpcd[572]: eth0: adding address fe80::dea6:32ff:fe48:45e2 Sep 11 19:07:48 volumiok dhcpcd[572]: ipv6_addaddr1: Permission denied Sep 11 19:07:49 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:49 volumiok sh[531]: eth0: soliciting an IPv6 router Sep 11 19:07:49 volumiok wireless.js[581]: SETTING APPROPRIATE REG DOMAIN: SG Sep 11 19:07:49 volumiok wireless.js[581]: SUCCESSFULLY SET NEW REGDOMAIN: SG Sep 11 19:07:49 volumiok wireless.js[581]: Start wireless flow Sep 11 19:07:49 volumiok wireless.js[581]: Stopped hotspot (if there).. Sep 11 19:07:49 volumiok dhcpcd[572]: eth0: soliciting an IPv6 router Sep 11 19:07:49 volumiok sudo[756]: pam_unix(sudo:session): session closed for user root Sep 11 19:07:49 volumiok sudo[762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 11 19:07:49 volumiok sudo[762]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:07:49 volumiok sudo[762]: pam_unix(sudo:session): session closed for user root Sep 11 19:07:49 volumiok sudo[764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set SG Sep 11 19:07:49 volumiok sudo[764]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:07:49 volumiok sudo[764]: pam_unix(sudo:session): session closed for user root Sep 11 19:07:49 volumiok sudo[769]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 11 19:07:49 volumiok sudo[769]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:07:49 volumiok sudo[769]: pam_unix(sudo:session): session closed for user root Sep 11 19:07:49 volumiok sudo[771]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 11 19:07:49 volumiok sudo[771]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:07:49 volumiok volumio-time-update[587]: volumio-time-update-util: Fetching time from Volumio... Sep 11 19:07:49 volumiok volumio-time-update[587]: volumio-time-update-util: Date not found in response Sep 11 19:07:49 volumiok volumio-time-update[587]: volumio-time-update-util: Retrying in 5 seconds... Sep 11 19:07:49 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:49 volumiok sh[531]: eth0: rebinding lease of 192.168.1.10 Sep 11 19:07:49 volumiok sh[531]: eth0: NAK: from 192.168.1.254 Sep 11 19:07:49 volumiok dhcpcd[572]: eth0: rebinding lease of 192.168.1.10 Sep 11 19:07:49 volumiok bthelper[665]: Changing power off succeeded Sep 11 19:07:49 volumiok dhcpcd[572]: eth0: NAK: from 192.168.1.254 Sep 11 19:07:49 volumiok sh[531]: eth0: soliciting a DHCP lease Sep 11 19:07:49 volumiok dhcpcd[572]: eth0: soliciting a DHCP lease Sep 11 19:07:49 volumiok volumio-remote-updater[584]: [2025-09-11 19:07:49] [info] asio async_connect error: system:111 (Connection refused) Sep 11 19:07:49 volumiok volumio-remote-updater[584]: [2025-09-11 19:07:49] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 11 19:07:49 volumiok volumio-remote-updater[584]: [2025-09-11 19:07:49] [error] handle_connect error: Underlying Transport Error Sep 11 19:07:49 volumiok sudo[771]: pam_unix(sudo:session): session closed for user root Sep 11 19:07:49 volumiok wireless.js[581]: DHCP IP Sep 11 19:07:49 volumiok wireless.js[581]: Start ap Sep 11 19:07:49 volumiok wpa_supplicant[788]: Successfully initialized wpa_supplicant Sep 11 19:07:49 volumiok sudo[789]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Sep 11 19:07:49 volumiok sudo[789]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:07:49 volumiok kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 11 19:07:49 volumiok dhcpcd[790]: dev: loaded udev Sep 11 19:07:50 volumiok dhcpcd-run-hooks[799]: wlan0: starting wpa_supplicant Sep 11 19:07:50 volumiok bthelper[665]: [CHG] Controller DC:A6:32:48:45:E5 Class: 0x0000041c Sep 11 19:07:50 volumiok bthelper[665]: Changing power on succeeded Sep 11 19:07:50 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:50 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 11 19:07:50 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 11 19:07:50 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 11 19:07:50 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 11 19:07:50 volumiok kernel: sda: unable to read partition table Sep 11 19:07:50 volumiok dhcpcd-run-hooks[810]: wlan0: failed to start wpa_supplicant Sep 11 19:07:50 volumiok dhcpcd-run-hooks[811]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Sep 11 19:07:50 volumiok dhcpcd[790]: wlan0: connected to Access Point `' Sep 11 19:07:50 volumiok dhcpcd[790]: DUID 00:01:00:01:2f:db:05:16:dc:a6:32:f1:71:bf Sep 11 19:07:50 volumiok dhcpcd[790]: eth0: IAID 32:48:45:e2 Sep 11 19:07:50 volumiok dhcpcd[790]: eth0: adding address fe80::dea6:32ff:fe48:45e2 Sep 11 19:07:50 volumiok dhcpcd[790]: ipv6_addaddr1: Permission denied Sep 11 19:07:50 volumiok dhcpcd[790]: wlan0: waiting for carrier Sep 11 19:07:50 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:50 volumiok dhcpcd[790]: eth0: soliciting an IPv6 router Sep 11 19:07:50 volumiok wireless.js[581]: trying... Sep 11 19:07:50 volumiok sudo[822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 11 19:07:50 volumiok sudo[822]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:07:50 volumiok sudo[822]: pam_unix(sudo:session): session closed for user root Sep 11 19:07:51 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:51 volumiok dhcpcd[790]: eth0: soliciting a DHCP lease Sep 11 19:07:51 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:51 volumiok dhcpcd[790]: eth0: offered 192.168.1.10 from 192.168.1.254 Sep 11 19:07:51 volumiok wireless.js[581]: trying... Sep 11 19:07:51 volumiok sudo[825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 11 19:07:51 volumiok sudo[825]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:07:51 volumiok dhcpcd[790]: eth0: probing address 192.168.1.10/24 Sep 11 19:07:51 volumiok sudo[825]: pam_unix(sudo:session): session closed for user root Sep 11 19:07:52 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:52 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:52 volumiok wireless.js[581]: trying... Sep 11 19:07:52 volumiok sudo[828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 11 19:07:52 volumiok sudo[828]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:07:52 volumiok sudo[828]: pam_unix(sudo:session): session closed for user root Sep 11 19:07:53 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:53 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:53 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:54 volumiok wireless.js[581]: trying... Sep 11 19:07:53 volumiok sudo[831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 11 19:07:53 volumiok sudo[831]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:07:53 volumiok sudo[831]: pam_unix(sudo:session): session closed for user root Sep 11 19:07:54 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:54 volumiok volumio-time-update[587]: volumio-time-update-util: Fetching time from Volumio... Sep 11 19:07:54 volumiok volumio-time-update[587]: volumio-time-update-util: Date not found in response Sep 11 19:07:54 volumiok volumio-time-update[587]: volumio-time-update-util: Retrying in 5 seconds... Sep 11 19:07:54 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:54 volumiok dhcpcd[572]: eth0: probing for an IPv4LL address Sep 11 19:07:55 volumiok wireless.js[581]: trying... Sep 11 19:07:55 volumiok sh[531]: eth0: probing for an IPv4LL address Sep 11 19:07:54 volumiok sudo[839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 11 19:07:54 volumiok sudo[839]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:07:54 volumiok sudo[839]: pam_unix(sudo:session): session closed for user root Sep 11 19:07:55 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:55 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:56 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 11 19:07:56 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 11 19:07:56 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 11 19:07:56 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 11 19:07:56 volumiok sh[531]: eth0: offered 192.168.1.10 from 192.168.1.254 Sep 11 19:07:56 volumiok sh[531]: eth0: probing address 192.168.1.10/24 Sep 11 19:07:55 volumiok dhcpcd[572]: eth0: offered 192.168.1.10 from 192.168.1.254 Sep 11 19:07:56 volumiok wireless.js[581]: trying... Sep 11 19:07:55 volumiok dhcpcd[572]: eth0: probing address 192.168.1.10/24 Sep 11 19:07:55 volumiok sudo[842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 11 19:07:55 volumiok sudo[842]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:07:55 volumiok sudo[842]: pam_unix(sudo:session): session closed for user root Sep 11 19:07:56 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:56 volumiok wpa_supplicant[809]: wlan0: Trying to associate with SSID 'Azksk' Sep 11 19:07:56 volumiok dhcpcd[790]: wlan0: carrier acquired Sep 11 19:07:56 volumiok dhcpcd[790]: wlan0: connected to Access Point `Azksk' Sep 11 19:07:56 volumiok wpa_supplicant[809]: wlan0: Associated with fc:ec:da:b8:1e:62 Sep 11 19:07:56 volumiok wpa_supplicant[809]: wlan0: CTRL-EVENT-CONNECTED - Connection to fc:ec:da:b8:1e:62 completed [id=0 id_str=] Sep 11 19:07:56 volumiok wpa_supplicant[809]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 11 19:07:56 volumiok wpa_supplicant[809]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=SG Sep 11 19:07:56 volumiok dhcpcd[790]: wlan0: IAID 32:48:45:e4 Sep 11 19:07:56 volumiok dhcpcd[790]: wlan0: adding address fe80::dea6:32ff:fe48:45e4 Sep 11 19:07:56 volumiok dhcpcd[790]: ipv6_addaddr1: Permission denied Sep 11 19:07:56 volumiok dhcpcd[790]: wlan0: carrier lost Sep 11 19:07:56 volumiok dhcpcd[790]: wlan0: carrier acquired Sep 11 19:07:56 volumiok dhcpcd[790]: wlan0: IAID 32:48:45:e4 Sep 11 19:07:56 volumiok dhcpcd[790]: wlan0: soliciting an IPv6 router Sep 11 19:07:56 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:56 volumiok wireless.js[581]: trying... Sep 11 19:07:56 volumiok sudo[857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 11 19:07:56 volumiok sudo[857]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:07:56 volumiok sudo[857]: pam_unix(sudo:session): session closed for user root Sep 11 19:07:57 volumiok dhcpcd[790]: wlan0: rebinding lease of 192.168.1.9 Sep 11 19:07:57 volumiok dhcpcd[790]: wlan0: NAK: from 192.168.1.254 Sep 11 19:07:57 volumiok dhcpcd[790]: wlan0: soliciting a DHCP lease Sep 11 19:07:57 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:57 volumiok volumio-remote-updater[584]: [2025-09-11 19:07:57] [info] asio async_connect error: system:111 (Connection refused) Sep 11 19:07:57 volumiok volumio-remote-updater[584]: [2025-09-11 19:07:57] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 11 19:07:57 volumiok volumio-remote-updater[584]: [2025-09-11 19:07:57] [error] handle_connect error: Underlying Transport Error Sep 11 19:07:57 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:57 volumiok dhcpcd[790]: eth0: leased 192.168.1.10 for 14400 seconds Sep 11 19:07:57 volumiok avahi-daemon[589]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.10. Sep 11 19:07:57 volumiok avahi-daemon[589]: New relevant interface eth0.IPv4 for mDNS. Sep 11 19:07:57 volumiok dhcpcd[790]: eth0: adding route to 192.168.1.0/24 Sep 11 19:07:57 volumiok avahi-daemon[589]: Registering new address record for 192.168.1.10 on eth0.IPv4. Sep 11 19:07:57 volumiok dhcpcd[790]: eth0: adding default route via 192.168.1.254 Sep 11 19:07:57 volumiok dhcpcd[790]: forked to background, child pid 889 Sep 11 19:07:57 volumiok wireless.js[581]: trying... Sep 11 19:07:57 volumiok sudo[891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 11 19:07:57 volumiok sudo[891]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:07:57 volumiok sudo[891]: pam_unix(sudo:session): session closed for user root Sep 11 19:07:57 volumiok sudo[789]: pam_unix(sudo:session): session closed for user root Sep 11 19:07:58 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:58 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:58 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 11 19:07:58 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 11 19:07:58 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 11 19:07:58 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 11 19:07:58 volumiok wireless.js[581]: trying... Sep 11 19:07:58 volumiok sudo[894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 11 19:07:58 volumiok sudo[894]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:07:58 volumiok sudo[894]: pam_unix(sudo:session): session closed for user root Sep 11 19:07:59 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:59 volumiok dhcpcd[889]: wlan0: offered 192.168.1.12 from 192.168.1.254 Sep 11 19:07:59 volumiok dhcpcd[889]: wlan0: probing address 192.168.1.12/24 Sep 11 19:07:59 volumiok sh[531]: eth0: using IPv4LL address 169.254.24.107 Sep 11 19:07:59 volumiok sh[531]: eth0: adding route to 169.254.0.0/16 Sep 11 19:07:59 volumiok sh[531]: eth0: adding default route Sep 11 19:07:59 volumiok dhcpcd[572]: eth0: using IPv4LL address 169.254.24.107 Sep 11 19:07:59 volumiok dhcpcd[572]: eth0: adding route to 169.254.0.0/16 Sep 11 19:07:59 volumiok dhcpcd[572]: eth0: adding default route Sep 11 19:07:59 volumiok dhcpcd[889]: eth0: pid -612885162 deleted default route via 192.168.1.254 Sep 11 19:07:59 volumiok avahi-daemon[589]: Registering new address record for 169.254.24.107 on eth0.IPv4. Sep 11 19:07:59 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:07:59 volumiok dhcpcd[572]: forked to background, child pid 914 Sep 11 19:07:59 volumiok volumio-time-update[587]: volumio-time-update-util: Fetching time from Volumio... Sep 11 19:07:59 volumiok sh[531]: forked to background, child pid 914 Sep 11 19:07:59 volumiok dhcpcd[914]: eth0: pid -612885162 deleted default route Sep 11 19:07:59 volumiok sh[531]: eth0=eth0 Sep 11 19:07:59 volumiok wireless.js[581]: trying... Sep 11 19:07:59 volumiok sudo[942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 11 19:07:59 volumiok sudo[942]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:07:59 volumiok sudo[942]: pam_unix(sudo:session): session closed for user root Sep 11 19:08:00 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:00 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:00 volumiok dhcpcd[914]: eth0: leased 192.168.1.10 for 14400 seconds Sep 11 19:08:00 volumiok dhcpcd[914]: eth0: adding route to 192.168.1.0/24 Sep 11 19:08:00 volumiok dhcpcd[914]: eth0: adding default route via 192.168.1.254 Sep 11 19:08:00 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:01 volumiok wireless.js[581]: trying... Sep 11 19:08:00 volumiok dhcpcd[914]: eth0: deleting route to 169.254.0.0/16 Sep 11 19:08:00 volumiok avahi-daemon[589]: Withdrawing address record for 169.254.24.107 on eth0. Sep 11 19:08:00 volumiok sudo[965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 11 19:08:00 volumiok sudo[965]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:08:00 volumiok sudo[965]: pam_unix(sudo:session): session closed for user root Sep 11 19:08:01 volumiok dhcpcd[914]: eth0: pid 914 deleted default route via 192.168.1.254 Sep 11 19:08:01 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:01 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 11 19:08:01 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 11 19:08:01 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 11 19:08:01 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 11 19:08:01 volumiok kernel: sda: unable to read partition table Sep 11 19:08:01 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:02 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Sep 11 19:08:02 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 1d cf ff 80 00 00 08 00 Sep 11 19:08:02 volumiok kernel: I/O error, dev sda, sector 500170624 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Sep 11 19:08:01 volumiok sudo[985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 11 19:08:02 volumiok wireless.js[581]: trying... Sep 11 19:08:01 volumiok sudo[985]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:08:01 volumiok sudo[985]: pam_unix(sudo:session): session closed for user root Sep 11 19:08:02 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:02 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:03 volumiok wireless.js[581]: trying... Sep 11 19:08:02 volumiok sudo[988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 11 19:08:02 volumiok sudo[988]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:08:02 volumiok sudo[988]: pam_unix(sudo:session): session closed for user root Sep 11 19:08:03 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:03 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:04 volumiok wireless.js[581]: trying... Sep 11 19:08:03 volumiok sudo[991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 11 19:08:03 volumiok sudo[991]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:08:03 volumiok sudo[991]: pam_unix(sudo:session): session closed for user root Sep 11 19:08:04 volumiok dhcpcd[889]: wlan0: leased 192.168.1.12 for 14400 seconds Sep 11 19:08:04 volumiok avahi-daemon[589]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.12. Sep 11 19:08:04 volumiok dhcpcd[889]: eth0: adding default route via 192.168.1.254 Sep 11 19:08:04 volumiok dhcpcd[889]: wlan0: adding route to 192.168.1.0/24 Sep 11 19:08:04 volumiok dhcpcd[889]: wlan0: adding default route via 192.168.1.254 Sep 11 19:08:04 volumiok avahi-daemon[589]: New relevant interface wlan0.IPv4 for mDNS. Sep 11 19:08:04 volumiok avahi-daemon[589]: Registering new address record for 192.168.1.12 on wlan0.IPv4. Sep 11 19:08:04 volumiok wireless.js[581]: trying... Sep 11 19:08:04 volumiok sudo[1015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 11 19:08:04 volumiok sudo[1015]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:08:05 volumiok sudo[1015]: pam_unix(sudo:session): session closed for user root Sep 11 19:08:05 volumiok wireless.js[581]: Connected to: ----Azksk Sep 11 19:08:05 volumiok wireless.js[581]: ---- Sep 11 19:08:05 volumiok sudo[1018]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 11 19:08:05 volumiok sudo[1018]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:08:05 volumiok sudo[1018]: pam_unix(sudo:session): session closed for user root Sep 11 19:08:05 volumiok wireless.js[581]: ... joined AP, wlan0 IPv4 is 192.168.1.12, ipV6 is undefined Sep 11 19:08:05 volumiok wireless.js[581]: It's done! AP Sep 11 19:08:05 volumiok systemd[1]: Started Wireless Services. Sep 11 19:08:05 volumiok kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 11 19:08:05 volumiok systemd[1]: Started Volumio Backend Module. Sep 11 19:08:05 volumiok systemd[1]: Started Volumio Cpu Tweaker. Sep 11 19:08:05 volumiok volumio-cpu-tweak[1024]: Setting RT Priority for mpd Sep 11 19:08:05 volumiok volumio-cpu-tweak[1024]: pid 35's current scheduling policy: SCHED_OTHER Sep 11 19:08:05 volumiok volumio-cpu-tweak[1024]: pid 35's current scheduling priority: 0 Sep 11 19:08:05 volumiok volumio-cpu-tweak[1024]: Setting MPD Affinity Sep 11 19:08:05 volumiok volumio-cpu-tweak[1024]: pid 3's current affinity mask: f Sep 11 19:08:05 volumiok volumio-cpu-tweak[1024]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Sep 11 19:08:05 volumiok volumio-cpu-tweak[1024]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Sep 11 19:08:05 volumiok systemd[1]: volumio_cpu_tweak.service: Succeeded. Sep 11 19:08:06 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:06 volumiok volumio[1023]: info: ------------------------------------------- Sep 11 19:08:06 volumiok volumio[1023]: info: ----- Volumio3 ---- Sep 11 19:08:06 volumiok volumio[1023]: info: ------------------------------------------- Sep 11 19:08:06 volumiok volumio[1023]: info: ----- System startup ---- Sep 11 19:08:06 volumiok volumio[1023]: info: ------------------------------------------- Sep 11 19:08:06 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:07 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=5s Sep 11 19:08:07 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 1d cf ff 80 00 00 08 00 Sep 11 19:08:07 volumiok kernel: I/O error, dev sda, sector 500170624 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 11 19:08:07 volumiok kernel: Buffer I/O error on dev sda, logical block 62521328, async page read Sep 11 19:08:07 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:07 volumiok volumio-remote-updater[584]: [2025-09-11 19:08:07] [connect] Successful connection Sep 11 19:08:07 volumiok volumio[1023]: info: MYVOLUMIO Environment detected Sep 11 19:08:07 volumiok volumio[1023]: info: Plugin folders cleanup Sep 11 19:08:07 volumiok volumio[1023]: info: Scanning into folder /volumio/app/plugins/ Sep 11 19:08:07 volumiok volumio[1023]: info: Scanning category audio_interface Sep 11 19:08:07 volumiok volumio[1023]: info: Scanning category miscellanea Sep 11 19:08:07 volumiok volumio[1023]: info: Scanning category music_service Sep 11 19:08:07 volumiok volumio[1023]: info: Scanning category plugins.json Sep 11 19:08:07 volumiok volumio[1023]: info: Scanning category system_controller Sep 11 19:08:07 volumiok volumio[1023]: info: Scanning category user_interface Sep 11 19:08:07 volumiok volumio[1023]: info: Scanning into folder /data/plugins/ Sep 11 19:08:07 volumiok volumio[1023]: info: Plugin folders cleanup completed Sep 11 19:08:07 volumiok volumio[1023]: info: ------------------------------------------- Sep 11 19:08:07 volumiok volumio[1023]: info: ----- Core plugins startup ---- Sep 11 19:08:07 volumiok volumio[1023]: info: ------------------------------------------- Sep 11 19:08:07 volumiok volumio[1023]: info: Loading plugins from folder /volumio/app/plugins/ Sep 11 19:08:07 volumiok volumio[1023]: info: Adding plugin upnp to MyMusic Plugins Sep 11 19:08:07 volumiok volumio[1023]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 11 19:08:07 volumiok volumio[1023]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 11 19:08:07 volumiok volumio[1023]: info: Loading plugins from folder /data/plugins/ Sep 11 19:08:07 volumiok volumio[1023]: info: Loading plugin "system"... Sep 11 19:08:07 volumiok volumio[1023]: info: Loading plugin "appearance"... Sep 11 19:08:07 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:08 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:08 volumiok volumio[1023]: info: Loading plugin "network"... Sep 11 19:08:08 volumiok volumio[1023]: info: Refreshing Cached IP Addresses Sep 11 19:08:08 volumiok sudo[1054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 11 19:08:08 volumiok sudo[1054]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:08:08 volumiok sudo[1054]: pam_unix(sudo:session): session closed for user root Sep 11 19:08:08 volumiok sudo[1056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 11 19:08:08 volumiok sudo[1056]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:08:08 volumiok sudo[1056]: pam_unix(sudo:session): session closed for user root Sep 11 19:08:08 volumiok volumio[1023]: info: Loading plugin "services"... Sep 11 19:08:08 volumiok volumio[1023]: info: Loading plugin "alsa_controller"... Sep 11 19:08:08 volumiok sudo[1065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 11 19:08:08 volumiok sudo[1065]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:08:08 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:09 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 11 19:08:09 volumiok volumio[1023]: info: Loading plugin "wizard"... Sep 11 19:08:09 volumiok volumio[1023]: info: Loading plugin "networkfs"... Sep 11 19:08:09 volumiok volumio[1023]: info: Starting Udev Watcher for removable devices Sep 11 19:08:09 volumiok volumio[1023]: info: Ignoring mount for partition: boot Sep 11 19:08:09 volumiok volumio[1023]: info: Ignoring mount for partition: volumio Sep 11 19:08:09 volumiok volumio[1023]: info: Ignoring mount for partition: volumio_data Sep 11 19:08:09 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 11 19:08:09 volumiok volumio[1023]: info: Loading plugin "volumio_command_line_client"... Sep 11 19:08:09 volumiok volumio[1023]: info: Loading plugin "upnp"... Sep 11 19:08:09 volumiok volumio[1023]: info: [1757588889090] Starting Upmpd Daemon Sep 11 19:08:09 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 11 19:08:09 volumiok volumio[1023]: info: Loading plugin "my_music"... Sep 11 19:08:09 volumiok sudo[1082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=kohsk14,password=yWaonng74A,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.13/Mu /mnt/NAS/DSM624 Sep 11 19:08:09 volumiok sudo[1082]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:08:09 volumiok volumio[1023]: info: Loading plugin "mpd"... Sep 11 19:08:09 volumiok kernel: Key type cifs.spnego registered Sep 11 19:08:09 volumiok kernel: Key type cifs.idmap registered Sep 11 19:08:09 volumiok 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. Sep 11 19:08:09 volumiok kernel: CIFS: Attempting to mount //192.168.1.13/Mu Sep 11 19:08:09 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:09 volumiok volumio[1023]: info: Loading plugin "upnp_browser"... Sep 11 19:08:09 volumiok volumio[1023]: info: Loading plugin "alarm-clock"... Sep 11 19:08:09 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:10 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 11 19:08:10 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 11 19:08:10 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 11 19:08:10 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 11 19:08:10 volumiok node[1023]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 11 19:08:10 volumiok volumio[1023]: info: Plugin airplay_emulation is not enabled Sep 11 19:08:10 volumiok volumio[1023]: info: Loading plugin "last_100"... Sep 11 19:08:10 volumiok volumio[1023]: info: Loading plugin "webradio"... Sep 11 19:08:10 volumiok volumio[1023]: info: Loading plugin "i2s_dacs"... Sep 11 19:08:10 volumiok volumio[1023]: info: I2S DAC not set, start Auto-detection Sep 11 19:08:10 volumiok volumio[1023]: info: Loading plugin "volumiodiscovery"... Sep 11 19:08:10 volumiok volumio[1023]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 11 19:08:10 volumiok volumio[1023]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 11 19:08:10 volumiok volumio[1023]: *** WARNING *** For more information see Sep 11 19:08:10 volumiok volumio[1023]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 11 19:08:10 volumiok volumio[1023]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 11 19:08:10 volumiok volumio[1023]: *** WARNING *** For more information see Sep 11 19:08:10 volumiok volumio[1023]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 11 19:08:10 volumiok volumio[1023]: info: Discovery: Started advertising with name: Volumiok Sep 11 19:08:10 volumiok node[1023]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 11 19:08:10 volumiok node[1023]: *** WARNING *** For more information see Sep 11 19:08:10 volumiok node[1023]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 11 19:08:10 volumiok node[1023]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 11 19:08:10 volumiok node[1023]: *** WARNING *** For more information see Sep 11 19:08:10 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 11 19:08:10 volumiok volumio[1023]: info: Loading plugin "outputs"... Sep 11 19:08:10 volumiok volumio[1023]: info: Loading plugin "albumart"... Sep 11 19:08:10 volumiok volumio[1023]: info: Plugin example_plugin is not enabled Sep 11 19:08:10 volumiok volumio[1023]: info: Loading plugin "inputs"... Sep 11 19:08:10 volumiok volumio[1023]: info: Loading plugin "updater_comm"... Sep 11 19:08:10 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:10 volumiok volumio[1023]: info: Plugin mpdemulation is not enabled Sep 11 19:08:10 volumiok volumio[1023]: info: Loading plugin "rest_api"... Sep 11 19:08:10 volumiok volumio[1023]: info: Loading plugin "websocket"... Sep 11 19:08:10 volumiok volumio[1023]: info: Starting Socket.io Server version 2.3.0 Sep 11 19:08:10 volumiok volumio[1023]: Forking 3 albumart workers Sep 11 19:08:10 volumiok volumio[1023]: info: Loading i18n strings for locale en Sep 11 19:08:10 volumiok volumio[1023]: Updating browse sources language Sep 11 19:08:10 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 11 19:08:10 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 11 19:08:10 volumiok volumio[1023]: info: CoreCommandRouter::initPlayerControls Sep 11 19:08:10 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 19:08:10 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 19:08:10 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 19:08:10 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 19:08:10 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 19:08:10 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 19:08:10 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 19:08:10 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 19:08:10 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 11 19:08:10 volumiok volumio[1023]: Express server listening on port 3000 Sep 11 19:08:10 volumiok volumio[1023]: [Metrics] WebUI: 4s 412.35ms Sep 11 19:08:10 volumiok volumio[1023]: info: CoreStateMachine::resetVolumioState Sep 11 19:08:10 volumiok volumio[1023]: info: CoreStateMachine::getcurrentVolume Sep 11 19:08:10 volumiok volumio[1023]: info: CoreCommandRouter::volumioRetrievevolume Sep 11 19:08:10 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 11 19:08:10 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:10 volumiok volumio[1023]: info: Volumio Network Manager: Network status updated: 3 Sep 11 19:08:10 volumiok volumio[1023]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Sep 11 19:08:10 volumiok volumio[1023]: info: CoreStateMachine::setRepeat null single undefined Sep 11 19:08:10 volumiok volumio[1023]: info: CoreStateMachine::pushState Sep 11 19:08:10 volumiok volumio[1023]: info: CorePlayQueue::getTrack 0 Sep 11 19:08:10 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 19:08:10 volumiok volumio[1023]: info: CoreCommandRouter::volumioPushState Sep 11 19:08:10 volumiok volumio[1023]: info: CoreStateMachine::setRandom null Sep 11 19:08:10 volumiok volumio[1023]: info: CoreStateMachine::pushState Sep 11 19:08:10 volumiok volumio[1023]: info: CorePlayQueue::getTrack 0 Sep 11 19:08:10 volumiok volumio[1023]: info: CoreCommandRouter::volumioPushState Sep 11 19:08:10 volumiok volumio[1023]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 11 19:08:10 volumiok volumio[1023]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111 Sep 11 19:08:10 volumiok volumio[1023]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 11 19:08:10 volumiok volumio[1023]: info: Setting Device type: Raspberry PI Sep 11 19:08:10 volumiok volumio-remote-updater[584]: [2025-09-11 19:08:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757588887 101 Sep 11 19:08:10 volumiok volumio[1023]: Starting albumart workers Sep 11 19:08:10 volumiok volumio[1023]: info: VolumeController:: Volume=100 Mute =false Sep 11 19:08:10 volumiok volumio[1023]: info: CoreStateMachine::pushState Sep 11 19:08:10 volumiok volumio[1023]: info: CorePlayQueue::getTrack 0 Sep 11 19:08:10 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 19:08:10 volumiok volumio[1023]: info: CoreCommandRouter::volumioPushState Sep 11 19:08:10 volumiok volumio[1023]: info: CoreStateMachine::updateTrackBlock Sep 11 19:08:10 volumiok volumio[1023]: info: CorePlayQueue::getTrackBlock Sep 11 19:08:10 volumiok volumio[1023]: info: CoreCommandRouter::volumioRetrievevolume Sep 11 19:08:10 volumiok volumio[1023]: Starting albumart workers Sep 11 19:08:11 volumiok volumio[1023]: Starting albumart workers Sep 11 19:08:11 volumiok volumio[1023]: info: Discovery: adding d072bc9f-95e7-4e0f-9dcd-6a0491230831 Sep 11 19:08:11 volumiok volumio[1023]: info: Discovery: Found device Volumiok Sep 11 19:08:11 volumiok volumio[1023]: info: CoreCommandRouter::volumioGetState Sep 11 19:08:11 volumiok volumio[1023]: info: CorePlayQueue::getTrack 0 Sep 11 19:08:11 volumiok volumio[1023]: info: Discovery: this is already registered, d072bc9f-95e7-4e0f-9dcd-6a0491230831 Sep 11 19:08:11 volumiok volumio[1023]: info: Discovery: Found device Volumiok Sep 11 19:08:11 volumiok volumio[1023]: info: CoreCommandRouter::volumioGetState Sep 11 19:08:11 volumiok volumio[1023]: info: CorePlayQueue::getTrack 0 Sep 11 19:08:11 volumiok volumio[1023]: info: VolumeController:: Volume=100 Mute =false Sep 11 19:08:11 volumiok volumio[1023]: info: CoreStateMachine::pushState Sep 11 19:08:11 volumiok volumio[1023]: info: CorePlayQueue::getTrack 0 Sep 11 19:08:11 volumiok volumio[1023]: info: CoreCommandRouter::volumioPushState Sep 11 19:08:11 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:11 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 19:08:11 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 19:08:11 volumiok volumio[1023]: info: Discovery: Getting this device information Sep 11 19:08:11 volumiok volumio[1023]: info: CoreCommandRouter::volumioGetState Sep 11 19:08:11 volumiok volumio[1023]: info: CorePlayQueue::getTrack 0 Sep 11 19:08:11 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 19:08:11 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 19:08:11 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 19:08:11 volumiok volumio[1023]: info: Discovery: Getting this device information Sep 11 19:08:11 volumiok volumio[1023]: info: CoreCommandRouter::volumioGetState Sep 11 19:08:11 volumiok volumio[1023]: info: CorePlayQueue::getTrack 0 Sep 11 19:08:11 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 19:08:11 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 19:08:11 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 19:08:11 volumiok volumio[1023]: info: Discovery: Getting this device information Sep 11 19:08:11 volumiok volumio[1023]: info: CoreCommandRouter::volumioGetState Sep 11 19:08:11 volumiok volumio[1023]: info: CorePlayQueue::getTrack 0 Sep 11 19:08:11 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 19:08:11 volumiok volumio[1023]: verbose: New Socket.io Connection to 192.168.1.10:3000 from 192.168.1.5 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Sep 11 19:08:11 volumiok volumio[1023]: info: CoreCommandRouter::volumioGetState Sep 11 19:08:11 volumiok volumio[1023]: info: CorePlayQueue::getTrack 0 Sep 11 19:08:11 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 11 19:08:11 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 11 19:08:11 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:12 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:12 volumiok sudo[1065]: pam_unix(sudo:session): session closed for user root Sep 11 19:08:12 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:12 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 11 19:08:12 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 11 19:08:12 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 11 19:08:12 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 11 19:08:13 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:13 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:13 volumiok systemd[1]: systemd-fsckd.service: Succeeded. Sep 11 19:08:14 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:14 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:14 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:15 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:15 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 11 19:08:15 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 11 19:08:15 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 11 19:08:15 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 11 19:08:15 volumiok kernel: sda: unable to read partition table Sep 11 19:08:15 volumiok systemd[1]: systemd-hostnamed.service: Succeeded. Sep 11 19:08:15 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:16 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:16 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:17 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:17 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:18 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:18 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:19 volumiok volumio[1023]: verbose: New Socket.io Connection to 192.168.1.10:3000 from 192.168.1.5 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Sep 11 19:08:19 volumiok sudo[1187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 11 19:08:19 volumiok sudo[1187]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:08:19 volumiok sudo[1187]: pam_unix(sudo:session): session closed for user root Sep 11 19:08:19 volumiok sudo[1189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 11 19:08:19 volumiok sudo[1189]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:08:19 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:19 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 19:08:19 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 19:08:19 volumiok volumio[1023]: info: Discovery: Getting this device information Sep 11 19:08:19 volumiok volumio[1023]: info: CoreCommandRouter::volumioGetState Sep 11 19:08:19 volumiok volumio[1023]: info: CorePlayQueue::getTrack 0 Sep 11 19:08:19 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 19:08:19 volumiok volumio[1023]: info: Upmpdcli Daemon Started Sep 11 19:08:19 volumiok systemd[1]: Started UPnP Renderer front-end to MPD. Sep 11 19:08:19 volumiok sudo[1189]: pam_unix(sudo:session): session closed for user root Sep 11 19:08:19 volumiok sudo[1193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 11 19:08:19 volumiok sudo[1193]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:08:19 volumiok sudo[1193]: pam_unix(sudo:session): session closed for user root Sep 11 19:08:19 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:19 volumiok volumio[1023]: info: CoreCommandRouter::volumioGetState Sep 11 19:08:19 volumiok volumio[1023]: info: CorePlayQueue::getTrack 0 Sep 11 19:08:20 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:20 volumiok volumio[1023]: verbose: New Socket.io Connection to 192.168.1.10:3000 from 192.168.1.5 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Sep 11 19:08:20 volumiok volumio[1023]: info: CoreCommandRouter::volumioGetState Sep 11 19:08:20 volumiok volumio[1023]: info: CorePlayQueue::getTrack 0 Sep 11 19:08:20 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 11 19:08:20 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 11 19:08:20 volumiok volumio[1023]: info: CoreCommandRouter::volumioGetState Sep 11 19:08:20 volumiok volumio[1023]: info: CorePlayQueue::getTrack 0 Sep 11 19:08:20 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:21 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:21 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 11 19:08:21 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 11 19:08:21 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 11 19:08:21 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 11 19:08:21 volumiok sudo[1197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 11 19:08:21 volumiok sudo[1197]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:08:21 volumiok sudo[1197]: pam_unix(sudo:session): session closed for user root Sep 11 19:08:21 volumiok sudo[1199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 11 19:08:21 volumiok sudo[1199]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:08:21 volumiok sudo[1199]: pam_unix(sudo:session): session closed for user root Sep 11 19:08:21 volumiok volumio[1023]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 15; SM-X115 Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/140.0.7339.51 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Sep 11 19:08:21 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:21 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 11 19:08:21 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:22 volumiok sudo[1203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 11 19:08:22 volumiok sudo[1203]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:08:22 volumiok sudo[1203]: pam_unix(sudo:session): session closed for user root Sep 11 19:08:22 volumiok sudo[1205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 11 19:08:22 volumiok sudo[1205]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:08:22 volumiok sudo[1205]: pam_unix(sudo:session): session closed for user root Sep 11 19:08:22 volumiok volumio[1023]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 15; SM-X115 Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/140.0.7339.51 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Sep 11 19:08:22 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:22 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 11 19:08:22 volumiok volumio[1023]: info: CoreCommandRouter::volumioGetVisibleSources Sep 11 19:08:22 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 11 19:08:22 volumiok volumio[1023]: info: CoreCommandRouter::volumioGetState Sep 11 19:08:22 volumiok volumio[1023]: info: CorePlayQueue::getTrack 0 Sep 11 19:08:22 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 11 19:08:22 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 11 19:08:22 volumiok volumio[1023]: info: Received Get System Info Sep 11 19:08:22 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 19:08:22 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 19:08:22 volumiok volumio[1023]: info: Discovery: Getting this device information Sep 11 19:08:22 volumiok volumio[1023]: info: CoreCommandRouter::volumioGetState Sep 11 19:08:22 volumiok volumio[1023]: info: CorePlayQueue::getTrack 0 Sep 11 19:08:22 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 19:08:22 volumiok volumio[1023]: info: CoreCommandRouter::volumioGetState Sep 11 19:08:22 volumiok volumio[1023]: info: CorePlayQueue::getTrack 0 Sep 11 19:08:22 volumiok volumio[1023]: info: Listing playlists Sep 11 19:08:22 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 11 19:08:22 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 11 19:08:22 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:23 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:23 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:24 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 11 19:08:24 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 11 19:08:24 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 11 19:08:24 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 11 19:08:24 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 11 19:08:24 volumiok volumio[1023]: info: Received Get System Info Sep 11 19:08:24 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 19:08:24 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 19:08:24 volumiok volumio[1023]: info: Discovery: Getting this device information Sep 11 19:08:24 volumiok volumio[1023]: info: CoreCommandRouter::volumioGetState Sep 11 19:08:24 volumiok volumio[1023]: info: CorePlayQueue::getTrack 0 Sep 11 19:08:24 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 19:08:24 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 11 19:08:24 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:24 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 11 19:08:24 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:25 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:25 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:25 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 11 19:08:25 volumiok volumio[1023]: info: Received Get System Info Sep 11 19:08:25 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 19:08:25 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 19:08:25 volumiok volumio[1023]: info: Discovery: Getting this device information Sep 11 19:08:25 volumiok volumio[1023]: info: CoreCommandRouter::volumioGetState Sep 11 19:08:25 volumiok volumio[1023]: info: CorePlayQueue::getTrack 0 Sep 11 19:08:25 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 19:08:26 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:26 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 11 19:08:26 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:26 volumiok kernel: cryptd: max_cpu_qlen set to 1000 Sep 11 19:08:26 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 11 19:08:26 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 11 19:08:26 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 11 19:08:26 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 11 19:08:26 volumiok kernel: sda: unable to read partition table Sep 11 19:08:27 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:27 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Sep 11 19:08:27 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 1d cf ff 80 00 00 08 00 Sep 11 19:08:27 volumiok kernel: I/O error, dev sda, sector 500170624 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Sep 11 19:08:27 volumiok sudo[1082]: pam_unix(sudo:session): session closed for user root Sep 11 19:08:27 volumiok kernel: CIFS: VFS: Autodisabling the use of server inode numbers on new server Sep 11 19:08:27 volumiok kernel: CIFS: VFS: The server doesn't seem to support them properly or the files might be on different servers (DFS) Sep 11 19:08:27 volumiok kernel: CIFS: VFS: Hardlinks will not be recognized on this mount. Consider mounting with the "noserverino" option to silence this message. Sep 11 19:08:27 volumiok volumio[1023]: info: Completed loading Core Plugins Sep 11 19:08:27 volumiok volumio[1023]: info: Preparing to generate the ALSA configuration file Sep 11 19:08:27 volumiok volumio[1023]: info: Asound.conf file unchanged, so no further update is needed Sep 11 19:08:27 volumiok volumio[1023]: info: Output device has changed, restarting MPD Sep 11 19:08:27 volumiok sudo[1227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 11 19:08:27 volumiok volumio[1023]: info: ___________ START PLUGINS ___________ Sep 11 19:08:27 volumiok sudo[1227]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:08:27 volumiok sudo[1227]: pam_unix(sudo:session): session closed for user root Sep 11 19:08:27 volumiok volumio[1023]: info: ControllerMpd::onStart: Initializing MPD Sep 11 19:08:27 volumiok volumio[1023]: info: Creating MPD Configuration file Sep 11 19:08:27 volumiok sudo[1229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 11 19:08:27 volumiok sudo[1229]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:08:27 volumiok sudo[1233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 11 19:08:27 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 11 19:08:27 volumiok volumio[1023]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 11 19:08:27 volumiok volumio[1023]: info: [1757588907475] CoreMusicLibrary::Adding element Media Servers Sep 11 19:08:27 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 11 19:08:27 volumiok sudo[1233]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:08:27 volumiok sudo[1233]: pam_unix(sudo:session): session closed for user root Sep 11 19:08:27 volumiok sudo[1235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 11 19:08:27 volumiok sudo[1235]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 19:08:27 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:27 volumiok volumio[1023]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 11 19:08:27 volumiok volumio[1023]: info: [1757588907498] CoreMusicLibrary::Adding element Last_100 Sep 11 19:08:27 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 11 19:08:27 volumiok volumio[1023]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 11 19:08:27 volumiok volumio[1023]: info: [1757588907500] CoreMusicLibrary::Adding element Webradio Sep 11 19:08:27 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 11 19:08:27 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 11 19:08:27 volumiok volumio[1023]: info: Initializing BBC Radios Sep 11 19:08:27 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 11 19:08:27 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 11 19:08:27 volumiok volumio[1023]: info: Volumio Calling Home Sep 11 19:08:27 volumiok volumio[1023]: info: MPD Permissions set Sep 11 19:08:27 volumiok volumio[1023]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 11 19:08:27 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 19:08:27 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 11 19:08:27 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 19:08:27 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 19:08:27 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 11 19:08:27 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 19:08:27 volumiok volumio[1023]: info: CoreCommandRouter::volumioGetState Sep 11 19:08:27 volumiok volumio[1023]: info: CorePlayQueue::getTrack 0 Sep 11 19:08:27 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:28 volumiok volumio[1023]: info: Volumio called home Sep 11 19:08:28 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:28 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:30 volumiok volumio[1023]: info: CoreCommandRouter::volumioGetQueue Sep 11 19:08:30 volumiok volumio[1023]: info: CoreStateMachine::getQueue Sep 11 19:08:30 volumiok volumio[1023]: info: CorePlayQueue::getQueue Sep 11 19:08:31 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:32 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:32 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=4s Sep 11 19:08:32 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 1d cf ff 80 00 00 08 00 Sep 11 19:08:32 volumiok kernel: I/O error, dev sda, sector 500170624 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 11 19:08:32 volumiok kernel: Buffer I/O error on dev sda, logical block 62521328, async page read Sep 11 19:08:32 volumiok volumio[1023]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 11 19:08:32 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:32 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:33 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:33 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 11 19:08:34 volumiok volumio[1023]: info: Preload queue cleared Sep 11 19:08:34 volumiok volumio[1023]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 11 19:08:34 volumiok volumio[1023]: info: CoreStateMachine::ClearQueue Sep 11 19:08:34 volumiok volumio[1023]: info: CoreStateMachine::stop Sep 11 19:08:34 volumiok volumio[1023]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 19:08:34 volumiok volumio[1023]: info: CorePlayQueue::clearPlayQueue Sep 11 19:08:34 volumiok volumio[1023]: info: CorePlayQueue::saveQueue Sep 11 19:08:34 volumiok volumio[1023]: info: CoreCommandRouter::volumioPushQueue Sep 11 19:08:34 volumiok volumio[1023]: info: CoreStateMachine::addQueueItems Sep 11 19:08:34 volumiok volumio[1023]: info: CorePlayQueue::addQueueItems Sep 11 19:08:34 volumiok volumio[1023]: info: Preload queue cleared Sep 11 19:08:34 volumiok volumio[1023]: info: Adding Item to queue: music-library/NAS/DSM624/Demo/Naim A Forsione/01 - Various Artists - Antonio Forcione & Sabina Sciubba - Could You Believe-.flac Sep 11 19:08:34 volumiok volumio[1023]: info: Exploding uri music-library/NAS/DSM624/Demo/Naim A Forsione/01 - Various Artists - Antonio Forcione & Sabina Sciubba - Could You Believe-.flac in service mpd Sep 11 19:08:34 volumiok volumio[1023]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 11 19:08:34 volumiok volumio[1023]: TypeError: Cannot read property 'then' of undefined Sep 11 19:08:34 volumiok volumio[1023]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2528:19) Sep 11 19:08:34 volumiok volumio[1023]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2326:21) Sep 11 19:08:34 volumiok volumio[1023]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:16) Sep 11 19:08:34 volumiok volumio[1023]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Sep 11 19:08:34 volumiok volumio[1023]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Sep 11 19:08:34 volumiok volumio[1023]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Sep 11 19:08:34 volumiok volumio[1023]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:249:25) Sep 11 19:08:34 volumiok volumio[1023]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:511:25) Sep 11 19:08:34 volumiok volumio[1023]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Sep 11 19:08:34 volumiok volumio[1023]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Sep 11 19:08:34 volumiok volumio[1023]: at Socket.emit (events.js:315:20) Sep 11 19:08:34 volumiok volumio[1023]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 11 19:08:34 volumiok volumio[1023]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 11 19:08:34 volumiok volumio[1023]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| 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"