-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Sat 2024-04-27 15:47:51 EDT. -- Apr 27 15:46:48 rhvolumio kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000 Apr 27 15:46:48 rhvolumio kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x00ffffffff -> 0x0400000000 Apr 27 15:46:48 rhvolumio kernel: brcm-pcie fd500000.pcie: setting SCB_ACCESS_EN, READ_UR_MODE, MAX_BURST_SIZE Apr 27 15:46:48 rhvolumio kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Apr 27 15:46:48 rhvolumio kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Apr 27 15:46:48 rhvolumio kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff]) Apr 27 15:46:48 rhvolumio kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 Apr 27 15:46:48 rhvolumio kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Apr 27 15:46:48 rhvolumio kernel: PCI: bus0: Fast back to back transfers disabled Apr 27 15:46:48 rhvolumio kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Apr 27 15:46:48 rhvolumio kernel: pci_bus 0000:01: supply vpcie3v3 not found, using dummy regulator Apr 27 15:46:48 rhvolumio kernel: pci_bus 0000:01: supply vpcie3v3aux not found, using dummy regulator Apr 27 15:46:48 rhvolumio kernel: pci_bus 0000:01: supply vpcie12v not found, using dummy regulator Apr 27 15:46:48 rhvolumio kernel: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) Apr 27 15:46:48 rhvolumio kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 Apr 27 15:46:48 rhvolumio kernel: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] Apr 27 15:46:48 rhvolumio kernel: pci 0000:01:00.0: PME# supported from D0 D3hot Apr 27 15:46:48 rhvolumio kernel: PCI: bus1: Fast back to back transfers disabled Apr 27 15:46:48 rhvolumio kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Apr 27 15:46:48 rhvolumio kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] Apr 27 15:46:48 rhvolumio kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] Apr 27 15:46:48 rhvolumio kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Apr 27 15:46:48 rhvolumio kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Apr 27 15:46:48 rhvolumio kernel: pcieport 0000:00:00.0: enabling device (0000 -> 0002) Apr 27 15:46:48 rhvolumio kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 45 Apr 27 15:46:48 rhvolumio kernel: bcm2708_fb soc:fb: FB found 1 display(s) Apr 27 15:46:48 rhvolumio kernel: Console: switching to colour frame buffer device 160x25 Apr 27 15:46:48 rhvolumio kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 1280x400 Apr 27 15:46:48 rhvolumio kernel: iproc-rng200 fe104000.rng: hwrng registered Apr 27 15:46:48 rhvolumio kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Apr 27 15:46:48 rhvolumio kernel: brd: module loaded Apr 27 15:46:48 rhvolumio kernel: loop: module loaded Apr 27 15:46:48 rhvolumio kernel: Loading iSCSI transport class v2.0-870. Apr 27 15:46:48 rhvolumio kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Apr 27 15:46:48 rhvolumio kernel: Freeing initrd memory: 13628K Apr 27 15:46:48 rhvolumio kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Apr 27 15:46:48 rhvolumio kernel: usbcore: registered new device driver r8152-cfgselector Apr 27 15:46:48 rhvolumio kernel: usbcore: registered new interface driver r8152 Apr 27 15:46:48 rhvolumio kernel: usbcore: registered new interface driver lan78xx Apr 27 15:46:48 rhvolumio kernel: usbcore: registered new interface driver smsc95xx Apr 27 15:46:48 rhvolumio kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Apr 27 15:46:48 rhvolumio kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Apr 27 15:46:48 rhvolumio kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0b00040000000890 Apr 27 15:46:48 rhvolumio kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Apr 27 15:46:48 rhvolumio kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Apr 27 15:46:48 rhvolumio kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Apr 27 15:46:48 rhvolumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01 Apr 27 15:46:48 rhvolumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Apr 27 15:46:48 rhvolumio kernel: usb usb1: Product: xHCI Host Controller Apr 27 15:46:48 rhvolumio kernel: usb usb1: Manufacturer: Linux 6.1.69-v7l+ xhci-hcd Apr 27 15:46:48 rhvolumio kernel: usb usb1: SerialNumber: 0000:01:00.0 Apr 27 15:46:48 rhvolumio kernel: hub 1-0:1.0: USB hub found Apr 27 15:46:48 rhvolumio kernel: hub 1-0:1.0: 1 port detected Apr 27 15:46:48 rhvolumio kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.01 Apr 27 15:46:48 rhvolumio kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Apr 27 15:46:48 rhvolumio kernel: usb usb2: Product: xHCI Host Controller Apr 27 15:46:48 rhvolumio kernel: usb usb2: Manufacturer: Linux 6.1.69-v7l+ xhci-hcd Apr 27 15:46:48 rhvolumio kernel: usb usb2: SerialNumber: 0000:01:00.0 Apr 27 15:46:48 rhvolumio kernel: hub 2-0:1.0: USB hub found Apr 27 15:46:48 rhvolumio kernel: hub 2-0:1.0: 4 ports detected Apr 27 15:46:48 rhvolumio kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Apr 27 15:46:48 rhvolumio kernel: dwc_otg: FIQ enabled Apr 27 15:46:48 rhvolumio kernel: dwc_otg: NAK holdoff enabled Apr 27 15:46:48 rhvolumio kernel: dwc_otg: FIQ split-transaction FSM enabled Apr 27 15:46:48 rhvolumio kernel: Module dwc_common_port init Apr 27 15:46:48 rhvolumio kernel: usbcore: registered new interface driver uas Apr 27 15:46:48 rhvolumio kernel: usbcore: registered new interface driver usb-storage Apr 27 15:46:48 rhvolumio kernel: mousedev: PS/2 mouse device common for all mice Apr 27 15:46:48 rhvolumio kernel: sdhci: Secure Digital Host Controller Interface driver Apr 27 15:46:48 rhvolumio kernel: sdhci: Copyright(c) Pierre Ossman Apr 27 15:46:48 rhvolumio kernel: sdhci-pltfm: SDHCI platform and OF driver helper Apr 27 15:46:48 rhvolumio kernel: ledtrig-cpu: registered to indicate activity on CPUs Apr 27 15:46:48 rhvolumio kernel: hid: raw HID events driver (C) Jiri Kosina Apr 27 15:46:48 rhvolumio kernel: usbcore: registered new interface driver usbhid Apr 27 15:46:48 rhvolumio kernel: usbhid: USB HID core driver Apr 27 15:46:48 rhvolumio kernel: Initializing XFRM netlink socket Apr 27 15:46:48 rhvolumio kernel: NET: Registered PF_PACKET protocol family Apr 27 15:46:48 rhvolumio kernel: Key type dns_resolver registered Apr 27 15:46:48 rhvolumio kernel: Registering SWP/SWPB emulation handler Apr 27 15:46:48 rhvolumio kernel: registered taskstats version 1 Apr 27 15:46:48 rhvolumio kernel: Loading compiled-in X.509 certificates Apr 27 15:46:48 rhvolumio kernel: Key type .fscrypt registered Apr 27 15:46:48 rhvolumio kernel: Key type fscrypt-provisioning registered Apr 27 15:46:48 rhvolumio kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Apr 27 15:46:48 rhvolumio kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Apr 27 15:46:48 rhvolumio kernel: fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 51, base_baud = 0) is a PL011 rev2 Apr 27 15:46:48 rhvolumio kernel: serial serial0: tty port ttyAMA1 registered Apr 27 15:46:48 rhvolumio kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Apr 27 15:46:48 rhvolumio kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Apr 27 15:46:48 rhvolumio kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Apr 27 15:46:48 rhvolumio kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Apr 27 15:46:48 rhvolumio kernel: of_cfs_init Apr 27 15:46:48 rhvolumio kernel: of_cfs_init: OK Apr 27 15:46:48 rhvolumio kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Apr 27 15:46:48 rhvolumio kernel: Freeing unused kernel image (initmem) memory: 2048K Apr 27 15:46:48 rhvolumio kernel: Run /init as init process Apr 27 15:46:48 rhvolumio kernel: with arguments: Apr 27 15:46:48 rhvolumio kernel: /init Apr 27 15:46:48 rhvolumio kernel: splash Apr 27 15:46:48 rhvolumio kernel: with environment: Apr 27 15:46:48 rhvolumio kernel: HOME=/ Apr 27 15:46:48 rhvolumio kernel: TERM=linux Apr 27 15:46:48 rhvolumio kernel: imgpart=/dev/mmcblk0p2 Apr 27 15:46:48 rhvolumio kernel: imgfile=/volumio_current.sqsh Apr 27 15:46:48 rhvolumio kernel: bootdelay=5 Apr 27 15:46:48 rhvolumio kernel: mmc1: new high speed SDIO card at address 0001 Apr 27 15:46:48 rhvolumio kernel: mmc0: new ultra high speed DDR50 SDXC card at address aaaa Apr 27 15:46:48 rhvolumio kernel: mmcblk0: mmc0:aaaa SB128 119 GiB Apr 27 15:46:48 rhvolumio kernel: mmcblk0: p1 p2 p3 Apr 27 15:46:48 rhvolumio kernel: mmcblk0: mmc0:aaaa SB128 119 GiB (quirks 0x00004000) Apr 27 15:46:48 rhvolumio kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Apr 27 15:46:48 rhvolumio initramfs: Booting Volumio for BCM2711 Apr 27 15:46:48 rhvolumio initramfs: This script mounts rootfs RO with an overlay RW layer. Apr 27 15:46:48 rhvolumio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Apr 27 15:46:48 rhvolumio initramfs: IMGPART=/dev/mmcblk0p2 Apr 27 15:46:48 rhvolumio initramfs: IMGFILE=/volumio_current.sqsh Apr 27 15:46:48 rhvolumio initramfs: Boot delay (except first time) will be 5 seconds Apr 27 15:46:48 rhvolumio initramfs: /dev/mmcblk0p2: Apr 27 15:46:48 rhvolumio kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Apr 27 15:46:48 rhvolumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Apr 27 15:46:48 rhvolumio kernel: usb 1-1: Product: USB2.0 Hub Apr 27 15:46:48 rhvolumio kernel: hub 1-1:1.0: USB hub found Apr 27 15:46:48 rhvolumio kernel: hub 1-1:1.0: 4 ports detected Apr 27 15:46:48 rhvolumio kernel: EXT4-fs (mmcblk0p2): recovery complete Apr 27 15:46:48 rhvolumio kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none. Apr 27 15:46:48 rhvolumio initramfs: Doing a 5 second delay here to give kernel load a headstart Apr 27 15:46:48 rhvolumio kernel: usb 2-2: new SuperSpeed USB device number 2 using xhci_hcd Apr 27 15:46:48 rhvolumio kernel: usb 2-2: New USB device found, idVendor=0bda, idProduct=9210, bcdDevice=20.01 Apr 27 15:46:48 rhvolumio kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Apr 27 15:46:48 rhvolumio kernel: usb 2-2: Product: SSK Storage Apr 27 15:46:48 rhvolumio kernel: usb 2-2: Manufacturer: SSK Apr 27 15:46:48 rhvolumio kernel: usb 2-2: SerialNumber: 012345678913 Apr 27 15:46:48 rhvolumio kernel: usb 2-2: Enable of device-initiated U1 failed. Apr 27 15:46:48 rhvolumio kernel: usb 2-2: Enable of device-initiated U2 failed. Apr 27 15:46:48 rhvolumio kernel: usb 2-2: Enable of device-initiated U1 failed. Apr 27 15:46:48 rhvolumio kernel: usb 2-2: Enable of device-initiated U2 failed. Apr 27 15:46:48 rhvolumio kernel: scsi host0: uas Apr 27 15:46:48 rhvolumio kernel: scsi 0:0:0:0: Direct-Access SSK SSK Storage 1.00 PQ: 0 ANSI: 6 Apr 27 15:46:48 rhvolumio kernel: sd 0:0:0:0: [sda] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB) Apr 27 15:46:48 rhvolumio kernel: sd 0:0:0:0: [sda] Write Protect is off Apr 27 15:46:48 rhvolumio kernel: sd 0:0:0:0: [sda] Mode Sense: 37 00 00 08 Apr 27 15:46:48 rhvolumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Apr 27 15:46:48 rhvolumio kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 512 bytes Apr 27 15:46:48 rhvolumio kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes Apr 27 15:46:48 rhvolumio kernel: sda: sda1 Apr 27 15:46:48 rhvolumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Apr 27 15:46:48 rhvolumio initramfs: Checking for USB updates Apr 27 15:46:48 rhvolumio kernel: F2FS-fs (sda1): Magic Mismatch, valid(0xf2f52010) - read(0x0) Apr 27 15:46:48 rhvolumio kernel: F2FS-fs (sda1): Can't find valid F2FS filesystem in 1th superblock Apr 27 15:46:48 rhvolumio kernel: F2FS-fs (sda1): Magic Mismatch, valid(0xf2f52010) - read(0x0) Apr 27 15:46:48 rhvolumio kernel: F2FS-fs (sda1): Can't find valid F2FS filesystem in 2th superblock Apr 27 15:46:48 rhvolumio kernel: loop0: detected capacity change from 0 to 967536 Apr 27 15:46:48 rhvolumio kernel: EXT4-fs (mmcblk0p3): recovery complete Apr 27 15:46:48 rhvolumio kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Quota mode: none. Apr 27 15:46:48 rhvolumio initramfs: With Option: Apr 27 15:46:48 rhvolumio initramfs: VOLUMIO_VERSION="3.631" Apr 27 15:46:48 rhvolumio initramfs: Finish initramfs, continue booting Volumio Apr 27 15:46:48 rhvolumio systemd[1]: System time before build time, advancing clock. Apr 27 15:46:48 rhvolumio kernel: NET: Registered PF_INET6 protocol family Apr 27 15:46:48 rhvolumio kernel: Segment Routing with IPv6 Apr 27 15:46:48 rhvolumio kernel: In-situ OAM (IOAM) with IPv6 Apr 27 15:46:48 rhvolumio systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Apr 27 15:46:48 rhvolumio systemd[1]: Detected architecture arm. Apr 27 15:46:48 rhvolumio systemd[1]: Set hostname to . Apr 27 15:46:48 rhvolumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 27 15:46:48 rhvolumio systemd[1]: Listening on RPCbind Server Activation Socket. Apr 27 15:46:48 rhvolumio systemd[1]: Started Forward Password Requests to Wall Directory Watch. Apr 27 15:46:48 rhvolumio systemd[1]: Created slice User and Session Slice. Apr 27 15:46:48 rhvolumio systemd[1]: Reached target Slices. Apr 27 15:46:48 rhvolumio systemd[1]: Listening on fsck to fsckd communication Socket. Apr 27 15:46:48 rhvolumio systemd[1]: Listening on initctl Compatibility Named Pipe. Apr 27 15:46:48 rhvolumio kernel: i2c_dev: i2c /dev entries driver Apr 27 15:46:48 rhvolumio systemd-journald[221]: Journal started Apr 27 15:46:48 rhvolumio systemd-journald[221]: Runtime journal (/run/log/journal/c1d531165394c79721acb5a765d65e88) is 7.5M, max 30.0M, 22.5M free. Apr 27 15:46:48 rhvolumio systemd-modules-load[225]: Inserted module 'i2c_dev' Apr 27 15:46:48 rhvolumio fake-hwclock[213]: Sat Apr 27 19:46:48 UTC 2024 Apr 27 15:46:48 rhvolumio systemd[1]: Started Load/Save Random Seed. Apr 27 15:46:48 rhvolumio systemd[1]: Mounted Kernel Configuration File System. Apr 27 15:46:48 rhvolumio systemd[1]: dynamicswap.service: Succeeded. Apr 27 15:46:48 rhvolumio systemd[1]: Started Create System Users. Apr 27 15:46:48 rhvolumio systemd[1]: Started Apply Kernel Variables. Apr 27 15:46:48 rhvolumio systemd[1]: Starting Create Static Device Nodes in /dev... Apr 27 15:46:48 rhvolumio systemd[1]: Started udev Coldplug all Devices. Apr 27 15:46:48 rhvolumio systemd[1]: Starting Helper to synchronize boot up for ifupdown... Apr 27 15:46:48 rhvolumio systemd[1]: Started Create Static Device Nodes in /dev. Apr 27 15:46:48 rhvolumio systemd[1]: Starting udev Kernel Device Manager... Apr 27 15:46:48 rhvolumio systemd[1]: Reached target Local File Systems (Pre). Apr 27 15:46:48 rhvolumio systemd[1]: Mounting /var/spool/cups... Apr 27 15:46:48 rhvolumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Apr 27 15:46:48 rhvolumio systemd[1]: Mounting /tmp... Apr 27 15:46:48 rhvolumio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Apr 27 15:46:48 rhvolumio systemd[1]: Mounting /var/log... Apr 27 15:46:48 rhvolumio systemd[1]: Started Helper to synchronize boot up for ifupdown. Apr 27 15:46:48 rhvolumio systemd[1]: Mounted /var/spool/cups. Apr 27 15:46:48 rhvolumio systemd[1]: Mounting /var/spool/cups/tmp... Apr 27 15:46:48 rhvolumio systemd[1]: Mounted /tmp. Apr 27 15:46:48 rhvolumio systemd[1]: Mounted /var/log. Apr 27 15:46:48 rhvolumio systemd[1]: Starting Flush Journal to Persistent Storage... Apr 27 15:46:48 rhvolumio systemd[1]: Mounted /var/spool/cups/tmp. Apr 27 15:46:48 rhvolumio systemd-udevd[245]: Network interface NamePolicy= disabled on kernel command line, ignoring. Apr 27 15:46:48 rhvolumio systemd-journald[221]: Runtime journal (/run/log/journal/c1d531165394c79721acb5a765d65e88) is 7.5M, max 30.0M, 22.5M free. Apr 27 15:46:48 rhvolumio systemd[1]: Started Flush Journal to Persistent Storage. Apr 27 15:46:48 rhvolumio systemd[1]: Started udev Kernel Device Manager. Apr 27 15:46:48 rhvolumio systemd[1]: Starting Show Plymouth Boot Screen... Apr 27 15:46:48 rhvolumio systemd[1]: Received SIGRTMIN+20 from PID 265 (plymouthd). Apr 27 15:46:48 rhvolumio systemd[1]: Started Show Plymouth Boot Screen. Apr 27 15:46:48 rhvolumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Apr 27 15:46:48 rhvolumio systemd[1]: Reached target Paths. Apr 27 15:46:48 rhvolumio systemd[1]: Reached target Local Encrypted Volumes. Apr 27 15:46:48 rhvolumio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Apr 27 15:46:48 rhvolumio systemd-udevd[261]: Using default interface naming scheme 'v240'. Apr 27 15:46:49 rhvolumio kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Apr 27 15:46:49 rhvolumio kernel: mc: Linux media interface: v0.10 Apr 27 15:46:49 rhvolumio kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Apr 27 15:46:49 rhvolumio kernel: [vc_sm_connected_init]: start Apr 27 15:46:49 rhvolumio kernel: [vc_sm_connected_init]: installed successfully Apr 27 15:46:49 rhvolumio systemd-udevd[261]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 27 15:46:49 rhvolumio kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Apr 27 15:46:49 rhvolumio kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Apr 27 15:46:49 rhvolumio kernel: i2c 1-0030: Fixed dependency cycle(s) with /soc/i2s@7e203000/port/endpoint Apr 27 15:46:49 rhvolumio kernel: videodev: Linux video capture interface: v2.00 Apr 27 15:46:49 rhvolumio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Apr 27 15:46:49 rhvolumio kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Apr 27 15:46:49 rhvolumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Apr 27 15:46:49 rhvolumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Apr 27 15:46:49 rhvolumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Apr 27 15:46:49 rhvolumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Apr 27 15:46:49 rhvolumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Apr 27 15:46:49 rhvolumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Apr 27 15:46:49 rhvolumio kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Apr 27 15:46:49 rhvolumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Apr 27 15:46:49 rhvolumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Apr 27 15:46:49 rhvolumio systemd-udevd[260]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 27 15:46:49 rhvolumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Apr 27 15:46:49 rhvolumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Apr 27 15:46:49 rhvolumio kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Apr 27 15:46:49 rhvolumio kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Apr 27 15:46:49 rhvolumio kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Apr 27 15:46:49 rhvolumio kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Apr 27 15:46:49 rhvolumio kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Apr 27 15:46:49 rhvolumio kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Apr 27 15:46:49 rhvolumio kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Apr 27 15:46:49 rhvolumio kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Apr 27 15:46:49 rhvolumio kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Apr 27 15:46:49 rhvolumio kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Apr 27 15:46:49 rhvolumio kernel: rpivid feb10000.codec: Device registered as /dev/video19 Apr 27 15:46:49 rhvolumio kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Apr 27 15:46:49 rhvolumio kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Apr 27 15:46:49 rhvolumio kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Apr 27 15:46:50 rhvolumio kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Apr 27 15:46:50 rhvolumio kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Apr 27 15:46:50 rhvolumio kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Apr 27 15:46:50 rhvolumio kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Apr 27 15:46:50 rhvolumio kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Apr 27 15:46:50 rhvolumio kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Apr 27 15:46:50 rhvolumio systemd[1]: Found device /dev/mmcblk0p1. Apr 27 15:46:50 rhvolumio kernel: Bluetooth: Core ver 2.22 Apr 27 15:46:50 rhvolumio kernel: NET: Registered PF_BLUETOOTH protocol family Apr 27 15:46:50 rhvolumio kernel: Bluetooth: HCI device and connection manager initialized Apr 27 15:46:50 rhvolumio kernel: Bluetooth: HCI socket layer initialized Apr 27 15:46:50 rhvolumio kernel: Bluetooth: L2CAP socket layer initialized Apr 27 15:46:50 rhvolumio kernel: Bluetooth: SCO socket layer initialized Apr 27 15:46:50 rhvolumio kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Apr 27 15:46:50 rhvolumio kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Apr 27 15:46:50 rhvolumio kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Apr 27 15:46:50 rhvolumio kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Apr 27 15:46:50 rhvolumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Apr 27 15:46:50 rhvolumio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Apr 27 15:46:50 rhvolumio kernel: Bluetooth: HCI UART driver ver 2.3 Apr 27 15:46:50 rhvolumio kernel: Bluetooth: HCI UART protocol H4 registered Apr 27 15:46:50 rhvolumio kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Apr 27 15:46:50 rhvolumio kernel: Bluetooth: HCI UART protocol Broadcom registered Apr 27 15:46:50 rhvolumio kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Apr 27 15:46:50 rhvolumio kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Apr 27 15:46:50 rhvolumio kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Apr 27 15:46:50 rhvolumio kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Apr 27 15:46:50 rhvolumio kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Apr 27 15:46:50 rhvolumio kernel: uart-pl011 fe201000.serial: no DMA platform data Apr 27 15:46:50 rhvolumio kernel: Bluetooth: hci0: BCM: chip id 107 Apr 27 15:46:50 rhvolumio kernel: Bluetooth: hci0: BCM: features 0x2f Apr 27 15:46:50 rhvolumio kernel: Bluetooth: hci0: BCM4345C0 Apr 27 15:46:50 rhvolumio kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Apr 27 15:46:50 rhvolumio kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Apr 27 15:46:50 rhvolumio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Apr 27 15:46:50 rhvolumio kernel: usbcore: registered new interface driver brcmfmac Apr 27 15:46:50 rhvolumio kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Apr 27 15:46:51 rhvolumio 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 Apr 27 15:46:51 rhvolumio systemd-udevd[272]: Using default interface naming scheme 'v240'. Apr 27 15:46:51 rhvolumio systemd-udevd[272]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 27 15:46:51 rhvolumio systemd-udevd[258]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 1' failed with exit code 17. Apr 27 15:46:51 rhvolumio kernel: Bluetooth: hci0: BCM: features 0x2f Apr 27 15:46:51 rhvolumio kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Apr 27 15:46:51 rhvolumio kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Apr 27 15:46:52 rhvolumio systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Apr 27 15:46:52 rhvolumio systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Apr 27 15:46:52 rhvolumio systemd[1]: Created slice system-bthelper.slice. Apr 27 15:46:52 rhvolumio systemd[1]: Condition check resulted in Huge Pages File System being skipped. Apr 27 15:46:52 rhvolumio systemd[1]: Condition check resulted in FUSE Control File System being skipped. Apr 27 15:46:52 rhvolumio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Apr 27 15:46:52 rhvolumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Apr 27 15:46:52 rhvolumio systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Apr 27 15:46:52 rhvolumio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Apr 27 15:46:52 rhvolumio systemd[1]: Starting File System Check on /dev/mmcblk0p1... Apr 27 15:46:52 rhvolumio systemd[1]: Starting Load/Save RF Kill Switch Status... Apr 27 15:46:52 rhvolumio systemd[1]: Started File System Check Daemon to report status. Apr 27 15:46:52 rhvolumio systemd[1]: Started Load/Save RF Kill Switch Status. Apr 27 15:46:52 rhvolumio systemd-fsck[425]: fsck.fat 4.1 (2017-01-24) Apr 27 15:46:52 rhvolumio systemd-fsck[425]: /dev/mmcblk0p1: 367 files, 33349/46774 clusters Apr 27 15:46:52 rhvolumio systemd[1]: Started File System Check on /dev/mmcblk0p1. Apr 27 15:46:52 rhvolumio systemd[1]: Mounting /boot... Apr 27 15:46:52 rhvolumio systemd[1]: Mounted /boot. Apr 27 15:46:52 rhvolumio systemd[1]: Reached target Local File Systems. Apr 27 15:46:52 rhvolumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Apr 27 15:46:52 rhvolumio systemd[1]: Started ifup for eth0. Apr 27 15:46:52 rhvolumio systemd[1]: Starting Create Volatile Files and Directories... Apr 27 15:46:52 rhvolumio systemd[1]: Started ifup for wlan0. Apr 27 15:46:52 rhvolumio systemd[1]: Starting Raise network interfaces... Apr 27 15:46:52 rhvolumio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Apr 27 15:46:52 rhvolumio systemd[1]: Starting Preprocess NFS configuration... Apr 27 15:46:52 rhvolumio systemd[1]: plymouth-read-write.service: Succeeded. Apr 27 15:46:52 rhvolumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Apr 27 15:46:52 rhvolumio systemd[1]: Received SIGRTMIN+20 from PID 265 (plymouthd). Apr 27 15:46:52 rhvolumio systemd[1]: nfs-config.service: Succeeded. Apr 27 15:46:52 rhvolumio systemd[1]: Started Preprocess NFS configuration. Apr 27 15:46:52 rhvolumio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Apr 27 15:46:52 rhvolumio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Apr 27 15:46:52 rhvolumio systemd[1]: Reached target NFS client services. Apr 27 15:46:52 rhvolumio systemd[1]: Started Create Volatile Files and Directories. Apr 27 15:46:52 rhvolumio systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Apr 27 15:46:52 rhvolumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Apr 27 15:46:52 rhvolumio systemd[1]: Starting RPC bind portmap service... Apr 27 15:46:52 rhvolumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Apr 27 15:46:52 rhvolumio systemd[1]: Started Update UTMP about System Boot/Shutdown. Apr 27 15:46:52 rhvolumio systemd[1]: Reached target System Initialization. Apr 27 15:46:52 rhvolumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Apr 27 15:46:52 rhvolumio systemd[1]: Listening on triggerhappy.socket. Apr 27 15:46:52 rhvolumio systemd[1]: Listening on D-Bus System Message Bus Socket. Apr 27 15:46:52 rhvolumio systemd[1]: Reached target Sockets. Apr 27 15:46:53 rhvolumio systemd[1]: Started Daily man-db regeneration. Apr 27 15:46:53 rhvolumio systemd[1]: Started Daily apt download activities. Apr 27 15:46:53 rhvolumio systemd[1]: Started Daily apt upgrade and clean activities. Apr 27 15:46:53 rhvolumio systemd[1]: Started Volumio Splash. Apr 27 15:46:53 rhvolumio systemd[1]: Reached target Basic System. Apr 27 15:46:53 rhvolumio systemd[499]: volumiosplash.service: Failed to execute command: No such file or directory Apr 27 15:46:53 rhvolumio systemd[499]: volumiosplash.service: Failed at step EXEC spawning /bin/volumiosplash.sh: No such file or directory Apr 27 15:46:53 rhvolumio systemd[1]: Started volumio-remote-updater.service. Apr 27 15:46:53 rhvolumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 27 15:46:53 rhvolumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Apr 27 15:46:53 rhvolumio systemd[1]: Starting Configure Bluetooth Modems connected by UART... Apr 27 15:46:53 rhvolumio systemd[1]: Started D-Bus System Message Bus. Apr 27 15:46:53 rhvolumio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Apr 27 15:46:53 rhvolumio systemd[1]: Starting dhcpcd on all interfaces... Apr 27 15:46:53 rhvolumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 27 15:46:53 rhvolumio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Apr 27 15:46:53 rhvolumio systemd[1]: Started Manage Sound Card State (restore and store). Apr 27 15:46:53 rhvolumio kernel: 8021q: 802.1Q VLAN Support v1.8 Apr 27 15:46:53 rhvolumio systemd[1]: Starting Save/Restore Sound Card State... Apr 27 15:46:53 rhvolumio alsactl[528]: alsactl 1.1.8 daemon started Apr 27 15:46:53 rhvolumio dhcpcd[518]: Not running dhcpcd because /etc/network/interfaces Apr 27 15:46:53 rhvolumio dhcpcd[518]: defines some interfaces that will use a Apr 27 15:46:53 rhvolumio dhcpcd[518]: DHCP client or static address Apr 27 15:46:53 rhvolumio systemd[1]: Starting triggerhappy global hotkey daemon... Apr 27 15:46:53 rhvolumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Apr 27 15:46:53 rhvolumio systemd[1]: Reached target Login Prompts. Apr 27 15:46:53 rhvolumio systemd[1]: Starting Wireless Services... Apr 27 15:46:53 rhvolumio systemd[1]: Starting WPA supplicant... Apr 27 15:46:53 rhvolumio systemd[1]: Started Daily Cleanup of Temporary Directories. Apr 27 15:46:53 rhvolumio systemd[1]: Reached target Timers. Apr 27 15:46:53 rhvolumio systemd[1]: Starting Login Service... Apr 27 15:46:53 rhvolumio avahi-daemon[504]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Apr 27 15:46:53 rhvolumio avahi-daemon[504]: Successfully dropped root privileges. Apr 27 15:46:53 rhvolumio systemd[1]: Started Volumio Iptables Module. Apr 27 15:46:53 rhvolumio avahi-daemon[504]: avahi-daemon 0.7 starting up. Apr 27 15:46:53 rhvolumio systemd[1]: Started Volumio Log Rotation Service. Apr 27 15:46:53 rhvolumio thd[539]: Unable to parse trigger line: Apr 27 15:46:53 rhvolumio thd[539]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Apr 27 15:46:53 rhvolumio thd[539]: Unable to parse trigger line: Apr 27 15:46:53 rhvolumio thd[539]: Found socket passed from systemd Apr 27 15:46:53 rhvolumio systemd[1]: Started RPC bind portmap service. Apr 27 15:46:53 rhvolumio systemd[1]: volumiosplash.service: Main process exited, code=exited, status=203/EXEC Apr 27 15:46:53 rhvolumio systemd[1]: volumiosplash.service: Failed with result 'exit-code'. Apr 27 15:46:53 rhvolumio systemd[1]: hciuart.service: Succeeded. Apr 27 15:46:53 rhvolumio systemd[1]: Started Configure Bluetooth Modems connected by UART. Apr 27 15:46:53 rhvolumio systemd[1]: Started triggerhappy global hotkey daemon. Apr 27 15:46:53 rhvolumio systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Apr 27 15:46:53 rhvolumio systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Apr 27 15:46:53 rhvolumio systemd[1]: Failed to start dhcpcd on all interfaces. Apr 27 15:46:53 rhvolumio systemd[1]: Started Save/Restore Sound Card State. Apr 27 15:46:53 rhvolumio systemd[1]: Reached target Sound Card. Apr 27 15:46:53 rhvolumio systemd[1]: Starting Raspberry Pi bluetooth helper... Apr 27 15:46:53 rhvolumio systemd[1]: Reached target RPC Port Mapper. Apr 27 15:46:53 rhvolumio sh[435]: wlan0=wlan0 Apr 27 15:46:53 rhvolumio systemd[1]: Reached target Remote File Systems (Pre). Apr 27 15:46:53 rhvolumio systemd[1]: Reached target Remote File Systems. Apr 27 15:46:53 rhvolumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Apr 27 15:46:53 rhvolumio systemd-logind[546]: New seat seat0. Apr 27 15:46:53 rhvolumio bthelper[555]: Raspberry Pi BDADDR already set Apr 27 15:46:54 rhvolumio sh[433]: eth0: waiting for carrier Apr 27 15:46:54 rhvolumio dhcpcd[484]: eth0: waiting for carrier Apr 27 15:46:54 rhvolumio kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Apr 27 15:46:54 rhvolumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Apr 27 15:46:54 rhvolumio systemd[1]: Started Raspberry Pi bluetooth helper. Apr 27 15:46:54 rhvolumio avahi-daemon[504]: Successfully called chroot(). Apr 27 15:46:54 rhvolumio avahi-daemon[504]: Successfully dropped remaining capabilities. Apr 27 15:46:54 rhvolumio wpa_supplicant[545]: Successfully initialized wpa_supplicant Apr 27 15:46:54 rhvolumio avahi-daemon[504]: Loading service file /services/volumio.service. Apr 27 15:46:54 rhvolumio avahi-daemon[504]: Network interface enumeration completed. Apr 27 15:46:54 rhvolumio avahi-daemon[504]: Server startup complete. Host name is rhvolumio.local. Local service cookie is 2303322580. Apr 27 15:46:54 rhvolumio avahi-daemon[504]: Service "rhVolumio" (/services/volumio.service) successfully established. Apr 27 15:46:54 rhvolumio systemd[1]: Started Raise network interfaces. Apr 27 15:46:54 rhvolumio systemd[1]: Started Login Service. Apr 27 15:46:54 rhvolumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Apr 27 15:46:54 rhvolumio systemd[1]: Started WPA supplicant. Apr 27 15:46:54 rhvolumio systemd[1]: Reached target Network. Apr 27 15:46:54 rhvolumio systemd[1]: Starting OpenBSD Secure Shell server... Apr 27 15:46:54 rhvolumio volumio-remote-updater[500]: Error: No active session Apr 27 15:46:54 rhvolumio systemd[1]: Starting Network Time Service... Apr 27 15:46:54 rhvolumio systemd[1]: Reached target Network is Online. Apr 27 15:46:54 rhvolumio systemd[1]: Starting /etc/rc.local Compatibility... Apr 27 15:46:54 rhvolumio volumio-remote-updater[500]: [2024-04-27 15:46:54] [info] asio async_connect error: system:111 (Connection refused) Apr 27 15:46:54 rhvolumio volumio-remote-updater[500]: [2024-04-27 15:46:54] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 27 15:46:54 rhvolumio volumio-remote-updater[500]: [2024-04-27 15:46:54] [error] handle_connect error: Underlying Transport Error Apr 27 15:46:54 rhvolumio systemd[1]: Starting LSB: Brings up/down network automatically... Apr 27 15:46:54 rhvolumio systemd[1]: Starting Samba NMB Daemon... Apr 27 15:46:54 rhvolumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Apr 27 15:46:54 rhvolumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 27 15:46:54 rhvolumio systemd[1]: Starting Permit User Sessions... Apr 27 15:46:54 rhvolumio systemd[1]: Starting Bluetooth service... Apr 27 15:46:54 rhvolumio volumio[522]: Could not open config: /tmp/upmpdcli.conf Apr 27 15:46:54 rhvolumio systemd[1]: Started /etc/rc.local Compatibility. Apr 27 15:46:54 rhvolumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 27 15:46:54 rhvolumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 27 15:46:54 rhvolumio systemd[1]: Started Permit User Sessions. Apr 27 15:46:54 rhvolumio systemd[1]: Starting Hold until boot process finishes up... Apr 27 15:46:54 rhvolumio systemd[1]: Starting Terminate Plymouth Boot Screen... Apr 27 15:46:54 rhvolumio systemd[1]: plymouth-start.service: Succeeded. Apr 27 15:46:54 rhvolumio systemd[1]: plymouth-quit-wait.service: Succeeded. Apr 27 15:46:54 rhvolumio systemd[1]: Started Hold until boot process finishes up. Apr 27 15:46:54 rhvolumio systemd[1]: plymouth-quit.service: Succeeded. Apr 27 15:46:54 rhvolumio systemd[1]: Started Terminate Plymouth Boot Screen. Apr 27 15:46:54 rhvolumio systemd[1]: Received SIGRTMIN+21 from PID 265 (n/a). Apr 27 15:46:54 rhvolumio systemd[1]: Received SIGRTMIN+21 from PID 265 (n/a). Apr 27 15:46:55 rhvolumio loadcpufreq[567]: Loading cpufreq kernel modules...done (none). Apr 27 15:46:55 rhvolumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Apr 27 15:46:55 rhvolumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Apr 27 15:46:55 rhvolumio ntpd[626]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 27 15:46:55 rhvolumio ntpd[626]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Apr 27 15:46:55 rhvolumio systemd[1]: Started Network Time Service. Apr 27 15:46:55 rhvolumio systemd[1]: iptables.service: Succeeded. Apr 27 15:46:55 rhvolumio ifplugd(eth0)[683]: ifplugd 0.28 initializing. Apr 27 15:46:55 rhvolumio ifplugd(eth0)[683]: Using interface eth0/D8:3A:DD:23:E2:A2 with driver (version: 6.1.69-v7l+) Apr 27 15:46:55 rhvolumio ifplugd(eth0)[683]: Using detection mode: SIOCETHTOOL Apr 27 15:46:55 rhvolumio ifplugd(eth0)[683]: Initialization complete, link beat not detected. Apr 27 15:46:55 rhvolumio ntpd[677]: proto: precision = 2.093 usec (-19) Apr 27 15:46:55 rhvolumio bluetoothd[638]: Bluetooth daemon 5.50 Apr 27 15:46:55 rhvolumio sshd[678]: Server listening on 0.0.0.0 port 22. Apr 27 15:46:55 rhvolumio sshd[678]: Server listening on :: port 22. Apr 27 15:46:55 rhvolumio ntpd[677]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 27 15:46:55 rhvolumio ntpd[677]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 27 15:46:55 rhvolumio ntpd[677]: Listen and drop on 0 v6wildcard [::]:123 Apr 27 15:46:55 rhvolumio ntpd[677]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 27 15:46:55 rhvolumio ntpd[677]: Listen normally on 2 lo 127.0.0.1:123 Apr 27 15:46:55 rhvolumio ntpd[677]: Listening on routing socket on fd #19 for interface updates Apr 27 15:46:55 rhvolumio ntpd[677]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 27 15:46:55 rhvolumio ntpd[677]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 27 15:46:55 rhvolumio ifplugd[630]: Network Interface Plugging Daemon...start eth0...done. Apr 27 15:46:55 rhvolumio bluetoothd[638]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Apr 27 15:46:55 rhvolumio systemd[1]: Started OpenBSD Secure Shell server. Apr 27 15:46:55 rhvolumio systemd[1]: Started LSB: Brings up/down network automatically. Apr 27 15:46:55 rhvolumio systemd[1]: Started Bluetooth service. Apr 27 15:46:55 rhvolumio bluetoothd[638]: Starting SDP server Apr 27 15:46:55 rhvolumio bluetoothd[638]: Excluding (cli) sap Apr 27 15:46:55 rhvolumio systemd[1]: Reached target Bluetooth. Apr 27 15:46:55 rhvolumio cpufrequtils[670]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Apr 27 15:46:55 rhvolumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Apr 27 15:46:55 rhvolumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Apr 27 15:46:55 rhvolumio kernel: Bluetooth: BNEP filters: protocol multicast Apr 27 15:46:55 rhvolumio kernel: Bluetooth: BNEP socket layer initialized Apr 27 15:46:55 rhvolumio bluetoothd[638]: Bluetooth management interface 1.22 initialized Apr 27 15:46:55 rhvolumio dbus-daemon[509]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=638 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Apr 27 15:46:55 rhvolumio kernel: Bluetooth: MGMT ver 1.22 Apr 27 15:46:55 rhvolumio bluetoothd[638]: Failed to set privacy: Rejected (0x0b) Apr 27 15:46:55 rhvolumio systemd[1]: Starting Hostname Service... Apr 27 15:46:55 rhvolumio dbus-daemon[509]: [system] Successfully activated service 'org.freedesktop.hostname1' Apr 27 15:46:55 rhvolumio systemd[1]: Started Hostname Service. Apr 27 15:46:55 rhvolumio nmbd[643]: [2024/04/27 15:46:55.996308, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Apr 27 15:46:55 rhvolumio nmbd[643]: started asyncdns process 704 Apr 27 15:46:55 rhvolumio nmbd[643]: [2024/04/27 15:46:55.998422, 0] ../lib/util/become_daemon.c:149(daemon_status) Apr 27 15:46:55 rhvolumio nmbd[643]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Apr 27 15:46:55 rhvolumio nmbd[643]: [2024/04/27 15:46:55.998561, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Apr 27 15:46:55 rhvolumio nmbd[643]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Apr 27 15:46:56 rhvolumio haveged[487]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Apr 27 15:46:56 rhvolumio haveged[487]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Apr 27 15:46:56 rhvolumio haveged[487]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99949 Apr 27 15:46:56 rhvolumio haveged[487]: haveged: fills: 0, generated: 0 Apr 27 15:46:56 rhvolumio wireless.js[544]: Cleaning previous... Apr 27 15:46:56 rhvolumio ntpd[677]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 27 15:46:56 rhvolumio sudo[712]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 27 15:46:56 rhvolumio sudo[712]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:46:56 rhvolumio sudo[712]: pam_unix(sudo:session): session closed for user root Apr 27 15:46:56 rhvolumio sudo[714]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 27 15:46:56 rhvolumio sudo[714]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:46:56 rhvolumio sudo[714]: pam_unix(sudo:session): session closed for user root Apr 27 15:46:57 rhvolumio wireless.js[544]: Stopped aP Apr 27 15:46:57 rhvolumio sudo[721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 27 15:46:57 rhvolumio sudo[721]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:46:57 rhvolumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 27 15:46:57 rhvolumio sudo[721]: pam_unix(sudo:session): session closed for user root Apr 27 15:46:57 rhvolumio sudo[723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Apr 27 15:46:57 rhvolumio sudo[723]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:46:57 rhvolumio sudo[723]: pam_unix(sudo:session): session closed for user root Apr 27 15:46:57 rhvolumio sudo[730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 27 15:46:57 rhvolumio sudo[730]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:46:57 rhvolumio sudo[730]: pam_unix(sudo:session): session closed for user root Apr 27 15:46:57 rhvolumio sudo[732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Apr 27 15:46:57 rhvolumio sudo[732]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:46:57 rhvolumio ntpd[677]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 27 15:46:57 rhvolumio systemd[1]: systemd-rfkill.service: Succeeded. Apr 27 15:46:58 rhvolumio ntpd[677]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 27 15:46:59 rhvolumio bthelper[555]: Changing power off succeeded Apr 27 15:46:59 rhvolumio bthelper[555]: [CHG] Controller D8:3A:DD:23:E2:A4 Class: 0x0000041c Apr 27 15:46:59 rhvolumio bthelper[555]: Changing power on succeeded Apr 27 15:46:59 rhvolumio ntpd[677]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 27 15:46:59 rhvolumio volumio-remote-updater[500]: [2024-04-27 15:46:59] [info] asio async_connect error: system:111 (Connection refused) Apr 27 15:46:59 rhvolumio volumio-remote-updater[500]: [2024-04-27 15:46:59] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 27 15:46:59 rhvolumio volumio-remote-updater[500]: [2024-04-27 15:46:59] [error] handle_connect error: Underlying Transport Error Apr 27 15:46:59 rhvolumio sudo[732]: pam_unix(sudo:session): session closed for user root Apr 27 15:46:59 rhvolumio wireless.js[544]: SETTING APPROPRIATE REG DOMAIN: US Apr 27 15:46:59 rhvolumio sudo[744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 27 15:46:59 rhvolumio sudo[744]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:46:59 rhvolumio sudo[744]: pam_unix(sudo:session): session closed for user root Apr 27 15:46:59 rhvolumio sudo[746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Apr 27 15:46:59 rhvolumio sudo[746]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:46:59 rhvolumio sudo[746]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:00 rhvolumio wireless.js[544]: SUCCESSFULLY SET NEW REGDOMAIN: US Apr 27 15:47:00 rhvolumio wireless.js[544]: Start wireless flow Apr 27 15:47:00 rhvolumio wireless.js[544]: Stopped hotspot (if there).. Apr 27 15:47:00 rhvolumio sudo[751]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 27 15:47:00 rhvolumio sudo[751]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:00 rhvolumio sudo[751]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:00 rhvolumio sudo[753]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 27 15:47:00 rhvolumio sudo[753]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:00 rhvolumio sudo[753]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:00 rhvolumio wireless.js[544]: DHCP IP Apr 27 15:47:00 rhvolumio wireless.js[544]: Start ap Apr 27 15:47:00 rhvolumio wpa_supplicant[755]: Successfully initialized wpa_supplicant Apr 27 15:47:00 rhvolumio sudo[756]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Apr 27 15:47:00 rhvolumio sudo[756]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:00 rhvolumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 27 15:47:00 rhvolumio dhcpcd[757]: dev: loaded udev Apr 27 15:47:00 rhvolumio dhcpcd-run-hooks[775]: wlan0: starting wpa_supplicant Apr 27 15:47:00 rhvolumio dhcpcd-run-hooks[780]: wlan0: failed to start wpa_supplicant Apr 27 15:47:00 rhvolumio dhcpcd-run-hooks[781]: 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 Apr 27 15:47:00 rhvolumio dhcpcd[757]: wlan0: connected to Access Point `' Apr 27 15:47:01 rhvolumio dhcpcd[757]: no interfaces have a carrier Apr 27 15:47:01 rhvolumio dhcpcd[757]: forked to background, child pid 791 Apr 27 15:47:01 rhvolumio dhcpcd[791]: eth0: waiting for carrier Apr 27 15:47:01 rhvolumio dhcpcd[791]: wlan0: waiting for carrier Apr 27 15:47:01 rhvolumio sudo[756]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:01 rhvolumio wireless.js[544]: trying... Apr 27 15:47:01 rhvolumio sudo[793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 27 15:47:01 rhvolumio sudo[793]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:01 rhvolumio sudo[793]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:02 rhvolumio wireless.js[544]: trying... Apr 27 15:47:02 rhvolumio sudo[796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 27 15:47:02 rhvolumio sudo[796]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:02 rhvolumio sudo[796]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:03 rhvolumio wpa_supplicant[779]: wlan0: Trying to associate with SSID 'Biei' Apr 27 15:47:03 rhvolumio wireless.js[544]: trying... Apr 27 15:47:03 rhvolumio sudo[799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 27 15:47:03 rhvolumio sudo[799]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:03 rhvolumio sudo[799]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:04 rhvolumio wireless.js[544]: trying... Apr 27 15:47:04 rhvolumio sudo[802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 27 15:47:04 rhvolumio sudo[802]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:04 rhvolumio sudo[802]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:05 rhvolumio wireless.js[544]: trying... Apr 27 15:47:05 rhvolumio sudo[805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 27 15:47:05 rhvolumio sudo[805]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:05 rhvolumio sudo[805]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:06 rhvolumio wireless.js[544]: trying... Apr 27 15:47:06 rhvolumio sudo[808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 27 15:47:06 rhvolumio sudo[808]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:06 rhvolumio sudo[808]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:07 rhvolumio volumio-remote-updater[500]: [2024-04-27 15:47:07] [info] asio async_connect error: system:111 (Connection refused) Apr 27 15:47:07 rhvolumio volumio-remote-updater[500]: [2024-04-27 15:47:07] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 27 15:47:07 rhvolumio volumio-remote-updater[500]: [2024-04-27 15:47:07] [error] handle_connect error: Underlying Transport Error Apr 27 15:47:07 rhvolumio dhcpcd[791]: wlan0: carrier acquired Apr 27 15:47:07 rhvolumio dhcpcd[791]: wlan0: connected to Access Point `Biei' Apr 27 15:47:07 rhvolumio wpa_supplicant[779]: wlan0: Associated with 34:98:b5:98:d1:16 Apr 27 15:47:07 rhvolumio wpa_supplicant[779]: wlan0: CTRL-EVENT-CONNECTED - Connection to 34:98:b5:98:d1:16 completed [id=0 id_str=] Apr 27 15:47:07 rhvolumio wpa_supplicant[779]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 27 15:47:07 rhvolumio wpa_supplicant[779]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Apr 27 15:47:07 rhvolumio dhcpcd[791]: DUID 00:01:00:01:2d:69:1d:57:d8:3a:dd:23:e2:a3 Apr 27 15:47:07 rhvolumio dhcpcd[791]: wlan0: IAID dd:23:e2:a3 Apr 27 15:47:07 rhvolumio dhcpcd[791]: wlan0: adding address fe80::fb19:f68b:adfa:f7ef Apr 27 15:47:07 rhvolumio dhcpcd[791]: ipv6_addaddr1: Permission denied Apr 27 15:47:07 rhvolumio dhcpcd[791]: wlan0: carrier lost Apr 27 15:47:07 rhvolumio dhcpcd[791]: wlan0: carrier acquired Apr 27 15:47:07 rhvolumio dhcpcd[791]: wlan0: IAID dd:23:e2:a3 Apr 27 15:47:07 rhvolumio wireless.js[544]: trying... Apr 27 15:47:07 rhvolumio sudo[823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 27 15:47:07 rhvolumio sudo[823]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:07 rhvolumio sudo[823]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:07 rhvolumio dhcpcd[791]: wlan0: rebinding lease of 10.0.0.58 Apr 27 15:47:08 rhvolumio dhcpcd[791]: wlan0: soliciting an IPv6 router Apr 27 15:47:08 rhvolumio wireless.js[544]: trying... Apr 27 15:47:08 rhvolumio sudo[826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 27 15:47:08 rhvolumio sudo[826]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:08 rhvolumio sudo[826]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:09 rhvolumio dhcpcd[791]: wlan0: probing address 10.0.0.58/24 Apr 27 15:47:09 rhvolumio wireless.js[544]: trying... Apr 27 15:47:09 rhvolumio sudo[829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 27 15:47:09 rhvolumio sudo[829]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:09 rhvolumio sudo[829]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:10 rhvolumio wireless.js[544]: trying... Apr 27 15:47:10 rhvolumio sudo[832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 27 15:47:10 rhvolumio sudo[832]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:10 rhvolumio sudo[832]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:11 rhvolumio wireless.js[544]: trying... Apr 27 15:47:11 rhvolumio sudo[835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 27 15:47:11 rhvolumio sudo[835]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:11 rhvolumio sudo[835]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:12 rhvolumio wireless.js[544]: trying... Apr 27 15:47:12 rhvolumio sudo[838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 27 15:47:12 rhvolumio sudo[838]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:12 rhvolumio sudo[838]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:13 rhvolumio wireless.js[544]: trying... Apr 27 15:47:13 rhvolumio sudo[841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 27 15:47:13 rhvolumio sudo[841]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:13 rhvolumio sudo[841]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:14 rhvolumio dhcpcd[791]: wlan0: leased 10.0.0.58 for 86400 seconds Apr 27 15:47:14 rhvolumio avahi-daemon[504]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.0.0.58. Apr 27 15:47:14 rhvolumio avahi-daemon[504]: New relevant interface wlan0.IPv4 for mDNS. Apr 27 15:47:14 rhvolumio dhcpcd[791]: wlan0: adding route to 10.0.0.0/24 Apr 27 15:47:14 rhvolumio avahi-daemon[504]: Registering new address record for 10.0.0.58 on wlan0.IPv4. Apr 27 15:47:14 rhvolumio dhcpcd[791]: wlan0: adding default route via 10.0.0.1 Apr 27 15:47:14 rhvolumio wireless.js[544]: trying... Apr 27 15:47:14 rhvolumio sudo[864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 27 15:47:14 rhvolumio sudo[864]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:14 rhvolumio sudo[864]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:14 rhvolumio wireless.js[544]: Connected to: ----Biei Apr 27 15:47:14 rhvolumio wireless.js[544]: ---- Apr 27 15:47:14 rhvolumio sudo[867]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 27 15:47:14 rhvolumio sudo[867]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:14 rhvolumio sudo[867]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:14 rhvolumio wireless.js[544]: ... joined AP, wlan0 IPv4 is 10.0.0.58, ipV6 is undefined Apr 27 15:47:14 rhvolumio wireless.js[544]: It's done! AP Apr 27 15:47:14 rhvolumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 27 15:47:14 rhvolumio systemd[1]: Started Wireless Services. Apr 27 15:47:14 rhvolumio systemd[1]: Started Volumio Backend Module. Apr 27 15:47:14 rhvolumio systemd[1]: Started Volumio Cpu Tweaker. Apr 27 15:47:14 rhvolumio volumio-cpu-tweak[873]: Setting RT Priority for mpd Apr 27 15:47:14 rhvolumio volumio-cpu-tweak[873]: pid 35's current scheduling policy: SCHED_OTHER Apr 27 15:47:14 rhvolumio volumio-cpu-tweak[873]: pid 35's current scheduling priority: 0 Apr 27 15:47:14 rhvolumio volumio-cpu-tweak[873]: Setting MPD Affinity Apr 27 15:47:14 rhvolumio volumio-cpu-tweak[873]: pid 3's current affinity mask: f Apr 27 15:47:14 rhvolumio volumio-cpu-tweak[873]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Apr 27 15:47:14 rhvolumio volumio-cpu-tweak[873]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Apr 27 15:47:14 rhvolumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Apr 27 15:47:16 rhvolumio systemd[1]: Started Samba NMB Daemon. Apr 27 15:47:16 rhvolumio nmbd[643]: [2024/04/27 15:47:16.006082, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 27 15:47:16 rhvolumio nmbd[643]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 27 15:47:16 rhvolumio systemd[1]: Starting Samba Winbind Daemon... Apr 27 15:47:16 rhvolumio ntpd[677]: Listen normally on 3 wlan0 10.0.0.58:123 Apr 27 15:47:16 rhvolumio ntpd[677]: new interface(s) found: waking up resolver Apr 27 15:47:16 rhvolumio winbindd[889]: [2024/04/27 15:47:16.310786, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 27 15:47:16 rhvolumio winbindd[889]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 27 15:47:16 rhvolumio winbindd[889]: [2024/04/27 15:47:16.330053, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 27 15:47:16 rhvolumio winbindd[889]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 27 15:47:16 rhvolumio systemd[1]: Started Samba Winbind Daemon. Apr 27 15:47:16 rhvolumio systemd[1]: Starting Samba SMB Daemon... Apr 27 15:47:16 rhvolumio volumio[872]: info: ------------------------------------------- Apr 27 15:47:16 rhvolumio volumio[872]: info: ----- Volumio3 ---- Apr 27 15:47:16 rhvolumio volumio[872]: info: ------------------------------------------- Apr 27 15:47:16 rhvolumio volumio[872]: info: ----- System startup ---- Apr 27 15:47:16 rhvolumio volumio[872]: info: ------------------------------------------- Apr 27 15:47:16 rhvolumio smbd[894]: [2024/04/27 15:47:16.758177, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 27 15:47:16 rhvolumio systemd[1]: Started Samba SMB Daemon. Apr 27 15:47:16 rhvolumio systemd[1]: Reached target Multi-User System. Apr 27 15:47:16 rhvolumio smbd[894]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 27 15:47:16 rhvolumio systemd[1]: Reached target Graphical Interface. Apr 27 15:47:16 rhvolumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 27 15:47:16 rhvolumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 27 15:47:16 rhvolumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 27 15:47:16 rhvolumio systemd[1]: Startup finished in 9.494s (kernel) + 30.576s (userspace) = 40.070s. Apr 27 15:47:17 rhvolumio volumio-remote-updater[500]: [2024-04-27 15:47:17] [connect] Successful connection Apr 27 15:47:17 rhvolumio volumio[872]: info: MYVOLUMIO Environment detected Apr 27 15:47:17 rhvolumio volumio[872]: info: Plugin folders cleanup Apr 27 15:47:17 rhvolumio volumio[872]: info: Scanning into folder /volumio/app/plugins/ Apr 27 15:47:17 rhvolumio volumio[872]: info: Scanning category audio_interface Apr 27 15:47:17 rhvolumio volumio[872]: info: Scanning category miscellanea Apr 27 15:47:17 rhvolumio volumio[872]: info: Scanning category music_service Apr 27 15:47:17 rhvolumio volumio[872]: info: Scanning category plugins.json Apr 27 15:47:17 rhvolumio volumio[872]: info: Scanning category system_controller Apr 27 15:47:17 rhvolumio volumio[872]: info: Scanning category user_interface Apr 27 15:47:17 rhvolumio volumio[872]: info: Scanning into folder /data/plugins/ Apr 27 15:47:17 rhvolumio volumio[872]: info: Scanning category audio_interface Apr 27 15:47:17 rhvolumio volumio[872]: info: Scanning category user_interface Apr 27 15:47:17 rhvolumio volumio[872]: info: Plugin folders cleanup completed Apr 27 15:47:17 rhvolumio volumio[872]: info: ------------------------------------------- Apr 27 15:47:17 rhvolumio volumio[872]: info: ----- Core plugins startup ---- Apr 27 15:47:17 rhvolumio volumio[872]: info: ------------------------------------------- Apr 27 15:47:17 rhvolumio volumio[872]: info: Loading plugins from folder /volumio/app/plugins/ Apr 27 15:47:17 rhvolumio volumio[872]: info: Adding plugin upnp to MyMusic Plugins Apr 27 15:47:17 rhvolumio volumio[872]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 27 15:47:17 rhvolumio volumio[872]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 27 15:47:17 rhvolumio volumio[872]: info: Loading plugins from folder /data/plugins/ Apr 27 15:47:17 rhvolumio volumio[872]: info: Loading plugin "system"... Apr 27 15:47:17 rhvolumio volumio[872]: info: Loading plugin "appearance"... Apr 27 15:47:18 rhvolumio volumio[872]: info: Loading plugin "network"... Apr 27 15:47:18 rhvolumio volumio[872]: info: Refreshing Cached IP Addresses Apr 27 15:47:18 rhvolumio sudo[917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 27 15:47:18 rhvolumio sudo[917]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:18 rhvolumio sudo[917]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:18 rhvolumio volumio[872]: info: Loading plugin "services"... Apr 27 15:47:18 rhvolumio sudo[919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 27 15:47:18 rhvolumio sudo[919]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:18 rhvolumio volumio[872]: info: Loading plugin "alsa_controller"... Apr 27 15:47:18 rhvolumio sudo[919]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:18 rhvolumio sudo[922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 27 15:47:18 rhvolumio sudo[922]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:18 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 27 15:47:18 rhvolumio volumio[872]: info: Loading plugin "wizard"... Apr 27 15:47:18 rhvolumio volumio[872]: info: Loading plugin "networkfs"... Apr 27 15:47:18 rhvolumio volumio[872]: info: Starting Udev Watcher for removable devices Apr 27 15:47:18 rhvolumio sudo[930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=ronwho,password=gaPPkh817711,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.0.0.2/music/Tidal /mnt/NAS/NAS Apr 27 15:47:18 rhvolumio sudo[930]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:18 rhvolumio volumio[872]: info: Ignoring mount for partition: boot Apr 27 15:47:18 rhvolumio volumio[872]: info: Ignoring mount for partition: volumio Apr 27 15:47:18 rhvolumio volumio[872]: info: Ignoring mount for partition: volumio_data Apr 27 15:47:18 rhvolumio volumio[872]: info: Mounting Device F99E-4F96 Apr 27 15:47:18 rhvolumio sudo[941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/F99E-4F96 -o noatime Apr 27 15:47:18 rhvolumio sudo[941]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:18 rhvolumio mount.exfat[943]: volume was not unmounted cleanly Apr 27 15:47:18 rhvolumio kernel: Key type cifs.spnego registered Apr 27 15:47:18 rhvolumio kernel: Key type cifs.idmap registered Apr 27 15:47:18 rhvolumio 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. Apr 27 15:47:18 rhvolumio kernel: CIFS: Attempting to mount \\10.0.0.2\music Apr 27 15:47:18 rhvolumio kernel: fuse: init (API version 7.37) Apr 27 15:47:18 rhvolumio sudo[941]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:18 rhvolumio volumio[872]: WARN: volume was not unmounted cleanly. Apr 27 15:47:18 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 27 15:47:18 rhvolumio volumio[872]: info: Loading plugin "volumio_command_line_client"... Apr 27 15:47:18 rhvolumio volumio[872]: info: Loading plugin "upnp"... Apr 27 15:47:19 rhvolumio volumio[872]: info: [1714247239002] Starting Upmpd Daemon Apr 27 15:47:19 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 27 15:47:19 rhvolumio volumio[872]: info: Loading plugin "my_music"... Apr 27 15:47:19 rhvolumio volumio[872]: info: Loading plugin "mpd"... Apr 27 15:47:19 rhvolumio systemd[1]: Mounting FUSE Control File System... Apr 27 15:47:19 rhvolumio systemd[1]: Mounted FUSE Control File System. Apr 27 15:47:19 rhvolumio volumio[872]: info: Loading plugin "upnp_browser"... Apr 27 15:47:19 rhvolumio volumio[872]: info: Loading plugin "alarm-clock"... Apr 27 15:47:19 rhvolumio volumio[872]: info: Loading plugin "airplay_emulation"... Apr 27 15:47:19 rhvolumio volumio[872]: info: Starting Shairport Sync Apr 27 15:47:19 rhvolumio volumio[872]: info: Loading plugin "last_100"... Apr 27 15:47:19 rhvolumio volumio[872]: info: Loading plugin "webradio"... Apr 27 15:47:19 rhvolumio volumio[872]: info: Loading plugin "i2s_dacs"... Apr 27 15:47:19 rhvolumio volumio[872]: info: Loading plugin "volumiodiscovery"... Apr 27 15:47:19 rhvolumio volumio[872]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 27 15:47:19 rhvolumio node[872]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 27 15:47:19 rhvolumio volumio[872]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 27 15:47:19 rhvolumio node[872]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 27 15:47:19 rhvolumio volumio[872]: *** WARNING *** For more information see Apr 27 15:47:19 rhvolumio node[872]: *** WARNING *** For more information see Apr 27 15:47:19 rhvolumio volumio[872]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 27 15:47:19 rhvolumio node[872]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 27 15:47:19 rhvolumio volumio[872]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 27 15:47:19 rhvolumio node[872]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 27 15:47:19 rhvolumio volumio[872]: *** WARNING *** For more information see Apr 27 15:47:19 rhvolumio node[872]: *** WARNING *** For more information see Apr 27 15:47:19 rhvolumio volumio[872]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 27 15:47:19 rhvolumio volumio[872]: info: Discovery: Started advertising with name: rhVolumio Apr 27 15:47:19 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 27 15:47:19 rhvolumio volumio[872]: info: Loading plugin "now_playing"... Apr 27 15:47:19 rhvolumio sudo[930]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:19 rhvolumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE Apr 27 15:47:19 rhvolumio kernel: CIFS: VFS: \\10.0.0.2 Send error in SessSetup = -13 Apr 27 15:47:19 rhvolumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Apr 27 15:47:21 rhvolumio volumio[872]: info: Loading plugin "outputs"... Apr 27 15:47:21 rhvolumio volumio[872]: info: Loading plugin "albumart"... Apr 27 15:47:21 rhvolumio volumio[872]: info: Plugin example_plugin is not enabled Apr 27 15:47:21 rhvolumio volumio[872]: info: Loading plugin "inputs"... Apr 27 15:47:21 rhvolumio volumio[872]: info: Loading plugin "updater_comm"... Apr 27 15:47:21 rhvolumio volumio[872]: info: Plugin mpdemulation is not enabled Apr 27 15:47:21 rhvolumio volumio[872]: info: Loading plugin "rest_api"... Apr 27 15:47:21 rhvolumio volumio[872]: info: Loading plugin "websocket"... Apr 27 15:47:21 rhvolumio volumio[872]: info: Loading plugin "fusiondsp"... Apr 27 15:47:21 rhvolumio volumio[872]: Forking 3 albumart workers Apr 27 15:47:21 rhvolumio volumio[872]: info: Applying required configuration parameters for plugin fusiondsp Apr 27 15:47:21 rhvolumio volumio[872]: info: Loading plugin "lastfm"... Apr 27 15:47:22 rhvolumio volumio[872]: Starting albumart workers Apr 27 15:47:22 rhvolumio volumio[872]: Starting albumart workers Apr 27 15:47:22 rhvolumio volumio[872]: Starting albumart workers Apr 27 15:47:22 rhvolumio systemd[1]: systemd-fsckd.service: Succeeded. Apr 27 15:47:22 rhvolumio sudo[922]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:22 rhvolumio volumio[872]: info: Loading plugin "touch_display"... Apr 27 15:47:22 rhvolumio volumio[872]: info: Applying required configuration parameters for plugin touch_display Apr 27 15:47:23 rhvolumio volumio[872]: info: Loading i18n strings for locale en Apr 27 15:47:23 rhvolumio volumio[872]: Updating browse sources language Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreCommandRouter::initPlayerControls Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 15:47:23 rhvolumio volumio[872]: Express server listening on port 3000 Apr 27 15:47:23 rhvolumio volumio[872]: [Metrics] WebUI: 7s 391.86ms Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreStateMachine::resetVolumioState Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreStateMachine::getcurrentVolume Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreCommandRouter::volumioRetrievevolume Apr 27 15:47:23 rhvolumio volumio[872]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Apr 27 15:47:23 rhvolumio volumio[872]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Apr 27 15:47:23 rhvolumio volumio[872]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 15:47:23 rhvolumio volumio[872]: info: Reloading queue from file Apr 27 15:47:23 rhvolumio volumio[872]: info: VolumeController:: Volume=93 Mute =false Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreStateMachine::pushState Apr 27 15:47:23 rhvolumio volumio[872]: info: CorePlayQueue::getTrack 0 Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreCommandRouter::volumioPushState Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreStateMachine::updateTrackBlock Apr 27 15:47:23 rhvolumio volumio[872]: info: CorePlayQueue::getTrackBlock Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreCommandRouter::volumioRetrievevolume Apr 27 15:47:23 rhvolumio volumio[872]: info: Setting Device type: Raspberry PI Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreStateMachine::setRepeat null single undefined Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreStateMachine::pushState Apr 27 15:47:23 rhvolumio volumio[872]: info: CorePlayQueue::getTrack 0 Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreCommandRouter::volumioPushState Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreStateMachine::setRandom null Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreStateMachine::pushState Apr 27 15:47:23 rhvolumio volumio[872]: info: CorePlayQueue::getTrack 0 Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreCommandRouter::volumioPushState Apr 27 15:47:23 rhvolumio volumio[872]: info: VolumeController:: Volume=93 Mute =false Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreStateMachine::pushState Apr 27 15:47:23 rhvolumio volumio[872]: info: CorePlayQueue::getTrack 0 Apr 27 15:47:23 rhvolumio volumio[872]: info: CoreCommandRouter::volumioPushState Apr 27 15:47:23 rhvolumio volumio[872]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Apr 27 15:47:24 rhvolumio sh[433]: timed out Apr 27 15:47:24 rhvolumio dhcpcd[484]: timed out Apr 27 15:47:24 rhvolumio sh[433]: dhcpcd exited Apr 27 15:47:24 rhvolumio dhcpcd[484]: dhcpcd exited Apr 27 15:47:24 rhvolumio sh[433]: ifup: failed to bring up eth0 Apr 27 15:47:24 rhvolumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Apr 27 15:47:24 rhvolumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Apr 27 15:47:25 rhvolumio systemd[1]: systemd-hostnamed.service: Succeeded. Apr 27 15:47:28 rhvolumio sudo[1035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=ronwho,password=gaPPkh817711,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.0.0.2/music/Tidal /mnt/NAS/NAS Apr 27 15:47:28 rhvolumio sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:28 rhvolumio kernel: CIFS: Attempting to mount \\10.0.0.2\music Apr 27 15:47:28 rhvolumio sudo[1035]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:28 rhvolumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE Apr 27 15:47:28 rhvolumio kernel: CIFS: VFS: \\10.0.0.2 Send error in SessSetup = -13 Apr 27 15:47:28 rhvolumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Apr 27 15:47:28 rhvolumio volumio[872]: info: Cannot mount NAS NAS at system boot, trial number 2 ,retrying in 5 seconds Apr 27 15:47:29 rhvolumio sudo[1041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 27 15:47:29 rhvolumio sudo[1041]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:29 rhvolumio sudo[1041]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:29 rhvolumio sudo[1043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 27 15:47:29 rhvolumio sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:29 rhvolumio sudo[1043]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:29 rhvolumio sudo[1047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 27 15:47:29 rhvolumio sudo[1047]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:29 rhvolumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 27 15:47:29 rhvolumio sudo[1047]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:29 rhvolumio volumio[872]: info: Upmpdcli Daemon Started Apr 27 15:47:29 rhvolumio volumio[872]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 27 15:47:32 rhvolumio volumio-remote-updater[500]: [2024-04-27 15:47:32] [connect] Successful connection Apr 27 15:47:32 rhvolumio volumio-remote-updater[500]: [2024-04-27 15:47:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1714247252 101 Apr 27 15:47:32 rhvolumio volumio[872]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Apr 27 15:47:33 rhvolumio volumio[872]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 27 15:47:33 rhvolumio wpa_supplicant[779]: RRM: Ignoring radio measurement request: Not RRM network Apr 27 15:47:33 rhvolumio sudo[1051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=ronwho,password=gaPPkh817711,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.0.0.2/music/Tidal /mnt/NAS/NAS Apr 27 15:47:33 rhvolumio sudo[1051]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:33 rhvolumio kernel: CIFS: Attempting to mount \\10.0.0.2\music Apr 27 15:47:33 rhvolumio sudo[1051]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:33 rhvolumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE Apr 27 15:47:33 rhvolumio kernel: CIFS: VFS: \\10.0.0.2 Send error in SessSetup = -13 Apr 27 15:47:33 rhvolumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Apr 27 15:47:33 rhvolumio volumio[872]: info: Cannot mount NAS NAS at system boot, trial number 3 ,retrying in 5 seconds Apr 27 15:47:38 rhvolumio sudo[1057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=ronwho,password=gaPPkh817711,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.0.0.2/music/Tidal /mnt/NAS/NAS Apr 27 15:47:38 rhvolumio sudo[1057]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:38 rhvolumio kernel: CIFS: Attempting to mount \\10.0.0.2\music Apr 27 15:47:38 rhvolumio sudo[1057]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:38 rhvolumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE Apr 27 15:47:38 rhvolumio kernel: CIFS: VFS: \\10.0.0.2 Send error in SessSetup = -13 Apr 27 15:47:38 rhvolumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Apr 27 15:47:38 rhvolumio volumio[872]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Apr 27 15:47:39 rhvolumio volumio[872]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 27 15:47:39 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 15:47:39 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 15:47:39 rhvolumio volumio[872]: info: Discovery: Getting this device information Apr 27 15:47:39 rhvolumio volumio[872]: info: CoreCommandRouter::volumioGetState Apr 27 15:47:39 rhvolumio volumio[872]: info: CorePlayQueue::getTrack 0 Apr 27 15:47:39 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 15:47:39 rhvolumio volumio[872]: verbose: New Socket.io Connection to 10.0.0.58 from 10.0.0.55 UA: unknown Total Clients: 4 Apr 27 15:47:39 rhvolumio volumio[872]: info: CoreCommandRouter::volumioGetState Apr 27 15:47:39 rhvolumio volumio[872]: info: CorePlayQueue::getTrack 0 Apr 27 15:47:39 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 27 15:47:39 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 27 15:47:43 rhvolumio sudo[1063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 27 15:47:43 rhvolumio sudo[1063]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:43 rhvolumio sudo[1063]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:43 rhvolumio sudo[1065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 27 15:47:43 rhvolumio sudo[1065]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:43 rhvolumio sudo[1065]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:43 rhvolumio volumio[872]: verbose: New Socket.io Connection to 10.0.0.58 from 10.0.0.55 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 5 Apr 27 15:47:43 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 27 15:47:43 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 27 15:47:43 rhvolumio volumio[872]: info: CoreCommandRouter::volumioGetVisibleSources Apr 27 15:47:43 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 15:47:43 rhvolumio volumio[872]: info: CoreCommandRouter::volumioGetState Apr 27 15:47:43 rhvolumio volumio[872]: info: CorePlayQueue::getTrack 0 Apr 27 15:47:43 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 27 15:47:43 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 27 15:47:43 rhvolumio volumio[872]: info: Received Get System Info Apr 27 15:47:43 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 15:47:43 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 15:47:43 rhvolumio volumio[872]: info: Discovery: Getting this device information Apr 27 15:47:43 rhvolumio volumio[872]: info: CoreCommandRouter::volumioGetState Apr 27 15:47:43 rhvolumio volumio[872]: info: CorePlayQueue::getTrack 0 Apr 27 15:47:43 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 15:47:43 rhvolumio volumio[872]: info: CoreCommandRouter::volumioGetState Apr 27 15:47:43 rhvolumio volumio[872]: info: CorePlayQueue::getTrack 0 Apr 27 15:47:43 rhvolumio volumio[872]: info: Listing playlists Apr 27 15:47:43 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 27 15:47:43 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 27 15:47:43 rhvolumio volumio[872]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 15:47:44 rhvolumio sudo[1069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=ronwho,password=gaPPkh817711,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.0.0.2/music/Tidal /mnt/NAS/NAS Apr 27 15:47:44 rhvolumio sudo[1069]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:47:44 rhvolumio kernel: CIFS: Attempting to mount \\10.0.0.2\music Apr 27 15:47:44 rhvolumio sudo[1069]: pam_unix(sudo:session): session closed for user root Apr 27 15:47:44 rhvolumio volumio[872]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Apr 27 15:47:44 rhvolumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE Apr 27 15:47:44 rhvolumio kernel: CIFS: VFS: \\10.0.0.2 Send error in SessSetup = -13 Apr 27 15:47:44 rhvolumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Apr 27 15:47:44 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 27 15:47:44 rhvolumio volumio[872]: info: Received Get System Info Apr 27 15:47:44 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 15:47:44 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 15:47:44 rhvolumio volumio[872]: info: Discovery: Getting this device information Apr 27 15:47:44 rhvolumio volumio[872]: info: CoreCommandRouter::volumioGetState Apr 27 15:47:44 rhvolumio volumio[872]: info: CorePlayQueue::getTrack 0 Apr 27 15:47:44 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 15:47:45 rhvolumio volumio[872]: info: CoreCommandRouter::volumioPlay Apr 27 15:47:45 rhvolumio volumio[872]: info: CoreStateMachine::play index undefined Apr 27 15:47:45 rhvolumio volumio[872]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 15:47:45 rhvolumio volumio[872]: info: CorePlayQueue::getTrack 0 Apr 27 15:47:45 rhvolumio volumio[872]: info: CoreStateMachine::startPlaybackTimer Apr 27 15:47:45 rhvolumio volumio[872]: info: CorePlayQueue::getTrack 0 Apr 27 15:47:45 rhvolumio volumio[872]: verbose: ControllerMpd::clearAddPlayTracks USB/F99E-4F96/Jazz/Kenny Burrell/[M] Midnight Blue (2012 Remaster) [77698515] [1963]/01 - Kenny Burrell - Chitlins Con Carne (Remastered).flac Apr 27 15:47:45 rhvolumio volumio[872]: verbose: ControllerMpd::sendMpdCommand stop Apr 27 15:47:45 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 27 15:47:46 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 27 15:47:46 rhvolumio volumio[872]: info: Received Get System Info Apr 27 15:47:46 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 15:47:46 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 15:47:46 rhvolumio volumio[872]: info: Discovery: Getting this device information Apr 27 15:47:46 rhvolumio volumio[872]: info: CoreCommandRouter::volumioGetState Apr 27 15:47:46 rhvolumio volumio[872]: info: CorePlayQueue::getTrack 0 Apr 27 15:47:46 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 15:47:46 rhvolumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 27 15:47:49 rhvolumio volumio[872]: info: CoreCommandRouter::volumioPlay Apr 27 15:47:49 rhvolumio volumio[872]: info: CoreStateMachine::play index undefined Apr 27 15:47:49 rhvolumio volumio[872]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 15:47:49 rhvolumio volumio[872]: info: CorePlayQueue::getTrack 0 Apr 27 15:47:49 rhvolumio volumio[872]: info: CoreStateMachine::startPlaybackTimer Apr 27 15:47:49 rhvolumio volumio[872]: info: CorePlayQueue::getTrack 0 Apr 27 15:47:49 rhvolumio volumio[872]: verbose: ControllerMpd::clearAddPlayTracks USB/F99E-4F96/Jazz/Kenny Burrell/[M] Midnight Blue (2012 Remaster) [77698515] [1963]/01 - Kenny Burrell - Chitlins Con Carne (Remastered).flac Apr 27 15:47:49 rhvolumio volumio[872]: verbose: ControllerMpd::sendMpdCommand stop Apr 27 15:47:49 rhvolumio volumio[872]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 27 15:47:50 rhvolumio volumio[872]: info: CoreCommandRouter::volumioGetQueue Apr 27 15:47:50 rhvolumio volumio[872]: info: CoreStateMachine::getQueue Apr 27 15:47:50 rhvolumio volumio[872]: info: CorePlayQueue::getQueue Apr 27 15:47:51 rhvolumio volumio[872]: info: Preload queue cleared Apr 27 15:47:51 rhvolumio volumio[872]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 27 15:47:51 rhvolumio volumio[872]: info: CoreStateMachine::ClearQueue Apr 27 15:47:51 rhvolumio volumio[872]: info: CoreStateMachine::stop Apr 27 15:47:51 rhvolumio volumio[872]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 15:47:51 rhvolumio volumio[872]: info: CorePlayQueue::clearPlayQueue Apr 27 15:47:51 rhvolumio volumio[872]: info: CorePlayQueue::saveQueue Apr 27 15:47:51 rhvolumio volumio[872]: info: CoreCommandRouter::volumioPushQueue Apr 27 15:47:51 rhvolumio volumio[872]: info: CoreStateMachine::addQueueItems Apr 27 15:47:51 rhvolumio volumio[872]: info: CorePlayQueue::addQueueItems Apr 27 15:47:51 rhvolumio volumio[872]: info: Preload queue cleared Apr 27 15:47:51 rhvolumio volumio[872]: info: Adding Item to queue: music-library/USB/F99E-4F96/Jazz/Kenny Burrell/[M] Midnight Blue (2012 Remaster) [77698515] [1963]/01 - Kenny Burrell - Chitlins Con Carne (Remastered).flac Apr 27 15:47:51 rhvolumio volumio[872]: info: Exploding uri music-library/USB/F99E-4F96/Jazz/Kenny Burrell/[M] Midnight Blue (2012 Remaster) [77698515] [1963]/01 - Kenny Burrell - Chitlins Con Carne (Remastered).flac in service mpd Apr 27 15:47:51 rhvolumio volumio[872]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 15:47:51 rhvolumio volumio[872]: TypeError: Cannot read property 'then' of undefined Apr 27 15:47:51 rhvolumio volumio[872]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2527:19) Apr 27 15:47:51 rhvolumio volumio[872]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2325:21) Apr 27 15:47:51 rhvolumio volumio[872]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1359:16) Apr 27 15:47:51 rhvolumio volumio[872]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Apr 27 15:47:51 rhvolumio volumio[872]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Apr 27 15:47:51 rhvolumio volumio[872]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Apr 27 15:47:51 rhvolumio volumio[872]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Apr 27 15:47:51 rhvolumio volumio[872]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:501:23) Apr 27 15:47:51 rhvolumio volumio[872]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:477:17) Apr 27 15:47:51 rhvolumio volumio[872]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:118:33) Apr 27 15:47:51 rhvolumio volumio[872]: at Socket.emit (events.js:315:20) Apr 27 15:47:51 rhvolumio volumio[872]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 27 15:47:51 rhvolumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 27 15:47:51 rhvolumio volumio[872]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 15:47:51 rhvolumio sudo[1083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-27 15:46 Apr 27 15:47:51 rhvolumio sudo[1083]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"