-- Logs begin at Thu 2019-02-14 15:41:59 IST, end at Fri 2024-08-30 18:19:59 IST. -- Aug 30 18:19:07 pi kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 45 Aug 30 18:19:07 pi kernel: bcm2708_fb soc:fb: FB found 1 display(s) Aug 30 18:19:07 pi kernel: Console: switching to colour frame buffer device 80x30 Aug 30 18:19:07 pi kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Aug 30 18:19:07 pi kernel: iproc-rng200 fe104000.rng: hwrng registered Aug 30 18:19:07 pi kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Aug 30 18:19:07 pi kernel: brd: module loaded Aug 30 18:19:07 pi kernel: loop: module loaded Aug 30 18:19:07 pi kernel: Loading iSCSI transport class v2.0-870. Aug 30 18:19:07 pi kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Aug 30 18:19:07 pi kernel: Freeing initrd memory: 14316K Aug 30 18:19:07 pi kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Aug 30 18:19:07 pi kernel: usbcore: registered new device driver r8152-cfgselector Aug 30 18:19:07 pi kernel: usbcore: registered new interface driver r8152 Aug 30 18:19:07 pi kernel: usbcore: registered new interface driver lan78xx Aug 30 18:19:07 pi kernel: usbcore: registered new interface driver smsc95xx Aug 30 18:19:07 pi kernel: xhci_hcd 0000:01:00.0: enabling device (0140 -> 0142) Aug 30 18:19:07 pi kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Aug 30 18:19:07 pi kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Aug 30 18:19:07 pi kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0b00040000000890 Aug 30 18:19:07 pi kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Aug 30 18:19:07 pi kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Aug 30 18:19:07 pi kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Aug 30 18:19:07 pi kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01 Aug 30 18:19:07 pi kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Aug 30 18:19:07 pi kernel: usb usb1: Product: xHCI Host Controller Aug 30 18:19:07 pi kernel: usb usb1: Manufacturer: Linux 6.1.69-v7l+ xhci-hcd Aug 30 18:19:07 pi kernel: usb usb1: SerialNumber: 0000:01:00.0 Aug 30 18:19:07 pi kernel: hub 1-0:1.0: USB hub found Aug 30 18:19:07 pi kernel: hub 1-0:1.0: 1 port detected Aug 30 18:19:07 pi kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.01 Aug 30 18:19:07 pi kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Aug 30 18:19:07 pi kernel: usb usb2: Product: xHCI Host Controller Aug 30 18:19:07 pi kernel: usb usb2: Manufacturer: Linux 6.1.69-v7l+ xhci-hcd Aug 30 18:19:07 pi kernel: usb usb2: SerialNumber: 0000:01:00.0 Aug 30 18:19:07 pi kernel: hub 2-0:1.0: USB hub found Aug 30 18:19:07 pi kernel: hub 2-0:1.0: 4 ports detected Aug 30 18:19:07 pi kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Aug 30 18:19:07 pi kernel: dwc_otg: FIQ enabled Aug 30 18:19:07 pi kernel: dwc_otg: NAK holdoff enabled Aug 30 18:19:07 pi kernel: dwc_otg: FIQ split-transaction FSM enabled Aug 30 18:19:07 pi kernel: Module dwc_common_port init Aug 30 18:19:07 pi kernel: usbcore: registered new interface driver uas Aug 30 18:19:07 pi kernel: usbcore: registered new interface driver usb-storage Aug 30 18:19:07 pi kernel: mousedev: PS/2 mouse device common for all mice Aug 30 18:19:07 pi kernel: sdhci: Secure Digital Host Controller Interface driver Aug 30 18:19:07 pi kernel: sdhci: Copyright(c) Pierre Ossman Aug 30 18:19:07 pi kernel: sdhci-pltfm: SDHCI platform and OF driver helper Aug 30 18:19:07 pi kernel: ledtrig-cpu: registered to indicate activity on CPUs Aug 30 18:19:07 pi kernel: hid: raw HID events driver (C) Jiri Kosina Aug 30 18:19:07 pi kernel: usbcore: registered new interface driver usbhid Aug 30 18:19:07 pi kernel: usbhid: USB HID core driver Aug 30 18:19:07 pi kernel: Initializing XFRM netlink socket Aug 30 18:19:07 pi kernel: NET: Registered PF_PACKET protocol family Aug 30 18:19:07 pi kernel: Key type dns_resolver registered Aug 30 18:19:07 pi kernel: Registering SWP/SWPB emulation handler Aug 30 18:19:07 pi kernel: registered taskstats version 1 Aug 30 18:19:07 pi kernel: Loading compiled-in X.509 certificates Aug 30 18:19:07 pi kernel: Key type .fscrypt registered Aug 30 18:19:07 pi kernel: Key type fscrypt-provisioning registered Aug 30 18:19:07 pi kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Aug 30 18:19:07 pi kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Aug 30 18:19:07 pi kernel: fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 51, base_baud = 0) is a PL011 rev2 Aug 30 18:19:07 pi kernel: serial serial0: tty port ttyAMA1 registered Aug 30 18:19:07 pi kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Aug 30 18:19:07 pi kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Aug 30 18:19:07 pi kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Aug 30 18:19:07 pi kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Aug 30 18:19:07 pi kernel: of_cfs_init Aug 30 18:19:07 pi kernel: of_cfs_init: OK Aug 30 18:19:07 pi kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Aug 30 18:19:07 pi kernel: Freeing unused kernel image (initmem) memory: 2048K Aug 30 18:19:07 pi kernel: Run /init as init process Aug 30 18:19:07 pi kernel: with arguments: Aug 30 18:19:07 pi kernel: /init Aug 30 18:19:07 pi kernel: splash Aug 30 18:19:07 pi kernel: nodebug Aug 30 18:19:07 pi kernel: with environment: Aug 30 18:19:07 pi kernel: HOME=/ Aug 30 18:19:07 pi kernel: TERM=linux Aug 30 18:19:07 pi kernel: imgpart=UUID=0c3a77ec-ae84-4f1b-8028-c61cc251ffe5 Aug 30 18:19:07 pi kernel: imgfile=/volumio_current.sqsh Aug 30 18:19:07 pi kernel: bootpart=UUID=41CA-9A8B Aug 30 18:19:07 pi kernel: datapart=UUID=13212a15-0bce-40eb-a3c9-8cfa25d22574 Aug 30 18:19:07 pi kernel: uuidconfig=cmdline.txt Aug 30 18:19:07 pi kernel: pcie_aspm=off Aug 30 18:19:07 pi kernel: bootdelay=7 Aug 30 18:19:07 pi kernel: use_kmsg=no Aug 30 18:19:07 pi kernel: mmc1: new high speed SDIO card at address 0001 Aug 30 18:19:07 pi kernel: mmc0: new ultra high speed DDR50 SDHC card at address aaaa Aug 30 18:19:07 pi kernel: mmcblk0: mmc0:aaaa SC16G 14.8 GiB Aug 30 18:19:07 pi kernel: mmcblk0: p1 p2 p3 Aug 30 18:19:07 pi kernel: mmcblk0: mmc0:aaaa SC16G 14.8 GiB (quirks 0x00004000) Aug 30 18:19:07 pi kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Aug 30 18:19:07 pi kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Aug 30 18:19:07 pi kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Aug 30 18:19:07 pi kernel: usb 1-1: Product: USB2.0 Hub Aug 30 18:19:07 pi kernel: hub 1-1:1.0: USB hub found Aug 30 18:19:07 pi kernel: hub 1-1:1.0: 4 ports detected Aug 30 18:19:07 pi kernel: usb 1-1.2: new high-speed USB device number 3 using xhci_hcd Aug 30 18:19:07 pi kernel: usb 1-1.2: New USB device found, idVendor=2d87, idProduct=c002, bcdDevice= 2.01 Aug 30 18:19:07 pi kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Aug 30 18:19:07 pi kernel: usb 1-1.2: Product: Cayin RU7 Aug 30 18:19:07 pi kernel: usb 1-1.2: Manufacturer: Cayin Aug 30 18:19:07 pi kernel: usb 1-1.2: SerialNumber: Cayin RU7 Aug 30 18:19:07 pi kernel: fuse: init (API version 7.37) Aug 30 18:19:07 pi kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 30 18:19:07 pi kernel: EXT4-fs (mmcblk0p2): recovery complete Aug 30 18:19:07 pi kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none. Aug 30 18:19:07 pi kernel: loop0: detected capacity change from 0 to 983360 Aug 30 18:19:07 pi kernel: EXT4-fs (mmcblk0p3): recovery complete Aug 30 18:19:07 pi kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Quota mode: none. Aug 30 18:19:07 pi systemd[1]: System time before build time, advancing clock. Aug 30 18:19:07 pi kernel: NET: Registered PF_INET6 protocol family Aug 30 18:19:07 pi kernel: Segment Routing with IPv6 Aug 30 18:19:07 pi kernel: In-situ OAM (IOAM) with IPv6 Aug 30 18:19:07 pi systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Aug 30 18:19:07 pi systemd[1]: Detected architecture arm. Aug 30 18:19:07 pi systemd[1]: Set hostname to . Aug 30 18:19:07 pi systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 30 18:19:07 pi systemd[1]: Listening on udev Control Socket. Aug 30 18:19:07 pi systemd[1]: Listening on Journal Audit Socket. Aug 30 18:19:07 pi systemd[1]: Listening on fsck to fsckd communication Socket. Aug 30 18:19:07 pi systemd[1]: Listening on Journal Socket (/dev/log). Aug 30 18:19:07 pi systemd[1]: Created slice system-systemd\x2dfsck.slice. Aug 30 18:19:07 pi systemd[1]: Started Forward Password Requests to Wall Directory Watch. Aug 30 18:19:07 pi systemd-journald[310]: Journal started Aug 30 18:19:07 pi systemd-journald[310]: Runtime journal (/run/log/journal/d5012141e8efab18115b4334669e938a) is 7.5M, max 30.0M, 22.5M free. Aug 30 18:19:07 pi fake-hwclock[308]: Fri Aug 30 12:49:07 UTC 2024 Aug 30 18:19:07 pi systemd[1]: Mounted POSIX Message Queue File System. Aug 30 18:19:07 pi systemd-modules-load[326]: Inserted module 'i2c_dev' Aug 30 18:19:07 pi kernel: i2c_dev: i2c /dev entries driver Aug 30 18:19:07 pi systemd[1]: Started Load Kernel Modules. Aug 30 18:19:07 pi systemd[1]: Started Load/Save Random Seed. Aug 30 18:19:07 pi systemd[1]: Starting Apply Kernel Variables... Aug 30 18:19:07 pi systemd[1]: Mounting FUSE Control File System... Aug 30 18:19:07 pi systemd[1]: Mounting Kernel Configuration File System... Aug 30 18:19:07 pi systemd[1]: dynamicswap.service: Succeeded. Aug 30 18:19:07 pi systemd[1]: Started Create System Users. Aug 30 18:19:07 pi systemd[1]: Starting Create Static Device Nodes in /dev... Aug 30 18:19:07 pi systemd[1]: Mounted FUSE Control File System. Aug 30 18:19:07 pi systemd[1]: Mounted Kernel Configuration File System. Aug 30 18:19:07 pi systemd[1]: Started Apply Kernel Variables. Aug 30 18:19:07 pi systemd[1]: Started Create Static Device Nodes in /dev. Aug 30 18:19:07 pi systemd[1]: Starting udev Kernel Device Manager... Aug 30 18:19:07 pi systemd[1]: Reached target Local File Systems (Pre). Aug 30 18:19:07 pi systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Aug 30 18:19:07 pi systemd[1]: Mounting /tmp... Aug 30 18:19:07 pi systemd[1]: Mounting /var/spool/cups... Aug 30 18:19:07 pi systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Aug 30 18:19:07 pi systemd[1]: Mounting /var/log... Aug 30 18:19:07 pi systemd[1]: Mounted /tmp. Aug 30 18:19:07 pi systemd[1]: Mounted /var/spool/cups. Aug 30 18:19:07 pi systemd[1]: Mounted /var/log. Aug 30 18:19:07 pi systemd[1]: Starting Flush Journal to Persistent Storage... Aug 30 18:19:07 pi systemd[1]: Mounting /var/spool/cups/tmp... Aug 30 18:19:07 pi systemd[1]: Started udev Coldplug all Devices. Aug 30 18:19:07 pi systemd-udevd[345]: Network interface NamePolicy= disabled on kernel command line, ignoring. Aug 30 18:19:07 pi systemd[1]: Starting Helper to synchronize boot up for ifupdown... Aug 30 18:19:07 pi systemd[1]: Mounted /var/spool/cups/tmp. Aug 30 18:19:07 pi systemd-journald[310]: Runtime journal (/run/log/journal/d5012141e8efab18115b4334669e938a) is 7.5M, max 30.0M, 22.5M free. Aug 30 18:19:07 pi systemd[1]: Started Helper to synchronize boot up for ifupdown. Aug 30 18:19:07 pi systemd[1]: Started Flush Journal to Persistent Storage. Aug 30 18:19:07 pi systemd[1]: Started udev Kernel Device Manager. Aug 30 18:19:07 pi systemd[1]: Starting Show Plymouth Boot Screen... Aug 30 18:19:07 pi systemd[1]: plymouth-start.service: Succeeded. Aug 30 18:19:07 pi systemd[1]: Started Show Plymouth Boot Screen. Aug 30 18:19:07 pi systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Aug 30 18:19:07 pi systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Aug 30 18:19:07 pi systemd[1]: Reached target Paths. Aug 30 18:19:07 pi systemd[1]: Reached target Local Encrypted Volumes. Aug 30 18:19:07 pi systemd-udevd[387]: Using default interface naming scheme 'v240'. Aug 30 18:19:07 pi kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Aug 30 18:19:07 pi kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Aug 30 18:19:08 pi systemd-udevd[387]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Aug 30 18:19:08 pi kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Aug 30 18:19:08 pi kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Aug 30 18:19:08 pi kernel: [vc_sm_connected_init]: start Aug 30 18:19:08 pi kernel: [vc_sm_connected_init]: installed successfully Aug 30 18:19:08 pi kernel: mc: Linux media interface: v0.10 Aug 30 18:19:08 pi systemd[1]: Found device /dev/disk/by-uuid/41CA-9A8B. Aug 30 18:19:08 pi systemd-udevd[386]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Aug 30 18:19:08 pi kernel: videodev: Linux video capture interface: v2.00 Aug 30 18:19:08 pi kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Aug 30 18:19:08 pi kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Aug 30 18:19:08 pi kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Aug 30 18:19:08 pi kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Aug 30 18:19:08 pi kernel: Bluetooth: Core ver 2.22 Aug 30 18:19:08 pi kernel: NET: Registered PF_BLUETOOTH protocol family Aug 30 18:19:08 pi kernel: Bluetooth: HCI device and connection manager initialized Aug 30 18:19:08 pi kernel: Bluetooth: HCI socket layer initialized Aug 30 18:19:08 pi kernel: Bluetooth: L2CAP socket layer initialized Aug 30 18:19:08 pi kernel: Bluetooth: SCO socket layer initialized Aug 30 18:19:08 pi kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Aug 30 18:19:08 pi kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Aug 30 18:19:08 pi kernel: usbcore: registered new interface driver brcmfmac Aug 30 18:19:08 pi kernel: Bluetooth: HCI UART driver ver 2.3 Aug 30 18:19:08 pi kernel: Bluetooth: HCI UART protocol H4 registered Aug 30 18:19:08 pi kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Aug 30 18:19:08 pi kernel: Bluetooth: HCI UART protocol Broadcom registered Aug 30 18:19:08 pi kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Aug 30 18:19:08 pi kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Aug 30 18:19:09 pi kernel: uart-pl011 fe201000.serial: no DMA platform data Aug 30 18:19:09 pi kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Aug 30 18:19:09 pi kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Aug 30 18:19:09 pi kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Aug 30 18:19:09 pi kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Aug 30 18:19:09 pi kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Aug 30 18:19:09 pi kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Aug 30 18:19:09 pi kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Aug 30 18:19:09 pi kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Aug 30 18:19:09 pi kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov 1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60 Aug 30 18:19:09 pi kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Aug 30 18:19:09 pi kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Aug 30 18:19:09 pi kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Aug 30 18:19:09 pi kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Aug 30 18:19:09 pi kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Aug 30 18:19:09 pi kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Aug 30 18:19:09 pi kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Aug 30 18:19:09 pi kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Aug 30 18:19:09 pi kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Aug 30 18:19:09 pi kernel: rpivid feb10000.codec: Device registered as /dev/video19 Aug 30 18:19:09 pi kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Aug 30 18:19:09 pi kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Aug 30 18:19:09 pi kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Aug 30 18:19:09 pi kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Aug 30 18:19:09 pi kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Aug 30 18:19:09 pi kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Aug 30 18:19:09 pi kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Aug 30 18:19:09 pi kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Aug 30 18:19:09 pi kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Aug 30 18:19:09 pi kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Aug 30 18:19:09 pi kernel: Bluetooth: hci0: BCM: chip id 107 Aug 30 18:19:09 pi kernel: Bluetooth: hci0: BCM: features 0x2f Aug 30 18:19:09 pi kernel: Bluetooth: hci0: BCM4345C0 Aug 30 18:19:09 pi kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Aug 30 18:19:09 pi kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Aug 30 18:19:09 pi kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Aug 30 18:19:09 pi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Aug 30 18:19:09 pi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Aug 30 18:19:09 pi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Aug 30 18:19:09 pi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Aug 30 18:19:09 pi systemd-udevd[367]: Using default interface naming scheme 'v240'. Aug 30 18:19:09 pi systemd-udevd[367]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Aug 30 18:19:09 pi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Aug 30 18:19:09 pi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Aug 30 18:19:09 pi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Aug 30 18:19:09 pi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Aug 30 18:19:09 pi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Aug 30 18:19:09 pi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Aug 30 18:19:09 pi kernel: usb 1-1.2: 1:4 : unsupported format bits 0x100000000 Aug 30 18:19:09 pi kernel: usbcore: registered new interface driver snd-usb-audio Aug 30 18:19:10 pi kernel: Bluetooth: hci0: BCM: features 0x2f Aug 30 18:19:10 pi kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Aug 30 18:19:10 pi kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Aug 30 18:19:10 pi systemd-udevd[357]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Aug 30 18:19:10 pi systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Aug 30 18:19:10 pi systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Aug 30 18:19:10 pi systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Aug 30 18:19:10 pi systemd[1]: Condition check resulted in Huge Pages File System being skipped. Aug 30 18:19:10 pi systemd[1]: Created slice system-bthelper.slice. Aug 30 18:19:10 pi systemd[1]: Starting Show Plymouth Boot Screen... Aug 30 18:19:10 pi systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Aug 30 18:19:10 pi systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Aug 30 18:19:10 pi systemd[1]: Starting File System Check on /dev/disk/by-uuid/41CA-9A8B... Aug 30 18:19:10 pi systemd[1]: Starting Load/Save RF Kill Switch Status... Aug 30 18:19:10 pi systemd[1]: plymouth-start.service: Succeeded. Aug 30 18:19:10 pi systemd[1]: Started Show Plymouth Boot Screen. Aug 30 18:19:10 pi systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Aug 30 18:19:10 pi systemd[1]: Started File System Check Daemon to report status. Aug 30 18:19:10 pi systemd[1]: Started Load/Save RF Kill Switch Status. Aug 30 18:19:11 pi systemd-fsck[514]: fsck.fat 4.1 (2017-01-24) Aug 30 18:19:11 pi systemd-fsck[514]: Unexpected long filename sequence number (2 vs. expected 1). Aug 30 18:19:11 pi systemd-fsck[514]: It could be that just the number is wrong Aug 30 18:19:11 pi systemd-fsck[514]: if "network-confi" seems to match "g". Aug 30 18:19:11 pi systemd-fsck[514]: Not auto-correcting this. Aug 30 18:19:11 pi systemd-fsck[514]: /dev/mmcblk0p1: 449 files, 135823/184582 clusters Aug 30 18:19:11 pi systemd[1]: Started File System Check on /dev/disk/by-uuid/41CA-9A8B. Aug 30 18:19:11 pi systemd[1]: Mounting /boot... Aug 30 18:19:11 pi systemd[1]: Mounted /boot. Aug 30 18:19:11 pi systemd[1]: Reached target Local File Systems. Aug 30 18:19:11 pi systemd[1]: Starting Raise network interfaces... Aug 30 18:19:11 pi systemd[1]: Starting Preprocess NFS configuration... Aug 30 18:19:11 pi systemd[1]: Started ifup for wlan0. Aug 30 18:19:11 pi systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Aug 30 18:19:11 pi systemd[1]: Started ifup for eth0. Aug 30 18:19:11 pi systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Aug 30 18:19:11 pi systemd[1]: Starting Create Volatile Files and Directories... Aug 30 18:19:11 pi systemd[1]: plymouth-read-write.service: Succeeded. Aug 30 18:19:11 pi systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Aug 30 18:19:11 pi systemd[1]: Received SIGRTMIN+20 from PID 205 (plymouthd). Aug 30 18:19:11 pi systemd[1]: nfs-config.service: Succeeded. Aug 30 18:19:11 pi systemd[1]: Started Preprocess NFS configuration. Aug 30 18:19:11 pi systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Aug 30 18:19:11 pi systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Aug 30 18:19:11 pi systemd[1]: Reached target NFS client services. Aug 30 18:19:11 pi systemd[1]: Started Create Volatile Files and Directories. Aug 30 18:19:11 pi systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Aug 30 18:19:11 pi systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Aug 30 18:19:11 pi systemd[1]: Starting Update UTMP about System Boot/Shutdown... Aug 30 18:19:11 pi systemd[1]: Starting RPC bind portmap service... Aug 30 18:19:11 pi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 30 18:19:11 pi kernel: 8021q: 802.1Q VLAN Support v1.8 Aug 30 18:19:11 pi systemd[1]: Started RPC bind portmap service. Aug 30 18:19:11 pi systemd[1]: Reached target RPC Port Mapper. Aug 30 18:19:11 pi systemd[1]: Reached target Remote File Systems (Pre). Aug 30 18:19:11 pi systemd[1]: Reached target Remote File Systems. Aug 30 18:19:11 pi systemd[1]: Started Update UTMP about System Boot/Shutdown. Aug 30 18:19:11 pi systemd[1]: Reached target System Initialization. Aug 30 18:19:11 pi systemd[1]: Listening on triggerhappy.socket. Aug 30 18:19:11 pi systemd[1]: Started Daily apt download activities. Aug 30 18:19:11 pi systemd[1]: Started Daily Cleanup of Temporary Directories. Aug 30 18:19:11 pi systemd[1]: Started Daily apt upgrade and clean activities. Aug 30 18:19:11 pi systemd[1]: Reached target Timers. Aug 30 18:19:11 pi systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Aug 30 18:19:11 pi systemd[1]: Listening on D-Bus System Message Bus Socket. Aug 30 18:19:11 pi systemd[1]: Reached target Sockets. Aug 30 18:19:11 pi systemd[1]: Reached target Basic System. Aug 30 18:19:11 pi systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Aug 30 18:19:11 pi systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Aug 30 18:19:11 pi systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Aug 30 18:19:11 pi systemd[1]: Started Volumio Iptables Module. Aug 30 18:19:11 pi systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Aug 30 18:19:11 pi systemd[1]: Starting Configure Bluetooth Modems connected by UART... Aug 30 18:19:11 pi systemd[1]: Started Manage Sound Card State (restore and store). Aug 30 18:19:11 pi systemd[1]: Starting Login Service... Aug 30 18:19:11 pi systemd[1]: Starting Wireless Services... Aug 30 18:19:11 pi alsactl[637]: alsactl 1.1.8 daemon started Aug 30 18:19:11 pi systemd[1]: Started Volumio Log Rotation Service. Aug 30 18:19:11 pi systemd[1]: Started D-Bus System Message Bus. Aug 30 18:19:11 pi systemd[1]: Starting triggerhappy global hotkey daemon... Aug 30 18:19:11 pi systemd[1]: Started UPnP Renderer front-end to MPD. Aug 30 18:19:11 pi sh[529]: eth0: waiting for carrier Aug 30 18:19:11 pi dhcpcd[575]: eth0: waiting for carrier Aug 30 18:19:11 pi kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Aug 30 18:19:11 pi kernel: bcmgenet fd580000.ethernet eth0: Link is Down Aug 30 18:19:11 pi systemd[1]: Starting WPA supplicant... Aug 30 18:19:11 pi systemd[1]: Starting Save/Restore Sound Card State... Aug 30 18:19:11 pi systemd[1]: Started volumio-remote-updater.service. Aug 30 18:19:11 pi systemd[1]: Starting dhcpcd on all interfaces... Aug 30 18:19:11 pi systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Aug 30 18:19:12 pi systemd[1]: hciuart.service: Succeeded. Aug 30 18:19:12 pi thd[647]: Unable to parse trigger line: Aug 30 18:19:12 pi thd[647]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Aug 30 18:19:12 pi thd[647]: Unable to parse trigger line: Aug 30 18:19:12 pi thd[647]: Found socket passed from systemd Aug 30 18:19:12 pi systemd[1]: Started Configure Bluetooth Modems connected by UART. Aug 30 18:19:12 pi dhcpcd[658]: Not running dhcpcd because /etc/network/interfaces Aug 30 18:19:12 pi dhcpcd[658]: defines some interfaces that will use a Aug 30 18:19:12 pi dhcpcd[658]: DHCP client or static address Aug 30 18:19:12 pi systemd[1]: Started triggerhappy global hotkey daemon. Aug 30 18:19:12 pi systemd-logind[638]: New seat seat0. Aug 30 18:19:12 pi systemd[1]: Started Save/Restore Sound Card State. Aug 30 18:19:12 pi systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Aug 30 18:19:12 pi systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Aug 30 18:19:12 pi systemd[1]: Failed to start dhcpcd on all interfaces. Aug 30 18:19:12 pi systemd[1]: Reached target Sound Card. Aug 30 18:19:12 pi systemd[1]: Starting Raspberry Pi bluetooth helper... Aug 30 18:19:12 pi avahi-daemon[659]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Aug 30 18:19:12 pi avahi-daemon[659]: Successfully dropped root privileges. Aug 30 18:19:12 pi avahi-daemon[659]: avahi-daemon 0.7 starting up. Aug 30 18:19:12 pi sh[526]: wlan0=wlan0 Aug 30 18:19:12 pi volumio-remote-updater[657]: Error: No active session Aug 30 18:19:12 pi volumio-remote-updater[657]: [2024-08-30 18:19:12] [info] asio async_connect error: system:111 (Connection refused) Aug 30 18:19:12 pi volumio-remote-updater[657]: [2024-08-30 18:19:12] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 30 18:19:12 pi volumio-remote-updater[657]: [2024-08-30 18:19:12] [error] handle_connect error: Underlying Transport Error Aug 30 18:19:12 pi bthelper[666]: Raspberry Pi BDADDR already set Aug 30 18:19:12 pi systemd[1]: Started Raise network interfaces. Aug 30 18:19:12 pi avahi-daemon[659]: Successfully called chroot(). Aug 30 18:19:12 pi systemd[1]: Started Raspberry Pi bluetooth helper. Aug 30 18:19:12 pi avahi-daemon[659]: Successfully dropped remaining capabilities. Aug 30 18:19:12 pi avahi-daemon[659]: Loading service file /services/volumio.service. Aug 30 18:19:12 pi systemd[1]: Started Login Service. Aug 30 18:19:12 pi systemd[1]: Started Avahi mDNS/DNS-SD Stack. Aug 30 18:19:12 pi wpa_supplicant[652]: Successfully initialized wpa_supplicant Aug 30 18:19:12 pi systemd[1]: Started WPA supplicant. Aug 30 18:19:12 pi avahi-daemon[659]: Network interface enumeration completed. Aug 30 18:19:12 pi avahi-daemon[659]: Server startup complete. Host name is pi.local. Local service cookie is 3170394250. Aug 30 18:19:12 pi avahi-daemon[659]: Service "pi" (/services/volumio.service) successfully established. Aug 30 18:19:12 pi systemd[1]: Starting Bluetooth service... Aug 30 18:19:12 pi systemd[1]: Reached target Network. Aug 30 18:19:12 pi systemd[1]: Starting Network Time Service... Aug 30 18:19:12 pi systemd[1]: Starting Permit User Sessions... Aug 30 18:19:12 pi systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Aug 30 18:19:12 pi volumio[649]: Could not open config: /tmp/upmpdcli.conf Aug 30 18:19:12 pi systemd[1]: Starting OpenBSD Secure Shell server... Aug 30 18:19:12 pi systemd[1]: Reached target Network is Online. Aug 30 18:19:12 pi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 30 18:19:12 pi systemd[1]: Starting LSB: Brings up/down network automatically... Aug 30 18:19:12 pi systemd[1]: Starting Samba NMB Daemon... Aug 30 18:19:12 pi systemd[1]: Starting /etc/rc.local Compatibility... Aug 30 18:19:13 pi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 30 18:19:13 pi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 30 18:19:13 pi systemd[1]: Started Permit User Sessions. Aug 30 18:19:13 pi systemd[1]: Started /etc/rc.local Compatibility. Aug 30 18:19:13 pi bluetoothd[718]: Bluetooth daemon 5.50 Aug 30 18:19:13 pi systemd[1]: Starting Terminate Plymouth Boot Screen... Aug 30 18:19:13 pi systemd[1]: Starting Hold until boot process finishes up... Aug 30 18:19:13 pi bluetoothd[718]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Aug 30 18:19:13 pi systemd[1]: Started Bluetooth service. Aug 30 18:19:13 pi bluetoothd[718]: Starting SDP server Aug 30 18:19:13 pi bluetoothd[718]: Excluding (cli) sap Aug 30 18:19:13 pi systemd[1]: Received SIGRTMIN+21 from PID 205 (plymouthd). Aug 30 18:19:13 pi systemd[1]: Reached target Bluetooth. Aug 30 18:19:13 pi loadcpufreq[617]: Loading cpufreq kernel modules...done (none). Aug 30 18:19:13 pi systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Aug 30 18:19:13 pi ntpd[721]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 30 18:19:13 pi ntpd[721]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Aug 30 18:19:13 pi systemd[1]: plymouth-quit.service: Succeeded. Aug 30 18:19:13 pi systemd[1]: Started Terminate Plymouth Boot Screen. Aug 30 18:19:13 pi systemd[1]: plymouth-quit-wait.service: Succeeded. Aug 30 18:19:13 pi systemd[1]: Started Hold until boot process finishes up. Aug 30 18:19:13 pi systemd[1]: Received SIGRTMIN+21 from PID 205 (n/a). Aug 30 18:19:13 pi systemd[1]: Started Network Time Service. Aug 30 18:19:13 pi ntpd[762]: proto: precision = 2.092 usec (-19) Aug 30 18:19:13 pi ntpd[762]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 30 18:19:13 pi ntpd[762]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 30 18:19:13 pi ntpd[762]: Listen and drop on 0 v6wildcard [::]:123 Aug 30 18:19:13 pi ntpd[762]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 30 18:19:13 pi ntpd[762]: Listen normally on 2 lo 127.0.0.1:123 Aug 30 18:19:13 pi systemd[1]: Started Getty on tty1. Aug 30 18:19:13 pi systemd[1]: Reached target Login Prompts. Aug 30 18:19:13 pi ntpd[762]: Listening on routing socket on fd #19 for interface updates Aug 30 18:19:13 pi ntpd[762]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 30 18:19:13 pi ntpd[762]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 30 18:19:13 pi kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Aug 30 18:19:13 pi kernel: Bluetooth: BNEP filters: protocol multicast Aug 30 18:19:13 pi kernel: Bluetooth: BNEP socket layer initialized Aug 30 18:19:13 pi kernel: Bluetooth: MGMT ver 1.22 Aug 30 18:19:13 pi systemd[1]: Starting LSB: set CPUFreq kernel parameters... Aug 30 18:19:13 pi bluetoothd[718]: Bluetooth management interface 1.22 initialized Aug 30 18:19:13 pi ifplugd(eth0)[782]: ifplugd 0.28 initializing. Aug 30 18:19:13 pi ifplugd(eth0)[782]: Using interface eth0/DC:A6:32:85:1B:AB with driver (version: 6.1.69-v7l+) Aug 30 18:19:13 pi ifplugd(eth0)[782]: Using detection mode: SIOCETHTOOL Aug 30 18:19:13 pi ifplugd(eth0)[782]: Initialization complete, link beat not detected. Aug 30 18:19:13 pi dbus-daemon[643]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=718 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Aug 30 18:19:13 pi sshd[765]: Server listening on 0.0.0.0 port 22. Aug 30 18:19:13 pi sshd[765]: Server listening on :: port 22. Aug 30 18:19:13 pi ifplugd[738]: Network Interface Plugging Daemon...start eth0...done. Aug 30 18:19:13 pi bluetoothd[718]: Failed to set privacy: Rejected (0x0b) Aug 30 18:19:13 pi systemd[1]: Started OpenBSD Secure Shell server. Aug 30 18:19:13 pi systemd[1]: Started LSB: Brings up/down network automatically. Aug 30 18:19:13 pi systemd[1]: iptables.service: Succeeded. Aug 30 18:19:13 pi systemd[1]: Starting Hostname Service... Aug 30 18:19:14 pi cpufrequtils[780]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Aug 30 18:19:14 pi systemd[1]: Started LSB: set CPUFreq kernel parameters. Aug 30 18:19:14 pi dbus-daemon[643]: [system] Successfully activated service 'org.freedesktop.hostname1' Aug 30 18:19:14 pi systemd[1]: Started Hostname Service. Aug 30 18:19:14 pi ntpd[762]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 30 18:19:14 pi nmbd[747]: [2024/08/30 18:19:14.426475, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Aug 30 18:19:14 pi nmbd[747]: started asyncdns process 804 Aug 30 18:19:14 pi nmbd[747]: [2024/08/30 18:19:14.428368, 0] ../lib/util/become_daemon.c:149(daemon_status) Aug 30 18:19:14 pi nmbd[747]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Aug 30 18:19:14 pi nmbd[747]: [2024/08/30 18:19:14.428498, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Aug 30 18:19:14 pi nmbd[747]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Aug 30 18:19:14 pi haveged[571]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Aug 30 18:19:14 pi haveged[571]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Aug 30 18:19:14 pi haveged[571]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00094 Aug 30 18:19:14 pi haveged[571]: haveged: fills: 0, generated: 0 Aug 30 18:19:14 pi wireless.js[640]: Cleaning previous... Aug 30 18:19:14 pi sudo[812]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 30 18:19:14 pi sudo[812]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:14 pi sudo[812]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:14 pi sudo[814]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 30 18:19:14 pi sudo[814]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:15 pi ntpd[762]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 30 18:19:15 pi sudo[814]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:15 pi wireless.js[640]: Stopped aP Aug 30 18:19:15 pi sudo[821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 30 18:19:15 pi sudo[821]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:15 pi sudo[821]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:15 pi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 30 18:19:15 pi sudo[823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Aug 30 18:19:15 pi sudo[823]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:15 pi sudo[823]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:15 pi sudo[830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 30 18:19:15 pi sudo[830]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:15 pi sudo[830]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:15 pi sudo[832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Aug 30 18:19:15 pi sudo[832]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:15 pi systemd[1]: systemd-rfkill.service: Succeeded. Aug 30 18:19:16 pi ntpd[762]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 30 18:19:17 pi ntpd[762]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 30 18:19:17 pi volumio-remote-updater[657]: [2024-08-30 18:19:17] [info] asio async_connect error: system:111 (Connection refused) Aug 30 18:19:17 pi volumio-remote-updater[657]: [2024-08-30 18:19:17] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 30 18:19:17 pi volumio-remote-updater[657]: [2024-08-30 18:19:17] [error] handle_connect error: Underlying Transport Error Aug 30 18:19:17 pi bthelper[666]: Changing power off succeeded Aug 30 18:19:17 pi bthelper[666]: [CHG] Controller DC:A6:32:85:1B:AE Class: 0x0000041c Aug 30 18:19:17 pi bthelper[666]: Changing power on succeeded Aug 30 18:19:18 pi sudo[832]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:18 pi wireless.js[640]: SETTING APPROPRIATE REG DOMAIN: IN Aug 30 18:19:18 pi sudo[844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 30 18:19:18 pi sudo[844]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:18 pi sudo[844]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:18 pi sudo[846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set IN Aug 30 18:19:18 pi sudo[846]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:18 pi sudo[846]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:18 pi wireless.js[640]: SUCCESSFULLY SET NEW REGDOMAIN: IN Aug 30 18:19:18 pi wireless.js[640]: Start wireless flow Aug 30 18:19:18 pi wireless.js[640]: Stopped hotspot (if there).. Aug 30 18:19:18 pi sudo[851]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 30 18:19:18 pi sudo[851]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:18 pi sudo[851]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:18 pi sudo[853]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 30 18:19:18 pi sudo[853]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:18 pi sudo[853]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:18 pi wireless.js[640]: DHCP IP Aug 30 18:19:18 pi wireless.js[640]: Start ap Aug 30 18:19:18 pi wpa_supplicant[855]: Successfully initialized wpa_supplicant Aug 30 18:19:18 pi sudo[856]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Aug 30 18:19:18 pi sudo[856]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:18 pi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 30 18:19:18 pi dhcpcd[857]: dev: loaded udev Aug 30 18:19:18 pi dhcpcd-run-hooks[875]: wlan0: starting wpa_supplicant Aug 30 18:19:19 pi dhcpcd-run-hooks[880]: wlan0: failed to start wpa_supplicant Aug 30 18:19:19 pi dhcpcd-run-hooks[881]: 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 Aug 30 18:19:19 pi dhcpcd[857]: wlan0: connected to Access Point `' Aug 30 18:19:19 pi dhcpcd[857]: no interfaces have a carrier Aug 30 18:19:19 pi dhcpcd[857]: forked to background, child pid 891 Aug 30 18:19:19 pi dhcpcd[891]: eth0: waiting for carrier Aug 30 18:19:19 pi dhcpcd[891]: wlan0: waiting for carrier Aug 30 18:19:19 pi sudo[856]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:19 pi wireless.js[640]: trying... Aug 30 18:19:19 pi sudo[893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 30 18:19:19 pi sudo[893]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:19 pi sudo[893]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:20 pi wireless.js[640]: trying... Aug 30 18:19:20 pi sudo[896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 30 18:19:20 pi sudo[896]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:20 pi sudo[896]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:21 pi wpa_supplicant[879]: wlan0: Trying to associate with SSID 'RS' Aug 30 18:19:21 pi wireless.js[640]: trying... Aug 30 18:19:21 pi sudo[899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 30 18:19:21 pi sudo[899]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:21 pi sudo[899]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:22 pi wireless.js[640]: trying... Aug 30 18:19:22 pi sudo[902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 30 18:19:22 pi sudo[902]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:22 pi sudo[902]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:23 pi wireless.js[640]: trying... Aug 30 18:19:23 pi sudo[905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 30 18:19:23 pi sudo[905]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:23 pi sudo[905]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:24 pi dhcpcd[891]: wlan0: carrier acquired Aug 30 18:19:24 pi dhcpcd[891]: wlan0: connected to Access Point `RS' Aug 30 18:19:24 pi wpa_supplicant[879]: wlan0: Associated with d8:07:b6:cd:55:c7 Aug 30 18:19:24 pi wpa_supplicant[879]: wlan0: CTRL-EVENT-CONNECTED - Connection to d8:07:b6:cd:55:c7 completed [id=0 id_str=] Aug 30 18:19:24 pi wpa_supplicant[879]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 30 18:19:24 pi dhcpcd[891]: DUID 00:01:00:01:2e:31:51:dd:dc:a6:32:85:1b:ab Aug 30 18:19:24 pi dhcpcd[891]: wlan0: IAID 32:85:1b:ac Aug 30 18:19:24 pi dhcpcd[891]: wlan0: adding address fe80::3d4d:e791:2ad6:cdc9 Aug 30 18:19:24 pi dhcpcd[891]: ipv6_addaddr1: Permission denied Aug 30 18:19:24 pi dhcpcd[891]: wlan0: carrier lost Aug 30 18:19:24 pi dhcpcd[891]: wlan0: carrier acquired Aug 30 18:19:24 pi dhcpcd[891]: wlan0: IAID 32:85:1b:ac Aug 30 18:19:24 pi dhcpcd[891]: wlan0: soliciting an IPv6 router Aug 30 18:19:24 pi wireless.js[640]: trying... Aug 30 18:19:24 pi sudo[920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 30 18:19:24 pi sudo[920]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:24 pi sudo[920]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:24 pi dhcpcd[891]: wlan0: rebinding lease of 192.168.0.10 Aug 30 18:19:25 pi dhcpcd[891]: wlan0: probing address 192.168.0.10/24 Aug 30 18:19:25 pi volumio-remote-updater[657]: [2024-08-30 18:19:25] [info] asio async_connect error: system:111 (Connection refused) Aug 30 18:19:25 pi volumio-remote-updater[657]: [2024-08-30 18:19:25] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 30 18:19:25 pi volumio-remote-updater[657]: [2024-08-30 18:19:25] [error] handle_connect error: Underlying Transport Error Aug 30 18:19:25 pi wireless.js[640]: trying... Aug 30 18:19:25 pi sudo[923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 30 18:19:25 pi sudo[923]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:25 pi sudo[923]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:26 pi wireless.js[640]: trying... Aug 30 18:19:26 pi sudo[926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 30 18:19:26 pi sudo[926]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:26 pi sudo[926]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:27 pi wireless.js[640]: trying... Aug 30 18:19:27 pi sudo[929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 30 18:19:27 pi sudo[929]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:27 pi sudo[929]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:28 pi wireless.js[640]: trying... Aug 30 18:19:28 pi sudo[932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 30 18:19:28 pi sudo[932]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:28 pi sudo[932]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:29 pi wireless.js[640]: trying... Aug 30 18:19:29 pi sudo[935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 30 18:19:29 pi sudo[935]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:29 pi sudo[935]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:30 pi dhcpcd[891]: wlan0: leased 192.168.0.10 for infinity Aug 30 18:19:30 pi avahi-daemon[659]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.10. Aug 30 18:19:30 pi dhcpcd[891]: wlan0: adding route to 192.168.0.0/24 Aug 30 18:19:30 pi dhcpcd[891]: wlan0: adding default route via 192.168.0.1 Aug 30 18:19:30 pi avahi-daemon[659]: New relevant interface wlan0.IPv4 for mDNS. Aug 30 18:19:30 pi avahi-daemon[659]: Registering new address record for 192.168.0.10 on wlan0.IPv4. Aug 30 18:19:30 pi wireless.js[640]: trying... Aug 30 18:19:30 pi sudo[958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 30 18:19:30 pi sudo[958]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:30 pi sudo[958]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:30 pi wireless.js[640]: Connected to: ----RS Aug 30 18:19:30 pi wireless.js[640]: ---- Aug 30 18:19:30 pi sudo[961]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 18:19:30 pi sudo[961]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:30 pi sudo[961]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:30 pi wireless.js[640]: ... joined AP, wlan0 IPv4 is 192.168.0.10, ipV6 is undefined Aug 30 18:19:30 pi wireless.js[640]: It's done! AP Aug 30 18:19:31 pi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 30 18:19:31 pi systemd[1]: Started Wireless Services. Aug 30 18:19:31 pi systemd[1]: Started Volumio Backend Module. Aug 30 18:19:31 pi systemd[1]: Started Volumio Cpu Tweaker. Aug 30 18:19:31 pi volumio-cpu-tweak[967]: Setting RT Priority for mpd Aug 30 18:19:31 pi volumio-cpu-tweak[967]: pid 35's current scheduling policy: SCHED_OTHER Aug 30 18:19:31 pi volumio-cpu-tweak[967]: pid 35's current scheduling priority: 0 Aug 30 18:19:31 pi volumio-cpu-tweak[967]: Setting MPD Affinity Aug 30 18:19:31 pi volumio-cpu-tweak[967]: pid 3's current affinity mask: f Aug 30 18:19:31 pi volumio-cpu-tweak[967]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Aug 30 18:19:31 pi volumio-cpu-tweak[967]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Aug 30 18:19:31 pi systemd[1]: volumio_cpu_tweak.service: Succeeded. Aug 30 18:19:31 pi ntpd[762]: Listen normally on 3 wlan0 192.168.0.10:123 Aug 30 18:19:31 pi ntpd[762]: new interface(s) found: waking up resolver Aug 30 18:19:32 pi volumio[966]: info: ------------------------------------------- Aug 30 18:19:32 pi volumio[966]: info: ----- Volumio3 ---- Aug 30 18:19:32 pi volumio[966]: info: ------------------------------------------- Aug 30 18:19:32 pi volumio[966]: info: ----- System startup ---- Aug 30 18:19:32 pi volumio[966]: info: ------------------------------------------- Aug 30 18:19:33 pi volumio[966]: info: MYVOLUMIO Environment detected Aug 30 18:19:33 pi volumio[966]: info: Plugin folders cleanup Aug 30 18:19:33 pi volumio[966]: info: Scanning into folder /volumio/app/plugins/ Aug 30 18:19:33 pi volumio[966]: info: Scanning category audio_interface Aug 30 18:19:33 pi volumio[966]: info: Scanning category miscellanea Aug 30 18:19:33 pi volumio[966]: info: Scanning category music_service Aug 30 18:19:33 pi volumio[966]: info: Scanning category plugins.json Aug 30 18:19:33 pi volumio[966]: info: Scanning category system_controller Aug 30 18:19:33 pi volumio[966]: info: Scanning category user_interface Aug 30 18:19:33 pi volumio[966]: info: Scanning into folder /data/plugins/ Aug 30 18:19:33 pi volumio[966]: info: Scanning category audio_interface Aug 30 18:19:33 pi volumio[966]: info: Scanning category music_service Aug 30 18:19:33 pi volumio[966]: info: Plugin folders cleanup completed Aug 30 18:19:33 pi volumio[966]: info: ------------------------------------------- Aug 30 18:19:33 pi volumio[966]: info: ----- Core plugins startup ---- Aug 30 18:19:33 pi volumio[966]: info: ------------------------------------------- Aug 30 18:19:33 pi volumio[966]: info: Loading plugins from folder /volumio/app/plugins/ Aug 30 18:19:33 pi volumio[966]: info: Adding plugin upnp to MyMusic Plugins Aug 30 18:19:33 pi volumio[966]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 30 18:19:33 pi volumio[966]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 30 18:19:33 pi volumio[966]: info: Loading plugins from folder /data/plugins/ Aug 30 18:19:33 pi volumio[966]: info: Loading plugin "system"... Aug 30 18:19:33 pi volumio[966]: info: Loading plugin "appearance"... Aug 30 18:19:34 pi nmbd[747]: [2024/08/30 18:19:34.435392, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 30 18:19:34 pi nmbd[747]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Aug 30 18:19:34 pi systemd[1]: Started Samba NMB Daemon. Aug 30 18:19:34 pi systemd[1]: Starting Samba Winbind Daemon... Aug 30 18:19:34 pi volumio[966]: info: Loading plugin "network"... Aug 30 18:19:34 pi volumio[966]: info: Refreshing Cached IP Addresses Aug 30 18:19:34 pi winbindd[996]: [2024/08/30 18:19:34.731232, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Aug 30 18:19:34 pi winbindd[996]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Aug 30 18:19:34 pi sudo[999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 18:19:34 pi sudo[999]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:34 pi volumio[966]: info: Loading plugin "services"... Aug 30 18:19:34 pi sudo[999]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:34 pi sudo[1002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 18:19:34 pi sudo[1002]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:34 pi volumio[966]: info: Loading plugin "alsa_controller"... Aug 30 18:19:34 pi winbindd[996]: [2024/08/30 18:19:34.753844, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 30 18:19:34 pi winbindd[996]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Aug 30 18:19:34 pi systemd[1]: Started Samba Winbind Daemon. Aug 30 18:19:34 pi sudo[1002]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:34 pi sudo[1005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 30 18:19:34 pi sudo[1005]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:34 pi systemd[1]: Starting Samba SMB Daemon... Aug 30 18:19:34 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 18:19:34 pi volumio[966]: info: Loading plugin "wizard"... Aug 30 18:19:34 pi volumio[966]: info: Loading plugin "networkfs"... Aug 30 18:19:34 pi volumio[966]: info: Starting Udev Watcher for removable devices Aug 30 18:19:34 pi sudo[1016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=saurav,password=sur9618ama,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.3/t5 /mnt/NAS/t5 Aug 30 18:19:34 pi sudo[1016]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:34 pi volumio[966]: info: Ignoring mount for partition: boot Aug 30 18:19:34 pi volumio[966]: info: Ignoring mount for partition: volumio Aug 30 18:19:34 pi volumio[966]: info: Ignoring mount for partition: volumio_data Aug 30 18:19:34 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 18:19:34 pi volumio[966]: info: Loading plugin "volumio_command_line_client"... Aug 30 18:19:34 pi volumio[966]: info: Loading plugin "upnp"... Aug 30 18:19:34 pi volumio[966]: info: [1725022174994] Starting Upmpd Daemon Aug 30 18:19:34 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 18:19:34 pi volumio[966]: info: Loading plugin "my_music"... Aug 30 18:19:35 pi volumio[966]: info: Loading plugin "mpd"... Aug 30 18:19:35 pi volumio-remote-updater[657]: [2024-08-30 18:19:35] [connect] Successful connection Aug 30 18:19:35 pi kernel: Key type cifs.spnego registered Aug 30 18:19:35 pi kernel: Key type cifs.idmap registered Aug 30 18:19:35 pi kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Aug 30 18:19:35 pi kernel: CIFS: Attempting to mount \\192.168.0.3\t5 Aug 30 18:19:35 pi smbd[1014]: [2024/08/30 18:19:35.184090, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 30 18:19:35 pi systemd[1]: Started Samba SMB Daemon. Aug 30 18:19:35 pi smbd[1014]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Aug 30 18:19:35 pi systemd[1]: Reached target Multi-User System. Aug 30 18:19:35 pi systemd[1]: Reached target Graphical Interface. Aug 30 18:19:35 pi systemd[1]: Starting Update UTMP about System Runlevel Changes... Aug 30 18:19:35 pi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Aug 30 18:19:35 pi systemd[1]: Started Update UTMP about System Runlevel Changes. Aug 30 18:19:35 pi systemd[1]: Startup finished in 14.257s (kernel) + 30.343s (userspace) = 44.601s. Aug 30 18:19:35 pi volumio[966]: info: Loading plugin "upnp_browser"... Aug 30 18:19:35 pi volumio[966]: info: Loading plugin "alarm-clock"... Aug 30 18:19:35 pi volumio[966]: info: Loading plugin "airplay_emulation"... Aug 30 18:19:35 pi volumio[966]: info: Starting Shairport Sync Aug 30 18:19:35 pi volumio[966]: info: Loading plugin "last_100"... Aug 30 18:19:35 pi volumio[966]: info: Loading plugin "webradio"... Aug 30 18:19:35 pi volumio[966]: info: Loading plugin "i2s_dacs"... Aug 30 18:19:35 pi volumio[966]: info: I2S DAC not set, start Auto-detection Aug 30 18:19:35 pi volumio[966]: info: Loading plugin "volumiodiscovery"... Aug 30 18:19:35 pi volumio[966]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 30 18:19:35 pi volumio[966]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 18:19:35 pi node[966]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 30 18:19:35 pi volumio[966]: *** WARNING *** For more information see Aug 30 18:19:35 pi volumio[966]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 30 18:19:35 pi volumio[966]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 18:19:35 pi volumio[966]: *** WARNING *** For more information see Aug 30 18:19:35 pi node[966]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 18:19:35 pi node[966]: *** WARNING *** For more information see Aug 30 18:19:35 pi node[966]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 30 18:19:35 pi node[966]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 18:19:35 pi node[966]: *** WARNING *** For more information see Aug 30 18:19:35 pi volumio[966]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 30 18:19:35 pi volumio[966]: info: Discovery: Started advertising with name: pi Aug 30 18:19:36 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 18:19:36 pi volumio[966]: info: Loading plugin "spop"... Aug 30 18:19:37 pi volumio[966]: info: Loading plugin "outputs"... Aug 30 18:19:37 pi volumio[966]: info: Loading plugin "albumart"... Aug 30 18:19:37 pi volumio[966]: info: Plugin example_plugin is not enabled Aug 30 18:19:37 pi volumio[966]: info: Loading plugin "inputs"... Aug 30 18:19:37 pi volumio[966]: info: Loading plugin "updater_comm"... Aug 30 18:19:37 pi volumio[966]: info: Plugin mpdemulation is not enabled Aug 30 18:19:37 pi volumio[966]: info: Loading plugin "rest_api"... Aug 30 18:19:37 pi volumio[966]: info: Loading plugin "websocket"... Aug 30 18:19:37 pi volumio[966]: info: Starting Socket.io Server version 2.3.0 Aug 30 18:19:37 pi volumio[966]: info: Plugin fusiondsp is not enabled Aug 30 18:19:37 pi volumio[966]: info: Loading i18n strings for locale en Aug 30 18:19:37 pi volumio[966]: Updating browse sources language Aug 30 18:19:37 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 18:19:37 pi volumio[966]: Forking 3 albumart workers Aug 30 18:19:37 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 18:19:37 pi volumio[966]: info: CoreCommandRouter::initPlayerControls Aug 30 18:19:37 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:37 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:37 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:37 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:37 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:37 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:37 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:37 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:37 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 18:19:37 pi volumio[966]: Express server listening on port 3000 Aug 30 18:19:37 pi volumio[966]: [Metrics] WebUI: 5s 379.99ms Aug 30 18:19:37 pi volumio[966]: info: CoreStateMachine::resetVolumioState Aug 30 18:19:37 pi volumio[966]: info: CoreStateMachine::getcurrentVolume Aug 30 18:19:37 pi volumio[966]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 18:19:37 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 18:19:37 pi volumio-remote-updater[657]: [2024-08-30 18:19:37] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1725022175 101 Aug 30 18:19:37 pi volumio[966]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Aug 30 18:19:37 pi volumio[966]: verbose: New Socket.io Connection to 192.168.0.10 from 192.168.0.15 UA: Mozilla/5.0 (iPad; CPU OS 12_5_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 2 Aug 30 18:19:37 pi volumio[966]: verbose: New Socket.io Connection to 192.168.0.10 from 192.168.0.15 UA: Mozilla/5.0 (iPad; CPU OS 12_5_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 3 Aug 30 18:19:37 pi volumio[966]: Starting albumart workers Aug 30 18:19:37 pi volumio[966]: info: Reloading queue from file Aug 30 18:19:37 pi volumio[966]: info: Setting Device type: Raspberry PI Aug 30 18:19:37 pi volumio[966]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 30 18:19:37 pi volumio[966]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Aug 30 18:19:37 pi volumio[966]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 30 18:19:37 pi volumio[966]: info: CoreStateMachine::setRepeat null single undefined Aug 30 18:19:37 pi volumio[966]: info: CoreStateMachine::pushState Aug 30 18:19:37 pi volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 18:19:37 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 18:19:37 pi volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 18:19:37 pi volumio[966]: info: CoreStateMachine::setRandom null Aug 30 18:19:37 pi volumio[966]: info: CoreStateMachine::pushState Aug 30 18:19:37 pi volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 18:19:37 pi volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 18:19:37 pi volumio[966]: Starting albumart workers Aug 30 18:19:38 pi volumio[966]: info: VolumeController:: Volume=100 Mute =false Aug 30 18:19:38 pi volumio[966]: info: CoreStateMachine::pushState Aug 30 18:19:38 pi volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 18:19:38 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 18:19:38 pi volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 18:19:38 pi volumio[966]: info: CoreStateMachine::updateTrackBlock Aug 30 18:19:38 pi volumio[966]: info: CorePlayQueue::getTrackBlock Aug 30 18:19:38 pi volumio[966]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 18:19:38 pi volumio[966]: Starting albumart workers Aug 30 18:19:38 pi volumio[966]: info: VolumeController:: Volume=100 Mute =false Aug 30 18:19:38 pi volumio[966]: info: CoreStateMachine::pushState Aug 30 18:19:38 pi volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 18:19:38 pi volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 18:19:38 pi sudo[1005]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:41 pi systemd[1]: systemd-fsckd.service: Succeeded. Aug 30 18:19:41 pi sudo[1016]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:41 pi volumio[966]: info: Cannot mount NAS t5 at system boot, trial number 1 ,retrying in 5 seconds Aug 30 18:19:41 pi volumio[966]: info: Completed loading Core Plugins Aug 30 18:19:41 pi volumio[966]: info: Preparing to generate the ALSA configuration file Aug 30 18:19:41 pi kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 30 18:19:41 pi kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Aug 30 18:19:41 pi volumio[966]: info: Asound.conf file unchanged, so no further update is needed Aug 30 18:19:41 pi volumio[966]: info: Output device has changed, restarting MPD Aug 30 18:19:41 pi sudo[1104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 18:19:41 pi volumio[966]: info: Output device has changed, restarting Shairport Sync Aug 30 18:19:41 pi sudo[1104]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 18:19:41 pi sudo[1104]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:41 pi sudo[1106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 18:19:41 pi sudo[1106]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:41 pi volumio[966]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 18:19:41 pi volumio[966]: info: ___________ START PLUGINS ___________ Aug 30 18:19:41 pi volumio[966]: info: ControllerMpd::onStart: Initializing MPD Aug 30 18:19:41 pi volumio[966]: info: Creating MPD Configuration file Aug 30 18:19:41 pi sudo[1114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 18:19:41 pi sudo[1114]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 18:19:41 pi volumio[966]: info: [1725022181489] CoreMusicLibrary::Adding element Media Servers Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 18:19:41 pi sudo[1114]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:41 pi systemd[1]: Listening on mpd.socket. Aug 30 18:19:41 pi sudo[1116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 18:19:41 pi systemd[1]: Starting Music Player Daemon... Aug 30 18:19:41 pi sudo[1116]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 18:19:41 pi systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 30 18:19:41 pi systemd[1]: mpd.service: Succeeded. Aug 30 18:19:41 pi systemd[1]: Stopped Music Player Daemon. Aug 30 18:19:41 pi systemd[1]: Starting Music Player Daemon... Aug 30 18:19:41 pi volumio[966]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 18:19:41 pi sudo[1123]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 18:19:41 pi sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:41 pi volumio[966]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 18:19:41 pi volumio[966]: info: [1725022181597] CoreMusicLibrary::Adding element Last_100 Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 18:19:41 pi volumio[966]: info: [1725022181600] CoreMusicLibrary::Adding element Webradio Aug 30 18:19:41 pi sudo[1123]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 18:19:41 pi sudo[1123]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 18:19:41 pi volumio[966]: info: Initializing BBC Radios Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 18:19:41 pi volumio[966]: info: Creating Spotify config file Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:41 pi volumio[966]: info: Volumio Calling Home Aug 30 18:19:41 pi volumio[966]: verbose: New Socket.io Connection to 192.168.0.10 from 192.168.0.15 UA: Mozilla/5.0 (iPad; CPU OS 12_5_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 4 Aug 30 18:19:41 pi volumio[966]: info: MPD Permissions set Aug 30 18:19:41 pi volumio[966]: info: MPD Permissions set Aug 30 18:19:41 pi volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 30 18:19:41 pi volumio[966]: info: Spotify config file written Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:41 pi sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 30 18:19:41 pi sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:41 pi dhcpcd[575]: timed out Aug 30 18:19:41 pi sh[529]: timed out Aug 30 18:19:41 pi sh[529]: dhcpcd exited Aug 30 18:19:41 pi dhcpcd[575]: dhcpcd exited Aug 30 18:19:41 pi sh[529]: ifup: failed to bring up eth0 Aug 30 18:19:41 pi systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Aug 30 18:19:41 pi systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:19:41 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:41 pi volumio[966]: info: Starting Shairport Sync Aug 30 18:19:41 pi volumio[966]: info: Starting Shairport Sync Aug 30 18:19:41 pi systemd[1]: Started go-librespot Daemon. Aug 30 18:19:41 pi go-librespot[1151]: Librespot-go daemon starting... Aug 30 18:19:41 pi volumio[966]: info: Starting Shairport Sync Aug 30 18:19:41 pi sudo[1143]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:41 pi sudo[1156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 18:19:41 pi sudo[1159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 18:19:41 pi sudo[1159]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:41 pi sudo[1156]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:42 pi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 30 18:19:42 pi sudo[1163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 18:19:42 pi sudo[1163]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:42 pi systemd[1]: shairport-sync.service: Succeeded. Aug 30 18:19:42 pi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 30 18:19:42 pi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 30 18:19:42 pi sudo[1159]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:42 pi volumio[966]: info: Shairport-Sync Started Aug 30 18:19:42 pi volumio[966]: Error adding Membership: Error: addMembership EINVAL Aug 30 18:19:42 pi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 30 18:19:42 pi systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 30 18:19:42 pi systemd[1]: shairport-sync.service: Succeeded. Aug 30 18:19:42 pi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 30 18:19:42 pi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 30 18:19:42 pi sudo[1156]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:42 pi volumio[966]: info: Shairport-Sync Started Aug 30 18:19:42 pi sudo[1163]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:42 pi volumio[966]: info: Shairport-Sync Started Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 18:19:42 pi volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 18:19:42 pi volumio[966]: info: Received Get System Info Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 18:19:42 pi volumio[966]: info: Discovery: Getting this device information Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 18:19:42 pi volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 18:19:42 pi volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 18:19:42 pi volumio[966]: info: Listing playlists Aug 30 18:19:42 pi volumio[966]: info: CoreCommandRouter::volumioGetQueue Aug 30 18:19:42 pi volumio[966]: info: CoreStateMachine::getQueue Aug 30 18:19:42 pi volumio[966]: info: CorePlayQueue::getQueue Aug 30 18:19:42 pi go-librespot[1151]: time="2024-08-30T18:19:42+05:30" level=info msg="generated new device id: 56bf467aebea78a297ad66a7ab0285d84b8f8580" Aug 30 18:19:43 pi mpd[1128]: Aug 30 18:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 30 18:19:43 pi systemd[1]: Started Music Player Daemon. Aug 30 18:19:43 pi sudo[1116]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:43 pi sudo[1106]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:43 pi volumio[966]: info: Completed starting Core Plugins Aug 30 18:19:43 pi volumio[966]: info: ------------------------------------------- Aug 30 18:19:43 pi volumio[966]: info: ----- MyVolumio plugins startup ---- Aug 30 18:19:43 pi volumio[966]: info: ------------------------------------------- Aug 30 18:19:43 pi volumio[966]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 30 18:19:43 pi volumio[966]: info: MPD running with PID1128 Aug 30 18:19:43 pi volumio[966]: ,establishing connection Aug 30 18:19:43 pi volumio[966]: error: MPD error: The expression evaluated to a falsy value: Aug 30 18:19:43 pi volumio[966]: assert.ok(self.idling) Aug 30 18:19:43 pi volumio[966]: error: The expression evaluated to a falsy value: Aug 30 18:19:43 pi volumio[966]: assert.ok(self.idling) Aug 30 18:19:43 pi volumio[966]: error: MPD error: The expression evaluated to a falsy value: Aug 30 18:19:43 pi volumio[966]: assert.ok(self.idling) Aug 30 18:19:43 pi volumio[966]: error: The expression evaluated to a falsy value: Aug 30 18:19:43 pi volumio[966]: assert.ok(self.idling) Aug 30 18:19:43 pi volumio[966]: error: updateQueue error: null Aug 30 18:19:44 pi systemd[1]: systemd-hostnamed.service: Succeeded. Aug 30 18:19:44 pi volumio[966]: info: go-librespot daemon successfully initialized Aug 30 18:19:45 pi sudo[1181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 18:19:45 pi sudo[1181]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:45 pi sudo[1181]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:45 pi sudo[1183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 18:19:45 pi sudo[1183]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:45 pi sudo[1183]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:45 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:45 pi sudo[1187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 30 18:19:45 pi sudo[1187]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:45 pi systemd[1]: Started UPnP Renderer front-end to MPD. Aug 30 18:19:45 pi sudo[1187]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:45 pi volumio[966]: info: Upmpdcli Daemon Started Aug 30 18:19:45 pi volumio[1189]: Generating RSA private key, 4096 bit long modulus (2 primes) Aug 30 18:19:46 pi sudo[1206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=saurav,password=sur9618ama,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.3/t5 /mnt/NAS/t5 Aug 30 18:19:46 pi sudo[1206]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:46 pi kernel: CIFS: Attempting to mount \\192.168.0.3\t5 Aug 30 18:19:47 pi volumio[966]: info: Volumio called home Aug 30 18:19:47 pi go-librespot[1151]: time="2024-08-30T18:19:47+05:30" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 30 18:19:47 pi go-librespot[1151]: time="2024-08-30T18:19:47+05:30" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 30 18:19:47 pi go-librespot[1151]: time="2024-08-30T18:19:47+05:30" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 30 18:19:47 pi go-librespot[1151]: time="2024-08-30T18:19:47+05:30" level=debug msg="zeroconf server listening on port 37331" Aug 30 18:19:47 pi volumio[966]: info: Initializing connection to go-librespot Websocket Aug 30 18:19:48 pi volumio[966]: info: Discovery: adding 2b637307-6444-4d4f-9d44-d36a36c0836d Aug 30 18:19:48 pi volumio[966]: info: Discovery: Found device pi Aug 30 18:19:48 pi volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 18:19:48 pi volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 18:19:48 pi volumio[966]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 30 18:19:49 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:51 pi go-librespot[1151]: time="2024-08-30T18:19:51+05:30" level=debug msg="new websocket client" Aug 30 18:19:51 pi volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 18:19:51 pi volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 18:19:51 pi volumio[966]: info: Connection to go-librespot Websocket established Aug 30 18:19:52 pi sudo[1206]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:52 pi volumio[966]: info: Cannot mount NAS t5 at system boot, trial number 2 ,retrying in 5 seconds Aug 30 18:19:52 pi kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 30 18:19:52 pi kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Aug 30 18:19:53 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:54 pi volumio[966]: info: Getting Spotify volume Aug 30 18:19:54 pi volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 30 18:19:54 pi volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 18:19:54 pi volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 18:19:54 pi volumio[966]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 30 18:19:54 pi volumio[966]: SPOTIFY: SPOTIFY VOLUME undefined Aug 30 18:19:54 pi volumio[966]: SPOTIFY: VOLUMIO VOLUME 100 Aug 30 18:19:54 pi volumio[966]: info: Aligning Spotify Volume to Volumio Volume Aug 30 18:19:54 pi volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 18:19:54 pi volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 18:19:54 pi volumio[966]: info: Setting Spotify Volume from Volumio: 100 Aug 30 18:19:55 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Aug 30 18:19:56 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 30 18:19:56 pi volumio[966]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Aug 30 18:19:56 pi volumio[966]: info: Sending Spotify command with payload to local API: /player/volume Aug 30 18:19:57 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 30 18:19:57 pi volumio[966]: info: Adding plugin bluetooth to MyMusic Plugins Aug 30 18:19:57 pi volumio[966]: info: Adding plugin multiroom to MyMusic Plugins Aug 30 18:19:57 pi volumio[966]: info: Adding plugin metavolumio to MyMusic Plugins Aug 30 18:19:57 pi volumio[966]: info: Adding plugin cd_controller to MyMusic Plugins Aug 30 18:19:57 pi volumio[966]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 30 18:19:57 pi volumio[966]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 30 18:19:57 pi volumio[966]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 30 18:19:58 pi volumio[966]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 30 18:19:58 pi volumio[966]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 30 18:19:58 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 18:19:58 pi volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 18:19:58 pi volumio[966]: info: Starting MyVolumio Remote Streaming Endpoints Aug 30 18:19:58 pi volumio[966]: info: MyVolumio login type: Token Aug 30 18:19:58 pi volumio[966]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 30 18:19:58 pi volumio[966]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 30 18:19:59 pi volumio[966]: info: Starting Streaming Service Transparent Proxy Aug 30 18:19:59 pi volumio[966]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 30 18:19:59 pi volumio[966]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 30 18:19:59 pi volumio[966]: info: Streaming services startup Aug 30 18:19:59 pi volumio[966]: info: Starting Streaming Daemon Aug 30 18:19:59 pi volumio[966]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 30 18:19:59 pi sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 30 18:19:59 pi sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:19:59 pi sudo[1220]: pam_unix(sudo:session): session closed for user root Aug 30 18:19:59 pi volumio[966]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 18:19:59 pi volumio[966]: TypeError: Cannot read property 'replace' of undefined Aug 30 18:19:59 pi volumio[966]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Aug 30 18:19:59 pi volumio[966]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Aug 30 18:19:59 pi volumio[966]: at listOnTimeout (internal/timers.js:554:17) Aug 30 18:19:59 pi volumio[966]: at processTimers (internal/timers.js:497:7) Aug 30 18:19:59 pi volumio[966]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 18:19:59 pi sudo[1236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-30 18:18 Aug 30 18:19:59 pi sudo[1236]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"