-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Sun 2024-04-28 15:14:42 GMT. -- Apr 28 15:13:39 warpandwoof kernel: Setting default values for core params Apr 28 15:13:39 warpandwoof kernel: Finished setting default values for core params Apr 28 15:13:39 warpandwoof kernel: Using Buffer DMA mode Apr 28 15:13:39 warpandwoof kernel: Periodic Transfer Interrupt Enhancement - disabled Apr 28 15:13:39 warpandwoof kernel: Multiprocessor Interrupt Enhancement - disabled Apr 28 15:13:39 warpandwoof kernel: OTG VER PARAM: 0, OTG VER FLAG: 0 Apr 28 15:13:39 warpandwoof kernel: Dedicated Tx FIFOs mode Apr 28 15:13:39 warpandwoof kernel: Apr 28 15:13:39 warpandwoof kernel: WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = b4104000 dma = 0xf4104000 len=9024 Apr 28 15:13:39 warpandwoof kernel: FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled Apr 28 15:13:39 warpandwoof kernel: dwc_otg: Microframe scheduler enabled Apr 28 15:13:39 warpandwoof kernel: Apr 28 15:13:39 warpandwoof kernel: WARN::hcd_init_fiq:457: FIQ on core 1 Apr 28 15:13:39 warpandwoof kernel: Apr 28 15:13:39 warpandwoof kernel: WARN::hcd_init_fiq:458: FIQ ASM at 807cb80c length 36 Apr 28 15:13:39 warpandwoof kernel: Apr 28 15:13:39 warpandwoof kernel: WARN::hcd_init_fiq:497: MPHI regs_base at b8810000 Apr 28 15:13:39 warpandwoof kernel: dwc_otg 3f980000.usb: DWC OTG Controller Apr 28 15:13:39 warpandwoof kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 Apr 28 15:13:39 warpandwoof kernel: dwc_otg 3f980000.usb: irq 89, io mem 0x00000000 Apr 28 15:13:39 warpandwoof kernel: Init: Port Power? op_state=1 Apr 28 15:13:39 warpandwoof kernel: Init: Power Port (0) Apr 28 15:13:39 warpandwoof kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01 Apr 28 15:13:39 warpandwoof kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Apr 28 15:13:39 warpandwoof kernel: usb usb1: Product: DWC OTG Controller Apr 28 15:13:39 warpandwoof kernel: usb usb1: Manufacturer: Linux 6.1.69-v7+ dwc_otg_hcd Apr 28 15:13:39 warpandwoof kernel: usb usb1: SerialNumber: 3f980000.usb Apr 28 15:13:39 warpandwoof kernel: hub 1-0:1.0: USB hub found Apr 28 15:13:39 warpandwoof kernel: hub 1-0:1.0: 1 port detected Apr 28 15:13:39 warpandwoof kernel: dwc_otg: FIQ enabled Apr 28 15:13:39 warpandwoof kernel: dwc_otg: NAK holdoff enabled Apr 28 15:13:39 warpandwoof kernel: dwc_otg: FIQ split-transaction FSM enabled Apr 28 15:13:39 warpandwoof kernel: Module dwc_common_port init Apr 28 15:13:39 warpandwoof kernel: usbcore: registered new interface driver usb-storage Apr 28 15:13:39 warpandwoof kernel: mousedev: PS/2 mouse device common for all mice Apr 28 15:13:39 warpandwoof kernel: sdhci: Secure Digital Host Controller Interface driver Apr 28 15:13:39 warpandwoof kernel: sdhci: Copyright(c) Pierre Ossman Apr 28 15:13:39 warpandwoof kernel: sdhci-pltfm: SDHCI platform and OF driver helper Apr 28 15:13:39 warpandwoof kernel: ledtrig-cpu: registered to indicate activity on CPUs Apr 28 15:13:39 warpandwoof kernel: hid: raw HID events driver (C) Jiri Kosina Apr 28 15:13:39 warpandwoof kernel: usbcore: registered new interface driver usbhid Apr 28 15:13:39 warpandwoof kernel: usbhid: USB HID core driver Apr 28 15:13:39 warpandwoof kernel: bcm2835_vchiq 3f00b840.mailbox: there is not valid maps for state default Apr 28 15:13:39 warpandwoof kernel: Initializing XFRM netlink socket Apr 28 15:13:39 warpandwoof kernel: NET: Registered PF_PACKET protocol family Apr 28 15:13:39 warpandwoof kernel: Key type dns_resolver registered Apr 28 15:13:39 warpandwoof kernel: Registering SWP/SWPB emulation handler Apr 28 15:13:39 warpandwoof kernel: registered taskstats version 1 Apr 28 15:13:39 warpandwoof kernel: Loading compiled-in X.509 certificates Apr 28 15:13:39 warpandwoof kernel: Key type .fscrypt registered Apr 28 15:13:39 warpandwoof kernel: Key type fscrypt-provisioning registered Apr 28 15:13:39 warpandwoof kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled Apr 28 15:13:39 warpandwoof kernel: 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 114, base_baud = 0) is a PL011 rev2 Apr 28 15:13:39 warpandwoof kernel: printk: console [ttyAMA0] enabled Apr 28 15:13:39 warpandwoof kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Apr 28 15:13:39 warpandwoof kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Apr 28 15:13:39 warpandwoof kernel: sdhost: log_buf @ f9d60a0c (f4107000) Apr 28 15:13:39 warpandwoof kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Apr 28 15:13:39 warpandwoof kernel: of_cfs_init Apr 28 15:13:39 warpandwoof kernel: of_cfs_init: OK Apr 28 15:13:39 warpandwoof kernel: Freeing unused kernel image (initmem) memory: 1024K Apr 28 15:13:39 warpandwoof kernel: Indeed it is in host mode hprt0 = 00021501 Apr 28 15:13:39 warpandwoof kernel: Run /init as init process Apr 28 15:13:39 warpandwoof kernel: with arguments: Apr 28 15:13:39 warpandwoof kernel: /init Apr 28 15:13:39 warpandwoof kernel: splash Apr 28 15:13:39 warpandwoof kernel: with environment: Apr 28 15:13:39 warpandwoof kernel: HOME=/ Apr 28 15:13:39 warpandwoof kernel: TERM=linux Apr 28 15:13:39 warpandwoof kernel: imgpart=/dev/mmcblk0p2 Apr 28 15:13:39 warpandwoof kernel: imgfile=/volumio_current.sqsh Apr 28 15:13:39 warpandwoof kernel: bootdelay=5 Apr 28 15:13:39 warpandwoof kernel: mmc0: new high speed MMC card at address 0001 Apr 28 15:13:39 warpandwoof kernel: mmcblk0: mmc0:0001 Biwin 28.8 GiB Apr 28 15:13:39 warpandwoof kernel: mmcblk0: p1 p2 p3 Apr 28 15:13:39 warpandwoof kernel: mmcblk0: mmc0:0001 Biwin 28.8 GiB Apr 28 15:13:39 warpandwoof kernel: mmcblk0boot0: mmc0:0001 Biwin 4.00 MiB Apr 28 15:13:39 warpandwoof kernel: mmcblk0boot1: mmc0:0001 Biwin 4.00 MiB Apr 28 15:13:39 warpandwoof kernel: mmcblk0rpmb: mmc0:0001 Biwin 4.00 MiB, chardev (245:0) Apr 28 15:13:39 warpandwoof kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg Apr 28 15:13:39 warpandwoof kernel: Indeed it is in host mode hprt0 = 00001101 Apr 28 15:13:39 warpandwoof initramfs: Booting Volumio for BCM2835 Apr 28 15:13:39 warpandwoof initramfs: This script mounts rootfs RO with an overlay RW layer. Apr 28 15:13:39 warpandwoof kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Apr 28 15:13:39 warpandwoof initramfs: IMGPART=/dev/mmcblk0p2 Apr 28 15:13:39 warpandwoof initramfs: IMGFILE=/volumio_current.sqsh Apr 28 15:13:39 warpandwoof initramfs: Boot delay (except first time) will be 5 seconds Apr 28 15:13:39 warpandwoof initramfs: /dev/mmcblk0p2: Apr 28 15:13:39 warpandwoof kernel: usb 1-1: New USB device found, idVendor=0451, idProduct=8142, bcdDevice= 1.00 Apr 28 15:13:39 warpandwoof kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=1 Apr 28 15:13:39 warpandwoof kernel: usb 1-1: SerialNumber: A80008612C4B Apr 28 15:13:39 warpandwoof kernel: hub 1-1:1.0: USB hub found Apr 28 15:13:39 warpandwoof kernel: hub 1-1:1.0: 4 ports detected Apr 28 15:13:39 warpandwoof kernel: EXT4-fs (mmcblk0p2): recovery complete Apr 28 15:13:39 warpandwoof kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none. Apr 28 15:13:39 warpandwoof initramfs: Doing a 5 second delay here to give kernel load a headstart Apr 28 15:13:39 warpandwoof kernel: usb 1-1.2: new high-speed USB device number 3 using dwc_otg Apr 28 15:13:39 warpandwoof kernel: usb 1-1.2: New USB device found, idVendor=152a, idProduct=8809, bcdDevice= 1.03 Apr 28 15:13:39 warpandwoof kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Apr 28 15:13:39 warpandwoof kernel: usb 1-1.2: Product: Revolution Apr 28 15:13:39 warpandwoof kernel: usb 1-1.2: Manufacturer: Allo.com Apr 28 15:13:39 warpandwoof kernel: usb 1-1.3: new high-speed USB device number 4 using dwc_otg Apr 28 15:13:39 warpandwoof kernel: usb 1-1.3: New USB device found, idVendor=0b95, idProduct=1790, bcdDevice= 1.00 Apr 28 15:13:39 warpandwoof kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Apr 28 15:13:39 warpandwoof kernel: usb 1-1.3: Product: AX88179 Apr 28 15:13:39 warpandwoof kernel: usb 1-1.3: Manufacturer: ASIX Elec. Corp. Apr 28 15:13:39 warpandwoof kernel: usb 1-1.3: SerialNumber: 00000000000028 Apr 28 15:13:39 warpandwoof initramfs: Checking for USB updates Apr 28 15:13:39 warpandwoof initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Apr 28 15:13:39 warpandwoof kernel: loop0: detected capacity change from 0 to 967536 Apr 28 15:13:39 warpandwoof kernel: EXT4-fs (mmcblk0p3): recovery complete Apr 28 15:13:39 warpandwoof kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Quota mode: none. Apr 28 15:13:39 warpandwoof initramfs: With Option: Apr 28 15:13:39 warpandwoof initramfs: VOLUMIO_VERSION="3.631" Apr 28 15:13:39 warpandwoof initramfs: Finish initramfs, continue booting Volumio Apr 28 15:13:39 warpandwoof systemd[1]: System time before build time, advancing clock. Apr 28 15:13:39 warpandwoof kernel: NET: Registered PF_INET6 protocol family Apr 28 15:13:39 warpandwoof kernel: Segment Routing with IPv6 Apr 28 15:13:39 warpandwoof kernel: In-situ OAM (IOAM) with IPv6 Apr 28 15:13:39 warpandwoof 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) Apr 28 15:13:39 warpandwoof systemd[1]: Detected architecture arm. Apr 28 15:13:39 warpandwoof systemd[1]: Set hostname to . Apr 28 15:13:39 warpandwoof kernel: uart-pl011 3f201000.serial: no DMA platform data Apr 28 15:13:39 warpandwoof 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. Apr 28 15:13:39 warpandwoof systemd[1]: Started Forward Password Requests to Wall Directory Watch. Apr 28 15:13:39 warpandwoof systemd[1]: Listening on Journal Audit Socket. Apr 28 15:13:39 warpandwoof systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Apr 28 15:13:39 warpandwoof systemd[1]: Created slice system-serial\x2dgetty.slice. Apr 28 15:13:39 warpandwoof systemd[1]: Listening on initctl Compatibility Named Pipe. Apr 28 15:13:39 warpandwoof systemd[1]: Created slice system-getty.slice. Apr 28 15:13:39 warpandwoof kernel: i2c_dev: i2c /dev entries driver Apr 28 15:13:39 warpandwoof systemd-journald[194]: Journal started Apr 28 15:13:39 warpandwoof systemd-journald[194]: Runtime journal (/run/log/journal/6c9b1d414d94da048f39d3e06336abc2) is 11.2M, max 30.0M, 18.7M free. Apr 28 15:13:39 warpandwoof systemd-modules-load[199]: Inserted module 'i2c_dev' Apr 28 15:13:39 warpandwoof fake-hwclock[188]: Sun Apr 28 15:13:39 UTC 2024 Apr 28 15:13:39 warpandwoof systemd[1]: Started Load Kernel Modules. Apr 28 15:13:39 warpandwoof systemd[1]: Mounting Kernel Configuration File System... Apr 28 15:13:39 warpandwoof systemd[1]: Condition check resulted in FUSE Control File System being skipped. Apr 28 15:13:39 warpandwoof systemd[1]: Starting Apply Kernel Variables... Apr 28 15:13:39 warpandwoof systemd[1]: dynamicswap.service: Succeeded. Apr 28 15:13:39 warpandwoof systemd[1]: Mounted Kernel Configuration File System. Apr 28 15:13:39 warpandwoof systemd[1]: Started Load/Save Random Seed. Apr 28 15:13:39 warpandwoof systemd[1]: Started Apply Kernel Variables. Apr 28 15:13:39 warpandwoof systemd[1]: Started Create System Users. Apr 28 15:13:39 warpandwoof systemd[1]: Starting Create Static Device Nodes in /dev... Apr 28 15:13:39 warpandwoof systemd[1]: Started udev Coldplug all Devices. Apr 28 15:13:39 warpandwoof systemd[1]: Starting Helper to synchronize boot up for ifupdown... Apr 28 15:13:39 warpandwoof systemd[1]: Started Helper to synchronize boot up for ifupdown. Apr 28 15:13:39 warpandwoof systemd[1]: Started Create Static Device Nodes in /dev. Apr 28 15:13:39 warpandwoof systemd[1]: Starting udev Kernel Device Manager... Apr 28 15:13:39 warpandwoof systemd[1]: Reached target Local File Systems (Pre). Apr 28 15:13:39 warpandwoof systemd[1]: Mounting /var/spool/cups... Apr 28 15:13:39 warpandwoof systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Apr 28 15:13:39 warpandwoof systemd[1]: Mounting /var/log... Apr 28 15:13:39 warpandwoof systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Apr 28 15:13:39 warpandwoof systemd[1]: Mounting /tmp... Apr 28 15:13:39 warpandwoof systemd[1]: Mounted /var/spool/cups. Apr 28 15:13:39 warpandwoof systemd[1]: Mounted /var/log. Apr 28 15:13:39 warpandwoof systemd[1]: Mounted /tmp. Apr 28 15:13:39 warpandwoof systemd[1]: Starting Flush Journal to Persistent Storage... Apr 28 15:13:39 warpandwoof systemd[1]: Mounting /var/spool/cups/tmp... Apr 28 15:13:39 warpandwoof systemd-udevd[226]: Network interface NamePolicy= disabled on kernel command line, ignoring. Apr 28 15:13:39 warpandwoof systemd[1]: Mounted /var/spool/cups/tmp. Apr 28 15:13:39 warpandwoof systemd-journald[194]: Runtime journal (/run/log/journal/6c9b1d414d94da048f39d3e06336abc2) is 11.2M, max 30.0M, 18.7M free. Apr 28 15:13:39 warpandwoof systemd[1]: Started udev Kernel Device Manager. Apr 28 15:13:39 warpandwoof systemd[1]: Started Flush Journal to Persistent Storage. Apr 28 15:13:39 warpandwoof systemd[1]: Starting Show Plymouth Boot Screen... Apr 28 15:13:39 warpandwoof systemd[1]: Received SIGRTMIN+20 from PID 243 (plymouthd). Apr 28 15:13:39 warpandwoof systemd[1]: Started Show Plymouth Boot Screen. Apr 28 15:13:39 warpandwoof systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Apr 28 15:13:39 warpandwoof systemd[1]: Reached target Local Encrypted Volumes. Apr 28 15:13:39 warpandwoof systemd[1]: Reached target Paths. Apr 28 15:13:39 warpandwoof systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Apr 28 15:13:39 warpandwoof kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 Apr 28 15:13:39 warpandwoof kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem Apr 28 15:13:39 warpandwoof kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Apr 28 15:13:39 warpandwoof kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Apr 28 15:13:39 warpandwoof kernel: [vc_sm_connected_init]: start Apr 28 15:13:39 warpandwoof kernel: [vc_sm_connected_init]: installed successfully Apr 28 15:13:40 warpandwoof kernel: mc: Linux media interface: v0.10 Apr 28 15:13:40 warpandwoof systemd[1]: Found device /dev/ttyAMA0. Apr 28 15:13:40 warpandwoof kernel: videodev: Linux video capture interface: v2.00 Apr 28 15:13:40 warpandwoof kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Apr 28 15:13:40 warpandwoof kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Apr 28 15:13:40 warpandwoof kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Apr 28 15:13:40 warpandwoof kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Apr 28 15:13:40 warpandwoof kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Apr 28 15:13:40 warpandwoof kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Apr 28 15:13:40 warpandwoof kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Apr 28 15:13:40 warpandwoof kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Apr 28 15:13:40 warpandwoof kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Apr 28 15:13:40 warpandwoof kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Apr 28 15:13:40 warpandwoof kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Apr 28 15:13:40 warpandwoof kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Apr 28 15:13:40 warpandwoof kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Apr 28 15:13:40 warpandwoof kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Apr 28 15:13:40 warpandwoof kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Apr 28 15:13:40 warpandwoof kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Apr 28 15:13:40 warpandwoof kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Apr 28 15:13:40 warpandwoof kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Apr 28 15:13:40 warpandwoof kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Apr 28 15:13:40 warpandwoof kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Apr 28 15:13:40 warpandwoof kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Apr 28 15:13:40 warpandwoof kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Apr 28 15:13:40 warpandwoof kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Apr 28 15:13:40 warpandwoof kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Apr 28 15:13:40 warpandwoof kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Apr 28 15:13:40 warpandwoof kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Apr 28 15:13:40 warpandwoof kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Apr 28 15:13:40 warpandwoof kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Apr 28 15:13:40 warpandwoof kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Apr 28 15:13:40 warpandwoof kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Apr 28 15:13:40 warpandwoof kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Apr 28 15:13:40 warpandwoof kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Apr 28 15:13:40 warpandwoof kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Apr 28 15:13:40 warpandwoof kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Apr 28 15:13:40 warpandwoof kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Apr 28 15:13:40 warpandwoof kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Apr 28 15:13:40 warpandwoof kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Apr 28 15:13:40 warpandwoof systemd-udevd[236]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 28 15:13:41 warpandwoof kernel: usb 1-1.2: 1:3 : unsupported format bits 0x100000000 Apr 28 15:13:41 warpandwoof kernel: usbcore: registered new interface driver snd-usb-audio Apr 28 15:13:41 warpandwoof kernel: ax88179_178a 1-1.3:1.0 eth0: register 'ax88179_178a' at usb-3f980000.usb-1.3, ASIX AX88179 USB 3.0 Gigabit Ethernet, 00:17:f7:00:a8:a6 Apr 28 15:13:41 warpandwoof kernel: usbcore: registered new interface driver ax88179_178a Apr 28 15:13:41 warpandwoof systemd-udevd[232]: Using default interface naming scheme 'v240'. Apr 28 15:13:41 warpandwoof systemd-udevd[232]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 28 15:13:42 warpandwoof systemd[1]: Found device /dev/mmcblk0p1. Apr 28 15:13:42 warpandwoof systemd-udevd[239]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 1' failed with exit code 17. Apr 28 15:13:42 warpandwoof systemd-udevd[237]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Apr 28 15:13:42 warpandwoof systemd-udevd[235]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Apr 28 15:13:43 warpandwoof systemd[1]: Condition check resulted in AX88179 Gigabit Ethernet being skipped. Apr 28 15:13:43 warpandwoof systemd[1]: Condition check resulted in Huge Pages File System being skipped. Apr 28 15:13:43 warpandwoof systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Apr 28 15:13:43 warpandwoof systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Apr 28 15:13:43 warpandwoof systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Apr 28 15:13:43 warpandwoof systemd[1]: Condition check resulted in FUSE Control File System being skipped. Apr 28 15:13:43 warpandwoof systemd[1]: Starting File System Check on /dev/mmcblk0p1... Apr 28 15:13:43 warpandwoof systemd[1]: Started File System Check Daemon to report status. Apr 28 15:13:43 warpandwoof systemd-fsck[368]: fsck.fat 4.1 (2017-01-24) Apr 28 15:13:43 warpandwoof systemd-fsck[368]: /dev/mmcblk0p1: 470 files, 33810/46774 clusters Apr 28 15:13:43 warpandwoof systemd[1]: Started File System Check on /dev/mmcblk0p1. Apr 28 15:13:43 warpandwoof systemd[1]: Mounting /boot... Apr 28 15:13:43 warpandwoof systemd[1]: Mounted /boot. Apr 28 15:13:43 warpandwoof systemd[1]: Reached target Local File Systems. Apr 28 15:13:43 warpandwoof systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Apr 28 15:13:43 warpandwoof systemd[1]: Starting Create Volatile Files and Directories... Apr 28 15:13:43 warpandwoof systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Apr 28 15:13:43 warpandwoof systemd[1]: Started ifup for eth0. Apr 28 15:13:43 warpandwoof systemd[1]: Starting Raise network interfaces... Apr 28 15:13:43 warpandwoof systemd[1]: Starting Preprocess NFS configuration... Apr 28 15:13:43 warpandwoof systemd[1]: Received SIGRTMIN+20 from PID 243 (plymouthd). Apr 28 15:13:43 warpandwoof systemd[1]: plymouth-read-write.service: Succeeded. Apr 28 15:13:43 warpandwoof systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Apr 28 15:13:43 warpandwoof systemd[1]: nfs-config.service: Succeeded. Apr 28 15:13:43 warpandwoof systemd[1]: Started Preprocess NFS configuration. Apr 28 15:13:43 warpandwoof systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Apr 28 15:13:43 warpandwoof systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Apr 28 15:13:43 warpandwoof systemd[1]: Reached target NFS client services. Apr 28 15:13:43 warpandwoof systemd[1]: Started Create Volatile Files and Directories. Apr 28 15:13:43 warpandwoof systemd[1]: Starting RPC bind portmap service... Apr 28 15:13:43 warpandwoof systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Apr 28 15:13:43 warpandwoof systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Apr 28 15:13:43 warpandwoof systemd[1]: Starting Update UTMP about System Boot/Shutdown... Apr 28 15:13:43 warpandwoof systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Apr 28 15:13:43 warpandwoof systemd[1]: Started RPC bind portmap service. Apr 28 15:13:43 warpandwoof kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Apr 28 15:13:43 warpandwoof systemd[1]: Started Update UTMP about System Boot/Shutdown. Apr 28 15:13:43 warpandwoof systemd[1]: Reached target System Initialization. Apr 28 15:13:43 warpandwoof systemd[1]: Started Daily apt download activities. Apr 28 15:13:43 warpandwoof systemd[1]: Started Daily Cleanup of Temporary Directories. Apr 28 15:13:43 warpandwoof systemd[1]: Listening on D-Bus System Message Bus Socket. Apr 28 15:13:43 warpandwoof systemd[1]: Listening on triggerhappy.socket. Apr 28 15:13:43 warpandwoof systemd[1]: Started Daily apt upgrade and clean activities. Apr 28 15:13:43 warpandwoof systemd[1]: Reached target Timers. Apr 28 15:13:43 warpandwoof systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Apr 28 15:13:43 warpandwoof systemd[1]: Reached target Sockets. Apr 28 15:13:44 warpandwoof systemd[1]: Started Volumio Splash. Apr 28 15:13:44 warpandwoof systemd[1]: Reached target Basic System. Apr 28 15:13:44 warpandwoof systemd[1]: Starting triggerhappy global hotkey daemon... Apr 28 15:13:44 warpandwoof systemd[402]: volumiosplash.service: Failed to execute command: No such file or directory Apr 28 15:13:44 warpandwoof systemd[402]: volumiosplash.service: Failed at step EXEC spawning /bin/volumiosplash.sh: No such file or directory Apr 28 15:13:44 warpandwoof systemd[1]: Starting Login Service... Apr 28 15:13:44 warpandwoof systemd[1]: Started Volumio Log Rotation Service. Apr 28 15:13:44 warpandwoof kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Apr 28 15:13:44 warpandwoof kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Apr 28 15:13:44 warpandwoof kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Apr 28 15:13:44 warpandwoof systemd[1]: Starting Wireless Services... Apr 28 15:13:44 warpandwoof systemd[1]: Started D-Bus System Message Bus. Apr 28 15:13:44 warpandwoof sh[375]: command failed: No such device (-19) Apr 28 15:13:44 warpandwoof thd[409]: Unable to parse trigger line: Apr 28 15:13:44 warpandwoof thd[409]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Apr 28 15:13:44 warpandwoof thd[409]: Unable to parse trigger line: Apr 28 15:13:44 warpandwoof thd[409]: Found socket passed from systemd Apr 28 15:13:44 warpandwoof systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Apr 28 15:13:44 warpandwoof ifup[376]: command failed: No such device (-19) Apr 28 15:13:44 warpandwoof systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Apr 28 15:13:44 warpandwoof systemd[1]: Condition check resulted in Configure Bluetooth Modems connected by UART being skipped. Apr 28 15:13:44 warpandwoof systemd[1]: Started Manage Sound Card State (restore and store). Apr 28 15:13:44 warpandwoof systemd[1]: Starting Save/Restore Sound Card State... Apr 28 15:13:44 warpandwoof alsactl[426]: alsactl 1.1.8 daemon started Apr 28 15:13:44 warpandwoof systemd[1]: Started Volumio Iptables Module. Apr 28 15:13:44 warpandwoof systemd[1]: Starting WPA supplicant... Apr 28 15:13:44 warpandwoof systemd[1]: Started UPnP Renderer front-end to MPD. Apr 28 15:13:44 warpandwoof systemd[1]: Started volumio-remote-updater.service. Apr 28 15:13:44 warpandwoof systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Apr 28 15:13:44 warpandwoof systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Apr 28 15:13:44 warpandwoof systemd[1]: Starting dhcpcd on all interfaces... Apr 28 15:13:44 warpandwoof systemd[1]: Reached target RPC Port Mapper. Apr 28 15:13:44 warpandwoof systemd[1]: Reached target Remote File Systems (Pre). Apr 28 15:13:44 warpandwoof systemd[1]: Reached target Remote File Systems. Apr 28 15:13:44 warpandwoof systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Apr 28 15:13:44 warpandwoof systemd[1]: Started triggerhappy global hotkey daemon. Apr 28 15:13:44 warpandwoof systemd[1]: volumiosplash.service: Main process exited, code=exited, status=203/EXEC Apr 28 15:13:44 warpandwoof systemd[1]: volumiosplash.service: Failed with result 'exit-code'. Apr 28 15:13:44 warpandwoof dhcpcd[440]: Not running dhcpcd because /etc/network/interfaces Apr 28 15:13:44 warpandwoof dhcpcd[440]: defines some interfaces that will use a Apr 28 15:13:44 warpandwoof dhcpcd[440]: DHCP client or static address Apr 28 15:13:44 warpandwoof systemd-logind[410]: New seat seat0. Apr 28 15:13:44 warpandwoof avahi-daemon[422]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Apr 28 15:13:44 warpandwoof avahi-daemon[422]: Successfully dropped root privileges. Apr 28 15:13:44 warpandwoof avahi-daemon[422]: avahi-daemon 0.7 starting up. Apr 28 15:13:44 warpandwoof ifup[376]: command failed: No such device (-19) Apr 28 15:13:45 warpandwoof kernel: 8021q: 802.1Q VLAN Support v1.8 Apr 28 15:13:45 warpandwoof volumio-remote-updater[438]: Error: No active session Apr 28 15:13:45 warpandwoof volumio-remote-updater[438]: [2024-04-28 15:13:45] [info] asio async_connect error: system:111 (Connection refused) Apr 28 15:13:45 warpandwoof volumio-remote-updater[438]: [2024-04-28 15:13:45] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 28 15:13:45 warpandwoof volumio-remote-updater[438]: [2024-04-28 15:13:45] [error] handle_connect error: Underlying Transport Error Apr 28 15:13:45 warpandwoof volumio[435]: Could not open config: /tmp/upmpdcli.conf Apr 28 15:13:45 warpandwoof systemd[1]: Started Save/Restore Sound Card State. Apr 28 15:13:45 warpandwoof systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 28 15:13:45 warpandwoof systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 28 15:13:45 warpandwoof systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Apr 28 15:13:45 warpandwoof systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Apr 28 15:13:45 warpandwoof systemd[1]: Failed to start dhcpcd on all interfaces. Apr 28 15:13:45 warpandwoof avahi-daemon[422]: Successfully called chroot(). Apr 28 15:13:45 warpandwoof avahi-daemon[422]: Successfully dropped remaining capabilities. Apr 28 15:13:45 warpandwoof wpa_supplicant[433]: Successfully initialized wpa_supplicant Apr 28 15:13:45 warpandwoof avahi-daemon[422]: Loading service file /services/volumio.service. Apr 28 15:13:45 warpandwoof avahi-daemon[422]: Network interface enumeration completed. Apr 28 15:13:45 warpandwoof avahi-daemon[422]: Server startup complete. Host name is warpandwoof.local. Local service cookie is 3543944029. Apr 28 15:13:45 warpandwoof avahi-daemon[422]: Service "warpandwoof" (/services/volumio.service) successfully established. Apr 28 15:13:45 warpandwoof systemd[1]: Started Login Service. Apr 28 15:13:45 warpandwoof systemd[1]: Started Avahi mDNS/DNS-SD Stack. Apr 28 15:13:45 warpandwoof systemd[1]: Started WPA supplicant. Apr 28 15:13:45 warpandwoof systemd[1]: Reached target Sound Card. Apr 28 15:13:46 warpandwoof loadcpufreq[444]: Loading cpufreq kernel modules...done (none). Apr 28 15:13:46 warpandwoof systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Apr 28 15:13:46 warpandwoof systemd[1]: Starting LSB: set CPUFreq kernel parameters... Apr 28 15:13:46 warpandwoof systemd[1]: iptables.service: Succeeded. Apr 28 15:13:46 warpandwoof systemd[1]: Started Raise network interfaces. Apr 28 15:13:46 warpandwoof systemd[1]: Reached target Network. Apr 28 15:13:46 warpandwoof systemd[1]: Starting Network Time Service... Apr 28 15:13:46 warpandwoof systemd[1]: Starting Permit User Sessions... Apr 28 15:13:46 warpandwoof systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Apr 28 15:13:46 warpandwoof systemd[1]: Reached target Network is Online. Apr 28 15:13:46 warpandwoof systemd[1]: Starting LSB: Brings up/down network automatically... Apr 28 15:13:46 warpandwoof systemd[1]: Starting Samba NMB Daemon... Apr 28 15:13:46 warpandwoof systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 28 15:13:46 warpandwoof systemd[1]: Starting /etc/rc.local Compatibility... Apr 28 15:13:46 warpandwoof systemd[1]: Started /etc/rc.local Compatibility. Apr 28 15:13:46 warpandwoof systemd[1]: Started Permit User Sessions. Apr 28 15:13:46 warpandwoof systemd[1]: Starting Hold until boot process finishes up... Apr 28 15:13:46 warpandwoof systemd[1]: Starting Terminate Plymouth Boot Screen... Apr 28 15:13:46 warpandwoof systemd[1]: Received SIGRTMIN+21 from PID 243 (plymouthd). Apr 28 15:13:46 warpandwoof systemd[1]: plymouth-start.service: Succeeded. Apr 28 15:13:46 warpandwoof systemd[1]: plymouth-quit-wait.service: Succeeded. Apr 28 15:13:46 warpandwoof systemd[1]: Started Hold until boot process finishes up. Apr 28 15:13:46 warpandwoof systemd[1]: plymouth-quit.service: Succeeded. Apr 28 15:13:46 warpandwoof systemd[1]: Started Terminate Plymouth Boot Screen. Apr 28 15:13:46 warpandwoof systemd[1]: Received SIGRTMIN+21 from PID 243 (n/a). Apr 28 15:13:46 warpandwoof sh[375]: eth0: waiting for carrier Apr 28 15:13:46 warpandwoof dhcpcd[462]: eth0: waiting for carrier Apr 28 15:13:46 warpandwoof ntpd[556]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 28 15:13:46 warpandwoof ntpd[556]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Apr 28 15:13:46 warpandwoof systemd[1]: Started Network Time Service. Apr 28 15:13:46 warpandwoof ntpd[587]: proto: precision = 1.406 usec (-19) Apr 28 15:13:46 warpandwoof systemd[1]: Started Serial Getty on ttyAMA0. Apr 28 15:13:46 warpandwoof systemd[1]: Started Getty on tty1. Apr 28 15:13:46 warpandwoof systemd[1]: Reached target Login Prompts. Apr 28 15:13:46 warpandwoof ntpd[587]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 28 15:13:46 warpandwoof ntpd[587]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 28 15:13:46 warpandwoof ntpd[587]: Listen and drop on 0 v6wildcard [::]:123 Apr 28 15:13:46 warpandwoof ntpd[587]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 28 15:13:46 warpandwoof ntpd[587]: Listen normally on 2 lo 127.0.0.1:123 Apr 28 15:13:46 warpandwoof ntpd[587]: Listening on routing socket on fd #19 for interface updates Apr 28 15:13:46 warpandwoof ntpd[587]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 28 15:13:46 warpandwoof ntpd[587]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 28 15:13:46 warpandwoof ifplugd(eth0)[603]: ifplugd 0.28 initializing. Apr 28 15:13:46 warpandwoof ifplugd(eth0)[603]: Using interface eth0/00:17:F7:00:A8:A6 with driver (version: 6.1.69-v7+) Apr 28 15:13:46 warpandwoof ifplugd(eth0)[603]: Using detection mode: SIOCETHTOOL Apr 28 15:13:46 warpandwoof ifplugd(eth0)[603]: Initialization complete, link beat not detected. Apr 28 15:13:46 warpandwoof cpufrequtils[552]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Apr 28 15:13:46 warpandwoof systemd[1]: Started LSB: set CPUFreq kernel parameters. Apr 28 15:13:46 warpandwoof haveged[399]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Apr 28 15:13:46 warpandwoof haveged[399]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Apr 28 15:13:46 warpandwoof haveged[399]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99896 Apr 28 15:13:46 warpandwoof haveged[399]: haveged: fills: 0, generated: 0 Apr 28 15:13:46 warpandwoof ifplugd[559]: Network Interface Plugging Daemon...start eth0...done. Apr 28 15:13:46 warpandwoof systemd[1]: Started LSB: Brings up/down network automatically. Apr 28 15:13:47 warpandwoof nmbd[570]: [2024/04/28 15:13:47.391339, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Apr 28 15:13:47 warpandwoof nmbd[570]: started asyncdns process 607 Apr 28 15:13:47 warpandwoof nmbd[570]: [2024/04/28 15:13:47.396053, 0] ../lib/util/become_daemon.c:149(daemon_status) Apr 28 15:13:47 warpandwoof nmbd[570]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Apr 28 15:13:47 warpandwoof nmbd[570]: [2024/04/28 15:13:47.397623, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Apr 28 15:13:47 warpandwoof nmbd[570]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Apr 28 15:13:47 warpandwoof ntpd[587]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 28 15:13:47 warpandwoof wireless.js[415]: WIRELESS: No wireless interface, exiting Apr 28 15:13:47 warpandwoof iw[612]: command failed: No such device (-19) Apr 28 15:13:47 warpandwoof iwconfig[615]: Error for wireless request "Set Power Management" (8B2C) : Apr 28 15:13:47 warpandwoof iwconfig[615]: SET failed on device wlan0 ; No such device. Apr 28 15:13:47 warpandwoof systemd[1]: wireless.service: Succeeded. Apr 28 15:13:47 warpandwoof systemd[1]: Started Wireless Services. Apr 28 15:13:47 warpandwoof systemd[1]: Started Volumio Backend Module. Apr 28 15:13:47 warpandwoof systemd[1]: Started Volumio Cpu Tweaker. Apr 28 15:13:48 warpandwoof volumio-cpu-tweak[619]: Setting RT Priority for mpd Apr 28 15:13:48 warpandwoof volumio-cpu-tweak[619]: pid 35's current scheduling policy: SCHED_OTHER Apr 28 15:13:48 warpandwoof volumio-cpu-tweak[619]: pid 35's current scheduling priority: 0 Apr 28 15:13:48 warpandwoof volumio-cpu-tweak[619]: Setting MPD Affinity Apr 28 15:13:48 warpandwoof volumio-cpu-tweak[619]: pid 3's current affinity mask: f Apr 28 15:13:48 warpandwoof volumio-cpu-tweak[619]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Apr 28 15:13:48 warpandwoof volumio-cpu-tweak[619]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Apr 28 15:13:48 warpandwoof systemd[1]: volumio_cpu_tweak.service: Succeeded. Apr 28 15:13:48 warpandwoof ntpd[587]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 28 15:13:49 warpandwoof kernel: ax88179_178a 1-1.3:1.0 eth0: ax88179 - Link status is: 1 Apr 28 15:13:49 warpandwoof sh[375]: eth0: carrier acquired Apr 28 15:13:49 warpandwoof dhcpcd[462]: eth0: carrier acquired Apr 28 15:13:49 warpandwoof sh[375]: DUID 00:01:00:01:2a:c9:69:f6:00:17:f7:00:a8:a6 Apr 28 15:13:49 warpandwoof sh[375]: eth0: IAID f7:00:a8:a6 Apr 28 15:13:49 warpandwoof dhcpcd[462]: DUID 00:01:00:01:2a:c9:69:f6:00:17:f7:00:a8:a6 Apr 28 15:13:49 warpandwoof dhcpcd[462]: eth0: IAID f7:00:a8:a6 Apr 28 15:13:49 warpandwoof dhcpcd[462]: eth0: adding address fe80::2233:44fd:a9ae:2dbb Apr 28 15:13:49 warpandwoof sh[375]: eth0: adding address fe80::2233:44fd:a9ae:2dbb Apr 28 15:13:49 warpandwoof sh[375]: ipv6_addaddr1: Permission denied Apr 28 15:13:49 warpandwoof dhcpcd[462]: ipv6_addaddr1: Permission denied Apr 28 15:13:49 warpandwoof ntpd[587]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 28 15:13:49 warpandwoof sh[375]: eth0: soliciting an IPv6 router Apr 28 15:13:49 warpandwoof dhcpcd[462]: eth0: soliciting an IPv6 router Apr 28 15:13:49 warpandwoof ifplugd(eth0)[603]: Link beat detected. Apr 28 15:13:49 warpandwoof ifplugd(eth0)[603]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Apr 28 15:13:49 warpandwoof ifplugd(eth0)[603]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Apr 28 15:13:50 warpandwoof sh[375]: eth0: soliciting a DHCP lease Apr 28 15:13:50 warpandwoof dhcpcd[462]: eth0: soliciting a DHCP lease Apr 28 15:13:50 warpandwoof volumio-remote-updater[438]: [2024-04-28 15:13:50] [connect] Successful connection Apr 28 15:13:50 warpandwoof sh[375]: eth0: offered 192.168.1.132 from 192.168.1.254 Apr 28 15:13:50 warpandwoof dhcpcd[462]: eth0: offered 192.168.1.132 from 192.168.1.254 Apr 28 15:13:50 warpandwoof sh[375]: eth0: probing address 192.168.1.132/24 Apr 28 15:13:50 warpandwoof dhcpcd[462]: eth0: probing address 192.168.1.132/24 Apr 28 15:13:50 warpandwoof ntpd[587]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 28 15:13:50 warpandwoof volumio[618]: info: ------------------------------------------- Apr 28 15:13:50 warpandwoof volumio[618]: info: ----- Volumio3 ---- Apr 28 15:13:50 warpandwoof volumio[618]: info: ------------------------------------------- Apr 28 15:13:50 warpandwoof volumio[618]: info: ----- System startup ---- Apr 28 15:13:50 warpandwoof volumio[618]: info: ------------------------------------------- Apr 28 15:13:51 warpandwoof volumio[618]: info: MYVOLUMIO Environment detected Apr 28 15:13:52 warpandwoof volumio[618]: info: Plugin folders cleanup Apr 28 15:13:52 warpandwoof volumio[618]: info: Scanning into folder /volumio/app/plugins/ Apr 28 15:13:52 warpandwoof volumio[618]: info: Scanning category audio_interface Apr 28 15:13:52 warpandwoof volumio[618]: info: Scanning category miscellanea Apr 28 15:13:52 warpandwoof volumio[618]: info: Scanning category music_service Apr 28 15:13:52 warpandwoof volumio[618]: info: Scanning category plugins.json Apr 28 15:13:52 warpandwoof volumio[618]: info: Scanning category system_controller Apr 28 15:13:52 warpandwoof volumio[618]: info: Scanning category user_interface Apr 28 15:13:52 warpandwoof volumio[618]: info: Scanning into folder /data/plugins/ Apr 28 15:13:52 warpandwoof volumio[618]: info: Scanning category audio_interface Apr 28 15:13:52 warpandwoof volumio[618]: info: Scanning category music_service Apr 28 15:13:52 warpandwoof volumio[618]: info: Scanning category system_controller Apr 28 15:13:52 warpandwoof volumio[618]: info: Scanning category user_interface Apr 28 15:13:52 warpandwoof volumio[618]: info: Plugin folders cleanup completed Apr 28 15:13:52 warpandwoof volumio[618]: info: ------------------------------------------- Apr 28 15:13:52 warpandwoof volumio[618]: info: ----- Core plugins startup ---- Apr 28 15:13:52 warpandwoof volumio[618]: info: ------------------------------------------- Apr 28 15:13:52 warpandwoof volumio[618]: info: Loading plugins from folder /volumio/app/plugins/ Apr 28 15:13:52 warpandwoof volumio[618]: info: Adding plugin upnp to MyMusic Plugins Apr 28 15:13:52 warpandwoof volumio[618]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 28 15:13:52 warpandwoof volumio[618]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 28 15:13:52 warpandwoof volumio[618]: info: Loading plugins from folder /data/plugins/ Apr 28 15:13:52 warpandwoof volumio[618]: info: Loading plugin "system"... Apr 28 15:13:52 warpandwoof volumio[618]: info: Loading plugin "appearance"... Apr 28 15:13:53 warpandwoof volumio[618]: info: Loading plugin "network"... Apr 28 15:13:53 warpandwoof volumio[618]: info: Refreshing Cached IP Addresses Apr 28 15:13:53 warpandwoof volumio[618]: info: Loading plugin "services"... Apr 28 15:13:53 warpandwoof volumio[618]: info: Loading plugin "alsa_controller"... Apr 28 15:13:53 warpandwoof sudo[659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 28 15:13:53 warpandwoof sudo[655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 28 15:13:53 warpandwoof sudo[659]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:13:53 warpandwoof sudo[655]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:13:53 warpandwoof sudo[657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 28 15:13:53 warpandwoof sudo[657]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:13:53 warpandwoof sudo[655]: pam_unix(sudo:session): session closed for user root Apr 28 15:13:53 warpandwoof sudo[659]: pam_unix(sudo:session): session closed for user root Apr 28 15:13:53 warpandwoof sudo[657]: pam_unix(sudo:session): session closed for user root Apr 28 15:13:53 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 15:13:53 warpandwoof volumio[618]: info: Loading plugin "wizard"... Apr 28 15:13:53 warpandwoof volumio[618]: info: Loading plugin "networkfs"... Apr 28 15:13:54 warpandwoof sudo[670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.181/MusicSSD /mnt/NAS/MusicSSD Apr 28 15:13:54 warpandwoof sudo[670]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:13:54 warpandwoof sudo[672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Dominic Rutterford,password=07970692318,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.64/Lossless /mnt/NAS/Dropbox Apr 28 15:13:54 warpandwoof sudo[672]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:13:54 warpandwoof volumio[618]: info: Starting Udev Watcher for removable devices Apr 28 15:13:54 warpandwoof volumio[618]: info: Ignoring mount for partition: boot Apr 28 15:13:54 warpandwoof volumio[618]: info: Ignoring mount for partition: volumio Apr 28 15:13:54 warpandwoof volumio[618]: info: Ignoring mount for partition: volumio_data Apr 28 15:13:54 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 15:13:54 warpandwoof volumio[618]: info: Loading plugin "volumio_command_line_client"... Apr 28 15:13:54 warpandwoof volumio[618]: info: Loading plugin "upnp"... Apr 28 15:13:54 warpandwoof volumio[618]: info: [1714317234105] Starting Upmpd Daemon Apr 28 15:13:54 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 15:13:54 warpandwoof volumio[618]: info: Loading plugin "my_music"... Apr 28 15:13:54 warpandwoof volumio[618]: info: Loading plugin "mpd"... Apr 28 15:13:54 warpandwoof sudo[670]: pam_unix(sudo:session): session closed for user root Apr 28 15:13:54 warpandwoof sudo[672]: pam_unix(sudo:session): session closed for user root Apr 28 15:13:54 warpandwoof kernel: Key type cifs.spnego registered Apr 28 15:13:54 warpandwoof kernel: Key type cifs.idmap registered Apr 28 15:13:54 warpandwoof 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. Apr 28 15:13:54 warpandwoof kernel: CIFS: Attempting to mount \\192.168.1.181\MusicSSD Apr 28 15:13:54 warpandwoof kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Apr 28 15:13:54 warpandwoof kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Apr 28 15:13:54 warpandwoof kernel: CIFS: Attempting to mount \\192.168.1.64\Lossless Apr 28 15:13:54 warpandwoof kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Apr 28 15:13:54 warpandwoof kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Apr 28 15:13:54 warpandwoof volumio[618]: info: Loading plugin "upnp_browser"... Apr 28 15:13:55 warpandwoof volumio[618]: info: Loading plugin "alarm-clock"... Apr 28 15:13:55 warpandwoof volumio[618]: info: Loading plugin "airplay_emulation"... Apr 28 15:13:55 warpandwoof volumio[618]: info: Starting Shairport Sync Apr 28 15:13:55 warpandwoof volumio[618]: info: Loading plugin "last_100"... Apr 28 15:13:55 warpandwoof volumio[618]: info: Loading plugin "webradio"... Apr 28 15:13:55 warpandwoof volumio[618]: info: Loading plugin "i2s_dacs"... Apr 28 15:13:55 warpandwoof volumio[618]: info: I2S DAC not set, start Auto-detection Apr 28 15:13:55 warpandwoof volumio[618]: info: Loading plugin "volumiodiscovery"... Apr 28 15:13:55 warpandwoof volumio[618]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 28 15:13:55 warpandwoof volumio[618]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 15:13:55 warpandwoof volumio[618]: *** WARNING *** For more information see Apr 28 15:13:55 warpandwoof node[618]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 28 15:13:55 warpandwoof volumio[618]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 28 15:13:55 warpandwoof volumio[618]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 15:13:55 warpandwoof volumio[618]: *** WARNING *** For more information see Apr 28 15:13:55 warpandwoof node[618]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 15:13:55 warpandwoof node[618]: *** WARNING *** For more information see Apr 28 15:13:55 warpandwoof node[618]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 28 15:13:55 warpandwoof node[618]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 15:13:55 warpandwoof node[618]: *** WARNING *** For more information see Apr 28 15:13:55 warpandwoof volumio[618]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 28 15:13:55 warpandwoof volumio[618]: info: Discovery: Started advertising with name: warpandwoof Apr 28 15:13:55 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 15:13:55 warpandwoof volumio[618]: info: Loading plugin "spop"... Apr 28 15:13:55 warpandwoof sh[375]: eth0: leased 192.168.1.132 for 86400 seconds Apr 28 15:13:55 warpandwoof sh[375]: eth0: adding route to 192.168.1.0/24 Apr 28 15:13:55 warpandwoof sh[375]: eth0: adding default route via 192.168.1.254 Apr 28 15:13:55 warpandwoof dhcpcd[462]: eth0: leased 192.168.1.132 for 86400 seconds Apr 28 15:13:55 warpandwoof dhcpcd[462]: eth0: adding route to 192.168.1.0/24 Apr 28 15:13:55 warpandwoof dhcpcd[462]: eth0: adding default route via 192.168.1.254 Apr 28 15:13:55 warpandwoof avahi-daemon[422]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.132. Apr 28 15:13:55 warpandwoof avahi-daemon[422]: New relevant interface eth0.IPv4 for mDNS. Apr 28 15:13:55 warpandwoof avahi-daemon[422]: Registering new address record for 192.168.1.132 on eth0.IPv4. Apr 28 15:13:55 warpandwoof sh[375]: forked to background, child pid 712 Apr 28 15:13:55 warpandwoof dhcpcd[462]: forked to background, child pid 712 Apr 28 15:13:56 warpandwoof ifplugd(eth0)[603]: client: ifup: interface eth0 already configured Apr 28 15:13:56 warpandwoof sh[375]: eth0=eth0 Apr 28 15:13:56 warpandwoof ifplugd(eth0)[603]: Program executed successfully. Apr 28 15:13:57 warpandwoof volumio[618]: info: Loading plugin "outputs"... Apr 28 15:13:57 warpandwoof volumio[618]: info: Loading plugin "albumart"... Apr 28 15:13:57 warpandwoof nmbd[570]: [2024/04/28 15:13:57.403827, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 28 15:13:57 warpandwoof nmbd[570]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 28 15:13:57 warpandwoof systemd[1]: Started Samba NMB Daemon. Apr 28 15:13:57 warpandwoof volumio[618]: info: Plugin example_plugin is not enabled Apr 28 15:13:57 warpandwoof volumio[618]: info: Loading plugin "inputs"... Apr 28 15:13:57 warpandwoof volumio[618]: info: Loading plugin "updater_comm"... Apr 28 15:13:57 warpandwoof systemd[1]: Starting Samba Winbind Daemon... Apr 28 15:13:57 warpandwoof ntpd[587]: Listen normally on 3 eth0 192.168.1.132:123 Apr 28 15:13:57 warpandwoof ntpd[587]: new interface(s) found: waking up resolver Apr 28 15:13:57 warpandwoof volumio[618]: info: Plugin mpdemulation is not enabled Apr 28 15:13:57 warpandwoof volumio[618]: info: Loading plugin "rest_api"... Apr 28 15:13:57 warpandwoof volumio[618]: info: Loading plugin "websocket"... Apr 28 15:13:57 warpandwoof volumio[618]: info: Loading plugin "fusiondsp"... Apr 28 15:13:57 warpandwoof volumio[618]: Forking 3 albumart workers Apr 28 15:13:57 warpandwoof winbindd[738]: [2024/04/28 15:13:57.862310, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 28 15:13:57 warpandwoof winbindd[738]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 28 15:13:57 warpandwoof winbindd[738]: [2024/04/28 15:13:57.880960, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 28 15:13:57 warpandwoof winbindd[738]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 28 15:13:57 warpandwoof systemd[1]: Started Samba Winbind Daemon. Apr 28 15:13:57 warpandwoof systemd[1]: Starting Samba SMB Daemon... Apr 28 15:13:58 warpandwoof volumio[618]: info: Applying required configuration parameters for plugin fusiondsp Apr 28 15:13:58 warpandwoof winbindd[738]: [2024/04/28 15:13:58.583663, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Apr 28 15:13:58 warpandwoof winbindd[738]: res_names->count = 3, expected 4 Apr 28 15:13:58 warpandwoof systemd[1]: Started Samba SMB Daemon. Apr 28 15:13:58 warpandwoof systemd[1]: Reached target Multi-User System. Apr 28 15:13:58 warpandwoof systemd[1]: Reached target Graphical Interface. Apr 28 15:13:58 warpandwoof smbd[768]: [2024/04/28 15:13:58.628750, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 28 15:13:58 warpandwoof smbd[768]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 28 15:13:58 warpandwoof volumio[618]: info: Loading plugin "audiophonicsonoff"... Apr 28 15:13:58 warpandwoof systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 28 15:13:58 warpandwoof systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 28 15:13:58 warpandwoof systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 28 15:13:58 warpandwoof systemd[1]: Startup finished in 10.154s (kernel) + 21.785s (userspace) = 31.940s. Apr 28 15:13:58 warpandwoof volumio[618]: Starting albumart workers Apr 28 15:13:59 warpandwoof volumio[618]: Starting albumart workers Apr 28 15:13:59 warpandwoof volumio[618]: info: Applying required configuration parameters for plugin audiophonicsonoff Apr 28 15:13:59 warpandwoof volumio[618]: info: Audiophonics on/off initiated Apr 28 15:13:59 warpandwoof volumio[618]: info: Loading plugin "backup_restore"... Apr 28 15:13:59 warpandwoof volumio[618]: Starting albumart workers Apr 28 15:13:59 warpandwoof volumio[618]: info: Applying required configuration parameters for plugin backup_restore Apr 28 15:13:59 warpandwoof volumio[618]: info: Loading plugin "Systeminfo"... Apr 28 15:14:00 warpandwoof volumio[618]: info: Loading i18n strings for locale en Apr 28 15:14:00 warpandwoof volumio[618]: Updating browse sources language Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreCommandRouter::initPlayerControls Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 15:14:00 warpandwoof volumio[618]: Express server listening on port 3000 Apr 28 15:14:00 warpandwoof volumio[618]: [Metrics] WebUI: 10s 708.13ms Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreStateMachine::resetVolumioState Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreStateMachine::getcurrentVolume Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 15:14:00 warpandwoof volumio[618]: info: Cannot mount NAS Dropbox at system boot, trial number 1 ,retrying in 5 seconds Apr 28 15:14:00 warpandwoof volumio[618]: info: Cannot mount NAS MusicSSD at system boot, trial number 1 ,retrying in 5 seconds Apr 28 15:14:00 warpandwoof volumio[618]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 28 15:14:00 warpandwoof volumio[618]: wlan0 Interface doesn't support scanning. Apr 28 15:14:00 warpandwoof volumio[618]: info: Cannot use regular scanning, forcing with ap-force Apr 28 15:14:00 warpandwoof sudo[810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 28 15:14:00 warpandwoof sudo[810]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:14:00 warpandwoof sudo[810]: pam_unix(sudo:session): session closed for user root Apr 28 15:14:00 warpandwoof volumio[618]: command failed: No such device (-19) Apr 28 15:14:00 warpandwoof volumio[618]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Apr 28 15:14:00 warpandwoof volumio[618]: command failed: No such device (-19) Apr 28 15:14:00 warpandwoof volumio[618]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Apr 28 15:14:00 warpandwoof volumio[618]: info: Reloading queue from file Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreStateMachine::setRepeat null single undefined Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreStateMachine::pushState Apr 28 15:14:00 warpandwoof volumio[618]: info: CorePlayQueue::getTrack 0 Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreCommandRouter::volumioPushState Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreStateMachine::setRandom false Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreStateMachine::pushState Apr 28 15:14:00 warpandwoof volumio[618]: info: CorePlayQueue::getTrack 0 Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreCommandRouter::volumioPushState Apr 28 15:14:00 warpandwoof volumio[618]: info: Setting Device type: Raspberry PI Apr 28 15:14:00 warpandwoof volumio[618]: info: VolumeController:: Volume=undefined Mute =false Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreStateMachine::pushState Apr 28 15:14:00 warpandwoof volumio[618]: info: CorePlayQueue::getTrack 0 Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreCommandRouter::volumioPushState Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreStateMachine::updateTrackBlock Apr 28 15:14:00 warpandwoof volumio[618]: info: CorePlayQueue::getTrackBlock Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 15:14:00 warpandwoof volumio[618]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Apr 28 15:14:00 warpandwoof volumio[618]: info: Discovery: adding 1bf204eb-a356-4e5a-b360-42fb1dfbc9ef Apr 28 15:14:00 warpandwoof volumio[618]: info: Discovery: Found device warpandwoof Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreCommandRouter::volumioGetState Apr 28 15:14:00 warpandwoof volumio[618]: info: CorePlayQueue::getTrack 0 Apr 28 15:14:00 warpandwoof volumio[618]: info: VolumeController:: Volume=undefined Mute =false Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreStateMachine::pushState Apr 28 15:14:00 warpandwoof volumio[618]: info: CorePlayQueue::getTrack 0 Apr 28 15:14:00 warpandwoof volumio[618]: info: CoreCommandRouter::volumioPushState Apr 28 15:14:02 warpandwoof volumio-remote-updater[438]: [2024-04-28 15:14:02] [connect] Successful connection Apr 28 15:14:02 warpandwoof volumio-remote-updater[438]: [2024-04-28 15:14:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1714317242 101 Apr 28 15:14:02 warpandwoof volumio[618]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Apr 28 15:14:04 warpandwoof sudo[823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 28 15:14:04 warpandwoof sudo[823]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:14:04 warpandwoof sudo[823]: pam_unix(sudo:session): session closed for user root Apr 28 15:14:04 warpandwoof sudo[825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 28 15:14:04 warpandwoof sudo[825]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:14:04 warpandwoof sudo[829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 28 15:14:04 warpandwoof sudo[825]: pam_unix(sudo:session): session closed for user root Apr 28 15:14:04 warpandwoof sudo[829]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:14:04 warpandwoof systemd[1]: Started UPnP Renderer front-end to MPD. Apr 28 15:14:04 warpandwoof sudo[829]: pam_unix(sudo:session): session closed for user root Apr 28 15:14:04 warpandwoof volumio[618]: info: Upmpdcli Daemon Started Apr 28 15:14:04 warpandwoof volumio[618]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 28 15:14:05 warpandwoof sudo[835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Dominic Rutterford,password=07970692318,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.64/Lossless /mnt/NAS/Dropbox Apr 28 15:14:05 warpandwoof sudo[835]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:14:05 warpandwoof sudo[837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.181/MusicSSD /mnt/NAS/MusicSSD Apr 28 15:14:05 warpandwoof sudo[837]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:14:05 warpandwoof kernel: CIFS: Attempting to mount \\192.168.1.64\Lossless Apr 28 15:14:08 warpandwoof volumio[618]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 28 15:14:13 warpandwoof systemd[1]: systemd-fsckd.service: Succeeded. Apr 28 15:14:14 warpandwoof volumio[618]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 28 15:14:15 warpandwoof sudo[835]: pam_unix(sudo:session): session closed for user root Apr 28 15:14:15 warpandwoof kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Apr 28 15:14:15 warpandwoof kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Apr 28 15:14:15 warpandwoof kernel: CIFS: Attempting to mount \\192.168.1.181\MusicSSD Apr 28 15:14:15 warpandwoof volumio[618]: info: Cannot mount NAS Dropbox at system boot, trial number 2 ,retrying in 5 seconds Apr 28 15:14:16 warpandwoof sudo[837]: pam_unix(sudo:session): session closed for user root Apr 28 15:14:20 warpandwoof sudo[863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Dominic Rutterford,password=07970692318,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.64/Lossless /mnt/NAS/Dropbox Apr 28 15:14:20 warpandwoof sudo[863]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:14:20 warpandwoof kernel: CIFS: Attempting to mount \\192.168.1.64\Lossless Apr 28 15:14:24 warpandwoof volumio[618]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 28 15:14:30 warpandwoof volumio[618]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion Apr 28 15:14:30 warpandwoof volumio[618]: info: Preparing to generate the ALSA configuration file Apr 28 15:14:30 warpandwoof volumio[618]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 28 15:14:30 warpandwoof volumio[618]: info: Reading ALSA contributions from plugins. Apr 28 15:14:30 warpandwoof volumio[618]: info: Asound.conf file unchanged, so no further update is needed Apr 28 15:14:30 warpandwoof volumio[618]: info: Output device has changed, restarting MPD Apr 28 15:14:30 warpandwoof volumio[618]: info: Output device has changed, restarting Shairport Sync Apr 28 15:14:30 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:30 warpandwoof sudo[868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 15:14:30 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 15:14:30 warpandwoof sudo[868]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:14:30 warpandwoof sudo[868]: pam_unix(sudo:session): session closed for user root Apr 28 15:14:30 warpandwoof sudo[870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 15:14:30 warpandwoof sudo[870]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:14:30 warpandwoof volumio[618]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 28 15:14:30 warpandwoof volumio[618]: info: ___________ START PLUGINS ___________ Apr 28 15:14:30 warpandwoof volumio[618]: info: ControllerMpd::onStart: Initializing MPD Apr 28 15:14:30 warpandwoof volumio[618]: info: Creating MPD Configuration file Apr 28 15:14:30 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 15:14:30 warpandwoof volumio[618]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 15:14:30 warpandwoof sudo[878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 15:14:30 warpandwoof sudo[878]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:14:30 warpandwoof volumio[618]: info: [1714317270351] CoreMusicLibrary::Adding element Media Servers Apr 28 15:14:30 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 15:14:30 warpandwoof sudo[878]: pam_unix(sudo:session): session closed for user root Apr 28 15:14:30 warpandwoof systemd[1]: Listening on mpd.socket. Apr 28 15:14:30 warpandwoof systemd[1]: Starting Music Player Daemon... Apr 28 15:14:30 warpandwoof sudo[881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 15:14:30 warpandwoof sudo[881]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:14:30 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:30 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 15:14:30 warpandwoof systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 28 15:14:30 warpandwoof systemd[1]: mpd.service: Succeeded. Apr 28 15:14:30 warpandwoof systemd[1]: Stopped Music Player Daemon. Apr 28 15:14:30 warpandwoof systemd[1]: Starting Music Player Daemon... Apr 28 15:14:30 warpandwoof volumio[618]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 28 15:14:30 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:30 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 15:14:30 warpandwoof volumio[618]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 28 15:14:30 warpandwoof volumio[618]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 15:14:30 warpandwoof volumio[618]: info: [1714317270505] CoreMusicLibrary::Adding element Last_100 Apr 28 15:14:30 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 15:14:30 warpandwoof volumio[618]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 15:14:30 warpandwoof volumio[618]: info: [1714317270510] CoreMusicLibrary::Adding element Webradio Apr 28 15:14:30 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 15:14:30 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 15:14:30 warpandwoof volumio[618]: info: Creating Spotify config file Apr 28 15:14:30 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:30 warpandwoof volumio[618]: info: Loading i18n strings for locale en Apr 28 15:14:30 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 28 15:14:30 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:30 warpandwoof volumio[618]: info: FusionDsp - mixtype--------------------- undefined Apr 28 15:14:30 warpandwoof volumio[618]: info: Configuring GPIO pins Apr 28 15:14:30 warpandwoof volumio[618]: info: Soft shutdown GPIO binding... OK Apr 28 15:14:30 warpandwoof volumio[618]: info: Hardware button GPIO binding... OK Apr 28 15:14:30 warpandwoof volumio[618]: info: Boot OK GPIO binding... OK Apr 28 15:14:30 warpandwoof volumio[618]: info: Volumio Calling Home Apr 28 15:14:30 warpandwoof volumio[618]: info: Preparing to generate the ALSA configuration file Apr 28 15:14:30 warpandwoof volumio[618]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 28 15:14:30 warpandwoof volumio[618]: info: Reading ALSA contributions from plugins. Apr 28 15:14:30 warpandwoof volumio[618]: info: MPD Permissions set Apr 28 15:14:30 warpandwoof volumio[618]: info: MPD Permissions set Apr 28 15:14:30 warpandwoof volumio[618]: info: Volumio called home Apr 28 15:14:30 warpandwoof volumio[618]: info: Spotify config file written Apr 28 15:14:30 warpandwoof sudo[916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 28 15:14:30 warpandwoof sudo[916]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:14:30 warpandwoof kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Apr 28 15:14:30 warpandwoof kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Apr 28 15:14:30 warpandwoof sudo[863]: pam_unix(sudo:session): session closed for user root Apr 28 15:14:31 warpandwoof volumio[618]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof systemd[1]: Started go-librespot Daemon. Apr 28 15:14:31 warpandwoof go-librespot[918]: Librespot-go daemon starting... Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 15:14:31 warpandwoof sudo[916]: pam_unix(sudo:session): session closed for user root Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: Cannot mount NAS Dropbox at system boot, trial number 3 ,retrying in 5 seconds Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: An error occurred while refreshing Spotify Token Error: certificate is not yet valid Apr 28 15:14:31 warpandwoof volumio[618]: info: Starting Shairport Sync Apr 28 15:14:31 warpandwoof volumio[618]: info: Starting Shairport Sync Apr 28 15:14:31 warpandwoof sudo[934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 28 15:14:31 warpandwoof sudo[934]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:14:31 warpandwoof volumio[618]: info: Starting Shairport Sync Apr 28 15:14:31 warpandwoof sudo[937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 28 15:14:31 warpandwoof systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 28 15:14:31 warpandwoof sudo[937]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:14:31 warpandwoof systemd[1]: shairport-sync.service: Succeeded. Apr 28 15:14:31 warpandwoof systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 28 15:14:31 warpandwoof sudo[941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 28 15:14:31 warpandwoof sudo[941]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:14:31 warpandwoof volumio[618]: info: Asound.conf file unchanged, so no further update is needed Apr 28 15:14:31 warpandwoof volumio[618]: info: Output device has changed, restarting MPD Apr 28 15:14:31 warpandwoof systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 28 15:14:31 warpandwoof sudo[944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 15:14:31 warpandwoof sudo[944]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:14:31 warpandwoof sudo[934]: pam_unix(sudo:session): session closed for user root Apr 28 15:14:31 warpandwoof sudo[944]: pam_unix(sudo:session): session closed for user root Apr 28 15:14:31 warpandwoof volumio[618]: info: Output device has changed, restarting Shairport Sync Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 15:14:31 warpandwoof systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 28 15:14:31 warpandwoof systemd[1]: shairport-sync.service: Succeeded. Apr 28 15:14:31 warpandwoof systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 28 15:14:31 warpandwoof sudo[948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 15:14:31 warpandwoof sudo[948]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:14:31 warpandwoof systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 28 15:14:31 warpandwoof sudo[941]: pam_unix(sudo:session): session closed for user root Apr 28 15:14:31 warpandwoof sudo[937]: pam_unix(sudo:session): session closed for user root Apr 28 15:14:31 warpandwoof systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 28 15:14:31 warpandwoof systemd[1]: mpd.service: Succeeded. Apr 28 15:14:31 warpandwoof systemd[1]: Stopped Music Player Daemon. Apr 28 15:14:31 warpandwoof systemd[1]: Starting Music Player Daemon... Apr 28 15:14:31 warpandwoof volumio[618]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 28 15:14:31 warpandwoof volumio[618]: info: MPD Permissions set Apr 28 15:14:31 warpandwoof volumio[618]: info: Shairport-Sync Started Apr 28 15:14:31 warpandwoof volumio[618]: Error adding Membership: Error: addMembership EINVAL Apr 28 15:14:31 warpandwoof volumio[618]: info: Shairport-Sync Started Apr 28 15:14:31 warpandwoof volumio[618]: info: Shairport-Sync Started Apr 28 15:14:31 warpandwoof go-librespot[918]: time="2024-04-28T15:14:31Z" level=info msg="generated new device id: bf2626d261f0f68326768b8743a7e2d7a603c7a1" Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::volumioGetState Apr 28 15:14:31 warpandwoof volumio[618]: info: CorePlayQueue::getTrack 0 Apr 28 15:14:31 warpandwoof go-librespot[918]: time="2024-04-28T15:14:31Z" level=debug msg="stored credentials found for domchocolate" Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:31 warpandwoof volumio[618]: info: Starting Shairport Sync Apr 28 15:14:31 warpandwoof sudo[963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 28 15:14:31 warpandwoof sudo[963]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:14:31 warpandwoof systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 28 15:14:31 warpandwoof systemd[1]: shairport-sync.service: Succeeded. Apr 28 15:14:31 warpandwoof systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 28 15:14:31 warpandwoof systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 28 15:14:31 warpandwoof sudo[963]: pam_unix(sudo:session): session closed for user root Apr 28 15:14:31 warpandwoof volumio[618]: info: Shairport-Sync Started Apr 28 15:14:32 warpandwoof go-librespot[918]: time="2024-04-28T15:14:32Z" level=debug msg="obtained new client token: AAA7RmZDYeTLtUXOLBPKnIzZvH5Hpbwfsvkgs/iuBbwmkL/pelMAZuTWS5MRseMcWk6Xau62dZJQtWCDtQLsh/QgHT2RDzvJUjC/iIFjfnRHYSAj27OnuHT12QmqmFb7NhfgLzGOKUjEhhi2ntn31bDZCweKczpgpsef3nm8ySljBlTZLoJ2dU0HEnhod8u5JDoMX80WBG3RWAGdBliOXtH3ZxM6KmpW8O1UlwjQWO73SwbmBQOZZq/dRJwvKc4=" Apr 28 15:14:32 warpandwoof go-librespot[918]: time="2024-04-28T15:14:32Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 28 15:14:32 warpandwoof go-librespot[918]: time="2024-04-28T15:14:32Z" level=debug msg="completed keyexchange" Apr 28 15:14:32 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 15:14:32 warpandwoof volumio[618]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Apr 28 15:14:32 warpandwoof volumio[618]: info: FusionDsp loaded Apr 28 15:14:32 warpandwoof volumio[618]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 28 15:14:32 warpandwoof sudo[978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 28 15:14:32 warpandwoof sudo[978]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:14:32 warpandwoof volumio[618]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 28 15:14:32 warpandwoof volumio[618]: info: Adding Signal Path Element [object Object] Apr 28 15:14:32 warpandwoof volumio[618]: info: Adding fusiondspeq DSP Signal Path Element Apr 28 15:14:32 warpandwoof volumio[618]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 28 15:14:32 warpandwoof volumio[618]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Apr 28 15:14:32 warpandwoof go-librespot[918]: time="2024-04-28T15:14:32Z" level=debug msg="completed challenge" Apr 28 15:14:32 warpandwoof systemd[1]: Started FusionDsp Daemon. Apr 28 15:14:32 warpandwoof sudo[978]: pam_unix(sudo:session): session closed for user root Apr 28 15:14:32 warpandwoof go-librespot[918]: time="2024-04-28T15:14:32Z" level=debug msg="authenticated as domchocolate" Apr 28 15:14:33 warpandwoof go-librespot[918]: time="2024-04-28T15:14:33Z" level=debug msg="authenticated as domchocolate" Apr 28 15:14:33 warpandwoof go-librespot[918]: time="2024-04-28T15:14:33Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Apr 28 15:14:33 warpandwoof go-librespot[918]: time="2024-04-28T15:14:33Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 28 15:14:33 warpandwoof mpd[955]: Apr 28 15:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 28 15:14:33 warpandwoof go-librespot[918]: time="2024-04-28T15:14:33Z" level=debug msg="dealer connection opened" Apr 28 15:14:33 warpandwoof go-librespot[918]: time="2024-04-28T15:14:33Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 28 15:14:33 warpandwoof go-librespot[918]: time="2024-04-28T15:14:33Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 28 15:14:33 warpandwoof go-librespot[918]: time="2024-04-28T15:14:33Z" level=debug msg="received connection id: MjU2ZmQyMWMtMTFjMy00ODhlLTkxOTYtNTUzMzkwMjUwMWQ0K2RlYWxlcit0Y3A6Ly9nZXcxLWRlYWxlci1hLWxnaGYuZ2V3MS5zcG90aWZ5Lm5ldDo1NzAwKzgwRTJEQzhFNzgzRUYwRDkwNjczMUY3REMxMUZDMTkxNDQ2RTE2ODA1NkJDMDk1NTU5MTRENzA0RUYxMkRDMTk=" Apr 28 15:14:33 warpandwoof go-librespot[918]: time="2024-04-28T15:14:33Z" level=debug msg="put connect state because NEW_DEVICE" Apr 28 15:14:33 warpandwoof go-librespot[918]: time="2024-04-28T15:14:33Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Apr 28 15:14:33 warpandwoof go-librespot[918]: time="2024-04-28T15:14:33Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Apr 28 15:14:33 warpandwoof go-librespot[918]: time="2024-04-28T15:14:33Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513" Apr 28 15:14:33 warpandwoof systemd[1]: Started Music Player Daemon. Apr 28 15:14:33 warpandwoof sudo[870]: pam_unix(sudo:session): session closed for user root Apr 28 15:14:33 warpandwoof sudo[881]: pam_unix(sudo:session): session closed for user root Apr 28 15:14:33 warpandwoof sudo[948]: pam_unix(sudo:session): session closed for user root Apr 28 15:14:33 warpandwoof volumio[618]: info: Completed starting Core Plugins Apr 28 15:14:33 warpandwoof volumio[618]: info: ------------------------------------------- Apr 28 15:14:33 warpandwoof volumio[618]: info: ----- MyVolumio plugins startup ---- Apr 28 15:14:33 warpandwoof volumio[618]: info: ------------------------------------------- Apr 28 15:14:33 warpandwoof volumio[618]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 28 15:14:33 warpandwoof volumio[618]: info: MPD running with PID955 Apr 28 15:14:33 warpandwoof volumio[618]: ,establishing connection Apr 28 15:14:33 warpandwoof volumio[618]: error: MPD error: The expression evaluated to a falsy value: Apr 28 15:14:33 warpandwoof volumio[618]: assert.ok(self.idling) Apr 28 15:14:33 warpandwoof volumio[618]: error: The expression evaluated to a falsy value: Apr 28 15:14:33 warpandwoof volumio[618]: assert.ok(self.idling) Apr 28 15:14:33 warpandwoof volumio[618]: error: MPD error: The expression evaluated to a falsy value: Apr 28 15:14:33 warpandwoof volumio[618]: assert.ok(self.idling) Apr 28 15:14:33 warpandwoof volumio[618]: error: The expression evaluated to a falsy value: Apr 28 15:14:33 warpandwoof volumio[618]: assert.ok(self.idling) Apr 28 15:14:33 warpandwoof volumio[618]: error: MPD error: The expression evaluated to a falsy value: Apr 28 15:14:33 warpandwoof volumio[618]: assert.ok(self.idling) Apr 28 15:14:33 warpandwoof volumio[618]: error: The expression evaluated to a falsy value: Apr 28 15:14:33 warpandwoof volumio[618]: assert.ok(self.idling) Apr 28 15:14:33 warpandwoof volumio[618]: error: updateQueue error: null Apr 28 15:14:33 warpandwoof volumio[618]: info: FusionDsp - eq1: Apr 28 15:14:33 warpandwoof volumio[618]: type: Biquad Apr 28 15:14:33 warpandwoof volumio[618]: parameters: Apr 28 15:14:33 warpandwoof volumio[618]: type: Peaking Apr 28 15:14:33 warpandwoof volumio[618]: freq: 25 Apr 28 15:14:33 warpandwoof volumio[618]: q: 1.1 Apr 28 15:14:33 warpandwoof volumio[618]: gain: 0 Apr 28 15:14:33 warpandwoof volumio[618]: eq2: Apr 28 15:14:33 warpandwoof volumio[618]: type: Biquad Apr 28 15:14:33 warpandwoof volumio[618]: parameters: Apr 28 15:14:33 warpandwoof volumio[618]: type: Peaking Apr 28 15:14:33 warpandwoof volumio[618]: freq: 40 Apr 28 15:14:33 warpandwoof volumio[618]: q: 1.1 Apr 28 15:14:33 warpandwoof volumio[618]: gain: 0 Apr 28 15:14:33 warpandwoof volumio[618]: eq3: Apr 28 15:14:33 warpandwoof volumio[618]: type: Biquad Apr 28 15:14:33 warpandwoof volumio[618]: parameters: Apr 28 15:14:33 warpandwoof volumio[618]: type: Peaking Apr 28 15:14:33 warpandwoof volumio[618]: freq: 63 Apr 28 15:14:33 warpandwoof volumio[618]: q: 1.1 Apr 28 15:14:33 warpandwoof volumio[618]: gain: 0 Apr 28 15:14:33 warpandwoof volumio[618]: error: FusionDsp - WebSocket error: [object Object] Apr 28 15:14:34 warpandwoof volumio[618]: info: go-librespot daemon successfully initialized Apr 28 15:14:36 warpandwoof sudo[986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Dominic Rutterford,password=07970692318,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.64/Lossless /mnt/NAS/Dropbox Apr 28 15:14:36 warpandwoof sudo[986]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:14:36 warpandwoof kernel: CIFS: Attempting to mount \\192.168.1.64\Lossless Apr 28 15:14:37 warpandwoof volumio[618]: info: Initializing connection to go-librespot Websocket Apr 28 15:14:37 warpandwoof go-librespot[918]: time="2024-04-28T15:14:37Z" level=debug msg="new websocket client" Apr 28 15:14:37 warpandwoof volumio[618]: info: Connection to go-librespot Websocket established Apr 28 15:14:39 warpandwoof volumio[618]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 28 15:14:40 warpandwoof go-librespot[918]: time="2024-04-28T15:14:40Z" level=debug msg="handling transfer player command from 9fc2c4f39e6bc202ba963f59e4addcefdb4a0f1d" Apr 28 15:14:40 warpandwoof go-librespot[918]: time="2024-04-28T15:14:40Z" level=trace msg="fetched new page 0 with 26 items (list: 26)" Apr 28 15:14:40 warpandwoof go-librespot[918]: time="2024-04-28T15:14:40Z" level=debug msg="loading track spotify:track:2yzPBlE5UXK2sqvnNM9QQ0 (paused: true, position: 1958ms)" Apr 28 15:14:40 warpandwoof go-librespot[918]: time="2024-04-28T15:14:40Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 28 15:14:40 warpandwoof go-librespot[918]: time="2024-04-28T15:14:40Z" level=trace msg="emitting websocket event: will_play" Apr 28 15:14:40 warpandwoof volumio[618]: info: Getting Spotify volume Apr 28 15:14:40 warpandwoof go-librespot[918]: time="2024-04-28T15:14:40Z" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:2yzPBlE5UXK2sqvnNM9QQ0" Apr 28 15:14:40 warpandwoof go-librespot[918]: time="2024-04-28T15:14:40Z" level=debug msg="requested aes key for file bfccd1e3ad859cc83a4533fab8a5baf119556e99, gid: 2yzPBlE5UXK2sqvnNM9QQ0" Apr 28 15:14:40 warpandwoof volumio[618]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2yzPBlE5UXK2sqvnNM9QQ0","play_origin":"playlist/ondemand"}} Apr 28 15:14:40 warpandwoof go-librespot[918]: time="2024-04-28T15:14:40Z" level=debug msg="fetched first chunk of 22, total size is 11136728 bytes" Apr 28 15:14:40 warpandwoof go-librespot[918]: time="2024-04-28T15:14:40Z" level=trace msg="seek to 1958ms (diff: 364ms, samples: 86347, bytes: 52540)" Apr 28 15:14:40 warpandwoof go-librespot[918]: tail: invalid PID: ‘-f’ Apr 28 15:14:40 warpandwoof go-librespot[918]: ALSA lib /home/pi/volumio-hook-plugin/src/pcm_volumiohook.c:104:(_snd_pcm_volumiohook_execute) The PCM fusiondsphook got a non zero return 256 from command tail --pid `pidof camilladsp` -f /dev/null Apr 28 15:14:40 warpandwoof go-librespot[918]: ALSA lib /home/pi/volumio-hook-plugin/src/pcm_volumiohook.c:144:(snd_pcm_volumiohook_close) PCM close called for fusiondsphook Apr 28 15:14:40 warpandwoof go-librespot[918]: time="2024-04-28T15:14:40Z" level=info msg="loaded track \"West End Girls - 2001 Remaster\" (uri: spotify:track:2yzPBlE5UXK2sqvnNM9QQ0, paused: true, position: 1958ms, duration: 285733ms)" Apr 28 15:14:40 warpandwoof go-librespot[918]: time="2024-04-28T15:14:40Z" level=debug msg="fetched chunk 2/21, size: 524288" Apr 28 15:14:41 warpandwoof go-librespot[918]: time="2024-04-28T15:14:41Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 28 15:14:41 warpandwoof go-librespot[918]: time="2024-04-28T15:14:41Z" level=trace msg="emitting websocket event: metadata" Apr 28 15:14:41 warpandwoof go-librespot[918]: time="2024-04-28T15:14:41Z" level=trace msg="emitting websocket event: active" Apr 28 15:14:41 warpandwoof go-librespot[918]: time="2024-04-28T15:14:41Z" level=debug msg="sending successful reply for delaer request" Apr 28 15:14:41 warpandwoof go-librespot[918]: time="2024-04-28T15:14:41Z" level=debug msg="fetched chunk 1/21, size: 524288" Apr 28 15:14:41 warpandwoof go-librespot[918]: time="2024-04-28T15:14:41Z" level=debug msg="fetched chunk 3/21, size: 524288" Apr 28 15:14:41 warpandwoof go-librespot[918]: time="2024-04-28T15:14:41Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 28 15:14:41 warpandwoof go-librespot[918]: time="2024-04-28T15:14:41Z" level=trace msg="emitting websocket event: paused" Apr 28 15:14:41 warpandwoof go-librespot[918]: time="2024-04-28T15:14:41Z" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Apr 28 15:14:41 warpandwoof go-librespot[918]: time="2024-04-28T15:14:41Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Apr 28 15:14:41 warpandwoof go-librespot[918]: time="2024-04-28T15:14:41Z" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Apr 28 15:14:41 warpandwoof go-librespot[918]: time="2024-04-28T15:14:41Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1565" Apr 28 15:14:41 warpandwoof go-librespot[918]: time="2024-04-28T15:14:41Z" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Apr 28 15:14:41 warpandwoof go-librespot[918]: time="2024-04-28T15:14:41Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1335" Apr 28 15:14:42 warpandwoof volumio[618]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2yzPBlE5UXK2sqvnNM9QQ0","name":"West End Girls - 2001 Remaster","artist_names":["Pet Shop Boys"],"album_name":"Please","album_cover_url":"https://i.scdn.co/image/ab67616d00001e025e38730453e8354e9951a8ab","position":1958,"duration":285733,"release_date":"year:1986 month:3 day:24","track_number":2,"disc_number":1}} Apr 28 15:14:42 warpandwoof volumio[618]: SPOTIFY: received: {"type":"active","data":null} Apr 28 15:14:42 warpandwoof volumio[618]: info: Aligning Spotify Volume to Volumio Volume Apr 28 15:14:42 warpandwoof volumio[618]: info: CoreCommandRouter::volumioGetState Apr 28 15:14:42 warpandwoof volumio[618]: info: CorePlayQueue::getTrack 0 Apr 28 15:14:42 warpandwoof volumio[618]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:2yzPBlE5UXK2sqvnNM9QQ0","play_origin":"playlist/ondemand"}} Apr 28 15:14:42 warpandwoof volumio[618]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 15:14:42 warpandwoof volumio[618]: TypeError: Cannot read property 'service' of undefined Apr 28 15:14:42 warpandwoof volumio[618]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:336:50) Apr 28 15:14:42 warpandwoof volumio[618]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:270:18) Apr 28 15:14:42 warpandwoof volumio[618]: at WebSocket.message (/data/plugins/music_service/spop/index.js:189:14) Apr 28 15:14:42 warpandwoof volumio[618]: at WebSocket.emit (events.js:315:20) Apr 28 15:14:42 warpandwoof volumio[618]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1203:20) Apr 28 15:14:42 warpandwoof volumio[618]: at Receiver.emit (events.js:315:20) Apr 28 15:14:42 warpandwoof volumio[618]: at /data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:608:16 Apr 28 15:14:42 warpandwoof volumio[618]: at internal/process/task_queues.js:149:7 Apr 28 15:14:42 warpandwoof volumio[618]: at AsyncResource.runInAsyncScope (async_hooks.js:197:9) Apr 28 15:14:42 warpandwoof volumio[618]: at AsyncResource.runMicrotask (internal/process/task_queues.js:146:8) Apr 28 15:14:42 warpandwoof volumio[618]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 28 15:14:42 warpandwoof volumio[618]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 15:14:42 warpandwoof sudo[1015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-28 15:13 Apr 28 15:14:42 warpandwoof sudo[1015]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"