-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sun 2025-01-05 08:36:11 UTC. -- Jan 05 08:35:01 volumio-pi400 kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x00000e0000000890 Jan 05 08:35:01 volumio-pi400 kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 Jan 05 08:35:01 volumio-pi400 kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 05 08:35:01 volumio-pi400 kernel: usb usb1: Product: xHCI Host Controller Jan 05 08:35:01 volumio-pi400 kernel: usb usb1: Manufacturer: Linux 5.10.92-v7l+ xhci-hcd Jan 05 08:35:01 volumio-pi400 kernel: usb usb1: SerialNumber: 0000:01:00.0 Jan 05 08:35:01 volumio-pi400 kernel: hub 1-0:1.0: USB hub found Jan 05 08:35:01 volumio-pi400 kernel: hub 1-0:1.0: 1 port detected Jan 05 08:35:01 volumio-pi400 kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Jan 05 08:35:01 volumio-pi400 kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Jan 05 08:35:01 volumio-pi400 kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Jan 05 08:35:01 volumio-pi400 kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10 Jan 05 08:35:01 volumio-pi400 kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 05 08:35:01 volumio-pi400 kernel: usb usb2: Product: xHCI Host Controller Jan 05 08:35:01 volumio-pi400 kernel: usb usb2: Manufacturer: Linux 5.10.92-v7l+ xhci-hcd Jan 05 08:35:01 volumio-pi400 kernel: usb usb2: SerialNumber: 0000:01:00.0 Jan 05 08:35:01 volumio-pi400 kernel: hub 2-0:1.0: USB hub found Jan 05 08:35:01 volumio-pi400 kernel: hub 2-0:1.0: 4 ports detected Jan 05 08:35:01 volumio-pi400 kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Jan 05 08:35:01 volumio-pi400 kernel: dwc_otg: FIQ enabled Jan 05 08:35:01 volumio-pi400 kernel: dwc_otg: NAK holdoff enabled Jan 05 08:35:01 volumio-pi400 kernel: dwc_otg: FIQ split-transaction FSM enabled Jan 05 08:35:01 volumio-pi400 kernel: Module dwc_common_port init Jan 05 08:35:01 volumio-pi400 kernel: usbcore: registered new interface driver uas Jan 05 08:35:01 volumio-pi400 kernel: usbcore: registered new interface driver usb-storage Jan 05 08:35:01 volumio-pi400 kernel: mousedev: PS/2 mouse device common for all mice Jan 05 08:35:01 volumio-pi400 kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Jan 05 08:35:01 volumio-pi400 kernel: sdhci: Secure Digital Host Controller Interface driver Jan 05 08:35:01 volumio-pi400 kernel: sdhci: Copyright(c) Pierre Ossman Jan 05 08:35:01 volumio-pi400 kernel: mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe Jan 05 08:35:01 volumio-pi400 kernel: sdhci-pltfm: SDHCI platform and OF driver helper Jan 05 08:35:01 volumio-pi400 kernel: ledtrig-cpu: registered to indicate activity on CPUs Jan 05 08:35:01 volumio-pi400 kernel: hid: raw HID events driver (C) Jiri Kosina Jan 05 08:35:01 volumio-pi400 kernel: usbcore: registered new interface driver usbhid Jan 05 08:35:01 volumio-pi400 kernel: usbhid: USB HID core driver Jan 05 08:35:01 volumio-pi400 kernel: Initializing XFRM netlink socket Jan 05 08:35:01 volumio-pi400 kernel: NET: Registered protocol family 17 Jan 05 08:35:01 volumio-pi400 kernel: Key type dns_resolver registered Jan 05 08:35:01 volumio-pi400 kernel: Registering SWP/SWPB emulation handler Jan 05 08:35:01 volumio-pi400 kernel: registered taskstats version 1 Jan 05 08:35:01 volumio-pi400 kernel: Loading compiled-in X.509 certificates Jan 05 08:35:01 volumio-pi400 kernel: Key type ._fscrypt registered Jan 05 08:35:01 volumio-pi400 kernel: Key type .fscrypt registered Jan 05 08:35:01 volumio-pi400 kernel: Key type fscrypt-provisioning registered Jan 05 08:35:01 volumio-pi400 kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Jan 05 08:35:01 volumio-pi400 kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Jan 05 08:35:01 volumio-pi400 kernel: fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 36, base_baud = 0) is a PL011 rev2 Jan 05 08:35:01 volumio-pi400 kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Jan 05 08:35:01 volumio-pi400 kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Jan 05 08:35:01 volumio-pi400 kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Jan 05 08:35:01 volumio-pi400 kernel: of_cfs_init Jan 05 08:35:01 volumio-pi400 kernel: of_cfs_init: OK Jan 05 08:35:01 volumio-pi400 kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Jan 05 08:35:01 volumio-pi400 kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Jan 05 08:35:01 volumio-pi400 kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Jan 05 08:35:01 volumio-pi400 kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Jan 05 08:35:01 volumio-pi400 kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Jan 05 08:35:01 volumio-pi400 kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Jan 05 08:35:01 volumio-pi400 kernel: Freeing unused kernel memory: 2048K Jan 05 08:35:01 volumio-pi400 kernel: Run /init as init process Jan 05 08:35:01 volumio-pi400 kernel: with arguments: Jan 05 08:35:01 volumio-pi400 kernel: /init Jan 05 08:35:01 volumio-pi400 kernel: splash Jan 05 08:35:01 volumio-pi400 kernel: with environment: Jan 05 08:35:01 volumio-pi400 kernel: HOME=/ Jan 05 08:35:01 volumio-pi400 kernel: TERM=linux Jan 05 08:35:01 volumio-pi400 kernel: imgpart=/dev/mmcblk0p2 Jan 05 08:35:01 volumio-pi400 kernel: imgfile=/volumio_current.sqsh Jan 05 08:35:01 volumio-pi400 kernel: bootdelay=5 Jan 05 08:35:01 volumio-pi400 kernel: mmc0: new ultra high speed DDR50 SDHC card at address aaaa Jan 05 08:35:01 volumio-pi400 kernel: mmcblk0: mmc0:aaaa SL16G 14.8 GiB Jan 05 08:35:01 volumio-pi400 kernel: mmcblk0: p1 p2 p3 Jan 05 08:35:01 volumio-pi400 kernel: mmc1: new high speed SDIO card at address 0001 Jan 05 08:35:01 volumio-pi400 kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Jan 05 08:35:01 volumio-pi400 initramfs: Booting Volumio for BCM2711 Jan 05 08:35:01 volumio-pi400 initramfs: This script mounts rootfs RO with an overlay RW layer. Jan 05 08:35:01 volumio-pi400 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jan 05 08:35:01 volumio-pi400 initramfs: IMGPART=/dev/mmcblk0p2 Jan 05 08:35:01 volumio-pi400 initramfs: IMGFILE=/volumio_current.sqsh Jan 05 08:35:01 volumio-pi400 initramfs: Boot delay (except first time) will be 5 seconds Jan 05 08:35:01 volumio-pi400 initramfs: /dev/mmcblk0p2: Jan 05 08:35:01 volumio-pi400 kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Jan 05 08:35:01 volumio-pi400 kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Jan 05 08:35:01 volumio-pi400 kernel: usb 1-1: Product: USB2.0 Hub Jan 05 08:35:01 volumio-pi400 kernel: hub 1-1:1.0: USB hub found Jan 05 08:35:01 volumio-pi400 kernel: hub 1-1:1.0: 4 ports detected Jan 05 08:35:01 volumio-pi400 kernel: EXT4-fs (mmcblk0p2): recovery complete Jan 05 08:35:01 volumio-pi400 kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Jan 05 08:35:01 volumio-pi400 initramfs: Doing a 5 second delay here to give kernel load a headstart Jan 05 08:35:01 volumio-pi400 kernel: usb 1-1.4: new low-speed USB device number 3 using xhci_hcd Jan 05 08:35:01 volumio-pi400 kernel: usb 1-1.4: New USB device found, idVendor=04d9, idProduct=0007, bcdDevice= 1.61 Jan 05 08:35:01 volumio-pi400 kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Jan 05 08:35:01 volumio-pi400 kernel: usb 1-1.4: Product: Raspberry Pi Internal Keyboard Jan 05 08:35:01 volumio-pi400 kernel: usb 1-1.4: Manufacturer: Jan 05 08:35:01 volumio-pi400 kernel: input: Raspberry Pi Internal Keyboard as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.0/0003:04D9:0007.0001/input/input0 Jan 05 08:35:01 volumio-pi400 kernel: hid-generic 0003:04D9:0007.0001: input,hidraw0: USB HID v1.11 Keyboard [ Raspberry Pi Internal Keyboard] on usb-0000:01:00.0-1.4/input0 Jan 05 08:35:01 volumio-pi400 kernel: input: Raspberry Pi Internal Keyboard as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.1/0003:04D9:0007.0002/input/input1 Jan 05 08:35:01 volumio-pi400 kernel: hid-generic 0003:04D9:0007.0002: input,hidraw1: USB HID v1.11 Device [ Raspberry Pi Internal Keyboard] on usb-0000:01:00.0-1.4/input1 Jan 05 08:35:01 volumio-pi400 initramfs: Checking for USB updates Jan 05 08:35:01 volumio-pi400 initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Jan 05 08:35:01 volumio-pi400 kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jan 05 08:35:01 volumio-pi400 kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jan 05 08:35:01 volumio-pi400 kernel: EXT4-fs (mmcblk0p3): recovery complete Jan 05 08:35:01 volumio-pi400 kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Jan 05 08:35:01 volumio-pi400 initramfs: With Option: Jan 05 08:35:01 volumio-pi400 initramfs: VOLUMIO_VERSION="3.378" Jan 05 08:35:01 volumio-pi400 initramfs: Finish initramfs, continue booting Volumio Jan 05 08:35:01 volumio-pi400 systemd[1]: System time before build time, advancing clock. Jan 05 08:35:01 volumio-pi400 kernel: NET: Registered protocol family 10 Jan 05 08:35:01 volumio-pi400 kernel: Segment Routing with IPv6 Jan 05 08:35:01 volumio-pi400 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) Jan 05 08:35:01 volumio-pi400 systemd[1]: Detected architecture arm. Jan 05 08:35:01 volumio-pi400 systemd[1]: Set hostname to . Jan 05 08:35:01 volumio-pi400 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. Jan 05 08:35:01 volumio-pi400 kernel: random: systemd: uninitialized urandom read (16 bytes read) Jan 05 08:35:01 volumio-pi400 kernel: random: systemd: uninitialized urandom read (16 bytes read) Jan 05 08:35:01 volumio-pi400 kernel: random: systemd: uninitialized urandom read (16 bytes read) Jan 05 08:35:01 volumio-pi400 systemd[1]: Listening on Journal Audit Socket. Jan 05 08:35:01 volumio-pi400 systemd[1]: Reached target System Time Synchronized. Jan 05 08:35:01 volumio-pi400 systemd[1]: Listening on Journal Socket (/dev/log). Jan 05 08:35:01 volumio-pi400 systemd[1]: Listening on fsck to fsckd communication Socket. Jan 05 08:35:01 volumio-pi400 systemd[1]: Listening on udev Kernel Socket. Jan 05 08:35:01 volumio-pi400 systemd[1]: Created slice system-systemd\x2dfsck.slice. Jan 05 08:35:01 volumio-pi400 kernel: i2c /dev entries driver Jan 05 08:35:01 volumio-pi400 systemd-journald[189]: Journal started Jan 05 08:35:01 volumio-pi400 systemd-journald[189]: Runtime journal (/run/log/journal/f19fe563bf7dcaf9ed2201976245a35f) is 7.5M, max 30.0M, 22.5M free. Jan 05 08:35:01 volumio-pi400 systemd-modules-load[199]: Inserted module 'i2c_dev' Jan 05 08:35:01 volumio-pi400 fake-hwclock[188]: Sun Jan 5 08:35:01 UTC 2025 Jan 05 08:35:01 volumio-pi400 systemd[1]: Mounted Kernel Configuration File System. Jan 05 08:35:01 volumio-pi400 systemd[1]: Started Apply Kernel Variables. Jan 05 08:35:01 volumio-pi400 systemd[1]: Started Create System Users. Jan 05 08:35:01 volumio-pi400 systemd[1]: Starting Create Static Device Nodes in /dev... Jan 05 08:35:01 volumio-pi400 systemd[1]: dynamicswap.service: Succeeded. Jan 05 08:35:01 volumio-pi400 systemd[1]: Started Create Static Device Nodes in /dev. Jan 05 08:35:01 volumio-pi400 systemd[1]: Reached target Local File Systems (Pre). Jan 05 08:35:01 volumio-pi400 systemd[1]: Mounting /var/spool/cups... Jan 05 08:35:01 volumio-pi400 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jan 05 08:35:01 volumio-pi400 systemd[1]: Mounting /var/log... Jan 05 08:35:01 volumio-pi400 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jan 05 08:35:01 volumio-pi400 systemd[1]: Mounting /tmp... Jan 05 08:35:01 volumio-pi400 systemd[1]: Starting udev Kernel Device Manager... Jan 05 08:35:01 volumio-pi400 systemd[1]: Mounted /var/spool/cups. Jan 05 08:35:01 volumio-pi400 systemd[1]: Mounted /var/log. Jan 05 08:35:01 volumio-pi400 systemd[1]: Starting Flush Journal to Persistent Storage... Jan 05 08:35:01 volumio-pi400 systemd[1]: Mounting /var/spool/cups/tmp... Jan 05 08:35:01 volumio-pi400 systemd[1]: Mounted /tmp. Jan 05 08:35:01 volumio-pi400 systemd-udevd[221]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jan 05 08:35:01 volumio-pi400 systemd[1]: Mounted /var/spool/cups/tmp. Jan 05 08:35:01 volumio-pi400 systemd-journald[189]: Runtime journal (/run/log/journal/f19fe563bf7dcaf9ed2201976245a35f) is 7.5M, max 30.0M, 22.5M free. Jan 05 08:35:01 volumio-pi400 systemd[1]: Started Flush Journal to Persistent Storage. Jan 05 08:35:01 volumio-pi400 systemd[1]: Started udev Kernel Device Manager. Jan 05 08:35:01 volumio-pi400 systemd[1]: Started udev Coldplug all Devices. Jan 05 08:35:01 volumio-pi400 systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jan 05 08:35:01 volumio-pi400 systemd[1]: Starting Show Plymouth Boot Screen... Jan 05 08:35:01 volumio-pi400 systemd[1]: Received SIGRTMIN+20 from PID 241 (plymouthd). Jan 05 08:35:01 volumio-pi400 systemd[1]: Started Show Plymouth Boot Screen. Jan 05 08:35:01 volumio-pi400 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jan 05 08:35:01 volumio-pi400 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 05 08:35:01 volumio-pi400 systemd[1]: Reached target Local Encrypted Volumes. Jan 05 08:35:01 volumio-pi400 systemd[1]: Reached target Paths. Jan 05 08:35:01 volumio-pi400 systemd-udevd[245]: Using default interface naming scheme 'v240'. Jan 05 08:35:01 volumio-pi400 kernel: rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000 Jan 05 08:35:01 volumio-pi400 kernel: rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000 Jan 05 08:35:01 volumio-pi400 kernel: rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000 Jan 05 08:35:01 volumio-pi400 kernel: rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000 Jan 05 08:35:01 volumio-pi400 kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jan 05 08:35:01 volumio-pi400 kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jan 05 08:35:01 volumio-pi400 kernel: [vc_sm_connected_init]: start Jan 05 08:35:01 volumio-pi400 kernel: [vc_sm_connected_init]: installed successfully Jan 05 08:35:01 volumio-pi400 kernel: mc: Linux media interface: v0.10 Jan 05 08:35:02 volumio-pi400 systemd-udevd[245]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 05 08:35:02 volumio-pi400 kernel: videodev: Linux video capture interface: v2.00 Jan 05 08:35:02 volumio-pi400 kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jan 05 08:35:02 volumio-pi400 kernel: bcm2835_audio bcm2835_audio: there is not valid maps for state default Jan 05 08:35:02 volumio-pi400 systemd-udevd[228]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 05 08:35:02 volumio-pi400 kernel: bcm2835_audio bcm2835_audio: card created with 8 channels Jan 05 08:35:02 volumio-pi400 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 05 08:35:02 volumio-pi400 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 05 08:35:02 volumio-pi400 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 05 08:35:02 volumio-pi400 kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jan 05 08:35:02 volumio-pi400 kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jan 05 08:35:02 volumio-pi400 kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Jan 05 08:35:03 volumio-pi400 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jan 05 08:35:03 volumio-pi400 systemd-udevd[235]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 05 08:35:03 volumio-pi400 systemd-udevd[231]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 05 08:35:03 volumio-pi400 kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jan 05 08:35:04 volumio-pi400 kernel: brcmfmac: F1 signature read @0x18000000=0x15294345 Jan 05 08:35:04 volumio-pi400 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43456-sdio for chip BCM4345/9 Jan 05 08:35:04 volumio-pi400 kernel: usbcore: registered new interface driver brcmfmac Jan 05 08:35:04 volumio-pi400 kernel: Under-voltage detected! (0x00050005) Jan 05 08:35:04 volumio-pi400 systemd[1]: Found device /dev/mmcblk0p1. Jan 05 08:35:04 volumio-pi400 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43456-sdio for chip BCM4345/9 Jan 05 08:35:04 volumio-pi400 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43456-sdio for chip BCM4345/9 Jan 05 08:35:04 volumio-pi400 kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/9 wl0: May 14 2020 17:26:08 version 7.84.17.1 (r871554) FWID 01-3d9e1d87 Jan 05 08:35:04 volumio-pi400 systemd-udevd[231]: Using default interface naming scheme 'v240'. Jan 05 08:35:04 volumio-pi400 systemd-udevd[231]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 05 08:35:04 volumio-pi400 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jan 05 08:35:04 volumio-pi400 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 05 08:35:04 volumio-pi400 systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jan 05 08:35:04 volumio-pi400 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jan 05 08:35:04 volumio-pi400 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jan 05 08:35:04 volumio-pi400 systemd[1]: Condition check resulted in FUSE Control File System being skipped. Jan 05 08:35:04 volumio-pi400 systemd[1]: Starting File System Check on /dev/mmcblk0p1... Jan 05 08:35:04 volumio-pi400 systemd[1]: Starting Load/Save RF Kill Switch Status... Jan 05 08:35:05 volumio-pi400 systemd[1]: Found device /sys/subsystem/net/devices/eth0. Jan 05 08:35:05 volumio-pi400 systemd[1]: Started Load/Save RF Kill Switch Status. Jan 05 08:35:05 volumio-pi400 systemd[1]: Started File System Check Daemon to report status. Jan 05 08:35:05 volumio-pi400 systemd-fsck[373]: fsck.fat 4.1 (2017-01-24) Jan 05 08:35:05 volumio-pi400 systemd-fsck[373]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Jan 05 08:35:05 volumio-pi400 systemd-fsck[373]: Automatically removing dirty bit. Jan 05 08:35:05 volumio-pi400 systemd-fsck[373]: Performing changes. Jan 05 08:35:05 volumio-pi400 systemd-fsck[373]: /dev/mmcblk0p1: 297 files, 27486/46774 clusters Jan 05 08:35:05 volumio-pi400 systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Jan 05 08:35:05 volumio-pi400 systemd[1]: Started Helper to synchronize boot up for ifupdown. Jan 05 08:35:05 volumio-pi400 systemd[1]: Started File System Check on /dev/mmcblk0p1. Jan 05 08:35:05 volumio-pi400 systemd[1]: Mounting /boot... Jan 05 08:35:05 volumio-pi400 kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jan 05 08:35:05 volumio-pi400 systemd[1]: Mounted /boot. Jan 05 08:35:05 volumio-pi400 systemd[1]: Reached target Local File Systems. Jan 05 08:35:05 volumio-pi400 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jan 05 08:35:05 volumio-pi400 systemd[1]: Starting Create Volatile Files and Directories... Jan 05 08:35:05 volumio-pi400 systemd[1]: Started ifup for wlan0. Jan 05 08:35:05 volumio-pi400 systemd[1]: Starting Preprocess NFS configuration... Jan 05 08:35:05 volumio-pi400 systemd[1]: Started ifup for eth0. Jan 05 08:35:05 volumio-pi400 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jan 05 08:35:05 volumio-pi400 systemd[1]: Starting Raise network interfaces... Jan 05 08:35:05 volumio-pi400 systemd[1]: Received SIGRTMIN+20 from PID 241 (plymouthd). Jan 05 08:35:05 volumio-pi400 systemd[1]: plymouth-read-write.service: Succeeded. Jan 05 08:35:05 volumio-pi400 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jan 05 08:35:05 volumio-pi400 systemd[1]: nfs-config.service: Succeeded. Jan 05 08:35:05 volumio-pi400 systemd[1]: Started Preprocess NFS configuration. Jan 05 08:35:05 volumio-pi400 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jan 05 08:35:05 volumio-pi400 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jan 05 08:35:05 volumio-pi400 systemd[1]: Reached target NFS client services. Jan 05 08:35:05 volumio-pi400 systemd[1]: Started Create Volatile Files and Directories. Jan 05 08:35:05 volumio-pi400 systemd[1]: Starting RPC bind portmap service... Jan 05 08:35:05 volumio-pi400 systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jan 05 08:35:05 volumio-pi400 systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jan 05 08:35:05 volumio-pi400 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jan 05 08:35:05 volumio-pi400 systemd[1]: Started Update UTMP about System Boot/Shutdown. Jan 05 08:35:05 volumio-pi400 systemd[1]: Reached target System Initialization. Jan 05 08:35:05 volumio-pi400 systemd[1]: Started Daily Cleanup of Temporary Directories. Jan 05 08:35:05 volumio-pi400 systemd[1]: Listening on D-Bus System Message Bus Socket. Jan 05 08:35:05 volumio-pi400 systemd[1]: Listening on mpd.socket. Jan 05 08:35:05 volumio-pi400 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jan 05 08:35:05 volumio-pi400 systemd[1]: apt-daily.timer: Not using persistent file timestamp Wed 2025-01-29 22:26:45 UTC as it is in the future. Jan 05 08:35:05 volumio-pi400 systemd[1]: Started Daily apt download activities. Jan 05 08:35:05 volumio-pi400 systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Thu 2025-01-30 06:14:45 UTC as it is in the future. Jan 05 08:35:05 volumio-pi400 systemd[1]: Started Daily apt upgrade and clean activities. Jan 05 08:35:05 volumio-pi400 systemd[1]: Reached target Timers. Jan 05 08:35:05 volumio-pi400 systemd[1]: Listening on triggerhappy.socket. Jan 05 08:35:05 volumio-pi400 systemd[1]: Reached target Sockets. Jan 05 08:35:05 volumio-pi400 systemd[1]: Reached target Basic System. Jan 05 08:35:05 volumio-pi400 systemd[1]: Started volumio-remote-updater.service. Jan 05 08:35:05 volumio-pi400 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jan 05 08:35:05 volumio-pi400 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jan 05 08:35:05 volumio-pi400 systemd[1]: Started Volumio Iptables Module. Jan 05 08:35:05 volumio-pi400 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jan 05 08:35:05 volumio-pi400 systemd[1]: Starting triggerhappy global hotkey daemon... Jan 05 08:35:05 volumio-pi400 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jan 05 08:35:05 volumio-pi400 systemd[1]: Starting Configure Bluetooth Modems connected by UART... Jan 05 08:35:05 volumio-pi400 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 05 08:35:05 volumio-pi400 systemd[1]: Started D-Bus System Message Bus. Jan 05 08:35:05 volumio-pi400 thd[451]: Unable to parse trigger line: Jan 05 08:35:05 volumio-pi400 thd[451]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jan 05 08:35:05 volumio-pi400 thd[451]: Unable to parse trigger line: Jan 05 08:35:05 volumio-pi400 thd[451]: Unable to parse trigger line: Jan 05 08:35:05 volumio-pi400 thd[451]: Unable to parse trigger line: Jan 05 08:35:05 volumio-pi400 thd[451]: Unable to parse trigger line: Jan 05 08:35:05 volumio-pi400 thd[451]: Unable to parse trigger line: Jan 05 08:35:05 volumio-pi400 systemd[1]: Starting WPA supplicant... Jan 05 08:35:05 volumio-pi400 systemd[1]: Started Manage Sound Card State (restore and store). Jan 05 08:35:05 volumio-pi400 alsactl[470]: alsactl 1.1.8 daemon started Jan 05 08:35:05 volumio-pi400 systemd[1]: Starting Save/Restore Sound Card State... Jan 05 08:35:05 volumio-pi400 systemd[1]: Starting dhcpcd on all interfaces... Jan 05 08:35:05 volumio-pi400 systemd[1]: Starting Wireless Services... Jan 05 08:35:05 volumio-pi400 systemd[1]: Starting Login Service... Jan 05 08:35:05 volumio-pi400 systemd[1]: Started UPnP Renderer front-end to MPD. Jan 05 08:35:06 volumio-pi400 avahi-daemon[453]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jan 05 08:35:06 volumio-pi400 avahi-daemon[453]: Successfully dropped root privileges. Jan 05 08:35:06 volumio-pi400 avahi-daemon[453]: avahi-daemon 0.7 starting up. Jan 05 08:35:06 volumio-pi400 systemd-logind[476]: Watching system buttons on /dev/input/event0 ( Raspberry Pi Internal Keyboard) Jan 05 08:35:06 volumio-pi400 systemd-logind[476]: New seat seat0. Jan 05 08:35:06 volumio-pi400 kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Jan 05 08:35:06 volumio-pi400 kernel: bcmgenet fd580000.ethernet eth0: Link is Down Jan 05 08:35:06 volumio-pi400 kernel: uart-pl011 fe201000.serial: no DMA platform data Jan 05 08:35:06 volumio-pi400 dhcpcd[474]: dev: loaded udev Jan 05 08:35:07 volumio-pi400 kernel: 8021q: 802.1Q VLAN Support v1.8 Jan 05 08:35:07 volumio-pi400 dhcpcd[474]: forked to background, child pid 522 Jan 05 08:35:07 volumio-pi400 thd[451]: Found socket passed from systemd Jan 05 08:35:07 volumio-pi400 systemd[1]: Started RPC bind portmap service. Jan 05 08:35:07 volumio-pi400 systemd[1]: Started triggerhappy global hotkey daemon. Jan 05 08:35:07 volumio-pi400 systemd[1]: Started Save/Restore Sound Card State. Jan 05 08:35:07 volumio-pi400 systemd[1]: Started dhcpcd on all interfaces. Jan 05 08:35:07 volumio-pi400 avahi-daemon[453]: Successfully called chroot(). Jan 05 08:35:07 volumio-pi400 avahi-daemon[453]: Successfully dropped remaining capabilities. Jan 05 08:35:07 volumio-pi400 avahi-daemon[453]: Loading service file /services/volumio.service. Jan 05 08:35:07 volumio-pi400 avahi-daemon[453]: Network interface enumeration completed. Jan 05 08:35:07 volumio-pi400 avahi-daemon[453]: Server startup complete. Host name is volumio-pi400.local. Local service cookie is 4114523885. Jan 05 08:35:07 volumio-pi400 avahi-daemon[453]: Service "Volumio-Pi400" (/services/volumio.service) successfully established. Jan 05 08:35:07 volumio-pi400 ifup[389]: ifup: waiting for lock on /run/network/ifstate.wlan0 Jan 05 08:35:07 volumio-pi400 systemd[1]: Started Login Service. Jan 05 08:35:07 volumio-pi400 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jan 05 08:35:07 volumio-pi400 systemd[1]: Reached target Sound Card. Jan 05 08:35:07 volumio-pi400 systemd[1]: Reached target RPC Port Mapper. Jan 05 08:35:07 volumio-pi400 systemd[1]: Reached target Remote File Systems (Pre). Jan 05 08:35:07 volumio-pi400 systemd[1]: Reached target Remote File Systems. Jan 05 08:35:07 volumio-pi400 sh[384]: eth0=eth0 Jan 05 08:35:07 volumio-pi400 sh[381]: wlan0=wlan0 Jan 05 08:35:07 volumio-pi400 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jan 05 08:35:07 volumio-pi400 systemd[1]: Started Raise network interfaces. Jan 05 08:35:08 volumio-pi400 dhcpcd-run-hooks[603]: wlan0: starting wpa_supplicant Jan 05 08:35:08 volumio-pi400 loadcpufreq[541]: Loading cpufreq kernel modules...done (none). Jan 05 08:35:08 volumio-pi400 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jan 05 08:35:08 volumio-pi400 systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jan 05 08:35:08 volumio-pi400 systemd[1]: iptables.service: Succeeded. Jan 05 08:35:08 volumio-pi400 cpufrequtils[613]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jan 05 08:35:08 volumio-pi400 systemd[1]: Started LSB: set CPUFreq kernel parameters. Jan 05 08:35:08 volumio-pi400 systemd[1]: Started WPA supplicant. Jan 05 08:35:08 volumio-pi400 systemd[1]: Reached target Network. Jan 05 08:35:08 volumio-pi400 wpa_supplicant[469]: Successfully initialized wpa_supplicant Jan 05 08:35:08 volumio-pi400 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jan 05 08:35:08 volumio-pi400 systemd[1]: Starting Network Time Service... Jan 05 08:35:08 volumio-pi400 systemd[1]: Reached target Network is Online. Jan 05 08:35:08 volumio-pi400 systemd[1]: Starting LSB: Brings up/down network automatically... Jan 05 08:35:08 volumio-pi400 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 05 08:35:08 volumio-pi400 systemd[1]: Starting /etc/rc.local Compatibility... Jan 05 08:35:08 volumio-pi400 systemd[1]: Starting OpenBSD Secure Shell server... Jan 05 08:35:08 volumio-pi400 systemd[1]: Starting Music Player Daemon... Jan 05 08:35:08 volumio-pi400 systemd[1]: Starting Permit User Sessions... Jan 05 08:35:08 volumio-pi400 systemd[1]: Starting Samba NMB Daemon... Jan 05 08:35:08 volumio-pi400 systemd[1]: Started /etc/rc.local Compatibility. Jan 05 08:35:09 volumio-pi400 systemd[1]: Started Permit User Sessions. Jan 05 08:35:09 volumio-pi400 systemd[1]: Starting Hold until boot process finishes up... Jan 05 08:35:09 volumio-pi400 systemd[1]: Starting Terminate Plymouth Boot Screen... Jan 05 08:35:09 volumio-pi400 systemd[1]: Received SIGRTMIN+21 from PID 241 (plymouthd). Jan 05 08:35:09 volumio-pi400 systemd[1]: Received SIGRTMIN+21 from PID 241 (plymouthd). Jan 05 08:35:09 volumio-pi400 ifplugd[629]: Network Interface Plugging Daemon...error: unexpectedly disconnected from boot status daemon Jan 05 08:35:09 volumio-pi400 systemd[1]: plymouth-start.service: Succeeded. Jan 05 08:35:09 volumio-pi400 systemd[1]: plymouth-quit-wait.service: Succeeded. Jan 05 08:35:09 volumio-pi400 systemd[1]: Started Hold until boot process finishes up. Jan 05 08:35:09 volumio-pi400 systemd[1]: plymouth-quit.service: Succeeded. Jan 05 08:35:09 volumio-pi400 systemd[1]: Started Terminate Plymouth Boot Screen. Jan 05 08:35:09 volumio-pi400 ifplugd(eth0)[656]: ifplugd 0.28 initializing. Jan 05 08:35:09 volumio-pi400 ifplugd(eth0)[656]: Using interface eth0/DC:A6:32:DE:27:50 with driver (version: 5.10.92-v7l+) Jan 05 08:35:09 volumio-pi400 systemd[1]: Started Getty on tty1. Jan 05 08:35:09 volumio-pi400 ifplugd(eth0)[656]: Using detection mode: SIOCETHTOOL Jan 05 08:35:09 volumio-pi400 ifplugd(eth0)[656]: Initialization complete, link beat not detected. Jan 05 08:35:09 volumio-pi400 systemd[1]: Reached target Login Prompts. Jan 05 08:35:09 volumio-pi400 ifplugd[629]: start eth0...done. Jan 05 08:35:09 volumio-pi400 systemd[1]: Started LSB: Brings up/down network automatically. Jan 05 08:35:09 volumio-pi400 haveged[427]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jan 05 08:35:09 volumio-pi400 haveged[427]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jan 05 08:35:09 volumio-pi400 haveged[427]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99791 Jan 05 08:35:09 volumio-pi400 haveged[427]: haveged: fills: 0, generated: 0 Jan 05 08:35:09 volumio-pi400 kernel: random: crng init done Jan 05 08:35:09 volumio-pi400 kernel: random: 7 urandom warning(s) missed due to ratelimiting Jan 05 08:35:10 volumio-pi400 systemd[1]: systemd-rfkill.service: Succeeded. Jan 05 08:35:10 volumio-pi400 ntpd[628]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 05 08:35:10 volumio-pi400 ntpd[628]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jan 05 08:35:10 volumio-pi400 systemd[1]: Started Network Time Service. Jan 05 08:35:10 volumio-pi400 ntpd[663]: proto: precision = 2.000 usec (-19) Jan 05 08:35:10 volumio-pi400 ntpd[663]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 05 08:35:10 volumio-pi400 ntpd[663]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 05 08:35:10 volumio-pi400 ntpd[663]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 740 days ago Jan 05 08:35:10 volumio-pi400 ntpd[663]: Listen and drop on 0 v6wildcard [::]:123 Jan 05 08:35:10 volumio-pi400 ntpd[663]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 05 08:35:10 volumio-pi400 ntpd[663]: Listen normally on 2 lo 127.0.0.1:123 Jan 05 08:35:10 volumio-pi400 ntpd[663]: Listening on routing socket on fd #19 for interface updates Jan 05 08:35:10 volumio-pi400 ntpd[663]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 05 08:35:10 volumio-pi400 ntpd[663]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 05 08:35:11 volumio-pi400 dhcpcd[522]: wlan0: connected to Access Point `' Jan 05 08:35:11 volumio-pi400 dhcpcd[522]: eth0: waiting for carrier Jan 05 08:35:11 volumio-pi400 dhcpcd[522]: wlan0: waiting for carrier Jan 05 08:35:11 volumio-pi400 sshd[677]: Server listening on 0.0.0.0 port 22. Jan 05 08:35:11 volumio-pi400 sshd[677]: Server listening on :: port 22. Jan 05 08:35:11 volumio-pi400 systemd[1]: Started OpenBSD Secure Shell server. Jan 05 08:35:11 volumio-pi400 volumio-remote-updater[447]: Error: No active session Jan 05 08:35:11 volumio-pi400 volumio-remote-updater[447]: [2025-01-05 08:35:11] [info] asio async_connect error: system:111 (Connection refused) Jan 05 08:35:11 volumio-pi400 volumio-remote-updater[447]: [2025-01-05 08:35:11] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 05 08:35:11 volumio-pi400 volumio-remote-updater[447]: [2025-01-05 08:35:11] [error] handle_connect error: Underlying Transport Error Jan 05 08:35:11 volumio-pi400 ntpd[663]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 05 08:35:12 volumio-pi400 volumio[479]: Could not open config: /tmp/upmpdcli.conf Jan 05 08:35:12 volumio-pi400 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 05 08:35:12 volumio-pi400 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 05 08:35:12 volumio-pi400 ntpd[663]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 05 08:35:13 volumio-pi400 ntpd[663]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 05 08:35:14 volumio-pi400 kernel: Bluetooth: Core ver 2.22 Jan 05 08:35:14 volumio-pi400 kernel: NET: Registered protocol family 31 Jan 05 08:35:14 volumio-pi400 kernel: Bluetooth: HCI device and connection manager initialized Jan 05 08:35:14 volumio-pi400 kernel: Bluetooth: HCI socket layer initialized Jan 05 08:35:14 volumio-pi400 kernel: Bluetooth: L2CAP socket layer initialized Jan 05 08:35:14 volumio-pi400 kernel: Bluetooth: SCO socket layer initialized Jan 05 08:35:14 volumio-pi400 kernel: Bluetooth: HCI UART driver ver 2.3 Jan 05 08:35:14 volumio-pi400 kernel: Bluetooth: HCI UART protocol H4 registered Jan 05 08:35:14 volumio-pi400 kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jan 05 08:35:14 volumio-pi400 kernel: Bluetooth: HCI UART protocol Broadcom registered Jan 05 08:35:14 volumio-pi400 btuart[454]: bcm43xx_init Jan 05 08:35:14 volumio-pi400 btuart[454]: Flash firmware /lib/firmware/brcm/BCM4345C5.hcd Jan 05 08:35:14 volumio-pi400 btuart[454]: Set Controller UART speed to 3000000 bit/s Jan 05 08:35:14 volumio-pi400 btuart[454]: Device setup complete Jan 05 08:35:14 volumio-pi400 systemd[1]: Starting Load/Save RF Kill Switch Status... Jan 05 08:35:14 volumio-pi400 systemd[1]: Started Configure Bluetooth Modems connected by UART. Jan 05 08:35:14 volumio-pi400 systemd[1]: Started Load/Save RF Kill Switch Status. Jan 05 08:35:14 volumio-pi400 systemd[1]: Created slice system-bthelper.slice. Jan 05 08:35:14 volumio-pi400 systemd[1]: Starting Raspberry Pi bluetooth helper... Jan 05 08:35:14 volumio-pi400 nmbd[643]: [2025/01/05 08:35:14.572510, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 05 08:35:14 volumio-pi400 nmbd[643]: started asyncdns process 697 Jan 05 08:35:14 volumio-pi400 nmbd[643]: [2025/01/05 08:35:14.577328, 0] ../lib/util/become_daemon.c:149(daemon_status) Jan 05 08:35:14 volumio-pi400 nmbd[643]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jan 05 08:35:14 volumio-pi400 nmbd[643]: [2025/01/05 08:35:14.577626, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jan 05 08:35:14 volumio-pi400 nmbd[643]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jan 05 08:35:14 volumio-pi400 bthelper[695]: Raspberry Pi BDADDR already set Jan 05 08:35:14 volumio-pi400 systemd[1]: Started Raspberry Pi bluetooth helper. Jan 05 08:35:14 volumio-pi400 systemd[1]: Starting Bluetooth service... Jan 05 08:35:14 volumio-pi400 ntpd[663]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 05 08:35:15 volumio-pi400 bluetoothd[705]: Bluetooth daemon 5.50 Jan 05 08:35:15 volumio-pi400 bluetoothd[705]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jan 05 08:35:15 volumio-pi400 systemd[1]: Started Bluetooth service. Jan 05 08:35:15 volumio-pi400 systemd[1]: Reached target Bluetooth. Jan 05 08:35:15 volumio-pi400 bluetoothd[705]: Starting SDP server Jan 05 08:35:15 volumio-pi400 bluetoothd[705]: Excluding (cli) sap Jan 05 08:35:15 volumio-pi400 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jan 05 08:35:15 volumio-pi400 kernel: Bluetooth: BNEP filters: protocol multicast Jan 05 08:35:15 volumio-pi400 kernel: Bluetooth: BNEP socket layer initialized Jan 05 08:35:15 volumio-pi400 bluetoothd[705]: Bluetooth management interface 1.18 initialized Jan 05 08:35:15 volumio-pi400 dbus-daemon[461]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=705 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jan 05 08:35:15 volumio-pi400 systemd[1]: Starting Hostname Service... Jan 05 08:35:15 volumio-pi400 bluetoothd[705]: Failed to set privacy: Rejected (0x0b) Jan 05 08:35:15 volumio-pi400 dbus-daemon[461]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 05 08:35:15 volumio-pi400 systemd[1]: Started Hostname Service. Jan 05 08:35:16 volumio-pi400 dhcpcd[522]: wlan0: carrier acquired Jan 05 08:35:16 volumio-pi400 dhcpcd[522]: wlan0: connected to Access Point `He-Fixed-My-PC' Jan 05 08:35:16 volumio-pi400 dhcpcd[522]: DUID 00:01:00:01:29:d8:61:9b:dc:a6:32:de:27:50 Jan 05 08:35:16 volumio-pi400 dhcpcd[522]: wlan0: IAID 32:de:27:51 Jan 05 08:35:16 volumio-pi400 dhcpcd[522]: wlan0: adding address fe80::dea6:32ff:fede:2751 Jan 05 08:35:16 volumio-pi400 dhcpcd[522]: ipv6_addaddr1: Permission denied Jan 05 08:35:16 volumio-pi400 dhcpcd[522]: wlan0: probing address 192.168.1.164/24 Jan 05 08:35:16 volumio-pi400 dhcpcd[522]: wlan0: soliciting an IPv6 router Jan 05 08:35:16 volumio-pi400 volumio-remote-updater[447]: [2025-01-05 08:35:16] [info] asio async_connect error: system:111 (Connection refused) Jan 05 08:35:16 volumio-pi400 volumio-remote-updater[447]: [2025-01-05 08:35:16] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 05 08:35:16 volumio-pi400 volumio-remote-updater[447]: [2025-01-05 08:35:16] [error] handle_connect error: Underlying Transport Error Jan 05 08:35:16 volumio-pi400 wireless.js[475]: Cleaning previous... Jan 05 08:35:17 volumio-pi400 sudo[718]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 05 08:35:17 volumio-pi400 sudo[718]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:17 volumio-pi400 sudo[718]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:17 volumio-pi400 sudo[720]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 05 08:35:17 volumio-pi400 sudo[720]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:17 volumio-pi400 mpd[659]: Jan 05 08:35 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 05 08:35:17 volumio-pi400 dhcpcd[522]: wlan0: carrier lost Jan 05 08:35:17 volumio-pi400 sudo[720]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:18 volumio-pi400 wireless.js[475]: Stopped aP Jan 05 08:35:18 volumio-pi400 sudo[746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 05 08:35:18 volumio-pi400 sudo[746]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:18 volumio-pi400 sudo[746]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:18 volumio-pi400 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 05 08:35:18 volumio-pi400 sudo[748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jan 05 08:35:18 volumio-pi400 sudo[748]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:18 volumio-pi400 sudo[748]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:18 volumio-pi400 sudo[755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 05 08:35:18 volumio-pi400 sudo[755]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:18 volumio-pi400 sudo[755]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:18 volumio-pi400 sudo[757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jan 05 08:35:18 volumio-pi400 sudo[757]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:19 volumio-pi400 mpd[659]: Jan 05 08:35 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 05 08:35:19 volumio-pi400 mpd[659]: Jan 05 08:35 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 05 08:35:19 volumio-pi400 systemd[1]: Started Music Player Daemon. Jan 05 08:35:19 volumio-pi400 systemd[1]: systemd-rfkill.service: Succeeded. Jan 05 08:35:19 volumio-pi400 bthelper[695]: Changing power off succeeded Jan 05 08:35:20 volumio-pi400 bthelper[695]: [CHG] Controller DC:A6:32:DE:27:52 Class: 0x0000041c Jan 05 08:35:20 volumio-pi400 bthelper[695]: Changing power on succeeded Jan 05 08:35:21 volumio-pi400 sudo[757]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:21 volumio-pi400 wireless.js[475]: Start wireless flow Jan 05 08:35:21 volumio-pi400 wireless.js[475]: Stopped hotspot (if there).. Jan 05 08:35:21 volumio-pi400 sudo[771]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 05 08:35:21 volumio-pi400 sudo[771]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:21 volumio-pi400 sudo[771]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:21 volumio-pi400 sudo[773]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 05 08:35:21 volumio-pi400 sudo[773]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:21 volumio-pi400 sudo[773]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:21 volumio-pi400 wireless.js[475]: DHCP IP Jan 05 08:35:21 volumio-pi400 wireless.js[475]: Start ap Jan 05 08:35:21 volumio-pi400 wpa_supplicant[775]: Successfully initialized wpa_supplicant Jan 05 08:35:21 volumio-pi400 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 05 08:35:21 volumio-pi400 sudo[776]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jan 05 08:35:21 volumio-pi400 sudo[776]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:21 volumio-pi400 dhcpcd[777]: sending commands to master dhcpcd process Jan 05 08:35:21 volumio-pi400 dhcpcd[522]: control command: /sbin/dhcpcd Jan 05 08:35:21 volumio-pi400 sudo[776]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:21 volumio-pi400 dhcpcd[522]: lo: carrier acquired Jan 05 08:35:21 volumio-pi400 dhcpcd[522]: lo: IAID 6c:6f:00:00 Jan 05 08:35:21 volumio-pi400 dhcpcd[522]: lo: ipv6_start: Operation not supported Jan 05 08:35:21 volumio-pi400 dhcpcd[522]: lo: using static address 127.0.0.1/8 Jan 05 08:35:21 volumio-pi400 dhcpcd[522]: lo: adding route to 127.0.0.0/8 Jan 05 08:35:21 volumio-pi400 dhcpcd[522]: arp_announce: Invalid argument Jan 05 08:35:22 volumio-pi400 wireless.js[475]: trying... Jan 05 08:35:22 volumio-pi400 sudo[817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 05 08:35:22 volumio-pi400 sudo[817]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:22 volumio-pi400 sudo[817]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:23 volumio-pi400 kernel: cam1-reg: disabling Jan 05 08:35:23 volumio-pi400 kernel: cam-dummy-reg: disabling Jan 05 08:35:23 volumio-pi400 wireless.js[475]: trying... Jan 05 08:35:23 volumio-pi400 sudo[820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 05 08:35:23 volumio-pi400 sudo[820]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:23 volumio-pi400 sudo[820]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:24 volumio-pi400 wpa_supplicant[782]: wlan0: Trying to associate with SSID 'He-Fixed-My-PC' Jan 05 08:35:24 volumio-pi400 volumio-remote-updater[447]: [2025-01-05 08:35:24] [info] asio async_connect error: system:111 (Connection refused) Jan 05 08:35:24 volumio-pi400 volumio-remote-updater[447]: [2025-01-05 08:35:24] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 05 08:35:24 volumio-pi400 volumio-remote-updater[447]: [2025-01-05 08:35:24] [error] handle_connect error: Underlying Transport Error Jan 05 08:35:24 volumio-pi400 wireless.js[475]: trying... Jan 05 08:35:24 volumio-pi400 sudo[823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 05 08:35:24 volumio-pi400 sudo[823]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:24 volumio-pi400 sudo[823]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:25 volumio-pi400 wireless.js[475]: trying... Jan 05 08:35:25 volumio-pi400 sudo[826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 05 08:35:25 volumio-pi400 sudo[826]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:25 volumio-pi400 sudo[826]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:26 volumio-pi400 wireless.js[475]: trying... Jan 05 08:35:26 volumio-pi400 sudo[829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 05 08:35:26 volumio-pi400 sudo[829]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:26 volumio-pi400 sudo[829]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:27 volumio-pi400 wpa_supplicant[782]: wlan0: Associated with 50:0f:f5:23:8c:b4 Jan 05 08:35:27 volumio-pi400 wpa_supplicant[782]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 05 08:35:27 volumio-pi400 wpa_supplicant[782]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=GB Jan 05 08:35:27 volumio-pi400 wpa_supplicant[782]: wlan0: WPA: Key negotiation completed with 50:0f:f5:23:8c:b4 [PTK=CCMP GTK=CCMP] Jan 05 08:35:27 volumio-pi400 wpa_supplicant[782]: wlan0: CTRL-EVENT-CONNECTED - Connection to 50:0f:f5:23:8c:b4 completed [id=0 id_str=] Jan 05 08:35:27 volumio-pi400 dhcpcd[522]: wlan0: carrier acquired Jan 05 08:35:27 volumio-pi400 dhcpcd[522]: wlan0: IAID 32:de:27:51 Jan 05 08:35:27 volumio-pi400 dhcpcd[522]: wlan0: probing address 192.168.1.164/24 Jan 05 08:35:27 volumio-pi400 wireless.js[475]: trying... Jan 05 08:35:27 volumio-pi400 sudo[833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 05 08:35:27 volumio-pi400 sudo[833]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:27 volumio-pi400 sudo[833]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:27 volumio-pi400 dhcpcd[522]: wlan0: soliciting an IPv6 router Jan 05 08:35:28 volumio-pi400 wireless.js[475]: trying... Jan 05 08:35:28 volumio-pi400 sudo[836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 05 08:35:28 volumio-pi400 sudo[836]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:28 volumio-pi400 sudo[836]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:29 volumio-pi400 wireless.js[475]: trying... Jan 05 08:35:29 volumio-pi400 sudo[839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 05 08:35:29 volumio-pi400 sudo[839]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:29 volumio-pi400 sudo[839]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:30 volumio-pi400 wireless.js[475]: trying... Jan 05 08:35:30 volumio-pi400 sudo[842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 05 08:35:30 volumio-pi400 sudo[842]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:30 volumio-pi400 sudo[842]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:31 volumio-pi400 wireless.js[475]: trying... Jan 05 08:35:31 volumio-pi400 sudo[845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 05 08:35:31 volumio-pi400 sudo[845]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:31 volumio-pi400 sudo[845]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:32 volumio-pi400 dhcpcd[522]: wlan0: using static address 192.168.1.164/24 Jan 05 08:35:32 volumio-pi400 avahi-daemon[453]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.164. Jan 05 08:35:32 volumio-pi400 avahi-daemon[453]: New relevant interface wlan0.IPv4 for mDNS. Jan 05 08:35:32 volumio-pi400 dhcpcd[522]: wlan0: adding route to 192.168.1.0/24 Jan 05 08:35:32 volumio-pi400 avahi-daemon[453]: Registering new address record for 192.168.1.164 on wlan0.IPv4. Jan 05 08:35:32 volumio-pi400 dhcpcd[522]: wlan0: adding default route via 192.168.1.254 Jan 05 08:35:32 volumio-pi400 wireless.js[475]: trying... Jan 05 08:35:32 volumio-pi400 sudo[869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 05 08:35:32 volumio-pi400 sudo[869]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:32 volumio-pi400 sudo[869]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:32 volumio-pi400 wireless.js[475]: Connected to: ----He-Fixed-My-PC Jan 05 08:35:32 volumio-pi400 wireless.js[475]: ---- Jan 05 08:35:32 volumio-pi400 sudo[872]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 05 08:35:32 volumio-pi400 sudo[872]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:32 volumio-pi400 sudo[872]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:32 volumio-pi400 wireless.js[475]: ... joined AP, wlan0 IPv4 is 192.168.1.164, ipV6 is undefined Jan 05 08:35:32 volumio-pi400 wireless.js[475]: It's done! AP Jan 05 08:35:32 volumio-pi400 systemd[1]: Started Wireless Services. Jan 05 08:35:32 volumio-pi400 systemd[1]: Started Volumio Backend Module. Jan 05 08:35:32 volumio-pi400 systemd[1]: Started Volumio Streaming Daemon. Jan 05 08:35:33 volumio-pi400 volumio-streaming-daemon[877]: ############################ Jan 05 08:35:33 volumio-pi400 volumio-streaming-daemon[877]: # Volumio Streaming Daemon # Jan 05 08:35:33 volumio-pi400 volumio-streaming-daemon[877]: # Running on port 7777 # Jan 05 08:35:33 volumio-pi400 volumio-streaming-daemon[877]: ############################ Jan 05 08:35:33 volumio-pi400 volumio-streaming-daemon[877]: Environment: production Jan 05 08:35:33 volumio-pi400 ntpd[663]: Listen normally on 3 wlan0 192.168.1.164:123 Jan 05 08:35:33 volumio-pi400 ntpd[663]: new interface(s) found: waking up resolver Jan 05 08:35:34 volumio-pi400 volumio-remote-updater[447]: [2025-01-05 08:35:34] [info] asio async_connect error: system:111 (Connection refused) Jan 05 08:35:34 volumio-pi400 volumio-remote-updater[447]: [2025-01-05 08:35:34] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 05 08:35:34 volumio-pi400 volumio-remote-updater[447]: [2025-01-05 08:35:34] [error] handle_connect error: Underlying Transport Error Jan 05 08:35:34 volumio-pi400 systemd[1]: Started Samba NMB Daemon. Jan 05 08:35:34 volumio-pi400 nmbd[643]: [2025/01/05 08:35:34.594454, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 05 08:35:34 volumio-pi400 nmbd[643]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 05 08:35:34 volumio-pi400 systemd[1]: Starting Samba Winbind Daemon... Jan 05 08:35:35 volumio-pi400 winbindd[889]: [2025/01/05 08:35:35.075553, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 05 08:35:35 volumio-pi400 winbindd[889]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 05 08:35:35 volumio-pi400 winbindd[889]: [2025/01/05 08:35:35.109629, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 05 08:35:35 volumio-pi400 systemd[1]: Started Samba Winbind Daemon. Jan 05 08:35:35 volumio-pi400 winbindd[889]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 05 08:35:35 volumio-pi400 systemd[1]: Starting Samba SMB Daemon... Jan 05 08:35:35 volumio-pi400 systemd[1]: systemd-fsckd.service: Succeeded. Jan 05 08:35:36 volumio-pi400 smbd[894]: [2025/01/05 08:35:36.193437, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 05 08:35:36 volumio-pi400 systemd[1]: Started Samba SMB Daemon. Jan 05 08:35:36 volumio-pi400 smbd[894]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 05 08:35:36 volumio-pi400 systemd[1]: Reached target Multi-User System. Jan 05 08:35:36 volumio-pi400 systemd[1]: Reached target Graphical Interface. Jan 05 08:35:36 volumio-pi400 systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 05 08:35:36 volumio-pi400 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 05 08:35:36 volumio-pi400 systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 05 08:35:36 volumio-pi400 systemd[1]: Startup finished in 9.396s (kernel) + 36.852s (userspace) = 46.249s. Jan 05 08:35:37 volumio-pi400 volumio[876]: info: ------------------------------------------- Jan 05 08:35:37 volumio-pi400 volumio[876]: info: ----- Volumio3 ---- Jan 05 08:35:37 volumio-pi400 volumio[876]: info: ------------------------------------------- Jan 05 08:35:37 volumio-pi400 volumio[876]: info: ----- System startup ---- Jan 05 08:35:37 volumio-pi400 volumio[876]: info: ------------------------------------------- Jan 05 08:35:38 volumio-pi400 volumio[876]: info: MYVOLUMIO Environment detected Jan 05 08:35:39 volumio-pi400 volumio[876]: info: Plugin folders cleanup Jan 05 08:35:39 volumio-pi400 volumio[876]: info: Scanning into folder /volumio/app/plugins/ Jan 05 08:35:39 volumio-pi400 volumio[876]: info: Scanning category audio_interface Jan 05 08:35:39 volumio-pi400 volumio[876]: info: Scanning category miscellanea Jan 05 08:35:39 volumio-pi400 volumio[876]: info: Scanning category music_service Jan 05 08:35:39 volumio-pi400 volumio[876]: info: Scanning category plugins.json Jan 05 08:35:39 volumio-pi400 volumio[876]: info: Scanning category system_controller Jan 05 08:35:39 volumio-pi400 volumio[876]: info: Scanning category user_interface Jan 05 08:35:39 volumio-pi400 volumio[876]: info: Scanning into folder /data/plugins/ Jan 05 08:35:39 volumio-pi400 volumio[876]: info: Plugin folders cleanup completed Jan 05 08:35:39 volumio-pi400 volumio[876]: info: ------------------------------------------- Jan 05 08:35:39 volumio-pi400 volumio[876]: info: ----- Core plugins startup ---- Jan 05 08:35:39 volumio-pi400 volumio[876]: info: ------------------------------------------- Jan 05 08:35:39 volumio-pi400 volumio[876]: info: Loading plugins from folder /volumio/app/plugins/ Jan 05 08:35:39 volumio-pi400 volumio[876]: info: Adding plugin upnp to MyMusic Plugins Jan 05 08:35:39 volumio-pi400 volumio[876]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 05 08:35:39 volumio-pi400 volumio[876]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 05 08:35:39 volumio-pi400 volumio[876]: info: Loading plugins from folder /data/plugins/ Jan 05 08:35:39 volumio-pi400 volumio[876]: info: Loading plugin "system"... Jan 05 08:35:39 volumio-pi400 volumio[876]: info: Loading plugin "appearance"... Jan 05 08:35:41 volumio-pi400 volumio[876]: info: Loading plugin "network"... Jan 05 08:35:41 volumio-pi400 volumio[876]: info: Refreshing Cached IP Addresses Jan 05 08:35:41 volumio-pi400 sudo[914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 05 08:35:41 volumio-pi400 sudo[914]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:41 volumio-pi400 sudo[914]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:41 volumio-pi400 volumio[876]: info: Loading plugin "services"... Jan 05 08:35:41 volumio-pi400 sudo[916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 05 08:35:41 volumio-pi400 sudo[916]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:41 volumio-pi400 volumio[876]: info: Loading plugin "alsa_controller"... Jan 05 08:35:41 volumio-pi400 sudo[916]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:41 volumio-pi400 sudo[919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 05 08:35:41 volumio-pi400 sudo[919]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:41 volumio-pi400 volumio[876]: warn: Unable to locate the audio output device D10. Please configure a valid output device. Jan 05 08:35:41 volumio-pi400 volumio[876]: Invalid card number. Jan 05 08:35:41 volumio-pi400 volumio[876]: info: Hardware Mixer selected but no Hardware mixer available, detecting default mixer Jan 05 08:35:41 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 05 08:35:41 volumio-pi400 volumio[876]: Invalid card number. Jan 05 08:35:41 volumio-pi400 volumio[876]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 05 08:35:41 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 05 08:35:41 volumio-pi400 volumio[876]: info: Loading plugin "wizard"... Jan 05 08:35:41 volumio-pi400 volumio[876]: info: Loading plugin "volumio_command_line_client"... Jan 05 08:35:41 volumio-pi400 volumio[876]: info: Loading plugin "upnp"... Jan 05 08:35:41 volumio-pi400 volumio[876]: info: [1736066141765] Starting Upmpd Daemon Jan 05 08:35:41 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 05 08:35:41 volumio-pi400 volumio[876]: info: Loading plugin "my_music"... Jan 05 08:35:41 volumio-pi400 volumio[876]: info: Loading plugin "mpd"... Jan 05 08:35:42 volumio-pi400 volumio[876]: info: Creating MPD Configuration file Jan 05 08:35:42 volumio-pi400 volumio[876]: info: Loading plugin "upnp_browser"... Jan 05 08:35:42 volumio-pi400 sudo[933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 05 08:35:42 volumio-pi400 sudo[933]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:42 volumio-pi400 sudo[933]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:42 volumio-pi400 sudo[935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 05 08:35:42 volumio-pi400 sudo[935]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:42 volumio-pi400 systemd[1]: Stopping Music Player Daemon... Jan 05 08:35:42 volumio-pi400 systemd[1]: mpd.service: Succeeded. Jan 05 08:35:42 volumio-pi400 systemd[1]: Stopped Music Player Daemon. Jan 05 08:35:42 volumio-pi400 systemd[1]: Starting Music Player Daemon... Jan 05 08:35:43 volumio-pi400 volumio[876]: info: Loading plugin "networkfs"... Jan 05 08:35:43 volumio-pi400 volumio[876]: info: Starting Udev Watcher for removable devices Jan 05 08:35:43 volumio-pi400 sudo[943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=phillip,password=webster67,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.78/flacs /mnt/NAS/FLACS Jan 05 08:35:44 volumio-pi400 sudo[943]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:44 volumio-pi400 kernel: FS-Cache: Netfs 'cifs' registered for caching Jan 05 08:35:44 volumio-pi400 kernel: Key type cifs.spnego registered Jan 05 08:35:44 volumio-pi400 kernel: Key type cifs.idmap registered Jan 05 08:35:44 volumio-pi400 kernel: CIFS: Attempting to mount //192.168.1.78/flacs Jan 05 08:35:44 volumio-pi400 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. Jan 05 08:35:44 volumio-pi400 volumio-remote-updater[447]: [2025-01-05 08:35:44] [connect] Successful connection Jan 05 08:35:44 volumio-pi400 mpd[940]: Jan 05 08:35 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 05 08:35:44 volumio-pi400 volumio[876]: info: Ignoring mount for partition: boot Jan 05 08:35:44 volumio-pi400 volumio[876]: info: Ignoring mount for partition: volumio Jan 05 08:35:44 volumio-pi400 volumio[876]: info: Ignoring mount for partition: volumio_data Jan 05 08:35:44 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 05 08:35:44 volumio-pi400 volumio[876]: info: Loading plugin "alarm-clock"... Jan 05 08:35:44 volumio-pi400 volumio[876]: info: Loading plugin "airplay_emulation"... Jan 05 08:35:45 volumio-pi400 volumio[876]: info: Starting Shairport Sync Jan 05 08:35:45 volumio-pi400 volumio[876]: info: Loading plugin "last_100"... Jan 05 08:35:45 volumio-pi400 volumio[876]: info: Loading plugin "webradio"... Jan 05 08:35:45 volumio-pi400 sudo[919]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:45 volumio-pi400 volumio[876]: info: Loading plugin "i2s_dacs"... Jan 05 08:35:45 volumio-pi400 volumio[876]: info: Loading plugin "volumiodiscovery"... Jan 05 08:35:45 volumio-pi400 volumio[876]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 05 08:35:45 volumio-pi400 volumio[876]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 05 08:35:45 volumio-pi400 node[876]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 05 08:35:45 volumio-pi400 volumio[876]: *** WARNING *** For more information see Jan 05 08:35:45 volumio-pi400 volumio[876]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 05 08:35:45 volumio-pi400 volumio[876]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 05 08:35:45 volumio-pi400 volumio[876]: *** WARNING *** For more information see Jan 05 08:35:45 volumio-pi400 node[876]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 05 08:35:45 volumio-pi400 node[876]: *** WARNING *** For more information see Jan 05 08:35:45 volumio-pi400 node[876]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 05 08:35:45 volumio-pi400 node[876]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 05 08:35:45 volumio-pi400 node[876]: *** WARNING *** For more information see Jan 05 08:35:45 volumio-pi400 volumio[876]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 05 08:35:45 volumio-pi400 volumio[876]: info: Discovery: Started advertising with name: Volumio-Pi400 Jan 05 08:35:45 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 05 08:35:45 volumio-pi400 volumio[876]: info: Loading plugin "outputs"... Jan 05 08:35:45 volumio-pi400 volumio[876]: info: Loading plugin "albumart"... Jan 05 08:35:45 volumio-pi400 volumio[876]: info: Plugin example_plugin is not enabled Jan 05 08:35:45 volumio-pi400 volumio[876]: info: Loading plugin "inputs"... Jan 05 08:35:45 volumio-pi400 volumio[876]: info: Loading plugin "updater_comm"... Jan 05 08:35:45 volumio-pi400 systemd[1]: systemd-hostnamed.service: Succeeded. Jan 05 08:35:45 volumio-pi400 volumio[876]: info: Plugin mpdemulation is not enabled Jan 05 08:35:45 volumio-pi400 volumio[876]: info: Loading plugin "rest_api"... Jan 05 08:35:45 volumio-pi400 volumio[876]: info: Loading plugin "websocket"... Jan 05 08:35:45 volumio-pi400 volumio[876]: info: Loading i18n strings for locale en Jan 05 08:35:45 volumio-pi400 volumio[876]: Updating browse sources language Jan 05 08:35:45 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 05 08:35:45 volumio-pi400 mpd[940]: Jan 05 08:35 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 05 08:35:45 volumio-pi400 mpd[940]: Jan 05 08:35 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 05 08:35:45 volumio-pi400 systemd[1]: Started Music Player Daemon. Jan 05 08:35:45 volumio-pi400 sudo[935]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:45 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 05 08:35:46 volumio-pi400 volumio[876]: info: CoreCommandRouter::initPlayerControls Jan 05 08:35:46 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:46 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:46 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:46 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:46 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:46 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:46 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:46 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:46 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 05 08:35:46 volumio-pi400 volumio[876]: Express server listening on port 3000 Jan 05 08:35:46 volumio-pi400 volumio[876]: [Metrics] WebUI: 10s 349.07ms Jan 05 08:35:46 volumio-pi400 volumio[876]: Forking 3 albumart workers Jan 05 08:35:46 volumio-pi400 volumio[876]: info: CoreStateMachine::resetVolumioState Jan 05 08:35:46 volumio-pi400 volumio[876]: info: CoreStateMachine::getcurrentVolume Jan 05 08:35:46 volumio-pi400 volumio[876]: info: CoreCommandRouter::volumioRetrievevolume Jan 05 08:35:46 volumio-pi400 volumio[876]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 05 08:35:46 volumio-pi400 volumio[876]: info: CoreStateMachine::getcurrentVolume Jan 05 08:35:46 volumio-pi400 volumio[876]: info: CoreCommandRouter::volumioRetrievevolume Jan 05 08:35:46 volumio-pi400 volumio[876]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jan 05 08:35:46 volumio-pi400 volumio[876]: info: MPD Permissions set Jan 05 08:35:46 volumio-pi400 volumio[876]: verbose: New Socket.io Connection to 192.168.1.164:3000 from 192.168.1.169 UA: Dart/3.5 (dart:io) Total Clients: 1 Jan 05 08:35:46 volumio-pi400 volumio[876]: verbose: New Socket.io Connection to 192.168.1.164 from 192.168.1.169 UA: Mozilla/5.0 (Linux; Android 8.1.0; SM-T580 Build/M1AJQ; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.179 Safari/537.36 Total Clients: 2 Jan 05 08:35:46 volumio-pi400 volumio-remote-updater[447]: [2025-01-05 08:35:46] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1736066144 101 Jan 05 08:35:46 volumio-pi400 volumio[876]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Jan 05 08:35:46 volumio-pi400 volumio[876]: verbose: New Socket.io Connection to 192.168.1.164 from 192.168.1.169 UA: Mozilla/5.0 (Linux; Android 8.1.0; SM-T580 Build/M1AJQ; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.179 Safari/537.36 Total Clients: 4 Jan 05 08:35:46 volumio-pi400 volumio[876]: info: VolumeController:: Volume=undefined Mute =false Jan 05 08:35:46 volumio-pi400 volumio[876]: info: CoreStateMachine::pushState Jan 05 08:35:46 volumio-pi400 volumio[876]: info: CorePlayQueue::getTrack 0 Jan 05 08:35:46 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 05 08:35:46 volumio-pi400 volumio[876]: info: CoreCommandRouter::volumioPushState Jan 05 08:35:46 volumio-pi400 volumio[876]: info: CoreStateMachine::updateTrackBlock Jan 05 08:35:46 volumio-pi400 volumio[876]: info: CorePlayQueue::getTrackBlock Jan 05 08:35:46 volumio-pi400 volumio[876]: info: CoreCommandRouter::volumioRetrievevolume Jan 05 08:35:46 volumio-pi400 volumio[876]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jan 05 08:35:47 volumio-pi400 volumio[876]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jan 05 08:35:47 volumio-pi400 volumio[876]: info: MPD running with PID940 Jan 05 08:35:47 volumio-pi400 volumio[876]: ,establishing connection Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:47 volumio-pi400 volumio[876]: info: Reloading queue from file Jan 05 08:35:47 volumio-pi400 volumio[876]: error: updateQueue error: null Jan 05 08:35:47 volumio-pi400 volumio[876]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 05 08:35:47 volumio-pi400 volumio[876]: info: VolumeController:: Volume=undefined Mute =false Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreStateMachine::pushState Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CorePlayQueue::getTrack 0 Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::volumioPushState Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreStateMachine::updateTrackBlock Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CorePlayQueue::getTrackBlock Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::volumioRetrievevolume Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreStateMachine::setRepeat true single undefined Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreStateMachine::pushState Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CorePlayQueue::getTrack 0 Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::volumioPushState Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreStateMachine::setRandom null Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreStateMachine::pushState Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CorePlayQueue::getTrack 0 Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::volumioPushState Jan 05 08:35:47 volumio-pi400 volumio[876]: info: Setting Device type: Raspberry PI Jan 05 08:35:47 volumio-pi400 volumio[876]: info: VolumeController:: Volume=undefined Mute =false Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreStateMachine::pushState Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CorePlayQueue::getTrack 0 Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::volumioPushState Jan 05 08:35:47 volumio-pi400 volumio[876]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jan 05 08:35:47 volumio-pi400 volumio[876]: info: VolumeController:: Volume=undefined Mute =false Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreStateMachine::pushState Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CorePlayQueue::getTrack 0 Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::volumioPushState Jan 05 08:35:47 volumio-pi400 volumio[876]: info: Discovery: adding a2debaa3-0a64-4ef0-a89a-e4255a12bf5b Jan 05 08:35:47 volumio-pi400 volumio[876]: info: Discovery: Found device Volumio-Pi400 Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::volumioGetState Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CorePlayQueue::getTrack 0 Jan 05 08:35:47 volumio-pi400 volumio[876]: Starting albumart workers Jan 05 08:35:47 volumio-pi400 volumio[876]: Starting albumart workers Jan 05 08:35:47 volumio-pi400 volumio[876]: Starting albumart workers Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 05 08:35:47 volumio-pi400 volumio[876]: info: Discovery: Getting this device information Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::volumioGetState Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CorePlayQueue::getTrack 0 Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 05 08:35:47 volumio-pi400 volumio[876]: verbose: New Socket.io Connection to 192.168.1.164:3000 from 192.168.1.169 UA: Dart/3.5 (dart:io) Total Clients: 4 Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CoreCommandRouter::volumioGetState Jan 05 08:35:47 volumio-pi400 volumio[876]: info: CorePlayQueue::getTrack 0 Jan 05 08:35:48 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 05 08:35:48 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 05 08:35:49 volumio-pi400 volumio[876]: error: updateQueue error: null Jan 05 08:35:50 volumio-pi400 sudo[943]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:50 volumio-pi400 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 05 08:35:50 volumio-pi400 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 05 08:35:52 volumio-pi400 sudo[1036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 05 08:35:52 volumio-pi400 sudo[1036]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:52 volumio-pi400 sudo[1036]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:52 volumio-pi400 sudo[1039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 05 08:35:52 volumio-pi400 sudo[1039]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:52 volumio-pi400 sudo[1039]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:55 volumio-pi400 volumio[876]: info: Cannot mount NAS FLACS at system boot, trial number 1 ,retrying in 5 seconds Jan 05 08:35:55 volumio-pi400 volumio[876]: info: Completed loading Core Plugins Jan 05 08:35:55 volumio-pi400 volumio[876]: info: Preparing to generate the ALSA configuration file Jan 05 08:36:06 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 05 08:36:06 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 05 08:36:06 volumio-pi400 volumio[876]: info: Discovery: Getting this device information Jan 05 08:36:06 volumio-pi400 volumio[876]: info: CoreCommandRouter::volumioGetState Jan 05 08:36:06 volumio-pi400 volumio[876]: info: CorePlayQueue::getTrack 0 Jan 05 08:36:06 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 05 08:36:06 volumio-pi400 sudo[1042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=phillip,password=webster67,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.78/flacs /mnt/NAS/FLACS Jan 05 08:36:06 volumio-pi400 sudo[1042]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:36:06 volumio-pi400 kernel: CIFS: Attempting to mount //192.168.1.78/flacs Jan 05 08:36:07 volumio-pi400 volumio[876]: info: Asound.conf file unchanged, so no further update is needed Jan 05 08:36:07 volumio-pi400 volumio[876]: info: Output device has changed, restarting MPD Jan 05 08:36:07 volumio-pi400 sudo[1047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 05 08:36:07 volumio-pi400 sudo[1047]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:36:07 volumio-pi400 sudo[1047]: pam_unix(sudo:session): session closed for user root Jan 05 08:36:08 volumio-pi400 volumio[876]: info: Output device has changed, restarting Shairport Sync Jan 05 08:36:08 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:36:08 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 05 08:36:08 volumio-pi400 sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 05 08:36:08 volumio-pi400 sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:36:08 volumio-pi400 systemd[1]: Stopping Music Player Daemon... Jan 05 08:36:08 volumio-pi400 volumio[876]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 05 08:36:08 volumio-pi400 volumio[876]: info: ___________ START PLUGINS ___________ Jan 05 08:36:08 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 05 08:36:08 volumio-pi400 volumio[876]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 05 08:36:08 volumio-pi400 volumio[876]: info: [1736066168205] CoreMusicLibrary::Adding element Media Servers Jan 05 08:36:08 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 05 08:36:08 volumio-pi400 volumio[876]: Cannot find translation for sourceMedia Servers Jan 05 08:36:08 volumio-pi400 systemd[1]: mpd.service: Succeeded. Jan 05 08:36:08 volumio-pi400 systemd[1]: Stopped Music Player Daemon. Jan 05 08:36:08 volumio-pi400 systemd[1]: Starting Music Player Daemon... Jan 05 08:36:08 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:36:08 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 05 08:36:08 volumio-pi400 volumio[876]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 05 08:36:08 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:36:08 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 05 08:36:08 volumio-pi400 volumio[876]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 05 08:36:08 volumio-pi400 volumio[876]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 05 08:36:08 volumio-pi400 volumio[876]: info: [1736066168547] CoreMusicLibrary::Adding element Last_100 Jan 05 08:36:08 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 05 08:36:08 volumio-pi400 volumio[876]: Cannot find translation for sourceMedia Servers Jan 05 08:36:08 volumio-pi400 volumio[876]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 05 08:36:08 volumio-pi400 volumio[876]: info: [1736066168554] CoreMusicLibrary::Adding element Webradio Jan 05 08:36:08 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 05 08:36:08 volumio-pi400 volumio[876]: Cannot find translation for sourceMedia Servers Jan 05 08:36:08 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 05 08:36:08 volumio-pi400 volumio[876]: info: Volumio Calling Home Jan 05 08:36:08 volumio-pi400 volumio[876]: info: Completed starting Core Plugins Jan 05 08:36:08 volumio-pi400 volumio[876]: info: ------------------------------------------- Jan 05 08:36:08 volumio-pi400 volumio[876]: info: ----- MyVolumio plugins startup ---- Jan 05 08:36:08 volumio-pi400 volumio[876]: info: ------------------------------------------- Jan 05 08:36:08 volumio-pi400 volumio[876]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 05 08:36:08 volumio-pi400 volumio[876]: info: CoreCommandRouter::volumioGetState Jan 05 08:36:08 volumio-pi400 volumio[876]: info: CorePlayQueue::getTrack 0 Jan 05 08:36:09 volumio-pi400 volumio[876]: info: MPD Permissions set Jan 05 08:36:09 volumio-pi400 volumio[876]: info: Volumio called home Jan 05 08:36:09 volumio-pi400 sudo[1068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 05 08:36:09 volumio-pi400 sudo[1068]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:36:09 volumio-pi400 systemd[1]: Started UPnP Renderer front-end to MPD. Jan 05 08:36:09 volumio-pi400 volumio[876]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Jan 05 08:36:09 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:36:09 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:36:09 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:36:09 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:36:09 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:36:09 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:36:09 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:36:09 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:36:09 volumio-pi400 sudo[1068]: pam_unix(sudo:session): session closed for user root Jan 05 08:36:09 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 05 08:36:09 volumio-pi400 volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:36:09 volumio-pi400 mpd[1063]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 05 08:36:09 volumio-pi400 mpd[1063]: output: No 'audio_output' defined in config file Jan 05 08:36:09 volumio-pi400 mpd[1063]: output: Attempt to detect audio output device Jan 05 08:36:09 volumio-pi400 mpd[1063]: output: Attempting to detect a alsa audio device Jan 05 08:36:09 volumio-pi400 mpd[1063]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Jan 05 08:36:09 volumio-pi400 mpd[1063]: alsa_output: Error opening default ALSA device: No such device Jan 05 08:36:09 volumio-pi400 mpd[1063]: output: Attempting to detect a oss audio device Jan 05 08:36:09 volumio-pi400 mpd[1063]: oss_output: Error opening OSS device "/dev/dsp": No such file or directory Jan 05 08:36:09 volumio-pi400 mpd[1063]: oss_output: Error opening OSS device "/dev/sound/dsp": No such file or directory Jan 05 08:36:09 volumio-pi400 mpd[1063]: output: Attempting to detect a pulse audio device Jan 05 08:36:09 volumio-pi400 mpd[1063]: output: Attempting to detect a jack audio device Jan 05 08:36:09 volumio-pi400 mpd[1063]: output: Successfully detected a jack audio device Jan 05 08:36:09 volumio-pi400 mpd[1063]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 05 08:36:09 volumio-pi400 mpd[1063]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 05 08:36:09 volumio-pi400 mpd[1063]: zeroconf: No global port, disabling zeroconf Jan 05 08:36:09 volumio-pi400 systemd[1]: Started Music Player Daemon. Jan 05 08:36:09 volumio-pi400 sudo[1050]: pam_unix(sudo:session): session closed for user root Jan 05 08:36:09 volumio-pi400 mpd[1063]: client: [0] opened from 127.0.0.1:58532 Jan 05 08:36:09 volumio-pi400 mpd[1063]: client: [1] opened from local Jan 05 08:36:10 volumio-pi400 volumio[876]: info: Upmpdcli Daemon Started Jan 05 08:36:10 volumio-pi400 volumio[876]: info: Starting Shairport Sync Jan 05 08:36:10 volumio-pi400 volumio[876]: info: Starting Shairport Sync Jan 05 08:36:10 volumio-pi400 sudo[1079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 05 08:36:10 volumio-pi400 sudo[1079]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:36:10 volumio-pi400 volumio[876]: info: Starting Shairport Sync Jan 05 08:36:10 volumio-pi400 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 05 08:36:10 volumio-pi400 systemd[1]: shairport-sync.service: Succeeded. Jan 05 08:36:10 volumio-pi400 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 05 08:36:10 volumio-pi400 sudo[1082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 05 08:36:10 volumio-pi400 sudo[1082]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:36:10 volumio-pi400 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 05 08:36:10 volumio-pi400 sudo[1079]: pam_unix(sudo:session): session closed for user root Jan 05 08:36:10 volumio-pi400 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 05 08:36:10 volumio-pi400 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 05 08:36:10 volumio-pi400 systemd[1]: shairport-sync.service: Succeeded. Jan 05 08:36:10 volumio-pi400 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 05 08:36:10 volumio-pi400 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 05 08:36:10 volumio-pi400 sudo[1087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 05 08:36:10 volumio-pi400 sudo[1087]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:36:10 volumio-pi400 volumio[876]: error: updateQueue error: null Jan 05 08:36:10 volumio-pi400 sudo[1082]: pam_unix(sudo:session): session closed for user root Jan 05 08:36:10 volumio-pi400 mpd[1063]: client: [2] opened from 127.0.0.1:58538 Jan 05 08:36:10 volumio-pi400 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 05 08:36:10 volumio-pi400 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 05 08:36:10 volumio-pi400 systemd[1]: shairport-sync.service: Succeeded. Jan 05 08:36:10 volumio-pi400 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 05 08:36:10 volumio-pi400 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 05 08:36:10 volumio-pi400 volumio[876]: info: Shairport-Sync Started Jan 05 08:36:10 volumio-pi400 sudo[1087]: pam_unix(sudo:session): session closed for user root Jan 05 08:36:10 volumio-pi400 volumio[876]: Error adding Membership: Error: addMembership EINVAL Jan 05 08:36:10 volumio-pi400 volumio[876]: info: Shairport-Sync Started Jan 05 08:36:10 volumio-pi400 volumio[876]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 05 08:36:10 volumio-pi400 volumio[876]: Error: certificate is not yet valid Jan 05 08:36:10 volumio-pi400 volumio[876]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Jan 05 08:36:10 volumio-pi400 volumio[876]: at TLSSocket.emit (events.js:315:20) Jan 05 08:36:10 volumio-pi400 volumio[876]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Jan 05 08:36:10 volumio-pi400 volumio[876]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Jan 05 08:36:10 volumio-pi400 volumio[876]: code: 'CERT_NOT_YET_VALID' Jan 05 08:36:10 volumio-pi400 volumio[876]: } Jan 05 08:36:10 volumio-pi400 volumio[876]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 05 08:36:11 volumio-pi400 sudo[1102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-05 08:35 Jan 05 08:36:11 volumio-pi400 sudo[1102]: 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="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="85acf7bbbceb3e3dec447156a39a88033f921210" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 30 Sep 2022 10:43:40 AM CEST" VOLUMIO_VERSION="3.378" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4bc817297375b72097a1fade15c6518b"