-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Fri 2024-12-20 03:42:32 UTC. -- Dec 20 03:41:40 volumio---upstairs kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06 Dec 20 03:41:40 volumio---upstairs kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Dec 20 03:41:40 volumio---upstairs kernel: usb usb2: Product: xHCI Host Controller Dec 20 03:41:40 volumio---upstairs kernel: usb usb2: Manufacturer: Linux 6.6.62-v7l+ xhci-hcd Dec 20 03:41:40 volumio---upstairs kernel: usb usb2: SerialNumber: 0000:01:00.0 Dec 20 03:41:40 volumio---upstairs kernel: hub 2-0:1.0: USB hub found Dec 20 03:41:40 volumio---upstairs kernel: hub 2-0:1.0: 4 ports detected Dec 20 03:41:40 volumio---upstairs kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Dec 20 03:41:40 volumio---upstairs kernel: dwc_otg: FIQ enabled Dec 20 03:41:40 volumio---upstairs kernel: dwc_otg: NAK holdoff enabled Dec 20 03:41:40 volumio---upstairs kernel: dwc_otg: FIQ split-transaction FSM enabled Dec 20 03:41:40 volumio---upstairs kernel: Module dwc_common_port init Dec 20 03:41:40 volumio---upstairs kernel: usbcore: registered new interface driver uas Dec 20 03:41:40 volumio---upstairs kernel: usbcore: registered new interface driver usb-storage Dec 20 03:41:40 volumio---upstairs kernel: mousedev: PS/2 mouse device common for all mice Dec 20 03:41:40 volumio---upstairs kernel: sdhci: Secure Digital Host Controller Interface driver Dec 20 03:41:40 volumio---upstairs kernel: sdhci: Copyright(c) Pierre Ossman Dec 20 03:41:40 volumio---upstairs kernel: sdhci-pltfm: SDHCI platform and OF driver helper Dec 20 03:41:40 volumio---upstairs kernel: ledtrig-cpu: registered to indicate activity on CPUs Dec 20 03:41:40 volumio---upstairs kernel: hid: raw HID events driver (C) Jiri Kosina Dec 20 03:41:40 volumio---upstairs kernel: usbcore: registered new interface driver usbhid Dec 20 03:41:40 volumio---upstairs kernel: usbhid: USB HID core driver Dec 20 03:41:40 volumio---upstairs kernel: Initializing XFRM netlink socket Dec 20 03:41:40 volumio---upstairs kernel: NET: Registered PF_PACKET protocol family Dec 20 03:41:40 volumio---upstairs kernel: Key type dns_resolver registered Dec 20 03:41:40 volumio---upstairs kernel: Registering SWP/SWPB emulation handler Dec 20 03:41:40 volumio---upstairs kernel: registered taskstats version 1 Dec 20 03:41:40 volumio---upstairs kernel: Loading compiled-in X.509 certificates Dec 20 03:41:40 volumio---upstairs kernel: Key type .fscrypt registered Dec 20 03:41:40 volumio---upstairs kernel: Key type fscrypt-provisioning registered Dec 20 03:41:40 volumio---upstairs kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Dec 20 03:41:40 volumio---upstairs kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Dec 20 03:41:40 volumio---upstairs kernel: fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 51, base_baud = 0) is a PL011 rev2 Dec 20 03:41:40 volumio---upstairs kernel: serial serial0: tty port ttyAMA1 registered Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Dec 20 03:41:40 volumio---upstairs kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Dec 20 03:41:40 volumio---upstairs kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Dec 20 03:41:40 volumio---upstairs kernel: of_cfs_init Dec 20 03:41:40 volumio---upstairs kernel: of_cfs_init: OK Dec 20 03:41:40 volumio---upstairs kernel: clk: Disabling unused clocks Dec 20 03:41:40 volumio---upstairs kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Dec 20 03:41:40 volumio---upstairs kernel: Freeing unused kernel image (initmem) memory: 2048K Dec 20 03:41:40 volumio---upstairs kernel: Run /init as init process Dec 20 03:41:40 volumio---upstairs kernel: with arguments: Dec 20 03:41:40 volumio---upstairs kernel: /init Dec 20 03:41:40 volumio---upstairs kernel: splash Dec 20 03:41:40 volumio---upstairs kernel: nodebug Dec 20 03:41:40 volumio---upstairs kernel: with environment: Dec 20 03:41:40 volumio---upstairs kernel: HOME=/ Dec 20 03:41:40 volumio---upstairs kernel: TERM=linux Dec 20 03:41:40 volumio---upstairs kernel: numa_policy=interleave Dec 20 03:41:40 volumio---upstairs kernel: imgpart=UUID=aad048ad-7eb3-4549-8020-f5742baed86d Dec 20 03:41:40 volumio---upstairs kernel: imgfile=/volumio_current.sqsh Dec 20 03:41:40 volumio---upstairs kernel: bootpart=UUID=5883-B9C7 Dec 20 03:41:40 volumio---upstairs kernel: datapart=UUID=cfbe554b-d5ac-4dfd-b89b-effce104ffff Dec 20 03:41:40 volumio---upstairs kernel: uuidconfig=cmdline.txt Dec 20 03:41:40 volumio---upstairs kernel: pcie_aspm=off Dec 20 03:41:40 volumio---upstairs kernel: bootdelay=7 Dec 20 03:41:40 volumio---upstairs kernel: use_kmsg=no Dec 20 03:41:40 volumio---upstairs kernel: mmc1: new high speed SDIO card at address 0001 Dec 20 03:41:40 volumio---upstairs kernel: mmc0: new ultra high speed DDR50 SDHC card at address 0001 Dec 20 03:41:40 volumio---upstairs kernel: mmcblk0: mmc0:0001 SD32G 28.9 GiB Dec 20 03:41:40 volumio---upstairs kernel: mmcblk0: p1 p2 p3 Dec 20 03:41:40 volumio---upstairs kernel: mmcblk0: mmc0:0001 SD32G 28.9 GiB Dec 20 03:41:40 volumio---upstairs kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Dec 20 03:41:40 volumio---upstairs kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Dec 20 03:41:40 volumio---upstairs kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Dec 20 03:41:40 volumio---upstairs kernel: usb 1-1: Product: USB2.0 Hub Dec 20 03:41:40 volumio---upstairs kernel: hub 1-1:1.0: USB hub found Dec 20 03:41:40 volumio---upstairs kernel: hub 1-1:1.0: 4 ports detected Dec 20 03:41:40 volumio---upstairs kernel: usb 1-1.2: new high-speed USB device number 3 using xhci_hcd Dec 20 03:41:40 volumio---upstairs kernel: fuse: init (API version 7.39) Dec 20 03:41:40 volumio---upstairs kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Dec 20 03:41:40 volumio---upstairs kernel: usb 1-1.2: New USB device found, idVendor=25c4, idProduct=0008, bcdDevice= 1.61 Dec 20 03:41:40 volumio---upstairs kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 20 03:41:40 volumio---upstairs kernel: usb 1-1.2: Product: irDAC II Dec 20 03:41:40 volumio---upstairs kernel: usb 1-1.2: Manufacturer: ARCAM Dec 20 03:41:40 volumio---upstairs kernel: input: ARCAM irDAC II as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.2/1-1.2:1.3/0003:25C4:0008.0001/input/input0 Dec 20 03:41:40 volumio---upstairs kernel: hid-generic 0003:25C4:0008.0001: input,hidraw0: USB HID v1.10 Device [ARCAM irDAC II] on usb-0000:01:00.0-1.2/input3 Dec 20 03:41:40 volumio---upstairs kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Dec 20 03:41:40 volumio---upstairs kernel: EXT4-fs (mmcblk0p2): recovery complete Dec 20 03:41:40 volumio---upstairs kernel: EXT4-fs (mmcblk0p2): mounted filesystem aad048ad-7eb3-4549-8020-f5742baed86d r/w with ordered data mode. Quota mode: none. Dec 20 03:41:40 volumio---upstairs kernel: loop0: detected capacity change from 0 to 995064 Dec 20 03:41:40 volumio---upstairs kernel: EXT4-fs (mmcblk0p3): recovery complete Dec 20 03:41:40 volumio---upstairs kernel: EXT4-fs (mmcblk0p3): mounted filesystem cfbe554b-d5ac-4dfd-b89b-effce104ffff r/w with ordered data mode. Quota mode: none. Dec 20 03:41:40 volumio---upstairs systemd[1]: System time before build time, advancing clock. Dec 20 03:41:40 volumio---upstairs kernel: NET: Registered PF_INET6 protocol family Dec 20 03:41:40 volumio---upstairs kernel: Segment Routing with IPv6 Dec 20 03:41:40 volumio---upstairs kernel: In-situ OAM (IOAM) with IPv6 Dec 20 03:41:40 volumio---upstairs 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) Dec 20 03:41:40 volumio---upstairs systemd[1]: Detected architecture arm. Dec 20 03:41:40 volumio---upstairs systemd[1]: Set hostname to . Dec 20 03:41:40 volumio---upstairs 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. Dec 20 03:41:40 volumio---upstairs systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Dec 20 03:41:40 volumio---upstairs systemd[1]: Created slice User and Session Slice. Dec 20 03:41:40 volumio---upstairs systemd[1]: Reached target Slices. Dec 20 03:41:40 volumio---upstairs systemd[1]: Listening on RPCbind Server Activation Socket. Dec 20 03:41:40 volumio---upstairs systemd[1]: Reached target System Time Synchronized. Dec 20 03:41:40 volumio---upstairs systemd[1]: Created slice system-systemd\x2dfsck.slice. Dec 20 03:41:40 volumio---upstairs kernel: i2c_dev: i2c /dev entries driver Dec 20 03:41:40 volumio---upstairs systemd-journald[321]: Journal started Dec 20 03:41:40 volumio---upstairs systemd-journald[321]: Runtime journal (/run/log/journal/b09207998001defae5f73bf26405b501) is 7.5M, max 30.0M, 22.5M free. Dec 20 03:41:40 volumio---upstairs systemd-modules-load[316]: Inserted module 'i2c_dev' Dec 20 03:41:40 volumio---upstairs fake-hwclock[317]: Fri Dec 20 03:41:40 UTC 2024 Dec 20 03:41:40 volumio---upstairs systemd[1]: Started Load/Save Random Seed. Dec 20 03:41:40 volumio---upstairs systemd[1]: Started Create System Users. Dec 20 03:41:40 volumio---upstairs systemd[1]: Starting Create Static Device Nodes in /dev... Dec 20 03:41:40 volumio---upstairs systemd[1]: Started udev Coldplug all Devices. Dec 20 03:41:40 volumio---upstairs systemd[1]: Starting Helper to synchronize boot up for ifupdown... Dec 20 03:41:40 volumio---upstairs systemd[1]: dynamicswap.service: Succeeded. Dec 20 03:41:40 volumio---upstairs systemd[1]: Started Helper to synchronize boot up for ifupdown. Dec 20 03:41:40 volumio---upstairs systemd[1]: Started Create Static Device Nodes in /dev. Dec 20 03:41:40 volumio---upstairs systemd[1]: Starting udev Kernel Device Manager... Dec 20 03:41:40 volumio---upstairs systemd[1]: Reached target Local File Systems (Pre). Dec 20 03:41:40 volumio---upstairs systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Dec 20 03:41:40 volumio---upstairs systemd[1]: Mounting /var/log... Dec 20 03:41:40 volumio---upstairs systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Dec 20 03:41:40 volumio---upstairs systemd[1]: Mounting /tmp... Dec 20 03:41:40 volumio---upstairs systemd[1]: Mounting /var/spool/cups... Dec 20 03:41:40 volumio---upstairs systemd[1]: Mounted /var/log. Dec 20 03:41:40 volumio---upstairs systemd[1]: Mounted /tmp. Dec 20 03:41:40 volumio---upstairs systemd[1]: Mounted /var/spool/cups. Dec 20 03:41:40 volumio---upstairs systemd[1]: Mounting /var/spool/cups/tmp... Dec 20 03:41:40 volumio---upstairs systemd[1]: Starting Flush Journal to Persistent Storage... Dec 20 03:41:40 volumio---upstairs systemd-udevd[344]: Network interface NamePolicy= disabled on kernel command line, ignoring. Dec 20 03:41:40 volumio---upstairs systemd[1]: Mounted /var/spool/cups/tmp. Dec 20 03:41:40 volumio---upstairs systemd-journald[321]: Runtime journal (/run/log/journal/b09207998001defae5f73bf26405b501) is 7.5M, max 30.0M, 22.5M free. Dec 20 03:41:40 volumio---upstairs systemd[1]: Started udev Kernel Device Manager. Dec 20 03:41:40 volumio---upstairs systemd[1]: Starting Show Plymouth Boot Screen... Dec 20 03:41:40 volumio---upstairs systemd[1]: Started Flush Journal to Persistent Storage. Dec 20 03:41:40 volumio---upstairs systemd[1]: plymouth-start.service: Succeeded. Dec 20 03:41:40 volumio---upstairs systemd[1]: Started Show Plymouth Boot Screen. Dec 20 03:41:40 volumio---upstairs systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Dec 20 03:41:40 volumio---upstairs systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Dec 20 03:41:40 volumio---upstairs systemd[1]: Reached target Paths. Dec 20 03:41:40 volumio---upstairs systemd[1]: Reached target Local Encrypted Volumes. Dec 20 03:41:40 volumio---upstairs systemd-udevd[365]: Using default interface naming scheme 'v240'. Dec 20 03:41:40 volumio---upstairs kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Dec 20 03:41:40 volumio---upstairs kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Dec 20 03:41:40 volumio---upstairs systemd-udevd[365]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 20 03:41:40 volumio---upstairs kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Dec 20 03:41:40 volumio---upstairs kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Dec 20 03:41:40 volumio---upstairs kernel: [vc_sm_connected_init]: start Dec 20 03:41:40 volumio---upstairs kernel: [vc_sm_connected_init]: installed successfully Dec 20 03:41:40 volumio---upstairs kernel: mc: Linux media interface: v0.10 Dec 20 03:41:40 volumio---upstairs systemd-udevd[369]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 20 03:41:40 volumio---upstairs kernel: videodev: Linux video capture interface: v2.00 Dec 20 03:41:40 volumio---upstairs kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Dec 20 03:41:40 volumio---upstairs kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Dec 20 03:41:40 volumio---upstairs kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Dec 20 03:41:40 volumio---upstairs kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Dec 20 03:41:40 volumio---upstairs kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Dec 20 03:41:40 volumio---upstairs kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Dec 20 03:41:40 volumio---upstairs kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Dec 20 03:41:40 volumio---upstairs kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Dec 20 03:41:40 volumio---upstairs kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Dec 20 03:41:40 volumio---upstairs kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Dec 20 03:41:40 volumio---upstairs kernel: rpivid feb10000.codec: Device registered as /dev/video19 Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Dec 20 03:41:40 volumio---upstairs kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Dec 20 03:41:40 volumio---upstairs kernel: Bluetooth: Core ver 2.22 Dec 20 03:41:40 volumio---upstairs kernel: NET: Registered PF_BLUETOOTH protocol family Dec 20 03:41:40 volumio---upstairs kernel: Bluetooth: HCI device and connection manager initialized Dec 20 03:41:40 volumio---upstairs kernel: Bluetooth: HCI socket layer initialized Dec 20 03:41:40 volumio---upstairs kernel: Bluetooth: L2CAP socket layer initialized Dec 20 03:41:40 volumio---upstairs kernel: Bluetooth: SCO socket layer initialized Dec 20 03:41:40 volumio---upstairs kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Dec 20 03:41:40 volumio---upstairs kernel: Bluetooth: HCI UART driver ver 2.3 Dec 20 03:41:40 volumio---upstairs kernel: Bluetooth: HCI UART protocol H4 registered Dec 20 03:41:40 volumio---upstairs kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Dec 20 03:41:40 volumio---upstairs kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Dec 20 03:41:41 volumio---upstairs kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Dec 20 03:41:41 volumio---upstairs kernel: Bluetooth: HCI UART protocol Broadcom registered Dec 20 03:41:41 volumio---upstairs kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Dec 20 03:41:41 volumio---upstairs kernel: usbcore: registered new interface driver brcmfmac Dec 20 03:41:41 volumio---upstairs kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Dec 20 03:41:41 volumio---upstairs kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Dec 20 03:41:41 volumio---upstairs kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Dec 20 03:41:41 volumio---upstairs kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Dec 20 03:41:41 volumio---upstairs kernel: uart-pl011 fe201000.serial: no DMA platform data Dec 20 03:41:41 volumio---upstairs systemd[1]: Found device /dev/disk/by-uuid/5883-B9C7. Dec 20 03:41:41 volumio---upstairs systemd-udevd[352]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Dec 20 03:41:41 volumio---upstairs systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Dec 20 03:41:41 volumio---upstairs kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Dec 20 03:41:41 volumio---upstairs 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 Dec 20 03:41:41 volumio---upstairs systemd-udevd[376]: Using default interface naming scheme 'v240'. Dec 20 03:41:41 volumio---upstairs systemd-udevd[376]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 20 03:41:41 volumio---upstairs kernel: Bluetooth: hci0: BCM: chip id 107 Dec 20 03:41:41 volumio---upstairs kernel: Bluetooth: hci0: BCM: features 0x2f Dec 20 03:41:41 volumio---upstairs kernel: Bluetooth: hci0: BCM4345C0 Dec 20 03:41:41 volumio---upstairs kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Dec 20 03:41:41 volumio---upstairs kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Dec 20 03:41:41 volumio---upstairs kernel: usb 1-1.2: 1:3 : unsupported format bits 0x100000000 Dec 20 03:41:41 volumio---upstairs kernel: usbcore: registered new interface driver snd-usb-audio Dec 20 03:41:41 volumio---upstairs kernel: alsactl[498]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Dec 20 03:41:41 volumio---upstairs systemd-udevd[364]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 1' failed with exit code 17. Dec 20 03:41:41 volumio---upstairs systemd[1]: Created slice system-bthelper.slice. Dec 20 03:41:41 volumio---upstairs systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Dec 20 03:41:41 volumio---upstairs systemd[1]: Starting Show Plymouth Boot Screen... Dec 20 03:41:41 volumio---upstairs systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Dec 20 03:41:41 volumio---upstairs systemd[1]: Condition check resulted in Huge Pages File System being skipped. Dec 20 03:41:41 volumio---upstairs systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Dec 20 03:41:41 volumio---upstairs systemd[1]: Starting File System Check on /dev/disk/by-uuid/5883-B9C7... Dec 20 03:41:41 volumio---upstairs systemd[1]: Starting Load/Save RF Kill Switch Status... Dec 20 03:41:41 volumio---upstairs systemd[1]: Started File System Check Daemon to report status. Dec 20 03:41:41 volumio---upstairs systemd[1]: plymouth-start.service: Succeeded. Dec 20 03:41:41 volumio---upstairs systemd[1]: Started Show Plymouth Boot Screen. Dec 20 03:41:41 volumio---upstairs systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Dec 20 03:41:41 volumio---upstairs systemd-udevd[350]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Dec 20 03:41:41 volumio---upstairs systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Dec 20 03:41:41 volumio---upstairs systemd[1]: Started Load/Save RF Kill Switch Status. Dec 20 03:41:41 volumio---upstairs systemd-fsck[503]: fsck.fat 4.1 (2017-01-24) Dec 20 03:41:41 volumio---upstairs systemd-fsck[503]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Dec 20 03:41:41 volumio---upstairs systemd-fsck[503]: Automatically removing dirty bit. Dec 20 03:41:41 volumio---upstairs systemd-fsck[503]: Performing changes. Dec 20 03:41:41 volumio---upstairs systemd-fsck[503]: /dev/mmcblk0p1: 410 files, 41752/46774 clusters Dec 20 03:41:41 volumio---upstairs systemd[1]: Started File System Check on /dev/disk/by-uuid/5883-B9C7. Dec 20 03:41:41 volumio---upstairs systemd[1]: Mounting /boot... Dec 20 03:41:41 volumio---upstairs systemd[1]: Mounted /boot. Dec 20 03:41:41 volumio---upstairs systemd[1]: Reached target Local File Systems. Dec 20 03:41:42 volumio---upstairs systemd[1]: Started ifup for wlan0. Dec 20 03:41:42 volumio---upstairs systemd[1]: Started ifup for eth0. Dec 20 03:41:42 volumio---upstairs systemd[1]: Starting Create Volatile Files and Directories... Dec 20 03:41:42 volumio---upstairs systemd[1]: Starting Raise network interfaces... Dec 20 03:41:42 volumio---upstairs systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Dec 20 03:41:42 volumio---upstairs systemd[1]: Starting Preprocess NFS configuration... Dec 20 03:41:42 volumio---upstairs systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Dec 20 03:41:42 volumio---upstairs systemd[1]: Received SIGRTMIN+20 from PID 206 (plymouthd). Dec 20 03:41:42 volumio---upstairs systemd[1]: plymouth-read-write.service: Succeeded. Dec 20 03:41:42 volumio---upstairs systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Dec 20 03:41:42 volumio---upstairs systemd[1]: nfs-config.service: Succeeded. Dec 20 03:41:42 volumio---upstairs systemd[1]: Started Preprocess NFS configuration. Dec 20 03:41:42 volumio---upstairs systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Dec 20 03:41:42 volumio---upstairs systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Dec 20 03:41:42 volumio---upstairs systemd[1]: Reached target NFS client services. Dec 20 03:41:42 volumio---upstairs kernel: Bluetooth: hci0: BCM: features 0x2f Dec 20 03:41:42 volumio---upstairs kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Dec 20 03:41:42 volumio---upstairs kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Dec 20 03:41:42 volumio---upstairs kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Dec 20 03:41:42 volumio---upstairs systemd[1]: Started Create Volatile Files and Directories. Dec 20 03:41:42 volumio---upstairs systemd[1]: Starting RPC bind portmap service... Dec 20 03:41:42 volumio---upstairs systemd[1]: Starting Update UTMP about System Boot/Shutdown... Dec 20 03:41:42 volumio---upstairs systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Dec 20 03:41:42 volumio---upstairs systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Dec 20 03:41:42 volumio---upstairs systemd[1]: Started RPC bind portmap service. Dec 20 03:41:42 volumio---upstairs systemd[1]: Reached target RPC Port Mapper. Dec 20 03:41:42 volumio---upstairs systemd[1]: Reached target Remote File Systems (Pre). Dec 20 03:41:42 volumio---upstairs systemd[1]: Reached target Remote File Systems. Dec 20 03:41:42 volumio---upstairs systemd[1]: Started Update UTMP about System Boot/Shutdown. Dec 20 03:41:42 volumio---upstairs systemd[1]: Reached target System Initialization. Dec 20 03:41:42 volumio---upstairs systemd[1]: apt-daily.timer: Not using persistent file timestamp Thu 2025-02-20 21:15:35 UTC as it is in the future. Dec 20 03:41:42 volumio---upstairs systemd[1]: Started Daily apt download activities. Dec 20 03:41:42 volumio---upstairs systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Thu 2025-02-20 06:15:35 UTC as it is in the future. Dec 20 03:41:42 volumio---upstairs systemd[1]: Started Daily apt upgrade and clean activities. Dec 20 03:41:42 volumio---upstairs systemd[1]: Listening on D-Bus System Message Bus Socket. Dec 20 03:41:42 volumio---upstairs systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Dec 20 03:41:42 volumio---upstairs systemd[1]: Started Daily Cleanup of Temporary Directories. Dec 20 03:41:42 volumio---upstairs systemd[1]: Reached target Timers. Dec 20 03:41:42 volumio---upstairs systemd[1]: Listening on triggerhappy.socket. Dec 20 03:41:42 volumio---upstairs systemd[1]: Reached target Sockets. Dec 20 03:41:42 volumio---upstairs systemd[1]: Reached target Basic System. Dec 20 03:41:42 volumio---upstairs systemd[1]: Started Manage Sound Card State (restore and store). Dec 20 03:41:42 volumio---upstairs kernel: 8021q: 802.1Q VLAN Support v1.8 Dec 20 03:41:42 volumio---upstairs systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Dec 20 03:41:42 volumio---upstairs alsactl[584]: alsactl 1.1.8 daemon started Dec 20 03:41:42 volumio---upstairs systemd[1]: Starting Save/Restore Sound Card State... Dec 20 03:41:42 volumio---upstairs systemd[1]: Started UPnP Renderer front-end to MPD. Dec 20 03:41:42 volumio---upstairs systemd[1]: Started MPD Monitor Service. Dec 20 03:41:42 volumio---upstairs systemd[1]: Started D-Bus System Message Bus. Dec 20 03:41:42 volumio---upstairs kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 20 03:41:42 volumio---upstairs systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Dec 20 03:41:42 volumio---upstairs systemd[1]: Starting Wireless Services... Dec 20 03:41:42 volumio---upstairs systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Dec 20 03:41:42 volumio---upstairs systemd[1]: Started Volumio Iptables Module. Dec 20 03:41:42 volumio---upstairs systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Dec 20 03:41:42 volumio---upstairs systemd[1]: Started volumio-remote-updater.service. Dec 20 03:41:42 volumio---upstairs systemd[1]: Starting Login Service... Dec 20 03:41:42 volumio---upstairs systemd[1]: Starting triggerhappy global hotkey daemon... Dec 20 03:41:42 volumio---upstairs systemd[1]: Starting Volumio Time Update Utility... Dec 20 03:41:42 volumio---upstairs systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Dec 20 03:41:42 volumio---upstairs systemd[1]: Starting WPA supplicant... Dec 20 03:41:42 volumio---upstairs systemd[1]: Started Volumio Log Rotation Service. Dec 20 03:41:42 volumio---upstairs systemd[1]: Starting dhcpcd on all interfaces... Dec 20 03:41:42 volumio---upstairs systemd[1]: Starting Configure Bluetooth Modems connected by UART... Dec 20 03:41:42 volumio---upstairs systemd[1]: Started Save/Restore Sound Card State. Dec 20 03:41:42 volumio---upstairs systemd[1]: Reached target Sound Card. Dec 20 03:41:42 volumio---upstairs volumio-time-update[601]: volumio-time-update-util: Fetching time from Volumio... Dec 20 03:41:42 volumio---upstairs thd[600]: Unable to parse trigger line: Dec 20 03:41:42 volumio---upstairs thd[600]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Dec 20 03:41:42 volumio---upstairs thd[600]: Unable to parse trigger line: Dec 20 03:41:42 volumio---upstairs thd[600]: Found socket passed from systemd Dec 20 03:41:42 volumio---upstairs dhcpcd[605]: Not running dhcpcd because /etc/network/interfaces Dec 20 03:41:42 volumio---upstairs dhcpcd[605]: defines some interfaces that will use a Dec 20 03:41:42 volumio---upstairs dhcpcd[605]: DHCP client or static address Dec 20 03:41:42 volumio---upstairs avahi-daemon[585]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Dec 20 03:41:42 volumio---upstairs avahi-daemon[585]: Successfully dropped root privileges. Dec 20 03:41:42 volumio---upstairs avahi-daemon[585]: avahi-daemon 0.7 starting up. Dec 20 03:41:42 volumio---upstairs mpd_monitor.sh[588]: MPD error: Connection refused Dec 20 03:41:42 volumio---upstairs systemd-logind[599]: New seat seat0. Dec 20 03:41:42 volumio---upstairs mpd_monitor.sh[588]: mpd: no process found Dec 20 03:41:42 volumio---upstairs volumio-remote-updater[598]: Error: No active session Dec 20 03:41:42 volumio---upstairs volumio-remote-updater[598]: [2024-12-20 03:41:42] [info] asio async_connect error: system:111 (Connection refused) Dec 20 03:41:42 volumio---upstairs volumio-remote-updater[598]: [2024-12-20 03:41:42] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 20 03:41:42 volumio---upstairs volumio-remote-updater[598]: [2024-12-20 03:41:42] [error] handle_connect error: Underlying Transport Error Dec 20 03:41:42 volumio---upstairs volumio-time-update[601]: volumio-time-update-util: Date not found in response Dec 20 03:41:42 volumio---upstairs volumio-time-update[601]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 03:41:42 volumio---upstairs avahi-daemon[585]: Successfully called chroot(). Dec 20 03:41:42 volumio---upstairs avahi-daemon[585]: Successfully dropped remaining capabilities. Dec 20 03:41:42 volumio---upstairs systemd[1]: Started Login Service. Dec 20 03:41:42 volumio---upstairs systemd[1]: Started triggerhappy global hotkey daemon. Dec 20 03:41:42 volumio---upstairs systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Dec 20 03:41:42 volumio---upstairs systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Dec 20 03:41:42 volumio---upstairs systemd[1]: Failed to start dhcpcd on all interfaces. Dec 20 03:41:42 volumio---upstairs systemd[1]: hciuart.service: Succeeded. Dec 20 03:41:42 volumio---upstairs avahi-daemon[585]: Loading service file /services/volumio.service. Dec 20 03:41:42 volumio---upstairs wpa_supplicant[603]: Successfully initialized wpa_supplicant Dec 20 03:41:42 volumio---upstairs systemd[1]: Started Configure Bluetooth Modems connected by UART. Dec 20 03:41:42 volumio---upstairs avahi-daemon[585]: Network interface enumeration completed. Dec 20 03:41:42 volumio---upstairs volumio[587]: Could not open config: /tmp/upmpdcli.conf Dec 20 03:41:42 volumio---upstairs avahi-daemon[585]: Server startup complete. Host name is volumio---upstairs.local. Local service cookie is 3740056. Dec 20 03:41:42 volumio---upstairs avahi-daemon[585]: Service "Volumio - Upstairs" (/services/volumio.service) successfully established. Dec 20 03:41:42 volumio---upstairs systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 20 03:41:42 volumio---upstairs systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 20 03:41:42 volumio---upstairs sh[513]: eth0: waiting for carrier Dec 20 03:41:42 volumio---upstairs dhcpcd[562]: eth0: waiting for carrier Dec 20 03:41:42 volumio---upstairs kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Dec 20 03:41:42 volumio---upstairs kernel: bcmgenet fd580000.ethernet eth0: Link is Down Dec 20 03:41:42 volumio---upstairs systemd[1]: Started Avahi mDNS/DNS-SD Stack. Dec 20 03:41:42 volumio---upstairs systemd[1]: Started WPA supplicant. Dec 20 03:41:42 volumio---upstairs sh[512]: wlan0=wlan0 Dec 20 03:41:43 volumio---upstairs systemd[1]: Listening on mpd.socket. Dec 20 03:41:43 volumio---upstairs systemd[1]: Starting Raspberry Pi bluetooth helper... Dec 20 03:41:43 volumio---upstairs systemd[1]: Started Raise network interfaces. Dec 20 03:41:43 volumio---upstairs systemd[1]: Reached target Network. Dec 20 03:41:43 volumio---upstairs systemd[1]: Starting Music Player Daemon... Dec 20 03:41:43 volumio---upstairs systemd[1]: Starting Permit User Sessions... Dec 20 03:41:43 volumio---upstairs bthelper[704]: Raspberry Pi BDADDR already set Dec 20 03:41:43 volumio---upstairs systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Dec 20 03:41:43 volumio---upstairs systemd[1]: Starting Network Time Service... Dec 20 03:41:43 volumio---upstairs systemd[1]: Reached target Network is Online. Dec 20 03:41:43 volumio---upstairs systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 20 03:41:43 volumio---upstairs systemd[1]: Starting Samba NMB Daemon... Dec 20 03:41:43 volumio---upstairs systemd[1]: Starting LSB: Brings up/down network automatically... Dec 20 03:41:43 volumio---upstairs systemd[1]: Starting /etc/rc.local Compatibility... Dec 20 03:41:43 volumio---upstairs systemd[1]: Started Raspberry Pi bluetooth helper. Dec 20 03:41:43 volumio---upstairs systemd[1]: Started Permit User Sessions. Dec 20 03:41:43 volumio---upstairs systemd[1]: Started /etc/rc.local Compatibility. Dec 20 03:41:43 volumio---upstairs systemd[1]: iptables.service: Succeeded. Dec 20 03:41:43 volumio---upstairs systemd[1]: Starting Hold until boot process finishes up... Dec 20 03:41:43 volumio---upstairs loadcpufreq[602]: Loading cpufreq kernel modules...done (none). Dec 20 03:41:43 volumio---upstairs systemd[1]: Starting Terminate Plymouth Boot Screen... Dec 20 03:41:43 volumio---upstairs systemd[1]: Starting Bluetooth service... Dec 20 03:41:43 volumio---upstairs systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Dec 20 03:41:43 volumio---upstairs systemd[1]: Starting LSB: set CPUFreq kernel parameters... Dec 20 03:41:43 volumio---upstairs systemd[1]: Received SIGRTMIN+21 from PID 206 (plymouthd). Dec 20 03:41:43 volumio---upstairs systemd[1]: Received SIGRTMIN+21 from PID 206 (plymouthd). Dec 20 03:41:43 volumio---upstairs systemd[1]: plymouth-quit-wait.service: Succeeded. Dec 20 03:41:43 volumio---upstairs systemd[1]: Started Hold until boot process finishes up. Dec 20 03:41:43 volumio---upstairs systemd[1]: plymouth-quit.service: Succeeded. Dec 20 03:41:43 volumio---upstairs systemd[1]: Started Terminate Plymouth Boot Screen. Dec 20 03:41:43 volumio---upstairs systemd[1]: Started Getty on tty1. Dec 20 03:41:43 volumio---upstairs systemd[1]: Reached target Login Prompts. Dec 20 03:41:43 volumio---upstairs haveged[569]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Dec 20 03:41:43 volumio---upstairs haveged[569]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Dec 20 03:41:43 volumio---upstairs haveged[569]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00001 Dec 20 03:41:43 volumio---upstairs haveged[569]: haveged: fills: 0, generated: 0 Dec 20 03:41:43 volumio---upstairs ifplugd(eth0)[774]: ifplugd 0.28 initializing. Dec 20 03:41:43 volumio---upstairs ifplugd(eth0)[774]: Using interface eth0/DC:A6:32:A1:DF:5B with driver (version: 6.6.62-v7l+) Dec 20 03:41:43 volumio---upstairs ifplugd(eth0)[774]: Using detection mode: SIOCETHTOOL Dec 20 03:41:43 volumio---upstairs ifplugd(eth0)[774]: Initialization complete, link beat not detected. Dec 20 03:41:43 volumio---upstairs ntpd[727]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 20 03:41:43 volumio---upstairs ntpd[727]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Dec 20 03:41:43 volumio---upstairs systemd[1]: Started Network Time Service. Dec 20 03:41:43 volumio---upstairs ntpd[781]: proto: precision = 0.833 usec (-20) Dec 20 03:41:43 volumio---upstairs ntpd[781]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 20 03:41:43 volumio---upstairs ntpd[781]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 20 03:41:43 volumio---upstairs ntpd[781]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): will expire in less than 8 days Dec 20 03:41:43 volumio---upstairs ntpd[781]: Listen and drop on 0 v6wildcard [::]:123 Dec 20 03:41:43 volumio---upstairs ntpd[781]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 20 03:41:43 volumio---upstairs ntpd[781]: Listen normally on 2 lo 127.0.0.1:123 Dec 20 03:41:43 volumio---upstairs ntpd[781]: Listening on routing socket on fd #19 for interface updates Dec 20 03:41:43 volumio---upstairs ntpd[781]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 20 03:41:43 volumio---upstairs ntpd[781]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 20 03:41:43 volumio---upstairs cpufrequtils[758]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Dec 20 03:41:43 volumio---upstairs systemd[1]: Started LSB: set CPUFreq kernel parameters. Dec 20 03:41:43 volumio---upstairs ifplugd[742]: Network Interface Plugging Daemon...start eth0...done. Dec 20 03:41:43 volumio---upstairs systemd[1]: Started LSB: Brings up/down network automatically. Dec 20 03:41:43 volumio---upstairs bluetoothd[755]: Bluetooth daemon 5.50 Dec 20 03:41:43 volumio---upstairs bluetoothd[755]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Dec 20 03:41:43 volumio---upstairs systemd[1]: Started Bluetooth service. Dec 20 03:41:43 volumio---upstairs systemd[1]: Reached target Bluetooth. Dec 20 03:41:43 volumio---upstairs bluetoothd[755]: Starting SDP server Dec 20 03:41:43 volumio---upstairs bluetoothd[755]: Excluding (cli) sap Dec 20 03:41:43 volumio---upstairs sudo[716]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 20 03:41:43 volumio---upstairs sudo[716]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:41:43 volumio---upstairs sudo[716]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 20 03:41:43 volumio---upstairs sudo[716]: pam_unix(sudo:session): session closed for user root Dec 20 03:41:43 volumio---upstairs kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Dec 20 03:41:43 volumio---upstairs kernel: Bluetooth: BNEP filters: protocol multicast Dec 20 03:41:43 volumio---upstairs kernel: Bluetooth: BNEP socket layer initialized Dec 20 03:41:43 volumio---upstairs bluetoothd[755]: Bluetooth management interface 1.22 initialized Dec 20 03:41:43 volumio---upstairs dbus-daemon[589]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=755 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Dec 20 03:41:43 volumio---upstairs kernel: Bluetooth: MGMT ver 1.22 Dec 20 03:41:43 volumio---upstairs bluetoothd[755]: Failed to set privacy: Rejected (0x0b) Dec 20 03:41:43 volumio---upstairs systemd[1]: Starting Hostname Service... Dec 20 03:41:44 volumio---upstairs dbus-daemon[589]: [system] Successfully activated service 'org.freedesktop.hostname1' Dec 20 03:41:44 volumio---upstairs systemd[1]: Started Hostname Service. Dec 20 03:41:44 volumio---upstairs nmbd[749]: [2024/12/20 03:41:44.423324, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 20 03:41:44 volumio---upstairs nmbd[749]: started asyncdns process 796 Dec 20 03:41:44 volumio---upstairs nmbd[749]: [2024/12/20 03:41:44.425019, 0] ../lib/util/become_daemon.c:149(daemon_status) Dec 20 03:41:44 volumio---upstairs nmbd[749]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Dec 20 03:41:44 volumio---upstairs nmbd[749]: [2024/12/20 03:41:44.425202, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Dec 20 03:41:44 volumio---upstairs nmbd[749]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Dec 20 03:41:44 volumio---upstairs ntpd[781]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 20 03:41:44 volumio---upstairs wireless.js[594]: Cleaning previous... Dec 20 03:41:44 volumio---upstairs sudo[804]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 20 03:41:44 volumio---upstairs sudo[804]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:41:44 volumio---upstairs sudo[804]: pam_unix(sudo:session): session closed for user root Dec 20 03:41:44 volumio---upstairs sudo[806]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 20 03:41:44 volumio---upstairs sudo[806]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:41:44 volumio---upstairs kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx Dec 20 03:41:45 volumio---upstairs sh[513]: eth0: carrier acquired Dec 20 03:41:45 volumio---upstairs dhcpcd[562]: eth0: carrier acquired Dec 20 03:41:45 volumio---upstairs sudo[806]: pam_unix(sudo:session): session closed for user root Dec 20 03:41:45 volumio---upstairs sh[513]: DUID 00:01:00:01:2b:98:73:3b:dc:a6:32:a1:df:5b Dec 20 03:41:45 volumio---upstairs sh[513]: eth0: IAID 32:a1:df:5b Dec 20 03:41:45 volumio---upstairs dhcpcd[562]: DUID 00:01:00:01:2b:98:73:3b:dc:a6:32:a1:df:5b Dec 20 03:41:45 volumio---upstairs dhcpcd[562]: eth0: IAID 32:a1:df:5b Dec 20 03:41:45 volumio---upstairs sh[513]: eth0: adding address fe80::9870:da1d:675:8026 Dec 20 03:41:45 volumio---upstairs sh[513]: ipv6_addaddr1: Permission denied Dec 20 03:41:45 volumio---upstairs dhcpcd[562]: eth0: adding address fe80::9870:da1d:675:8026 Dec 20 03:41:45 volumio---upstairs dhcpcd[562]: ipv6_addaddr1: Permission denied Dec 20 03:41:45 volumio---upstairs wireless.js[594]: Stopped aP Dec 20 03:41:45 volumio---upstairs wireless.js[594]: Wireless Networking DISABLED, not starting wireless flow Dec 20 03:41:45 volumio---upstairs ntpd[781]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 20 03:41:45 volumio---upstairs ifplugd(eth0)[774]: Link beat detected. Dec 20 03:41:45 volumio---upstairs systemd[1]: wireless.service: Succeeded. Dec 20 03:41:45 volumio---upstairs systemd[1]: Started Wireless Services. Dec 20 03:41:45 volumio---upstairs systemd[1]: Started Volumio Backend Module. Dec 20 03:41:45 volumio---upstairs systemd[1]: Started Volumio Cpu Tweaker. Dec 20 03:41:45 volumio---upstairs volumio-cpu-tweak[816]: Setting RT Priority for mpd Dec 20 03:41:45 volumio---upstairs sh[513]: eth0: rebinding lease of 192.168.100.142 Dec 20 03:41:45 volumio---upstairs dhcpcd[562]: eth0: rebinding lease of 192.168.100.142 Dec 20 03:41:45 volumio---upstairs volumio-cpu-tweak[816]: Setting MPD Affinity Dec 20 03:41:45 volumio---upstairs volumio-cpu-tweak[816]: pid 793's current affinity mask: f Dec 20 03:41:45 volumio---upstairs volumio-cpu-tweak[816]: pid 793's new affinity mask: 3 Dec 20 03:41:45 volumio---upstairs ifplugd(eth0)[774]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Dec 20 03:41:45 volumio---upstairs volumio-cpu-tweak[816]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Dec 20 03:41:45 volumio---upstairs volumio-cpu-tweak[816]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Dec 20 03:41:45 volumio---upstairs ifplugd(eth0)[774]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Dec 20 03:41:45 volumio---upstairs systemd[1]: volumio_cpu_tweak.service: Succeeded. Dec 20 03:41:46 volumio---upstairs mpd[793]: Dec 20 03:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 20 03:41:46 volumio---upstairs sh[513]: eth0: soliciting an IPv6 router Dec 20 03:41:46 volumio---upstairs dhcpcd[562]: eth0: soliciting an IPv6 router Dec 20 03:41:46 volumio---upstairs systemd[1]: Started Music Player Daemon. Dec 20 03:41:46 volumio---upstairs mpd_monitor.sh[588]: MPD restarted due to no mpc output. Dec 20 03:41:46 volumio---upstairs ntpd[781]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 20 03:41:46 volumio---upstairs systemd[1]: systemd-rfkill.service: Succeeded. Dec 20 03:41:47 volumio---upstairs volumio[815]: info: ------------------------------------------- Dec 20 03:41:47 volumio---upstairs volumio[815]: info: ----- Volumio3 ---- Dec 20 03:41:47 volumio---upstairs volumio[815]: info: ------------------------------------------- Dec 20 03:41:47 volumio---upstairs volumio[815]: info: ----- System startup ---- Dec 20 03:41:47 volumio---upstairs volumio[815]: info: ------------------------------------------- Dec 20 03:41:47 volumio---upstairs ntpd[781]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 20 03:41:47 volumio---upstairs volumio-remote-updater[598]: [2024-12-20 03:41:47] [connect] Successful connection Dec 20 03:41:47 volumio---upstairs volumio-time-update[601]: volumio-time-update-util: Fetching time from Volumio... Dec 20 03:41:47 volumio---upstairs volumio-time-update[601]: volumio-time-update-util: Date not found in response Dec 20 03:41:47 volumio---upstairs volumio-time-update[601]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 03:41:48 volumio---upstairs volumio[815]: info: MYVOLUMIO Environment detected Dec 20 03:41:48 volumio---upstairs bthelper[704]: Changing power off succeeded Dec 20 03:41:48 volumio---upstairs volumio[815]: info: Plugin folders cleanup Dec 20 03:41:48 volumio---upstairs volumio[815]: info: Scanning into folder /volumio/app/plugins/ Dec 20 03:41:48 volumio---upstairs volumio[815]: info: Scanning category audio_interface Dec 20 03:41:48 volumio---upstairs volumio[815]: info: Scanning category miscellanea Dec 20 03:41:48 volumio---upstairs volumio[815]: info: Scanning category music_service Dec 20 03:41:48 volumio---upstairs volumio[815]: info: Scanning category plugins.json Dec 20 03:41:48 volumio---upstairs volumio[815]: info: Scanning category system_controller Dec 20 03:41:48 volumio---upstairs volumio[815]: info: Scanning category user_interface Dec 20 03:41:48 volumio---upstairs volumio[815]: info: Scanning into folder /data/plugins/ Dec 20 03:41:48 volumio---upstairs volumio[815]: info: Scanning category music_service Dec 20 03:41:48 volumio---upstairs volumio[815]: info: Scanning category user_interface Dec 20 03:41:48 volumio---upstairs volumio[815]: info: Plugin folders cleanup completed Dec 20 03:41:48 volumio---upstairs volumio[815]: info: ------------------------------------------- Dec 20 03:41:48 volumio---upstairs volumio[815]: info: ----- Core plugins startup ---- Dec 20 03:41:48 volumio---upstairs volumio[815]: info: ------------------------------------------- Dec 20 03:41:48 volumio---upstairs volumio[815]: info: Loading plugins from folder /volumio/app/plugins/ Dec 20 03:41:48 volumio---upstairs volumio[815]: info: Adding plugin upnp to MyMusic Plugins Dec 20 03:41:48 volumio---upstairs volumio[815]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 20 03:41:48 volumio---upstairs volumio[815]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 20 03:41:48 volumio---upstairs volumio[815]: info: Loading plugins from folder /data/plugins/ Dec 20 03:41:48 volumio---upstairs volumio[815]: info: Loading plugin "system"... Dec 20 03:41:48 volumio---upstairs volumio[815]: info: Loading plugin "appearance"... Dec 20 03:41:48 volumio---upstairs bthelper[704]: [CHG] Controller DC:A6:32:A1:DF:5F Class: 0x0000041c Dec 20 03:41:48 volumio---upstairs bthelper[704]: Changing power on succeeded Dec 20 03:41:49 volumio---upstairs volumio[815]: info: Loading plugin "network"... Dec 20 03:41:49 volumio---upstairs volumio[815]: info: Refreshing Cached IP Addresses Dec 20 03:41:49 volumio---upstairs sudo[868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 03:41:49 volumio---upstairs sudo[868]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:41:49 volumio---upstairs sudo[868]: pam_unix(sudo:session): session closed for user root Dec 20 03:41:49 volumio---upstairs sudo[870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 03:41:49 volumio---upstairs sudo[870]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:41:49 volumio---upstairs volumio[815]: info: Loading plugin "services"... Dec 20 03:41:49 volumio---upstairs volumio[815]: info: Loading plugin "alsa_controller"... Dec 20 03:41:49 volumio---upstairs sudo[870]: pam_unix(sudo:session): session closed for user root Dec 20 03:41:49 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 03:41:49 volumio---upstairs volumio[815]: info: Loading plugin "wizard"... Dec 20 03:41:49 volumio---upstairs volumio[815]: info: Loading plugin "networkfs"... Dec 20 03:41:49 volumio---upstairs volumio[815]: info: Starting Udev Watcher for removable devices Dec 20 03:41:49 volumio---upstairs sudo[893]: 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.100.175/jules/music /mnt/NAS/Music Dec 20 03:41:49 volumio---upstairs sudo[893]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:41:49 volumio---upstairs sudo[895]: 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.100.175/jules/albums /mnt/NAS/Albums Dec 20 03:41:49 volumio---upstairs sudo[895]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:41:49 volumio---upstairs volumio[815]: info: Ignoring mount for partition: boot Dec 20 03:41:49 volumio---upstairs volumio[815]: info: Ignoring mount for partition: volumio Dec 20 03:41:49 volumio---upstairs volumio[815]: info: Ignoring mount for partition: volumio_data Dec 20 03:41:49 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 03:41:49 volumio---upstairs volumio[815]: info: Loading plugin "volumio_command_line_client"... Dec 20 03:41:49 volumio---upstairs volumio[815]: info: Loading plugin "upnp"... Dec 20 03:41:49 volumio---upstairs volumio[815]: info: [1734666109550] Starting Upmpd Daemon Dec 20 03:41:49 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 03:41:49 volumio---upstairs volumio[815]: info: Loading plugin "my_music"... Dec 20 03:41:49 volumio---upstairs volumio[815]: info: Loading plugin "mpd"... Dec 20 03:41:49 volumio---upstairs sudo[893]: pam_unix(sudo:session): session closed for user root Dec 20 03:41:49 volumio---upstairs sudo[895]: pam_unix(sudo:session): session closed for user root Dec 20 03:41:49 volumio---upstairs kernel: Key type cifs.spnego registered Dec 20 03:41:49 volumio---upstairs kernel: Key type cifs.idmap registered Dec 20 03:41:49 volumio---upstairs 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. Dec 20 03:41:49 volumio---upstairs kernel: CIFS: Attempting to mount //192.168.100.175/jules/music Dec 20 03:41:49 volumio---upstairs kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Dec 20 03:41:49 volumio---upstairs kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Dec 20 03:41:49 volumio---upstairs kernel: CIFS: Attempting to mount //192.168.100.175/jules/albums Dec 20 03:41:49 volumio---upstairs kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Dec 20 03:41:49 volumio---upstairs kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Dec 20 03:41:49 volumio---upstairs volumio[815]: info: Loading plugin "upnp_browser"... Dec 20 03:41:50 volumio---upstairs volumio[815]: info: Loading plugin "alarm-clock"... Dec 20 03:41:50 volumio---upstairs volumio[815]: info: Loading plugin "airplay_emulation"... Dec 20 03:41:50 volumio---upstairs volumio[815]: info: Starting Shairport Sync Dec 20 03:41:50 volumio---upstairs volumio[815]: info: Loading plugin "last_100"... Dec 20 03:41:50 volumio---upstairs volumio[815]: info: Loading plugin "webradio"... Dec 20 03:41:50 volumio---upstairs volumio[815]: info: Loading plugin "i2s_dacs"... Dec 20 03:41:50 volumio---upstairs volumio[815]: info: I2S DAC not set, start Auto-detection Dec 20 03:41:50 volumio---upstairs volumio[815]: info: Loading plugin "volumiodiscovery"... Dec 20 03:41:50 volumio---upstairs volumio[815]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 03:41:50 volumio---upstairs volumio[815]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 03:41:50 volumio---upstairs node[815]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 03:41:50 volumio---upstairs volumio[815]: *** WARNING *** For more information see Dec 20 03:41:50 volumio---upstairs volumio[815]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 03:41:50 volumio---upstairs volumio[815]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 03:41:50 volumio---upstairs volumio[815]: *** WARNING *** For more information see Dec 20 03:41:50 volumio---upstairs node[815]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 03:41:50 volumio---upstairs node[815]: *** WARNING *** For more information see Dec 20 03:41:50 volumio---upstairs node[815]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 03:41:50 volumio---upstairs node[815]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 03:41:50 volumio---upstairs node[815]: *** WARNING *** For more information see Dec 20 03:41:50 volumio---upstairs volumio[815]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 20 03:41:50 volumio---upstairs volumio[815]: info: Discovery: Started advertising with name: Volumio - Upstairs Dec 20 03:41:50 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 03:41:50 volumio---upstairs volumio[815]: info: Loading plugin "mixcloud"... Dec 20 03:41:50 volumio---upstairs sh[513]: eth0: probing for an IPv4LL address Dec 20 03:41:50 volumio---upstairs dhcpcd[562]: eth0: probing for an IPv4LL address Dec 20 03:41:50 volumio---upstairs sh[513]: eth0: DHCP lease expired Dec 20 03:41:50 volumio---upstairs dhcpcd[562]: eth0: DHCP lease expired Dec 20 03:41:50 volumio---upstairs sh[513]: eth0: soliciting a DHCP lease Dec 20 03:41:50 volumio---upstairs dhcpcd[562]: eth0: soliciting a DHCP lease Dec 20 03:41:50 volumio---upstairs volumio[815]: info: Loading plugin "outputs"... Dec 20 03:41:50 volumio---upstairs volumio[815]: info: Loading plugin "albumart"... Dec 20 03:41:50 volumio---upstairs volumio[815]: info: Plugin example_plugin is not enabled Dec 20 03:41:50 volumio---upstairs volumio[815]: info: Loading plugin "inputs"... Dec 20 03:41:50 volumio---upstairs volumio[815]: info: Loading plugin "updater_comm"... Dec 20 03:41:50 volumio---upstairs volumio[815]: info: Plugin mpdemulation is not enabled Dec 20 03:41:50 volumio---upstairs volumio[815]: info: Loading plugin "rest_api"... Dec 20 03:41:50 volumio---upstairs volumio[815]: info: Loading plugin "websocket"... Dec 20 03:41:50 volumio---upstairs volumio[815]: info: Starting Socket.io Server version 2.3.0 Dec 20 03:41:50 volumio---upstairs volumio[815]: info: Loading plugin "motherearthradio"... Dec 20 03:41:50 volumio---upstairs volumio[815]: Forking 3 albumart workers Dec 20 03:41:51 volumio---upstairs volumio[815]: info: Applying required configuration parameters for plugin motherearthradio Dec 20 03:41:51 volumio---upstairs volumio[815]: info: [1734666111311] [MotherEarth] API delay: 5 Dec 20 03:41:51 volumio---upstairs volumio[815]: info: Loading plugin "radio_paradise"... Dec 20 03:41:51 volumio---upstairs volumio[815]: Starting albumart workers Dec 20 03:41:51 volumio---upstairs volumio[815]: Starting albumart workers Dec 20 03:41:51 volumio---upstairs volumio[815]: Starting albumart workers Dec 20 03:41:51 volumio---upstairs volumio[815]: info: Applying required configuration parameters for plugin radio_paradise Dec 20 03:41:51 volumio---upstairs volumio[815]: info: [1734666111639] [RadioParadise] API delay: 5 Dec 20 03:41:51 volumio---upstairs volumio[815]: info: Loading plugin "lastfm"... Dec 20 03:41:52 volumio---upstairs volumio[815]: info: Loading i18n strings for locale en Dec 20 03:41:52 volumio---upstairs volumio[815]: Updating browse sources language Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::initPlayerControls Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 03:41:52 volumio---upstairs volumio[815]: Express server listening on port 3000 Dec 20 03:41:52 volumio---upstairs volumio[815]: [Metrics] WebUI: 5s 720.23ms Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreStateMachine::resetVolumioState Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreStateMachine::getcurrentVolume Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 03:41:52 volumio---upstairs volumio[815]: info: Volumio Network Manager: Network status updated: 0 Dec 20 03:41:52 volumio---upstairs volumio[815]: info: Cannot mount NAS Albums at system boot, trial number 1 ,retrying in 5 seconds Dec 20 03:41:52 volumio---upstairs volumio[815]: info: Cannot mount NAS Music at system boot, trial number 1 ,retrying in 5 seconds Dec 20 03:41:52 volumio---upstairs volumio-remote-updater[598]: [2024-12-20 03:41:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1734666107 101 Dec 20 03:41:52 volumio---upstairs volumio[815]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Dec 20 03:41:52 volumio---upstairs volumio[815]: info: VolumeController:: Volume=100 Mute =false Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreStateMachine::pushState Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CorePlayQueue::getTrack 0 Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::volumioPushState Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreStateMachine::updateTrackBlock Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CorePlayQueue::getTrackBlock Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 03:41:52 volumio---upstairs volumio[815]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 20 03:41:52 volumio---upstairs volumio[815]: info: Reloading queue from file Dec 20 03:41:52 volumio---upstairs volumio-time-update[601]: volumio-time-update-util: Fetching time from Volumio... Dec 20 03:41:52 volumio---upstairs volumio-time-update[601]: volumio-time-update-util: Date not found in response Dec 20 03:41:52 volumio---upstairs volumio-time-update[601]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 03:41:52 volumio---upstairs volumio[815]: info: Setting Device type: Raspberry PI Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreStateMachine::setRepeat true single undefined Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreStateMachine::pushState Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CorePlayQueue::getTrack 0 Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::volumioPushState Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreStateMachine::setRandom false Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreStateMachine::pushState Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CorePlayQueue::getTrack 0 Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::volumioPushState Dec 20 03:41:52 volumio---upstairs volumio[815]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 20 03:41:52 volumio---upstairs volumio[815]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Dec 20 03:41:52 volumio---upstairs volumio[815]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 20 03:41:52 volumio---upstairs volumio[815]: info: VolumeController:: Volume=100 Mute =false Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreStateMachine::pushState Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CorePlayQueue::getTrack 0 Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::volumioPushState Dec 20 03:41:52 volumio---upstairs volumio[815]: info: Completed loading Core Plugins Dec 20 03:41:52 volumio---upstairs volumio[815]: info: Preparing to generate the ALSA configuration file Dec 20 03:41:52 volumio---upstairs volumio[815]: info: Asound.conf file unchanged, so no further update is needed Dec 20 03:41:52 volumio---upstairs volumio[815]: info: Output device has changed, restarting MPD Dec 20 03:41:52 volumio---upstairs sudo[993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 03:41:52 volumio---upstairs sudo[993]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:41:52 volumio---upstairs volumio[815]: info: Output device has changed, restarting Shairport Sync Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 03:41:52 volumio---upstairs sudo[993]: pam_unix(sudo:session): session closed for user root Dec 20 03:41:52 volumio---upstairs sudo[995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 03:41:52 volumio---upstairs sudo[995]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:41:52 volumio---upstairs systemd[1]: Stopping Music Player Daemon... Dec 20 03:41:52 volumio---upstairs volumio[815]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 03:41:52 volumio---upstairs volumio[815]: info: ___________ START PLUGINS ___________ Dec 20 03:41:52 volumio---upstairs volumio[815]: info: ControllerMpd::onStart: Initializing MPD Dec 20 03:41:52 volumio---upstairs volumio[815]: info: Creating MPD Configuration file Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 03:41:52 volumio---upstairs volumio[815]: info: [1734666112990] CoreMusicLibrary::Adding element Media Servers Dec 20 03:41:52 volumio---upstairs sudo[1002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 03:41:52 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 03:41:52 volumio---upstairs sudo[1002]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:41:52 volumio---upstairs sudo[1002]: pam_unix(sudo:session): session closed for user root Dec 20 03:41:53 volumio---upstairs sudo[1004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 03:41:53 volumio---upstairs sudo[1004]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:41:53 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:41:53 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 03:41:53 volumio---upstairs volumio[815]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 03:41:53 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:41:53 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 03:41:53 volumio---upstairs volumio[815]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 03:41:53 volumio---upstairs volumio[815]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 03:41:53 volumio---upstairs volumio[815]: info: [1734666113077] CoreMusicLibrary::Adding element Last_100 Dec 20 03:41:53 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 03:41:53 volumio---upstairs systemd[1]: mpd.service: Succeeded. Dec 20 03:41:53 volumio---upstairs volumio[815]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 03:41:53 volumio---upstairs volumio[815]: info: [1734666113080] CoreMusicLibrary::Adding element Webradio Dec 20 03:41:53 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 03:41:53 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 03:41:53 volumio---upstairs systemd[1]: Stopped Music Player Daemon. Dec 20 03:41:53 volumio---upstairs volumio[815]: info: Initializing BBC Radios Dec 20 03:41:53 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 03:41:53 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 03:41:53 volumio---upstairs volumio[815]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 03:41:53 volumio---upstairs volumio[815]: info: [1734666113139] CoreMusicLibrary::Adding element Mixcloud Dec 20 03:41:53 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 03:41:53 volumio---upstairs volumio[815]: Cannot find translation for source Mixcloud Dec 20 03:41:53 volumio---upstairs volumio[815]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 03:41:53 volumio---upstairs volumio[815]: info: [1734666113145] CoreMusicLibrary::Adding element Mother Earth Radio Dec 20 03:41:53 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 03:41:53 volumio---upstairs volumio[815]: Cannot find translation for source Mixcloud Dec 20 03:41:53 volumio---upstairs volumio[815]: Cannot find translation for source Mother Earth Radio Dec 20 03:41:53 volumio---upstairs volumio[815]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 03:41:53 volumio---upstairs volumio[815]: info: [1734666113150] CoreMusicLibrary::Adding element Radio Paradise Dec 20 03:41:53 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 03:41:53 volumio---upstairs volumio[815]: Cannot find translation for source Mixcloud Dec 20 03:41:53 volumio---upstairs volumio[815]: Cannot find translation for source Mother Earth Radio Dec 20 03:41:53 volumio---upstairs volumio[815]: Cannot find translation for source Radio Paradise Dec 20 03:41:53 volumio---upstairs volumio[815]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 03:41:53 volumio---upstairs volumio[815]: info: [1734666113152] CoreMusicLibrary::Adding element LastFM Dec 20 03:41:53 volumio---upstairs volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 03:41:53 volumio---upstairs volumio[815]: Cannot find translation for source Mixcloud Dec 20 03:41:53 volumio---upstairs volumio[815]: Cannot find translation for source Mother Earth Radio Dec 20 03:41:53 volumio---upstairs volumio[815]: Cannot find translation for source Radio Paradise Dec 20 03:41:53 volumio---upstairs volumio[815]: Cannot find translation for source LastFM Dec 20 03:41:53 volumio---upstairs volumio[815]: info: [LastFM] scrobbler initiated! Dec 20 03:41:53 volumio---upstairs volumio[815]: info: [LastFM] extended logging: false Dec 20 03:41:53 volumio---upstairs volumio[815]: info: [LastFM] try scrobble stream/radio plays: true Dec 20 03:41:53 volumio---upstairs systemd[1]: Starting Music Player Daemon... Dec 20 03:41:53 volumio---upstairs volumio[815]: info: [LastFM] Left init routine Dec 20 03:41:53 volumio---upstairs volumio[815]: info: [LastFM] Socket already connected: true Dec 20 03:41:53 volumio---upstairs volumio[815]: info: Volumio Calling Home Dec 20 03:41:53 volumio---upstairs sudo[1013]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 20 03:41:53 volumio---upstairs sudo[1013]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:41:53 volumio---upstairs sudo[1013]: pam_unix(sudo:session): session closed for user root Dec 20 03:41:53 volumio---upstairs volumio[815]: Unhandled rejection Error: No sockets available, cannot start. Dec 20 03:41:53 volumio---upstairs volumio[815]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 20 03:41:53 volumio---upstairs volumio[815]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 20 03:41:53 volumio---upstairs volumio[815]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 20 03:41:53 volumio---upstairs volumio[815]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 20 03:41:53 volumio---upstairs volumio[815]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 20 03:41:53 volumio---upstairs volumio[815]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 20 03:41:53 volumio---upstairs volumio[815]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 20 03:41:53 volumio---upstairs volumio[815]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 20 03:41:53 volumio---upstairs volumio[815]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 20 03:41:53 volumio---upstairs volumio[815]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 20 03:41:53 volumio---upstairs volumio[815]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 20 03:41:53 volumio---upstairs volumio[815]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 20 03:41:53 volumio---upstairs volumio[815]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 20 03:41:53 volumio---upstairs volumio[815]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 20 03:41:53 volumio---upstairs volumio[815]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 20 03:41:53 volumio---upstairs volumio[815]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 20 03:41:53 volumio---upstairs volumio[815]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 03:41:53 volumio---upstairs volumio[815]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 20 03:41:53 volumio---upstairs volumio[815]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 20 03:41:53 volumio---upstairs volumio[815]: errno: -16, Dec 20 03:41:53 volumio---upstairs volumio[815]: code: 'EBUSY', Dec 20 03:41:53 volumio---upstairs volumio[815]: syscall: 'getaddrinfo', Dec 20 03:41:53 volumio---upstairs volumio[815]: hostname: 'ws.audioscrobbler.com' Dec 20 03:41:53 volumio---upstairs volumio[815]: } Dec 20 03:41:53 volumio---upstairs volumio[815]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 03:41:53 volumio---upstairs sudo[1038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-20 03:40 Dec 20 03:41:53 volumio---upstairs sudo[1038]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:41:53 volumio---upstairs sudo[1038]: pam_unix(sudo:session): session closed for user root Dec 20 03:41:53 volumio---upstairs volumio-remote-updater[598]: [2024-12-20 03:41:53] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 20 03:41:53 volumio---upstairs volumio-remote-updater[598]: [2024-12-20 03:41:53] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 20 03:41:53 volumio---upstairs systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 20 03:41:53 volumio---upstairs sudo[1004]: pam_unix(sudo:session): session closed for user root Dec 20 03:41:53 volumio---upstairs sudo[995]: pam_unix(sudo:session): session closed for user root Dec 20 03:41:53 volumio---upstairs systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 20 03:41:53 volumio---upstairs systemd[1]: Started dynamicswap service. Dec 20 03:41:53 volumio---upstairs systemd[1]: dynamicswap.service: Succeeded. Dec 20 03:41:53 volumio---upstairs mpd[1028]: Dec 20 03:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 20 03:41:54 volumio---upstairs systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 20 03:41:54 volumio---upstairs systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Dec 20 03:41:54 volumio---upstairs systemd[1]: Started Music Player Daemon. Dec 20 03:41:54 volumio---upstairs systemd[1]: Started dynamicswap service. Dec 20 03:41:54 volumio---upstairs systemd[1]: Stopped Volumio Backend Module. Dec 20 03:41:54 volumio---upstairs systemd[1]: Started Volumio Backend Module. Dec 20 03:41:54 volumio---upstairs systemd[1]: dynamicswap.service: Succeeded. Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: ------------------------------------------- Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: ----- Volumio3 ---- Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: ------------------------------------------- Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: ----- System startup ---- Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: ------------------------------------------- Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: MYVOLUMIO Environment detected Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: Plugin folders cleanup Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: Scanning into folder /volumio/app/plugins/ Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: Scanning category audio_interface Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: Scanning category miscellanea Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: Scanning category music_service Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: Scanning category plugins.json Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: Scanning category system_controller Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: Scanning category user_interface Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: Scanning into folder /data/plugins/ Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: Scanning category music_service Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: Scanning category user_interface Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: Plugin folders cleanup completed Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: ------------------------------------------- Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: ----- Core plugins startup ---- Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: ------------------------------------------- Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: Loading plugins from folder /volumio/app/plugins/ Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: Adding plugin upnp to MyMusic Plugins Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: Loading plugins from folder /data/plugins/ Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: Loading plugin "system"... Dec 20 03:41:55 volumio---upstairs volumio[1054]: info: Loading plugin "appearance"... Dec 20 03:41:56 volumio---upstairs sh[513]: eth0: using IPv4LL address 169.254.133.145 Dec 20 03:41:56 volumio---upstairs sh[513]: eth0: adding route to 169.254.0.0/16 Dec 20 03:41:56 volumio---upstairs sh[513]: eth0: adding default route Dec 20 03:41:56 volumio---upstairs dhcpcd[562]: eth0: using IPv4LL address 169.254.133.145 Dec 20 03:41:56 volumio---upstairs dhcpcd[562]: eth0: adding route to 169.254.0.0/16 Dec 20 03:41:56 volumio---upstairs avahi-daemon[585]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.133.145. Dec 20 03:41:56 volumio---upstairs avahi-daemon[585]: New relevant interface eth0.IPv4 for mDNS. Dec 20 03:41:56 volumio---upstairs dhcpcd[562]: eth0: adding default route Dec 20 03:41:56 volumio---upstairs avahi-daemon[585]: Registering new address record for 169.254.133.145 on eth0.IPv4. Dec 20 03:41:56 volumio---upstairs sh[513]: forked to background, child pid 1090 Dec 20 03:41:56 volumio---upstairs dhcpcd[562]: forked to background, child pid 1090 Dec 20 03:41:56 volumio---upstairs ifplugd(eth0)[774]: client: ifup: interface eth0 already configured Dec 20 03:41:56 volumio---upstairs sh[513]: eth0=eth0 Dec 20 03:41:56 volumio---upstairs volumio[1054]: info: Loading plugin "network"... Dec 20 03:41:56 volumio---upstairs volumio[1054]: info: Refreshing Cached IP Addresses Dec 20 03:41:56 volumio---upstairs sudo[1120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 03:41:56 volumio---upstairs sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:41:56 volumio---upstairs sudo[1120]: pam_unix(sudo:session): session closed for user root Dec 20 03:41:56 volumio---upstairs sudo[1122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 03:41:56 volumio---upstairs sudo[1122]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:41:56 volumio---upstairs volumio[1054]: info: Loading plugin "services"... Dec 20 03:41:56 volumio---upstairs volumio[1054]: info: Loading plugin "alsa_controller"... Dec 20 03:41:56 volumio---upstairs sudo[1122]: pam_unix(sudo:session): session closed for user root Dec 20 03:41:56 volumio---upstairs ifplugd(eth0)[774]: Program executed successfully. Dec 20 03:41:56 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 03:41:56 volumio---upstairs volumio[1054]: info: Loading plugin "wizard"... Dec 20 03:41:56 volumio---upstairs volumio[1054]: info: Loading plugin "networkfs"... Dec 20 03:41:56 volumio---upstairs sudo[1145]: 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.100.175/jules/music /mnt/NAS/Music Dec 20 03:41:56 volumio---upstairs sudo[1145]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:41:56 volumio---upstairs volumio[1054]: info: Starting Udev Watcher for removable devices Dec 20 03:41:56 volumio---upstairs kernel: CIFS: Attempting to mount //192.168.100.175/jules/music Dec 20 03:41:56 volumio---upstairs sudo[1149]: 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.100.175/jules/albums /mnt/NAS/Albums Dec 20 03:41:56 volumio---upstairs sudo[1149]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:41:56 volumio---upstairs volumio[1054]: info: Ignoring mount for partition: boot Dec 20 03:41:56 volumio---upstairs volumio[1054]: info: Ignoring mount for partition: volumio Dec 20 03:41:56 volumio---upstairs volumio[1054]: info: Ignoring mount for partition: volumio_data Dec 20 03:41:56 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 03:41:56 volumio---upstairs volumio[1054]: info: Loading plugin "volumio_command_line_client"... Dec 20 03:41:56 volumio---upstairs volumio[1054]: info: Loading plugin "upnp"... Dec 20 03:41:56 volumio---upstairs volumio[1054]: info: [1734666116519] Starting Upmpd Daemon Dec 20 03:41:56 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 03:41:56 volumio---upstairs volumio[1054]: info: Loading plugin "my_music"... Dec 20 03:41:56 volumio---upstairs volumio[1054]: info: Loading plugin "mpd"... Dec 20 03:41:56 volumio---upstairs volumio[1054]: info: Loading plugin "upnp_browser"... Dec 20 03:41:56 volumio---upstairs volumio[1054]: info: Loading plugin "alarm-clock"... Dec 20 03:41:56 volumio---upstairs volumio[1054]: info: Loading plugin "airplay_emulation"... Dec 20 03:41:56 volumio---upstairs volumio[1054]: info: Starting Shairport Sync Dec 20 03:41:56 volumio---upstairs volumio[1054]: info: Loading plugin "last_100"... Dec 20 03:41:57 volumio---upstairs volumio[1054]: info: Loading plugin "webradio"... Dec 20 03:41:57 volumio---upstairs volumio[1054]: info: Loading plugin "i2s_dacs"... Dec 20 03:41:57 volumio---upstairs volumio[1054]: info: I2S DAC not set, start Auto-detection Dec 20 03:41:57 volumio---upstairs volumio[1054]: info: Loading plugin "volumiodiscovery"... Dec 20 03:41:57 volumio---upstairs volumio[1054]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 03:41:57 volumio---upstairs volumio[1054]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 03:41:57 volumio---upstairs volumio[1054]: *** WARNING *** For more information see Dec 20 03:41:57 volumio---upstairs node[1054]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 03:41:57 volumio---upstairs volumio[1054]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 03:41:57 volumio---upstairs volumio[1054]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 03:41:57 volumio---upstairs volumio[1054]: *** WARNING *** For more information see Dec 20 03:41:57 volumio---upstairs node[1054]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 03:41:57 volumio---upstairs node[1054]: *** WARNING *** For more information see Dec 20 03:41:57 volumio---upstairs node[1054]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 03:41:57 volumio---upstairs node[1054]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 03:41:57 volumio---upstairs node[1054]: *** WARNING *** For more information see Dec 20 03:41:57 volumio---upstairs volumio[1054]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 20 03:41:57 volumio---upstairs volumio[1054]: info: Discovery: Started advertising with name: Volumio - Upstairs Dec 20 03:41:57 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 03:41:57 volumio---upstairs volumio[1054]: info: Loading plugin "mixcloud"... Dec 20 03:41:57 volumio---upstairs volumio[1054]: info: Loading plugin "outputs"... Dec 20 03:41:57 volumio---upstairs volumio[1054]: info: Loading plugin "albumart"... Dec 20 03:41:57 volumio---upstairs volumio[1054]: info: Plugin example_plugin is not enabled Dec 20 03:41:57 volumio---upstairs volumio[1054]: info: Loading plugin "inputs"... Dec 20 03:41:57 volumio---upstairs volumio[1054]: info: Loading plugin "updater_comm"... Dec 20 03:41:57 volumio---upstairs volumio[1054]: info: Plugin mpdemulation is not enabled Dec 20 03:41:57 volumio---upstairs volumio[1054]: info: Loading plugin "rest_api"... Dec 20 03:41:57 volumio---upstairs volumio[1054]: info: Loading plugin "websocket"... Dec 20 03:41:57 volumio---upstairs volumio[1054]: info: Starting Socket.io Server version 2.3.0 Dec 20 03:41:57 volumio---upstairs volumio[1054]: info: Loading plugin "motherearthradio"... Dec 20 03:41:57 volumio---upstairs ntpd[781]: Listen normally on 3 eth0 169.254.133.145:123 Dec 20 03:41:57 volumio---upstairs ntpd[781]: new interface(s) found: waking up resolver Dec 20 03:41:57 volumio---upstairs volumio[1054]: Forking 3 albumart workers Dec 20 03:41:57 volumio---upstairs volumio[1054]: info: Applying required configuration parameters for plugin motherearthradio Dec 20 03:41:57 volumio---upstairs volumio[1054]: info: [1734666117602] [MotherEarth] API delay: 5 Dec 20 03:41:57 volumio---upstairs volumio[1054]: info: Loading plugin "radio_paradise"... Dec 20 03:41:57 volumio---upstairs volumio[1054]: info: Applying required configuration parameters for plugin radio_paradise Dec 20 03:41:57 volumio---upstairs volumio[1054]: info: [1734666117755] [RadioParadise] API delay: 5 Dec 20 03:41:57 volumio---upstairs volumio[1054]: info: Loading plugin "lastfm"... Dec 20 03:41:57 volumio---upstairs volumio-time-update[601]: volumio-time-update-util: Fetching time from Volumio... Dec 20 03:41:58 volumio---upstairs volumio[1054]: Starting albumart workers Dec 20 03:41:58 volumio---upstairs volumio[1054]: Starting albumart workers Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: Loading i18n strings for locale en Dec 20 03:41:58 volumio---upstairs volumio[1054]: Updating browse sources language Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 03:41:58 volumio---upstairs volumio[1054]: Starting albumart workers Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreCommandRouter::initPlayerControls Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 03:41:58 volumio---upstairs volumio[1054]: Express server listening on port 3000 Dec 20 03:41:58 volumio---upstairs volumio[1054]: [Metrics] WebUI: 3s 382.92ms Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreStateMachine::resetVolumioState Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreStateMachine::getcurrentVolume Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: Volumio Network Manager: Network status updated: 1 Dec 20 03:41:58 volumio---upstairs volumio[1054]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: Reloading queue from file Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: VolumeController:: Volume=100 Mute =false Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreStateMachine::pushState Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CorePlayQueue::getTrack 0 Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreCommandRouter::volumioPushState Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreStateMachine::updateTrackBlock Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CorePlayQueue::getTrackBlock Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreStateMachine::setRepeat true single undefined Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreStateMachine::pushState Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CorePlayQueue::getTrack 0 Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreCommandRouter::volumioPushState Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreStateMachine::setRandom false Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreStateMachine::pushState Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CorePlayQueue::getTrack 0 Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreCommandRouter::volumioPushState Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: Setting Device type: Raspberry PI Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: VolumeController:: Volume=100 Mute =false Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreStateMachine::pushState Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CorePlayQueue::getTrack 0 Dec 20 03:41:58 volumio---upstairs volumio[1054]: info: CoreCommandRouter::volumioPushState Dec 20 03:41:58 volumio---upstairs volumio-remote-updater[598]: [2024-12-20 03:41:58] [connect] Successful connection Dec 20 03:41:58 volumio---upstairs volumio-remote-updater[598]: [2024-12-20 03:41:58] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1734666118 101 Dec 20 03:41:58 volumio---upstairs volumio[1054]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Dec 20 03:41:59 volumio---upstairs nmbd[749]: [2024/12/20 03:41:59.427777, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 20 03:41:59 volumio---upstairs nmbd[749]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Dec 20 03:41:59 volumio---upstairs systemd[1]: Started Samba NMB Daemon. Dec 20 03:41:59 volumio---upstairs systemd[1]: Starting Samba Winbind Daemon... Dec 20 03:41:59 volumio---upstairs winbindd[1224]: [2024/12/20 03:41:59.638331, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Dec 20 03:41:59 volumio---upstairs winbindd[1224]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 20 03:41:59 volumio---upstairs winbindd[1224]: [2024/12/20 03:41:59.663551, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 20 03:41:59 volumio---upstairs winbindd[1224]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Dec 20 03:41:59 volumio---upstairs systemd[1]: Started Samba Winbind Daemon. Dec 20 03:41:59 volumio---upstairs systemd[1]: Starting Samba SMB Daemon... Dec 20 03:42:00 volumio---upstairs winbindd[1224]: [2024/12/20 03:42:00.006526, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Dec 20 03:42:00 volumio---upstairs winbindd[1224]: res_names->count = 1, expected 2 Dec 20 03:42:00 volumio---upstairs smbd[1229]: [2024/12/20 03:42:00.020434, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 20 03:42:00 volumio---upstairs systemd[1]: Started Samba SMB Daemon. Dec 20 03:42:00 volumio---upstairs smbd[1229]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Dec 20 03:42:06 volumio---upstairs sudo[1255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 03:42:06 volumio---upstairs sudo[1255]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:42:06 volumio---upstairs sudo[1255]: pam_unix(sudo:session): session closed for user root Dec 20 03:42:06 volumio---upstairs sudo[1257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 03:42:06 volumio---upstairs sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:42:06 volumio---upstairs sudo[1257]: pam_unix(sudo:session): session closed for user root Dec 20 03:42:06 volumio---upstairs sudo[1261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 20 03:42:06 volumio---upstairs sudo[1261]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:42:06 volumio---upstairs sudo[1145]: pam_unix(sudo:session): session closed for user root Dec 20 03:42:06 volumio---upstairs kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Dec 20 03:42:06 volumio---upstairs kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Dec 20 03:42:06 volumio---upstairs kernel: CIFS: Attempting to mount //192.168.100.175/jules/albums Dec 20 03:42:06 volumio---upstairs volumio[1054]: info: Cannot mount NAS Music at system boot, trial number 1 ,retrying in 5 seconds Dec 20 03:42:06 volumio---upstairs systemd[1]: Started UPnP Renderer front-end to MPD. Dec 20 03:42:06 volumio---upstairs sudo[1261]: pam_unix(sudo:session): session closed for user root Dec 20 03:42:06 volumio---upstairs volumio[1054]: info: Upmpdcli Daemon Started Dec 20 03:42:06 volumio---upstairs volumio[1263]: Generating RSA private key, 4096 bit long modulus (2 primes) Dec 20 03:42:08 volumio---upstairs volumio[1263]: .......................++++ Dec 20 03:42:10 volumio---upstairs volumio[1263]: ...................++++ Dec 20 03:42:10 volumio---upstairs volumio[1263]: e is 65537 (0x010001) Dec 20 03:42:10 volumio---upstairs volumio[1263]: writing RSA key Dec 20 03:42:10 volumio---upstairs kernel: cryptd: max_cpu_qlen set to 1000 Dec 20 03:42:11 volumio---upstairs sudo[1149]: pam_unix(sudo:session): session closed for user root Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: Completed loading Core Plugins Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: Preparing to generate the ALSA configuration file Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: Asound.conf file unchanged, so no further update is needed Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: Output device has changed, restarting MPD Dec 20 03:42:11 volumio---upstairs sudo[1320]: 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.100.175/jules/music /mnt/NAS/Music Dec 20 03:42:11 volumio---upstairs sudo[1320]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:42:11 volumio---upstairs sudo[1323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: Output device has changed, restarting Shairport Sync Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 03:42:11 volumio---upstairs sudo[1323]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:42:11 volumio---upstairs kernel: CIFS: Attempting to mount //192.168.100.175/jules/music Dec 20 03:42:11 volumio---upstairs sudo[1323]: pam_unix(sudo:session): session closed for user root Dec 20 03:42:11 volumio---upstairs sudo[1327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 03:42:11 volumio---upstairs sudo[1327]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:42:11 volumio---upstairs systemd[1]: Stopping Music Player Daemon... Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: ___________ START PLUGINS ___________ Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: ControllerMpd::onStart: Initializing MPD Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: Creating MPD Configuration file Dec 20 03:42:11 volumio---upstairs systemd[1]: mpd.service: Succeeded. Dec 20 03:42:11 volumio---upstairs systemd[1]: Stopped Music Player Daemon. Dec 20 03:42:11 volumio---upstairs sudo[1320]: pam_unix(sudo:session): session closed for user root Dec 20 03:42:11 volumio---upstairs sudo[1334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 03:42:11 volumio---upstairs sudo[1334]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: [1734666131740] CoreMusicLibrary::Adding element Media Servers Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 03:42:11 volumio---upstairs systemd[1]: Starting Music Player Daemon... Dec 20 03:42:11 volumio---upstairs sudo[1334]: pam_unix(sudo:session): session closed for user root Dec 20 03:42:11 volumio---upstairs sudo[1337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 03:42:11 volumio---upstairs sudo[1337]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: [1734666131823] CoreMusicLibrary::Adding element Last_100 Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: [1734666131826] CoreMusicLibrary::Adding element Webradio Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 03:42:11 volumio---upstairs systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 20 03:42:11 volumio---upstairs systemd[1]: mpd.service: Succeeded. Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 03:42:11 volumio---upstairs systemd[1]: Stopped Music Player Daemon. Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: Initializing BBC Radios Dec 20 03:42:11 volumio---upstairs systemd[1]: Starting Music Player Daemon... Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: [1734666131887] CoreMusicLibrary::Adding element Mixcloud Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 03:42:11 volumio---upstairs volumio[1054]: Cannot find translation for source Mixcloud Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: [1734666131893] CoreMusicLibrary::Adding element Mother Earth Radio Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 03:42:11 volumio---upstairs volumio[1054]: Cannot find translation for source Mixcloud Dec 20 03:42:11 volumio---upstairs volumio[1054]: Cannot find translation for source Mother Earth Radio Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: [1734666131897] CoreMusicLibrary::Adding element Radio Paradise Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 03:42:11 volumio---upstairs volumio[1054]: Cannot find translation for source Mixcloud Dec 20 03:42:11 volumio---upstairs volumio[1054]: Cannot find translation for source Mother Earth Radio Dec 20 03:42:11 volumio---upstairs volumio[1054]: Cannot find translation for source Radio Paradise Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: [1734666131899] CoreMusicLibrary::Adding element LastFM Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 03:42:11 volumio---upstairs volumio[1054]: Cannot find translation for source Mixcloud Dec 20 03:42:11 volumio---upstairs volumio[1054]: Cannot find translation for source Mother Earth Radio Dec 20 03:42:11 volumio---upstairs volumio[1054]: Cannot find translation for source Radio Paradise Dec 20 03:42:11 volumio---upstairs volumio[1054]: Cannot find translation for source LastFM Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: [LastFM] scrobbler initiated! Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: [LastFM] extended logging: false Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: [LastFM] try scrobble stream/radio plays: true Dec 20 03:42:11 volumio---upstairs sudo[1346]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 20 03:42:11 volumio---upstairs sudo[1346]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: [LastFM] Left init routine Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: [LastFM] Socket already connected: true Dec 20 03:42:11 volumio---upstairs volumio[1054]: info: Volumio Calling Home Dec 20 03:42:11 volumio---upstairs sudo[1346]: pam_unix(sudo:session): session closed for user root Dec 20 03:42:11 volumio---upstairs systemd[1]: systemd-fsckd.service: Succeeded. Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: MPD Permissions set Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: MPD Permissions set Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: Starting Shairport Sync Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: Starting Shairport Sync Dec 20 03:42:12 volumio---upstairs sudo[1371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: Starting Shairport Sync Dec 20 03:42:12 volumio---upstairs sudo[1371]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:42:12 volumio---upstairs sudo[1373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 20 03:42:12 volumio---upstairs sudo[1373]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:42:12 volumio---upstairs sudo[1376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 20 03:42:12 volumio---upstairs sudo[1376]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 03:42:12 volumio---upstairs systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 20 03:42:12 volumio---upstairs systemd[1]: shairport-sync.service: Succeeded. Dec 20 03:42:12 volumio---upstairs systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 20 03:42:12 volumio---upstairs systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 20 03:42:12 volumio---upstairs sudo[1371]: pam_unix(sudo:session): session closed for user root Dec 20 03:42:12 volumio---upstairs sudo[1373]: pam_unix(sudo:session): session closed for user root Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: Shairport-Sync Started Dec 20 03:42:12 volumio---upstairs volumio[1054]: Error adding Membership: Error: addMembership EINVAL Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: Shairport-Sync Started Dec 20 03:42:12 volumio---upstairs systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 20 03:42:12 volumio---upstairs systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 20 03:42:12 volumio---upstairs systemd[1]: shairport-sync.service: Succeeded. Dec 20 03:42:12 volumio---upstairs systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 20 03:42:12 volumio---upstairs systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 20 03:42:12 volumio---upstairs sudo[1376]: pam_unix(sudo:session): session closed for user root Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: Shairport-Sync Started Dec 20 03:42:12 volumio---upstairs mpd[1358]: Dec 20 03:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 20 03:42:12 volumio---upstairs systemd[1]: Started Music Player Daemon. Dec 20 03:42:12 volumio---upstairs sudo[1327]: pam_unix(sudo:session): session closed for user root Dec 20 03:42:12 volumio---upstairs sudo[1337]: pam_unix(sudo:session): session closed for user root Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: Completed starting Core Plugins Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: ------------------------------------------- Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: ----- MyVolumio plugins startup ---- Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: ------------------------------------------- Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 20 03:42:12 volumio---upstairs volumio[1054]: info: MPD running with PID1358 Dec 20 03:42:12 volumio---upstairs volumio[1054]: ,establishing connection Dec 20 03:42:12 volumio---upstairs volumio[1054]: error: MPD error: The expression evaluated to a falsy value: Dec 20 03:42:12 volumio---upstairs volumio[1054]: assert.ok(self.idling) Dec 20 03:42:12 volumio---upstairs volumio[1054]: error: The expression evaluated to a falsy value: Dec 20 03:42:12 volumio---upstairs volumio[1054]: assert.ok(self.idling) Dec 20 03:42:12 volumio---upstairs volumio[1054]: error: MPD error: The expression evaluated to a falsy value: Dec 20 03:42:12 volumio---upstairs volumio[1054]: assert.ok(self.idling) Dec 20 03:42:12 volumio---upstairs volumio[1054]: error: The expression evaluated to a falsy value: Dec 20 03:42:12 volumio---upstairs volumio[1054]: assert.ok(self.idling) Dec 20 03:42:12 volumio---upstairs volumio[1054]: error: updateQueue error: null Dec 20 03:42:14 volumio---upstairs systemd[1]: systemd-hostnamed.service: Succeeded. Dec 20 03:42:17 volumio---upstairs volumio-time-update[601]: volumio-time-update-util: Date not found in response Dec 20 03:42:17 volumio---upstairs volumio-time-update[601]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 03:42:17 volumio---upstairs volumio[1054]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 20 03:42:22 volumio---upstairs volumio-time-update[601]: volumio-time-update-util: Fetching time from Volumio... Dec 20 03:42:29 volumio---upstairs dhcpcd[1090]: eth0: offered 192.168.100.142 from 192.168.100.1 Dec 20 03:42:29 volumio---upstairs dhcpcd[1090]: eth0: probing address 192.168.100.142/24 Dec 20 03:42:31 volumio---upstairs volumio[1054]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 03:42:31 volumio---upstairs volumio[1054]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Dec 20 03:42:31 volumio---upstairs volumio[1054]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 20 03:42:31 volumio---upstairs volumio[1054]: errno: -3007, Dec 20 03:42:31 volumio---upstairs volumio[1054]: code: 'ENOTFOUND', Dec 20 03:42:31 volumio---upstairs volumio[1054]: syscall: 'getaddrinfo', Dec 20 03:42:31 volumio---upstairs volumio[1054]: hostname: 'ws.audioscrobbler.com' Dec 20 03:42:31 volumio---upstairs volumio[1054]: } Dec 20 03:42:31 volumio---upstairs volumio[1054]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 03:42:32 volumio---upstairs sudo[1439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-20 03:41 Dec 20 03:42:32 volumio---upstairs sudo[1439]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"