-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Mon 2025-01-13 11:49:32 CET. -- Jan 13 11:48:47 hifi kernel: Initialise system trusted keyrings Jan 13 11:48:47 hifi kernel: workingset: timestamp_bits=14 max_order=19 bucket_order=5 Jan 13 11:48:47 hifi kernel: zbud: loaded Jan 13 11:48:47 hifi kernel: NFS: Registering the id_resolver key type Jan 13 11:48:47 hifi kernel: Key type id_resolver registered Jan 13 11:48:47 hifi kernel: Key type id_legacy registered Jan 13 11:48:47 hifi kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Jan 13 11:48:47 hifi kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Jan 13 11:48:47 hifi kernel: Key type asymmetric registered Jan 13 11:48:47 hifi kernel: Asymmetric key parser 'x509' registered Jan 13 11:48:47 hifi kernel: bounce: pool size: 64 pages Jan 13 11:48:47 hifi kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Jan 13 11:48:47 hifi kernel: io scheduler mq-deadline registered Jan 13 11:48:47 hifi kernel: io scheduler kyber registered Jan 13 11:48:47 hifi kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: Jan 13 11:48:47 hifi kernel: brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] Jan 13 11:48:47 hifi kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000 Jan 13 11:48:47 hifi kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x007fffffff -> 0x0400000000 Jan 13 11:48:47 hifi kernel: brcm-pcie fd500000.pcie: setting SCB_ACCESS_EN, READ_UR_MODE, MAX_BURST_SIZE Jan 13 11:48:47 hifi kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Jan 13 11:48:47 hifi kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jan 13 11:48:47 hifi kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff]) Jan 13 11:48:47 hifi kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 Jan 13 11:48:47 hifi kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Jan 13 11:48:47 hifi kernel: PCI: bus0: Fast back to back transfers disabled Jan 13 11:48:47 hifi kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Jan 13 11:48:47 hifi kernel: pci_bus 0000:01: supply vpcie3v3 not found, using dummy regulator Jan 13 11:48:47 hifi kernel: pci_bus 0000:01: supply vpcie3v3aux not found, using dummy regulator Jan 13 11:48:47 hifi kernel: pci_bus 0000:01: supply vpcie12v not found, using dummy regulator Jan 13 11:48:47 hifi kernel: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) Jan 13 11:48:47 hifi kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 Jan 13 11:48:47 hifi kernel: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] Jan 13 11:48:47 hifi kernel: pci 0000:01:00.0: PME# supported from D0 D3hot Jan 13 11:48:47 hifi kernel: PCI: bus1: Fast back to back transfers disabled Jan 13 11:48:47 hifi kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Jan 13 11:48:47 hifi kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] Jan 13 11:48:47 hifi kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] Jan 13 11:48:47 hifi kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Jan 13 11:48:47 hifi kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Jan 13 11:48:47 hifi kernel: pci 0000:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Jan 13 11:48:47 hifi kernel: pci 0000:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 Jan 13 11:48:47 hifi kernel: pcieport 0000:00:00.0: enabling device (0000 -> 0002) Jan 13 11:48:47 hifi kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 45 Jan 13 11:48:47 hifi kernel: bcm2708_fb soc:fb: FB found 1 display(s) Jan 13 11:48:47 hifi kernel: Console: switching to colour frame buffer device 80x30 Jan 13 11:48:47 hifi kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Jan 13 11:48:47 hifi kernel: iproc-rng200 fe104000.rng: hwrng registered Jan 13 11:48:47 hifi kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Jan 13 11:48:47 hifi kernel: brd: module loaded Jan 13 11:48:47 hifi kernel: loop: module loaded Jan 13 11:48:47 hifi kernel: Loading iSCSI transport class v2.0-870. Jan 13 11:48:47 hifi kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Jan 13 11:48:47 hifi kernel: Freeing initrd memory: 14316K Jan 13 11:48:47 hifi kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Jan 13 11:48:47 hifi kernel: usbcore: registered new device driver r8152-cfgselector Jan 13 11:48:47 hifi kernel: usbcore: registered new interface driver r8152 Jan 13 11:48:47 hifi kernel: usbcore: registered new interface driver lan78xx Jan 13 11:48:47 hifi kernel: usbcore: registered new interface driver smsc95xx Jan 13 11:48:47 hifi kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Jan 13 11:48:47 hifi kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Jan 13 11:48:47 hifi kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0b00040000000890 Jan 13 11:48:47 hifi kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Jan 13 11:48:47 hifi kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Jan 13 11:48:47 hifi kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Jan 13 11:48:47 hifi kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01 Jan 13 11:48:47 hifi kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 13 11:48:47 hifi kernel: usb usb1: Product: xHCI Host Controller Jan 13 11:48:47 hifi kernel: usb usb1: Manufacturer: Linux 6.1.69-v7l+ xhci-hcd Jan 13 11:48:47 hifi kernel: usb usb1: SerialNumber: 0000:01:00.0 Jan 13 11:48:47 hifi kernel: hub 1-0:1.0: USB hub found Jan 13 11:48:47 hifi kernel: hub 1-0:1.0: 1 port detected Jan 13 11:48:47 hifi kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.01 Jan 13 11:48:47 hifi kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 13 11:48:47 hifi kernel: usb usb2: Product: xHCI Host Controller Jan 13 11:48:47 hifi kernel: usb usb2: Manufacturer: Linux 6.1.69-v7l+ xhci-hcd Jan 13 11:48:47 hifi kernel: usb usb2: SerialNumber: 0000:01:00.0 Jan 13 11:48:47 hifi kernel: hub 2-0:1.0: USB hub found Jan 13 11:48:47 hifi kernel: hub 2-0:1.0: 4 ports detected Jan 13 11:48:47 hifi kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Jan 13 11:48:47 hifi kernel: dwc_otg: FIQ enabled Jan 13 11:48:47 hifi kernel: dwc_otg: NAK holdoff enabled Jan 13 11:48:47 hifi kernel: dwc_otg: FIQ split-transaction FSM enabled Jan 13 11:48:47 hifi kernel: Module dwc_common_port init Jan 13 11:48:47 hifi kernel: usbcore: registered new interface driver uas Jan 13 11:48:47 hifi kernel: usbcore: registered new interface driver usb-storage Jan 13 11:48:47 hifi kernel: mousedev: PS/2 mouse device common for all mice Jan 13 11:48:47 hifi kernel: sdhci: Secure Digital Host Controller Interface driver Jan 13 11:48:47 hifi kernel: sdhci: Copyright(c) Pierre Ossman Jan 13 11:48:47 hifi kernel: sdhci-pltfm: SDHCI platform and OF driver helper Jan 13 11:48:47 hifi kernel: ledtrig-cpu: registered to indicate activity on CPUs Jan 13 11:48:47 hifi kernel: hid: raw HID events driver (C) Jiri Kosina Jan 13 11:48:47 hifi kernel: usbcore: registered new interface driver usbhid Jan 13 11:48:47 hifi kernel: usbhid: USB HID core driver Jan 13 11:48:47 hifi kernel: Initializing XFRM netlink socket Jan 13 11:48:47 hifi kernel: NET: Registered PF_PACKET protocol family Jan 13 11:48:47 hifi kernel: Key type dns_resolver registered Jan 13 11:48:47 hifi kernel: Registering SWP/SWPB emulation handler Jan 13 11:48:47 hifi kernel: registered taskstats version 1 Jan 13 11:48:47 hifi kernel: Loading compiled-in X.509 certificates Jan 13 11:48:47 hifi kernel: Key type .fscrypt registered Jan 13 11:48:47 hifi kernel: Key type fscrypt-provisioning registered Jan 13 11:48:47 hifi kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Jan 13 11:48:47 hifi kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Jan 13 11:48:47 hifi kernel: fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 51, base_baud = 0) is a PL011 rev2 Jan 13 11:48:47 hifi kernel: serial serial0: tty port ttyAMA1 registered Jan 13 11:48:47 hifi kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Jan 13 11:48:47 hifi kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Jan 13 11:48:47 hifi kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Jan 13 11:48:47 hifi kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Jan 13 11:48:47 hifi kernel: of_cfs_init Jan 13 11:48:47 hifi kernel: of_cfs_init: OK Jan 13 11:48:47 hifi kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Jan 13 11:48:47 hifi kernel: Freeing unused kernel image (initmem) memory: 2048K Jan 13 11:48:47 hifi kernel: mmc1: new high speed SDIO card at address 0001 Jan 13 11:48:47 hifi kernel: Run /init as init process Jan 13 11:48:47 hifi kernel: with arguments: Jan 13 11:48:47 hifi kernel: /init Jan 13 11:48:47 hifi kernel: splash Jan 13 11:48:47 hifi kernel: nodebug Jan 13 11:48:47 hifi kernel: with environment: Jan 13 11:48:47 hifi kernel: HOME=/ Jan 13 11:48:47 hifi kernel: TERM=linux Jan 13 11:48:47 hifi kernel: imgpart=UUID=0c3a77ec-ae84-4f1b-8028-c61cc251ffe5 Jan 13 11:48:47 hifi kernel: imgfile=/volumio_current.sqsh Jan 13 11:48:47 hifi kernel: bootpart=UUID=41CA-9A8B Jan 13 11:48:47 hifi kernel: datapart=UUID=0324d53a-d066-482b-b659-eb98af2b49e7 Jan 13 11:48:47 hifi kernel: uuidconfig=cmdline.txt Jan 13 11:48:47 hifi kernel: pcie_aspm=off Jan 13 11:48:47 hifi kernel: bootdelay=7 Jan 13 11:48:47 hifi kernel: use_kmsg=no Jan 13 11:48:47 hifi kernel: mmc0: new ultra high speed DDR50 SDHC card at address aaaa Jan 13 11:48:47 hifi kernel: mmcblk0: mmc0:aaaa SL32G 29.7 GiB Jan 13 11:48:47 hifi kernel: mmcblk0: p1 p2 p3 Jan 13 11:48:47 hifi kernel: mmcblk0: mmc0:aaaa SL32G 29.7 GiB (quirks 0x00004000) Jan 13 11:48:47 hifi kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Jan 13 11:48:47 hifi kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Jan 13 11:48:47 hifi kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Jan 13 11:48:47 hifi kernel: usb 1-1: Product: USB2.0 Hub Jan 13 11:48:47 hifi kernel: hub 1-1:1.0: USB hub found Jan 13 11:48:47 hifi kernel: hub 1-1:1.0: 4 ports detected Jan 13 11:48:47 hifi kernel: fuse: init (API version 7.37) Jan 13 11:48:47 hifi kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jan 13 11:48:47 hifi kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jan 13 11:48:47 hifi kernel: EXT4-fs (mmcblk0p2): recovery complete Jan 13 11:48:47 hifi kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none. Jan 13 11:48:47 hifi kernel: loop0: detected capacity change from 0 to 983360 Jan 13 11:48:47 hifi kernel: EXT4-fs (mmcblk0p3): recovery complete Jan 13 11:48:47 hifi kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Quota mode: none. Jan 13 11:48:47 hifi systemd[1]: System time before build time, advancing clock. Jan 13 11:48:47 hifi kernel: NET: Registered PF_INET6 protocol family Jan 13 11:48:47 hifi kernel: Segment Routing with IPv6 Jan 13 11:48:47 hifi kernel: In-situ OAM (IOAM) with IPv6 Jan 13 11:48:47 hifi systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Jan 13 11:48:47 hifi systemd[1]: Detected architecture arm. Jan 13 11:48:47 hifi systemd[1]: Set hostname to . Jan 13 11:48:47 hifi systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 13 11:48:47 hifi systemd[1]: Started Forward Password Requests to Wall Directory Watch. Jan 13 11:48:47 hifi systemd[1]: Listening on initctl Compatibility Named Pipe. Jan 13 11:48:47 hifi systemd[1]: Created slice User and Session Slice. Jan 13 11:48:47 hifi systemd[1]: Listening on RPCbind Server Activation Socket. Jan 13 11:48:47 hifi systemd[1]: Reached target System Time Synchronized. Jan 13 11:48:47 hifi systemd[1]: Reached target Slices. Jan 13 11:48:47 hifi kernel: i2c_dev: i2c /dev entries driver Jan 13 11:48:47 hifi systemd-journald[322]: Journal started Jan 13 11:48:47 hifi systemd-journald[322]: Runtime journal (/run/log/journal/d5012141e8efab18115b4334669e938a) is 7.5M, max 30.0M, 22.5M free. Jan 13 11:48:47 hifi systemd-modules-load[318]: Inserted module 'i2c_dev' Jan 13 11:48:47 hifi fake-hwclock[320]: Mon Jan 13 10:48:47 UTC 2025 Jan 13 11:48:47 hifi systemd[1]: dynamicswap.service: Succeeded. Jan 13 11:48:47 hifi systemd[1]: Started Create System Users. Jan 13 11:48:47 hifi systemd[1]: Starting Create Static Device Nodes in /dev... Jan 13 11:48:47 hifi systemd[1]: Started Create Static Device Nodes in /dev. Jan 13 11:48:47 hifi systemd[1]: Starting udev Kernel Device Manager... Jan 13 11:48:47 hifi systemd[1]: Reached target Local File Systems (Pre). Jan 13 11:48:47 hifi systemd[1]: Mounting /var/spool/cups... Jan 13 11:48:47 hifi systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jan 13 11:48:47 hifi systemd[1]: Mounting /tmp... Jan 13 11:48:47 hifi systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jan 13 11:48:47 hifi systemd[1]: Mounting /var/log... Jan 13 11:48:47 hifi systemd[1]: Started udev Coldplug all Devices. Jan 13 11:48:47 hifi systemd[1]: Mounted /var/spool/cups. Jan 13 11:48:47 hifi systemd[1]: Mounted /tmp. Jan 13 11:48:47 hifi systemd[1]: Mounted /var/log. Jan 13 11:48:47 hifi systemd[1]: Starting Flush Journal to Persistent Storage... Jan 13 11:48:47 hifi systemd[1]: Mounting /var/spool/cups/tmp... Jan 13 11:48:47 hifi systemd-udevd[345]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jan 13 11:48:47 hifi systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jan 13 11:48:47 hifi systemd[1]: Mounted /var/spool/cups/tmp. Jan 13 11:48:47 hifi systemd[1]: Started Helper to synchronize boot up for ifupdown. Jan 13 11:48:47 hifi systemd-journald[322]: Runtime journal (/run/log/journal/d5012141e8efab18115b4334669e938a) is 7.5M, max 30.0M, 22.5M free. Jan 13 11:48:47 hifi systemd[1]: Started Flush Journal to Persistent Storage. Jan 13 11:48:47 hifi systemd[1]: Started udev Kernel Device Manager. Jan 13 11:48:47 hifi systemd[1]: Starting Show Plymouth Boot Screen... Jan 13 11:48:47 hifi systemd[1]: plymouth-start.service: Succeeded. Jan 13 11:48:47 hifi systemd[1]: Started Show Plymouth Boot Screen. Jan 13 11:48:47 hifi systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 13 11:48:47 hifi systemd[1]: Reached target Local Encrypted Volumes. Jan 13 11:48:47 hifi systemd[1]: Reached target Paths. Jan 13 11:48:47 hifi systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jan 13 11:48:47 hifi systemd-udevd[360]: Using default interface naming scheme 'v240'. Jan 13 11:48:47 hifi systemd-udevd[360]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 13 11:48:47 hifi kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jan 13 11:48:47 hifi kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jan 13 11:48:47 hifi kernel: [vc_sm_connected_init]: start Jan 13 11:48:47 hifi kernel: [vc_sm_connected_init]: installed successfully Jan 13 11:48:47 hifi kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Jan 13 11:48:47 hifi kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Jan 13 11:48:47 hifi kernel: mc: Linux media interface: v0.10 Jan 13 11:48:48 hifi kernel: videodev: Linux video capture interface: v2.00 Jan 13 11:48:48 hifi kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 13 11:48:48 hifi kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Jan 13 11:48:48 hifi kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jan 13 11:48:48 hifi kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jan 13 11:48:48 hifi kernel: rpivid feb10000.codec: Device registered as /dev/video19 Jan 13 11:48:48 hifi kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jan 13 11:48:48 hifi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jan 13 11:48:48 hifi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jan 13 11:48:48 hifi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jan 13 11:48:48 hifi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jan 13 11:48:48 hifi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jan 13 11:48:48 hifi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jan 13 11:48:48 hifi kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jan 13 11:48:48 hifi kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jan 13 11:48:48 hifi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Jan 13 11:48:48 hifi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Jan 13 11:48:48 hifi kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jan 13 11:48:48 hifi kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jan 13 11:48:48 hifi kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jan 13 11:48:48 hifi kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jan 13 11:48:48 hifi kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jan 13 11:48:48 hifi kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jan 13 11:48:48 hifi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Jan 13 11:48:48 hifi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Jan 13 11:48:48 hifi kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Jan 13 11:48:48 hifi kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Jan 13 11:48:48 hifi kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Jan 13 11:48:48 hifi kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Jan 13 11:48:48 hifi kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jan 13 11:48:48 hifi kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jan 13 11:48:48 hifi kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jan 13 11:48:48 hifi kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jan 13 11:48:48 hifi kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jan 13 11:48:48 hifi systemd[1]: Found device /dev/disk/by-uuid/41CA-9A8B. Jan 13 11:48:48 hifi kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jan 13 11:48:48 hifi kernel: Bluetooth: Core ver 2.22 Jan 13 11:48:48 hifi kernel: NET: Registered PF_BLUETOOTH protocol family Jan 13 11:48:48 hifi kernel: Bluetooth: HCI device and connection manager initialized Jan 13 11:48:48 hifi kernel: Bluetooth: HCI socket layer initialized Jan 13 11:48:48 hifi kernel: Bluetooth: L2CAP socket layer initialized Jan 13 11:48:48 hifi kernel: Bluetooth: SCO socket layer initialized Jan 13 11:48:48 hifi kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Jan 13 11:48:48 hifi kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Jan 13 11:48:48 hifi kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jan 13 11:48:48 hifi systemd[1]: Starting File System Check on /dev/disk/by-uuid/41CA-9A8B... Jan 13 11:48:48 hifi kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jan 13 11:48:48 hifi kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Jan 13 11:48:48 hifi systemd[1]: Started File System Check Daemon to report status. Jan 13 11:48:48 hifi kernel: Bluetooth: HCI UART driver ver 2.3 Jan 13 11:48:48 hifi kernel: Bluetooth: HCI UART protocol H4 registered Jan 13 11:48:48 hifi kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jan 13 11:48:48 hifi kernel: Bluetooth: HCI UART protocol Broadcom registered Jan 13 11:48:48 hifi kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Jan 13 11:48:48 hifi kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Jan 13 11:48:48 hifi kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jan 13 11:48:48 hifi kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Jan 13 11:48:48 hifi kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Jan 13 11:48:48 hifi kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jan 13 11:48:48 hifi kernel: usbcore: registered new interface driver brcmfmac Jan 13 11:48:49 hifi kernel: uart-pl011 fe201000.serial: no DMA platform data Jan 13 11:48:49 hifi systemd-udevd[357]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 13 11:48:49 hifi kernel: Bluetooth: hci0: BCM: chip id 107 Jan 13 11:48:49 hifi kernel: Bluetooth: hci0: BCM: features 0x2f Jan 13 11:48:49 hifi kernel: Bluetooth: hci0: BCM4345C0 Jan 13 11:48:49 hifi kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Jan 13 11:48:49 hifi kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Jan 13 11:48:49 hifi systemd[1]: Found device /sys/subsystem/net/devices/eth0. Jan 13 11:48:49 hifi kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov 1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60 Jan 13 11:48:49 hifi systemd-fsck[431]: fsck.fat 4.1 (2017-01-24) Jan 13 11:48:49 hifi systemd-fsck[431]: 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Jan 13 11:48:49 hifi systemd-fsck[431]: Automatically removing dirty bit. Jan 13 11:48:49 hifi systemd-fsck[431]: Performing changes. Jan 13 11:48:49 hifi systemd-fsck[431]: /dev/mmcblk0p1: 446 files, 135819/184582 clusters Jan 13 11:48:49 hifi systemd[1]: Started File System Check on /dev/disk/by-uuid/41CA-9A8B. Jan 13 11:48:49 hifi systemd-udevd[357]: Using default interface naming scheme 'v240'. Jan 13 11:48:49 hifi systemd-udevd[357]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 13 11:48:49 hifi kernel: wm8804 1-003b: revision E Jan 13 11:48:49 hifi systemd-udevd[363]: Process '/sbin/crda' failed with exit code 255. Jan 13 11:48:50 hifi kernel: Bluetooth: hci0: BCM: features 0x2f Jan 13 11:48:50 hifi kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Jan 13 11:48:50 hifi kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Jan 13 11:48:50 hifi systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Jan 13 11:48:50 hifi systemd[1]: Created slice system-bthelper.slice. Jan 13 11:48:50 hifi systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jan 13 11:48:50 hifi systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jan 13 11:48:50 hifi systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jan 13 11:48:50 hifi systemd[1]: Starting Show Plymouth Boot Screen... Jan 13 11:48:50 hifi systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jan 13 11:48:50 hifi systemd[1]: Mounting /boot... Jan 13 11:48:50 hifi systemd[1]: Starting Load/Save RF Kill Switch Status... Jan 13 11:48:50 hifi systemd[1]: plymouth-start.service: Succeeded. Jan 13 11:48:50 hifi systemd[1]: Started Show Plymouth Boot Screen. Jan 13 11:48:50 hifi systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 13 11:48:50 hifi systemd[1]: Mounted /boot. Jan 13 11:48:50 hifi systemd[1]: Reached target Local File Systems. Jan 13 11:48:50 hifi systemd[1]: Starting Create Volatile Files and Directories... Jan 13 11:48:50 hifi systemd[1]: Starting Preprocess NFS configuration... Jan 13 11:48:50 hifi systemd[1]: Started ifup for wlan0. Jan 13 11:48:50 hifi systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jan 13 11:48:50 hifi systemd[1]: Starting Raise network interfaces... Jan 13 11:48:50 hifi systemd[1]: Started ifup for eth0. Jan 13 11:48:50 hifi systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jan 13 11:48:50 hifi systemd[1]: Started Load/Save RF Kill Switch Status. Jan 13 11:48:50 hifi systemd[1]: Received SIGRTMIN+20 from PID 207 (plymouthd). Jan 13 11:48:50 hifi systemd[1]: nfs-config.service: Succeeded. Jan 13 11:48:50 hifi systemd[1]: Started Preprocess NFS configuration. Jan 13 11:48:50 hifi systemd[1]: plymouth-read-write.service: Succeeded. Jan 13 11:48:50 hifi systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jan 13 11:48:50 hifi systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jan 13 11:48:50 hifi systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jan 13 11:48:50 hifi systemd[1]: Reached target NFS client services. Jan 13 11:48:50 hifi systemd[1]: Started Create Volatile Files and Directories. Jan 13 11:48:51 hifi systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jan 13 11:48:51 hifi systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jan 13 11:48:51 hifi systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jan 13 11:48:51 hifi systemd[1]: Starting RPC bind portmap service... Jan 13 11:48:51 hifi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 13 11:48:51 hifi systemd[1]: Started Update UTMP about System Boot/Shutdown. Jan 13 11:48:51 hifi systemd[1]: Reached target System Initialization. Jan 13 11:48:51 hifi systemd[1]: Started Daily Cleanup of Temporary Directories. Jan 13 11:48:51 hifi systemd[1]: apt-daily.timer: Not using persistent file timestamp Wed 2025-01-15 13:01:48 CET as it is in the future. Jan 13 11:48:51 hifi systemd[1]: Started Daily apt download activities. Jan 13 11:48:51 hifi systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Wed 2025-01-15 13:01:48 CET as it is in the future. Jan 13 11:48:51 hifi systemd[1]: Started Daily apt upgrade and clean activities. Jan 13 11:48:51 hifi systemd[1]: Reached target Timers. Jan 13 11:48:51 hifi systemd[1]: Listening on triggerhappy.socket. Jan 13 11:48:51 hifi kernel: 8021q: 802.1Q VLAN Support v1.8 Jan 13 11:48:51 hifi systemd[1]: Listening on D-Bus System Message Bus Socket. Jan 13 11:48:51 hifi systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jan 13 11:48:51 hifi systemd[1]: Reached target Sockets. Jan 13 11:48:51 hifi systemd[1]: Reached target Basic System. Jan 13 11:48:51 hifi systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jan 13 11:48:51 hifi systemd[1]: Started Volumio Log Rotation Service. Jan 13 11:48:51 hifi systemd[1]: Started volumio-remote-updater.service. Jan 13 11:48:51 hifi systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jan 13 11:48:51 hifi systemd[1]: Starting dhcpcd on all interfaces... Jan 13 11:48:51 hifi systemd[1]: Started D-Bus System Message Bus. Jan 13 11:48:51 hifi systemd[1]: Starting Login Service... Jan 13 11:48:51 hifi systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jan 13 11:48:51 hifi dhcpcd[615]: Not running dhcpcd because /etc/network/interfaces Jan 13 11:48:51 hifi dhcpcd[615]: defines some interfaces that will use a Jan 13 11:48:51 hifi dhcpcd[615]: DHCP client or static address Jan 13 11:48:51 hifi systemd[1]: Starting triggerhappy global hotkey daemon... Jan 13 11:48:51 hifi systemd[1]: Started UPnP Renderer front-end to MPD. Jan 13 11:48:51 hifi systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jan 13 11:48:51 hifi systemd[1]: Starting Wireless Services... Jan 13 11:48:51 hifi systemd[1]: Started Volumio Iptables Module. Jan 13 11:48:51 hifi systemd[1]: Starting Configure Bluetooth Modems connected by UART... Jan 13 11:48:51 hifi thd[624]: Unable to parse trigger line: Jan 13 11:48:51 hifi thd[624]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jan 13 11:48:51 hifi thd[624]: Unable to parse trigger line: Jan 13 11:48:51 hifi systemd[1]: Started Manage Sound Card State (restore and store). Jan 13 11:48:51 hifi thd[624]: Found socket passed from systemd Jan 13 11:48:51 hifi systemd[1]: Starting WPA supplicant... Jan 13 11:48:51 hifi alsactl[635]: alsactl 1.1.8 daemon started Jan 13 11:48:51 hifi systemd[1]: Starting Save/Restore Sound Card State... Jan 13 11:48:51 hifi systemd[1]: Started RPC bind portmap service. Jan 13 11:48:51 hifi systemd[1]: Started triggerhappy global hotkey daemon. Jan 13 11:48:51 hifi systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Jan 13 11:48:51 hifi systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Jan 13 11:48:51 hifi systemd[1]: Failed to start dhcpcd on all interfaces. Jan 13 11:48:51 hifi systemd-logind[621]: New seat seat0. Jan 13 11:48:51 hifi systemd[1]: Started Save/Restore Sound Card State. Jan 13 11:48:51 hifi systemd[1]: Reached target Sound Card. Jan 13 11:48:51 hifi systemd[1]: Reached target RPC Port Mapper. Jan 13 11:48:51 hifi systemd[1]: Reached target Remote File Systems (Pre). Jan 13 11:48:51 hifi systemd[1]: Reached target Remote File Systems. Jan 13 11:48:51 hifi avahi-daemon[623]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jan 13 11:48:51 hifi avahi-daemon[623]: Successfully dropped root privileges. Jan 13 11:48:51 hifi systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jan 13 11:48:51 hifi avahi-daemon[623]: avahi-daemon 0.7 starting up. Jan 13 11:48:51 hifi systemd[1]: hciuart.service: Succeeded. Jan 13 11:48:51 hifi systemd[1]: Started Configure Bluetooth Modems connected by UART. Jan 13 11:48:51 hifi kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Jan 13 11:48:51 hifi kernel: bcmgenet fd580000.ethernet eth0: Link is Down Jan 13 11:48:51 hifi dhcpcd[566]: eth0: waiting for carrier Jan 13 11:48:51 hifi sh[525]: eth0: waiting for carrier Jan 13 11:48:51 hifi systemd[1]: Starting Raspberry Pi bluetooth helper... Jan 13 11:48:51 hifi sh[517]: wlan0=wlan0 Jan 13 11:48:52 hifi bthelper[668]: Raspberry Pi BDADDR already set Jan 13 11:48:52 hifi systemd[1]: Started Login Service. Jan 13 11:48:52 hifi avahi-daemon[623]: Successfully called chroot(). Jan 13 11:48:52 hifi avahi-daemon[623]: Successfully dropped remaining capabilities. Jan 13 11:48:52 hifi systemd[1]: Started Raspberry Pi bluetooth helper. Jan 13 11:48:52 hifi wpa_supplicant[636]: Successfully initialized wpa_supplicant Jan 13 11:48:52 hifi avahi-daemon[623]: Loading service file /services/volumio.service. Jan 13 11:48:52 hifi avahi-daemon[623]: Network interface enumeration completed. Jan 13 11:48:52 hifi avahi-daemon[623]: Server startup complete. Host name is hifi.local. Local service cookie is 1277000574. Jan 13 11:48:52 hifi avahi-daemon[623]: Service "Hifi" (/services/volumio.service) successfully established. Jan 13 11:48:52 hifi systemd[1]: Started Raise network interfaces. Jan 13 11:48:52 hifi systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jan 13 11:48:52 hifi systemd[1]: Started WPA supplicant. Jan 13 11:48:52 hifi systemd[1]: Reached target Network. Jan 13 11:48:52 hifi systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jan 13 11:48:52 hifi systemd[1]: Reached target Network is Online. Jan 13 11:48:52 hifi volumio-remote-updater[613]: Error: No active session Jan 13 11:48:52 hifi volumio-remote-updater[613]: [2025-01-13 11:48:52] [info] asio async_connect error: system:111 (Connection refused) Jan 13 11:48:52 hifi volumio-remote-updater[613]: [2025-01-13 11:48:52] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 13 11:48:52 hifi volumio-remote-updater[613]: [2025-01-13 11:48:52] [error] handle_connect error: Underlying Transport Error Jan 13 11:48:52 hifi systemd[1]: Starting LSB: Brings up/down network automatically... Jan 13 11:48:52 hifi systemd[1]: Starting Network Time Service... Jan 13 11:48:52 hifi systemd[1]: Starting Samba NMB Daemon... Jan 13 11:48:52 hifi systemd[1]: Starting /etc/rc.local Compatibility... Jan 13 11:48:52 hifi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 13 11:48:52 hifi volumio[628]: Could not open config: /tmp/upmpdcli.conf Jan 13 11:48:52 hifi systemd[1]: Starting Permit User Sessions... Jan 13 11:48:52 hifi systemd[1]: Starting Bluetooth service... Jan 13 11:48:52 hifi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 13 11:48:53 hifi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 13 11:48:53 hifi systemd[1]: Started /etc/rc.local Compatibility. Jan 13 11:48:53 hifi systemd[1]: Started Permit User Sessions. Jan 13 11:48:53 hifi systemd[1]: Starting Hold until boot process finishes up... Jan 13 11:48:53 hifi systemd[1]: Starting Terminate Plymouth Boot Screen... Jan 13 11:48:53 hifi systemd[1]: Received SIGRTMIN+21 from PID 207 (plymouthd). Jan 13 11:48:53 hifi loadcpufreq[654]: Loading cpufreq kernel modules...done (none). Jan 13 11:48:53 hifi bluetoothd[723]: Bluetooth daemon 5.50 Jan 13 11:48:53 hifi systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jan 13 11:48:53 hifi ntpd[714]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 13 11:48:53 hifi ntpd[714]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jan 13 11:48:53 hifi systemd[1]: iptables.service: Succeeded. Jan 13 11:48:53 hifi systemd[1]: Started Network Time Service. Jan 13 11:48:53 hifi systemd[1]: plymouth-quit-wait.service: Succeeded. Jan 13 11:48:53 hifi systemd[1]: Started Hold until boot process finishes up. Jan 13 11:48:53 hifi systemd[1]: plymouth-quit.service: Succeeded. Jan 13 11:48:53 hifi bluetoothd[723]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jan 13 11:48:53 hifi ntpd[755]: proto: precision = 2.148 usec (-19) Jan 13 11:48:53 hifi systemd[1]: Started Terminate Plymouth Boot Screen. Jan 13 11:48:53 hifi systemd[1]: Received SIGRTMIN+21 from PID 207 (n/a). Jan 13 11:48:53 hifi systemd[1]: Started Bluetooth service. Jan 13 11:48:53 hifi systemd[1]: Reached target Bluetooth. Jan 13 11:48:53 hifi ntpd[755]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 13 11:48:53 hifi ntpd[755]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 13 11:48:53 hifi ntpd[755]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 17 days ago Jan 13 11:48:53 hifi ntpd[755]: Listen and drop on 0 v6wildcard [::]:123 Jan 13 11:48:53 hifi ntpd[755]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 13 11:48:53 hifi ntpd[755]: Listen normally on 2 lo 127.0.0.1:123 Jan 13 11:48:53 hifi ntpd[755]: Listening on routing socket on fd #19 for interface updates Jan 13 11:48:53 hifi ntpd[755]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 13 11:48:53 hifi ntpd[755]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 13 11:48:53 hifi bluetoothd[723]: Starting SDP server Jan 13 11:48:53 hifi bluetoothd[723]: Excluding (cli) sap Jan 13 11:48:53 hifi ifplugd(eth0)[765]: ifplugd 0.28 initializing. Jan 13 11:48:53 hifi ifplugd(eth0)[765]: Using interface eth0/D8:3A:DD:9C:2B:AE with driver (version: 6.1.69-v7l+) Jan 13 11:48:53 hifi ifplugd(eth0)[765]: Using detection mode: SIOCETHTOOL Jan 13 11:48:53 hifi ifplugd(eth0)[765]: Initialization complete, link beat not detected. Jan 13 11:48:53 hifi systemd[1]: Started Getty on tty1. Jan 13 11:48:53 hifi systemd[1]: Reached target Login Prompts. Jan 13 11:48:53 hifi systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jan 13 11:48:53 hifi ifplugd[708]: Network Interface Plugging Daemon...start eth0...done. Jan 13 11:48:53 hifi systemd[1]: Started LSB: Brings up/down network automatically. Jan 13 11:48:53 hifi kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jan 13 11:48:53 hifi kernel: Bluetooth: BNEP filters: protocol multicast Jan 13 11:48:53 hifi kernel: Bluetooth: BNEP socket layer initialized Jan 13 11:48:53 hifi bluetoothd[723]: Bluetooth management interface 1.22 initialized Jan 13 11:48:53 hifi dbus-daemon[617]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=723 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jan 13 11:48:53 hifi kernel: Bluetooth: MGMT ver 1.22 Jan 13 11:48:53 hifi bluetoothd[723]: Failed to set privacy: Rejected (0x0b) Jan 13 11:48:53 hifi cpufrequtils[777]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jan 13 11:48:53 hifi systemd[1]: Started LSB: set CPUFreq kernel parameters. Jan 13 11:48:53 hifi systemd[1]: Starting Hostname Service... Jan 13 11:48:53 hifi dbus-daemon[617]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 13 11:48:53 hifi systemd[1]: Started Hostname Service. Jan 13 11:48:54 hifi haveged[554]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jan 13 11:48:54 hifi haveged[554]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jan 13 11:48:54 hifi haveged[554]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00393 Jan 13 11:48:54 hifi haveged[554]: haveged: fills: 0, generated: 0 Jan 13 11:48:54 hifi nmbd[732]: [2025/01/13 11:48:54.183496, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 13 11:48:54 hifi nmbd[732]: started asyncdns process 796 Jan 13 11:48:54 hifi nmbd[732]: [2025/01/13 11:48:54.186576, 0] ../lib/util/become_daemon.c:149(daemon_status) Jan 13 11:48:54 hifi nmbd[732]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jan 13 11:48:54 hifi nmbd[732]: [2025/01/13 11:48:54.187137, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jan 13 11:48:54 hifi nmbd[732]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jan 13 11:48:54 hifi ntpd[755]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 13 11:48:54 hifi wireless.js[629]: Cleaning previous... Jan 13 11:48:54 hifi sudo[804]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 13 11:48:54 hifi sudo[804]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:48:54 hifi sudo[804]: pam_unix(sudo:session): session closed for user root Jan 13 11:48:54 hifi sudo[806]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 13 11:48:54 hifi sudo[806]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:48:55 hifi sudo[806]: pam_unix(sudo:session): session closed for user root Jan 13 11:48:55 hifi wireless.js[629]: Stopped aP Jan 13 11:48:55 hifi sudo[813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 13 11:48:55 hifi sudo[813]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:48:55 hifi sudo[813]: pam_unix(sudo:session): session closed for user root Jan 13 11:48:55 hifi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 13 11:48:55 hifi sudo[815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jan 13 11:48:55 hifi sudo[815]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:48:55 hifi sudo[815]: pam_unix(sudo:session): session closed for user root Jan 13 11:48:55 hifi ntpd[755]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 13 11:48:55 hifi sudo[822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 13 11:48:55 hifi sudo[822]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:48:55 hifi sudo[822]: pam_unix(sudo:session): session closed for user root Jan 13 11:48:55 hifi sudo[824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jan 13 11:48:55 hifi sudo[824]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:48:55 hifi systemd[1]: systemd-rfkill.service: Succeeded. Jan 13 11:48:56 hifi ntpd[755]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 13 11:48:57 hifi bthelper[668]: Changing power off succeeded Jan 13 11:48:57 hifi ntpd[755]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 13 11:48:57 hifi bthelper[668]: [CHG] Controller D8:3A:DD:9C:2B:B2 Class: 0x0000041c Jan 13 11:48:57 hifi bthelper[668]: Changing power on succeeded Jan 13 11:48:57 hifi volumio-remote-updater[613]: [2025-01-13 11:48:57] [info] asio async_connect error: system:111 (Connection refused) Jan 13 11:48:57 hifi volumio-remote-updater[613]: [2025-01-13 11:48:57] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 13 11:48:57 hifi volumio-remote-updater[613]: [2025-01-13 11:48:57] [error] handle_connect error: Underlying Transport Error Jan 13 11:48:58 hifi sudo[824]: pam_unix(sudo:session): session closed for user root Jan 13 11:48:58 hifi wireless.js[629]: SETTING APPROPRIATE REG DOMAIN: FO Jan 13 11:48:58 hifi sudo[835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 13 11:48:58 hifi sudo[835]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:48:58 hifi sudo[835]: pam_unix(sudo:session): session closed for user root Jan 13 11:48:58 hifi sudo[837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set FO Jan 13 11:48:58 hifi sudo[837]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:48:58 hifi sudo[837]: pam_unix(sudo:session): session closed for user root Jan 13 11:48:58 hifi wireless.js[629]: SUCCESSFULLY SET NEW REGDOMAIN: FO Jan 13 11:48:58 hifi wireless.js[629]: Start wireless flow Jan 13 11:48:58 hifi wireless.js[629]: Stopped hotspot (if there).. Jan 13 11:48:58 hifi systemd-udevd[365]: Process '/sbin/crda' failed with exit code 255. Jan 13 11:48:58 hifi sudo[843]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 13 11:48:58 hifi sudo[843]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:48:58 hifi sudo[843]: pam_unix(sudo:session): session closed for user root Jan 13 11:48:58 hifi sudo[845]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 13 11:48:58 hifi sudo[845]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:48:58 hifi sudo[845]: pam_unix(sudo:session): session closed for user root Jan 13 11:48:58 hifi wireless.js[629]: DHCP IP Jan 13 11:48:58 hifi wireless.js[629]: Start ap Jan 13 11:48:58 hifi wpa_supplicant[847]: Successfully initialized wpa_supplicant Jan 13 11:48:58 hifi sudo[848]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jan 13 11:48:58 hifi sudo[848]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:48:58 hifi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 13 11:48:58 hifi dhcpcd[849]: dev: loaded udev Jan 13 11:48:58 hifi dhcpcd-run-hooks[867]: wlan0: starting wpa_supplicant Jan 13 11:48:59 hifi dhcpcd-run-hooks[872]: wlan0: failed to start wpa_supplicant Jan 13 11:48:59 hifi dhcpcd-run-hooks[873]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Jan 13 11:48:59 hifi dhcpcd[849]: wlan0: connected to Access Point `' Jan 13 11:48:59 hifi dhcpcd[849]: no interfaces have a carrier Jan 13 11:48:59 hifi dhcpcd[849]: forked to background, child pid 883 Jan 13 11:48:59 hifi dhcpcd[883]: eth0: waiting for carrier Jan 13 11:48:59 hifi dhcpcd[883]: wlan0: waiting for carrier Jan 13 11:48:59 hifi sudo[848]: pam_unix(sudo:session): session closed for user root Jan 13 11:48:59 hifi wireless.js[629]: trying... Jan 13 11:48:59 hifi sudo[885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 13 11:48:59 hifi sudo[885]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:48:59 hifi sudo[885]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:00 hifi wireless.js[629]: trying... Jan 13 11:49:00 hifi sudo[888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 13 11:49:00 hifi sudo[888]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:00 hifi sudo[888]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:01 hifi wpa_supplicant[871]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jan 13 11:49:01 hifi wpa_supplicant[871]: wlan0: Trying to associate with SSID 'boss' Jan 13 11:49:01 hifi wireless.js[629]: trying... Jan 13 11:49:01 hifi sudo[891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 13 11:49:01 hifi sudo[891]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:01 hifi sudo[891]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:02 hifi wireless.js[629]: trying... Jan 13 11:49:02 hifi sudo[894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 13 11:49:02 hifi sudo[894]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:02 hifi sudo[894]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:03 hifi wireless.js[629]: trying... Jan 13 11:49:03 hifi sudo[897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 13 11:49:03 hifi sudo[897]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:03 hifi sudo[897]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:04 hifi dhcpcd[883]: wlan0: carrier acquired Jan 13 11:49:04 hifi dhcpcd[883]: wlan0: connected to Access Point `boss' Jan 13 11:49:04 hifi wpa_supplicant[871]: wlan0: Associated with 34:97:f6:08:fb:7c Jan 13 11:49:04 hifi wpa_supplicant[871]: wlan0: CTRL-EVENT-CONNECTED - Connection to 34:97:f6:08:fb:7c completed [id=0 id_str=] Jan 13 11:49:04 hifi wpa_supplicant[871]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 13 11:49:04 hifi dhcpcd[883]: DUID 00:01:00:01:2e:31:53:02:d8:3a:dd:9c:2b:b0 Jan 13 11:49:04 hifi dhcpcd[883]: wlan0: IAID dd:9c:2b:b0 Jan 13 11:49:04 hifi dhcpcd[883]: wlan0: adding address fe80::508b:aca1:f774:2969 Jan 13 11:49:04 hifi dhcpcd[883]: ipv6_addaddr1: Permission denied Jan 13 11:49:04 hifi dhcpcd[883]: wlan0: carrier lost Jan 13 11:49:04 hifi systemd-udevd[900]: Process '/sbin/crda' failed with exit code 255. Jan 13 11:49:04 hifi dhcpcd[883]: wlan0: carrier acquired Jan 13 11:49:04 hifi dhcpcd[883]: wlan0: IAID dd:9c:2b:b0 Jan 13 11:49:04 hifi wireless.js[629]: trying... Jan 13 11:49:04 hifi sudo[914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 13 11:49:04 hifi sudo[914]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:04 hifi sudo[914]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:04 hifi dhcpcd[883]: wlan0: soliciting an IPv6 router Jan 13 11:49:05 hifi dhcpcd[883]: wlan0: rebinding lease of 192.168.1.177 Jan 13 11:49:05 hifi dhcpcd[883]: wlan0: probing address 192.168.1.177/24 Jan 13 11:49:05 hifi volumio-remote-updater[613]: [2025-01-13 11:49:05] [info] asio async_connect error: system:111 (Connection refused) Jan 13 11:49:05 hifi volumio-remote-updater[613]: [2025-01-13 11:49:05] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 13 11:49:05 hifi volumio-remote-updater[613]: [2025-01-13 11:49:05] [error] handle_connect error: Underlying Transport Error Jan 13 11:49:05 hifi wireless.js[629]: trying... Jan 13 11:49:05 hifi sudo[917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 13 11:49:05 hifi sudo[917]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:05 hifi sudo[917]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:06 hifi wireless.js[629]: trying... Jan 13 11:49:06 hifi sudo[920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 13 11:49:06 hifi sudo[920]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:06 hifi sudo[920]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:07 hifi wireless.js[629]: trying... Jan 13 11:49:07 hifi sudo[923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 13 11:49:07 hifi sudo[923]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:07 hifi sudo[923]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:08 hifi wpa_supplicant[871]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jan 13 11:49:08 hifi wireless.js[629]: trying... Jan 13 11:49:08 hifi sudo[926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 13 11:49:08 hifi sudo[926]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:08 hifi sudo[926]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:09 hifi wireless.js[629]: trying... Jan 13 11:49:09 hifi sudo[929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 13 11:49:09 hifi sudo[929]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:09 hifi sudo[929]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:10 hifi dhcpcd[883]: wlan0: leased 192.168.1.177 for 86400 seconds Jan 13 11:49:10 hifi avahi-daemon[623]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.177. Jan 13 11:49:10 hifi avahi-daemon[623]: New relevant interface wlan0.IPv4 for mDNS. Jan 13 11:49:10 hifi dhcpcd[883]: wlan0: adding route to 192.168.1.0/24 Jan 13 11:49:10 hifi avahi-daemon[623]: Registering new address record for 192.168.1.177 on wlan0.IPv4. Jan 13 11:49:10 hifi dhcpcd[883]: wlan0: adding default route via 192.168.1.1 Jan 13 11:49:10 hifi wireless.js[629]: trying... Jan 13 11:49:10 hifi sudo[952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 13 11:49:10 hifi sudo[952]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:10 hifi sudo[952]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:10 hifi wireless.js[629]: Connected to: ----boss Jan 13 11:49:10 hifi wireless.js[629]: ---- Jan 13 11:49:10 hifi sudo[955]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 11:49:10 hifi sudo[955]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:10 hifi sudo[955]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:10 hifi wireless.js[629]: ... joined AP, wlan0 IPv4 is 192.168.1.177, ipV6 is undefined Jan 13 11:49:10 hifi wireless.js[629]: It's done! AP Jan 13 11:49:11 hifi systemd[1]: Started Wireless Services. Jan 13 11:49:11 hifi systemd[1]: Started Volumio Backend Module. Jan 13 11:49:11 hifi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 13 11:49:11 hifi systemd[1]: Started Volumio Cpu Tweaker. Jan 13 11:49:11 hifi volumio-cpu-tweak[961]: Setting RT Priority for mpd Jan 13 11:49:11 hifi volumio-cpu-tweak[961]: pid 35's current scheduling policy: SCHED_OTHER Jan 13 11:49:11 hifi volumio-cpu-tweak[961]: pid 35's current scheduling priority: 0 Jan 13 11:49:11 hifi volumio-cpu-tweak[961]: Setting MPD Affinity Jan 13 11:49:11 hifi volumio-cpu-tweak[961]: pid 3's current affinity mask: f Jan 13 11:49:11 hifi volumio-cpu-tweak[961]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jan 13 11:49:11 hifi volumio-cpu-tweak[961]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jan 13 11:49:11 hifi systemd[1]: volumio_cpu_tweak.service: Succeeded. Jan 13 11:49:11 hifi ntpd[755]: Listen normally on 3 wlan0 192.168.1.177:123 Jan 13 11:49:11 hifi ntpd[755]: new interface(s) found: waking up resolver Jan 13 11:49:12 hifi volumio[960]: info: ------------------------------------------- Jan 13 11:49:12 hifi volumio[960]: info: ----- Volumio3 ---- Jan 13 11:49:12 hifi volumio[960]: info: ------------------------------------------- Jan 13 11:49:12 hifi volumio[960]: info: ----- System startup ---- Jan 13 11:49:12 hifi volumio[960]: info: ------------------------------------------- Jan 13 11:49:13 hifi volumio[960]: info: MYVOLUMIO Environment detected Jan 13 11:49:13 hifi volumio[960]: info: Plugin folders cleanup Jan 13 11:49:13 hifi volumio[960]: info: Scanning into folder /volumio/app/plugins/ Jan 13 11:49:13 hifi volumio[960]: info: Scanning category audio_interface Jan 13 11:49:13 hifi volumio[960]: info: Scanning category miscellanea Jan 13 11:49:13 hifi volumio[960]: info: Scanning category music_service Jan 13 11:49:13 hifi volumio[960]: info: Scanning category plugins.json Jan 13 11:49:13 hifi volumio[960]: info: Scanning category system_controller Jan 13 11:49:13 hifi volumio[960]: info: Scanning category user_interface Jan 13 11:49:13 hifi volumio[960]: info: Scanning into folder /data/plugins/ Jan 13 11:49:13 hifi volumio[960]: info: Scanning category audio_interface Jan 13 11:49:13 hifi volumio[960]: info: Scanning category music_service Jan 13 11:49:13 hifi volumio[960]: info: Plugin folders cleanup completed Jan 13 11:49:13 hifi volumio[960]: info: ------------------------------------------- Jan 13 11:49:13 hifi volumio[960]: info: ----- Core plugins startup ---- Jan 13 11:49:13 hifi volumio[960]: info: ------------------------------------------- Jan 13 11:49:13 hifi volumio[960]: info: Loading plugins from folder /volumio/app/plugins/ Jan 13 11:49:13 hifi volumio[960]: info: Adding plugin upnp to MyMusic Plugins Jan 13 11:49:13 hifi volumio[960]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 13 11:49:13 hifi volumio[960]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 13 11:49:13 hifi volumio[960]: info: Loading plugins from folder /data/plugins/ Jan 13 11:49:13 hifi volumio[960]: info: Loading plugin "system"... Jan 13 11:49:13 hifi volumio[960]: info: Loading plugin "appearance"... Jan 13 11:49:14 hifi nmbd[732]: [2025/01/13 11:49:14.192979, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 13 11:49:14 hifi systemd[1]: Started Samba NMB Daemon. Jan 13 11:49:14 hifi nmbd[732]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 13 11:49:14 hifi systemd[1]: Starting Samba Winbind Daemon... Jan 13 11:49:14 hifi winbindd[990]: [2025/01/13 11:49:14.513902, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 13 11:49:14 hifi winbindd[990]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 13 11:49:14 hifi winbindd[990]: [2025/01/13 11:49:14.533763, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 13 11:49:14 hifi winbindd[990]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 13 11:49:14 hifi systemd[1]: Started Samba Winbind Daemon. Jan 13 11:49:14 hifi systemd[1]: Starting Samba SMB Daemon... Jan 13 11:49:14 hifi volumio[960]: info: Loading plugin "network"... Jan 13 11:49:14 hifi volumio[960]: info: Refreshing Cached IP Addresses Jan 13 11:49:14 hifi winbindd[990]: [2025/01/13 11:49:14.983920, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Jan 13 11:49:14 hifi winbindd[990]: res_names->count = 3, expected 4 Jan 13 11:49:14 hifi sudo[1002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 11:49:14 hifi sudo[1002]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:14 hifi sudo[1002]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:15 hifi volumio[960]: info: Loading plugin "services"... Jan 13 11:49:15 hifi sudo[1004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 11:49:15 hifi sudo[1004]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:15 hifi volumio[960]: info: Loading plugin "alsa_controller"... Jan 13 11:49:15 hifi smbd[995]: [2025/01/13 11:49:15.009257, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 13 11:49:15 hifi systemd[1]: Started Samba SMB Daemon. Jan 13 11:49:15 hifi smbd[995]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 13 11:49:15 hifi systemd[1]: Reached target Multi-User System. Jan 13 11:49:15 hifi sudo[1007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 13 11:49:15 hifi systemd[1]: Reached target Graphical Interface. Jan 13 11:49:15 hifi sudo[1007]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:15 hifi systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 13 11:49:15 hifi sudo[1004]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:15 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 11:49:15 hifi volumio[960]: info: Loading plugin "wizard"... Jan 13 11:49:15 hifi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 13 11:49:15 hifi systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 13 11:49:15 hifi systemd[1]: Startup finished in 14.561s (kernel) + 30.280s (userspace) = 44.842s. Jan 13 11:49:15 hifi volumio[960]: info: Loading plugin "networkfs"... Jan 13 11:49:15 hifi volumio[960]: info: Starting Udev Watcher for removable devices Jan 13 11:49:15 hifi sudo[1016]: 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.26/delat /mnt/NAS/delat Jan 13 11:49:15 hifi sudo[1016]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:15 hifi volumio[960]: info: Ignoring mount for partition: boot Jan 13 11:49:15 hifi volumio[960]: info: Ignoring mount for partition: volumio Jan 13 11:49:15 hifi volumio[960]: info: Ignoring mount for partition: volumio_data Jan 13 11:49:15 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 11:49:15 hifi volumio[960]: info: Loading plugin "volumio_command_line_client"... Jan 13 11:49:15 hifi volumio[960]: info: Loading plugin "upnp"... Jan 13 11:49:15 hifi volumio[960]: info: [1736765355208] Starting Upmpd Daemon Jan 13 11:49:15 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 11:49:15 hifi volumio[960]: info: Loading plugin "my_music"... Jan 13 11:49:15 hifi volumio[960]: info: Loading plugin "mpd"... Jan 13 11:49:15 hifi volumio-remote-updater[613]: [2025-01-13 11:49:15] [connect] Successful connection Jan 13 11:49:15 hifi kernel: Key type cifs.spnego registered Jan 13 11:49:15 hifi kernel: Key type cifs.idmap registered Jan 13 11:49:15 hifi kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jan 13 11:49:15 hifi kernel: CIFS: Attempting to mount \\192.168.1.26\delat Jan 13 11:49:15 hifi volumio[960]: info: Loading plugin "upnp_browser"... Jan 13 11:49:15 hifi volumio[960]: info: Loading plugin "alarm-clock"... Jan 13 11:49:15 hifi volumio[960]: info: Loading plugin "airplay_emulation"... Jan 13 11:49:15 hifi volumio[960]: info: Starting Shairport Sync Jan 13 11:49:15 hifi volumio[960]: info: Loading plugin "last_100"... Jan 13 11:49:16 hifi volumio[960]: info: Loading plugin "webradio"... Jan 13 11:49:16 hifi volumio[960]: info: Loading plugin "i2s_dacs"... Jan 13 11:49:16 hifi volumio[960]: info: Loading plugin "volumiodiscovery"... Jan 13 11:49:16 hifi volumio[960]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 11:49:16 hifi volumio[960]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 11:49:16 hifi volumio[960]: *** WARNING *** For more information see Jan 13 11:49:16 hifi node[960]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 11:49:16 hifi volumio[960]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 11:49:16 hifi volumio[960]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 11:49:16 hifi volumio[960]: *** WARNING *** For more information see Jan 13 11:49:16 hifi node[960]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 11:49:16 hifi node[960]: *** WARNING *** For more information see Jan 13 11:49:16 hifi node[960]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 11:49:16 hifi node[960]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 11:49:16 hifi node[960]: *** WARNING *** For more information see Jan 13 11:49:16 hifi volumio[960]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 13 11:49:16 hifi volumio[960]: info: Discovery: Started advertising with name: Hifi Jan 13 11:49:16 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 11:49:16 hifi volumio[960]: info: Loading plugin "spop"... Jan 13 11:49:17 hifi volumio[960]: info: Loading plugin "outputs"... Jan 13 11:49:17 hifi volumio[960]: info: Loading plugin "albumart"... Jan 13 11:49:17 hifi volumio[960]: info: Plugin example_plugin is not enabled Jan 13 11:49:17 hifi volumio[960]: info: Loading plugin "inputs"... Jan 13 11:49:17 hifi volumio[960]: info: Loading plugin "updater_comm"... Jan 13 11:49:17 hifi volumio[960]: info: Plugin mpdemulation is not enabled Jan 13 11:49:17 hifi volumio[960]: info: Loading plugin "rest_api"... Jan 13 11:49:17 hifi volumio[960]: info: Loading plugin "websocket"... Jan 13 11:49:17 hifi volumio[960]: info: Starting Socket.io Server version 2.3.0 Jan 13 11:49:17 hifi volumio[960]: info: Loading plugin "fusiondsp"... Jan 13 11:49:17 hifi volumio[960]: Forking 3 albumart workers Jan 13 11:49:18 hifi volumio[960]: info: Applying required configuration parameters for plugin fusiondsp Jan 13 11:49:18 hifi volumio[960]: info: Loading i18n strings for locale en Jan 13 11:49:18 hifi volumio[960]: Updating browse sources language Jan 13 11:49:18 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 11:49:18 hifi volumio[960]: Starting albumart workers Jan 13 11:49:18 hifi volumio[960]: Starting albumart workers Jan 13 11:49:18 hifi volumio[960]: Starting albumart workers Jan 13 11:49:18 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 11:49:18 hifi volumio[960]: info: CoreCommandRouter::initPlayerControls Jan 13 11:49:18 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:18 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:18 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:18 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:18 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:18 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:18 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:18 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:18 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 11:49:18 hifi volumio[960]: Express server listening on port 3000 Jan 13 11:49:18 hifi volumio[960]: [Metrics] WebUI: 6s 193.46ms Jan 13 11:49:18 hifi volumio[960]: info: CoreStateMachine::resetVolumioState Jan 13 11:49:18 hifi volumio[960]: info: CoreStateMachine::getcurrentVolume Jan 13 11:49:18 hifi volumio[960]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 11:49:18 hifi volumio-remote-updater[613]: [2025-01-13 11:49:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1736765355 101 Jan 13 11:49:18 hifi sudo[1007]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:18 hifi volumio[960]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jan 13 11:49:18 hifi volumio[960]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 11:49:18 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 11:49:18 hifi volumio[960]: info: Setting Device type: Raspberry PI Jan 13 11:49:18 hifi volumio[960]: info: CoreStateMachine::setRepeat null single undefined Jan 13 11:49:18 hifi volumio[960]: info: CoreStateMachine::pushState Jan 13 11:49:18 hifi volumio[960]: info: CorePlayQueue::getTrack 0 Jan 13 11:49:18 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 11:49:18 hifi volumio[960]: info: CoreCommandRouter::volumioPushState Jan 13 11:49:18 hifi volumio[960]: info: CoreStateMachine::setRandom null Jan 13 11:49:18 hifi volumio[960]: info: CoreStateMachine::pushState Jan 13 11:49:18 hifi volumio[960]: info: CorePlayQueue::getTrack 0 Jan 13 11:49:18 hifi volumio[960]: info: CoreCommandRouter::volumioPushState Jan 13 11:49:18 hifi volumio[960]: info: VolumeController:: Volume=undefined Mute =false Jan 13 11:49:18 hifi volumio[960]: info: CoreStateMachine::pushState Jan 13 11:49:18 hifi volumio[960]: info: CorePlayQueue::getTrack 0 Jan 13 11:49:18 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 11:49:18 hifi volumio[960]: info: CoreCommandRouter::volumioPushState Jan 13 11:49:18 hifi volumio[960]: info: CoreStateMachine::updateTrackBlock Jan 13 11:49:18 hifi volumio[960]: info: CorePlayQueue::getTrackBlock Jan 13 11:49:18 hifi volumio[960]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 11:49:18 hifi volumio[960]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 13 11:49:18 hifi volumio[960]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Jan 13 11:49:18 hifi volumio[960]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 13 11:49:18 hifi volumio[960]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 11:49:18 hifi volumio[960]: info: Discovery: adding 2e387222-6bec-4a45-b90b-0d7f896ec08c Jan 13 11:49:18 hifi volumio[960]: info: Discovery: Found device Hifi Jan 13 11:49:18 hifi volumio[960]: info: CoreCommandRouter::volumioGetState Jan 13 11:49:18 hifi volumio[960]: info: CorePlayQueue::getTrack 0 Jan 13 11:49:18 hifi volumio[960]: info: VolumeController:: Volume=undefined Mute =false Jan 13 11:49:18 hifi volumio[960]: info: CoreStateMachine::pushState Jan 13 11:49:18 hifi volumio[960]: info: CorePlayQueue::getTrack 0 Jan 13 11:49:18 hifi volumio[960]: info: CoreCommandRouter::volumioPushState Jan 13 11:49:19 hifi systemd[1]: systemd-fsckd.service: Succeeded. Jan 13 11:49:21 hifi sh[525]: timed out Jan 13 11:49:21 hifi dhcpcd[566]: timed out Jan 13 11:49:21 hifi sh[525]: dhcpcd exited Jan 13 11:49:21 hifi dhcpcd[566]: dhcpcd exited Jan 13 11:49:21 hifi sh[525]: ifup: failed to bring up eth0 Jan 13 11:49:21 hifi systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jan 13 11:49:21 hifi systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jan 13 11:49:24 hifi systemd[1]: systemd-hostnamed.service: Succeeded. Jan 13 11:49:25 hifi sudo[1100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 11:49:25 hifi sudo[1100]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:25 hifi sudo[1100]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:25 hifi sudo[1102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 11:49:25 hifi sudo[1102]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:25 hifi sudo[1102]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:25 hifi sudo[1105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 13 11:49:25 hifi sudo[1105]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:25 hifi systemd[1]: Started UPnP Renderer front-end to MPD. Jan 13 11:49:25 hifi sudo[1105]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:25 hifi volumio[960]: info: Upmpdcli Daemon Started Jan 13 11:49:25 hifi volumio[960]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Jan 13 11:49:25 hifi sudo[1016]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:25 hifi volumio[960]: info: Cannot mount NAS delat at system boot, trial number 1 ,retrying in 5 seconds Jan 13 11:49:25 hifi volumio[960]: info: Completed loading Core Plugins Jan 13 11:49:25 hifi volumio[960]: info: Preparing to generate the ALSA configuration file Jan 13 11:49:25 hifi kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 13 11:49:25 hifi kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Jan 13 11:49:25 hifi volumio[960]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 13 11:49:25 hifi volumio[960]: info: Reading ALSA contributions from plugins. Jan 13 11:49:25 hifi volumio[960]: info: Asound.conf file unchanged, so no further update is needed Jan 13 11:49:25 hifi volumio[960]: info: Output device has changed, restarting MPD Jan 13 11:49:25 hifi volumio[960]: info: Output device has changed, restarting Shairport Sync Jan 13 11:49:25 hifi sudo[1110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 11:49:25 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:25 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 11:49:25 hifi sudo[1110]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:25 hifi sudo[1110]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:25 hifi sudo[1112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 11:49:25 hifi sudo[1112]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:25 hifi volumio[960]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 11:49:25 hifi volumio[960]: info: ___________ START PLUGINS ___________ Jan 13 11:49:25 hifi volumio[960]: info: ControllerMpd::onStart: Initializing MPD Jan 13 11:49:25 hifi volumio[960]: info: Creating MPD Configuration file Jan 13 11:49:25 hifi sudo[1120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 11:49:25 hifi sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:25 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 11:49:25 hifi volumio[960]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 11:49:25 hifi volumio[960]: info: [1736765365583] CoreMusicLibrary::Adding element Media Servers Jan 13 11:49:25 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 11:49:25 hifi sudo[1120]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:25 hifi sudo[1122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 11:49:25 hifi sudo[1122]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:25 hifi systemd[1]: Listening on mpd.socket. Jan 13 11:49:25 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:25 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 11:49:25 hifi systemd[1]: Starting Music Player Daemon... Jan 13 11:49:25 hifi systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 13 11:49:25 hifi systemd[1]: mpd.service: Succeeded. Jan 13 11:49:25 hifi systemd[1]: Stopped Music Player Daemon. Jan 13 11:49:25 hifi systemd[1]: Starting Music Player Daemon... Jan 13 11:49:25 hifi volumio[960]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 11:49:25 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:25 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 11:49:25 hifi volumio[960]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 11:49:25 hifi volumio[960]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 11:49:25 hifi volumio[960]: info: [1736765365684] CoreMusicLibrary::Adding element Last_100 Jan 13 11:49:25 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 11:49:25 hifi volumio[960]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 11:49:25 hifi volumio[960]: info: [1736765365686] CoreMusicLibrary::Adding element Webradio Jan 13 11:49:25 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 11:49:25 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 11:49:25 hifi sudo[1129]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 11:49:25 hifi sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:25 hifi volumio[960]: info: Initializing BBC Radios Jan 13 11:49:25 hifi sudo[1129]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 13 11:49:25 hifi sudo[1129]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:25 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 11:49:25 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 11:49:25 hifi volumio[960]: info: Creating Spotify config file Jan 13 11:49:25 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:25 hifi volumio[960]: info: Loading i18n strings for locale en Jan 13 11:49:25 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 13 11:49:25 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:25 hifi volumio[960]: info: FusionDsp - mixtype--------------------- undefined Jan 13 11:49:25 hifi volumio[960]: info: Volumio Calling Home Jan 13 11:49:25 hifi volumio[960]: info: Preparing to generate the ALSA configuration file Jan 13 11:49:25 hifi volumio[960]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 13 11:49:25 hifi volumio[960]: info: Reading ALSA contributions from plugins. Jan 13 11:49:25 hifi volumio[960]: info: MPD Permissions set Jan 13 11:49:25 hifi volumio[960]: info: MPD Permissions set Jan 13 11:49:25 hifi volumio[960]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 13 11:49:25 hifi volumio[960]: info: Spotify config file written Jan 13 11:49:25 hifi sudo[1149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 13 11:49:25 hifi sudo[1149]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi systemd[1]: Started go-librespot Daemon. Jan 13 11:49:26 hifi go-librespot[1155]: Librespot-go daemon starting... Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi sudo[1149]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:26 hifi volumio[960]: error: error Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::volumioGetState Jan 13 11:49:26 hifi volumio[960]: info: CorePlayQueue::getTrack 0 Jan 13 11:49:26 hifi volumio[960]: info: Volumio called home Jan 13 11:49:26 hifi volumio[960]: info: Starting Shairport Sync Jan 13 11:49:26 hifi volumio[960]: info: Starting Shairport Sync Jan 13 11:49:26 hifi volumio[960]: info: Starting Shairport Sync Jan 13 11:49:26 hifi sudo[1162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 11:49:26 hifi sudo[1162]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:26 hifi sudo[1164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 11:49:26 hifi sudo[1164]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:26 hifi sudo[1171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 11:49:26 hifi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 13 11:49:26 hifi sudo[1171]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:26 hifi systemd[1]: shairport-sync.service: Succeeded. Jan 13 11:49:26 hifi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 13 11:49:26 hifi volumio[960]: info: Asound.conf file unchanged, so no further update is needed Jan 13 11:49:26 hifi volumio[960]: info: Output device has changed, restarting MPD Jan 13 11:49:26 hifi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 13 11:49:26 hifi sudo[1164]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:26 hifi volumio[960]: info: Output device has changed, restarting Shairport Sync Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 11:49:26 hifi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 13 11:49:26 hifi systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 13 11:49:26 hifi systemd[1]: shairport-sync.service: Succeeded. Jan 13 11:49:26 hifi sudo[1162]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:26 hifi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 13 11:49:26 hifi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 13 11:49:26 hifi sudo[1171]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:26 hifi sudo[1177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 11:49:26 hifi sudo[1177]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:26 hifi sudo[1177]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:26 hifi sudo[1179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 11:49:26 hifi sudo[1179]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:26 hifi systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 13 11:49:26 hifi systemd[1]: mpd.service: Succeeded. Jan 13 11:49:26 hifi systemd[1]: Stopped Music Player Daemon. Jan 13 11:49:26 hifi volumio[960]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 11:49:26 hifi systemd[1]: Starting Music Player Daemon... Jan 13 11:49:26 hifi volumio[960]: info: MPD Permissions set Jan 13 11:49:26 hifi volumio[960]: info: Shairport-Sync Started Jan 13 11:49:26 hifi volumio[960]: Error adding Membership: Error: addMembership EINVAL Jan 13 11:49:26 hifi volumio[960]: info: Shairport-Sync Started Jan 13 11:49:26 hifi volumio[960]: info: Shairport-Sync Started Jan 13 11:49:26 hifi volumio[960]: info: An error occurred while refreshing Spotify Token Error: Bad Request Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:26 hifi volumio[960]: info: Starting Shairport Sync Jan 13 11:49:26 hifi sudo[1188]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 11:49:26 hifi sudo[1188]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:26 hifi go-librespot[1155]: time="2025-01-13T11:49:26+01:00" level=info msg="generated new device id: b976a066a9e6ea20e9704d496ee0729318b1e9b2" Jan 13 11:49:26 hifi sudo[1188]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 13 11:49:26 hifi sudo[1188]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:26 hifi sudo[1195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 11:49:26 hifi sudo[1195]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:26 hifi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 13 11:49:26 hifi systemd[1]: shairport-sync.service: Succeeded. Jan 13 11:49:26 hifi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 13 11:49:26 hifi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 13 11:49:26 hifi sudo[1195]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:26 hifi volumio[960]: info: Shairport-Sync Started Jan 13 11:49:26 hifi go-librespot[1155]: time="2025-01-13T11:49:26+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 13 11:49:26 hifi go-librespot[1155]: time="2025-01-13T11:49:26+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 13 11:49:26 hifi go-librespot[1155]: time="2025-01-13T11:49:26+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 13 11:49:26 hifi go-librespot[1155]: time="2025-01-13T11:49:26+01:00" level=debug msg="zeroconf server listening on port 35373" Jan 13 11:49:27 hifi mpd[1196]: Jan 13 11:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 11:49:27 hifi systemd[1]: Started Music Player Daemon. Jan 13 11:49:27 hifi sudo[1179]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:27 hifi sudo[1112]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:27 hifi sudo[1122]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:27 hifi volumio[960]: info: camilladsp spawned new process with pid 1211, instance 1, run: true Jan 13 11:49:27 hifi volumio[960]: info: camilladsp service started and running in background, instance 1 Jan 13 11:49:27 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 11:49:27 hifi volumio[960]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Jan 13 11:49:27 hifi volumio[960]: info: FusionDsp loaded Jan 13 11:49:27 hifi volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 11:49:27 hifi sudo[1215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jan 13 11:49:27 hifi sudo[1215]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:27 hifi volumio[960]: info: FusionDsp - Reporting Fusion DSP Enabled Jan 13 11:49:27 hifi volumio[960]: info: Adding Signal Path Element [object Object] Jan 13 11:49:27 hifi volumio[960]: info: Adding fusiondspeq DSP Signal Path Element Jan 13 11:49:27 hifi volumio[960]: info: FusionDsp - ---- installed callbackRead Jan 13 11:49:28 hifi volumio[960]: info: Completed starting Core Plugins Jan 13 11:49:28 hifi volumio[960]: info: ------------------------------------------- Jan 13 11:49:28 hifi volumio[960]: info: ----- MyVolumio plugins startup ---- Jan 13 11:49:28 hifi volumio[960]: info: ------------------------------------------- Jan 13 11:49:28 hifi volumio[960]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 13 11:49:28 hifi volumio[960]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 13 11:49:28 hifi volumio[960]: info: MPD running with PID1196 Jan 13 11:49:28 hifi volumio[960]: ,establishing connection Jan 13 11:49:28 hifi systemd[1]: Started FusionDsp Daemon. Jan 13 11:49:28 hifi sudo[1215]: pam_unix(sudo:session): session closed for user root Jan 13 11:49:28 hifi volumio[960]: error: MPD error: The expression evaluated to a falsy value: Jan 13 11:49:28 hifi volumio[960]: assert.ok(self.idling) Jan 13 11:49:28 hifi volumio[960]: error: The expression evaluated to a falsy value: Jan 13 11:49:28 hifi volumio[960]: assert.ok(self.idling) Jan 13 11:49:28 hifi volumio[960]: error: MPD error: The expression evaluated to a falsy value: Jan 13 11:49:28 hifi volumio[960]: assert.ok(self.idling) Jan 13 11:49:28 hifi volumio[960]: error: The expression evaluated to a falsy value: Jan 13 11:49:28 hifi volumio[960]: assert.ok(self.idling) Jan 13 11:49:28 hifi volumio[960]: error: MPD error: The expression evaluated to a falsy value: Jan 13 11:49:28 hifi volumio[960]: assert.ok(self.idling) Jan 13 11:49:28 hifi volumio[960]: error: The expression evaluated to a falsy value: Jan 13 11:49:28 hifi volumio[960]: assert.ok(self.idling) Jan 13 11:49:28 hifi volumio[960]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 13 11:49:28 hifi volumio[960]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jan 13 11:49:28 hifi volumio[960]: error: updateQueue error: null Jan 13 11:49:29 hifi volumio[960]: info: go-librespot daemon successfully initialized Jan 13 11:49:29 hifi volumio[1108]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 13 11:49:29 hifi volumio[1108]: ..++++ Jan 13 11:49:30 hifi volumio[1108]: ......++++ Jan 13 11:49:30 hifi volumio[1108]: e is 65537 (0x010001) Jan 13 11:49:30 hifi volumio[1108]: writing RSA key Jan 13 11:49:30 hifi sudo[1244]: 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.26/delat /mnt/NAS/delat Jan 13 11:49:30 hifi sudo[1244]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 11:49:30 hifi kernel: CIFS: Attempting to mount \\192.168.1.26\delat Jan 13 11:49:30 hifi go-librespot[1155]: time="2025-01-13T11:49:30+01:00" level=debug msg="obtained new client token: AABJi6Xn1inhit0qPNZPRjOK3dHedq/w5W0xoJMppnGn5X8Gj+TP/Hi/G63JSzzNNH5ksZGQW0/tH9S8yYuoH8GgC/9o0vd/hUujRZYlM55TgNqkyLega3Z64eyC6lgsYWRkWWPyedv/IutIbIcQf8sJIYTetPCmHYLaI4H6mR4lc3Ro4GNu+DzTyPFZ1pLkhz5t24wh0zlf3nmx60RUDbAbyT425mAdiPk7D9alA+KAPhLpslPeCblB3lzI" Jan 13 11:49:30 hifi go-librespot[1155]: time="2025-01-13T11:49:30+01:00" level=debug msg="completed keyexchange" Jan 13 11:49:31 hifi go-librespot[1155]: time="2025-01-13T11:49:31+01:00" level=debug msg="completed challenge" Jan 13 11:49:31 hifi go-librespot[1155]: time="2025-01-13T11:49:31+01:00" level=debug msg="authenticated as kon87" Jan 13 11:49:31 hifi go-librespot[1155]: time="2025-01-13T11:49:31+01:00" level=debug msg="authenticated as kon87" Jan 13 11:49:31 hifi go-librespot[1155]: time="2025-01-13T11:49:31+01:00" level=debug msg="dealer connection opened" Jan 13 11:49:31 hifi go-librespot[1155]: time="2025-01-13T11:49:31+01:00" level=info msg="accepted zeroconf user kon87 from iPhone" Jan 13 11:49:31 hifi go-librespot[1155]: time="2025-01-13T11:49:31+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 13 11:49:31 hifi go-librespot[1155]: time="2025-01-13T11:49:31+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 13 11:49:31 hifi go-librespot[1155]: time="2025-01-13T11:49:31+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 13 11:49:31 hifi go-librespot[1155]: time="2025-01-13T11:49:31+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 13 11:49:31 hifi go-librespot[1155]: time="2025-01-13T11:49:31+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 13 11:49:31 hifi go-librespot[1155]: time="2025-01-13T11:49:31+01:00" level=debug msg="autoplay enabled: false" Jan 13 11:49:31 hifi go-librespot[1155]: time="2025-01-13T11:49:31+01:00" level=debug msg="received connection id: ZWNiMzRhZTctNGVkOS00NjgxLThhMWEtNTgxZmQ3NGZmNDAwK2RlYWxlcit0Y3A6Ly8wYWNhNWEzYy5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArRTUzMkFBQjY2M0E4QkRBMTIxNkJBOEFBRDhBRDUyRDVCNkRGOTgwNTRCMURGMDkwQzgxRTU4NTBGNkE3ODk2Mg==" Jan 13 11:49:31 hifi go-librespot[1155]: time="2025-01-13T11:49:31+01:00" level=debug msg="put connect state because NEW_DEVICE" Jan 13 11:49:31 hifi go-librespot[1155]: time="2025-01-13T11:49:31+01:00" level=debug msg="handling transfer player command from 0477c52d1c4af37131ea322b8299d09209a59d3a" Jan 13 11:49:31 hifi go-librespot[1155]: time="2025-01-13T11:49:31+01:00" level=debug msg="resolved context of track" uri="spotify:album:1ut6B7i36aC2tYAcbTMu0y" Jan 13 11:49:31 hifi go-librespot[1155]: time="2025-01-13T11:49:31+01:00" level=trace msg="fetched new page 0 with 5 items (list: 5)" uri="spotify:album:1ut6B7i36aC2tYAcbTMu0y" Jan 13 11:49:31 hifi go-librespot[1155]: time="2025-01-13T11:49:31+01:00" level=debug msg="loading track (paused: true, position: 0ms)" uri="spotify:track:71WIJpEaq2p4IDQkLge0MX" Jan 13 11:49:31 hifi go-librespot[1155]: time="2025-01-13T11:49:31+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 13 11:49:31 hifi go-librespot[1155]: time="2025-01-13T11:49:31+01:00" level=trace msg="emitting websocket event: will_play" Jan 13 11:49:31 hifi go-librespot[1155]: time="2025-01-13T11:49:31+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Jan 13 11:49:31 hifi go-librespot[1155]: time="2025-01-13T11:49:31+01:00" level=debug msg="selected format OGG_VORBIS_320 (0b5c1217c531898533e7df0813a4b955326b1cad)" uri="spotify:track:71WIJpEaq2p4IDQkLge0MX" Jan 13 11:49:31 hifi go-librespot[1155]: time="2025-01-13T11:49:31+01:00" level=debug msg="requested aes key for file 0b5c1217c531898533e7df0813a4b955326b1cad, gid: 71WIJpEaq2p4IDQkLge0MX" Jan 13 11:49:31 hifi go-librespot[1155]: time="2025-01-13T11:49:31+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2779" Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=debug msg="fetched first chunk of 28, total size is 14453088 bytes" uri="spotify:track:71WIJpEaq2p4IDQkLge0MX" Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:71WIJpEaq2p4IDQkLge0MX" Jan 13 11:49:32 hifi volumio[960]: info: FusionDsp - ---- read samplerate, raw: Jan 13 11:49:32 hifi volumio[960]: error: FusionDsp - invalid sample rate Jan 13 11:49:32 hifi volumio[960]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 13 11:49:32 hifi volumio[960]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 13 11:49:32 hifi volumio[960]: info: camilladsp stopping service pid 1211... Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=debug msg="created new output device" Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=info msg="loaded track \"Breathe Out Black Out, Pt. 2\" (paused: true, position: 0ms, duration: 422250ms, prefetched: false)" uri="spotify:track:71WIJpEaq2p4IDQkLge0MX" Jan 13 11:49:32 hifi volumio[960]: info: camilladsp service terminated, instance 1 Jan 13 11:49:32 hifi volumio[960]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 13 11:49:32 hifi volumio[960]: info: camilladsp service started and running in background, instance 1 Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=debug msg="fetched chunk 2/27, size: 524288" uri="spotify:track:71WIJpEaq2p4IDQkLge0MX" Jan 13 11:49:32 hifi volumio[960]: info: camilladsp spawned new process with pid 1266, instance 1, run: true Jan 13 11:49:32 hifi volumio[960]: info: Initializing connection to go-librespot Websocket Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=debug msg="new websocket client" Jan 13 11:49:32 hifi volumio[960]: info: Connection to go-librespot Websocket established Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1832" Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=trace msg="emitting websocket event: metadata" Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=trace msg="emitting websocket event: active" Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=debug msg="sending successful reply for dealer request" Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jan 13 11:49:32 hifi volumio[960]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:71WIJpEaq2p4IDQkLge0MX","name":"Breathe Out Black Out, Pt. 2","artist_names":["Immanu El"],"album_name":"Distance","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02996e75fc7b09ef9fda5902d4","position":0,"duration":422250,"release_date":"year:2021 month:6 day:4","track_number":1,"disc_number":1}} Jan 13 11:49:32 hifi volumio[960]: SPOTIFY: received: {"type":"active","data":null} Jan 13 11:49:32 hifi volumio[960]: info: Aligning Spotify Volume to Volumio Volume Jan 13 11:49:32 hifi volumio[960]: info: CoreCommandRouter::volumioGetState Jan 13 11:49:32 hifi volumio[960]: info: CorePlayQueue::getTrack 0 Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=debug msg="fetched chunk 3/27, size: 524288" uri="spotify:track:71WIJpEaq2p4IDQkLge0MX" Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=debug msg="fetched chunk 1/27, size: 524288" uri="spotify:track:71WIJpEaq2p4IDQkLge0MX" Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=trace msg="emitting websocket event: paused" Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=debug msg="handling resume player command from 0477c52d1c4af37131ea322b8299d09209a59d3a" Jan 13 11:49:32 hifi volumio[960]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:71WIJpEaq2p4IDQkLge0MX","play_origin":"album"}} Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:71WIJpEaq2p4IDQkLge0MX" Jan 13 11:49:32 hifi volumio[960]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 11:49:32 hifi volumio[960]: TypeError: Cannot read property 'service' of undefined Jan 13 11:49:32 hifi volumio[960]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:336:50) Jan 13 11:49:32 hifi volumio[960]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:270:18) Jan 13 11:49:32 hifi volumio[960]: at WebSocket.message (/data/plugins/music_service/spop/index.js:189:14) Jan 13 11:49:32 hifi volumio[960]: at WebSocket.emit (events.js:315:20) Jan 13 11:49:32 hifi volumio[960]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Jan 13 11:49:32 hifi volumio[960]: at Receiver.emit (events.js:315:20) Jan 13 11:49:32 hifi volumio[960]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Jan 13 11:49:32 hifi volumio[960]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Jan 13 11:49:32 hifi volumio[960]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Jan 13 11:49:32 hifi volumio[960]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Jan 13 11:49:32 hifi volumio[960]: at writeOrBuffer (internal/streams/writable.js:358:12) Jan 13 11:49:32 hifi volumio[960]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Jan 13 11:49:32 hifi volumio[960]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Jan 13 11:49:32 hifi volumio[960]: at Socket.emit (events.js:315:20) Jan 13 11:49:32 hifi volumio[960]: at addChunk (internal/streams/readable.js:309:12) Jan 13 11:49:32 hifi volumio[960]: at readableAddChunk (internal/streams/readable.js:284:9) Jan 13 11:49:32 hifi volumio[960]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=debug msg="resume track at 133ms" Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=trace msg="scheduling prefetch in 391s" Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=debug msg="sending successful reply for dealer request" Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 13 11:49:32 hifi go-librespot[1155]: time="2025-01-13T11:49:32+01:00" level=trace msg="emitting websocket event: playing" Jan 13 11:49:32 hifi sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-13 11:48 Jan 13 11:49:32 hifi sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"