-- Logs begin at Sat 2018-05-05 07:05:17 CEST, end at Fri 2025-06-13 16:46:00 CEST. -- Feb 14 11:11:58 volumio1 kernel: Booting Linux on physical CPU 0x0 Feb 14 11:11:58 volumio1 kernel: Linux version 6.6.62-v7l+ (dom@buildbot) (arm-linux-gnueabihf-gcc (Ubuntu 11.4.0-1ubuntu1~22.04) 11.4.0, GNU ld (GNU Binutils for Ubuntu) 2.38) #1816 SMP Mon Nov 18 15:33:33 GMT 2024 Feb 14 11:11:58 volumio1 kernel: CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d Feb 14 11:11:58 volumio1 kernel: CPU: div instructions available: patching division code Feb 14 11:11:58 volumio1 kernel: CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache Feb 14 11:11:58 volumio1 kernel: OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.2 Feb 14 11:11:58 volumio1 kernel: random: crng init done Feb 14 11:11:58 volumio1 kernel: Memory policy: Data cache writealloc Feb 14 11:11:58 volumio1 kernel: Reserved memory: created CMA memory pool at 0x0000000029400000, size 64 MiB Feb 14 11:11:58 volumio1 kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Feb 14 11:11:58 volumio1 kernel: OF: reserved mem: 0x0000000029400000..0x000000002d3fffff (65536 KiB) map reusable linux,cma Feb 14 11:11:58 volumio1 kernel: OF: reserved mem: 0x000000003ef64fe0..0x000000003ef653cf (0 KiB) nomap non-reusable nvram@0 Feb 14 11:11:58 volumio1 kernel: Zone ranges: Feb 14 11:11:58 volumio1 kernel: DMA [mem 0x0000000000000000-0x000000002fffffff] Feb 14 11:11:58 volumio1 kernel: Normal empty Feb 14 11:11:58 volumio1 kernel: HighMem [mem 0x0000000030000000-0x00000000fbffffff] Feb 14 11:11:58 volumio1 kernel: Movable zone start for each node Feb 14 11:11:58 volumio1 kernel: Early memory node ranges Feb 14 11:11:58 volumio1 kernel: node 0: [mem 0x0000000000000000-0x0000000037ffffff] Feb 14 11:11:58 volumio1 kernel: node 0: [mem 0x0000000040000000-0x00000000fbffffff] Feb 14 11:11:58 volumio1 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000000fbffffff] Feb 14 11:11:58 volumio1 kernel: percpu: Embedded 18 pages/cpu s42004 r8192 d23532 u73728 Feb 14 11:11:58 volumio1 kernel: pcpu-alloc: s42004 r8192 d23532 u73728 alloc=18*4096 Feb 14 11:11:58 volumio1 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Feb 14 11:11:58 volumio1 kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:FB:B4:84 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=ae35765e-0158-4d8d-a475-17b52add8039 imgfile=/volumio_current.sqsh bootpart=UUID=6C60-DAB2 datapart=UUID=8648801e-3ad8-424d-949f-c52da8279dd7 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Feb 14 11:11:58 volumio1 kernel: cgroup: Disabling memory control group subsystem Feb 14 11:11:58 volumio1 kernel: Unknown kernel command line parameters "splash nodebug numa_policy=interleave imgpart=UUID=ae35765e-0158-4d8d-a475-17b52add8039 imgfile=/volumio_current.sqsh bootpart=UUID=6C60-DAB2 datapart=UUID=8648801e-3ad8-424d-949f-c52da8279dd7 uuidconfig=cmdline.txt pcie_aspm=off bootdelay=7 use_kmsg=no", will be passed to user space. Feb 14 11:11:58 volumio1 kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Feb 14 11:11:58 volumio1 kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 14 11:11:58 volumio1 kernel: Built 1 zonelists, mobility grouping on. Total pages: 997696 Feb 14 11:11:58 volumio1 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Feb 14 11:11:58 volumio1 kernel: software IO TLB: area num 4. Feb 14 11:11:58 volumio1 kernel: software IO TLB: mapped [mem 0x0000000023090000-0x0000000027090000] (64MB) Feb 14 11:11:58 volumio1 kernel: Memory: 3780844K/3997696K available (12288K kernel code, 1527K rwdata, 3492K rodata, 2048K init, 628K bss, 151316K reserved, 65536K cma-reserved, 3211264K highmem) Feb 14 11:11:58 volumio1 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Feb 14 11:11:58 volumio1 kernel: ftrace: allocating 38825 entries in 114 pages Feb 14 11:11:58 volumio1 kernel: ftrace: allocated 114 pages with 4 groups Feb 14 11:11:58 volumio1 kernel: trace event string verifier disabled Feb 14 11:11:58 volumio1 kernel: rcu: Hierarchical RCU implementation. Feb 14 11:11:58 volumio1 kernel: Rude variant of Tasks RCU enabled. Feb 14 11:11:58 volumio1 kernel: Tracing variant of Tasks RCU enabled. Feb 14 11:11:58 volumio1 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Feb 14 11:11:58 volumio1 kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Feb 14 11:11:58 volumio1 kernel: GIC: Using split EOI/Deactivate mode Feb 14 11:11:58 volumio1 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Feb 14 11:11:58 volumio1 kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Feb 14 11:11:58 volumio1 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Feb 14 11:11:58 volumio1 kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Feb 14 11:11:58 volumio1 kernel: Switching to timer-based delay loop, resolution 18ns Feb 14 11:11:58 volumio1 kernel: Console: colour dummy device 80x30 Feb 14 11:11:58 volumio1 kernel: printk: console [tty1] enabled Feb 14 11:11:58 volumio1 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000) Feb 14 11:11:58 volumio1 kernel: CPU: Testing write buffer coherency: ok Feb 14 11:11:58 volumio1 kernel: CPU0: Spectre BHB: enabling loop workaround for all CPUs Feb 14 11:11:58 volumio1 kernel: pid_max: default: 32768 minimum: 301 Feb 14 11:11:58 volumio1 kernel: LSM: initializing lsm=capability,integrity Feb 14 11:11:58 volumio1 kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 14 11:11:58 volumio1 kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 14 11:11:58 volumio1 kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Feb 14 11:11:58 volumio1 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Feb 14 11:11:58 volumio1 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Feb 14 11:11:58 volumio1 kernel: Setting up static identity map for 0x200000 - 0x20003c Feb 14 11:11:58 volumio1 kernel: rcu: Hierarchical SRCU implementation. Feb 14 11:11:58 volumio1 kernel: rcu: Max phase no-delay instances is 1000. Feb 14 11:11:58 volumio1 kernel: smp: Bringing up secondary CPUs ... Feb 14 11:11:58 volumio1 kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Feb 14 11:11:58 volumio1 kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Feb 14 11:11:58 volumio1 kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Feb 14 11:11:58 volumio1 kernel: smp: Brought up 1 node, 4 CPUs Feb 14 11:11:58 volumio1 kernel: SMP: Total of 4 processors activated (432.00 BogoMIPS). Feb 14 11:11:58 volumio1 kernel: CPU: All CPU(s) started in HYP mode. Feb 14 11:11:58 volumio1 kernel: CPU: Virtualization extensions available. Feb 14 11:11:58 volumio1 kernel: devtmpfs: initialized Feb 14 11:11:58 volumio1 kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0 Feb 14 11:11:58 volumio1 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Feb 14 11:11:58 volumio1 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Feb 14 11:11:58 volumio1 kernel: pinctrl core: initialized pinctrl subsystem Feb 14 11:11:58 volumio1 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Feb 14 11:11:58 volumio1 kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Feb 14 11:11:58 volumio1 kernel: audit: initializing netlink subsys (disabled) Feb 14 11:11:58 volumio1 kernel: audit: type=2000 audit(0.010:1): state=initialized audit_enabled=0 res=1 Feb 14 11:11:58 volumio1 kernel: thermal_sys: Registered thermal governor 'step_wise' Feb 14 11:11:58 volumio1 kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Feb 14 11:11:58 volumio1 kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Feb 14 11:11:58 volumio1 kernel: Serial: AMBA PL011 UART driver Feb 14 11:11:58 volumio1 kernel: bcm2835-mbox fe00b880.mailbox: mailbox enabled Feb 14 11:11:58 volumio1 kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2024-11-11T15:48:13, variant start Feb 14 11:11:58 volumio1 kernel: raspberrypi-firmware soc:firmware: Firmware hash is 903570ba72a9e117f92e5499de439f59dd96e417 Feb 14 11:11:58 volumio1 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Feb 14 11:11:58 volumio1 kernel: bcm2835-dma fe007000.dma-controller: DMA legacy API manager, dmachans=0x1 Feb 14 11:11:58 volumio1 kernel: SCSI subsystem initialized Feb 14 11:11:58 volumio1 kernel: usbcore: registered new interface driver usbfs Feb 14 11:11:58 volumio1 kernel: usbcore: registered new interface driver hub Feb 14 11:11:58 volumio1 kernel: usbcore: registered new device driver usb Feb 14 11:11:58 volumio1 kernel: pps_core: LinuxPPS API ver. 1 registered Feb 14 11:11:58 volumio1 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Feb 14 11:11:58 volumio1 kernel: PTP clock support registered Feb 14 11:11:58 volumio1 kernel: vgaarb: loaded Feb 14 11:11:58 volumio1 kernel: clocksource: Switched to clocksource arch_sys_counter Feb 14 11:11:58 volumio1 kernel: VFS: Disk quotas dquot_6.6.0 Feb 14 11:11:58 volumio1 kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Feb 14 11:11:58 volumio1 kernel: FS-Cache: Loaded Feb 14 11:11:58 volumio1 kernel: CacheFiles: Loaded Feb 14 11:11:58 volumio1 kernel: NET: Registered PF_INET protocol family Feb 14 11:11:58 volumio1 kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Feb 14 11:11:58 volumio1 kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear) Feb 14 11:11:58 volumio1 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 14 11:11:58 volumio1 kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Feb 14 11:11:58 volumio1 kernel: TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear) Feb 14 11:11:58 volumio1 kernel: TCP: Hash tables configured (established 8192 bind 8192) Feb 14 11:11:58 volumio1 kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 14 11:11:58 volumio1 kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 14 11:11:58 volumio1 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Feb 14 11:11:58 volumio1 kernel: RPC: Registered named UNIX socket transport module. Feb 14 11:11:58 volumio1 kernel: RPC: Registered udp transport module. Feb 14 11:11:58 volumio1 kernel: RPC: Registered tcp transport module. Feb 14 11:11:58 volumio1 kernel: RPC: Registered tcp-with-tls transport module. Feb 14 11:11:58 volumio1 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Feb 14 11:11:58 volumio1 kernel: PCI: CLS 0 bytes, default 64 Feb 14 11:11:58 volumio1 kernel: Trying to unpack rootfs image as initramfs... Feb 14 11:11:58 volumio1 kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Feb 14 11:11:58 volumio1 kernel: Freeing initrd memory: 28088K Feb 14 11:11:58 volumio1 kernel: Initialise system trusted keyrings Feb 14 11:11:58 volumio1 kernel: workingset: timestamp_bits=14 max_order=20 bucket_order=6 Feb 14 11:11:58 volumio1 kernel: zbud: loaded Feb 14 11:11:58 volumio1 kernel: NFS: Registering the id_resolver key type Feb 14 11:11:58 volumio1 kernel: Key type id_resolver registered Feb 14 11:11:58 volumio1 kernel: Key type id_legacy registered Feb 14 11:11:58 volumio1 kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Feb 14 11:11:58 volumio1 kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Feb 14 11:11:58 volumio1 kernel: Key type asymmetric registered Feb 14 11:11:58 volumio1 kernel: Asymmetric key parser 'x509' registered Feb 14 11:11:58 volumio1 kernel: bounce: pool size: 64 pages Feb 14 11:11:58 volumio1 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Feb 14 11:11:58 volumio1 kernel: io scheduler mq-deadline registered Feb 14 11:11:58 volumio1 kernel: io scheduler kyber registered Feb 14 11:11:58 volumio1 kernel: io scheduler bfq registered Feb 14 11:11:58 volumio1 kernel: pinctrl-bcm2835 fe200000.gpio: GPIO_OUT persistence: yes Feb 14 11:11:58 volumio1 kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: Feb 14 11:11:58 volumio1 kernel: brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] Feb 14 11:11:58 volumio1 kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x067fffffff -> 0x0080000000 Feb 14 11:11:58 volumio1 kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000 Feb 14 11:11:58 volumio1 kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Feb 14 11:11:58 volumio1 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Feb 14 11:11:58 volumio1 kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x67fffffff] (bus address [0x80000000-0xffffffff]) Feb 14 11:11:58 volumio1 kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 Feb 14 11:11:58 volumio1 kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Feb 14 11:11:58 volumio1 kernel: PCI: bus0: Fast back to back transfers disabled Feb 14 11:11:58 volumio1 kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Feb 14 11:11:58 volumio1 kernel: pci_bus 0000:01: supply vpcie3v3 not found, using dummy regulator Feb 14 11:11:58 volumio1 kernel: pci_bus 0000:01: supply vpcie3v3aux not found, using dummy regulator Feb 14 11:11:58 volumio1 kernel: pci_bus 0000:01: supply vpcie12v not found, using dummy regulator Feb 14 11:11:58 volumio1 kernel: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) Feb 14 11:11:58 volumio1 kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 Feb 14 11:11:58 volumio1 kernel: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] Feb 14 11:11:58 volumio1 kernel: pci 0000:01:00.0: PME# supported from D0 D3cold Feb 14 11:11:58 volumio1 kernel: PCI: bus1: Fast back to back transfers disabled Feb 14 11:11:58 volumio1 kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Feb 14 11:11:58 volumio1 kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] Feb 14 11:11:58 volumio1 kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] Feb 14 11:11:58 volumio1 kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Feb 14 11:11:58 volumio1 kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Feb 14 11:11:58 volumio1 kernel: pci 0000:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Feb 14 11:11:58 volumio1 kernel: pci 0000:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 Feb 14 11:11:58 volumio1 kernel: pcieport 0000:00:00.0: enabling device (0000 -> 0002) Feb 14 11:11:58 volumio1 kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 45 Feb 14 11:11:58 volumio1 kernel: bcm2708_fb soc:fb: FB found 1 display(s) Feb 14 11:11:58 volumio1 kernel: Console: switching to colour frame buffer device 80x30 Feb 14 11:11:58 volumio1 kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Feb 14 11:11:58 volumio1 kernel: iproc-rng200 fe104000.rng: hwrng registered Feb 14 11:11:58 volumio1 kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Feb 14 11:11:58 volumio1 kernel: brd: module loaded Feb 14 11:11:58 volumio1 kernel: loop: module loaded Feb 14 11:11:58 volumio1 kernel: Loading iSCSI transport class v2.0-870. Feb 14 11:11:58 volumio1 kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Feb 14 11:11:58 volumio1 kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Feb 14 11:11:58 volumio1 kernel: usbcore: registered new device driver r8152-cfgselector Feb 14 11:11:58 volumio1 kernel: usbcore: registered new interface driver r8152 Feb 14 11:11:58 volumio1 kernel: usbcore: registered new interface driver lan78xx Feb 14 11:11:58 volumio1 kernel: usbcore: registered new interface driver smsc95xx Feb 14 11:11:58 volumio1 kernel: xhci_hcd 0000:01:00.0: enabling device (0140 -> 0142) Feb 14 11:11:58 volumio1 kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Feb 14 11:11:58 volumio1 kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Feb 14 11:11:58 volumio1 kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0700240000000890 Feb 14 11:11:58 volumio1 kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Feb 14 11:11:58 volumio1 kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Feb 14 11:11:58 volumio1 kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Feb 14 11:11:58 volumio1 kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Feb 14 11:11:58 volumio1 kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 14 11:11:58 volumio1 kernel: usb usb1: Product: xHCI Host Controller Feb 14 11:11:58 volumio1 kernel: usb usb1: Manufacturer: Linux 6.6.62-v7l+ xhci-hcd Feb 14 11:11:58 volumio1 kernel: usb usb1: SerialNumber: 0000:01:00.0 Feb 14 11:11:58 volumio1 kernel: hub 1-0:1.0: USB hub found Feb 14 11:11:58 volumio1 kernel: hub 1-0:1.0: 1 port detected Feb 14 11:11:58 volumio1 kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06 Feb 14 11:11:58 volumio1 kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 14 11:11:58 volumio1 kernel: usb usb2: Product: xHCI Host Controller Feb 14 11:11:58 volumio1 kernel: usb usb2: Manufacturer: Linux 6.6.62-v7l+ xhci-hcd Feb 14 11:11:58 volumio1 kernel: usb usb2: SerialNumber: 0000:01:00.0 Feb 14 11:11:58 volumio1 kernel: hub 2-0:1.0: USB hub found Feb 14 11:11:58 volumio1 kernel: hub 2-0:1.0: 4 ports detected Feb 14 11:11:58 volumio1 kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Feb 14 11:11:58 volumio1 kernel: dwc_otg: FIQ enabled Feb 14 11:11:58 volumio1 kernel: dwc_otg: NAK holdoff enabled Feb 14 11:11:58 volumio1 kernel: dwc_otg: FIQ split-transaction FSM enabled Feb 14 11:11:58 volumio1 kernel: Module dwc_common_port init Feb 14 11:11:58 volumio1 kernel: usbcore: registered new interface driver uas Feb 14 11:11:58 volumio1 kernel: usbcore: registered new interface driver usb-storage Feb 14 11:11:58 volumio1 kernel: mousedev: PS/2 mouse device common for all mice Feb 14 11:11:58 volumio1 kernel: sdhci: Secure Digital Host Controller Interface driver Feb 14 11:11:58 volumio1 kernel: sdhci: Copyright(c) Pierre Ossman Feb 14 11:11:58 volumio1 kernel: sdhci-pltfm: SDHCI platform and OF driver helper Feb 14 11:11:58 volumio1 kernel: ledtrig-cpu: registered to indicate activity on CPUs Feb 14 11:11:58 volumio1 kernel: hid: raw HID events driver (C) Jiri Kosina Feb 14 11:11:58 volumio1 kernel: usbcore: registered new interface driver usbhid Feb 14 11:11:58 volumio1 kernel: usbhid: USB HID core driver Feb 14 11:11:58 volumio1 kernel: Initializing XFRM netlink socket Feb 14 11:11:58 volumio1 kernel: NET: Registered PF_PACKET protocol family Feb 14 11:11:58 volumio1 kernel: Key type dns_resolver registered Feb 14 11:11:58 volumio1 kernel: Registering SWP/SWPB emulation handler Feb 14 11:11:58 volumio1 kernel: registered taskstats version 1 Feb 14 11:11:58 volumio1 kernel: Loading compiled-in X.509 certificates Feb 14 11:11:58 volumio1 kernel: Key type .fscrypt registered Feb 14 11:11:58 volumio1 kernel: Key type fscrypt-provisioning registered Feb 14 11:11:58 volumio1 kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Feb 14 11:11:58 volumio1 kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Feb 14 11:11:58 volumio1 kernel: fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 51, base_baud = 0) is a PL011 rev2 Feb 14 11:11:58 volumio1 kernel: serial serial0: tty port ttyAMA1 registered Feb 14 11:11:58 volumio1 kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Feb 14 11:11:58 volumio1 kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Feb 14 11:11:58 volumio1 kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Feb 14 11:11:58 volumio1 kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Feb 14 11:11:58 volumio1 kernel: of_cfs_init Feb 14 11:11:58 volumio1 kernel: of_cfs_init: OK Feb 14 11:11:58 volumio1 kernel: clk: Disabling unused clocks Feb 14 11:11:58 volumio1 kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Feb 14 11:11:58 volumio1 kernel: Freeing unused kernel image (initmem) memory: 2048K Feb 14 11:11:58 volumio1 kernel: Run /init as init process Feb 14 11:11:58 volumio1 kernel: with arguments: Feb 14 11:11:58 volumio1 kernel: /init Feb 14 11:11:58 volumio1 kernel: splash Feb 14 11:11:58 volumio1 kernel: nodebug Feb 14 11:11:58 volumio1 kernel: with environment: Feb 14 11:11:58 volumio1 kernel: HOME=/ Feb 14 11:11:58 volumio1 kernel: TERM=linux Feb 14 11:11:58 volumio1 kernel: numa_policy=interleave Feb 14 11:11:58 volumio1 kernel: imgpart=UUID=ae35765e-0158-4d8d-a475-17b52add8039 Feb 14 11:11:58 volumio1 kernel: imgfile=/volumio_current.sqsh Feb 14 11:11:58 volumio1 kernel: bootpart=UUID=6C60-DAB2 Feb 14 11:11:58 volumio1 kernel: datapart=UUID=8648801e-3ad8-424d-949f-c52da8279dd7 Feb 14 11:11:58 volumio1 kernel: uuidconfig=cmdline.txt Feb 14 11:11:58 volumio1 kernel: pcie_aspm=off Feb 14 11:11:58 volumio1 kernel: bootdelay=7 Feb 14 11:11:58 volumio1 kernel: use_kmsg=no Feb 14 11:11:58 volumio1 kernel: mmc1: new high speed SDIO card at address 0001 Feb 14 11:11:58 volumio1 kernel: mmc0: new ultra high speed DDR50 SDHC card at address aaaa Feb 14 11:11:58 volumio1 kernel: mmcblk0: mmc0:aaaa SM32G 29.7 GiB Feb 14 11:11:58 volumio1 kernel: mmcblk0: p1 p2 p3 Feb 14 11:11:58 volumio1 kernel: mmcblk0: mmc0:aaaa SM32G 29.7 GiB (quirks 0x0000c000) Feb 14 11:11:58 volumio1 kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Feb 14 11:11:58 volumio1 kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Feb 14 11:11:58 volumio1 kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Feb 14 11:11:58 volumio1 kernel: usb 1-1: Product: USB2.0 Hub Feb 14 11:11:58 volumio1 kernel: hub 1-1:1.0: USB hub found Feb 14 11:11:58 volumio1 kernel: hub 1-1:1.0: 4 ports detected Feb 14 11:11:58 volumio1 kernel: fuse: init (API version 7.39) Feb 14 11:11:58 volumio1 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Feb 14 11:11:58 volumio1 kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Feb 14 11:11:58 volumio1 kernel: EXT4-fs (mmcblk0p2): recovery complete Feb 14 11:11:58 volumio1 kernel: EXT4-fs (mmcblk0p2): mounted filesystem ae35765e-0158-4d8d-a475-17b52add8039 r/w with ordered data mode. Quota mode: none. Feb 14 11:11:58 volumio1 kernel: loop0: detected capacity change from 0 to 996368 Feb 14 11:11:58 volumio1 kernel: EXT4-fs (mmcblk0p3): recovery complete Feb 14 11:11:58 volumio1 kernel: EXT4-fs (mmcblk0p3): mounted filesystem 8648801e-3ad8-424d-949f-c52da8279dd7 r/w with ordered data mode. Quota mode: none. Feb 14 11:11:58 volumio1 systemd[1]: System time before build time, advancing clock. Feb 14 11:11:58 volumio1 kernel: NET: Registered PF_INET6 protocol family Feb 14 11:11:58 volumio1 kernel: Segment Routing with IPv6 Feb 14 11:11:58 volumio1 kernel: In-situ OAM (IOAM) with IPv6 Feb 14 11:11:58 volumio1 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) Feb 14 11:11:58 volumio1 systemd[1]: Detected architecture arm. Feb 14 11:11:58 volumio1 systemd[1]: Set hostname to . Feb 14 11:11:58 volumio1 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. Feb 14 11:11:58 volumio1 systemd[1]: Listening on Journal Socket. Feb 14 11:11:58 volumio1 systemd[1]: Condition check resulted in Kernel Module supporting RPCSEC_GSS being skipped. Feb 14 11:11:58 volumio1 systemd[1]: Reached target System Time Synchronized. Feb 14 11:11:58 volumio1 systemd[1]: Listening on initctl Compatibility Named Pipe. Feb 14 11:11:58 volumio1 systemd[1]: Mounting POSIX Message Queue File System... Feb 14 11:11:58 volumio1 systemd[1]: Created slice system-getty.slice. Feb 14 11:11:58 volumio1 systemd-journald[314]: Journal started Feb 14 11:11:58 volumio1 systemd-journald[314]: Runtime journal (/run/log/journal/ba41094955353179f46b1ad365cba63d) is 3.7M, max 30.0M, 26.2M free. Feb 14 11:11:58 volumio1 systemd[1]: Mounted POSIX Message Queue File System. Feb 14 11:11:58 volumio1 systemd[1]: Mounted RPC Pipe File System. Feb 14 11:11:58 volumio1 systemd[1]: Started Remount Root and Kernel File Systems. Feb 14 11:11:58 volumio1 systemd[1]: Started Create list of required static device nodes for the current kernel. Feb 14 11:11:58 volumio1 systemd[1]: Mounted Kernel Debug File System. Jun 13 16:45:47 volumio1 fake-hwclock[315]: Fri Jun 13 14:45:47 UTC 2025 Jun 13 16:45:47 volumio1 systemd[1]: Starting Load/Save Random Seed... Jun 13 16:45:47 volumio1 systemd-modules-load[328]: Inserted module 'i2c_dev' Jun 13 16:45:47 volumio1 systemd[1]: Starting Create System Users... Jun 13 16:45:47 volumio1 kernel: i2c_dev: i2c /dev entries driver Jun 13 16:45:47 volumio1 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jun 13 16:45:47 volumio1 systemd[1]: Started Restore / save the current clock. Jun 13 16:45:47 volumio1 systemd[1]: Started Load Kernel Modules. Jun 13 16:45:47 volumio1 systemd[1]: Mounting Kernel Configuration File System... Jun 13 16:45:47 volumio1 systemd[1]: Starting Apply Kernel Variables... Jun 13 16:45:47 volumio1 systemd[1]: Mounting FUSE Control File System... Jun 13 16:45:47 volumio1 systemd[1]: Started Load/Save Random Seed. Jun 13 16:45:47 volumio1 systemd[1]: Mounted Kernel Configuration File System. Jun 13 16:45:47 volumio1 systemd[1]: Mounted FUSE Control File System. Jun 13 16:45:47 volumio1 systemd[1]: dynamicswap.service: Succeeded. Jun 13 16:45:47 volumio1 systemd[1]: Started Apply Kernel Variables. Jun 13 16:45:47 volumio1 systemd[1]: Started Create System Users. Jun 13 16:45:47 volumio1 systemd[1]: Starting Create Static Device Nodes in /dev... Jun 13 16:45:47 volumio1 systemd[1]: Started udev Coldplug all Devices. Jun 13 16:45:47 volumio1 systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jun 13 16:45:47 volumio1 systemd[1]: Started Create Static Device Nodes in /dev. Jun 13 16:45:47 volumio1 systemd[1]: Starting udev Kernel Device Manager... Jun 13 16:45:47 volumio1 systemd[1]: Reached target Local File Systems (Pre). Jun 13 16:45:47 volumio1 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jun 13 16:45:47 volumio1 systemd[1]: Mounting /var/log... Jun 13 16:45:47 volumio1 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jun 13 16:45:47 volumio1 systemd[1]: Mounting /tmp... Jun 13 16:45:47 volumio1 systemd[1]: Mounting /var/spool/cups... Jun 13 16:45:47 volumio1 systemd[1]: Mounted /var/log. Jun 13 16:45:47 volumio1 systemd[1]: Mounted /tmp. Jun 13 16:45:47 volumio1 systemd[1]: Mounted /var/spool/cups. Jun 13 16:45:47 volumio1 systemd[1]: Mounting /var/spool/cups/tmp... Jun 13 16:45:47 volumio1 systemd[1]: Starting Flush Journal to Persistent Storage... Jun 13 16:45:47 volumio1 systemd-udevd[347]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jun 13 16:45:47 volumio1 systemd[1]: Mounted /var/spool/cups/tmp. Jun 13 16:45:47 volumio1 systemd-journald[314]: Runtime journal (/run/log/journal/ba41094955353179f46b1ad365cba63d) is 7.5M, max 30.0M, 22.5M free. Jun 13 16:45:47 volumio1 systemd[1]: Started udev Kernel Device Manager. Jun 13 16:45:47 volumio1 systemd[1]: Starting Show Plymouth Boot Screen... Jun 13 16:45:47 volumio1 systemd[1]: Started Flush Journal to Persistent Storage. Jun 13 16:45:47 volumio1 systemd[1]: plymouth-start.service: Succeeded. Jun 13 16:45:47 volumio1 systemd[1]: Started Show Plymouth Boot Screen. Jun 13 16:45:47 volumio1 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jun 13 16:45:47 volumio1 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jun 13 16:45:47 volumio1 systemd[1]: Reached target Local Encrypted Volumes. Jun 13 16:45:47 volumio1 systemd[1]: Reached target Paths. Jun 13 16:45:47 volumio1 systemd-udevd[380]: Using default interface naming scheme 'v240'. Jun 13 16:45:47 volumio1 kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jun 13 16:45:47 volumio1 kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jun 13 16:45:47 volumio1 kernel: [vc_sm_connected_init]: start Jun 13 16:45:47 volumio1 kernel: [vc_sm_connected_init]: installed successfully Jun 13 16:45:47 volumio1 systemd-udevd[380]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jun 13 16:45:47 volumio1 kernel: mc: Linux media interface: v0.10 Jun 13 16:45:47 volumio1 kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Jun 13 16:45:47 volumio1 kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Jun 13 16:45:47 volumio1 systemd-udevd[385]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jun 13 16:45:47 volumio1 kernel: videodev: Linux video capture interface: v2.00 Jun 13 16:45:47 volumio1 kernel: usb 1-1.4: new high-speed USB device number 3 using xhci_hcd Jun 13 16:45:47 volumio1 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jun 13 16:45:47 volumio1 kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jun 13 16:45:47 volumio1 kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jun 13 16:45:47 volumio1 kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jun 13 16:45:47 volumio1 kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Jun 13 16:45:47 volumio1 kernel: rpivid feb10000.codec: Device registered as /dev/video19 Jun 13 16:45:47 volumio1 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jun 13 16:45:47 volumio1 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jun 13 16:45:47 volumio1 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jun 13 16:45:47 volumio1 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jun 13 16:45:47 volumio1 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jun 13 16:45:47 volumio1 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jun 13 16:45:47 volumio1 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jun 13 16:45:47 volumio1 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jun 13 16:45:47 volumio1 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jun 13 16:45:47 volumio1 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jun 13 16:45:47 volumio1 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Jun 13 16:45:47 volumio1 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Jun 13 16:45:47 volumio1 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Jun 13 16:45:47 volumio1 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Jun 13 16:45:47 volumio1 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jun 13 16:45:47 volumio1 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jun 13 16:45:47 volumio1 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jun 13 16:45:47 volumio1 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jun 13 16:45:47 volumio1 kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jun 13 16:45:47 volumio1 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jun 13 16:45:47 volumio1 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jun 13 16:45:47 volumio1 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jun 13 16:45:47 volumio1 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jun 13 16:45:47 volumio1 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Jun 13 16:45:47 volumio1 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Jun 13 16:45:47 volumio1 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Jun 13 16:45:47 volumio1 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Jun 13 16:45:47 volumio1 kernel: usb 1-1.4: New USB device found, idVendor=2752, idProduct=0045, bcdDevice= 1.76 Jun 13 16:45:47 volumio1 kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Jun 13 16:45:47 volumio1 kernel: usb 1-1.4: Product: SHD Jun 13 16:45:47 volumio1 kernel: usb 1-1.4: Manufacturer: miniDSP Jun 13 16:45:47 volumio1 kernel: hid-generic 0003:2752:0045.0001: hiddev96,hidraw0: USB HID v1.10 Device [miniDSP SHD] on usb-0000:01:00.0-1.4/input4 Jun 13 16:45:47 volumio1 kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jun 13 16:45:47 volumio1 kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Jun 13 16:45:47 volumio1 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jun 13 16:45:47 volumio1 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jun 13 16:45:47 volumio1 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jun 13 16:45:47 volumio1 kernel: Bluetooth: Core ver 2.22 Jun 13 16:45:47 volumio1 kernel: NET: Registered PF_BLUETOOTH protocol family Jun 13 16:45:47 volumio1 kernel: Bluetooth: HCI device and connection manager initialized Jun 13 16:45:47 volumio1 kernel: Bluetooth: HCI socket layer initialized Jun 13 16:45:47 volumio1 kernel: Bluetooth: L2CAP socket layer initialized Jun 13 16:45:47 volumio1 kernel: Bluetooth: SCO socket layer initialized Jun 13 16:45:47 volumio1 kernel: Bluetooth: HCI UART driver ver 2.3 Jun 13 16:45:47 volumio1 kernel: Bluetooth: HCI UART protocol H4 registered Jun 13 16:45:47 volumio1 kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jun 13 16:45:47 volumio1 kernel: Bluetooth: HCI UART protocol Broadcom registered Jun 13 16:45:47 volumio1 kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Jun 13 16:45:48 volumio1 kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Jun 13 16:45:48 volumio1 kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Jun 13 16:45:48 volumio1 kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Jun 13 16:45:48 volumio1 kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jun 13 16:45:48 volumio1 kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jun 13 16:45:48 volumio1 kernel: uart-pl011 fe201000.serial: no DMA platform data Jun 13 16:45:48 volumio1 kernel: alsactl[481]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Jun 13 16:45:48 volumio1 systemd[1]: Found device /dev/disk/by-uuid/6C60-DAB2. Jun 13 16:45:48 volumio1 kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Jun 13 16:45:48 volumio1 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Jun 13 16:45:48 volumio1 kernel: usbcore: registered new interface driver brcmfmac Jun 13 16:45:48 volumio1 systemd[1]: Created slice system-bthelper.slice. Jun 13 16:45:48 volumio1 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jun 13 16:45:48 volumio1 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jun 13 16:45:48 volumio1 kernel: Bluetooth: hci0: BCM: chip id 107 Jun 13 16:45:48 volumio1 kernel: Bluetooth: hci0: BCM: features 0x2f Jun 13 16:45:48 volumio1 kernel: Bluetooth: hci0: BCM4345C0 Jun 13 16:45:48 volumio1 kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Jun 13 16:45:48 volumio1 kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Jun 13 16:45:48 volumio1 systemd[1]: Starting Show Plymouth Boot Screen... Jun 13 16:45:48 volumio1 systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jun 13 16:45:48 volumio1 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jun 13 16:45:48 volumio1 systemd[1]: Starting File System Check on /dev/disk/by-uuid/6C60-DAB2... Jun 13 16:45:48 volumio1 systemd[1]: Found device /sys/subsystem/net/devices/eth0. Jun 13 16:45:48 volumio1 systemd[1]: Starting Load/Save RF Kill Switch Status... Jun 13 16:45:48 volumio1 systemd[1]: Started File System Check Daemon to report status. Jun 13 16:45:48 volumio1 systemd[1]: plymouth-start.service: Succeeded. Jun 13 16:45:48 volumio1 systemd[1]: Started Show Plymouth Boot Screen. Jun 13 16:45:48 volumio1 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jun 13 16:45:48 volumio1 systemd[1]: Started Load/Save RF Kill Switch Status. Jun 13 16:45:48 volumio1 kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Jun 13 16:45:48 volumio1 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 Jun 13 16:45:48 volumio1 systemd-udevd[376]: Using default interface naming scheme 'v240'. Jun 13 16:45:48 volumio1 systemd-udevd[376]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jun 13 16:45:48 volumio1 systemd-fsck[494]: fsck.fat 4.1 (2017-01-24) Jun 13 16:45:48 volumio1 systemd-fsck[494]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Jun 13 16:45:48 volumio1 systemd-fsck[494]: Automatically removing dirty bit. Jun 13 16:45:48 volumio1 systemd-fsck[494]: Performing changes. Jun 13 16:45:48 volumio1 systemd-fsck[494]: /dev/mmcblk0p1: 406 files, 41746/46774 clusters Jun 13 16:45:48 volumio1 systemd[1]: Started File System Check on /dev/disk/by-uuid/6C60-DAB2. Jun 13 16:45:48 volumio1 systemd[1]: Mounting /boot... Jun 13 16:45:48 volumio1 systemd[1]: Mounted /boot. Jun 13 16:45:48 volumio1 systemd[1]: Reached target Local File Systems. Jun 13 16:45:48 volumio1 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jun 13 16:45:48 volumio1 systemd[1]: Starting Preprocess NFS configuration... Jun 13 16:45:48 volumio1 systemd[1]: Starting Create Volatile Files and Directories... Jun 13 16:45:48 volumio1 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jun 13 16:45:48 volumio1 systemd[1]: Started ifup for eth0. Jun 13 16:45:48 volumio1 systemd[1]: plymouth-read-write.service: Succeeded. Jun 13 16:45:48 volumio1 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jun 13 16:45:48 volumio1 systemd[1]: Received SIGRTMIN+20 from PID 210 (plymouthd). Jun 13 16:45:48 volumio1 systemd[1]: nfs-config.service: Succeeded. Jun 13 16:45:48 volumio1 systemd[1]: Started Preprocess NFS configuration. Jun 13 16:45:48 volumio1 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jun 13 16:45:48 volumio1 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jun 13 16:45:48 volumio1 systemd[1]: Reached target NFS client services. Jun 13 16:45:48 volumio1 systemd[1]: Started Create Volatile Files and Directories. Jun 13 16:45:48 volumio1 systemd[1]: Starting RPC bind portmap service... Jun 13 16:45:48 volumio1 systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jun 13 16:45:48 volumio1 systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jun 13 16:45:48 volumio1 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jun 13 16:45:48 volumio1 kernel: 8021q: 802.1Q VLAN Support v1.8 Jun 13 16:45:48 volumio1 systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Jun 13 16:45:48 volumio1 systemd[1]: Started RPC bind portmap service. Jun 13 16:45:48 volumio1 systemd[1]: Reached target RPC Port Mapper. Jun 13 16:45:48 volumio1 systemd[1]: Reached target Remote File Systems (Pre). Jun 13 16:45:48 volumio1 systemd[1]: Reached target Remote File Systems. Jun 13 16:45:48 volumio1 sh[518]: eth0: waiting for carrier Jun 13 16:45:48 volumio1 kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Jun 13 16:45:48 volumio1 kernel: bcmgenet fd580000.ethernet eth0: Link is Down Jun 13 16:45:48 volumio1 dhcpcd[538]: eth0: waiting for carrier Jun 13 16:45:48 volumio1 systemd[1]: Started ifup for wlan0. Jun 13 16:45:48 volumio1 systemd[1]: Started Update UTMP about System Boot/Shutdown. Jun 13 16:45:48 volumio1 systemd[1]: Reached target System Initialization. Jun 13 16:45:48 volumio1 systemd[1]: Started Daily Cleanup of Temporary Directories. Jun 13 16:45:48 volumio1 systemd[1]: Listening on D-Bus System Message Bus Socket. Jun 13 16:45:48 volumio1 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jun 13 16:45:48 volumio1 systemd[1]: apt-daily.timer: Not using persistent file timestamp Wed 2025-06-25 09:58:21 CEST as it is in the future. Jun 13 16:45:48 volumio1 systemd[1]: Started Daily apt download activities. Jun 13 16:45:48 volumio1 systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Wed 2025-06-25 06:46:21 CEST as it is in the future. Jun 13 16:45:48 volumio1 systemd[1]: Started Daily apt upgrade and clean activities. Jun 13 16:45:48 volumio1 systemd[1]: Reached target Timers. Jun 13 16:45:48 volumio1 systemd[1]: Listening on triggerhappy.socket. Jun 13 16:45:48 volumio1 systemd[1]: Reached target Sockets. Jun 13 16:45:48 volumio1 systemd[1]: Reached target Basic System. Jun 13 16:45:48 volumio1 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jun 13 16:45:48 volumio1 systemd[1]: Starting Login Service... Jun 13 16:45:48 volumio1 kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Jun 13 16:45:48 volumio1 systemd[1]: Started UPnP Renderer front-end to MPD. Jun 13 16:45:48 volumio1 systemd[1]: Started MPD Monitor Service. Jun 13 16:45:48 volumio1 systemd[1]: Starting Wireless Services... Jun 13 16:45:48 volumio1 systemd[1]: Started Manage Sound Card State (restore and store). Jun 13 16:45:48 volumio1 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jun 13 16:45:48 volumio1 mpd_monitor.sh[570]: MPD Monitor Service: Starting MPD Monitor Service Jun 13 16:45:48 volumio1 systemd[1]: Started Volumio Iptables Module. Jun 13 16:45:48 volumio1 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jun 13 16:45:48 volumio1 alsactl[574]: alsactl 1.1.8 daemon started Jun 13 16:45:48 volumio1 systemd[1]: Starting dhcpcd on all interfaces... Jun 13 16:45:48 volumio1 systemd[1]: Started Volumio Log Rotation Service. Jun 13 16:45:48 volumio1 systemd[1]: Starting Volumio Time Update Utility... Jun 13 16:45:48 volumio1 systemd[1]: Starting triggerhappy global hotkey daemon... Jun 13 16:45:48 volumio1 systemd[1]: Started D-Bus System Message Bus. Jun 13 16:45:48 volumio1 systemd[1]: Starting WPA supplicant... Jun 13 16:45:48 volumio1 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jun 13 16:45:48 volumio1 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jun 13 16:45:48 volumio1 systemd[1]: Starting Save/Restore Sound Card State... Jun 13 16:45:48 volumio1 systemd[1]: Starting Configure Bluetooth Modems connected by UART... Jun 13 16:45:48 volumio1 systemd[1]: Started volumio-remote-updater.service. Jun 13 16:45:48 volumio1 volumio-time-update[583]: volumio-time-update-util: Fetching time from Volumio... Jun 13 16:45:49 volumio1 dhcpcd[579]: Not running dhcpcd because /etc/network/interfaces Jun 13 16:45:49 volumio1 dhcpcd[579]: defines some interfaces that will use a Jun 13 16:45:49 volumio1 dhcpcd[579]: DHCP client or static address Jun 13 16:45:49 volumio1 thd[584]: Unable to parse trigger line: Jun 13 16:45:49 volumio1 thd[584]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jun 13 16:45:49 volumio1 thd[584]: Unable to parse trigger line: Jun 13 16:45:49 volumio1 thd[584]: Found socket passed from systemd Jun 13 16:45:49 volumio1 systemd-logind[566]: New seat seat0. Jun 13 16:45:49 volumio1 kernel: Bluetooth: hci0: BCM: features 0x2f Jun 13 16:45:49 volumio1 kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Jun 13 16:45:49 volumio1 kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Jun 13 16:45:49 volumio1 mpd_monitor.sh[570]: MPD error: Connection refused Jun 13 16:45:49 volumio1 mpd_monitor.sh[570]: MPD Monitor Service: MPD Appears to be inactive, restarting Jun 13 16:45:49 volumio1 avahi-daemon[564]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jun 13 16:45:49 volumio1 avahi-daemon[564]: Successfully dropped root privileges. Jun 13 16:45:49 volumio1 avahi-daemon[564]: avahi-daemon 0.7 starting up. Jun 13 16:45:49 volumio1 mpd_monitor.sh[570]: mpd: no process found Jun 13 16:45:49 volumio1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 13 16:45:49 volumio1 systemd[1]: Started Login Service. Jun 13 16:45:49 volumio1 wpa_supplicant[587]: Successfully initialized wpa_supplicant Jun 13 16:45:49 volumio1 systemd[1]: Started triggerhappy global hotkey daemon. Jun 13 16:45:49 volumio1 systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Jun 13 16:45:49 volumio1 systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Jun 13 16:45:49 volumio1 systemd[1]: Failed to start dhcpcd on all interfaces. Jun 13 16:45:49 volumio1 systemd[1]: Started Save/Restore Sound Card State. Jun 13 16:45:49 volumio1 avahi-daemon[564]: Successfully called chroot(). Jun 13 16:45:49 volumio1 avahi-daemon[564]: Successfully dropped remaining capabilities. Jun 13 16:45:49 volumio1 systemd[1]: hciuart.service: Succeeded. Jun 13 16:45:49 volumio1 systemd[1]: Started Configure Bluetooth Modems connected by UART. Jun 13 16:45:49 volumio1 avahi-daemon[564]: Loading service file /services/volumio.service. Jun 13 16:45:49 volumio1 avahi-daemon[564]: Network interface enumeration completed. Jun 13 16:45:49 volumio1 avahi-daemon[564]: Server startup complete. Host name is volumio1.local. Local service cookie is 1009474604. Jun 13 16:45:49 volumio1 avahi-daemon[564]: Service "Volumio1" (/services/volumio.service) successfully established. Jun 13 16:45:49 volumio1 systemd[1]: Started WPA supplicant. Jun 13 16:45:49 volumio1 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jun 13 16:45:49 volumio1 sh[542]: wlan0=wlan0 Jun 13 16:45:49 volumio1 systemd[1]: Listening on mpd.socket. Jun 13 16:45:49 volumio1 systemd[1]: Starting Raspberry Pi bluetooth helper... Jun 13 16:45:49 volumio1 systemd[1]: Reached target Sound Card. Jun 13 16:45:49 volumio1 volumio-remote-updater[591]: Error: No active session Jun 13 16:45:49 volumio1 volumio-remote-updater[591]: [2025-06-13 16:45:49] [info] asio async_connect error: system:111 (Connection refused) Jun 13 16:45:49 volumio1 volumio-remote-updater[591]: [2025-06-13 16:45:49] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 13 16:45:49 volumio1 volumio-remote-updater[591]: [2025-06-13 16:45:49] [error] handle_connect error: Underlying Transport Error Jun 13 16:45:49 volumio1 volumio[569]: Could not open config: /tmp/upmpdcli.conf Jun 13 16:45:49 volumio1 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 13 16:45:49 volumio1 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 13 16:45:49 volumio1 bthelper[651]: Raspberry Pi BDADDR already set Jun 13 16:45:49 volumio1 kernel: usbcore: registered new interface driver snd-usb-audio Jun 13 16:45:49 volumio1 systemd[1]: Started Raspberry Pi bluetooth helper. Jun 13 16:45:49 volumio1 volumio-time-update[583]: volumio-time-update-util: Date not found in response Jun 13 16:45:49 volumio1 volumio-time-update[583]: volumio-time-update-util: Retrying in 5 seconds... Jun 13 16:45:49 volumio1 haveged[539]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jun 13 16:45:49 volumio1 haveged[539]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jun 13 16:45:49 volumio1 haveged[539]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00215 Jun 13 16:45:49 volumio1 haveged[539]: haveged: fills: 0, generated: 0 Jun 13 16:45:49 volumio1 loadcpufreq[588]: Loading cpufreq kernel modules...done (none). Jun 13 16:45:49 volumio1 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jun 13 16:45:49 volumio1 systemd[1]: iptables.service: Succeeded. Jun 13 16:45:49 volumio1 systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jun 13 16:45:49 volumio1 systemd[1]: Starting Bluetooth service... Jun 13 16:45:50 volumio1 systemd-udevd[376]: Process '/bin/bash -c '/usr/local/bin/volumio scanaudioinputs'' failed with exit code 7. Jun 13 16:45:50 volumio1 systemd-udevd[365]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Jun 13 16:45:50 volumio1 systemd[1]: Started Helper to synchronize boot up for ifupdown. Jun 13 16:45:50 volumio1 systemd[1]: Starting Raise network interfaces... Jun 13 16:45:50 volumio1 cpufrequtils[693]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jun 13 16:45:50 volumio1 systemd[1]: Started LSB: set CPUFreq kernel parameters. Jun 13 16:45:50 volumio1 bluetoothd[694]: Bluetooth daemon 5.50 Jun 13 16:45:50 volumio1 systemd[1]: Started Raise network interfaces. Jun 13 16:45:50 volumio1 systemd[1]: Reached target Network. Jun 13 16:45:50 volumio1 bluetoothd[694]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jun 13 16:45:50 volumio1 bluetoothd[694]: Starting SDP server Jun 13 16:45:50 volumio1 bluetoothd[694]: Excluding (cli) sap Jun 13 16:45:50 volumio1 systemd[1]: Starting Permit User Sessions... Jun 13 16:45:50 volumio1 systemd[1]: Starting Network Time Service... Jun 13 16:45:50 volumio1 systemd[1]: Starting Music Player Daemon... Jun 13 16:45:50 volumio1 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jun 13 16:45:50 volumio1 systemd[1]: Reached target Network is Online. Jun 13 16:45:50 volumio1 systemd[1]: Starting LSB: Brings up/down network automatically... Jun 13 16:45:50 volumio1 systemd[1]: Starting Samba NMB Daemon... Jun 13 16:45:50 volumio1 bluetoothd[694]: Bluetooth management interface 1.22 initialized Jun 13 16:45:50 volumio1 dbus-daemon[585]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=694 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jun 13 16:45:50 volumio1 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jun 13 16:45:50 volumio1 kernel: Bluetooth: BNEP filters: protocol multicast Jun 13 16:45:50 volumio1 kernel: Bluetooth: BNEP socket layer initialized Jun 13 16:45:50 volumio1 kernel: Bluetooth: MGMT ver 1.22 Jun 13 16:45:50 volumio1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 13 16:45:50 volumio1 systemd[1]: Starting /etc/rc.local Compatibility... Jun 13 16:45:50 volumio1 systemd[1]: Started Permit User Sessions. Jun 13 16:45:50 volumio1 systemd[1]: Started Bluetooth service. Jun 13 16:45:50 volumio1 bluetoothd[694]: Failed to set privacy: Rejected (0x0b) Jun 13 16:45:50 volumio1 ntpd[769]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 13 16:45:50 volumio1 ntpd[769]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jun 13 16:45:50 volumio1 ntpd[785]: proto: precision = 0.833 usec (-20) Jun 13 16:45:50 volumio1 ntpd[785]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 13 16:45:50 volumio1 ntpd[785]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 13 16:45:50 volumio1 ntpd[785]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 168 days ago Jun 13 16:45:50 volumio1 ntpd[785]: Listen and drop on 0 v6wildcard [::]:123 Jun 13 16:45:50 volumio1 ntpd[785]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 13 16:45:50 volumio1 ntpd[785]: Listen normally on 2 lo 127.0.0.1:123 Jun 13 16:45:50 volumio1 ntpd[785]: Listening on routing socket on fd #19 for interface updates Jun 13 16:45:50 volumio1 ntpd[785]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 13 16:45:50 volumio1 ntpd[785]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 13 16:45:50 volumio1 systemd[1]: Started Network Time Service. Jun 13 16:45:50 volumio1 systemd[1]: Started /etc/rc.local Compatibility. Jun 13 16:45:50 volumio1 systemd[1]: Starting Hostname Service... Jun 13 16:45:50 volumio1 systemd[1]: Reached target Bluetooth. Jun 13 16:45:50 volumio1 systemd[1]: Starting Hold until boot process finishes up... Jun 13 16:45:50 volumio1 systemd[1]: Starting Terminate Plymouth Boot Screen... Jun 13 16:45:50 volumio1 systemd[1]: Received SIGRTMIN+21 from PID 210 (plymouthd). Jun 13 16:45:50 volumio1 sudo[770]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 13 16:45:50 volumio1 sudo[770]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 16:45:50 volumio1 sudo[770]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jun 13 16:45:50 volumio1 sudo[770]: pam_unix(sudo:session): session closed for user root Jun 13 16:45:50 volumio1 systemd[1]: plymouth-quit.service: Succeeded. Jun 13 16:45:50 volumio1 systemd[1]: Started Terminate Plymouth Boot Screen. Jun 13 16:45:50 volumio1 systemd[1]: plymouth-quit-wait.service: Succeeded. Jun 13 16:45:50 volumio1 systemd[1]: Started Hold until boot process finishes up. Jun 13 16:45:50 volumio1 systemd[1]: Received SIGRTMIN+21 from PID 210 (plymouthd). Jun 13 16:45:50 volumio1 systemd[1]: Started Getty on tty1. Jun 13 16:45:50 volumio1 systemd[1]: Reached target Login Prompts. Jun 13 16:45:50 volumio1 ifplugd(eth0)[804]: ifplugd 0.28 initializing. Jun 13 16:45:50 volumio1 ifplugd(eth0)[804]: Using interface eth0/DC:A6:32:FB:B4:84 with driver (version: 6.6.62-v7l+) Jun 13 16:45:50 volumio1 ifplugd(eth0)[804]: Using detection mode: SIOCETHTOOL Jun 13 16:45:50 volumio1 ifplugd(eth0)[804]: Initialization complete, link beat not detected. Jun 13 16:45:50 volumio1 dbus-daemon[585]: [system] Successfully activated service 'org.freedesktop.hostname1' Jun 13 16:45:50 volumio1 systemd[1]: Started Hostname Service. Jun 13 16:45:50 volumio1 ifplugd[773]: Network Interface Plugging Daemon...start eth0...done. Jun 13 16:45:50 volumio1 systemd[1]: Started LSB: Brings up/down network automatically. Jun 13 16:45:51 volumio1 nmbd[780]: [2025/06/13 16:45:51.296587, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jun 13 16:45:51 volumio1 nmbd[780]: started asyncdns process 805 Jun 13 16:45:51 volumio1 nmbd[780]: [2025/06/13 16:45:51.298182, 0] ../lib/util/become_daemon.c:149(daemon_status) Jun 13 16:45:51 volumio1 nmbd[780]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jun 13 16:45:51 volumio1 nmbd[780]: [2025/06/13 16:45:51.298346, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jun 13 16:45:51 volumio1 nmbd[780]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jun 13 16:45:51 volumio1 wireless.js[573]: Cleaning previous... Jun 13 16:45:51 volumio1 sudo[813]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 13 16:45:51 volumio1 sudo[813]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 16:45:51 volumio1 sudo[813]: pam_unix(sudo:session): session closed for user root Jun 13 16:45:51 volumio1 sudo[815]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 13 16:45:51 volumio1 sudo[815]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 16:45:52 volumio1 ntpd[785]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 13 16:45:52 volumio1 sudo[815]: pam_unix(sudo:session): session closed for user root Jun 13 16:45:52 volumio1 wireless.js[573]: Stopped aP Jun 13 16:45:52 volumio1 sudo[822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 13 16:45:52 volumio1 sudo[822]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 16:45:52 volumio1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 13 16:45:52 volumio1 sudo[822]: pam_unix(sudo:session): session closed for user root Jun 13 16:45:52 volumio1 sudo[824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jun 13 16:45:52 volumio1 sudo[824]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 16:45:52 volumio1 sudo[824]: pam_unix(sudo:session): session closed for user root Jun 13 16:45:52 volumio1 sudo[831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 13 16:45:52 volumio1 sudo[831]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 16:45:52 volumio1 sudo[831]: pam_unix(sudo:session): session closed for user root Jun 13 16:45:52 volumio1 sudo[833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jun 13 16:45:52 volumio1 sudo[833]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 16:45:52 volumio1 ntpd[785]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 13 16:45:52 volumio1 mpd[795]: Jun 13 16:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 13 16:45:52 volumio1 sh[518]: eth0: carrier acquired Jun 13 16:45:52 volumio1 dhcpcd[538]: eth0: carrier acquired Jun 13 16:45:53 volumio1 sh[518]: DUID 00:01:00:01:2d:5e:64:97:dc:a6:32:fb:b4:84 Jun 13 16:45:53 volumio1 sh[518]: eth0: IAID 32:fb:b4:84 Jun 13 16:45:53 volumio1 sh[518]: eth0: adding address fe80::dea6:32ff:fefb:b484 Jun 13 16:45:53 volumio1 sh[518]: ipv6_addaddr1: Permission denied Jun 13 16:45:53 volumio1 dhcpcd[538]: DUID 00:01:00:01:2d:5e:64:97:dc:a6:32:fb:b4:84 Jun 13 16:45:53 volumio1 dhcpcd[538]: eth0: IAID 32:fb:b4:84 Jun 13 16:45:53 volumio1 dhcpcd[538]: eth0: adding address fe80::dea6:32ff:fefb:b484 Jun 13 16:45:53 volumio1 dhcpcd[538]: ipv6_addaddr1: Permission denied Jun 13 16:45:53 volumio1 sh[518]: eth0: soliciting an IPv6 router Jun 13 16:45:53 volumio1 dhcpcd[538]: eth0: soliciting an IPv6 router Jun 13 16:45:53 volumio1 kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Jun 13 16:45:53 volumio1 ifplugd(eth0)[804]: Link beat detected. Jun 13 16:45:53 volumio1 ifplugd(eth0)[804]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jun 13 16:45:53 volumio1 ifplugd(eth0)[804]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Jun 13 16:45:53 volumio1 systemd[1]: Started Music Player Daemon. Jun 13 16:45:53 volumio1 mpd_monitor.sh[570]: MPD Monitor Service: MPD restarted due to no mpc output. Jun 13 16:45:53 volumio1 sh[518]: eth0: rebinding lease of 192.168.50.30 Jun 13 16:45:53 volumio1 dhcpcd[538]: eth0: rebinding lease of 192.168.50.30 Jun 13 16:45:53 volumio1 systemd[1]: systemd-rfkill.service: Succeeded. Jun 13 16:45:53 volumio1 sh[518]: eth0: probing address 192.168.50.30/24 Jun 13 16:45:53 volumio1 dhcpcd[538]: eth0: probing address 192.168.50.30/24 Jun 13 16:45:53 volumio1 ntpd[785]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 13 16:45:54 volumio1 ntpd[785]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 13 16:45:54 volumio1 volumio-remote-updater[591]: [2025-06-13 16:45:54] [info] asio async_connect error: system:111 (Connection refused) Jun 13 16:45:54 volumio1 volumio-remote-updater[591]: [2025-06-13 16:45:54] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 13 16:45:54 volumio1 volumio-remote-updater[591]: [2025-06-13 16:45:54] [error] handle_connect error: Underlying Transport Error Jun 13 16:45:54 volumio1 sudo[833]: pam_unix(sudo:session): session closed for user root Jun 13 16:45:54 volumio1 wireless.js[573]: SETTING APPROPRIATE REG DOMAIN: DE Jun 13 16:45:54 volumio1 sudo[847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 13 16:45:54 volumio1 sudo[847]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 16:45:54 volumio1 sudo[847]: pam_unix(sudo:session): session closed for user root Jun 13 16:45:54 volumio1 sudo[849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Jun 13 16:45:54 volumio1 sudo[849]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 16:45:54 volumio1 sudo[849]: pam_unix(sudo:session): session closed for user root Jun 13 16:45:54 volumio1 wireless.js[573]: SUCCESSFULLY SET NEW REGDOMAIN: DE Jun 13 16:45:54 volumio1 volumio-time-update[583]: volumio-time-update-util: Fetching time from Volumio... Jun 13 16:45:54 volumio1 volumio-time-update[583]: volumio-time-update-util: Date not found in response Jun 13 16:45:54 volumio1 volumio-time-update[583]: volumio-time-update-util: Retrying in 5 seconds... Jun 13 16:45:54 volumio1 bthelper[651]: Changing power off succeeded Jun 13 16:45:54 volumio1 bthelper[651]: [CHG] Controller DC:A6:32:FB:B4:86 Powered: no Jun 13 16:45:54 volumio1 bthelper[651]: [CHG] Controller DC:A6:32:FB:B4:86 Discovering: no Jun 13 16:45:54 volumio1 bthelper[651]: [CHG] Controller DC:A6:32:FB:B4:86 Class: 0x00000000 Jun 13 16:45:54 volumio1 sudo[862]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 13 16:45:54 volumio1 sudo[862]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 16:45:54 volumio1 sudo[862]: pam_unix(sudo:session): session closed for user root Jun 13 16:45:54 volumio1 sudo[864]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 13 16:45:54 volumio1 sudo[864]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 16:45:55 volumio1 bthelper[651]: [CHG] Controller DC:A6:32:FB:B4:86 Class: 0x0000041c Jun 13 16:45:55 volumio1 bthelper[651]: Changing power on succeeded Jun 13 16:45:55 volumio1 sudo[864]: pam_unix(sudo:session): session closed for user root Jun 13 16:45:55 volumio1 avahi-daemon[564]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jun 13 16:45:55 volumio1 avahi-daemon[564]: New relevant interface wlan0.IPv4 for mDNS. Jun 13 16:45:55 volumio1 avahi-daemon[564]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Jun 13 16:45:55 volumio1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 13 16:45:55 volumio1 systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jun 13 16:45:55 volumio1 systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jun 13 16:45:55 volumio1 dnsmasq[877]: dnsmasq: syntax check OK. Jun 13 16:45:55 volumio1 hostapd[878]: Configuration file: /etc/hostapd/hostapd.conf Jun 13 16:45:55 volumio1 hostapd[878]: wlan0: Could not connect to kernel driver Jun 13 16:45:55 volumio1 hostapd[878]: Using interface wlan0 with hwaddr dc:a6:32:fb:b4:85 and ssid "Volumio-AE29D" Jun 13 16:45:55 volumio1 dnsmasq[884]: started, version 2.80 cachesize 150 Jun 13 16:45:55 volumio1 dnsmasq[884]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jun 13 16:45:55 volumio1 dnsmasq-dhcp[884]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jun 13 16:45:55 volumio1 dnsmasq[884]: reading /etc/resolv.conf Jun 13 16:45:55 volumio1 dnsmasq[884]: using nameserver 208.67.222.222#53 Jun 13 16:45:55 volumio1 dnsmasq[884]: using nameserver 208.67.220.220#53 Jun 13 16:45:55 volumio1 dnsmasq[884]: read /etc/hosts - 1 addresses Jun 13 16:45:55 volumio1 systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jun 13 16:45:55 volumio1 systemd[1]: Reached target Host and Network Name Lookups. Jun 13 16:45:55 volumio1 hostapd[878]: wlan0: interface state UNINITIALIZED->ENABLED Jun 13 16:45:55 volumio1 hostapd[878]: wlan0: AP-ENABLED Jun 13 16:45:55 volumio1 systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jun 13 16:45:55 volumio1 iwconfig[892]: Error for wireless request "Set Power Management" (8B2C) : Jun 13 16:45:55 volumio1 iwconfig[892]: SET failed on device wlan0 ; Invalid argument. Jun 13 16:45:55 volumio1 systemd[1]: wireless.service: Succeeded. Jun 13 16:45:55 volumio1 systemd[1]: Started Wireless Services. Jun 13 16:45:55 volumio1 systemd[1]: Started Volumio Backend Module. Jun 13 16:45:55 volumio1 systemd[1]: Started Volumio Cpu Tweaker. Jun 13 16:45:55 volumio1 volumio-cpu-tweak[894]: Setting RT Priority for mpd Jun 13 16:45:55 volumio1 volumio-cpu-tweak[894]: Setting MPD Affinity Jun 13 16:45:55 volumio1 volumio-cpu-tweak[894]: pid 795's current affinity mask: f Jun 13 16:45:55 volumio1 volumio-cpu-tweak[894]: pid 795's new affinity mask: 3 Jun 13 16:45:55 volumio1 volumio-cpu-tweak[894]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jun 13 16:45:55 volumio1 volumio-cpu-tweak[894]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jun 13 16:45:55 volumio1 systemd[1]: volumio_cpu_tweak.service: Succeeded. Jun 13 16:45:56 volumio1 nmbd[780]: [2025/06/13 16:45:56.302549, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 13 16:45:56 volumio1 systemd[1]: Started Samba NMB Daemon. Jun 13 16:45:56 volumio1 nmbd[780]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 13 16:45:56 volumio1 systemd[1]: Starting Samba Winbind Daemon... Jun 13 16:45:56 volumio1 winbindd[910]: [2025/06/13 16:45:56.563968, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 13 16:45:56 volumio1 winbindd[910]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 13 16:45:56 volumio1 winbindd[910]: [2025/06/13 16:45:56.600242, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 13 16:45:56 volumio1 winbindd[910]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 13 16:45:56 volumio1 systemd[1]: Started Samba Winbind Daemon. Jun 13 16:45:56 volumio1 systemd[1]: Starting Samba SMB Daemon... Jun 13 16:45:57 volumio1 smbd[915]: [2025/06/13 16:45:57.071980, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 13 16:45:57 volumio1 smbd[915]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 13 16:45:57 volumio1 systemd[1]: Started Samba SMB Daemon. Jun 13 16:45:57 volumio1 volumio[893]: info: ------------------------------------------- Jun 13 16:45:57 volumio1 ntpd[785]: Listen normally on 3 wlan0 192.168.211.1:123 Jun 13 16:45:57 volumio1 ntpd[785]: new interface(s) found: waking up resolver Jun 13 16:45:57 volumio1 volumio[893]: info: ----- Volumio3 ---- Jun 13 16:45:57 volumio1 volumio[893]: info: ------------------------------------------- Jun 13 16:45:57 volumio1 volumio[893]: info: ----- System startup ---- Jun 13 16:45:57 volumio1 volumio[893]: info: ------------------------------------------- Jun 13 16:45:58 volumio1 volumio[893]: info: MYVOLUMIO Environment detected Jun 13 16:45:58 volumio1 volumio[893]: info: Plugin folders cleanup Jun 13 16:45:58 volumio1 volumio[893]: info: Scanning into folder /volumio/app/plugins/ Jun 13 16:45:58 volumio1 volumio[893]: info: Scanning category audio_interface Jun 13 16:45:58 volumio1 volumio[893]: info: Scanning category miscellanea Jun 13 16:45:58 volumio1 volumio[893]: info: Scanning category music_service Jun 13 16:45:58 volumio1 volumio[893]: info: Scanning category plugins.json Jun 13 16:45:58 volumio1 volumio[893]: info: Scanning category system_controller Jun 13 16:45:58 volumio1 volumio[893]: info: Scanning category user_interface Jun 13 16:45:58 volumio1 volumio[893]: info: Scanning into folder /data/plugins/ Jun 13 16:45:58 volumio1 volumio[893]: info: Scanning category music_service Jun 13 16:45:58 volumio1 volumio[893]: info: Scanning category user_interface Jun 13 16:45:58 volumio1 volumio[893]: info: Plugin folders cleanup completed Jun 13 16:45:58 volumio1 volumio[893]: info: ------------------------------------------- Jun 13 16:45:58 volumio1 volumio[893]: info: ----- Core plugins startup ---- Jun 13 16:45:58 volumio1 volumio[893]: info: ------------------------------------------- Jun 13 16:45:58 volumio1 volumio[893]: info: Loading plugins from folder /volumio/app/plugins/ Jun 13 16:45:58 volumio1 volumio[893]: info: Adding plugin upnp to MyMusic Plugins Jun 13 16:45:58 volumio1 volumio[893]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 13 16:45:58 volumio1 volumio[893]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 13 16:45:58 volumio1 volumio[893]: info: Loading plugins from folder /data/plugins/ Jun 13 16:45:58 volumio1 volumio[893]: info: Loading plugin "system"... Jun 13 16:45:58 volumio1 volumio[893]: info: Loading plugin "appearance"... Jun 13 16:45:58 volumio1 sh[518]: eth0: leased 192.168.50.30 for 86400 seconds Jun 13 16:45:58 volumio1 dhcpcd[538]: eth0: leased 192.168.50.30 for 86400 seconds Jun 13 16:45:58 volumio1 sh[518]: eth0: adding route to 192.168.50.0/24 Jun 13 16:45:58 volumio1 sh[518]: eth0: adding default route via 192.168.50.1 Jun 13 16:45:58 volumio1 dhcpcd[538]: eth0: adding route to 192.168.50.0/24 Jun 13 16:45:58 volumio1 dhcpcd[538]: eth0: adding default route via 192.168.50.1 Jun 13 16:45:58 volumio1 avahi-daemon[564]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.50.30. Jun 13 16:45:58 volumio1 avahi-daemon[564]: New relevant interface eth0.IPv4 for mDNS. Jun 13 16:45:58 volumio1 avahi-daemon[564]: Registering new address record for 192.168.50.30 on eth0.IPv4. Jun 13 16:45:58 volumio1 dnsmasq[884]: reading /etc/resolv.conf Jun 13 16:45:58 volumio1 dnsmasq[884]: using nameserver 192.168.50.1#53 Jun 13 16:45:58 volumio1 dnsmasq[884]: using nameserver 208.67.222.222#53 Jun 13 16:45:58 volumio1 dnsmasq[884]: using nameserver 208.67.220.220#53 Jun 13 16:45:58 volumio1 sh[518]: forked to background, child pid 956 Jun 13 16:45:58 volumio1 dhcpcd[538]: forked to background, child pid 956 Jun 13 16:45:58 volumio1 ifplugd(eth0)[804]: client: ifup: interface eth0 already configured Jun 13 16:45:58 volumio1 sh[518]: eth0=eth0 Jun 13 16:45:58 volumio1 ifplugd(eth0)[804]: Program executed successfully. Jun 13 16:45:59 volumio1 volumio[893]: info: Loading plugin "network"... Jun 13 16:45:59 volumio1 volumio[893]: info: Refreshing Cached IP Addresses Jun 13 16:45:59 volumio1 sudo[983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 13 16:45:59 volumio1 sudo[983]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 16:45:59 volumio1 sudo[983]: pam_unix(sudo:session): session closed for user root Jun 13 16:45:59 volumio1 sudo[985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 13 16:45:59 volumio1 sudo[985]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 16:45:59 volumio1 sudo[985]: pam_unix(sudo:session): session closed for user root Jun 13 16:45:59 volumio1 volumio[893]: info: Loading plugin "services"... Jun 13 16:45:59 volumio1 volumio[893]: info: Loading plugin "alsa_controller"... Jun 13 16:45:59 volumio1 sudo[998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 13 16:45:59 volumio1 sudo[998]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 16:45:59 volumio1 volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 13 16:45:59 volumio1 volumio[893]: info: Loading plugin "wizard"... Jun 13 16:45:59 volumio1 volumio[893]: info: Loading plugin "networkfs"... Jun 13 16:45:59 volumio1 volumio[893]: info: Starting Udev Watcher for removable devices Jun 13 16:45:59 volumio1 ntpd[785]: Listen normally on 4 eth0 192.168.50.30:123 Jun 13 16:45:59 volumio1 ntpd[785]: new interface(s) found: waking up resolver Jun 13 16:45:59 volumio1 sudo[1011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=manu,password=Morretes-Uki-2023,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.95/Manu/Music /mnt/NAS/PAW-NAS1 Jun 13 16:45:59 volumio1 sudo[1011]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 16:45:59 volumio1 volumio[893]: info: Ignoring mount for partition: boot Jun 13 16:45:59 volumio1 volumio[893]: info: Ignoring mount for partition: volumio Jun 13 16:45:59 volumio1 volumio[893]: info: Ignoring mount for partition: volumio_data Jun 13 16:45:59 volumio1 volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 13 16:45:59 volumio1 volumio[893]: info: Loading plugin "volumio_command_line_client"... Jun 13 16:45:59 volumio1 volumio[893]: info: Loading plugin "upnp"... Jun 13 16:45:59 volumio1 volumio[893]: info: [1749825959615] Starting Upmpd Daemon Jun 13 16:45:59 volumio1 volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 13 16:45:59 volumio1 volumio[893]: info: Loading plugin "my_music"... Jun 13 16:45:59 volumio1 volumio[893]: info: Loading plugin "mpd"... Jun 13 16:45:59 volumio1 kernel: Key type cifs.spnego registered Jun 13 16:45:59 volumio1 kernel: Key type cifs.idmap registered Jun 13 16:45:59 volumio1 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. Jun 13 16:45:59 volumio1 kernel: CIFS: Attempting to mount //192.168.50.95/Manu/Music Jun 13 16:45:59 volumio1 volumio[893]: info: Loading plugin "upnp_browser"... Jun 13 16:45:59 volumio1 volumio-time-update[583]: volumio-time-update-util: Fetching time from Volumio... Jun 13 16:45:59 volumio1 volumio-time-update[583]: volumio-time-update-util: Setting system time to: 2018-05-05 07:05:17 Jun 13 16:45:59 volumio1 sudo[1030]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2018-05-05 07:05:17 Jun 13 16:45:59 volumio1 sudo[1030]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 16:45:59 volumio1 dbus-daemon[585]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.10' (uid=0 pid=1031 comm="timedatectl set-time 2018-05-05 07:05:17 ") Jun 13 16:46:00 volumio1 systemd[1]: Starting Time & Date Service... Jun 13 16:46:00 volumio1 dbus-daemon[585]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 13 16:46:00 volumio1 systemd[1]: Started Time & Date Service. May 05 07:36:00 volumio1 volumio[2405]: info: Initializing connection to go-librespot Websocket May 05 07:36:00 volumio1 volumio[2405]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 07:36:03 volumio1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 07:36:03 volumio1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 548. May 05 07:36:03 volumio1 systemd[1]: Stopped go-librespot Daemon. May 05 07:36:03 volumio1 systemd[1]: Started go-librespot Daemon. May 05 07:36:03 volumio1 go-librespot[10258]: Librespot-go daemon starting... May 05 07:36:03 volumio1 go-librespot[10258]: time="2018-05-05T07:36:03+02:00" level=info msg="generated new device id: 2210a5d904d316091a97cd094bdb568a3d420b0d" May 05 07:36:03 volumio1 go-librespot[10258]: time="2018-05-05T07:36:03+02:00" level=debug msg="stored credentials found for kindgott1334" May 05 07:36:03 volumio1 go-librespot[10258]: time="2018-05-05T07:36:03+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T07:36:03+02:00 is before 2025-01-06T00:00:00Z" May 05 07:36:03 volumio1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 07:36:03 volumio1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 07:36:03 volumio1 volumio[2405]: info: Initializing connection to go-librespot Websocket May 05 07:36:03 volumio1 volumio[2405]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 07:36:06 volumio1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 07:36:06 volumio1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 549. May 05 07:36:06 volumio1 systemd[1]: Stopped go-librespot Daemon. May 05 07:36:06 volumio1 systemd[1]: Started go-librespot Daemon. May 05 07:36:06 volumio1 go-librespot[10266]: Librespot-go daemon starting... May 05 07:36:06 volumio1 go-librespot[10266]: time="2018-05-05T07:36:06+02:00" level=info msg="generated new device id: 4425bd5d706ad6ede0ec6b5b59a66dffda69a20f" May 05 07:36:06 volumio1 go-librespot[10266]: time="2018-05-05T07:36:06+02:00" level=debug msg="stored credentials found for kindgott1334" May 05 07:36:06 volumio1 go-librespot[10266]: time="2018-05-05T07:36:06+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T07:36:06+02:00 is before 2025-01-06T00:00:00Z" May 05 07:36:06 volumio1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 07:36:06 volumio1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 07:36:06 volumio1 volumio[2405]: info: Initializing connection to go-librespot Websocket May 05 07:36:06 volumio1 volumio[2405]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 07:36:09 volumio1 volumio[2405]: info: Initializing connection to go-librespot Websocket May 05 07:36:09 volumio1 volumio[2405]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 07:36:09 volumio1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 07:36:09 volumio1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 550. May 05 07:36:09 volumio1 systemd[1]: Stopped go-librespot Daemon. May 05 07:36:09 volumio1 systemd[1]: Started go-librespot Daemon. May 05 07:36:09 volumio1 go-librespot[10312]: Librespot-go daemon starting... May 05 07:36:09 volumio1 go-librespot[10312]: time="2018-05-05T07:36:09+02:00" level=info msg="generated new device id: 0aa803a2fce4244ff3c90e4b7dc8c26c60ccc1ac" May 05 07:36:09 volumio1 go-librespot[10312]: time="2018-05-05T07:36:09+02:00" level=debug msg="stored credentials found for kindgott1334" May 05 07:36:09 volumio1 go-librespot[10312]: time="2018-05-05T07:36:09+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T07:36:09+02:00 is before 2025-01-06T00:00:00Z" May 05 07:36:09 volumio1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 07:36:09 volumio1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 07:36:12 volumio1 volumio[2405]: info: Initializing connection to go-librespot Websocket May 05 07:36:12 volumio1 volumio[2405]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 07:36:12 volumio1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 07:36:12 volumio1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 551. May 05 07:36:12 volumio1 systemd[1]: Stopped go-librespot Daemon. May 05 07:36:12 volumio1 systemd[1]: Started go-librespot Daemon. May 05 07:36:12 volumio1 go-librespot[10337]: Librespot-go daemon starting... May 05 07:36:12 volumio1 go-librespot[10337]: time="2018-05-05T07:36:12+02:00" level=info msg="generated new device id: 59a11b8eae2a36ce4ed600435c23c6071c9257de" May 05 07:36:12 volumio1 go-librespot[10337]: time="2018-05-05T07:36:12+02:00" level=debug msg="stored credentials found for kindgott1334" May 05 07:36:12 volumio1 go-librespot[10337]: time="2018-05-05T07:36:12+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T07:36:12+02:00 is before 2025-01-06T00:00:00Z" May 05 07:36:12 volumio1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 07:36:12 volumio1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 07:36:15 volumio1 volumio[2405]: info: Initializing connection to go-librespot Websocket May 05 07:36:15 volumio1 volumio[2405]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 07:36:16 volumio1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 07:36:16 volumio1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 552. May 05 07:36:16 volumio1 systemd[1]: Stopped go-librespot Daemon. May 05 07:36:16 volumio1 systemd[1]: Started go-librespot Daemon. May 05 07:36:16 volumio1 go-librespot[10345]: Librespot-go daemon starting... May 05 07:36:16 volumio1 go-librespot[10345]: time="2018-05-05T07:36:16+02:00" level=info msg="generated new device id: eae79cb3c9e5bc93cc477062cfb899f6b4c4b0cf" May 05 07:36:16 volumio1 go-librespot[10345]: time="2018-05-05T07:36:16+02:00" level=debug msg="stored credentials found for kindgott1334" May 05 07:36:16 volumio1 go-librespot[10345]: time="2018-05-05T07:36:16+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T07:36:16+02:00 is before 2025-01-06T00:00:00Z" May 05 07:36:16 volumio1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 07:36:16 volumio1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 07:36:18 volumio1 volumio[2405]: info: Initializing connection to go-librespot Websocket May 05 07:36:18 volumio1 volumio[2405]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 07:36:19 volumio1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 07:36:19 volumio1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 553. May 05 07:36:19 volumio1 systemd[1]: Stopped go-librespot Daemon. May 05 07:36:19 volumio1 systemd[1]: Started go-librespot Daemon. May 05 07:36:19 volumio1 go-librespot[10353]: Librespot-go daemon starting... May 05 07:36:19 volumio1 go-librespot[10353]: time="2018-05-05T07:36:19+02:00" level=info msg="generated new device id: ab16586256bf26c6e81b7e627c90e57ac8d44713" May 05 07:36:19 volumio1 go-librespot[10353]: time="2018-05-05T07:36:19+02:00" level=debug msg="stored credentials found for kindgott1334" May 05 07:36:19 volumio1 go-librespot[10353]: time="2018-05-05T07:36:19+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T07:36:19+02:00 is before 2025-01-06T00:00:00Z" May 05 07:36:19 volumio1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 07:36:19 volumio1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 07:36:21 volumio1 volumio[2405]: info: Initializing connection to go-librespot Websocket May 05 07:36:21 volumio1 volumio[2405]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 07:36:22 volumio1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 07:36:22 volumio1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 554. May 05 07:36:22 volumio1 systemd[1]: Stopped go-librespot Daemon. May 05 07:36:22 volumio1 systemd[1]: Started go-librespot Daemon. May 05 07:36:22 volumio1 go-librespot[10375]: Librespot-go daemon starting... May 05 07:36:22 volumio1 go-librespot[10375]: time="2018-05-05T07:36:22+02:00" level=info msg="generated new device id: 0e9b2687d1d2a35dd1d3a875981f178c69d428d1" May 05 07:36:22 volumio1 go-librespot[10375]: time="2018-05-05T07:36:22+02:00" level=debug msg="stored credentials found for kindgott1334" May 05 07:36:22 volumio1 go-librespot[10375]: time="2018-05-05T07:36:22+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T07:36:22+02:00 is before 2025-01-06T00:00:00Z" May 05 07:36:22 volumio1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 07:36:22 volumio1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 07:36:24 volumio1 volumio[2405]: info: Initializing connection to go-librespot Websocket May 05 07:36:24 volumio1 volumio[2405]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 07:36:25 volumio1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 07:36:25 volumio1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 555. May 05 07:36:25 volumio1 systemd[1]: Stopped go-librespot Daemon. May 05 07:36:25 volumio1 systemd[1]: Started go-librespot Daemon. May 05 07:36:25 volumio1 go-librespot[10383]: Librespot-go daemon starting... May 05 07:36:25 volumio1 go-librespot[10383]: time="2018-05-05T07:36:25+02:00" level=info msg="generated new device id: a11636da0e17e586bebea4eaf9b9f5090c46f614" May 05 07:36:25 volumio1 go-librespot[10383]: time="2018-05-05T07:36:25+02:00" level=debug msg="stored credentials found for kindgott1334" May 05 07:36:25 volumio1 go-librespot[10383]: time="2018-05-05T07:36:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T07:36:25+02:00 is before 2025-01-06T00:00:00Z" May 05 07:36:25 volumio1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 07:36:25 volumio1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 07:36:27 volumio1 volumio[2405]: info: Initializing connection to go-librespot Websocket May 05 07:36:27 volumio1 volumio[2405]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 07:36:29 volumio1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 07:36:29 volumio1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 556. May 05 07:36:29 volumio1 systemd[1]: Stopped go-librespot Daemon. May 05 07:36:29 volumio1 systemd[1]: Started go-librespot Daemon. May 05 07:36:29 volumio1 go-librespot[10391]: Librespot-go daemon starting... May 05 07:36:29 volumio1 go-librespot[10391]: time="2018-05-05T07:36:29+02:00" level=info msg="generated new device id: b77a361a527d289edd28512153f278d1cba82d27" May 05 07:36:29 volumio1 go-librespot[10391]: time="2018-05-05T07:36:29+02:00" level=debug msg="stored credentials found for kindgott1334" May 05 07:36:29 volumio1 go-librespot[10391]: time="2018-05-05T07:36:29+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T07:36:29+02:00 is before 2025-01-06T00:00:00Z" May 05 07:36:29 volumio1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 07:36:29 volumio1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 07:36:30 volumio1 volumio[2405]: info: Initializing connection to go-librespot Websocket May 05 07:36:30 volumio1 volumio[2405]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 07:36:32 volumio1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 07:36:32 volumio1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 557. May 05 07:36:32 volumio1 systemd[1]: Stopped go-librespot Daemon. May 05 07:36:32 volumio1 systemd[1]: Started go-librespot Daemon. May 05 07:36:32 volumio1 go-librespot[10416]: Librespot-go daemon starting... May 05 07:36:32 volumio1 go-librespot[10416]: time="2018-05-05T07:36:32+02:00" level=info msg="generated new device id: a65c5c140de65fbe5a49b76c2ec05c7fdbd6e5ca" May 05 07:36:32 volumio1 go-librespot[10416]: time="2018-05-05T07:36:32+02:00" level=debug msg="stored credentials found for kindgott1334" May 05 07:36:32 volumio1 go-librespot[10416]: time="2018-05-05T07:36:32+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T07:36:32+02:00 is before 2025-01-06T00:00:00Z" May 05 07:36:32 volumio1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 07:36:32 volumio1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 07:36:33 volumio1 volumio[2405]: info: Initializing connection to go-librespot Websocket May 05 07:36:33 volumio1 volumio[2405]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 07:36:35 volumio1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 07:36:35 volumio1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 558. May 05 07:36:35 volumio1 systemd[1]: Stopped go-librespot Daemon. May 05 07:36:35 volumio1 systemd[1]: Started go-librespot Daemon. May 05 07:36:35 volumio1 go-librespot[10424]: Librespot-go daemon starting... May 05 07:36:35 volumio1 go-librespot[10424]: time="2018-05-05T07:36:35+02:00" level=info msg="generated new device id: 5e8fcbb66a610c8aef8332cc1fa3cf90e9cb3409" May 05 07:36:35 volumio1 go-librespot[10424]: time="2018-05-05T07:36:35+02:00" level=debug msg="stored credentials found for kindgott1334" May 05 07:36:35 volumio1 go-librespot[10424]: time="2018-05-05T07:36:35+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T07:36:35+02:00 is before 2025-01-06T00:00:00Z" May 05 07:36:35 volumio1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 07:36:35 volumio1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 07:36:36 volumio1 volumio[2405]: info: Initializing connection to go-librespot Websocket May 05 07:36:36 volumio1 volumio[2405]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 07:36:38 volumio1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 07:36:38 volumio1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 559. May 05 07:36:38 volumio1 systemd[1]: Stopped go-librespot Daemon. May 05 07:36:38 volumio1 systemd[1]: Started go-librespot Daemon. May 05 07:36:38 volumio1 go-librespot[10432]: Librespot-go daemon starting... May 05 07:36:38 volumio1 go-librespot[10432]: time="2018-05-05T07:36:38+02:00" level=info msg="generated new device id: e72e563f80311b51121ed02a22f1ca1b2a194b6b" May 05 07:36:38 volumio1 go-librespot[10432]: time="2018-05-05T07:36:38+02:00" level=debug msg="stored credentials found for kindgott1334" May 05 07:36:38 volumio1 go-librespot[10432]: time="2018-05-05T07:36:38+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T07:36:38+02:00 is before 2025-01-06T00:00:00Z" May 05 07:36:38 volumio1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 07:36:38 volumio1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 07:36:39 volumio1 volumio[2405]: info: Initializing connection to go-librespot Websocket May 05 07:36:39 volumio1 volumio[2405]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 07:36:42 volumio1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 07:36:42 volumio1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 560. May 05 07:36:42 volumio1 systemd[1]: Stopped go-librespot Daemon. May 05 07:36:42 volumio1 systemd[1]: Started go-librespot Daemon. May 05 07:36:42 volumio1 go-librespot[10454]: Librespot-go daemon starting... May 05 07:36:42 volumio1 go-librespot[10454]: time="2018-05-05T07:36:42+02:00" level=info msg="generated new device id: 854356ecc7a02af4fa569d331374f9e0b28c078f" May 05 07:36:42 volumio1 go-librespot[10454]: time="2018-05-05T07:36:42+02:00" level=debug msg="stored credentials found for kindgott1334" May 05 07:36:42 volumio1 go-librespot[10454]: time="2018-05-05T07:36:42+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T07:36:42+02:00 is before 2025-01-06T00:00:00Z" May 05 07:36:42 volumio1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 07:36:42 volumio1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 07:36:42 volumio1 volumio[2405]: info: Initializing connection to go-librespot Websocket May 05 07:36:42 volumio1 volumio[2405]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 07:36:45 volumio1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 07:36:45 volumio1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 561. May 05 07:36:45 volumio1 systemd[1]: Stopped go-librespot Daemon. May 05 07:36:45 volumio1 systemd[1]: Started go-librespot Daemon. May 05 07:36:45 volumio1 go-librespot[10462]: Librespot-go daemon starting... May 05 07:36:45 volumio1 go-librespot[10462]: time="2018-05-05T07:36:45+02:00" level=info msg="generated new device id: df421214150816bd1011d7a74e9a9c9e4d7a21ca" May 05 07:36:45 volumio1 go-librespot[10462]: time="2018-05-05T07:36:45+02:00" level=debug msg="stored credentials found for kindgott1334" May 05 07:36:45 volumio1 go-librespot[10462]: time="2018-05-05T07:36:45+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T07:36:45+02:00 is before 2025-01-06T00:00:00Z" May 05 07:36:45 volumio1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 07:36:45 volumio1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 07:36:45 volumio1 volumio[2405]: info: Initializing connection to go-librespot Websocket May 05 07:36:45 volumio1 volumio[2405]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 07:36:48 volumio1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 07:36:48 volumio1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 562. May 05 07:36:48 volumio1 systemd[1]: Stopped go-librespot Daemon. May 05 07:36:48 volumio1 volumio[2405]: info: Initializing connection to go-librespot Websocket May 05 07:36:48 volumio1 volumio[2405]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 07:36:48 volumio1 systemd[1]: Started go-librespot Daemon. May 05 07:36:48 volumio1 go-librespot[10470]: Librespot-go daemon starting... May 05 07:36:48 volumio1 go-librespot[10470]: time="2018-05-05T07:36:48+02:00" level=info msg="generated new device id: 9e6986f3469825e2ff4a6ea3daf8219984b4a564" May 05 07:36:48 volumio1 go-librespot[10470]: time="2018-05-05T07:36:48+02:00" level=debug msg="stored credentials found for kindgott1334" May 05 07:36:48 volumio1 go-librespot[10470]: time="2018-05-05T07:36:48+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T07:36:48+02:00 is before 2025-01-06T00:00:00Z" May 05 07:36:48 volumio1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 07:36:48 volumio1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 07:36:51 volumio1 volumio[2405]: info: Initializing connection to go-librespot Websocket May 05 07:36:51 volumio1 volumio[2405]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 07:36:51 volumio1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 07:36:51 volumio1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 563. May 05 07:36:51 volumio1 systemd[1]: Stopped go-librespot Daemon. May 05 07:36:51 volumio1 systemd[1]: Started go-librespot Daemon. May 05 07:36:51 volumio1 go-librespot[10495]: Librespot-go daemon starting... May 05 07:36:51 volumio1 go-librespot[10495]: time="2018-05-05T07:36:51+02:00" level=info msg="generated new device id: 15c27f07339f8f79ab496217aae298be13da924f" May 05 07:36:51 volumio1 go-librespot[10495]: time="2018-05-05T07:36:51+02:00" level=debug msg="stored credentials found for kindgott1334" May 05 07:36:51 volumio1 go-librespot[10495]: time="2018-05-05T07:36:51+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T07:36:51+02:00 is before 2025-01-06T00:00:00Z" May 05 07:36:51 volumio1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 07:36:51 volumio1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 07:36:54 volumio1 volumio[2405]: info: Initializing connection to go-librespot Websocket May 05 07:36:54 volumio1 volumio[2405]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 07:36:55 volumio1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 07:36:55 volumio1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 564. May 05 07:36:55 volumio1 systemd[1]: Stopped go-librespot Daemon. May 05 07:36:55 volumio1 systemd[1]: Started go-librespot Daemon. May 05 07:36:55 volumio1 go-librespot[10503]: Librespot-go daemon starting... May 05 07:36:55 volumio1 go-librespot[10503]: time="2018-05-05T07:36:55+02:00" level=info msg="generated new device id: a0dacff9e9729f0dc6c47eb6da34f514da1cebde" May 05 07:36:55 volumio1 go-librespot[10503]: time="2018-05-05T07:36:55+02:00" level=debug msg="stored credentials found for kindgott1334" May 05 07:36:55 volumio1 go-librespot[10503]: time="2018-05-05T07:36:55+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T07:36:55+02:00 is before 2025-01-06T00:00:00Z" May 05 07:36:55 volumio1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 07:36:55 volumio1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 07:36:57 volumio1 volumio[2405]: info: Initializing connection to go-librespot Websocket May 05 07:36:57 volumio1 volumio[2405]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 07:36:58 volumio1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 07:36:58 volumio1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 565. May 05 07:36:58 volumio1 systemd[1]: Stopped go-librespot Daemon. May 05 07:36:58 volumio1 systemd[1]: Started go-librespot Daemon. May 05 07:36:58 volumio1 go-librespot[10511]: Librespot-go daemon starting... May 05 07:36:58 volumio1 go-librespot[10511]: time="2018-05-05T07:36:58+02:00" level=info msg="generated new device id: 4995f6d2679a506b0c6e1b3191ca6a12f2b47148" May 05 07:36:58 volumio1 go-librespot[10511]: time="2018-05-05T07:36:58+02:00" level=debug msg="stored credentials found for kindgott1334" May 05 07:36:58 volumio1 go-librespot[10511]: time="2018-05-05T07:36:58+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T07:36:58+02:00 is before 2025-01-06T00:00:00Z" May 05 07:36:58 volumio1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 07:36:58 volumio1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 07:37:00 volumio1 volumio[2405]: info: Initializing connection to go-librespot Websocket May 05 07:37:00 volumio1 volumio[2405]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 07:37:01 volumio1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 07:37:01 volumio1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 566. May 05 07:37:01 volumio1 systemd[1]: Stopped go-librespot Daemon. May 05 07:37:01 volumio1 systemd[1]: Started go-librespot Daemon. May 05 07:37:01 volumio1 go-librespot[10533]: Librespot-go daemon starting... May 05 07:37:01 volumio1 go-librespot[10533]: time="2018-05-05T07:37:01+02:00" level=info msg="generated new device id: b7f45165acea44668e83e2bf208c62c0a8e77057" May 05 07:37:01 volumio1 go-librespot[10533]: time="2018-05-05T07:37:01+02:00" level=debug msg="stored credentials found for kindgott1334" May 05 07:37:01 volumio1 go-librespot[10533]: time="2018-05-05T07:37:01+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T07:37:01+02:00 is before 2025-01-06T00:00:00Z" May 05 07:37:01 volumio1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 07:37:01 volumio1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 07:37:03 volumio1 volumio[2405]: info: Initializing connection to go-librespot Websocket May 05 07:37:03 volumio1 volumio[2405]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 05 07:37:04 volumio1 volumio[2405]: Searching plugin music_service/mpd May 05 07:37:04 volumio1 volumio[2405]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 05 07:37:04 volumio1 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 05 07:37:04 volumio1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 567. May 05 07:37:04 volumio1 systemd[1]: Stopped go-librespot Daemon. May 05 07:37:04 volumio1 systemd[1]: Started go-librespot Daemon. May 05 07:37:04 volumio1 go-librespot[10541]: Librespot-go daemon starting... May 05 07:37:04 volumio1 go-librespot[10541]: time="2018-05-05T07:37:04+02:00" level=info msg="generated new device id: 7a08491e0c9600fbc8f3cc71f0ce081ab8bb1d21" May 05 07:37:04 volumio1 go-librespot[10541]: time="2018-05-05T07:37:04+02:00" level=debug msg="stored credentials found for kindgott1334" May 05 07:37:04 volumio1 volumio[2405]: info: All search sources collected, pushing search results May 05 07:37:04 volumio1 go-librespot[10541]: time="2018-05-05T07:37:04+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2018-05-05T07:37:04+02:00 is before 2025-01-06T00:00:00Z" May 05 07:37:04 volumio1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 05 07:37:04 volumio1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 05 07:37:05 volumio1 volumio[2405]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' May 05 07:37:05 volumio1 volumio[2405]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 07:37:05 volumio1 volumio[2405]: Error: certificate is not yet valid May 05 07:37:05 volumio1 volumio[2405]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) May 05 07:37:05 volumio1 volumio[2405]: at TLSSocket.emit (events.js:315:20) May 05 07:37:05 volumio1 volumio[2405]: at TLSSocket._finishInit (_tls_wrap.js:932:8) May 05 07:37:05 volumio1 volumio[2405]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { May 05 07:37:05 volumio1 volumio[2405]: code: 'CERT_NOT_YET_VALID' May 05 07:37:05 volumio1 volumio[2405]: } May 05 07:37:05 volumio1 volumio[2405]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 07:37:05 volumio1 sudo[10559]: pam_unix(sudo:account): account volumio has password changed in future May 05 07:37:05 volumio1 sudo[10559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2018-05-05 07:36 May 05 07:37:05 volumio1 sudo[10559]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"