-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sun 2024-03-31 08:18:50 UTC. -- Mar 31 08:18:10 volumio-db kernel: brcm-pcie fd500000.pcie: setting SCB_ACCESS_EN, READ_UR_MODE, MAX_BURST_SIZE Mar 31 08:18:10 volumio-db kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Mar 31 08:18:10 volumio-db kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Mar 31 08:18:10 volumio-db kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff]) Mar 31 08:18:10 volumio-db kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 Mar 31 08:18:10 volumio-db kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Mar 31 08:18:10 volumio-db kernel: PCI: bus0: Fast back to back transfers disabled Mar 31 08:18:10 volumio-db kernel: pci_bus 0000:01: supply vpcie3v3 not found, using dummy regulator Mar 31 08:18:10 volumio-db kernel: pci_bus 0000:01: supply vpcie3v3aux not found, using dummy regulator Mar 31 08:18:10 volumio-db kernel: pci_bus 0000:01: supply vpcie12v not found, using dummy regulator Mar 31 08:18:10 volumio-db kernel: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) Mar 31 08:18:10 volumio-db kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 Mar 31 08:18:10 volumio-db kernel: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] Mar 31 08:18:10 volumio-db kernel: pci 0000:01:00.0: PME# supported from D0 D3hot Mar 31 08:18:10 volumio-db kernel: PCI: bus1: Fast back to back transfers disabled Mar 31 08:18:10 volumio-db kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] Mar 31 08:18:10 volumio-db kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] Mar 31 08:18:10 volumio-db kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Mar 31 08:18:10 volumio-db kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Mar 31 08:18:10 volumio-db kernel: pcieport 0000:00:00.0: enabling device (0000 -> 0002) Mar 31 08:18:10 volumio-db kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 45 Mar 31 08:18:10 volumio-db kernel: bcm2708_fb soc:fb: FB found 1 display(s) Mar 31 08:18:10 volumio-db kernel: Console: switching to colour frame buffer device 80x30 Mar 31 08:18:10 volumio-db kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Mar 31 08:18:10 volumio-db kernel: iproc-rng200 fe104000.rng: hwrng registered Mar 31 08:18:10 volumio-db kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Mar 31 08:18:10 volumio-db kernel: brd: module loaded Mar 31 08:18:10 volumio-db kernel: loop: module loaded Mar 31 08:18:10 volumio-db kernel: Loading iSCSI transport class v2.0-870. Mar 31 08:18:10 volumio-db kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Mar 31 08:18:10 volumio-db kernel: Freeing initrd memory: 13628K Mar 31 08:18:10 volumio-db kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Mar 31 08:18:10 volumio-db kernel: usbcore: registered new device driver r8152-cfgselector Mar 31 08:18:10 volumio-db kernel: usbcore: registered new interface driver r8152 Mar 31 08:18:10 volumio-db kernel: usbcore: registered new interface driver lan78xx Mar 31 08:18:10 volumio-db kernel: usbcore: registered new interface driver smsc95xx Mar 31 08:18:10 volumio-db kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Mar 31 08:18:10 volumio-db kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Mar 31 08:18:10 volumio-db kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0f00040000000890 Mar 31 08:18:10 volumio-db kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Mar 31 08:18:10 volumio-db kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Mar 31 08:18:10 volumio-db kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Mar 31 08:18:10 volumio-db kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01 Mar 31 08:18:10 volumio-db kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Mar 31 08:18:10 volumio-db kernel: usb usb1: Product: xHCI Host Controller Mar 31 08:18:10 volumio-db kernel: usb usb1: Manufacturer: Linux 6.1.69-v7l+ xhci-hcd Mar 31 08:18:10 volumio-db kernel: usb usb1: SerialNumber: 0000:01:00.0 Mar 31 08:18:10 volumio-db kernel: hub 1-0:1.0: USB hub found Mar 31 08:18:10 volumio-db kernel: hub 1-0:1.0: 1 port detected Mar 31 08:18:10 volumio-db kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.01 Mar 31 08:18:10 volumio-db kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Mar 31 08:18:10 volumio-db kernel: usb usb2: Product: xHCI Host Controller Mar 31 08:18:10 volumio-db kernel: usb usb2: Manufacturer: Linux 6.1.69-v7l+ xhci-hcd Mar 31 08:18:10 volumio-db kernel: usb usb2: SerialNumber: 0000:01:00.0 Mar 31 08:18:10 volumio-db kernel: hub 2-0:1.0: USB hub found Mar 31 08:18:10 volumio-db kernel: hub 2-0:1.0: 4 ports detected Mar 31 08:18:10 volumio-db kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Mar 31 08:18:10 volumio-db kernel: dwc_otg: FIQ enabled Mar 31 08:18:10 volumio-db kernel: dwc_otg: NAK holdoff enabled Mar 31 08:18:10 volumio-db kernel: dwc_otg: FIQ split-transaction FSM enabled Mar 31 08:18:10 volumio-db kernel: Module dwc_common_port init Mar 31 08:18:10 volumio-db kernel: usbcore: registered new interface driver uas Mar 31 08:18:10 volumio-db kernel: usbcore: registered new interface driver usb-storage Mar 31 08:18:10 volumio-db kernel: mousedev: PS/2 mouse device common for all mice Mar 31 08:18:10 volumio-db kernel: sdhci: Secure Digital Host Controller Interface driver Mar 31 08:18:10 volumio-db kernel: sdhci: Copyright(c) Pierre Ossman Mar 31 08:18:10 volumio-db kernel: sdhci-pltfm: SDHCI platform and OF driver helper Mar 31 08:18:10 volumio-db kernel: ledtrig-cpu: registered to indicate activity on CPUs Mar 31 08:18:10 volumio-db kernel: hid: raw HID events driver (C) Jiri Kosina Mar 31 08:18:10 volumio-db kernel: usbcore: registered new interface driver usbhid Mar 31 08:18:10 volumio-db kernel: usbhid: USB HID core driver Mar 31 08:18:10 volumio-db kernel: Initializing XFRM netlink socket Mar 31 08:18:10 volumio-db kernel: NET: Registered PF_PACKET protocol family Mar 31 08:18:10 volumio-db kernel: Key type dns_resolver registered Mar 31 08:18:10 volumio-db kernel: Registering SWP/SWPB emulation handler Mar 31 08:18:10 volumio-db kernel: registered taskstats version 1 Mar 31 08:18:10 volumio-db kernel: Loading compiled-in X.509 certificates Mar 31 08:18:10 volumio-db kernel: Key type .fscrypt registered Mar 31 08:18:10 volumio-db kernel: Key type fscrypt-provisioning registered Mar 31 08:18:10 volumio-db kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Mar 31 08:18:10 volumio-db kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Mar 31 08:18:10 volumio-db kernel: fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 51, base_baud = 0) is a PL011 rev2 Mar 31 08:18:10 volumio-db kernel: serial serial0: tty port ttyAMA1 registered Mar 31 08:18:10 volumio-db kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Mar 31 08:18:10 volumio-db kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Mar 31 08:18:10 volumio-db kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Mar 31 08:18:10 volumio-db kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Mar 31 08:18:10 volumio-db kernel: of_cfs_init Mar 31 08:18:10 volumio-db kernel: of_cfs_init: OK Mar 31 08:18:10 volumio-db kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Mar 31 08:18:10 volumio-db kernel: Freeing unused kernel image (initmem) memory: 2048K Mar 31 08:18:10 volumio-db kernel: Run /init as init process Mar 31 08:18:10 volumio-db kernel: with arguments: Mar 31 08:18:10 volumio-db kernel: /init Mar 31 08:18:10 volumio-db kernel: splash Mar 31 08:18:10 volumio-db kernel: with environment: Mar 31 08:18:10 volumio-db kernel: HOME=/ Mar 31 08:18:10 volumio-db kernel: TERM=linux Mar 31 08:18:10 volumio-db kernel: imgpart=/dev/mmcblk0p2 Mar 31 08:18:10 volumio-db kernel: imgfile=/volumio_current.sqsh Mar 31 08:18:10 volumio-db kernel: bootdelay=5 Mar 31 08:18:10 volumio-db kernel: mmc1: new high speed SDIO card at address 0001 Mar 31 08:18:10 volumio-db kernel: mmc0: new ultra high speed DDR50 SDXC card at address e624 Mar 31 08:18:10 volumio-db kernel: mmcblk0: mmc0:e624 SL128 119 GiB Mar 31 08:18:10 volumio-db kernel: mmcblk0: p1 p2 p3 Mar 31 08:18:10 volumio-db kernel: mmcblk0: mmc0:e624 SL128 119 GiB (quirks 0x00004000) Mar 31 08:18:10 volumio-db initramfs: Booting Volumio for BCM2711 Mar 31 08:18:10 volumio-db initramfs: This script mounts rootfs RO with an overlay RW layer. Mar 31 08:18:10 volumio-db kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Mar 31 08:18:10 volumio-db kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Mar 31 08:18:10 volumio-db initramfs: IMGPART=/dev/mmcblk0p2 Mar 31 08:18:10 volumio-db initramfs: IMGFILE=/volumio_current.sqsh Mar 31 08:18:10 volumio-db initramfs: Boot delay (except first time) will be 5 seconds Mar 31 08:18:10 volumio-db initramfs: /dev/mmcblk0p2 not detected,retrying mdev in 5 seconds Mar 31 08:18:10 volumio-db kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Mar 31 08:18:10 volumio-db kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Mar 31 08:18:10 volumio-db kernel: usb 1-1: Product: USB2.0 Hub Mar 31 08:18:10 volumio-db kernel: hub 1-1:1.0: USB hub found Mar 31 08:18:10 volumio-db kernel: hub 1-1:1.0: 4 ports detected Mar 31 08:18:10 volumio-db initramfs: /dev/mmcblk0p2: Mar 31 08:18:10 volumio-db kernel: EXT4-fs (mmcblk0p2): recovery complete Mar 31 08:18:10 volumio-db kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none. Mar 31 08:18:10 volumio-db initramfs: Doing a 5 second delay here to give kernel load a headstart Mar 31 08:18:10 volumio-db initramfs: Checking for USB updates Mar 31 08:18:10 volumio-db initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Mar 31 08:18:10 volumio-db kernel: loop0: detected capacity change from 0 to 967536 Mar 31 08:18:10 volumio-db kernel: usb 1-1.4: new full-speed USB device number 3 using xhci_hcd Mar 31 08:18:10 volumio-db kernel: usb 1-1.4: new high-speed USB device number 4 using xhci_hcd Mar 31 08:18:10 volumio-db kernel: EXT4-fs (mmcblk0p3): recovery complete Mar 31 08:18:10 volumio-db kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Quota mode: none. Mar 31 08:18:10 volumio-db initramfs: With Option: Mar 31 08:18:10 volumio-db kernel: usb 1-1.4: New USB device found, idVendor=1c07, idProduct=0013, bcdDevice= 0.36 Mar 31 08:18:10 volumio-db kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Mar 31 08:18:10 volumio-db kernel: usb 1-1.4: Product: CEntrance M8 Mar 31 08:18:10 volumio-db kernel: usb 1-1.4: Manufacturer: CEntrance Mar 31 08:18:10 volumio-db initramfs: VOLUMIO_VERSION="3.631" Mar 31 08:18:10 volumio-db initramfs: Finish initramfs, continue booting Volumio Mar 31 08:18:10 volumio-db systemd[1]: System time before build time, advancing clock. Mar 31 08:18:10 volumio-db kernel: NET: Registered PF_INET6 protocol family Mar 31 08:18:10 volumio-db kernel: Segment Routing with IPv6 Mar 31 08:18:10 volumio-db kernel: In-situ OAM (IOAM) with IPv6 Mar 31 08:18:10 volumio-db 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) Mar 31 08:18:10 volumio-db systemd[1]: Detected architecture arm. Mar 31 08:18:10 volumio-db systemd[1]: Set hostname to . Mar 31 08:18:10 volumio-db 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. Mar 31 08:18:10 volumio-db systemd[1]: Listening on RPCbind Server Activation Socket. Mar 31 08:18:10 volumio-db systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Mar 31 08:18:10 volumio-db systemd[1]: Created slice system-systemd\x2dfsck.slice. Mar 31 08:18:10 volumio-db systemd[1]: Listening on Journal Socket. Mar 31 08:18:10 volumio-db systemd[1]: Mounting POSIX Message Queue File System... Mar 31 08:18:10 volumio-db systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Mar 31 08:18:10 volumio-db kernel: i2c_dev: i2c /dev entries driver Mar 31 08:18:10 volumio-db systemd-journald[207]: Journal started Mar 31 08:18:10 volumio-db systemd-journald[207]: Runtime journal (/run/log/journal/3c3812e622e46b6dc8110864638cf76c) is 7.5M, max 30.0M, 22.5M free. Mar 31 08:18:10 volumio-db systemd-modules-load[202]: Inserted module 'i2c_dev' Mar 31 08:18:10 volumio-db fake-hwclock[205]: Sun Mar 31 08:18:10 UTC 2024 Mar 31 08:18:10 volumio-db systemd[1]: dynamicswap.service: Succeeded. Mar 31 08:18:10 volumio-db systemd[1]: Started Create System Users. Mar 31 08:18:10 volumio-db systemd[1]: Starting Create Static Device Nodes in /dev... Mar 31 08:18:10 volumio-db systemd[1]: Started Create Static Device Nodes in /dev. Mar 31 08:18:10 volumio-db systemd[1]: Reached target Local File Systems (Pre). Mar 31 08:18:10 volumio-db systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Mar 31 08:18:10 volumio-db systemd[1]: Mounting /tmp... Mar 31 08:18:10 volumio-db systemd[1]: Mounting /var/spool/cups... Mar 31 08:18:10 volumio-db systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Mar 31 08:18:10 volumio-db systemd[1]: Mounting /var/log... Mar 31 08:18:10 volumio-db systemd[1]: Starting udev Kernel Device Manager... Mar 31 08:18:10 volumio-db systemd[1]: Mounted /tmp. Mar 31 08:18:10 volumio-db systemd[1]: Mounted /var/spool/cups. Mar 31 08:18:10 volumio-db systemd[1]: Mounting /var/spool/cups/tmp... Mar 31 08:18:10 volumio-db systemd[1]: Mounted /var/log. Mar 31 08:18:10 volumio-db systemd[1]: Starting Flush Journal to Persistent Storage... Mar 31 08:18:10 volumio-db systemd[1]: Mounted /var/spool/cups/tmp. Mar 31 08:18:10 volumio-db systemd-udevd[236]: Network interface NamePolicy= disabled on kernel command line, ignoring. Mar 31 08:18:10 volumio-db systemd-journald[207]: Runtime journal (/run/log/journal/3c3812e622e46b6dc8110864638cf76c) is 7.5M, max 30.0M, 22.5M free. Mar 31 08:18:10 volumio-db systemd[1]: Started Flush Journal to Persistent Storage. Mar 31 08:18:10 volumio-db systemd[1]: Started udev Kernel Device Manager. Mar 31 08:18:10 volumio-db systemd[1]: Started udev Coldplug all Devices. Mar 31 08:18:10 volumio-db systemd[1]: Starting Helper to synchronize boot up for ifupdown... Mar 31 08:18:10 volumio-db systemd[1]: Starting Show Plymouth Boot Screen... Mar 31 08:18:10 volumio-db systemd[1]: Started Helper to synchronize boot up for ifupdown. Mar 31 08:18:10 volumio-db systemd[1]: Received SIGRTMIN+20 from PID 261 (plymouthd). Mar 31 08:18:10 volumio-db systemd[1]: Started Show Plymouth Boot Screen. Mar 31 08:18:10 volumio-db systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Mar 31 08:18:10 volumio-db systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Mar 31 08:18:10 volumio-db systemd[1]: Reached target Local Encrypted Volumes. Mar 31 08:18:10 volumio-db systemd[1]: Reached target Paths. Mar 31 08:18:10 volumio-db systemd-udevd[248]: Using default interface naming scheme 'v240'. Mar 31 08:18:10 volumio-db systemd-udevd[248]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 31 08:18:10 volumio-db kernel: mc: Linux media interface: v0.10 Mar 31 08:18:10 volumio-db kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Mar 31 08:18:10 volumio-db kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Mar 31 08:18:10 volumio-db kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Mar 31 08:18:10 volumio-db kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Mar 31 08:18:10 volumio-db kernel: [vc_sm_connected_init]: start Mar 31 08:18:10 volumio-db kernel: [vc_sm_connected_init]: installed successfully Mar 31 08:18:10 volumio-db kernel: videodev: Linux video capture interface: v2.00 Mar 31 08:18:11 volumio-db kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Mar 31 08:18:11 volumio-db kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Mar 31 08:18:11 volumio-db kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Mar 31 08:18:11 volumio-db kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Mar 31 08:18:11 volumio-db kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Mar 31 08:18:11 volumio-db kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Mar 31 08:18:11 volumio-db kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Mar 31 08:18:11 volumio-db kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Mar 31 08:18:11 volumio-db kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Mar 31 08:18:11 volumio-db kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Mar 31 08:18:11 volumio-db kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Mar 31 08:18:11 volumio-db kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Mar 31 08:18:11 volumio-db kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Mar 31 08:18:11 volumio-db kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Mar 31 08:18:11 volumio-db kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Mar 31 08:18:11 volumio-db kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Mar 31 08:18:11 volumio-db kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Mar 31 08:18:11 volumio-db kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Mar 31 08:18:11 volumio-db kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Mar 31 08:18:11 volumio-db kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Mar 31 08:18:11 volumio-db kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Mar 31 08:18:11 volumio-db kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Mar 31 08:18:11 volumio-db kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Mar 31 08:18:11 volumio-db kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Mar 31 08:18:11 volumio-db kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Mar 31 08:18:11 volumio-db kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Mar 31 08:18:11 volumio-db kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Mar 31 08:18:11 volumio-db kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Mar 31 08:18:11 volumio-db kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Mar 31 08:18:11 volumio-db kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Mar 31 08:18:11 volumio-db kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Mar 31 08:18:11 volumio-db kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Mar 31 08:18:11 volumio-db kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Mar 31 08:18:11 volumio-db kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Mar 31 08:18:11 volumio-db kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Mar 31 08:18:11 volumio-db kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Mar 31 08:18:11 volumio-db kernel: Bluetooth: Core ver 2.22 Mar 31 08:18:11 volumio-db kernel: NET: Registered PF_BLUETOOTH protocol family Mar 31 08:18:11 volumio-db kernel: Bluetooth: HCI device and connection manager initialized Mar 31 08:18:11 volumio-db kernel: Bluetooth: HCI socket layer initialized Mar 31 08:18:11 volumio-db kernel: Bluetooth: L2CAP socket layer initialized Mar 31 08:18:11 volumio-db kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Mar 31 08:18:11 volumio-db kernel: rpivid feb10000.codec: Device registered as /dev/video19 Mar 31 08:18:11 volumio-db kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Mar 31 08:18:11 volumio-db kernel: Bluetooth: SCO socket layer initialized Mar 31 08:18:11 volumio-db kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Mar 31 08:18:11 volumio-db kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Mar 31 08:18:11 volumio-db systemd-udevd[272]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 31 08:18:11 volumio-db kernel: Bluetooth: HCI UART driver ver 2.3 Mar 31 08:18:11 volumio-db kernel: Bluetooth: HCI UART protocol H4 registered Mar 31 08:18:11 volumio-db kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Mar 31 08:18:11 volumio-db kernel: Bluetooth: HCI UART protocol Broadcom registered Mar 31 08:18:11 volumio-db kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Mar 31 08:18:11 volumio-db kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Mar 31 08:18:11 volumio-db kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Mar 31 08:18:11 volumio-db kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Mar 31 08:18:11 volumio-db kernel: usbcore: registered new interface driver brcmfmac Mar 31 08:18:12 volumio-db kernel: uart-pl011 fe201000.serial: no DMA platform data Mar 31 08:18:12 volumio-db systemd[1]: Found device /dev/mmcblk0p1. Mar 31 08:18:12 volumio-db kernel: Bluetooth: hci0: BCM: chip id 107 Mar 31 08:18:12 volumio-db kernel: Bluetooth: hci0: BCM: features 0x2f Mar 31 08:18:12 volumio-db kernel: Bluetooth: hci0: BCM4345C0 Mar 31 08:18:12 volumio-db kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Mar 31 08:18:12 volumio-db kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Mar 31 08:18:12 volumio-db 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 Mar 31 08:18:12 volumio-db kernel: usbcore: registered new interface driver snd-usb-audio Mar 31 08:18:12 volumio-db systemd-udevd[250]: Using default interface naming scheme 'v240'. Mar 31 08:18:12 volumio-db systemd-udevd[250]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 31 08:18:12 volumio-db systemd-udevd[257]: Process '/sbin/crda' failed with exit code 255. Mar 31 08:18:13 volumio-db kernel: Bluetooth: hci0: BCM: features 0x2f Mar 31 08:18:13 volumio-db kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Mar 31 08:18:13 volumio-db kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Mar 31 08:18:13 volumio-db systemd-udevd[278]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Mar 31 08:18:13 volumio-db systemd-udevd[242]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Mar 31 08:18:13 volumio-db systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Mar 31 08:18:13 volumio-db systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Mar 31 08:18:13 volumio-db systemd[1]: Created slice system-bthelper.slice. Mar 31 08:18:13 volumio-db systemd[1]: Condition check resulted in Huge Pages File System being skipped. Mar 31 08:18:13 volumio-db systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Mar 31 08:18:13 volumio-db systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Mar 31 08:18:13 volumio-db systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Mar 31 08:18:13 volumio-db systemd[1]: Condition check resulted in FUSE Control File System being skipped. Mar 31 08:18:13 volumio-db systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Mar 31 08:18:13 volumio-db systemd[1]: Starting File System Check on /dev/mmcblk0p1... Mar 31 08:18:13 volumio-db systemd[1]: Starting Load/Save RF Kill Switch Status... Mar 31 08:18:14 volumio-db systemd[1]: Started File System Check Daemon to report status. Mar 31 08:18:14 volumio-db systemd[1]: Started Load/Save RF Kill Switch Status. Mar 31 08:18:14 volumio-db systemd-fsck[397]: fsck.fat 4.1 (2017-01-24) Mar 31 08:18:14 volumio-db systemd-fsck[397]: /dev/mmcblk0p1: 462 files, 33972/46774 clusters Mar 31 08:18:14 volumio-db systemd[1]: Started File System Check on /dev/mmcblk0p1. Mar 31 08:18:14 volumio-db systemd[1]: Mounting /boot... Mar 31 08:18:14 volumio-db systemd[1]: Mounted /boot. Mar 31 08:18:14 volumio-db systemd[1]: Reached target Local File Systems. Mar 31 08:18:14 volumio-db systemd[1]: Starting Preprocess NFS configuration... Mar 31 08:18:14 volumio-db systemd[1]: Started ifup for wlan0. Mar 31 08:18:14 volumio-db systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Mar 31 08:18:14 volumio-db systemd[1]: Starting Raise network interfaces... Mar 31 08:18:14 volumio-db systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Mar 31 08:18:14 volumio-db systemd[1]: Started ifup for eth0. Mar 31 08:18:14 volumio-db systemd[1]: Starting Create Volatile Files and Directories... Mar 31 08:18:14 volumio-db systemd[1]: Received SIGRTMIN+20 from PID 261 (plymouthd). Mar 31 08:18:14 volumio-db systemd[1]: plymouth-read-write.service: Succeeded. Mar 31 08:18:14 volumio-db systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Mar 31 08:18:14 volumio-db systemd[1]: nfs-config.service: Succeeded. Mar 31 08:18:14 volumio-db systemd[1]: Started Preprocess NFS configuration. Mar 31 08:18:14 volumio-db systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Mar 31 08:18:14 volumio-db systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Mar 31 08:18:14 volumio-db systemd[1]: Reached target NFS client services. Mar 31 08:18:14 volumio-db systemd[1]: Started Create Volatile Files and Directories. Mar 31 08:18:14 volumio-db systemd[1]: Starting Update UTMP about System Boot/Shutdown... Mar 31 08:18:14 volumio-db systemd[1]: Starting RPC bind portmap service... Mar 31 08:18:14 volumio-db systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Mar 31 08:18:14 volumio-db systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Mar 31 08:18:14 volumio-db kernel: 8021q: 802.1Q VLAN Support v1.8 Mar 31 08:18:14 volumio-db kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 31 08:18:14 volumio-db systemd[1]: Started Update UTMP about System Boot/Shutdown. Mar 31 08:18:14 volumio-db systemd[1]: Reached target System Initialization. Mar 31 08:18:14 volumio-db systemd[1]: Started Volumio Splash. Mar 31 08:18:14 volumio-db systemd[1]: Started Daily apt download activities. Mar 31 08:18:14 volumio-db systemd[1]: Started Daily Cleanup of Temporary Directories. Mar 31 08:18:14 volumio-db systemd[1]: Started Daily apt upgrade and clean activities. Mar 31 08:18:14 volumio-db systemd[1]: Reached target Timers. Mar 31 08:18:14 volumio-db systemd[480]: volumiosplash.service: Failed to execute command: No such file or directory Mar 31 08:18:14 volumio-db systemd[480]: volumiosplash.service: Failed at step EXEC spawning /bin/volumiosplash.sh: No such file or directory Mar 31 08:18:14 volumio-db systemd[1]: Listening on D-Bus System Message Bus Socket. Mar 31 08:18:14 volumio-db systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Mar 31 08:18:14 volumio-db systemd[1]: Listening on triggerhappy.socket. Mar 31 08:18:14 volumio-db systemd[1]: Reached target Sockets. Mar 31 08:18:14 volumio-db systemd[1]: Reached target Basic System. Mar 31 08:18:14 volumio-db systemd[1]: Starting Login Service... Mar 31 08:18:14 volumio-db systemd[1]: Starting Wireless Services... Mar 31 08:18:14 volumio-db systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Mar 31 08:18:14 volumio-db systemd[1]: Started Volumio Iptables Module. Mar 31 08:18:14 volumio-db systemd[1]: Starting Volumio SSH enabler... Mar 31 08:18:14 volumio-db systemd[1]: Started D-Bus System Message Bus. Mar 31 08:18:14 volumio-db volumiossh[507]: SSH file found, enabling SSH Mar 31 08:18:14 volumio-db systemd[1]: Started Manage Sound Card State (restore and store). Mar 31 08:18:14 volumio-db systemd[1]: Starting Save/Restore Sound Card State... Mar 31 08:18:14 volumio-db systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Mar 31 08:18:14 volumio-db systemd[1]: Started volumio-remote-updater.service. Mar 31 08:18:14 volumio-db systemd[1]: Starting Configure Bluetooth Modems connected by UART... Mar 31 08:18:14 volumio-db systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Mar 31 08:18:14 volumio-db alsactl[515]: alsactl 1.1.8 daemon started Mar 31 08:18:14 volumio-db systemd-logind[500]: New seat seat0. Mar 31 08:18:14 volumio-db systemd[1]: Starting dhcpcd on all interfaces... Mar 31 08:18:14 volumio-db avahi-daemon[503]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Mar 31 08:18:14 volumio-db avahi-daemon[503]: Successfully dropped root privileges. Mar 31 08:18:14 volumio-db sh[410]: eth0: waiting for carrier Mar 31 08:18:14 volumio-db kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Mar 31 08:18:15 volumio-db kernel: bcmgenet fd580000.ethernet eth0: Link is Down Mar 31 08:18:14 volumio-db dhcpcd[454]: eth0: waiting for carrier Mar 31 08:18:15 volumio-db dhcpcd[526]: Not running dhcpcd because /etc/network/interfaces Mar 31 08:18:15 volumio-db dhcpcd[526]: defines some interfaces that will use a Mar 31 08:18:15 volumio-db dhcpcd[526]: DHCP client or static address Mar 31 08:18:14 volumio-db avahi-daemon[503]: avahi-daemon 0.7 starting up. Mar 31 08:18:15 volumio-db alsactl[522]: Found hardware: "USB-Audio" "USB Mixer" "USB1c07:0013" "" "" Mar 31 08:18:15 volumio-db alsactl[522]: Hardware is initialized using a generic method Mar 31 08:18:15 volumio-db alsactl[522]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #3 (No such file or directory) Mar 31 08:18:15 volumio-db systemd[1]: Started Volumio Log Rotation Service. Mar 31 08:18:15 volumio-db alsactl[515]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #3 (No such file or directory) Mar 31 08:18:15 volumio-db alsactl[515]: Found hardware: "USB-Audio" "USB Mixer" "USB1c07:0013" "" "" Mar 31 08:18:15 volumio-db alsactl[515]: Hardware is initialized using a generic method Mar 31 08:18:15 volumio-db systemd[1]: Starting WPA supplicant... Mar 31 08:18:15 volumio-db systemd[1]: Started UPnP Renderer front-end to MPD. Mar 31 08:18:15 volumio-db systemd[1]: Starting triggerhappy global hotkey daemon... Mar 31 08:18:15 volumio-db systemd[1]: Started RPC bind portmap service. Mar 31 08:18:15 volumio-db systemd[1]: volumiosplash.service: Main process exited, code=exited, status=203/EXEC Mar 31 08:18:15 volumio-db systemd[1]: volumiosplash.service: Failed with result 'exit-code'. Mar 31 08:18:15 volumio-db systemd[1]: Started Save/Restore Sound Card State. Mar 31 08:18:15 volumio-db systemd[1]: hciuart.service: Succeeded. Mar 31 08:18:15 volumio-db systemd[1]: Started Configure Bluetooth Modems connected by UART. Mar 31 08:18:15 volumio-db systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Mar 31 08:18:15 volumio-db systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Mar 31 08:18:15 volumio-db systemd[1]: Failed to start dhcpcd on all interfaces. Mar 31 08:18:15 volumio-db volumiossh[538]: Synchronizing state of ssh.service with SysV service script with /lib/systemd/systemd-sysv-install. Mar 31 08:18:15 volumio-db volumiossh[538]: Executing: /lib/systemd/systemd-sysv-install enable ssh Mar 31 08:18:15 volumio-db thd[536]: Unable to parse trigger line: Mar 31 08:18:15 volumio-db thd[536]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Mar 31 08:18:15 volumio-db thd[536]: Unable to parse trigger line: Mar 31 08:18:15 volumio-db thd[536]: Unable to parse trigger line: Mar 31 08:18:15 volumio-db thd[536]: Found socket passed from systemd Mar 31 08:18:15 volumio-db systemd[1]: Started triggerhappy global hotkey daemon. Mar 31 08:18:15 volumio-db systemd[1]: Starting Raspberry Pi bluetooth helper... Mar 31 08:18:15 volumio-db systemd[1]: Reached target Sound Card. Mar 31 08:18:15 volumio-db systemd[1]: Reached target Remote File Systems (Pre). Mar 31 08:18:15 volumio-db systemd[1]: Reached target Remote File Systems. Mar 31 08:18:15 volumio-db systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Mar 31 08:18:15 volumio-db systemd[1]: Reached target RPC Port Mapper. Mar 31 08:18:15 volumio-db sh[405]: wlan0=wlan0 Mar 31 08:18:15 volumio-db volumio-remote-updater[523]: Error: No active session Mar 31 08:18:15 volumio-db volumio-remote-updater[523]: [2024-03-31 08:18:15] [info] asio async_connect error: system:111 (Connection refused) Mar 31 08:18:15 volumio-db volumio-remote-updater[523]: [2024-03-31 08:18:15] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 31 08:18:15 volumio-db volumio-remote-updater[523]: [2024-03-31 08:18:15] [error] handle_connect error: Underlying Transport Error Mar 31 08:18:15 volumio-db bthelper[545]: Raspberry Pi BDADDR already set Mar 31 08:18:15 volumio-db systemd[1]: Started Login Service. Mar 31 08:18:15 volumio-db avahi-daemon[503]: Successfully called chroot(). Mar 31 08:18:15 volumio-db avahi-daemon[503]: Successfully dropped remaining capabilities. Mar 31 08:18:15 volumio-db systemd[1]: Started Raspberry Pi bluetooth helper. Mar 31 08:18:15 volumio-db wpa_supplicant[534]: Successfully initialized wpa_supplicant Mar 31 08:18:15 volumio-db avahi-daemon[503]: Loading service file /services/volumio.service. Mar 31 08:18:15 volumio-db avahi-daemon[503]: Network interface enumeration completed. Mar 31 08:18:15 volumio-db avahi-daemon[503]: Server startup complete. Host name is volumio-db.local. Local service cookie is 900947314. Mar 31 08:18:15 volumio-db avahi-daemon[503]: Service "Volumio-db" (/services/volumio.service) successfully established. Mar 31 08:18:16 volumio-db systemd[1]: Started Avahi mDNS/DNS-SD Stack. Mar 31 08:18:16 volumio-db systemd[1]: Started WPA supplicant. Mar 31 08:18:16 volumio-db volumio[535]: Could not open config: /tmp/upmpdcli.conf Mar 31 08:18:16 volumio-db systemd[1]: Starting Bluetooth service... Mar 31 08:18:16 volumio-db systemd[1]: Started Raise network interfaces. Mar 31 08:18:16 volumio-db systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 31 08:18:16 volumio-db systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 31 08:18:16 volumio-db systemd[1]: Reached target Network. Mar 31 08:18:16 volumio-db systemd[1]: Starting Permit User Sessions... Mar 31 08:18:16 volumio-db systemd[1]: Starting Network Time Service... Mar 31 08:18:16 volumio-db systemd[1]: Reached target Network is Online. Mar 31 08:18:16 volumio-db systemd[1]: Starting LSB: Brings up/down network automatically... Mar 31 08:18:16 volumio-db systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Mar 31 08:18:16 volumio-db systemd[1]: Starting Samba NMB Daemon... Mar 31 08:18:16 volumio-db systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 31 08:18:16 volumio-db systemd[1]: Starting OpenBSD Secure Shell server... Mar 31 08:18:16 volumio-db systemd[1]: Starting /etc/rc.local Compatibility... Mar 31 08:18:16 volumio-db systemd[1]: iptables.service: Succeeded. Mar 31 08:18:16 volumio-db bluetoothd[602]: Bluetooth daemon 5.50 Mar 31 08:18:16 volumio-db loadcpufreq[547]: Loading cpufreq kernel modules...done (none). Mar 31 08:18:16 volumio-db systemd[1]: Started Permit User Sessions. Mar 31 08:18:16 volumio-db systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Mar 31 08:18:16 volumio-db systemd[1]: Started /etc/rc.local Compatibility. Mar 31 08:18:16 volumio-db systemd[1]: Starting LSB: set CPUFreq kernel parameters... Mar 31 08:18:16 volumio-db bluetoothd[602]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Mar 31 08:18:16 volumio-db systemd[1]: Starting Terminate Plymouth Boot Screen... Mar 31 08:18:16 volumio-db bluetoothd[602]: Starting SDP server Mar 31 08:18:16 volumio-db bluetoothd[602]: Excluding (cli) sap Mar 31 08:18:16 volumio-db systemd[1]: Starting Hold until boot process finishes up... Mar 31 08:18:16 volumio-db systemd[1]: Received SIGRTMIN+21 from PID 261 (plymouthd). Mar 31 08:18:16 volumio-db ntpd[610]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 31 08:18:16 volumio-db ntpd[610]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Mar 31 08:18:16 volumio-db ntpd[657]: proto: precision = 2.166 usec (-19) Mar 31 08:18:16 volumio-db systemd[1]: plymouth-start.service: Succeeded. Mar 31 08:18:16 volumio-db systemd[1]: Started Network Time Service. Mar 31 08:18:16 volumio-db ntpd[657]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 31 08:18:16 volumio-db ntpd[657]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 31 08:18:16 volumio-db ntpd[657]: Listen and drop on 0 v6wildcard [::]:123 Mar 31 08:18:16 volumio-db ntpd[657]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 31 08:18:16 volumio-db ntpd[657]: Listen normally on 2 lo 127.0.0.1:123 Mar 31 08:18:16 volumio-db ntpd[657]: Listening on routing socket on fd #19 for interface updates Mar 31 08:18:16 volumio-db ntpd[657]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 31 08:18:16 volumio-db ntpd[657]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 31 08:18:16 volumio-db systemd[1]: plymouth-quit.service: Succeeded. Mar 31 08:18:16 volumio-db systemd[1]: Started Terminate Plymouth Boot Screen. Mar 31 08:18:16 volumio-db systemd[1]: plymouth-quit-wait.service: Succeeded. Mar 31 08:18:16 volumio-db kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Mar 31 08:18:16 volumio-db kernel: Bluetooth: BNEP filters: protocol multicast Mar 31 08:18:16 volumio-db kernel: Bluetooth: BNEP socket layer initialized Mar 31 08:18:16 volumio-db bluetoothd[602]: Bluetooth management interface 1.22 initialized Mar 31 08:18:16 volumio-db dbus-daemon[510]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=602 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Mar 31 08:18:16 volumio-db systemd[1]: Started Hold until boot process finishes up. Mar 31 08:18:16 volumio-db kernel: Bluetooth: MGMT ver 1.22 Mar 31 08:18:16 volumio-db systemd[1]: Received SIGRTMIN+21 from PID 261 (n/a). Mar 31 08:18:16 volumio-db systemd[1]: Started Bluetooth service. Mar 31 08:18:16 volumio-db sshd[659]: Server listening on 0.0.0.0 port 22. Mar 31 08:18:16 volumio-db sshd[659]: Server listening on :: port 22. Mar 31 08:18:16 volumio-db systemd[1]: Started OpenBSD Secure Shell server. Mar 31 08:18:16 volumio-db bluetoothd[602]: Failed to set privacy: Rejected (0x0b) Mar 31 08:18:17 volumio-db ifplugd(eth0)[671]: ifplugd 0.28 initializing. Mar 31 08:18:17 volumio-db ifplugd(eth0)[671]: Using interface eth0/E4:5F:01:37:A7:64 with driver (version: 6.1.69-v7l+) Mar 31 08:18:17 volumio-db ifplugd(eth0)[671]: Using detection mode: SIOCETHTOOL Mar 31 08:18:17 volumio-db ifplugd(eth0)[671]: Initialization complete, link beat not detected. Mar 31 08:18:17 volumio-db ifplugd[612]: Network Interface Plugging Daemon...start eth0...done. Mar 31 08:18:17 volumio-db systemd[1]: Started LSB: Brings up/down network automatically. Mar 31 08:18:17 volumio-db systemd[1]: Starting Hostname Service... Mar 31 08:18:17 volumio-db systemd[1]: Reached target Bluetooth. Mar 31 08:18:17 volumio-db systemd[1]: Started Getty on tty1. Mar 31 08:18:17 volumio-db systemd[1]: Reached target Login Prompts. Mar 31 08:18:17 volumio-db cpufrequtils[651]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Mar 31 08:18:17 volumio-db systemd[1]: Started LSB: set CPUFreq kernel parameters. Mar 31 08:18:17 volumio-db systemd[1]: Reloading. Mar 31 08:18:17 volumio-db dbus-daemon[510]: [system] Successfully activated service 'org.freedesktop.hostname1' Mar 31 08:18:17 volumio-db ntpd[657]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 31 08:18:17 volumio-db nmbd[644]: [2024/03/31 08:18:17.792108, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Mar 31 08:18:17 volumio-db nmbd[644]: started asyncdns process 698 Mar 31 08:18:17 volumio-db nmbd[644]: [2024/03/31 08:18:17.793654, 0] ../lib/util/become_daemon.c:149(daemon_status) Mar 31 08:18:17 volumio-db nmbd[644]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Mar 31 08:18:17 volumio-db nmbd[644]: [2024/03/31 08:18:17.793777, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Mar 31 08:18:17 volumio-db nmbd[644]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Mar 31 08:18:17 volumio-db 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. Mar 31 08:18:17 volumio-db wireless.js[501]: Cleaning previous... Mar 31 08:18:17 volumio-db haveged[468]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Mar 31 08:18:17 volumio-db haveged[468]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Mar 31 08:18:17 volumio-db haveged[468]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99815 Mar 31 08:18:17 volumio-db haveged[468]: haveged: fills: 0, generated: 0 Mar 31 08:18:18 volumio-db systemd[1]: ifup@eth0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Mar 31 08:18:18 volumio-db systemd[1]: volumiossh.service: Current command vanished from the unit file, execution of the command list won't be resumed. Mar 31 08:18:18 volumio-db systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Mar 31 08:18:18 volumio-db systemd[1]: Started Hostname Service. Mar 31 08:18:18 volumio-db sudo[708]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 31 08:18:18 volumio-db sudo[708]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:18 volumio-db sudo[708]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:18 volumio-db sudo[710]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 31 08:18:18 volumio-db sudo[710]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:18 volumio-db systemd[1]: Reloading. Mar 31 08:18:18 volumio-db 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. Mar 31 08:18:18 volumio-db ntpd[657]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 31 08:18:18 volumio-db systemd[1]: Reloading. Mar 31 08:18:18 volumio-db sudo[710]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:18 volumio-db wireless.js[501]: Stopped aP Mar 31 08:18:18 volumio-db sudo[744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 31 08:18:18 volumio-db sudo[744]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:18 volumio-db sudo[744]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:18 volumio-db kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 31 08:18:18 volumio-db sudo[746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Mar 31 08:18:19 volumio-db sudo[746]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:19 volumio-db sudo[746]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:19 volumio-db 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. Mar 31 08:18:19 volumio-db sudo[753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 31 08:18:19 volumio-db sudo[753]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:19 volumio-db sudo[753]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:19 volumio-db sudo[755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Mar 31 08:18:19 volumio-db sudo[755]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:19 volumio-db systemd[1]: systemd-rfkill.service: Succeeded. Mar 31 08:18:19 volumio-db systemd[1]: volumiossh.service: Succeeded. Mar 31 08:18:19 volumio-db systemd[1]: Started Volumio SSH enabler. Mar 31 08:18:19 volumio-db ntpd[657]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 31 08:18:20 volumio-db volumio-remote-updater[523]: [2024-03-31 08:18:20] [info] asio async_connect error: system:111 (Connection refused) Mar 31 08:18:20 volumio-db volumio-remote-updater[523]: [2024-03-31 08:18:20] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 31 08:18:20 volumio-db volumio-remote-updater[523]: [2024-03-31 08:18:20] [error] handle_connect error: Underlying Transport Error Mar 31 08:18:20 volumio-db ntpd[657]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 31 08:18:20 volumio-db bthelper[545]: Changing power off succeeded Mar 31 08:18:20 volumio-db bthelper[545]: [CHG] Controller E4:5F:01:37:A7:6A Class: 0x0000041c Mar 31 08:18:20 volumio-db bthelper[545]: Changing power on succeeded Mar 31 08:18:21 volumio-db sudo[755]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:21 volumio-db wireless.js[501]: SETTING APPROPRIATE REG DOMAIN: IN Mar 31 08:18:21 volumio-db sudo[767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 31 08:18:21 volumio-db sudo[767]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:21 volumio-db sudo[767]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:21 volumio-db sudo[769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set IN Mar 31 08:18:21 volumio-db sudo[769]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:21 volumio-db sudo[769]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:21 volumio-db wireless.js[501]: SUCCESSFULLY SET NEW REGDOMAIN: IN Mar 31 08:18:21 volumio-db wireless.js[501]: Start wireless flow Mar 31 08:18:21 volumio-db wireless.js[501]: Stopped hotspot (if there).. Mar 31 08:18:21 volumio-db sudo[774]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 31 08:18:21 volumio-db sudo[774]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:22 volumio-db sudo[774]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:22 volumio-db sudo[776]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 31 08:18:22 volumio-db sudo[776]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:22 volumio-db sudo[776]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:22 volumio-db wireless.js[501]: DHCP IP Mar 31 08:18:22 volumio-db wireless.js[501]: Start ap Mar 31 08:18:22 volumio-db wpa_supplicant[778]: Successfully initialized wpa_supplicant Mar 31 08:18:22 volumio-db sudo[779]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Mar 31 08:18:22 volumio-db sudo[779]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:22 volumio-db kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 31 08:18:22 volumio-db dhcpcd[780]: dev: loaded udev Mar 31 08:18:22 volumio-db dhcpcd-run-hooks[798]: wlan0: starting wpa_supplicant Mar 31 08:18:22 volumio-db dhcpcd-run-hooks[803]: wlan0: failed to start wpa_supplicant Mar 31 08:18:22 volumio-db dhcpcd-run-hooks[804]: 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 Mar 31 08:18:22 volumio-db dhcpcd[780]: wlan0: connected to Access Point `' Mar 31 08:18:23 volumio-db dhcpcd[780]: no interfaces have a carrier Mar 31 08:18:23 volumio-db dhcpcd[780]: forked to background, child pid 814 Mar 31 08:18:23 volumio-db dhcpcd[814]: eth0: waiting for carrier Mar 31 08:18:23 volumio-db dhcpcd[814]: wlan0: waiting for carrier Mar 31 08:18:23 volumio-db sudo[779]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:23 volumio-db wireless.js[501]: trying... Mar 31 08:18:23 volumio-db sudo[816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 08:18:23 volumio-db sudo[816]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:23 volumio-db sudo[816]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:24 volumio-db wireless.js[501]: trying... Mar 31 08:18:24 volumio-db sudo[819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 08:18:24 volumio-db sudo[819]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:24 volumio-db sudo[819]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:25 volumio-db wpa_supplicant[802]: wlan0: Trying to associate with SSID 'pane24' Mar 31 08:18:25 volumio-db wireless.js[501]: trying... Mar 31 08:18:25 volumio-db sudo[822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 08:18:25 volumio-db sudo[822]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:25 volumio-db sudo[822]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:26 volumio-db wireless.js[501]: trying... Mar 31 08:18:26 volumio-db sudo[825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 08:18:26 volumio-db sudo[825]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:26 volumio-db sudo[825]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:27 volumio-db wireless.js[501]: trying... Mar 31 08:18:27 volumio-db sudo[828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 08:18:27 volumio-db sudo[828]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:27 volumio-db sudo[828]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:27 volumio-db volumio-remote-updater[523]: [2024-03-31 08:18:27] [info] asio async_connect error: system:111 (Connection refused) Mar 31 08:18:28 volumio-db volumio-remote-updater[523]: [2024-03-31 08:18:27] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 31 08:18:28 volumio-db volumio-remote-updater[523]: [2024-03-31 08:18:27] [error] handle_connect error: Underlying Transport Error Mar 31 08:18:28 volumio-db dhcpcd[814]: wlan0: carrier acquired Mar 31 08:18:28 volumio-db dhcpcd[814]: wlan0: connected to Access Point `pane24' Mar 31 08:18:28 volumio-db wpa_supplicant[802]: wlan0: Associated with c8:7f:54:e1:3c:60 Mar 31 08:18:28 volumio-db wpa_supplicant[802]: wlan0: CTRL-EVENT-CONNECTED - Connection to c8:7f:54:e1:3c:60 completed [id=0 id_str=] Mar 31 08:18:28 volumio-db wpa_supplicant[802]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Mar 31 08:18:28 volumio-db dhcpcd[814]: DUID 00:01:00:01:2b:1f:b5:bb:e4:5f:01:37:a7:64 Mar 31 08:18:28 volumio-db dhcpcd[814]: wlan0: IAID 01:37:a7:67 Mar 31 08:18:28 volumio-db dhcpcd[814]: wlan0: adding address fe80::4053:d7f8:693b:bd52 Mar 31 08:18:28 volumio-db dhcpcd[814]: ipv6_addaddr1: Permission denied Mar 31 08:18:28 volumio-db dhcpcd[814]: wlan0: carrier lost Mar 31 08:18:28 volumio-db systemd-udevd[830]: Process '/sbin/crda' failed with exit code 255. Mar 31 08:18:28 volumio-db dhcpcd[814]: wlan0: carrier acquired Mar 31 08:18:28 volumio-db dhcpcd[814]: wlan0: IAID 01:37:a7:67 Mar 31 08:18:28 volumio-db dhcpcd[814]: wlan0: soliciting an IPv6 router Mar 31 08:18:28 volumio-db wireless.js[501]: trying... Mar 31 08:18:28 volumio-db sudo[845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 08:18:28 volumio-db sudo[845]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:28 volumio-db sudo[845]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:28 volumio-db dhcpcd[814]: wlan0: soliciting a DHCP lease Mar 31 08:18:28 volumio-db dhcpcd[814]: wlan0: offered 192.168.50.120 from 192.168.50.1 Mar 31 08:18:28 volumio-db dhcpcd[814]: wlan0: probing address 192.168.50.120/24 Mar 31 08:18:29 volumio-db wireless.js[501]: trying... Mar 31 08:18:29 volumio-db sudo[848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 08:18:29 volumio-db sudo[848]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:29 volumio-db sudo[848]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:30 volumio-db wireless.js[501]: trying... Mar 31 08:18:30 volumio-db sudo[851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 08:18:30 volumio-db sudo[851]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:30 volumio-db sudo[851]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:31 volumio-db wpa_supplicant[802]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Mar 31 08:18:31 volumio-db wireless.js[501]: trying... Mar 31 08:18:31 volumio-db sudo[854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 08:18:31 volumio-db sudo[854]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:31 volumio-db sudo[854]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:32 volumio-db wireless.js[501]: trying... Mar 31 08:18:32 volumio-db sudo[857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 08:18:32 volumio-db sudo[857]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:32 volumio-db sudo[857]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:33 volumio-db wireless.js[501]: trying... Mar 31 08:18:33 volumio-db sudo[860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 08:18:33 volumio-db sudo[860]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:33 volumio-db sudo[860]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:34 volumio-db dhcpcd[814]: wlan0: leased 192.168.50.120 for 86400 seconds Mar 31 08:18:34 volumio-db avahi-daemon[503]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.50.120. Mar 31 08:18:34 volumio-db avahi-daemon[503]: New relevant interface wlan0.IPv4 for mDNS. Mar 31 08:18:34 volumio-db dhcpcd[814]: wlan0: adding route to 192.168.50.0/24 Mar 31 08:18:34 volumio-db avahi-daemon[503]: Registering new address record for 192.168.50.120 on wlan0.IPv4. Mar 31 08:18:34 volumio-db dhcpcd[814]: wlan0: adding default route via 192.168.50.1 Mar 31 08:18:34 volumio-db wireless.js[501]: trying... Mar 31 08:18:34 volumio-db sudo[883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 08:18:34 volumio-db sudo[883]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:34 volumio-db sudo[883]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:34 volumio-db wireless.js[501]: Connected to: ----pane24 Mar 31 08:18:34 volumio-db wireless.js[501]: ---- Mar 31 08:18:34 volumio-db sudo[886]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 08:18:34 volumio-db sudo[886]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:34 volumio-db sudo[886]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:34 volumio-db wireless.js[501]: ... joined AP, wlan0 IPv4 is 192.168.50.120, ipV6 is undefined Mar 31 08:18:34 volumio-db wireless.js[501]: It's done! AP Mar 31 08:18:34 volumio-db systemd[1]: Started Wireless Services. Mar 31 08:18:34 volumio-db systemd[1]: Started Volumio Backend Module. Mar 31 08:18:34 volumio-db kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 31 08:18:34 volumio-db systemd[1]: Started Volumio Cpu Tweaker. Mar 31 08:18:34 volumio-db volumio-cpu-tweak[892]: Setting RT Priority for mpd Mar 31 08:18:34 volumio-db volumio-cpu-tweak[892]: pid 35's current scheduling policy: SCHED_OTHER Mar 31 08:18:34 volumio-db volumio-cpu-tweak[892]: pid 35's current scheduling priority: 0 Mar 31 08:18:34 volumio-db volumio-cpu-tweak[892]: Setting MPD Affinity Mar 31 08:18:34 volumio-db volumio-cpu-tweak[892]: pid 3's current affinity mask: f Mar 31 08:18:34 volumio-db volumio-cpu-tweak[892]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Mar 31 08:18:34 volumio-db volumio-cpu-tweak[892]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Mar 31 08:18:34 volumio-db systemd[1]: volumio_cpu_tweak.service: Succeeded. Mar 31 08:18:35 volumio-db ntpd[657]: Listen normally on 3 wlan0 192.168.50.120:123 Mar 31 08:18:35 volumio-db ntpd[657]: new interface(s) found: waking up resolver Mar 31 08:18:36 volumio-db volumio[891]: info: ------------------------------------------- Mar 31 08:18:36 volumio-db volumio[891]: info: ----- Volumio3 ---- Mar 31 08:18:36 volumio-db volumio[891]: info: ------------------------------------------- Mar 31 08:18:36 volumio-db volumio[891]: info: ----- System startup ---- Mar 31 08:18:36 volumio-db volumio[891]: info: ------------------------------------------- Mar 31 08:18:37 volumio-db volumio[891]: info: MYVOLUMIO Environment detected Mar 31 08:18:37 volumio-db volumio[891]: info: Plugin folders cleanup Mar 31 08:18:37 volumio-db volumio[891]: info: Scanning into folder /volumio/app/plugins/ Mar 31 08:18:37 volumio-db volumio[891]: info: Scanning category audio_interface Mar 31 08:18:37 volumio-db volumio[891]: info: Scanning category miscellanea Mar 31 08:18:37 volumio-db volumio[891]: info: Scanning category music_service Mar 31 08:18:37 volumio-db volumio[891]: info: Scanning category plugins.json Mar 31 08:18:37 volumio-db volumio[891]: info: Scanning category system_controller Mar 31 08:18:37 volumio-db volumio[891]: info: Scanning category user_interface Mar 31 08:18:37 volumio-db volumio[891]: info: Scanning into folder /data/plugins/ Mar 31 08:18:37 volumio-db volumio[891]: info: Scanning category music_service Mar 31 08:18:37 volumio-db volumio[891]: info: Plugin folders cleanup completed Mar 31 08:18:37 volumio-db volumio[891]: info: ------------------------------------------- Mar 31 08:18:37 volumio-db volumio[891]: info: ----- Core plugins startup ---- Mar 31 08:18:37 volumio-db volumio[891]: info: ------------------------------------------- Mar 31 08:18:37 volumio-db volumio[891]: info: Loading plugins from folder /volumio/app/plugins/ Mar 31 08:18:37 volumio-db volumio[891]: info: Adding plugin upnp to MyMusic Plugins Mar 31 08:18:37 volumio-db volumio[891]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 31 08:18:37 volumio-db volumio[891]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 31 08:18:37 volumio-db volumio[891]: info: Loading plugins from folder /data/plugins/ Mar 31 08:18:37 volumio-db volumio[891]: info: Loading plugin "system"... Mar 31 08:18:37 volumio-db volumio[891]: info: Loading plugin "appearance"... Mar 31 08:18:37 volumio-db nmbd[644]: [2024/03/31 08:18:37.798872, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 31 08:18:37 volumio-db systemd[1]: Started Samba NMB Daemon. Mar 31 08:18:37 volumio-db nmbd[644]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 31 08:18:37 volumio-db systemd[1]: Starting Samba Winbind Daemon... Mar 31 08:18:37 volumio-db volumio-remote-updater[523]: [2024-03-31 08:18:37] [connect] Successful connection Mar 31 08:18:38 volumio-db winbindd[921]: [2024/03/31 08:18:38.078965, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 31 08:18:38 volumio-db winbindd[921]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 31 08:18:38 volumio-db winbindd[921]: [2024/03/31 08:18:38.104820, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 31 08:18:38 volumio-db winbindd[921]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 31 08:18:38 volumio-db systemd[1]: Started Samba Winbind Daemon. Mar 31 08:18:38 volumio-db systemd[1]: Starting Samba SMB Daemon... Mar 31 08:18:38 volumio-db volumio[891]: info: Loading plugin "network"... Mar 31 08:18:38 volumio-db volumio[891]: info: Refreshing Cached IP Addresses Mar 31 08:18:38 volumio-db sudo[928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 31 08:18:38 volumio-db sudo[928]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:38 volumio-db sudo[928]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:38 volumio-db sudo[930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 08:18:38 volumio-db volumio[891]: info: Loading plugin "services"... Mar 31 08:18:38 volumio-db sudo[930]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:38 volumio-db volumio[891]: info: Loading plugin "alsa_controller"... Mar 31 08:18:38 volumio-db sudo[930]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:38 volumio-db sudo[933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 31 08:18:38 volumio-db sudo[933]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:38 volumio-db volumio[891]: warn: Unable to locate the audio output device EarMen Angel. Please configure a valid output device. Mar 31 08:18:38 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 31 08:18:38 volumio-db volumio[891]: info: Loading plugin "wizard"... Mar 31 08:18:38 volumio-db volumio[891]: info: Loading plugin "networkfs"... Mar 31 08:18:38 volumio-db systemd[1]: Started Samba SMB Daemon. Mar 31 08:18:38 volumio-db smbd[926]: [2024/03/31 08:18:38.541519, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 31 08:18:38 volumio-db systemd[1]: Reached target Multi-User System. Mar 31 08:18:38 volumio-db volumio[891]: info: Starting Udev Watcher for removable devices Mar 31 08:18:38 volumio-db volumio[891]: info: Ignoring mount for partition: boot Mar 31 08:18:38 volumio-db volumio[891]: info: Ignoring mount for partition: volumio Mar 31 08:18:38 volumio-db volumio[891]: info: Ignoring mount for partition: volumio_data Mar 31 08:18:38 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 31 08:18:38 volumio-db volumio[891]: info: Loading plugin "volumio_command_line_client"... Mar 31 08:18:38 volumio-db volumio[891]: info: Loading plugin "upnp"... Mar 31 08:18:38 volumio-db smbd[926]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 31 08:18:38 volumio-db systemd[1]: Reached target Graphical Interface. Mar 31 08:18:38 volumio-db sudo[947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=mac,password=mac,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.100/Universe/Music/Library2 /mnt/NAS/Univ_Muisc_Lib Mar 31 08:18:38 volumio-db systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 31 08:18:38 volumio-db sudo[947]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:38 volumio-db volumio[891]: info: [1711873118606] Starting Upmpd Daemon Mar 31 08:18:38 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 31 08:18:38 volumio-db volumio[891]: info: Loading plugin "my_music"... Mar 31 08:18:38 volumio-db volumio[891]: info: Loading plugin "mpd"... Mar 31 08:18:38 volumio-db systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 31 08:18:38 volumio-db systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 31 08:18:38 volumio-db systemd[1]: Startup finished in 9.760s (kernel) + 30.368s (userspace) = 40.128s. Mar 31 08:18:38 volumio-db kernel: Key type cifs.spnego registered Mar 31 08:18:38 volumio-db kernel: Key type cifs.idmap registered Mar 31 08:18:38 volumio-db 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. Mar 31 08:18:38 volumio-db kernel: CIFS: Attempting to mount \\192.168.50.100\Universe Mar 31 08:18:38 volumio-db volumio[891]: info: Loading plugin "upnp_browser"... Mar 31 08:18:39 volumio-db volumio[891]: info: Loading plugin "alarm-clock"... Mar 31 08:18:39 volumio-db volumio[891]: info: Loading plugin "airplay_emulation"... Mar 31 08:18:39 volumio-db volumio[891]: info: Starting Shairport Sync Mar 31 08:18:39 volumio-db volumio[891]: info: Loading plugin "last_100"... Mar 31 08:18:39 volumio-db volumio[891]: info: Loading plugin "webradio"... Mar 31 08:18:39 volumio-db volumio[891]: info: Loading plugin "i2s_dacs"... Mar 31 08:18:39 volumio-db volumio[891]: info: I2S DAC not set, start Auto-detection Mar 31 08:18:39 volumio-db volumio[891]: info: Loading plugin "volumiodiscovery"... Mar 31 08:18:39 volumio-db volumio[891]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 31 08:18:39 volumio-db volumio[891]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 31 08:18:39 volumio-db node[891]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 31 08:18:39 volumio-db volumio[891]: *** WARNING *** For more information see Mar 31 08:18:39 volumio-db volumio[891]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 31 08:18:39 volumio-db volumio[891]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 31 08:18:39 volumio-db volumio[891]: *** WARNING *** For more information see Mar 31 08:18:39 volumio-db node[891]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 31 08:18:39 volumio-db node[891]: *** WARNING *** For more information see Mar 31 08:18:39 volumio-db node[891]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 31 08:18:39 volumio-db node[891]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 31 08:18:39 volumio-db node[891]: *** WARNING *** For more information see Mar 31 08:18:39 volumio-db volumio[891]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 31 08:18:39 volumio-db volumio[891]: info: Discovery: Started advertising with name: Volumio-db Mar 31 08:18:39 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 31 08:18:39 volumio-db volumio[891]: info: Loading plugin "spop"... Mar 31 08:18:40 volumio-db volumio[891]: info: Loading plugin "youtube2"... Mar 31 08:18:41 volumio-db volumio[891]: info: Loading plugin "ytmusic"... Mar 31 08:18:42 volumio-db sudo[933]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:42 volumio-db volumio[891]: info: Loading plugin "outputs"... Mar 31 08:18:42 volumio-db volumio[891]: info: Loading plugin "albumart"... Mar 31 08:18:42 volumio-db volumio[891]: info: Plugin example_plugin is not enabled Mar 31 08:18:42 volumio-db volumio[891]: info: Loading plugin "inputs"... Mar 31 08:18:42 volumio-db volumio[891]: info: Loading plugin "updater_comm"... Mar 31 08:18:42 volumio-db volumio[891]: info: Plugin mpdemulation is not enabled Mar 31 08:18:42 volumio-db volumio[891]: info: Loading plugin "rest_api"... Mar 31 08:18:42 volumio-db volumio[891]: info: Loading plugin "websocket"... Mar 31 08:18:42 volumio-db volumio[891]: info: Loading i18n strings for locale en Mar 31 08:18:42 volumio-db volumio[891]: Updating browse sources language Mar 31 08:18:42 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 31 08:18:42 volumio-db volumio[891]: Forking 3 albumart workers Mar 31 08:18:42 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 31 08:18:42 volumio-db volumio[891]: info: CoreCommandRouter::initPlayerControls Mar 31 08:18:42 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 08:18:42 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 08:18:42 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 08:18:42 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 08:18:42 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 08:18:42 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 08:18:42 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 08:18:42 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 08:18:42 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 31 08:18:42 volumio-db volumio[891]: Express server listening on port 3000 Mar 31 08:18:42 volumio-db volumio[891]: [Metrics] WebUI: 6s 978.47ms Mar 31 08:18:42 volumio-db volumio[891]: info: CoreStateMachine::resetVolumioState Mar 31 08:18:42 volumio-db volumio[891]: info: CoreStateMachine::getcurrentVolume Mar 31 08:18:42 volumio-db volumio[891]: info: CoreCommandRouter::volumioRetrievevolume Mar 31 08:18:42 volumio-db volumio[891]: info: CoreStateMachine::pushState Mar 31 08:18:42 volumio-db volumio[891]: info: CorePlayQueue::getTrack 0 Mar 31 08:18:42 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 08:18:42 volumio-db volumio[891]: info: CoreCommandRouter::volumioPushState Mar 31 08:18:42 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 08:18:42 volumio-db volumio-remote-updater[523]: [2024-03-31 08:18:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1711873117 101 Mar 31 08:18:42 volumio-db volumio[891]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Mar 31 08:18:43 volumio-db volumio[891]: info: Reloading queue from file Mar 31 08:18:43 volumio-db volumio[891]: info: CoreStateMachine::setRepeat true single undefined Mar 31 08:18:43 volumio-db volumio[891]: info: CoreStateMachine::pushState Mar 31 08:18:43 volumio-db volumio[891]: info: CorePlayQueue::getTrack 0 Mar 31 08:18:43 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 08:18:43 volumio-db volumio[891]: info: CoreCommandRouter::volumioPushState Mar 31 08:18:43 volumio-db volumio[891]: info: CoreStateMachine::setRandom undefined Mar 31 08:18:43 volumio-db volumio[891]: info: CoreStateMachine::pushState Mar 31 08:18:43 volumio-db volumio[891]: info: CorePlayQueue::getTrack 0 Mar 31 08:18:43 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 08:18:43 volumio-db volumio[891]: info: CoreCommandRouter::volumioPushState Mar 31 08:18:43 volumio-db volumio[891]: info: Setting Device type: Raspberry PI Mar 31 08:18:43 volumio-db volumio[891]: Starting albumart workers Mar 31 08:18:43 volumio-db volumio[891]: Starting albumart workers Mar 31 08:18:43 volumio-db volumio[891]: Starting albumart workers Mar 31 08:18:43 volumio-db sudo[1006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 31 08:18:43 volumio-db sudo[1006]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:43 volumio-db sudo[1007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 08:18:43 volumio-db sudo[1006]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:43 volumio-db sudo[1007]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:43 volumio-db sudo[1007]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:43 volumio-db volumio[891]: info: Discovery: adding 19d444b4-9e1d-4494-84ad-d23b732b20eb Mar 31 08:18:43 volumio-db volumio[891]: info: Discovery: Found device Volumio-db Mar 31 08:18:43 volumio-db volumio[891]: info: CoreCommandRouter::volumioGetState Mar 31 08:18:43 volumio-db volumio[891]: info: CorePlayQueue::getTrack 0 Mar 31 08:18:43 volumio-db volumio[891]: verbose: New Socket.io Connection to 192.168.50.120 from 192.168.50.23 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.4.1 Mobile/15E148 Safari/604.1 Total Clients: 2 Mar 31 08:18:43 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 31 08:18:43 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 31 08:18:43 volumio-db volumio[891]: info: CoreCommandRouter::volumioGetVisibleSources Mar 31 08:18:43 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 31 08:18:43 volumio-db volumio[891]: info: CoreCommandRouter::volumioGetState Mar 31 08:18:43 volumio-db volumio[891]: info: CorePlayQueue::getTrack 0 Mar 31 08:18:43 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 31 08:18:43 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 31 08:18:43 volumio-db volumio[891]: info: Received Get System Info Mar 31 08:18:43 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 31 08:18:43 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 31 08:18:43 volumio-db volumio[891]: info: Discovery: Getting this device information Mar 31 08:18:43 volumio-db volumio[891]: info: CoreCommandRouter::volumioGetState Mar 31 08:18:43 volumio-db volumio[891]: info: CorePlayQueue::getTrack 0 Mar 31 08:18:43 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 31 08:18:43 volumio-db volumio[891]: info: CoreCommandRouter::volumioGetState Mar 31 08:18:43 volumio-db volumio[891]: info: CorePlayQueue::getTrack 0 Mar 31 08:18:43 volumio-db volumio[891]: info: Listing playlists Mar 31 08:18:43 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 31 08:18:43 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 31 08:18:43 volumio-db sudo[947]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:44 volumio-db systemd[1]: systemd-fsckd.service: Succeeded. Mar 31 08:18:44 volumio-db sh[410]: timed out Mar 31 08:18:44 volumio-db dhcpcd[454]: timed out Mar 31 08:18:44 volumio-db sh[410]: dhcpcd exited Mar 31 08:18:44 volumio-db dhcpcd[454]: dhcpcd exited Mar 31 08:18:44 volumio-db sh[410]: ifup: failed to bring up eth0 Mar 31 08:18:44 volumio-db systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Mar 31 08:18:44 volumio-db systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Mar 31 08:18:45 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 31 08:18:45 volumio-db volumio[891]: info: Received Get System Info Mar 31 08:18:45 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 31 08:18:45 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 31 08:18:45 volumio-db volumio[891]: info: Discovery: Getting this device information Mar 31 08:18:45 volumio-db volumio[891]: info: CoreCommandRouter::volumioGetState Mar 31 08:18:45 volumio-db volumio[891]: info: CorePlayQueue::getTrack 0 Mar 31 08:18:45 volumio-db volumio[891]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 31 08:18:47 volumio-db volumio[891]: info: CoreCommandRouter::volumioGetQueue Mar 31 08:18:47 volumio-db volumio[891]: info: CoreStateMachine::getQueue Mar 31 08:18:47 volumio-db volumio[891]: info: CorePlayQueue::getQueue Mar 31 08:18:47 volumio-db systemd[1]: systemd-hostnamed.service: Succeeded. Mar 31 08:18:48 volumio-db sudo[1046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 31 08:18:48 volumio-db sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:48 volumio-db sudo[1046]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:48 volumio-db sudo[1048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 08:18:48 volumio-db sudo[1048]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:48 volumio-db sudo[1048]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:48 volumio-db sudo[1052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 31 08:18:48 volumio-db sudo[1052]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 08:18:48 volumio-db systemd[1]: Started UPnP Renderer front-end to MPD. Mar 31 08:18:48 volumio-db sudo[1052]: pam_unix(sudo:session): session closed for user root Mar 31 08:18:48 volumio-db volumio[891]: info: Upmpdcli Daemon Started Mar 31 08:18:48 volumio-db volumio[891]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Mar 31 08:18:49 volumio-db volumio[891]: info: Preload queue cleared Mar 31 08:18:49 volumio-db volumio[891]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 31 08:18:49 volumio-db volumio[891]: info: CoreStateMachine::ClearQueue Mar 31 08:18:49 volumio-db volumio[891]: info: CoreStateMachine::stop Mar 31 08:18:49 volumio-db volumio[891]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 31 08:18:49 volumio-db volumio[891]: info: CorePlayQueue::clearPlayQueue Mar 31 08:18:49 volumio-db volumio[891]: info: CorePlayQueue::saveQueue Mar 31 08:18:49 volumio-db volumio[891]: info: CoreCommandRouter::volumioPushQueue Mar 31 08:18:49 volumio-db volumio[891]: info: CoreStateMachine::addQueueItems Mar 31 08:18:49 volumio-db volumio[891]: info: CorePlayQueue::addQueueItems Mar 31 08:18:49 volumio-db volumio[891]: info: Preload queue cleared Mar 31 08:18:49 volumio-db volumio[891]: info: Adding Item to queue: music-library/NAS/Univ_Muisc_Lib/LossLess/PlayList/Test Tracks/04 Dhayaanchand.wav Mar 31 08:18:49 volumio-db volumio[891]: info: Exploding uri music-library/NAS/Univ_Muisc_Lib/LossLess/PlayList/Test Tracks/04 Dhayaanchand.wav in service mpd Mar 31 08:18:49 volumio-db volumio[891]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 31 08:18:49 volumio-db volumio[891]: TypeError: Cannot read property 'then' of undefined Mar 31 08:18:49 volumio-db volumio[891]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2527:19) Mar 31 08:18:49 volumio-db volumio[891]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2325:21) Mar 31 08:18:49 volumio-db volumio[891]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1359:16) Mar 31 08:18:49 volumio-db volumio[891]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Mar 31 08:18:49 volumio-db volumio[891]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Mar 31 08:18:49 volumio-db volumio[891]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Mar 31 08:18:49 volumio-db volumio[891]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Mar 31 08:18:49 volumio-db volumio[891]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:501:23) Mar 31 08:18:49 volumio-db volumio[891]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:477:17) Mar 31 08:18:49 volumio-db volumio[891]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:118:33) Mar 31 08:18:49 volumio-db volumio[891]: at Socket.emit (events.js:315:20) Mar 31 08:18:49 volumio-db volumio[891]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Mar 31 08:18:49 volumio-db volumio[891]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Mar 31 08:18:49 volumio-db volumio[891]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 31 08:18:50 volumio-db sudo[1064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-31 08:17 Mar 31 08:18:50 volumio-db sudo[1064]: 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"