-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sun 2024-05-05 14:29:33 UTC. -- May 05 14:28:51 romeo kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 May 05 14:28:51 romeo kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0f00040000000890 May 05 14:28:51 romeo kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller May 05 14:28:51 romeo kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 May 05 14:28:51 romeo kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed May 05 14:28:51 romeo kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01 May 05 14:28:51 romeo kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 May 05 14:28:51 romeo kernel: usb usb1: Product: xHCI Host Controller May 05 14:28:51 romeo kernel: usb usb1: Manufacturer: Linux 6.1.69-v7l+ xhci-hcd May 05 14:28:51 romeo kernel: usb usb1: SerialNumber: 0000:01:00.0 May 05 14:28:51 romeo kernel: hub 1-0:1.0: USB hub found May 05 14:28:51 romeo kernel: hub 1-0:1.0: 1 port detected May 05 14:28:51 romeo kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.01 May 05 14:28:51 romeo kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 May 05 14:28:51 romeo kernel: usb usb2: Product: xHCI Host Controller May 05 14:28:51 romeo kernel: usb usb2: Manufacturer: Linux 6.1.69-v7l+ xhci-hcd May 05 14:28:51 romeo kernel: usb usb2: SerialNumber: 0000:01:00.0 May 05 14:28:51 romeo kernel: hub 2-0:1.0: USB hub found May 05 14:28:51 romeo kernel: hub 2-0:1.0: 4 ports detected May 05 14:28:51 romeo kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) May 05 14:28:51 romeo kernel: dwc_otg: FIQ enabled May 05 14:28:51 romeo kernel: dwc_otg: NAK holdoff enabled May 05 14:28:51 romeo kernel: dwc_otg: FIQ split-transaction FSM enabled May 05 14:28:51 romeo kernel: Module dwc_common_port init May 05 14:28:51 romeo kernel: usbcore: registered new interface driver uas May 05 14:28:51 romeo kernel: usbcore: registered new interface driver usb-storage May 05 14:28:51 romeo kernel: mousedev: PS/2 mouse device common for all mice May 05 14:28:51 romeo kernel: sdhci: Secure Digital Host Controller Interface driver May 05 14:28:51 romeo kernel: sdhci: Copyright(c) Pierre Ossman May 05 14:28:51 romeo kernel: sdhci-pltfm: SDHCI platform and OF driver helper May 05 14:28:51 romeo kernel: ledtrig-cpu: registered to indicate activity on CPUs May 05 14:28:51 romeo kernel: hid: raw HID events driver (C) Jiri Kosina May 05 14:28:51 romeo kernel: usbcore: registered new interface driver usbhid May 05 14:28:51 romeo kernel: usbhid: USB HID core driver May 05 14:28:51 romeo kernel: Initializing XFRM netlink socket May 05 14:28:51 romeo kernel: NET: Registered PF_PACKET protocol family May 05 14:28:51 romeo kernel: Key type dns_resolver registered May 05 14:28:51 romeo kernel: Registering SWP/SWPB emulation handler May 05 14:28:51 romeo kernel: registered taskstats version 1 May 05 14:28:51 romeo kernel: Loading compiled-in X.509 certificates May 05 14:28:51 romeo kernel: Key type .fscrypt registered May 05 14:28:51 romeo kernel: Key type fscrypt-provisioning registered May 05 14:28:51 romeo kernel: uart-pl011 fe201000.serial: there is not valid maps for state default May 05 14:28:51 romeo kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled May 05 14:28:51 romeo kernel: fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 51, base_baud = 0) is a PL011 rev2 May 05 14:28:51 romeo kernel: serial serial0: tty port ttyAMA1 registered May 05 14:28:51 romeo kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer May 05 14:28:51 romeo kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver May 05 14:28:51 romeo kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 May 05 14:28:51 romeo kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated May 05 14:28:51 romeo kernel: of_cfs_init May 05 14:28:51 romeo kernel: of_cfs_init: OK May 05 14:28:51 romeo kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA May 05 14:28:51 romeo kernel: Freeing unused kernel image (initmem) memory: 2048K May 05 14:28:51 romeo kernel: Run /init as init process May 05 14:28:51 romeo kernel: with arguments: May 05 14:28:51 romeo kernel: /init May 05 14:28:51 romeo kernel: splash May 05 14:28:51 romeo kernel: with environment: May 05 14:28:51 romeo kernel: HOME=/ May 05 14:28:51 romeo kernel: TERM=linux May 05 14:28:51 romeo kernel: imgpart=/dev/mmcblk0p2 May 05 14:28:51 romeo kernel: imgfile=/volumio_current.sqsh May 05 14:28:51 romeo kernel: bootdelay=5 May 05 14:28:51 romeo kernel: mmc1: new high speed SDIO card at address 0001 May 05 14:28:51 romeo kernel: mmc0: new ultra high speed DDR50 SDHC card at address 5048 May 05 14:28:51 romeo kernel: mmcblk0: mmc0:5048 SD32G 29.7 GiB May 05 14:28:51 romeo kernel: mmcblk0: p1 p2 p3 May 05 14:28:51 romeo kernel: mmcblk0: mmc0:5048 SD32G 29.7 GiB (quirks 0x00004000) May 05 14:28:51 romeo kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd May 05 14:28:51 romeo initramfs: Booting Volumio for BCM2711 May 05 14:28:51 romeo initramfs: This script mounts rootfs RO with an overlay RW layer. May 05 14:28:51 romeo kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 05 14:28:51 romeo initramfs: IMGPART=/dev/mmcblk0p2 May 05 14:28:51 romeo initramfs: IMGFILE=/volumio_current.sqsh May 05 14:28:51 romeo initramfs: Boot delay (except first time) will be 5 seconds May 05 14:28:51 romeo initramfs: /dev/mmcblk0p2: May 05 14:28:51 romeo kernel: EXT4-fs (mmcblk0p2): recovery complete May 05 14:28:51 romeo kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none. May 05 14:28:51 romeo initramfs: Doing a 5 second delay here to give kernel load a headstart May 05 14:28:51 romeo kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 May 05 14:28:51 romeo kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 May 05 14:28:51 romeo kernel: usb 1-1: Product: USB2.0 Hub May 05 14:28:51 romeo kernel: hub 1-1:1.0: USB hub found May 05 14:28:51 romeo kernel: hub 1-1:1.0: 4 ports detected May 05 14:28:51 romeo initramfs: Checking for USB updates May 05 14:28:51 romeo initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) May 05 14:28:51 romeo kernel: loop0: detected capacity change from 0 to 976664 May 05 14:28:51 romeo kernel: EXT4-fs (mmcblk0p3): recovery complete May 05 14:28:51 romeo kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Quota mode: none. May 05 14:28:51 romeo initramfs: With Option: May 05 14:28:51 romeo initramfs: VOLUMIO_VERSION="3.661" May 05 14:28:51 romeo initramfs: Finish initramfs, continue booting Volumio May 05 14:28:51 romeo systemd[1]: System time before build time, advancing clock. May 05 14:28:51 romeo kernel: NET: Registered PF_INET6 protocol family May 05 14:28:51 romeo kernel: Segment Routing with IPv6 May 05 14:28:51 romeo kernel: In-situ OAM (IOAM) with IPv6 May 05 14:28:51 romeo 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) May 05 14:28:51 romeo systemd[1]: Detected architecture arm. May 05 14:28:51 romeo systemd[1]: Set hostname to . May 05 14:28:51 romeo 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. May 05 14:28:51 romeo systemd[1]: Started Forward Password Requests to Wall Directory Watch. May 05 14:28:51 romeo systemd[1]: Created slice User and Session Slice. May 05 14:28:51 romeo systemd[1]: Created slice system-systemd\x2dfsck.slice. May 05 14:28:51 romeo systemd[1]: Listening on Journal Socket. May 05 14:28:51 romeo systemd[1]: Condition check resulted in Kernel Module supporting RPCSEC_GSS being skipped. May 05 14:28:51 romeo systemd[1]: Starting Remount Root and Kernel File Systems... May 05 14:28:51 romeo kernel: i2c_dev: i2c /dev entries driver May 05 14:28:51 romeo systemd-journald[206]: Journal started May 05 14:28:51 romeo systemd-journald[206]: Runtime journal (/run/log/journal/3c3812e622e46b6dc8110864638cf76c) is 7.5M, max 30.0M, 22.5M free. May 05 14:28:51 romeo systemd-modules-load[196]: Inserted module 'i2c_dev' May 05 14:28:51 romeo fake-hwclock[210]: Sun May 5 14:28:51 UTC 2024 May 05 14:28:51 romeo systemd[1]: Started Load/Save Random Seed. May 05 14:28:51 romeo systemd[1]: dynamicswap.service: Succeeded. May 05 14:28:51 romeo systemd[1]: Started Create System Users. May 05 14:28:51 romeo systemd[1]: Starting Create Static Device Nodes in /dev... May 05 14:28:51 romeo systemd[1]: Started Create Static Device Nodes in /dev. May 05 14:28:51 romeo systemd[1]: Starting udev Kernel Device Manager... May 05 14:28:51 romeo systemd[1]: Reached target Local File Systems (Pre). May 05 14:28:51 romeo systemd[1]: Mounting /var/spool/cups... May 05 14:28:51 romeo systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. May 05 14:28:51 romeo systemd[1]: Mounting /var/log... May 05 14:28:51 romeo systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. May 05 14:28:51 romeo systemd[1]: Mounting /tmp... May 05 14:28:51 romeo systemd[1]: Mounted /var/spool/cups. May 05 14:28:51 romeo systemd[1]: Mounted /tmp. May 05 14:28:51 romeo systemd[1]: Mounting /var/spool/cups/tmp... May 05 14:28:51 romeo systemd-udevd[233]: Network interface NamePolicy= disabled on kernel command line, ignoring. May 05 14:28:51 romeo systemd[1]: Mounted /var/log. May 05 14:28:51 romeo systemd[1]: Starting Flush Journal to Persistent Storage... May 05 14:28:51 romeo systemd[1]: Mounted /var/spool/cups/tmp. May 05 14:28:51 romeo systemd[1]: Started udev Kernel Device Manager. May 05 14:28:51 romeo systemd[1]: Started udev Coldplug all Devices. May 05 14:28:51 romeo systemd-journald[206]: Runtime journal (/run/log/journal/3c3812e622e46b6dc8110864638cf76c) is 7.5M, max 30.0M, 22.5M free. May 05 14:28:51 romeo systemd[1]: Starting Helper to synchronize boot up for ifupdown... May 05 14:28:51 romeo systemd[1]: Starting Show Plymouth Boot Screen... May 05 14:28:51 romeo systemd[1]: Started Flush Journal to Persistent Storage. May 05 14:28:51 romeo systemd[1]: Received SIGRTMIN+20 from PID 255 (plymouthd). May 05 14:28:51 romeo systemd[1]: Started Show Plymouth Boot Screen. May 05 14:28:51 romeo systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. May 05 14:28:51 romeo systemd[1]: Reached target Paths. May 05 14:28:51 romeo systemd[1]: Reached target Local Encrypted Volumes. May 05 14:28:51 romeo systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. May 05 14:28:51 romeo systemd-udevd[243]: Using default interface naming scheme 'v240'. May 05 14:28:51 romeo kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 May 05 14:28:51 romeo kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem May 05 14:28:51 romeo systemd-udevd[243]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 05 14:28:51 romeo kernel: mc: Linux media interface: v0.10 May 05 14:28:51 romeo kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. May 05 14:28:51 romeo kernel: videodev: Linux video capture interface: v2.00 May 05 14:28:52 romeo kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver May 05 14:28:52 romeo kernel: [vc_sm_connected_init]: start May 05 14:28:52 romeo kernel: [vc_sm_connected_init]: installed successfully May 05 14:28:52 romeo kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. May 05 14:28:52 romeo kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. May 05 14:28:52 romeo kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. May 05 14:28:52 romeo kernel: rpivid feb10000.codec: Device registered as /dev/video19 May 05 14:28:52 romeo kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. May 05 14:28:52 romeo kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. May 05 14:28:52 romeo kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. May 05 14:28:52 romeo kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 May 05 14:28:52 romeo kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 May 05 14:28:52 romeo kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 May 05 14:28:52 romeo kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 May 05 14:28:52 romeo kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode May 05 14:28:52 romeo kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 May 05 14:28:52 romeo kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller May 05 14:28:52 romeo kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller May 05 14:28:52 romeo kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller May 05 14:28:52 romeo kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller May 05 14:28:52 romeo kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. May 05 14:28:52 romeo kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored May 05 14:28:52 romeo kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 May 05 14:28:52 romeo kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode May 05 14:28:52 romeo kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 May 05 14:28:52 romeo kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 May 05 14:28:52 romeo kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp May 05 14:28:52 romeo kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 May 05 14:28:52 romeo kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 May 05 14:28:52 romeo kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 May 05 14:28:52 romeo kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller May 05 14:28:52 romeo kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller May 05 14:28:52 romeo kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller May 05 14:28:52 romeo kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller May 05 14:28:52 romeo kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp May 05 14:28:52 romeo kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 May 05 14:28:52 romeo kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx May 05 14:28:52 romeo systemd[1]: Found device /dev/mmcblk0p1. May 05 14:28:52 romeo kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 May 05 14:28:52 romeo kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image May 05 14:28:52 romeo kernel: bcm2835_audio bcm2835_audio: card created with 4 channels May 05 14:28:52 romeo kernel: bcm2835_audio bcm2835_audio: card created with 4 channels May 05 14:28:52 romeo kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database May 05 14:28:52 romeo kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' May 05 14:28:52 romeo kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' May 05 14:28:52 romeo kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' May 05 14:28:52 romeo kernel: Bluetooth: Core ver 2.22 May 05 14:28:52 romeo kernel: NET: Registered PF_BLUETOOTH protocol family May 05 14:28:52 romeo kernel: Bluetooth: HCI device and connection manager initialized May 05 14:28:52 romeo kernel: Bluetooth: HCI socket layer initialized May 05 14:28:52 romeo kernel: Bluetooth: L2CAP socket layer initialized May 05 14:28:52 romeo kernel: Bluetooth: SCO socket layer initialized May 05 14:28:52 romeo kernel: Bluetooth: HCI UART driver ver 2.3 May 05 14:28:52 romeo kernel: Bluetooth: HCI UART protocol H4 registered May 05 14:28:52 romeo kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered May 05 14:28:52 romeo kernel: Bluetooth: HCI UART protocol Broadcom registered May 05 14:28:52 romeo kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator May 05 14:28:52 romeo kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator May 05 14:28:52 romeo systemd-udevd[241]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 05 14:28:53 romeo kernel: uart-pl011 fe201000.serial: no DMA platform data May 05 14:28:53 romeo kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 May 05 14:28:53 romeo kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 May 05 14:28:53 romeo kernel: usbcore: registered new interface driver brcmfmac May 05 14:28:53 romeo kernel: Bluetooth: hci0: BCM: chip id 107 May 05 14:28:53 romeo kernel: Bluetooth: hci0: BCM: features 0x2f May 05 14:28:53 romeo kernel: Bluetooth: hci0: BCM4345C0 May 05 14:28:53 romeo kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 May 05 14:28:53 romeo kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch May 05 14:28:53 romeo 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 May 05 14:28:53 romeo systemd-udevd[240]: Using default interface naming scheme 'v240'. May 05 14:28:53 romeo systemd-udevd[240]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 05 14:28:53 romeo kernel: wm8804 1-003b: revision E May 05 14:28:54 romeo kernel: Bluetooth: hci0: BCM: features 0x2f May 05 14:28:54 romeo kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ May 05 14:28:54 romeo kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 May 05 14:28:54 romeo systemd[1]: Started Helper to synchronize boot up for ifupdown. May 05 14:28:54 romeo systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. May 05 14:28:54 romeo systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. May 05 14:28:54 romeo systemd[1]: Created slice system-bthelper.slice. May 05 14:28:54 romeo systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. May 05 14:28:54 romeo systemd[1]: Condition check resulted in Huge Pages File System being skipped. May 05 14:28:54 romeo systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. May 05 14:28:54 romeo systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. May 05 14:28:54 romeo systemd[1]: Condition check resulted in FUSE Control File System being skipped. May 05 14:28:54 romeo systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. May 05 14:28:54 romeo systemd[1]: Starting File System Check on /dev/mmcblk0p1... May 05 14:28:54 romeo systemd[1]: Starting Load/Save RF Kill Switch Status... May 05 14:28:54 romeo systemd[1]: Started File System Check Daemon to report status. May 05 14:28:54 romeo systemd[1]: Started Load/Save RF Kill Switch Status. May 05 14:28:54 romeo systemd-fsck[397]: fsck.fat 4.1 (2017-01-24) May 05 14:28:54 romeo systemd-fsck[397]: /dev/mmcblk0p1: 371 files, 33372/46774 clusters May 05 14:28:54 romeo systemd[1]: Started File System Check on /dev/mmcblk0p1. May 05 14:28:54 romeo systemd[1]: Mounting /boot... May 05 14:28:55 romeo systemd[1]: Mounted /boot. May 05 14:28:55 romeo systemd[1]: Reached target Local File Systems. May 05 14:28:55 romeo systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. May 05 14:28:55 romeo systemd[1]: Started ifup for eth0. May 05 14:28:55 romeo systemd[1]: Starting Raise network interfaces... May 05 14:28:55 romeo systemd[1]: Starting Preprocess NFS configuration... May 05 14:28:55 romeo systemd[1]: Starting Create Volatile Files and Directories... May 05 14:28:55 romeo systemd[1]: Started ifup for wlan0. May 05 14:28:55 romeo systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... May 05 14:28:55 romeo systemd[1]: Received SIGRTMIN+20 from PID 255 (plymouthd). May 05 14:28:55 romeo systemd[1]: plymouth-read-write.service: Succeeded. May 05 14:28:55 romeo systemd[1]: Started Tell Plymouth To Write Out Runtime Data. May 05 14:28:55 romeo systemd[1]: nfs-config.service: Succeeded. May 05 14:28:55 romeo systemd[1]: Started Preprocess NFS configuration. May 05 14:28:55 romeo systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. May 05 14:28:55 romeo systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. May 05 14:28:55 romeo systemd[1]: Reached target NFS client services. May 05 14:28:55 romeo systemd[1]: Started Create Volatile Files and Directories. May 05 14:28:55 romeo kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 05 14:28:55 romeo systemd[1]: Started Entropy daemon using the HAVEGE algorithm. May 05 14:28:55 romeo systemd[1]: Starting Update UTMP about System Boot/Shutdown... May 05 14:28:55 romeo systemd[1]: Starting RPC bind portmap service... May 05 14:28:55 romeo systemd[1]: Condition check resulted in Network Time Synchronization being skipped. May 05 14:28:55 romeo kernel: 8021q: 802.1Q VLAN Support v1.8 May 05 14:28:55 romeo systemd[1]: Started Update UTMP about System Boot/Shutdown. May 05 14:28:55 romeo systemd[1]: Reached target System Initialization. May 05 14:28:55 romeo systemd[1]: Listening on triggerhappy.socket. May 05 14:28:55 romeo systemd[1]: Listening on D-Bus System Message Bus Socket. May 05 14:28:55 romeo systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. May 05 14:28:55 romeo systemd[1]: Reached target Sockets. May 05 14:28:55 romeo sh[404]: eth0: waiting for carrier May 05 14:28:55 romeo kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) May 05 14:28:55 romeo kernel: bcmgenet fd580000.ethernet eth0: Link is Down May 05 14:28:55 romeo dhcpcd[451]: eth0: waiting for carrier May 05 14:28:55 romeo systemd[1]: Started Volumio Splash. May 05 14:28:55 romeo systemd[1]: Reached target Basic System. May 05 14:28:55 romeo systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. May 05 14:28:55 romeo systemd[512]: volumiosplash.service: Failed to execute command: No such file or directory May 05 14:28:55 romeo systemd[512]: volumiosplash.service: Failed at step EXEC spawning /bin/volumiosplash.sh: No such file or directory May 05 14:28:55 romeo systemd[1]: Starting triggerhappy global hotkey daemon... May 05 14:28:55 romeo systemd[1]: Starting Configure Bluetooth Modems connected by UART... May 05 14:28:55 romeo systemd[1]: Started Volumio Log Rotation Service. May 05 14:28:55 romeo systemd[1]: Started volumio-remote-updater.service. May 05 14:28:55 romeo systemd[1]: Started Volumio Iptables Module. May 05 14:28:55 romeo thd[519]: Unable to parse trigger line: May 05 14:28:55 romeo thd[519]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle May 05 14:28:55 romeo thd[519]: Unable to parse trigger line: May 05 14:28:55 romeo thd[519]: Found socket passed from systemd May 05 14:28:55 romeo systemd[1]: Started UPnP Renderer front-end to MPD. May 05 14:28:55 romeo systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. May 05 14:28:55 romeo systemd[1]: Starting dhcpcd on all interfaces... May 05 14:28:55 romeo systemd[1]: Starting Avahi mDNS/DNS-SD Stack... May 05 14:28:55 romeo systemd[1]: Started Manage Sound Card State (restore and store). May 05 14:28:55 romeo systemd[1]: Starting Save/Restore Sound Card State... May 05 14:28:55 romeo alsactl[532]: alsactl 1.1.8 daemon started May 05 14:28:55 romeo systemd[1]: Starting Login Service... May 05 14:28:55 romeo systemd[1]: Started D-Bus System Message Bus. May 05 14:28:55 romeo dhcpcd[527]: Not running dhcpcd because /etc/network/interfaces May 05 14:28:55 romeo dhcpcd[527]: defines some interfaces that will use a May 05 14:28:55 romeo dhcpcd[527]: DHCP client or static address May 05 14:28:55 romeo systemd[1]: Started Daily apt download activities. May 05 14:28:55 romeo systemd[1]: Starting Wireless Services... May 05 14:28:55 romeo systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. May 05 14:28:55 romeo systemd[1]: Starting WPA supplicant... May 05 14:28:55 romeo systemd[1]: Started Daily Cleanup of Temporary Directories. May 05 14:28:55 romeo systemd[1]: Started Daily apt upgrade and clean activities. May 05 14:28:55 romeo systemd[1]: Reached target Timers. May 05 14:28:55 romeo systemd[1]: Started RPC bind portmap service. May 05 14:28:55 romeo systemd[1]: Started triggerhappy global hotkey daemon. May 05 14:28:55 romeo systemd[1]: volumiosplash.service: Main process exited, code=exited, status=203/EXEC May 05 14:28:55 romeo systemd[1]: volumiosplash.service: Failed with result 'exit-code'. May 05 14:28:55 romeo systemd[1]: hciuart.service: Succeeded. May 05 14:28:55 romeo systemd[1]: Started Configure Bluetooth Modems connected by UART. May 05 14:28:55 romeo systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED May 05 14:28:55 romeo systemd[1]: dhcpcd.service: Failed with result 'exit-code'. May 05 14:28:55 romeo systemd[1]: Failed to start dhcpcd on all interfaces. May 05 14:28:55 romeo systemd[1]: Started Save/Restore Sound Card State. May 05 14:28:55 romeo systemd[1]: Reached target Sound Card. May 05 14:28:55 romeo systemd[1]: Starting Raspberry Pi bluetooth helper... May 05 14:28:55 romeo systemd[1]: Reached target RPC Port Mapper. May 05 14:28:55 romeo systemd[1]: Reached target Remote File Systems (Pre). May 05 14:28:55 romeo systemd[1]: Reached target Remote File Systems. May 05 14:28:55 romeo systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... May 05 14:28:56 romeo systemd-logind[534]: New seat seat0. May 05 14:28:56 romeo avahi-daemon[529]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). May 05 14:28:56 romeo avahi-daemon[529]: Successfully dropped root privileges. May 05 14:28:56 romeo avahi-daemon[529]: avahi-daemon 0.7 starting up. May 05 14:28:56 romeo sh[412]: wlan0=wlan0 May 05 14:28:56 romeo bthelper[543]: Raspberry Pi BDADDR already set May 05 14:28:56 romeo systemd[1]: Started Raspberry Pi bluetooth helper. May 05 14:28:56 romeo avahi-daemon[529]: Successfully called chroot(). May 05 14:28:56 romeo avahi-daemon[529]: Successfully dropped remaining capabilities. May 05 14:28:56 romeo avahi-daemon[529]: Loading service file /services/volumio.service. May 05 14:28:56 romeo wpa_supplicant[542]: Successfully initialized wpa_supplicant May 05 14:28:56 romeo avahi-daemon[529]: Network interface enumeration completed. May 05 14:28:56 romeo avahi-daemon[529]: Server startup complete. Host name is romeo.local. Local service cookie is 952478400. May 05 14:28:56 romeo avahi-daemon[529]: Service "Romeo" (/services/volumio.service) successfully established. May 05 14:28:56 romeo systemd[1]: Started Login Service. May 05 14:28:56 romeo systemd[1]: Started Avahi mDNS/DNS-SD Stack. May 05 14:28:56 romeo systemd[1]: Started WPA supplicant. May 05 14:28:56 romeo systemd[1]: Starting Bluetooth service... May 05 14:28:56 romeo systemd[1]: Started Raise network interfaces. May 05 14:28:56 romeo systemd[1]: Reached target Network. May 05 14:28:56 romeo systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. May 05 14:28:56 romeo systemd[1]: Starting Permit User Sessions... May 05 14:28:56 romeo systemd[1]: Starting OpenBSD Secure Shell server... May 05 14:28:56 romeo systemd[1]: Starting Network Time Service... May 05 14:28:56 romeo systemd[1]: Reached target Network is Online. May 05 14:28:56 romeo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 05 14:28:56 romeo volumio-remote-updater[522]: Error: No active session May 05 14:28:56 romeo systemd[1]: Starting /etc/rc.local Compatibility... May 05 14:28:57 romeo systemd[1]: Starting LSB: Brings up/down network automatically... May 05 14:28:57 romeo volumio-remote-updater[522]: [2024-05-05 14:28:57] [info] asio async_connect error: system:111 (Connection refused) May 05 14:28:57 romeo volumio-remote-updater[522]: [2024-05-05 14:28:57] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 05 14:28:57 romeo volumio-remote-updater[522]: [2024-05-05 14:28:57] [error] handle_connect error: Underlying Transport Error May 05 14:28:57 romeo systemd[1]: Starting Samba NMB Daemon... May 05 14:28:57 romeo systemd[1]: Started Permit User Sessions. May 05 14:28:57 romeo systemd[1]: Started /etc/rc.local Compatibility. May 05 14:28:57 romeo systemd[1]: Starting Terminate Plymouth Boot Screen... May 05 14:28:57 romeo systemd[1]: Starting Hold until boot process finishes up... May 05 14:28:57 romeo systemd[1]: Received SIGRTMIN+21 from PID 255 (plymouthd). May 05 14:28:57 romeo systemd[1]: Received SIGRTMIN+21 from PID 255 (plymouthd). May 05 14:28:57 romeo volumio[525]: Could not open config: /tmp/upmpdcli.conf May 05 14:28:57 romeo systemd[1]: plymouth-start.service: Succeeded. May 05 14:28:57 romeo systemd[1]: plymouth-quit.service: Succeeded. May 05 14:28:57 romeo systemd[1]: Started Terminate Plymouth Boot Screen. May 05 14:28:57 romeo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 05 14:28:57 romeo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 05 14:28:57 romeo systemd[1]: plymouth-quit-wait.service: Succeeded. May 05 14:28:57 romeo systemd[1]: Started Hold until boot process finishes up. May 05 14:28:57 romeo systemd[1]: iptables.service: Succeeded. May 05 14:28:57 romeo bluetoothd[597]: Bluetooth daemon 5.50 May 05 14:28:57 romeo systemd[1]: Started Getty on tty1. May 05 14:28:57 romeo systemd[1]: Reached target Login Prompts. May 05 14:28:57 romeo bluetoothd[597]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf May 05 14:28:57 romeo loadcpufreq[547]: Loading cpufreq kernel modules...done (none). May 05 14:28:57 romeo bluetoothd[597]: Starting SDP server May 05 14:28:57 romeo bluetoothd[597]: Excluding (cli) sap May 05 14:28:57 romeo systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. May 05 14:28:57 romeo systemd[1]: Started Bluetooth service. May 05 14:28:57 romeo systemd[1]: Reached target Bluetooth. May 05 14:28:57 romeo systemd[1]: Starting LSB: set CPUFreq kernel parameters... May 05 14:28:57 romeo ifplugd(eth0)[658]: ifplugd 0.28 initializing. May 05 14:28:57 romeo ifplugd(eth0)[658]: Using interface eth0/DC:A6:32:CA:DF:8D with driver (version: 6.1.69-v7l+) May 05 14:28:57 romeo ifplugd(eth0)[658]: Using detection mode: SIOCETHTOOL May 05 14:28:57 romeo ifplugd(eth0)[658]: Initialization complete, link beat not detected. May 05 14:28:57 romeo kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 May 05 14:28:57 romeo kernel: Bluetooth: BNEP filters: protocol multicast May 05 14:28:57 romeo kernel: Bluetooth: BNEP socket layer initialized May 05 14:28:57 romeo bluetoothd[597]: Bluetooth management interface 1.22 initialized May 05 14:28:57 romeo ntpd[608]: ntpd 4.2.8p12@1.3728-o (1): Starting May 05 14:28:57 romeo ntpd[608]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 May 05 14:28:57 romeo dbus-daemon[535]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=597 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") May 05 14:28:57 romeo kernel: Bluetooth: MGMT ver 1.22 May 05 14:28:57 romeo ntpd[659]: proto: precision = 4.426 usec (-18) May 05 14:28:57 romeo systemd[1]: Started Network Time Service. May 05 14:28:57 romeo ifplugd[613]: Network Interface Plugging Daemon...start eth0...done. May 05 14:28:57 romeo systemd[1]: Started LSB: Brings up/down network automatically. May 05 14:28:57 romeo ntpd[659]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 05 14:28:57 romeo ntpd[659]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 05 14:28:57 romeo ntpd[659]: Listen and drop on 0 v6wildcard [::]:123 May 05 14:28:57 romeo ntpd[659]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 05 14:28:57 romeo ntpd[659]: Listen normally on 2 lo 127.0.0.1:123 May 05 14:28:57 romeo ntpd[659]: Listening on routing socket on fd #19 for interface updates May 05 14:28:57 romeo ntpd[659]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 05 14:28:57 romeo ntpd[659]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 05 14:28:57 romeo bluetoothd[597]: Failed to set privacy: Rejected (0x0b) May 05 14:28:57 romeo cpufrequtils[647]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. May 05 14:28:57 romeo systemd[1]: Started LSB: set CPUFreq kernel parameters. May 05 14:28:57 romeo systemd[1]: Starting Hostname Service... May 05 14:28:57 romeo sshd[672]: Server listening on 0.0.0.0 port 22. May 05 14:28:57 romeo sshd[672]: Server listening on :: port 22. May 05 14:28:57 romeo systemd[1]: Started OpenBSD Secure Shell server. May 05 14:28:58 romeo dbus-daemon[535]: [system] Successfully activated service 'org.freedesktop.hostname1' May 05 14:28:58 romeo systemd[1]: Started Hostname Service. May 05 14:28:58 romeo nmbd[619]: [2024/05/05 14:28:58.275635, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) May 05 14:28:58 romeo nmbd[619]: started asyncdns process 680 May 05 14:28:58 romeo nmbd[619]: [2024/05/05 14:28:58.277496, 0] ../lib/util/become_daemon.c:149(daemon_status) May 05 14:28:58 romeo nmbd[619]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... May 05 14:28:58 romeo nmbd[619]: [2024/05/05 14:28:58.277626, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) May 05 14:28:58 romeo nmbd[619]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). May 05 14:28:58 romeo haveged[458]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K May 05 14:28:58 romeo haveged[458]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 May 05 14:28:58 romeo haveged[458]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00029 May 05 14:28:58 romeo haveged[458]: haveged: fills: 0, generated: 0 May 05 14:28:58 romeo wireless.js[540]: Cleaning previous... May 05 14:28:58 romeo ntpd[659]: error resolving pool 0.debian.pool.ntp.org: System error (-11) May 05 14:28:58 romeo sudo[688]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 05 14:28:58 romeo sudo[688]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:28:58 romeo sudo[688]: pam_unix(sudo:session): session closed for user root May 05 14:28:58 romeo sudo[690]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 05 14:28:58 romeo sudo[690]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:28:59 romeo sudo[690]: pam_unix(sudo:session): session closed for user root May 05 14:28:59 romeo wireless.js[540]: Stopped aP May 05 14:28:59 romeo wireless.js[540]: Wireless Networking DISABLED, not starting wireless flow May 05 14:28:59 romeo systemd[1]: wireless.service: Succeeded. May 05 14:28:59 romeo systemd[1]: Started Wireless Services. May 05 14:28:59 romeo systemd[1]: Started Volumio Backend Module. May 05 14:28:59 romeo systemd[1]: Started Volumio Cpu Tweaker. May 05 14:28:59 romeo volumio-cpu-tweak[699]: Setting RT Priority for mpd May 05 14:28:59 romeo volumio-cpu-tweak[699]: pid 35's current scheduling policy: SCHED_OTHER May 05 14:28:59 romeo volumio-cpu-tweak[699]: pid 35's current scheduling priority: 0 May 05 14:28:59 romeo volumio-cpu-tweak[699]: Setting MPD Affinity May 05 14:28:59 romeo volumio-cpu-tweak[699]: pid 3's current affinity mask: f May 05 14:28:59 romeo volumio-cpu-tweak[699]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input May 05 14:28:59 romeo volumio-cpu-tweak[699]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 05 14:28:59 romeo systemd[1]: volumio_cpu_tweak.service: Succeeded. May 05 14:28:59 romeo ntpd[659]: error resolving pool 1.debian.pool.ntp.org: System error (-11) May 05 14:28:59 romeo sh[404]: eth0: carrier acquired May 05 14:28:59 romeo dhcpcd[451]: eth0: carrier acquired May 05 14:28:59 romeo sh[404]: DUID 00:01:00:01:2b:1f:b5:b8:dc:a6:32:ca:df:8d May 05 14:28:59 romeo sh[404]: eth0: IAID 32:ca:df:8d May 05 14:28:59 romeo sh[404]: eth0: adding address fe80::dea6:32ff:feca:df8d May 05 14:28:59 romeo sh[404]: ipv6_addaddr1: Permission denied May 05 14:28:59 romeo dhcpcd[451]: DUID 00:01:00:01:2b:1f:b5:b8:dc:a6:32:ca:df:8d May 05 14:28:59 romeo dhcpcd[451]: eth0: IAID 32:ca:df:8d May 05 14:28:59 romeo dhcpcd[451]: eth0: adding address fe80::dea6:32ff:feca:df8d May 05 14:28:59 romeo dhcpcd[451]: ipv6_addaddr1: Permission denied May 05 14:28:59 romeo kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx May 05 14:28:59 romeo sh[404]: eth0: soliciting an IPv6 router May 05 14:28:59 romeo dhcpcd[451]: eth0: soliciting an IPv6 router May 05 14:28:59 romeo systemd[1]: systemd-rfkill.service: Succeeded. May 05 14:29:00 romeo sh[404]: eth0: soliciting a DHCP lease May 05 14:29:00 romeo dhcpcd[451]: eth0: soliciting a DHCP lease May 05 14:29:00 romeo sh[404]: eth0: probing address 192.168.1.5/24 May 05 14:29:00 romeo dhcpcd[451]: eth0: probing address 192.168.1.5/24 May 05 14:29:00 romeo ntpd[659]: error resolving pool 2.debian.pool.ntp.org: System error (-11) May 05 14:29:00 romeo ifplugd(eth0)[658]: Link beat detected. May 05 14:29:00 romeo ifplugd(eth0)[658]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. May 05 14:29:00 romeo ifplugd(eth0)[658]: client: ifup: waiting for lock on /run/network/ifstate.eth0 May 05 14:29:01 romeo volumio[698]: info: ------------------------------------------- May 05 14:29:01 romeo volumio[698]: info: ----- Volumio3 ---- May 05 14:29:01 romeo volumio[698]: info: ------------------------------------------- May 05 14:29:01 romeo volumio[698]: info: ----- System startup ---- May 05 14:29:01 romeo volumio[698]: info: ------------------------------------------- May 05 14:29:01 romeo bthelper[543]: Changing power off succeeded May 05 14:29:01 romeo bthelper[543]: [CHG] Controller DC:A6:32:CA:DF:8F Class: 0x0000041c May 05 14:29:01 romeo bthelper[543]: Changing power on succeeded May 05 14:29:01 romeo bthelper[543]: [CHG] Controller DC:A6:32:CA:DF:8F Powered: yes May 05 14:29:01 romeo ntpd[659]: error resolving pool 3.debian.pool.ntp.org: System error (-11) May 05 14:29:01 romeo volumio[698]: info: MYVOLUMIO Environment detected May 05 14:29:02 romeo volumio-remote-updater[522]: [2024-05-05 14:29:02] [connect] Successful connection May 05 14:29:02 romeo volumio[698]: info: Plugin folders cleanup May 05 14:29:02 romeo volumio[698]: info: Scanning into folder /volumio/app/plugins/ May 05 14:29:02 romeo volumio[698]: info: Scanning category audio_interface May 05 14:29:02 romeo volumio[698]: info: Scanning category miscellanea May 05 14:29:02 romeo volumio[698]: info: Scanning category music_service May 05 14:29:02 romeo volumio[698]: info: Scanning category plugins.json May 05 14:29:02 romeo volumio[698]: info: Scanning category system_controller May 05 14:29:02 romeo volumio[698]: info: Scanning category user_interface May 05 14:29:02 romeo volumio[698]: info: Scanning into folder /data/plugins/ May 05 14:29:02 romeo volumio[698]: info: Scanning category music_service May 05 14:29:02 romeo volumio[698]: info: Plugin folders cleanup completed May 05 14:29:02 romeo volumio[698]: info: ------------------------------------------- May 05 14:29:02 romeo volumio[698]: info: ----- Core plugins startup ---- May 05 14:29:02 romeo volumio[698]: info: ------------------------------------------- May 05 14:29:02 romeo volumio[698]: info: Loading plugins from folder /volumio/app/plugins/ May 05 14:29:02 romeo volumio[698]: info: Adding plugin upnp to MyMusic Plugins May 05 14:29:02 romeo volumio[698]: info: Adding plugin airplay_emulation to MyMusic Plugins May 05 14:29:02 romeo volumio[698]: info: Adding plugin upnp_browser to MyMusic Plugins May 05 14:29:02 romeo volumio[698]: info: Loading plugins from folder /data/plugins/ May 05 14:29:02 romeo volumio[698]: info: Loading plugin "system"... May 05 14:29:02 romeo volumio[698]: info: Loading plugin "appearance"... May 05 14:29:03 romeo volumio[698]: info: Loading plugin "network"... May 05 14:29:03 romeo volumio[698]: info: Refreshing Cached IP Addresses May 05 14:29:03 romeo volumio[698]: info: Loading plugin "services"... May 05 14:29:03 romeo sudo[741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 05 14:29:03 romeo sudo[741]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:29:03 romeo volumio[698]: info: Loading plugin "alsa_controller"... May 05 14:29:03 romeo sudo[741]: pam_unix(sudo:session): session closed for user root May 05 14:29:03 romeo sudo[743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 14:29:03 romeo sudo[743]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:29:03 romeo sudo[743]: pam_unix(sudo:session): session closed for user root May 05 14:29:03 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 14:29:03 romeo volumio[698]: info: Loading plugin "wizard"... May 05 14:29:03 romeo volumio[698]: info: Loading plugin "networkfs"... May 05 14:29:03 romeo sudo[749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.11/Sea2/Extended-Disk /mnt/NAS/Extended-Disk May 05 14:29:03 romeo sudo[749]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:29:03 romeo volumio[698]: info: Starting Udev Watcher for removable devices May 05 14:29:03 romeo sudo[751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.11/Sea2\\music /mnt/NAS/music May 05 14:29:03 romeo sudo[751]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:29:03 romeo sudo[754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.11/Sea2\\Louis-DVD /mnt/NAS/Louis-DVD May 05 14:29:03 romeo sudo[754]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:29:03 romeo volumio[698]: info: Ignoring mount for partition: boot May 05 14:29:03 romeo volumio[698]: info: Ignoring mount for partition: volumio May 05 14:29:03 romeo volumio[698]: info: Ignoring mount for partition: volumio_data May 05 14:29:03 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 05 14:29:03 romeo volumio[698]: info: Loading plugin "volumio_command_line_client"... May 05 14:29:03 romeo volumio[698]: info: Loading plugin "upnp"... May 05 14:29:03 romeo volumio[698]: info: [1714919343222] Starting Upmpd Daemon May 05 14:29:03 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 05 14:29:03 romeo volumio[698]: info: Loading plugin "my_music"... May 05 14:29:03 romeo volumio[698]: info: Loading plugin "mpd"... May 05 14:29:03 romeo kernel: Key type cifs.spnego registered May 05 14:29:03 romeo kernel: Key type cifs.idmap registered May 05 14:29:03 romeo 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. May 05 14:29:03 romeo kernel: CIFS: Attempting to mount \\192.168.1.11\Sea2 May 05 14:29:03 romeo kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 05 14:29:03 romeo kernel: CIFS: VFS: cifs_mount failed w/return code = -101 May 05 14:29:03 romeo sudo[749]: pam_unix(sudo:session): session closed for user root May 05 14:29:03 romeo sudo[754]: pam_unix(sudo:session): session closed for user root May 05 14:29:03 romeo sudo[751]: pam_unix(sudo:session): session closed for user root May 05 14:29:03 romeo kernel: CIFS: Attempting to mount \\192.168.1.11\Sea2 May 05 14:29:03 romeo kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 05 14:29:03 romeo kernel: CIFS: VFS: cifs_mount failed w/return code = -101 May 05 14:29:03 romeo kernel: CIFS: Attempting to mount \\192.168.1.11\Sea2 May 05 14:29:03 romeo kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 05 14:29:03 romeo kernel: CIFS: VFS: cifs_mount failed w/return code = -101 May 05 14:29:03 romeo volumio[698]: info: Loading plugin "upnp_browser"... May 05 14:29:03 romeo volumio[698]: info: Loading plugin "alarm-clock"... May 05 14:29:03 romeo volumio[698]: info: Plugin airplay_emulation is not enabled May 05 14:29:03 romeo volumio[698]: info: Loading plugin "last_100"... May 05 14:29:03 romeo volumio[698]: info: Loading plugin "webradio"... May 05 14:29:04 romeo volumio[698]: info: Loading plugin "i2s_dacs"... May 05 14:29:04 romeo volumio[698]: info: Loading plugin "volumiodiscovery"... May 05 14:29:04 romeo volumio[698]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 05 14:29:04 romeo volumio[698]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 14:29:04 romeo node[698]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 05 14:29:04 romeo volumio[698]: *** WARNING *** For more information see May 05 14:29:04 romeo volumio[698]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 05 14:29:04 romeo volumio[698]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 14:29:04 romeo volumio[698]: *** WARNING *** For more information see May 05 14:29:04 romeo node[698]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 14:29:04 romeo node[698]: *** WARNING *** For more information see May 05 14:29:04 romeo node[698]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 05 14:29:04 romeo node[698]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 14:29:04 romeo node[698]: *** WARNING *** For more information see May 05 14:29:04 romeo volumio[698]: info: Applying required configuration parameters for plugin volumiodiscovery May 05 14:29:04 romeo volumio[698]: info: Discovery: Started advertising with name: Romeo May 05 14:29:04 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 05 14:29:04 romeo volumio[698]: info: Loading plugin "outputs"... May 05 14:29:04 romeo volumio[698]: info: Loading plugin "albumart"... May 05 14:29:04 romeo volumio[698]: info: Plugin example_plugin is not enabled May 05 14:29:04 romeo volumio[698]: info: Loading plugin "inputs"... May 05 14:29:04 romeo volumio[698]: info: Loading plugin "updater_comm"... May 05 14:29:04 romeo volumio[698]: info: Plugin mpdemulation is not enabled May 05 14:29:04 romeo volumio[698]: info: Loading plugin "rest_api"... May 05 14:29:04 romeo volumio[698]: info: Loading plugin "websocket"... May 05 14:29:04 romeo volumio[698]: info: Loading plugin "minidlna"... May 05 14:29:04 romeo volumio[698]: Forking 3 albumart workers May 05 14:29:04 romeo volumio[698]: info: Applying required configuration parameters for plugin minidlna May 05 14:29:04 romeo volumio[698]: info: Loading i18n strings for locale en May 05 14:29:04 romeo volumio[698]: Updating browse sources language May 05 14:29:04 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 14:29:04 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 14:29:04 romeo volumio[698]: info: CoreCommandRouter::initPlayerControls May 05 14:29:04 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:04 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:04 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:04 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:04 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:04 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:04 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:04 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:04 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 14:29:04 romeo volumio[698]: Express server listening on port 3000 May 05 14:29:04 romeo volumio[698]: [Metrics] WebUI: 4s 351.45ms May 05 14:29:04 romeo volumio[698]: info: CoreStateMachine::resetVolumioState May 05 14:29:04 romeo volumio[698]: info: CoreStateMachine::getcurrentVolume May 05 14:29:04 romeo volumio[698]: info: CoreCommandRouter::volumioRetrievevolume May 05 14:29:04 romeo volumio[698]: info: CoreStateMachine::pushState May 05 14:29:04 romeo volumio[698]: info: CorePlayQueue::getTrack 0 May 05 14:29:04 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 14:29:04 romeo volumio[698]: info: CoreCommandRouter::volumioPushState May 05 14:29:05 romeo volumio[698]: Starting albumart workers May 05 14:29:05 romeo volumio[698]: Starting albumart workers May 05 14:29:05 romeo volumio[698]: Starting albumart workers May 05 14:29:05 romeo volumio[698]: info: Cannot mount NAS Louis-DVD at system boot, trial number 1 ,retrying in 5 seconds May 05 14:29:05 romeo volumio[698]: info: Cannot mount NAS music at system boot, trial number 1 ,retrying in 5 seconds May 05 14:29:05 romeo volumio[698]: info: Cannot mount NAS Extended-Disk at system boot, trial number 1 ,retrying in 5 seconds May 05 14:29:05 romeo volumio-remote-updater[522]: [2024-05-05 14:29:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1714919342 101 May 05 14:29:05 romeo volumio[698]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 14:29:05 romeo volumio[698]: info: Reloading queue from file May 05 14:29:05 romeo volumio[698]: info: CoreStateMachine::setRepeat null single undefined May 05 14:29:05 romeo volumio[698]: info: CoreStateMachine::pushState May 05 14:29:05 romeo volumio[698]: info: CorePlayQueue::getTrack 0 May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::volumioPushState May 05 14:29:05 romeo volumio[698]: info: CoreStateMachine::setRandom false May 05 14:29:05 romeo volumio[698]: info: CoreStateMachine::pushState May 05 14:29:05 romeo volumio[698]: info: CorePlayQueue::getTrack 0 May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::volumioPushState May 05 14:29:05 romeo volumio[698]: info: Setting Device type: Raspberry PI May 05 14:29:05 romeo volumio[698]: info: Completed loading Core Plugins May 05 14:29:05 romeo volumio[698]: info: Preparing to generate the ALSA configuration file May 05 14:29:05 romeo volumio[698]: info: Asound.conf file unchanged, so no further update is needed May 05 14:29:05 romeo volumio[698]: info: Output device has changed, restarting MPD May 05 14:29:05 romeo volumio[698]: info: ___________ START PLUGINS ___________ May 05 14:29:05 romeo volumio[698]: info: ControllerMpd::onStart: Initializing MPD May 05 14:29:05 romeo volumio[698]: info: Creating MPD Configuration file May 05 14:29:05 romeo sudo[812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 05 14:29:05 romeo sudo[812]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:29:05 romeo sudo[812]: pam_unix(sudo:session): session closed for user root May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 14:29:05 romeo volumio[698]: info: [1714919345315] CoreMusicLibrary::Adding element Media Servers May 05 14:29:05 romeo sudo[813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 14:29:05 romeo sudo[813]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 14:29:05 romeo volumio[698]: info: [1714919345360] CoreMusicLibrary::Adding element Last_100 May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 14:29:05 romeo sudo[818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 14:29:05 romeo volumio[698]: info: [1714919345363] CoreMusicLibrary::Adding element Webradio May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 14:29:05 romeo sudo[818]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:29:05 romeo sudo[816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 14:29:05 romeo sudo[816]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:29:05 romeo sudo[816]: pam_unix(sudo:session): session closed for user root May 05 14:29:05 romeo volumio[698]: info: Loading i18n strings for locale en May 05 14:29:05 romeo volumio[698]: info: Volumio Calling Home May 05 14:29:05 romeo systemd[1]: Listening on mpd.socket. May 05 14:29:05 romeo systemd[1]: Starting Music Player Daemon... May 05 14:29:05 romeo sh[404]: eth0: leased 192.168.1.5 for 3600 seconds May 05 14:29:05 romeo sh[404]: eth0: adding route to 192.168.1.0/24 May 05 14:29:05 romeo sh[404]: eth0: adding default route via 192.168.1.254 May 05 14:29:05 romeo dhcpcd[451]: eth0: leased 192.168.1.5 for 3600 seconds May 05 14:29:05 romeo dhcpcd[451]: eth0: adding route to 192.168.1.0/24 May 05 14:29:05 romeo dhcpcd[451]: eth0: adding default route via 192.168.1.254 May 05 14:29:05 romeo avahi-daemon[529]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.5. May 05 14:29:05 romeo avahi-daemon[529]: New relevant interface eth0.IPv4 for mDNS. May 05 14:29:05 romeo avahi-daemon[529]: Registering new address record for 192.168.1.5 on eth0.IPv4. May 05 14:29:05 romeo sh[404]: forked to background, child pid 874 May 05 14:29:05 romeo dhcpcd[451]: forked to background, child pid 874 May 05 14:29:05 romeo volumio[698]: Unhandled rejection Error: No sockets available, cannot start. May 05 14:29:05 romeo volumio[698]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) May 05 14:29:05 romeo volumio[698]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) May 05 14:29:05 romeo volumio[698]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 May 05 14:29:05 romeo volumio[698]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) May 05 14:29:05 romeo volumio[698]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) May 05 14:29:05 romeo volumio[698]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) May 05 14:29:05 romeo volumio[698]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) May 05 14:29:05 romeo volumio[698]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) May 05 14:29:05 romeo volumio[698]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) May 05 14:29:05 romeo volumio[698]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) May 05 14:29:05 romeo volumio[698]: at HashMap. (/volumio/app/pluginmanager.js:496:31) May 05 14:29:05 romeo volumio[698]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 05 14:29:05 romeo volumio[698]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 05 14:29:05 romeo volumio[698]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) May 05 14:29:05 romeo volumio[698]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) May 05 14:29:05 romeo volumio[698]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 05 14:29:05 romeo volumio[698]: info: MPD Permissions set May 05 14:29:05 romeo volumio[698]: info: MPD Permissions set May 05 14:29:05 romeo volumio[698]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 May 05 14:29:05 romeo volumio[698]: info: Volumio called home May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 14:29:05 romeo ifplugd(eth0)[658]: client: ifup: interface eth0 already configured May 05 14:29:05 romeo sh[404]: eth0=eth0 May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 14:29:05 romeo ifplugd(eth0)[658]: Program executed successfully. May 05 14:29:05 romeo volumio[698]: info: CoreCommandRouter::volumioGetState May 05 14:29:05 romeo volumio[698]: info: CorePlayQueue::getTrack 0 May 05 14:29:06 romeo volumio[698]: info: Discovery: adding d52c3f10-479c-4fdc-8d4f-3fda2b30152e May 05 14:29:06 romeo volumio[698]: info: Discovery: Found device Romeo May 05 14:29:06 romeo volumio[698]: info: CoreCommandRouter::volumioGetState May 05 14:29:06 romeo volumio[698]: info: CorePlayQueue::getTrack 0 May 05 14:29:06 romeo volumio[698]: info: minidlna: Found miniDLNA version 1.2.1 May 05 14:29:06 romeo volumio[698]: info: minidlna: Starting minidlna.service May 05 14:29:06 romeo sudo[910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service May 05 14:29:06 romeo sudo[910]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:29:06 romeo systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. May 05 14:29:06 romeo sudo[910]: pam_unix(sudo:session): session closed for user root May 05 14:29:06 romeo volumio[698]: info: minidlna: systemctl start minidlna.service succeeded. May 05 14:29:06 romeo ntpd[659]: Listen normally on 3 eth0 192.168.1.5:123 May 05 14:29:06 romeo ntpd[659]: new interface(s) found: waking up resolver May 05 14:29:07 romeo mpd[838]: May 05 14:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 05 14:29:07 romeo systemd[1]: Started Music Player Daemon. May 05 14:29:07 romeo sudo[818]: pam_unix(sudo:session): session closed for user root May 05 14:29:07 romeo sudo[813]: pam_unix(sudo:session): session closed for user root May 05 14:29:07 romeo volumio[698]: info: Completed starting Core Plugins May 05 14:29:07 romeo volumio[698]: info: ------------------------------------------- May 05 14:29:07 romeo volumio[698]: info: ----- MyVolumio plugins startup ---- May 05 14:29:07 romeo volumio[698]: info: ------------------------------------------- May 05 14:29:07 romeo volumio[698]: info: [MyVolumio PluginManager] Fetching plans data.... May 05 14:29:07 romeo volumio[698]: error: MPD error: The expression evaluated to a falsy value: May 05 14:29:07 romeo volumio[698]: assert.ok(self.idling) May 05 14:29:07 romeo volumio[698]: error: The expression evaluated to a falsy value: May 05 14:29:07 romeo volumio[698]: assert.ok(self.idling) May 05 14:29:07 romeo volumio[698]: error: updateQueue error: null May 05 14:29:07 romeo volumio[698]: info: MPD running with PID838 May 05 14:29:07 romeo volumio[698]: ,establishing connection May 05 14:29:07 romeo volumio[698]: error: updateQueue error: null May 05 14:29:08 romeo nmbd[619]: [2024/05/05 14:29:08.288895, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 05 14:29:08 romeo nmbd[619]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 05 14:29:08 romeo systemd[1]: Started Samba NMB Daemon. May 05 14:29:08 romeo systemd[1]: Starting Samba Winbind Daemon... May 05 14:29:08 romeo winbindd[922]: [2024/05/05 14:29:08.523036, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 05 14:29:08 romeo winbindd[922]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 05 14:29:08 romeo winbindd[922]: [2024/05/05 14:29:08.538804, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 05 14:29:08 romeo winbindd[922]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 05 14:29:08 romeo systemd[1]: Started Samba Winbind Daemon. May 05 14:29:08 romeo volumio[698]: verbose: New Socket.io Connection to 192.168.1.5 from 192.168.1.60 UA: Mozilla/5.0 (X11; Linux x86_64; rv:125.0) Gecko/20100101 Firefox/125.0 Total Clients: 3 May 05 14:29:08 romeo volumio[698]: verbose: New Socket.io Connection to 192.168.1.5 from 192.168.1.60 UA: Mozilla/5.0 (X11; Linux x86_64; rv:125.0) Gecko/20100101 Firefox/125.0 Total Clients: 4 May 05 14:29:08 romeo volumio[698]: verbose: New Socket.io Connection to 192.168.1.5 from 192.168.1.60 UA: Mozilla/5.0 (X11; Linux x86_64; rv:125.0) Gecko/20100101 Firefox/125.0 Total Clients: 5 May 05 14:29:08 romeo volumio[698]: verbose: New Socket.io Connection to 192.168.1.5 from 192.168.1.60 UA: Mozilla/5.0 (X11; Linux x86_64; rv:125.0) Gecko/20100101 Firefox/125.0 Total Clients: 6 May 05 14:29:08 romeo volumio[698]: verbose: New Socket.io Connection to 192.168.1.5 from 192.168.1.60 UA: Mozilla/5.0 (X11; Linux x86_64; rv:125.0) Gecko/20100101 Firefox/125.0 Total Clients: 7 May 05 14:29:08 romeo systemd[1]: Starting Samba SMB Daemon... May 05 14:29:08 romeo volumio[698]: verbose: New Socket.io Connection to 192.168.1.5 from 192.168.1.60 UA: Mozilla/5.0 (X11; Linux x86_64; rv:125.0) Gecko/20100101 Firefox/125.0 Total Clients: 8 May 05 14:29:08 romeo volumio[698]: verbose: New Socket.io Connection to 192.168.1.5 from 192.168.1.60 UA: Mozilla/5.0 (X11; Linux x86_64; rv:125.0) Gecko/20100101 Firefox/125.0 Total Clients: 9 May 05 14:29:08 romeo volumio[698]: verbose: New Socket.io Connection to 192.168.1.5 from 192.168.1.60 UA: Mozilla/5.0 (X11; Linux x86_64; rv:125.0) Gecko/20100101 Firefox/125.0 Total Clients: 10 May 05 14:29:08 romeo smbd[927]: [2024/05/05 14:29:08.904133, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 05 14:29:08 romeo smbd[927]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 05 14:29:08 romeo systemd[1]: Started Samba SMB Daemon. May 05 14:29:08 romeo systemd[1]: Reached target Multi-User System. May 05 14:29:08 romeo systemd[1]: Reached target Graphical Interface. May 05 14:29:08 romeo systemd[1]: Starting Update UTMP about System Runlevel Changes... May 05 14:29:08 romeo systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 05 14:29:08 romeo systemd[1]: Started Update UTMP about System Runlevel Changes. May 05 14:29:08 romeo systemd[1]: Startup finished in 9.010s (kernel) + 19.718s (userspace) = 28.728s. May 05 14:29:10 romeo sudo[937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.11/Sea2\\Louis-DVD /mnt/NAS/Louis-DVD May 05 14:29:10 romeo sudo[937]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:29:10 romeo sudo[939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.11/Sea2\\music /mnt/NAS/music May 05 14:29:10 romeo sudo[939]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:29:10 romeo volumio[698]: verbose: New Socket.io Connection to 192.168.1.5 from 192.168.1.60 UA: Mozilla/5.0 (X11; Linux x86_64; rv:125.0) Gecko/20100101 Firefox/125.0 Total Clients: 11 May 05 14:29:10 romeo sudo[943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.11/Sea2/Extended-Disk /mnt/NAS/Extended-Disk May 05 14:29:10 romeo sudo[943]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:29:10 romeo kernel: CIFS: Attempting to mount \\192.168.1.11\Sea2 May 05 14:29:10 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 05 14:29:10 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 05 14:29:10 romeo volumio[698]: info: CoreCommandRouter::volumioGetVisibleSources May 05 14:29:10 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 14:29:10 romeo volumio[698]: info: CoreCommandRouter::volumioGetState May 05 14:29:10 romeo volumio[698]: info: CorePlayQueue::getTrack 0 May 05 14:29:10 romeo volumio[698]: info: CoreCommandRouter::volumioGetState May 05 14:29:10 romeo volumio[698]: info: CorePlayQueue::getTrack 0 May 05 14:29:10 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 05 14:29:10 romeo volumio[698]: info: CoreCommandRouter::volumioGetQueue May 05 14:29:10 romeo volumio[698]: info: CoreStateMachine::getQueue May 05 14:29:10 romeo volumio[698]: info: CorePlayQueue::getQueue May 05 14:29:10 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 05 14:29:10 romeo volumio[698]: info: Received Get System Info May 05 14:29:10 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 14:29:10 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 14:29:10 romeo volumio[698]: info: Discovery: Getting this device information May 05 14:29:10 romeo volumio[698]: info: CoreCommandRouter::volumioGetState May 05 14:29:10 romeo volumio[698]: info: CorePlayQueue::getTrack 0 May 05 14:29:10 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 14:29:10 romeo volumio[698]: info: CoreCommandRouter::volumioGetState May 05 14:29:10 romeo volumio[698]: info: CorePlayQueue::getTrack 0 May 05 14:29:10 romeo volumio[698]: info: Listing playlists May 05 14:29:10 romeo kernel: CIFS: Attempting to mount \\192.168.1.11\Sea2 May 05 14:29:10 romeo sudo[937]: pam_unix(sudo:session): session closed for user root May 05 14:29:10 romeo sudo[939]: pam_unix(sudo:session): session closed for user root May 05 14:29:10 romeo kernel: CIFS: Attempting to mount \\192.168.1.11\Sea2 May 05 14:29:10 romeo sudo[943]: pam_unix(sudo:session): session closed for user root May 05 14:29:11 romeo volumio[698]: info: CoreCommandRouter::volumioGetState May 05 14:29:11 romeo volumio[698]: info: CorePlayQueue::getTrack 0 May 05 14:29:12 romeo volumio[698]: info: CoreCommandRouter::volumioGetState May 05 14:29:12 romeo volumio[698]: info: CorePlayQueue::getTrack 0 May 05 14:29:13 romeo sudo[965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 05 14:29:13 romeo sudo[965]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:29:13 romeo sudo[965]: pam_unix(sudo:session): session closed for user root May 05 14:29:13 romeo sudo[967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 14:29:13 romeo sudo[967]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:29:13 romeo volumio[698]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 05 14:29:13 romeo sudo[967]: pam_unix(sudo:session): session closed for user root May 05 14:29:13 romeo sudo[971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 05 14:29:13 romeo sudo[971]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:29:13 romeo systemd[1]: Started UPnP Renderer front-end to MPD. May 05 14:29:13 romeo sudo[971]: pam_unix(sudo:session): session closed for user root May 05 14:29:13 romeo volumio[698]: info: Upmpdcli Daemon Started May 05 14:29:15 romeo volumio[973]: Generating RSA private key, 4096 bit long modulus (2 primes) May 05 14:29:15 romeo volumio[973]: ........++++ May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 05 14:29:16 romeo volumio[698]: info: Adding plugin bluetooth to MyMusic Plugins May 05 14:29:16 romeo volumio[698]: info: Adding plugin multiroom to MyMusic Plugins May 05 14:29:16 romeo volumio[698]: info: Adding plugin metavolumio to MyMusic Plugins May 05 14:29:16 romeo volumio[698]: info: Adding plugin cd_controller to MyMusic Plugins May 05 14:29:16 romeo volumio[698]: info: Adding plugin smart_inputs to MyMusic Plugins May 05 14:29:16 romeo volumio[698]: info: Adding plugin tidalconnect to MyMusic Plugins May 05 14:29:16 romeo volumio[698]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 05 14:29:17 romeo volumio[698]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 05 14:29:17 romeo volumio[698]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 05 14:29:17 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 14:29:17 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 14:29:17 romeo volumio[698]: info: Starting MyVolumio Remote Streaming Endpoints May 05 14:29:17 romeo volumio[698]: info: MyVolumio login type: Token May 05 14:29:17 romeo volumio[698]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 05 14:29:17 romeo volumio[698]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 05 14:29:18 romeo volumio[698]: info: Starting Streaming Service Transparent Proxy May 05 14:29:18 romeo volumio[698]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 05 14:29:18 romeo volumio[698]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 05 14:29:18 romeo volumio[698]: info: Streaming services startup May 05 14:29:18 romeo volumio[698]: info: Starting Streaming Daemon May 05 14:29:18 romeo sudo[996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 05 14:29:18 romeo sudo[996]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:29:18 romeo volumio[698]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 05 14:29:18 romeo sudo[996]: pam_unix(sudo:session): session closed for user root May 05 14:29:18 romeo volumio[698]: error: Cannot start Volumio Streaming Daemon May 05 14:29:18 romeo volumio[698]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 05 14:29:18 romeo volumio[698]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 05 14:29:19 romeo volumio[698]: STREAMING PROXY: Starting server on port 3245 May 05 14:29:19 romeo volumio[698]: Node JS runtime: 14 May 05 14:29:20 romeo volumio[973]: .................................................++++ May 05 14:29:20 romeo volumio[973]: e is 65537 (0x010001) May 05 14:29:20 romeo volumio[973]: writing RSA key May 05 14:29:23 romeo volumio[698]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 05 14:29:24 romeo systemd[1]: systemd-fsckd.service: Succeeded. May 05 14:29:25 romeo volumio[698]: info: MyVolumio token set successfully May 05 14:29:25 romeo volumio[698]: info: MYVOLUMIO: Adding device May 05 14:29:25 romeo volumio[698]: info: MYVOLUMIO: Evaluating Server May 05 14:29:26 romeo volumio[698]: info: MyVolumio status changed May 05 14:29:26 romeo volumio[698]: info: Streaming services startup May 05 14:29:26 romeo volumio[698]: info: Starting Streaming Daemon May 05 14:29:26 romeo volumio[698]: info: Removing browser output: myVolumio user plan is not superstar May 05 14:29:26 romeo volumio[698]: info: Removing audio output: May 05 14:29:26 romeo volumio[698]: info: Stoppping Tunnel 1 May 05 14:29:26 romeo sudo[1036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 05 14:29:26 romeo sudo[1036]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:29:26 romeo sudo[1036]: pam_unix(sudo:session): session closed for user root May 05 14:29:26 romeo sudo[1039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 05 14:29:26 romeo sudo[1039]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 14:29:26 romeo sudo[1039]: pam_unix(sudo:session): session closed for user root May 05 14:29:28 romeo volumio[698]: Cannot compose Albumart path May 05 14:29:28 romeo volumio[698]: Cannot compose Albumart path May 05 14:29:28 romeo volumio[698]: Cannot compose Albumart path May 05 14:29:28 romeo systemd[1]: systemd-hostnamed.service: Succeeded. May 05 14:29:28 romeo volumio[698]: Cannot compose Albumart path May 05 14:29:28 romeo volumio[698]: Cannot compose Albumart path May 05 14:29:28 romeo volumio[698]: Cannot compose Albumart path May 05 14:29:28 romeo volumio[698]: Cannot compose Albumart path May 05 14:29:28 romeo volumio[698]: Cannot compose Albumart path May 05 14:29:28 romeo volumio[698]: Cannot compose Albumart path May 05 14:29:28 romeo volumio[698]: Cannot compose Albumart path May 05 14:29:28 romeo volumio[698]: Cannot compose Albumart path May 05 14:29:28 romeo volumio[698]: Cannot compose Albumart path May 05 14:29:28 romeo volumio[698]: Cannot compose Albumart path May 05 14:29:28 romeo volumio[698]: Cannot compose Albumart path May 05 14:29:28 romeo volumio[698]: Cannot compose Albumart path May 05 14:29:29 romeo volumio[698]: Cannot compose Albumart path May 05 14:29:29 romeo volumio[698]: error: Cannot start Volumio Streaming Daemon May 05 14:29:29 romeo volumio[698]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 05 14:29:29 romeo volumio[698]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 05 14:29:29 romeo volumio[698]: info: Setting Geolocation for MyVolumio to eu3 May 05 14:29:29 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 14:29:29 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 14:29:29 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 14:29:29 romeo volumio[698]: info: Remote SSH Stopped May 05 14:29:30 romeo volumio[698]: info: Successfully Added MyVolumio device May 05 14:29:30 romeo volumio[698]: info: Updating MyVolumio device info May 05 14:29:30 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 14:29:31 romeo volumio[698]: info: Successfully Updated MyVolumio device May 05 14:29:32 romeo volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 05 14:29:32 romeo volumio[698]: info: Preload queue cleared May 05 14:29:32 romeo volumio[698]: info: CoreCommandRouter::volumioReplaceandPlayItems May 05 14:29:32 romeo volumio[698]: info: CoreStateMachine::ClearQueue May 05 14:29:32 romeo volumio[698]: info: CoreStateMachine::stop May 05 14:29:32 romeo volumio[698]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 14:29:32 romeo volumio[698]: info: CorePlayQueue::clearPlayQueue May 05 14:29:32 romeo volumio[698]: info: CorePlayQueue::saveQueue May 05 14:29:32 romeo volumio[698]: info: CoreCommandRouter::volumioPushQueue May 05 14:29:32 romeo volumio[698]: info: CoreStateMachine::addQueueItems May 05 14:29:32 romeo volumio[698]: info: CorePlayQueue::addQueueItems May 05 14:29:32 romeo volumio[698]: info: Preload queue cleared May 05 14:29:32 romeo volumio[698]: info: Adding Item to queue: music-library/NAS/Extended-Disk/Assorted/B/Blues/Katie Melua/Piece By Piece May 05 14:29:32 romeo volumio[698]: info: Exploding uri music-library/NAS/Extended-Disk/Assorted/B/Blues/Katie Melua/Piece By Piece in service mpd May 05 14:29:32 romeo volumio[698]: info: ALBUMART /albumart?cacheid=959&web=Katie%20Melua/Piece%20By%20Piece/large&path=%2Fmnt%2FNAS%2FExtended-Disk%2FAssorted%2FB%2FBlues%2FKatie%20Melua%2FPiece%20By%20Piece%2FKatie%20Melua%20-%20Piece%20By%20Piece%20-%2001%20-%20Shy%20Boy.mp3&metadata=false May 05 14:29:32 romeo volumio[698]: info: URI /mnt/NAS/Extended-Disk/Assorted/B/Blues/Katie Melua/Piece By Piece/Katie Melua - Piece By Piece - 01 - Shy Boy.mp3 May 05 14:29:32 romeo volumio[698]: info: ALBUMART /albumart?cacheid=959&web=Katie%20Melua/Piece%20By%20Piece/large&path=%2Fmnt%2FNAS%2FExtended-Disk%2FAssorted%2FB%2FBlues%2FKatie%20Melua%2FPiece%20By%20Piece%2FKatie%20Melua%20-%20Piece%20By%20Piece%20-%2002%20-%20Nine%20Million%20Bicycles.mp3&metadata=false May 05 14:29:32 romeo volumio[698]: info: URI /mnt/NAS/Extended-Disk/Assorted/B/Blues/Katie Melua/Piece By Piece/Katie Melua - Piece By Piece - 02 - Nine Million Bicycles.mp3 May 05 14:29:32 romeo volumio[698]: info: ALBUMART /albumart?cacheid=959&web=Katie%20Melua/Piece%20By%20Piece/large&path=%2Fmnt%2FNAS%2FExtended-Disk%2FAssorted%2FB%2FBlues%2FKatie%20Melua%2FPiece%20By%20Piece%2FKatie%20Melua%20-%20Piece%20By%20Piece%20-%2003%20-%20Piece%20By%20Piece.mp3&metadata=false May 05 14:29:32 romeo volumio[698]: info: URI /mnt/NAS/Extended-Disk/Assorted/B/Blues/Katie Melua/Piece By Piece/Katie Melua - Piece By Piece - 03 - Piece By Piece.mp3 May 05 14:29:32 romeo volumio[698]: info: ALBUMART /albumart?cacheid=959&web=Katie%20Melua/Piece%20By%20Piece/large&path=%2Fmnt%2FNAS%2FExtended-Disk%2FAssorted%2FB%2FBlues%2FKatie%20Melua%2FPiece%20By%20Piece%2FKatie%20Melua%20-%20Piece%20By%20Piece%20-%2004%20-%20Halfway%20Up%20The%20Hindu%20Kush.mp3&metadata=false May 05 14:29:32 romeo volumio[698]: info: URI /mnt/NAS/Extended-Disk/Assorted/B/Blues/Katie Melua/Piece By Piece/Katie Melua - Piece By Piece - 04 - Halfway Up The Hindu Kush.mp3 May 05 14:29:32 romeo volumio[698]: info: ALBUMART /albumart?cacheid=959&web=Katie%20Melua/Piece%20By%20Piece/large&path=%2Fmnt%2FNAS%2FExtended-Disk%2FAssorted%2FB%2FBlues%2FKatie%20Melua%2FPiece%20By%20Piece%2FKatie%20Melua%20-%20Piece%20By%20Piece%20-%2005%20-%20Blues%20In%20The%20Night.mp3&metadata=false May 05 14:29:32 romeo volumio[698]: info: URI /mnt/NAS/Extended-Disk/Assorted/B/Blues/Katie Melua/Piece By Piece/Katie Melua - Piece By Piece - 05 - Blues In The Night.mp3 May 05 14:29:32 romeo volumio[698]: info: ALBUMART /albumart?cacheid=959&web=Katie%20Melua/Piece%20By%20Piece/large&path=%2Fmnt%2FNAS%2FExtended-Disk%2FAssorted%2FB%2FBlues%2FKatie%20Melua%2FPiece%20By%20Piece%2FKatie%20Melua%20-%20Piece%20By%20Piece%20-%2006%20-%20Spider's%20Web.mp3&metadata=false May 05 14:29:32 romeo volumio[698]: info: URI /mnt/NAS/Extended-Disk/Assorted/B/Blues/Katie Melua/Piece By Piece/Katie Melua - Piece By Piece - 06 - Spider's Web.mp3 May 05 14:29:32 romeo volumio[698]: info: ALBUMART /albumart?cacheid=959&web=Katie%20Melua/Piece%20By%20Piece/large&path=%2Fmnt%2FNAS%2FExtended-Disk%2FAssorted%2FB%2FBlues%2FKatie%20Melua%2FPiece%20By%20Piece%2FKatie%20Melua%20-%20Piece%20By%20Piece%20-%2007%20-%20Blue%20Shoes.mp3&metadata=false May 05 14:29:32 romeo volumio[698]: info: URI /mnt/NAS/Extended-Disk/Assorted/B/Blues/Katie Melua/Piece By Piece/Katie Melua - Piece By Piece - 07 - Blue Shoes.mp3 May 05 14:29:32 romeo volumio[698]: info: ALBUMART /albumart?cacheid=959&web=Katie%20Melua/Piece%20By%20Piece/large&path=%2Fmnt%2FNAS%2FExtended-Disk%2FAssorted%2FB%2FBlues%2FKatie%20Melua%2FPiece%20By%20Piece%2FKatie%20Melua%20-%20Piece%20By%20Piece%20-%2008%20-%20On%20The%20Road%20Again.mp3&metadata=false May 05 14:29:32 romeo volumio[698]: info: URI /mnt/NAS/Extended-Disk/Assorted/B/Blues/Katie Melua/Piece By Piece/Katie Melua - Piece By Piece - 08 - On The Road Again.mp3 May 05 14:29:32 romeo volumio[698]: info: ALBUMART /albumart?cacheid=959&web=Katie%20Melua/Piece%20By%20Piece/large&path=%2Fmnt%2FNAS%2FExtended-Disk%2FAssorted%2FB%2FBlues%2FKatie%20Melua%2FPiece%20By%20Piece%2FKatie%20Melua%20-%20Piece%20By%20Piece%20-%2009%20-%20Thank%20You%2C%20Stars.mp3&metadata=false May 05 14:29:32 romeo volumio[698]: info: URI /mnt/NAS/Extended-Disk/Assorted/B/Blues/Katie Melua/Piece By Piece/Katie Melua - Piece By Piece - 09 - Thank You, Stars.mp3 May 05 14:29:32 romeo volumio[698]: info: ALBUMART /albumart?cacheid=959&web=Katie%20Melua/Piece%20By%20Piece/large&path=%2Fmnt%2FNAS%2FExtended-Disk%2FAssorted%2FB%2FBlues%2FKatie%20Melua%2FPiece%20By%20Piece%2FKatie%20Melua%20-%20Piece%20By%20Piece%20-%2010%20-%20Just%20Like%20Heaven.mp3&metadata=false May 05 14:29:32 romeo volumio[698]: info: URI /mnt/NAS/Extended-Disk/Assorted/B/Blues/Katie Melua/Piece By Piece/Katie Melua - Piece By Piece - 10 - Just Like Heaven.mp3 May 05 14:29:32 romeo volumio[698]: info: ALBUMART /albumart?cacheid=959&web=Katie%20Melua/Piece%20By%20Piece/large&path=%2Fmnt%2FNAS%2FExtended-Disk%2FAssorted%2FB%2FBlues%2FKatie%20Melua%2FPiece%20By%20Piece%2FKatie%20Melua%20-%20Piece%20By%20Piece%20-%2011%20-%20I%20Cried%20For%20You.mp3&metadata=false May 05 14:29:32 romeo volumio[698]: info: URI /mnt/NAS/Extended-Disk/Assorted/B/Blues/Katie Melua/Piece By Piece/Katie Melua - Piece By Piece - 11 - I Cried For You.mp3 May 05 14:29:32 romeo volumio[698]: info: ALBUMART /albumart?cacheid=959&web=Katie%20Melua/Piece%20By%20Piece/large&path=%2Fmnt%2FNAS%2FExtended-Disk%2FAssorted%2FB%2FBlues%2FKatie%20Melua%2FPiece%20By%20Piece%2FKatie%20Melua%20-%20Piece%20By%20Piece%20-%2012%20-%20I%20Do%20Believe%20In%20love.mp3&metadata=false May 05 14:29:32 romeo volumio[698]: info: URI /mnt/NAS/Extended-Disk/Assorted/B/Blues/Katie Melua/Piece By Piece/Katie Melua - Piece By Piece - 12 - I Do Believe In love.mp3 May 05 14:29:32 romeo volumio[698]: info: ALBUMART /albumart?cacheid=959&web=Katie%20Melua/Piece%20By%20Piece/large&path=%2Fmnt%2FNAS%2FExtended-Disk%2FAssorted%2FB%2FBlues%2FKatie%20Melua%2FPiece%20By%20Piece%2FKatie%20Melua%20-%20Piece%20By%20Piece.zip&metadata=false May 05 14:29:32 romeo volumio[698]: info: URI /mnt/NAS/Extended-Disk/Assorted/B/Blues/Katie Melua/Piece By Piece/Katie Melua - Piece By Piece.zip May 05 14:29:32 romeo volumio[698]: info: ALBUMART /albumart?cacheid=959&web=Katie%20Melua/Piece%20By%20Piece/large&path=%2Fmnt%2FNAS%2FExtended-Disk%2FAssorted%2FB%2FBlues%2FKatie%20Melua%2FPiece%20By%20Piece%2FKatie%20Melua%20-%20Piece%20By%20Piece.zip&metadata=false May 05 14:29:32 romeo volumio[698]: info: URI /mnt/NAS/Extended-Disk/Assorted/B/Blues/Katie Melua/Piece By Piece/Katie Melua - Piece By Piece.zip May 05 14:29:32 romeo volumio[698]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 14:29:32 romeo volumio[698]: Error: Unable to resolve or reject the same promise twice May 05 14:29:32 romeo volumio[698]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 05 14:29:32 romeo volumio[698]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 May 05 14:29:32 romeo volumio[698]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) May 05 14:29:32 romeo volumio[698]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) May 05 14:29:32 romeo volumio[698]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) May 05 14:29:32 romeo volumio[698]: at Socket.emit (events.js:315:20) May 05 14:29:32 romeo volumio[698]: at addChunk (internal/streams/readable.js:309:12) May 05 14:29:32 romeo volumio[698]: at readableAddChunk (internal/streams/readable.js:280:11) May 05 14:29:32 romeo volumio[698]: at Socket.Readable.push (internal/streams/readable.js:223:10) May 05 14:29:32 romeo volumio[698]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) May 05 14:29:32 romeo volumio[698]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 14:29:33 romeo sudo[1052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-05 14:28 May 05 14:29:33 romeo sudo[1052]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"