-- Logs begin at Fri 2024-11-22 09:11:35 CET, end at Fri 2024-11-22 09:12:52 CET. -- Nov 22 09:11:35 thomsaudiohoek kernel: Booting Linux on physical CPU 0x0000000000 [0x414fd0b1] Nov 22 09:11:35 thomsaudiohoek kernel: Linux version 6.6.56-v8+ (dom@buildbot) (aarch64-linux-gnu-gcc (Ubuntu 11.4.0-1ubuntu1~22.04) 11.4.0, GNU ld (GNU Binutils for Ubuntu) 2.38) #1803 SMP PREEMPT Thu Oct 10 14:12:44 BST 2024 Nov 22 09:11:35 thomsaudiohoek kernel: KASLR enabled Nov 22 09:11:35 thomsaudiohoek kernel: random: crng init done Nov 22 09:11:35 thomsaudiohoek kernel: Machine model: Raspberry Pi 5 Model B Rev 1.0 Nov 22 09:11:35 thomsaudiohoek kernel: efi: UEFI not found. Nov 22 09:11:35 thomsaudiohoek kernel: Reserved memory: created CMA memory pool at 0x0000000001c00000, size 64 MiB Nov 22 09:11:35 thomsaudiohoek kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Nov 22 09:11:35 thomsaudiohoek kernel: OF: reserved mem: 0x0000000001c00000..0x0000000005bfffff (65536 KiB) map reusable linux,cma Nov 22 09:11:35 thomsaudiohoek kernel: OF: reserved mem: 0x0000000000000000..0x000000000007ffff (512 KiB) nomap non-reusable atf@0 Nov 22 09:11:35 thomsaudiohoek kernel: OF: reserved mem: 0x000000003fd16200..0x000000003fd16238 (0 KiB) nomap non-reusable nvram@0 Nov 22 09:11:35 thomsaudiohoek kernel: Zone ranges: Nov 22 09:11:35 thomsaudiohoek kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Nov 22 09:11:35 thomsaudiohoek kernel: DMA32 empty Nov 22 09:11:35 thomsaudiohoek kernel: Normal [mem 0x0000000100000000-0x00000001ffffffff] Nov 22 09:11:35 thomsaudiohoek kernel: Movable zone start for each node Nov 22 09:11:35 thomsaudiohoek kernel: Early memory node ranges Nov 22 09:11:35 thomsaudiohoek kernel: node 0: [mem 0x0000000000000000-0x000000000007ffff] Nov 22 09:11:35 thomsaudiohoek kernel: node 0: [mem 0x0000000000080000-0x000000003fbfffff] Nov 22 09:11:35 thomsaudiohoek kernel: node 0: [mem 0x0000000040000000-0x00000001ffffffff] Nov 22 09:11:35 thomsaudiohoek kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001ffffffff] Nov 22 09:11:35 thomsaudiohoek kernel: On node 0, zone DMA: 1024 pages in unavailable ranges Nov 22 09:11:35 thomsaudiohoek kernel: psci: probing for conduit method from DT. Nov 22 09:11:35 thomsaudiohoek kernel: psci: PSCIv1.1 detected in firmware. Nov 22 09:11:35 thomsaudiohoek kernel: psci: Using standard PSCI v0.2 function IDs Nov 22 09:11:35 thomsaudiohoek kernel: psci: MIGRATE_INFO_TYPE not supported. Nov 22 09:11:35 thomsaudiohoek kernel: psci: SMC Calling Convention v1.2 Nov 22 09:11:35 thomsaudiohoek kernel: percpu: Embedded 30 pages/cpu s85672 r8192 d29016 u122880 Nov 22 09:11:35 thomsaudiohoek kernel: pcpu-alloc: s85672 r8192 d29016 u122880 alloc=30*4096 Nov 22 09:11:35 thomsaudiohoek kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Nov 22 09:11:35 thomsaudiohoek kernel: Detected PIPT I-cache on CPU0 Nov 22 09:11:35 thomsaudiohoek kernel: CPU features: detected: Virtualization Host Extensions Nov 22 09:11:35 thomsaudiohoek kernel: CPU features: detected: Hardware dirty bit management Nov 22 09:11:35 thomsaudiohoek kernel: CPU features: detected: Spectre-v4 Nov 22 09:11:35 thomsaudiohoek kernel: CPU features: detected: Spectre-BHB Nov 22 09:11:35 thomsaudiohoek kernel: CPU features: kernel page table isolation forced ON by KASLR Nov 22 09:11:35 thomsaudiohoek kernel: CPU features: detected: Kernel page table isolation (KPTI) Nov 22 09:11:35 thomsaudiohoek kernel: CPU features: detected: SSBS not fully self-synchronizing Nov 22 09:11:35 thomsaudiohoek kernel: alternatives: applying boot alternatives Nov 22 09:11:35 thomsaudiohoek kernel: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe smsc95xx.macaddr=2C:CF:67:26:EE:88 vc_mem.mem_base=0x3fc00000 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=ttyAMA10,115200 console=tty1 imgpart=UUID=c2ba6003-062c-4fe6-ba10-7098aecb5298 imgfile=/volumio_current.sqsh bootpart=UUID=466A-50F2 datapart=UUID=d3781328-ecc3-497c-a5ef-12c057da4894 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 Nov 22 09:11:35 thomsaudiohoek kernel: PCIe ASPM is disabled Nov 22 09:11:35 thomsaudiohoek kernel: Unknown kernel command line parameters "splash nodebug imgpart=UUID=c2ba6003-062c-4fe6-ba10-7098aecb5298 imgfile=/volumio_current.sqsh bootpart=UUID=466A-50F2 datapart=UUID=d3781328-ecc3-497c-a5ef-12c057da4894 uuidconfig=cmdline.txt bootdelay=7 use_kmsg=no", will be passed to user space. Nov 22 09:11:35 thomsaudiohoek kernel: Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear) Nov 22 09:11:35 thomsaudiohoek kernel: Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 22 09:11:35 thomsaudiohoek kernel: Built 1 zonelists, mobility grouping on. Total pages: 2063360 Nov 22 09:11:35 thomsaudiohoek kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 22 09:11:35 thomsaudiohoek kernel: software IO TLB: area num 4. Nov 22 09:11:35 thomsaudiohoek kernel: software IO TLB: mapped [mem 0x00000000fbfff000-0x00000000fffff000] (64MB) Nov 22 09:11:35 thomsaudiohoek kernel: Memory: 8037376K/8384512K available (13376K kernel code, 2212K rwdata, 4264K rodata, 4864K init, 1083K bss, 281600K reserved, 65536K cma-reserved) Nov 22 09:11:35 thomsaudiohoek kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Nov 22 09:11:35 thomsaudiohoek kernel: ftrace: allocating 43308 entries in 170 pages Nov 22 09:11:35 thomsaudiohoek kernel: ftrace: allocated 170 pages with 4 groups Nov 22 09:11:35 thomsaudiohoek kernel: trace event string verifier disabled Nov 22 09:11:35 thomsaudiohoek kernel: rcu: Preemptible hierarchical RCU implementation. Nov 22 09:11:35 thomsaudiohoek kernel: rcu: RCU event tracing is enabled. Nov 22 09:11:35 thomsaudiohoek kernel: rcu: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4. Nov 22 09:11:35 thomsaudiohoek kernel: Trampoline variant of Tasks RCU enabled. Nov 22 09:11:35 thomsaudiohoek kernel: Rude variant of Tasks RCU enabled. Nov 22 09:11:35 thomsaudiohoek kernel: Tracing variant of Tasks RCU enabled. Nov 22 09:11:35 thomsaudiohoek kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Nov 22 09:11:35 thomsaudiohoek kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Nov 22 09:11:35 thomsaudiohoek kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Nov 22 09:11:35 thomsaudiohoek kernel: Root IRQ handler: gic_handle_irq Nov 22 09:11:35 thomsaudiohoek kernel: GIC: Using split EOI/Deactivate mode Nov 22 09:11:35 thomsaudiohoek kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 22 09:11:35 thomsaudiohoek kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Nov 22 09:11:35 thomsaudiohoek kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Nov 22 09:11:35 thomsaudiohoek kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Nov 22 09:11:35 thomsaudiohoek kernel: Console: colour dummy device 80x25 Nov 22 09:11:35 thomsaudiohoek kernel: printk: console [tty1] enabled Nov 22 09:11:35 thomsaudiohoek kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000) Nov 22 09:11:35 thomsaudiohoek kernel: pid_max: default: 32768 minimum: 301 Nov 22 09:11:35 thomsaudiohoek kernel: LSM: initializing lsm=capability,integrity Nov 22 09:11:35 thomsaudiohoek kernel: Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) Nov 22 09:11:35 thomsaudiohoek kernel: Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) Nov 22 09:11:35 thomsaudiohoek kernel: cgroup: Disabling memory control group subsystem Nov 22 09:11:35 thomsaudiohoek kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Nov 22 09:11:35 thomsaudiohoek kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Nov 22 09:11:35 thomsaudiohoek kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Nov 22 09:11:35 thomsaudiohoek kernel: rcu: Hierarchical SRCU implementation. Nov 22 09:11:35 thomsaudiohoek kernel: rcu: Max phase no-delay instances is 1000. Nov 22 09:11:35 thomsaudiohoek kernel: EFI services will not be available. Nov 22 09:11:35 thomsaudiohoek kernel: smp: Bringing up secondary CPUs ... Nov 22 09:11:35 thomsaudiohoek kernel: Detected PIPT I-cache on CPU1 Nov 22 09:11:35 thomsaudiohoek kernel: CPU1: Booted secondary processor 0x0000000100 [0x414fd0b1] Nov 22 09:11:35 thomsaudiohoek kernel: Detected PIPT I-cache on CPU2 Nov 22 09:11:35 thomsaudiohoek kernel: CPU2: Booted secondary processor 0x0000000200 [0x414fd0b1] Nov 22 09:11:35 thomsaudiohoek kernel: Detected PIPT I-cache on CPU3 Nov 22 09:11:35 thomsaudiohoek kernel: CPU3: Booted secondary processor 0x0000000300 [0x414fd0b1] Nov 22 09:11:35 thomsaudiohoek kernel: smp: Brought up 1 node, 4 CPUs Nov 22 09:11:35 thomsaudiohoek kernel: SMP: Total of 4 processors activated. Nov 22 09:11:35 thomsaudiohoek kernel: CPU features: detected: 32-bit EL0 Support Nov 22 09:11:35 thomsaudiohoek kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Nov 22 09:11:35 thomsaudiohoek kernel: CPU features: detected: Common not Private translations Nov 22 09:11:35 thomsaudiohoek kernel: CPU features: detected: CRC32 instructions Nov 22 09:11:35 thomsaudiohoek kernel: CPU features: detected: RCpc load-acquire (LDAPR) Nov 22 09:11:35 thomsaudiohoek kernel: CPU features: detected: LSE atomic instructions Nov 22 09:11:35 thomsaudiohoek kernel: CPU features: detected: Privileged Access Never Nov 22 09:11:35 thomsaudiohoek kernel: CPU features: detected: RAS Extension Support Nov 22 09:11:35 thomsaudiohoek kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Nov 22 09:11:35 thomsaudiohoek kernel: CPU: All CPU(s) started at EL2 Nov 22 09:11:35 thomsaudiohoek kernel: alternatives: applying system-wide alternatives Nov 22 09:11:35 thomsaudiohoek kernel: devtmpfs: initialized Nov 22 09:11:35 thomsaudiohoek kernel: Enabled cp15_barrier support Nov 22 09:11:35 thomsaudiohoek kernel: Enabled setend support Nov 22 09:11:35 thomsaudiohoek kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Nov 22 09:11:35 thomsaudiohoek kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Nov 22 09:11:35 thomsaudiohoek kernel: pinctrl core: initialized pinctrl subsystem Nov 22 09:11:35 thomsaudiohoek kernel: DMI not present or invalid. Nov 22 09:11:35 thomsaudiohoek kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 22 09:11:35 thomsaudiohoek kernel: DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations Nov 22 09:11:35 thomsaudiohoek kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Nov 22 09:11:35 thomsaudiohoek kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Nov 22 09:11:35 thomsaudiohoek kernel: audit: initializing netlink subsys (disabled) Nov 22 09:11:35 thomsaudiohoek kernel: audit: type=2000 audit(0.008:1): state=initialized audit_enabled=0 res=1 Nov 22 09:11:35 thomsaudiohoek kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 22 09:11:35 thomsaudiohoek kernel: cpuidle: using governor menu Nov 22 09:11:35 thomsaudiohoek kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Nov 22 09:11:35 thomsaudiohoek kernel: ASID allocator initialised with 32768 entries Nov 22 09:11:35 thomsaudiohoek kernel: Serial: AMBA PL011 UART driver Nov 22 09:11:35 thomsaudiohoek kernel: bcm2835-mbox 107c013880.mailbox: mailbox enabled Nov 22 09:11:35 thomsaudiohoek kernel: 107d001000.serial: ttyAMA10 at MMIO 0x107d001000 (irq = 15, base_baud = 0) is a PL011 rev2 Nov 22 09:11:35 thomsaudiohoek kernel: printk: console [ttyAMA10] enabled Nov 22 09:11:35 thomsaudiohoek kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2024-06-05T15:41:49, variant start_cd Nov 22 09:11:35 thomsaudiohoek kernel: raspberrypi-firmware soc:firmware: Firmware hash is 6fe0b09100000000000000000000000000000000 Nov 22 09:11:35 thomsaudiohoek kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Nov 22 09:11:35 thomsaudiohoek kernel: Modules: 0 pages in range for non-PLT usage Nov 22 09:11:35 thomsaudiohoek kernel: Modules: 517792 pages in range for PLT usage Nov 22 09:11:35 thomsaudiohoek kernel: bcm2835-dma 1000010000.dma: DMA legacy API manager, dmachans=0x1 Nov 22 09:11:35 thomsaudiohoek kernel: iommu: Default domain type: Translated Nov 22 09:11:35 thomsaudiohoek kernel: iommu: DMA domain TLB invalidation policy: strict mode Nov 22 09:11:35 thomsaudiohoek kernel: SCSI subsystem initialized Nov 22 09:11:35 thomsaudiohoek kernel: usbcore: registered new interface driver usbfs Nov 22 09:11:35 thomsaudiohoek kernel: usbcore: registered new interface driver hub Nov 22 09:11:35 thomsaudiohoek kernel: usbcore: registered new device driver usb Nov 22 09:11:35 thomsaudiohoek kernel: pps_core: LinuxPPS API ver. 1 registered Nov 22 09:11:35 thomsaudiohoek kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Nov 22 09:11:35 thomsaudiohoek kernel: PTP clock support registered Nov 22 09:11:35 thomsaudiohoek kernel: vgaarb: loaded Nov 22 09:11:35 thomsaudiohoek kernel: clocksource: Switched to clocksource arch_sys_counter Nov 22 09:11:35 thomsaudiohoek kernel: VFS: Disk quotas dquot_6.6.0 Nov 22 09:11:35 thomsaudiohoek kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 22 09:11:35 thomsaudiohoek kernel: FS-Cache: Loaded Nov 22 09:11:35 thomsaudiohoek kernel: CacheFiles: Loaded Nov 22 09:11:35 thomsaudiohoek kernel: NET: Registered PF_INET protocol family Nov 22 09:11:35 thomsaudiohoek kernel: IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear) Nov 22 09:11:35 thomsaudiohoek kernel: tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear) Nov 22 09:11:35 thomsaudiohoek kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 22 09:11:35 thomsaudiohoek kernel: TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 22 09:11:35 thomsaudiohoek kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 22 09:11:35 thomsaudiohoek kernel: TCP: Hash tables configured (established 65536 bind 65536) Nov 22 09:11:35 thomsaudiohoek kernel: MPTCP token hash table entries: 8192 (order: 5, 196608 bytes, linear) Nov 22 09:11:35 thomsaudiohoek kernel: UDP hash table entries: 4096 (order: 5, 131072 bytes, linear) Nov 22 09:11:35 thomsaudiohoek kernel: UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear) Nov 22 09:11:35 thomsaudiohoek kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 22 09:11:35 thomsaudiohoek kernel: RPC: Registered named UNIX socket transport module. Nov 22 09:11:35 thomsaudiohoek kernel: RPC: Registered udp transport module. Nov 22 09:11:35 thomsaudiohoek kernel: RPC: Registered tcp transport module. Nov 22 09:11:35 thomsaudiohoek kernel: RPC: Registered tcp-with-tls transport module. Nov 22 09:11:35 thomsaudiohoek kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Nov 22 09:11:35 thomsaudiohoek kernel: PCI: CLS 0 bytes, default 64 Nov 22 09:11:35 thomsaudiohoek kernel: Trying to unpack rootfs image as initramfs... Nov 22 09:11:35 thomsaudiohoek kernel: kvm [1]: IPA Size Limit: 40 bits Nov 22 09:11:35 thomsaudiohoek kernel: kvm [1]: vgic interrupt IRQ9 Nov 22 09:11:35 thomsaudiohoek kernel: kvm [1]: VHE mode initialized successfully Nov 22 09:11:35 thomsaudiohoek kernel: Initialise system trusted keyrings Nov 22 09:11:35 thomsaudiohoek kernel: workingset: timestamp_bits=46 max_order=21 bucket_order=0 Nov 22 09:11:35 thomsaudiohoek kernel: zbud: loaded Nov 22 09:11:35 thomsaudiohoek kernel: NFS: Registering the id_resolver key type Nov 22 09:11:35 thomsaudiohoek kernel: Key type id_resolver registered Nov 22 09:11:35 thomsaudiohoek kernel: Key type id_legacy registered Nov 22 09:11:35 thomsaudiohoek kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Nov 22 09:11:35 thomsaudiohoek kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Nov 22 09:11:35 thomsaudiohoek kernel: Key type asymmetric registered Nov 22 09:11:35 thomsaudiohoek kernel: Asymmetric key parser 'x509' registered Nov 22 09:11:35 thomsaudiohoek kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Nov 22 09:11:35 thomsaudiohoek kernel: io scheduler mq-deadline registered Nov 22 09:11:35 thomsaudiohoek kernel: io scheduler kyber registered Nov 22 09:11:35 thomsaudiohoek kernel: io scheduler bfq registered Nov 22 09:11:35 thomsaudiohoek kernel: irq_brcmstb_l2: registered L2 intc (/soc/interrupt-controller@7c502000, parent irq: 26) Nov 22 09:11:35 thomsaudiohoek kernel: irq_brcmstb_l2: registered L2 intc (/soc/intc@7d503000, parent irq: 27) Nov 22 09:11:35 thomsaudiohoek kernel: irq_brcmstb_l2: registered L2 intc (/soc/intc@7d508380, parent irq: 28) Nov 22 09:11:35 thomsaudiohoek kernel: irq_brcmstb_l2: registered L2 intc (/soc/intc@7d508400, parent irq: 29) Nov 22 09:11:35 thomsaudiohoek kernel: irq_brcmstb_l2: registered L2 intc (/soc/interrupt-controller@7d510600, parent irq: 30) Nov 22 09:11:35 thomsaudiohoek kernel: irq_brcmstb_l2: registered L2 intc (/soc/intc@7d517b00, parent irq: 31) Nov 22 09:11:35 thomsaudiohoek kernel: Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled Nov 22 09:11:35 thomsaudiohoek kernel: 107d50c000.serial: ttyS0 at MMIO 0x107d50c000 (irq = 33, base_baud = 6000000) is a Broadcom BCM7271 UART Nov 22 09:11:35 thomsaudiohoek kernel: serial serial0: tty port ttyS0 registered Nov 22 09:11:35 thomsaudiohoek kernel: iproc-rng200 107d208000.rng: hwrng registered Nov 22 09:11:35 thomsaudiohoek kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3fc00000 mem_size:0x40000000(1024 MiB) Nov 22 09:11:35 thomsaudiohoek kernel: bcm2712-iommu-cache 1000005b00.iommuc: bcm2712_iommu_cache_probe Nov 22 09:11:35 thomsaudiohoek kernel: brd: module loaded Nov 22 09:11:35 thomsaudiohoek kernel: Freeing initrd memory: 28064K Nov 22 09:11:35 thomsaudiohoek kernel: loop: module loaded Nov 22 09:11:35 thomsaudiohoek kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Nov 22 09:11:35 thomsaudiohoek kernel: Loading iSCSI transport class v2.0-870. Nov 22 09:11:35 thomsaudiohoek kernel: usbcore: registered new device driver r8152-cfgselector Nov 22 09:11:35 thomsaudiohoek kernel: usbcore: registered new interface driver r8152 Nov 22 09:11:35 thomsaudiohoek kernel: usbcore: registered new interface driver lan78xx Nov 22 09:11:35 thomsaudiohoek kernel: usbcore: registered new interface driver smsc95xx Nov 22 09:11:35 thomsaudiohoek kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Nov 22 09:11:35 thomsaudiohoek kernel: dwc_otg: FIQ enabled Nov 22 09:11:35 thomsaudiohoek kernel: dwc_otg: NAK holdoff enabled Nov 22 09:11:35 thomsaudiohoek kernel: dwc_otg: FIQ split-transaction FSM enabled Nov 22 09:11:35 thomsaudiohoek kernel: Module dwc_common_port init Nov 22 09:11:35 thomsaudiohoek kernel: usbcore: registered new interface driver uas Nov 22 09:11:35 thomsaudiohoek kernel: usbcore: registered new interface driver usb-storage Nov 22 09:11:35 thomsaudiohoek kernel: mousedev: PS/2 mouse device common for all mice Nov 22 09:11:35 thomsaudiohoek kernel: rpi-rtc soc:rpi_rtc: registered as rtc0 Nov 22 09:11:35 thomsaudiohoek kernel: rpi-rtc soc:rpi_rtc: setting system clock to 2024-11-22T08:11:24 UTC (1732263084) Nov 22 09:11:35 thomsaudiohoek kernel: bcm2835-wdt bcm2835-wdt: Poweroff handler already present! Nov 22 09:11:35 thomsaudiohoek kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Nov 22 09:11:35 thomsaudiohoek kernel: sdhci: Secure Digital Host Controller Interface driver Nov 22 09:11:35 thomsaudiohoek kernel: sdhci: Copyright(c) Pierre Ossman Nov 22 09:11:35 thomsaudiohoek kernel: sdhci-pltfm: SDHCI platform and OF driver helper Nov 22 09:11:35 thomsaudiohoek kernel: ledtrig-cpu: registered to indicate activity on CPUs Nov 22 09:11:35 thomsaudiohoek kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Nov 22 09:11:35 thomsaudiohoek kernel: hid: raw HID events driver (C) Jiri Kosina Nov 22 09:11:35 thomsaudiohoek kernel: usbcore: registered new interface driver usbhid Nov 22 09:11:35 thomsaudiohoek kernel: usbhid: USB HID core driver Nov 22 09:11:35 thomsaudiohoek kernel: hw perfevents: enabled with armv8_cortex_a76 PMU driver, 7 counters available Nov 22 09:11:35 thomsaudiohoek kernel: NET: Registered PF_PACKET protocol family Nov 22 09:11:35 thomsaudiohoek kernel: Key type dns_resolver registered Nov 22 09:11:35 thomsaudiohoek kernel: registered taskstats version 1 Nov 22 09:11:35 thomsaudiohoek kernel: Loading compiled-in X.509 certificates Nov 22 09:11:35 thomsaudiohoek kernel: Key type .fscrypt registered Nov 22 09:11:35 thomsaudiohoek kernel: Key type fscrypt-provisioning registered Nov 22 09:11:35 thomsaudiohoek kernel: brcm-pcie 1000110000.pcie: host bridge /axi/pcie@110000 ranges: Nov 22 09:11:35 thomsaudiohoek kernel: brcm-pcie 1000110000.pcie: No bus range found for /axi/pcie@110000, using [bus 00-ff] Nov 22 09:11:35 thomsaudiohoek kernel: brcm-pcie 1000110000.pcie: MEM 0x1b80000000..0x1bffffffff -> 0x0080000000 Nov 22 09:11:35 thomsaudiohoek kernel: brcm-pcie 1000110000.pcie: MEM 0x1800000000..0x1b7fffffff -> 0x0400000000 Nov 22 09:11:35 thomsaudiohoek kernel: brcm-pcie 1000110000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000 Nov 22 09:11:35 thomsaudiohoek kernel: brcm-pcie 1000110000.pcie: Forcing gen 2 Nov 22 09:11:35 thomsaudiohoek kernel: brcm-pcie 1000110000.pcie: PCI host bridge to bus 0000:00 Nov 22 09:11:35 thomsaudiohoek kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Nov 22 09:11:35 thomsaudiohoek kernel: pci_bus 0000:00: root bus resource [mem 0x1b80000000-0x1bffffffff] (bus address [0x80000000-0xffffffff]) Nov 22 09:11:35 thomsaudiohoek kernel: pci_bus 0000:00: root bus resource [mem 0x1800000000-0x1b7fffffff pref] (bus address [0x400000000-0x77fffffff]) Nov 22 09:11:35 thomsaudiohoek kernel: pci 0000:00:00.0: [14e4:2712] type 01 class 0x060400 Nov 22 09:11:35 thomsaudiohoek kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Nov 22 09:11:35 thomsaudiohoek kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Nov 22 09:11:35 thomsaudiohoek kernel: brcm-pcie 1000110000.pcie: link up, 5.0 GT/s PCIe x1 (!SSC) Nov 22 09:11:35 thomsaudiohoek kernel: pci 0000:01:00.0: [2646:501c] type 00 class 0x010802 Nov 22 09:11:35 thomsaudiohoek kernel: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00003fff 64bit] Nov 22 09:11:35 thomsaudiohoek kernel: pci 0000:01:00.0: 4.000 Gb/s available PCIe bandwidth, limited by 5.0 GT/s PCIe x1 link at 0000:00:00.0 (capable of 63.012 Gb/s with 16.0 GT/s PCIe x4 link) Nov 22 09:11:35 thomsaudiohoek kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Nov 22 09:11:35 thomsaudiohoek kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x1b80000000-0x1b800fffff] Nov 22 09:11:35 thomsaudiohoek kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x1b80000000-0x1b80003fff 64bit] Nov 22 09:11:35 thomsaudiohoek kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Nov 22 09:11:35 thomsaudiohoek kernel: pci 0000:00:00.0: bridge window [mem 0x1b80000000-0x1b800fffff] Nov 22 09:11:35 thomsaudiohoek kernel: pci 0000:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Nov 22 09:11:35 thomsaudiohoek kernel: pci 0000:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 Nov 22 09:11:35 thomsaudiohoek kernel: pcieport 0000:00:00.0: enabling device (0000 -> 0002) Nov 22 09:11:35 thomsaudiohoek kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 38 Nov 22 09:11:35 thomsaudiohoek kernel: pcieport 0000:00:00.0: AER: enabled with IRQ 38 Nov 22 09:11:35 thomsaudiohoek kernel: nvme nvme0: pci function 0000:01:00.0 Nov 22 09:11:35 thomsaudiohoek kernel: nvme 0000:01:00.0: enabling device (0000 -> 0002) Nov 22 09:11:35 thomsaudiohoek kernel: hwmon hwmon1: temp1_input not attached to any thermal zone Nov 22 09:11:35 thomsaudiohoek kernel: hwmon hwmon1: temp3_input not attached to any thermal zone Nov 22 09:11:35 thomsaudiohoek kernel: nvme nvme0: allocated 64 MiB host memory buffer. Nov 22 09:11:35 thomsaudiohoek kernel: cma: cma_alloc: linux,cma: alloc failed, req-size: 4 pages, ret: -12 Nov 22 09:11:35 thomsaudiohoek kernel: cma: cma_alloc: linux,cma: alloc failed, req-size: 16 pages, ret: -12 Nov 22 09:11:35 thomsaudiohoek kernel: cma: cma_alloc: linux,cma: alloc failed, req-size: 4 pages, ret: -12 Nov 22 09:11:35 thomsaudiohoek kernel: cma: cma_alloc: linux,cma: alloc failed, req-size: 16 pages, ret: -12 Nov 22 09:11:35 thomsaudiohoek kernel: cma: cma_alloc: linux,cma: alloc failed, req-size: 4 pages, ret: -12 Nov 22 09:11:35 thomsaudiohoek kernel: cma: cma_alloc: linux,cma: alloc failed, req-size: 16 pages, ret: -12 Nov 22 09:11:35 thomsaudiohoek kernel: cma: cma_alloc: linux,cma: alloc failed, req-size: 4 pages, ret: -12 Nov 22 09:11:35 thomsaudiohoek kernel: cma: cma_alloc: linux,cma: alloc failed, req-size: 16 pages, ret: -12 Nov 22 09:11:35 thomsaudiohoek kernel: nvme nvme0: 4/0/0 default/read/poll queues Nov 22 09:11:35 thomsaudiohoek kernel: nvme0n1: p1 p2 p3 Nov 22 09:11:35 thomsaudiohoek kernel: brcm-pcie 1000120000.pcie: host bridge /axi/pcie@120000 ranges: Nov 22 09:11:35 thomsaudiohoek kernel: brcm-pcie 1000120000.pcie: No bus range found for /axi/pcie@120000, using [bus 00-ff] Nov 22 09:11:35 thomsaudiohoek kernel: brcm-pcie 1000120000.pcie: MEM 0x1f00000000..0x1ffffffffb -> 0x0000000000 Nov 22 09:11:35 thomsaudiohoek kernel: brcm-pcie 1000120000.pcie: MEM 0x1c00000000..0x1effffffff -> 0x0400000000 Nov 22 09:11:35 thomsaudiohoek kernel: brcm-pcie 1000120000.pcie: IB MEM 0x1f00000000..0x1f003fffff -> 0x0000000000 Nov 22 09:11:35 thomsaudiohoek kernel: brcm-pcie 1000120000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000 Nov 22 09:11:35 thomsaudiohoek kernel: brcm-pcie 1000120000.pcie: Forcing gen 2 Nov 22 09:11:35 thomsaudiohoek kernel: brcm-pcie 1000120000.pcie: PCI host bridge to bus 0001:00 Nov 22 09:11:35 thomsaudiohoek kernel: pci_bus 0001:00: root bus resource [bus 00-ff] Nov 22 09:11:35 thomsaudiohoek kernel: pci_bus 0001:00: root bus resource [mem 0x1f00000000-0x1ffffffffb] (bus address [0x00000000-0xfffffffb]) Nov 22 09:11:35 thomsaudiohoek kernel: pci_bus 0001:00: root bus resource [mem 0x1c00000000-0x1effffffff pref] (bus address [0x400000000-0x6ffffffff]) Nov 22 09:11:35 thomsaudiohoek kernel: pci 0001:00:00.0: [14e4:2712] type 01 class 0x060400 Nov 22 09:11:35 thomsaudiohoek kernel: pci 0001:00:00.0: PME# supported from D0 D3hot Nov 22 09:11:35 thomsaudiohoek kernel: pci 0001:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Nov 22 09:11:35 thomsaudiohoek kernel: brcm-pcie 1000120000.pcie: link up, 5.0 GT/s PCIe x4 (!SSC) Nov 22 09:11:35 thomsaudiohoek kernel: pci 0001:01:00.0: [1de4:0001] type 00 class 0x020000 Nov 22 09:11:35 thomsaudiohoek kernel: pci 0001:01:00.0: reg 0x10: [mem 0xffffc000-0xffffffff] Nov 22 09:11:35 thomsaudiohoek kernel: pci 0001:01:00.0: reg 0x14: [mem 0xffc00000-0xffffffff] Nov 22 09:11:35 thomsaudiohoek kernel: pci 0001:01:00.0: reg 0x18: [mem 0xffff0000-0xffffffff] Nov 22 09:11:35 thomsaudiohoek kernel: pci 0001:01:00.0: supports D1 Nov 22 09:11:35 thomsaudiohoek kernel: pci 0001:01:00.0: PME# supported from D0 D1 D3hot D3cold Nov 22 09:11:35 thomsaudiohoek kernel: pci_bus 0001:01: busn_res: [bus 01-ff] end is updated to 01 Nov 22 09:11:35 thomsaudiohoek kernel: pci 0001:00:00.0: BAR 8: assigned [mem 0x1f00000000-0x1f005fffff] Nov 22 09:11:35 thomsaudiohoek kernel: pci 0001:01:00.0: BAR 1: assigned [mem 0x1f00000000-0x1f003fffff] Nov 22 09:11:35 thomsaudiohoek kernel: pci 0001:01:00.0: BAR 2: assigned [mem 0x1f00400000-0x1f0040ffff] Nov 22 09:11:35 thomsaudiohoek kernel: pci 0001:01:00.0: BAR 0: assigned [mem 0x1f00410000-0x1f00413fff] Nov 22 09:11:35 thomsaudiohoek kernel: pci 0001:00:00.0: PCI bridge to [bus 01] Nov 22 09:11:35 thomsaudiohoek kernel: pci 0001:00:00.0: bridge window [mem 0x1f00000000-0x1f005fffff] Nov 22 09:11:35 thomsaudiohoek kernel: pci 0001:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Nov 22 09:11:35 thomsaudiohoek kernel: pci 0001:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 Nov 22 09:11:35 thomsaudiohoek kernel: pcieport 0001:00:00.0: enabling device (0000 -> 0002) Nov 22 09:11:35 thomsaudiohoek kernel: pcieport 0001:00:00.0: PME: Signaling with IRQ 44 Nov 22 09:11:35 thomsaudiohoek kernel: pcieport 0001:00:00.0: AER: enabled with IRQ 44 Nov 22 09:11:35 thomsaudiohoek kernel: rp1 0001:01:00.0: bar0 len 0x4000, start 0x1f00410000, end 0x1f00413fff, flags, 0x40200 Nov 22 09:11:35 thomsaudiohoek kernel: rp1 0001:01:00.0: bar1 len 0x400000, start 0x1f00000000, end 0x1f003fffff, flags, 0x40200 Nov 22 09:11:35 thomsaudiohoek kernel: rp1 0001:01:00.0: enabling device (0000 -> 0002) Nov 22 09:11:35 thomsaudiohoek kernel: rp1 0001:01:00.0: chip_id 0x20001927 Nov 22 09:11:35 thomsaudiohoek kernel: genirq: irq_chip rp1_irq_chip did not update eff. affinity mask of irq 106 Nov 22 09:11:35 thomsaudiohoek kernel: macb 1f00100000.ethernet eth0: Cadence GEM rev 0x00070109 at 0x1f00100000 irq 112 (2c:cf:67:26:ee:88) Nov 22 09:11:35 thomsaudiohoek kernel: dw_axi_dmac_platform 1f00188000.dma: DesignWare AXI DMA Controller, 8 channels Nov 22 09:11:35 thomsaudiohoek kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller Nov 22 09:11:35 thomsaudiohoek kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 1 Nov 22 09:11:35 thomsaudiohoek kernel: xhci-hcd xhci-hcd.0: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000008000000810 Nov 22 09:11:35 thomsaudiohoek kernel: xhci-hcd xhci-hcd.0: irq 137, io mem 0x1f00200000 Nov 22 09:11:35 thomsaudiohoek kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller Nov 22 09:11:35 thomsaudiohoek kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 2 Nov 22 09:11:35 thomsaudiohoek kernel: xhci-hcd xhci-hcd.0: Host supports USB 3.0 SuperSpeed Nov 22 09:11:35 thomsaudiohoek kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Nov 22 09:11:35 thomsaudiohoek kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Nov 22 09:11:35 thomsaudiohoek kernel: usb usb1: Product: xHCI Host Controller Nov 22 09:11:35 thomsaudiohoek kernel: usb usb1: Manufacturer: Linux 6.6.56-v8+ xhci-hcd Nov 22 09:11:35 thomsaudiohoek kernel: usb usb1: SerialNumber: xhci-hcd.0 Nov 22 09:11:35 thomsaudiohoek kernel: hub 1-0:1.0: USB hub found Nov 22 09:11:35 thomsaudiohoek kernel: hub 1-0:1.0: 2 ports detected Nov 22 09:11:35 thomsaudiohoek kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06 Nov 22 09:11:35 thomsaudiohoek kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Nov 22 09:11:35 thomsaudiohoek kernel: usb usb2: Product: xHCI Host Controller Nov 22 09:11:35 thomsaudiohoek kernel: usb usb2: Manufacturer: Linux 6.6.56-v8+ xhci-hcd Nov 22 09:11:35 thomsaudiohoek kernel: usb usb2: SerialNumber: xhci-hcd.0 Nov 22 09:11:35 thomsaudiohoek kernel: hub 2-0:1.0: USB hub found Nov 22 09:11:35 thomsaudiohoek kernel: hub 2-0:1.0: 1 port detected Nov 22 09:11:35 thomsaudiohoek kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller Nov 22 09:11:35 thomsaudiohoek kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 3 Nov 22 09:11:35 thomsaudiohoek kernel: xhci-hcd xhci-hcd.1: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000008000000810 Nov 22 09:11:35 thomsaudiohoek kernel: xhci-hcd xhci-hcd.1: irq 142, io mem 0x1f00300000 Nov 22 09:11:35 thomsaudiohoek kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller Nov 22 09:11:35 thomsaudiohoek kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 4 Nov 22 09:11:35 thomsaudiohoek kernel: xhci-hcd xhci-hcd.1: Host supports USB 3.0 SuperSpeed Nov 22 09:11:35 thomsaudiohoek kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Nov 22 09:11:35 thomsaudiohoek kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Nov 22 09:11:35 thomsaudiohoek kernel: usb usb3: Product: xHCI Host Controller Nov 22 09:11:35 thomsaudiohoek kernel: usb usb3: Manufacturer: Linux 6.6.56-v8+ xhci-hcd Nov 22 09:11:35 thomsaudiohoek kernel: usb usb3: SerialNumber: xhci-hcd.1 Nov 22 09:11:35 thomsaudiohoek kernel: hub 3-0:1.0: USB hub found Nov 22 09:11:35 thomsaudiohoek kernel: hub 3-0:1.0: 2 ports detected Nov 22 09:11:35 thomsaudiohoek kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06 Nov 22 09:11:35 thomsaudiohoek kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Nov 22 09:11:35 thomsaudiohoek kernel: usb usb4: Product: xHCI Host Controller Nov 22 09:11:35 thomsaudiohoek kernel: usb usb4: Manufacturer: Linux 6.6.56-v8+ xhci-hcd Nov 22 09:11:35 thomsaudiohoek kernel: usb usb4: SerialNumber: xhci-hcd.1 Nov 22 09:11:35 thomsaudiohoek kernel: hub 4-0:1.0: USB hub found Nov 22 09:11:35 thomsaudiohoek kernel: hub 4-0:1.0: 1 port detected Nov 22 09:11:35 thomsaudiohoek kernel: platform 1f00130000.dsi: Fixed dependency cycle(s) with /axi/pcie@120000/rp1/dsi@128000/bridge@0 Nov 22 09:11:35 thomsaudiohoek kernel: cma: cma_alloc: linux,cma: alloc failed, req-size: 514 pages, ret: -12 Nov 22 09:11:35 thomsaudiohoek kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Nov 22 09:11:35 thomsaudiohoek kernel: platform 1000800000.codec: bcm2712_iommu_probe_device: MMU 1000005100.iommu Nov 22 09:11:35 thomsaudiohoek kernel: platform 1000800000.codec: bcm2712_iommu_device_group: MMU 1000005100.iommu Nov 22 09:11:35 thomsaudiohoek kernel: platform 1000800000.codec: Adding to iommu group 0 Nov 22 09:11:35 thomsaudiohoek kernel: platform 1000880000.pisp_be: bcm2712_iommu_probe_device: MMU 1000005100.iommu Nov 22 09:11:35 thomsaudiohoek kernel: platform 1000880000.pisp_be: bcm2712_iommu_device_group: MMU 1000005100.iommu Nov 22 09:11:35 thomsaudiohoek kernel: platform 1000880000.pisp_be: Adding to iommu group 0 Nov 22 09:11:35 thomsaudiohoek kernel: platform 1000800000.codec: bcm2712_iommu_attach_dev: MMU 1000005100.iommu Nov 22 09:11:35 thomsaudiohoek kernel: platform 1000880000.pisp_be: bcm2712_iommu_attach_dev: MMU 1000005100.iommu Nov 22 09:11:35 thomsaudiohoek kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_probe: Success Nov 22 09:11:35 thomsaudiohoek kernel: cma: cma_alloc: linux,cma: alloc failed, req-size: 514 pages, ret: -12 Nov 22 09:11:35 thomsaudiohoek kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Nov 22 09:11:35 thomsaudiohoek kernel: platform axi:gpu: bcm2712_iommu_probe_device: MMU 1000005200.iommu Nov 22 09:11:35 thomsaudiohoek kernel: platform axi:gpu: bcm2712_iommu_device_group: MMU 1000005200.iommu Nov 22 09:11:35 thomsaudiohoek kernel: platform axi:gpu: Adding to iommu group 1 Nov 22 09:11:35 thomsaudiohoek kernel: platform axi:gpu: bcm2712_iommu_attach_dev: MMU 1000005200.iommu Nov 22 09:11:35 thomsaudiohoek kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_probe: Success Nov 22 09:11:35 thomsaudiohoek kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Nov 22 09:11:35 thomsaudiohoek kernel: platform 1f00130000.dsi: bcm2712_iommu_probe_device: MMU 1000005280.iommu Nov 22 09:11:35 thomsaudiohoek kernel: platform 1f00130000.dsi: bcm2712_iommu_device_group: MMU 1000005280.iommu Nov 22 09:11:35 thomsaudiohoek kernel: platform 1f00130000.dsi: Adding to iommu group 2 Nov 22 09:11:35 thomsaudiohoek kernel: platform 1f00130000.dsi: bcm2712_iommu_attach_dev: MMU 1000005280.iommu Nov 22 09:11:35 thomsaudiohoek kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_probe: Success Nov 22 09:11:35 thomsaudiohoek kernel: sdhci-brcmstb 1000fff000.mmc: Got CD GPIO Nov 22 09:11:35 thomsaudiohoek kernel: mmc1: CQHCI version 5.10 Nov 22 09:11:35 thomsaudiohoek kernel: of_cfs_init Nov 22 09:11:35 thomsaudiohoek kernel: of_cfs_init: OK Nov 22 09:11:35 thomsaudiohoek kernel: clk: Disabling unused clocks Nov 22 09:11:35 thomsaudiohoek kernel: mmc0: SDHCI controller on 1000fff000.mmc [1000fff000.mmc] using ADMA 64-bit Nov 22 09:11:35 thomsaudiohoek kernel: mmc1: SDHCI controller on 1001100000.mmc [1001100000.mmc] using ADMA 64-bit Nov 22 09:11:35 thomsaudiohoek kernel: Freeing unused kernel memory: 4864K Nov 22 09:11:35 thomsaudiohoek kernel: Run /init as init process Nov 22 09:11:35 thomsaudiohoek kernel: with arguments: Nov 22 09:11:35 thomsaudiohoek kernel: /init Nov 22 09:11:35 thomsaudiohoek kernel: splash Nov 22 09:11:35 thomsaudiohoek kernel: nodebug Nov 22 09:11:35 thomsaudiohoek kernel: with environment: Nov 22 09:11:35 thomsaudiohoek kernel: HOME=/ Nov 22 09:11:35 thomsaudiohoek kernel: TERM=linux Nov 22 09:11:35 thomsaudiohoek kernel: imgpart=UUID=c2ba6003-062c-4fe6-ba10-7098aecb5298 Nov 22 09:11:35 thomsaudiohoek kernel: imgfile=/volumio_current.sqsh Nov 22 09:11:35 thomsaudiohoek kernel: bootpart=UUID=466A-50F2 Nov 22 09:11:35 thomsaudiohoek kernel: datapart=UUID=d3781328-ecc3-497c-a5ef-12c057da4894 Nov 22 09:11:35 thomsaudiohoek kernel: uuidconfig=cmdline.txt Nov 22 09:11:35 thomsaudiohoek kernel: bootdelay=7 Nov 22 09:11:35 thomsaudiohoek kernel: use_kmsg=no Nov 22 09:11:35 thomsaudiohoek kernel: mmc1: new ultra high speed DDR50 SDIO card at address 0001 Nov 22 09:11:35 thomsaudiohoek kernel: usb 1-2: new high-speed USB device number 2 using xhci-hcd Nov 22 09:11:35 thomsaudiohoek kernel: snd_timer: Unknown symbol snd_info_register (err -2) Nov 22 09:11:35 thomsaudiohoek kernel: snd_timer: Unknown symbol snd_info_create_module_entry (err -2) Nov 22 09:11:35 thomsaudiohoek kernel: snd_timer: Unknown symbol snd_info_free_entry (err -2) Nov 22 09:11:35 thomsaudiohoek kernel: snd_timer: Unknown symbol snd_fasync_helper (err -2) Nov 22 09:11:35 thomsaudiohoek kernel: snd_timer: Unknown symbol snd_ecards_limit (err -2) Nov 22 09:11:35 thomsaudiohoek kernel: snd_timer: Unknown symbol snd_oss_info_register (err -2) Nov 22 09:11:35 thomsaudiohoek kernel: snd_timer: Unknown symbol snd_unregister_device (err -2) Nov 22 09:11:35 thomsaudiohoek kernel: snd_timer: Unknown symbol snd_device_alloc (err -2) Nov 22 09:11:35 thomsaudiohoek kernel: snd_timer: Unknown symbol snd_device_new (err -2) Nov 22 09:11:35 thomsaudiohoek kernel: snd_timer: Unknown symbol snd_fasync_free (err -2) Nov 22 09:11:35 thomsaudiohoek kernel: snd_timer: Unknown symbol snd_kill_fasync (err -2) Nov 22 09:11:35 thomsaudiohoek kernel: snd_timer: Unknown symbol snd_register_device (err -2) Nov 22 09:11:35 thomsaudiohoek kernel: brcmstb-i2c 107d508200.i2c: @97500hz registered in interrupt mode Nov 22 09:11:35 thomsaudiohoek kernel: brcmstb-i2c 107d508280.i2c: @97500hz registered in interrupt mode Nov 22 09:11:35 thomsaudiohoek kernel: input: pwr_button as /devices/platform/pwr_button/input/input0 Nov 22 09:11:35 thomsaudiohoek kernel: usb 1-2: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.04 Nov 22 09:11:35 thomsaudiohoek kernel: usb 1-2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Nov 22 09:11:35 thomsaudiohoek kernel: usb 1-2: Product: D10s Nov 22 09:11:35 thomsaudiohoek kernel: usb 1-2: Manufacturer: Topping Nov 22 09:11:35 thomsaudiohoek kernel: drm-rp1-dsi 1f00130000.dsi: bcm2712_iommu_of_xlate: MMU 1000005280.iommu Nov 22 09:11:35 thomsaudiohoek kernel: platform 100000001.panel_disp: Fixed dependency cycle(s) with /axi/pcie@120000/rp1/dsi@128000/bridge@0 Nov 22 09:11:35 thomsaudiohoek kernel: mipi-dsi 1f00130000.dsi.0: Fixed dependency cycle(s) with /panel_disp@1 Nov 22 09:11:35 thomsaudiohoek kernel: [drm] Initialized v3d 1.0.0 20180419 for 1002000000.v3d on minor 1 Nov 22 09:11:35 thomsaudiohoek kernel: drm-rp1-dsi 1f00130000.dsi: rp1dsi_host_attach: Attach DSI device name=tc358762 channel=0 lanes=1 format=0 flags=0x815 hs_rate=0 lp_rate=0 Nov 22 09:11:35 thomsaudiohoek kernel: [drm] Initialized drm-rp1-dsi 1.0.0 0 for 1f00130000.dsi on minor 0 Nov 22 09:11:35 thomsaudiohoek kernel: fuse: init (API version 7.39) Nov 22 09:11:35 thomsaudiohoek kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 22 09:11:35 thomsaudiohoek kernel: drm-rp1-dsi 1f00130000.dsi: [drm] rp1dsi: Command time (outvact): 33 Nov 22 09:11:35 thomsaudiohoek kernel: drm-rp1-dsi 1f00130000.dsi: [drm] rp1dsi: Nominal Byte clock 90000000 DPI clock 30000000 (parent rate 90000000) Nov 22 09:11:35 thomsaudiohoek kernel: Console: switching to colour frame buffer device 100x30 Nov 22 09:11:35 thomsaudiohoek kernel: drm-rp1-dsi 1f00130000.dsi: [drm] fb0: drm-rp1-dsidrmf frame buffer device Nov 22 09:11:35 thomsaudiohoek kernel: drm-rp1-dsi 1f00130000.dsi: rp1dsi_bind succeeded Nov 22 09:11:35 thomsaudiohoek kernel: FAT-fs (nvme0n1p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Nov 22 09:11:35 thomsaudiohoek kernel: EXT4-fs (nvme0n1p2): mounted filesystem c2ba6003-062c-4fe6-ba10-7098aecb5298 r/w with ordered data mode. Quota mode: none. Nov 22 09:11:35 thomsaudiohoek kernel: loop0: detected capacity change from 0 to 994880 Nov 22 09:11:35 thomsaudiohoek kernel: EXT4-fs (nvme0n1p3): recovery complete Nov 22 09:11:35 thomsaudiohoek kernel: EXT4-fs (nvme0n1p3): mounted filesystem d3781328-ecc3-497c-a5ef-12c057da4894 r/w with ordered data mode. Quota mode: none. Nov 22 09:11:35 thomsaudiohoek kernel: NET: Registered PF_INET6 protocol family Nov 22 09:11:35 thomsaudiohoek kernel: Segment Routing with IPv6 Nov 22 09:11:35 thomsaudiohoek kernel: In-situ OAM (IOAM) with IPv6 Nov 22 09:11:35 thomsaudiohoek 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) Nov 22 09:11:35 thomsaudiohoek systemd[1]: Detected architecture arm64. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Set hostname to . Nov 22 09:11:35 thomsaudiohoek kernel: uart-pl011 107d001000.serial: no DMA platform data Nov 22 09:11:35 thomsaudiohoek 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. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Listening on Journal Socket (/dev/log). Nov 22 09:11:35 thomsaudiohoek systemd[1]: Listening on udev Control Socket. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Listening on Journal Socket. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Starting Load Kernel Modules... Nov 22 09:11:35 thomsaudiohoek systemd[1]: Starting Remount Root and Kernel File Systems... Nov 22 09:11:35 thomsaudiohoek systemd[1]: Starting Restore / save the current clock... Nov 22 09:11:35 thomsaudiohoek kernel: i2c_dev: i2c /dev entries driver Nov 22 09:11:35 thomsaudiohoek systemd-journald[416]: Journal started Nov 22 09:11:35 thomsaudiohoek systemd-journald[416]: Runtime journal (/run/log/journal/d5012141e8efab18115b4334669e938a) is 3.7M, max 30.0M, 26.2M free. Nov 22 09:11:35 thomsaudiohoek systemd-modules-load[404]: Inserted module 'i2c_dev' Nov 22 09:11:35 thomsaudiohoek fake-hwclock[406]: Current system time: 2024-11-22 08:11:35 Nov 22 09:11:35 thomsaudiohoek fake-hwclock[406]: fake-hwclock saved clock information is in the past: 2024-11-22 08:10:45 Nov 22 09:11:35 thomsaudiohoek fake-hwclock[406]: To set system time to this saved clock anyway, use "force" Nov 22 09:11:35 thomsaudiohoek systemd[1]: Started Load/Save Random Seed. Nov 22 09:11:35 thomsaudiohoek systemd[1]: dynamicswap.service: Succeeded. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Started Create System Users. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Starting Create Static Device Nodes in /dev... Nov 22 09:11:35 thomsaudiohoek systemd[1]: Started Create Static Device Nodes in /dev. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Starting udev Kernel Device Manager... Nov 22 09:11:35 thomsaudiohoek systemd[1]: Reached target Local File Systems (Pre). Nov 22 09:11:35 thomsaudiohoek systemd[1]: Mounting /var/spool/cups... Nov 22 09:11:35 thomsaudiohoek systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Mounting /var/log... Nov 22 09:11:35 thomsaudiohoek systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Mounting /tmp... Nov 22 09:11:35 thomsaudiohoek systemd[1]: Mounted /var/spool/cups. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Mounting /var/spool/cups/tmp... Nov 22 09:11:35 thomsaudiohoek systemd[1]: Mounted /var/log. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Mounted /tmp. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Mounted /var/spool/cups/tmp. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Starting Flush Journal to Persistent Storage... Nov 22 09:11:35 thomsaudiohoek systemd-udevd[436]: Network interface NamePolicy= disabled on kernel command line, ignoring. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Started udev Coldplug all Devices. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Starting Helper to synchronize boot up for ifupdown... Nov 22 09:11:35 thomsaudiohoek systemd-journald[416]: Runtime journal (/run/log/journal/d5012141e8efab18115b4334669e938a) is 3.7M, max 30.0M, 26.2M free. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Started Flush Journal to Persistent Storage. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Started Helper to synchronize boot up for ifupdown. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Started udev Kernel Device Manager. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Starting Show Plymouth Boot Screen... Nov 22 09:11:35 thomsaudiohoek systemd[1]: plymouth-start.service: Succeeded. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Started Show Plymouth Boot Screen. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Reached target Paths. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Reached target Local Encrypted Volumes. Nov 22 09:11:35 thomsaudiohoek systemd-udevd[485]: Using default interface naming scheme 'v240'. Nov 22 09:11:35 thomsaudiohoek systemd-udevd[485]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Nov 22 09:11:35 thomsaudiohoek kernel: mc: Linux media interface: v0.10 Nov 22 09:11:35 thomsaudiohoek kernel: rpi-gpiomem 107d508500.gpiomem: window base 0x107d508500 size 0x00000040 Nov 22 09:11:35 thomsaudiohoek kernel: rpi-gpiomem 107d508500.gpiomem: initialised 1 regions as /dev/gpiomem1 Nov 22 09:11:35 thomsaudiohoek kernel: rpi-gpiomem 107d517c00.gpiomem: window base 0x107d517c00 size 0x00000040 Nov 22 09:11:35 thomsaudiohoek kernel: rpi-gpiomem 107d517c00.gpiomem: initialised 1 regions as /dev/gpiomem2 Nov 22 09:11:35 thomsaudiohoek kernel: rpi-gpiomem 107d504100.gpiomem: window base 0x107d504100 size 0x00000020 Nov 22 09:11:35 thomsaudiohoek kernel: rpi-gpiomem 107d504100.gpiomem: initialised 1 regions as /dev/gpiomem3 Nov 22 09:11:35 thomsaudiohoek kernel: rpi-gpiomem 107d510700.gpiomem: window base 0x107d510700 size 0x00000020 Nov 22 09:11:35 thomsaudiohoek kernel: rpi-gpiomem 107d510700.gpiomem: initialised 1 regions as /dev/gpiomem4 Nov 22 09:11:35 thomsaudiohoek kernel: rpi-gpiomem 1f000d0000.gpiomem: window base 0x1f000d0000 size 0x00030000 Nov 22 09:11:35 thomsaudiohoek kernel: rpi-gpiomem 1f000d0000.gpiomem: initialised 1 regions as /dev/gpiomem0 Nov 22 09:11:35 thomsaudiohoek systemd[1]: Found device /dev/ttyAMA10. Nov 22 09:11:35 thomsaudiohoek kernel: edt_ft5x06 4-0038: supply iovcc not found, using dummy regulator Nov 22 09:11:35 thomsaudiohoek systemd[1]: Found device /sys/subsystem/net/devices/eth0. Nov 22 09:11:35 thomsaudiohoek kernel: videodev: Linux video capture interface: v2.00 Nov 22 09:11:35 thomsaudiohoek systemd[1]: Created slice system-systemd\x2dbacklight.slice. Nov 22 09:11:35 thomsaudiohoek kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Nov 22 09:11:35 thomsaudiohoek kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Nov 22 09:11:35 thomsaudiohoek kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Nov 22 09:11:35 thomsaudiohoek kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Nov 22 09:11:35 thomsaudiohoek kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Nov 22 09:11:35 thomsaudiohoek systemd[1]: Starting Load/Save Screen Backlight Brightness of backlight:4-0045... Nov 22 09:11:35 thomsaudiohoek systemd[1]: Started Load/Save Screen Backlight Brightness of backlight:4-0045. Nov 22 09:11:35 thomsaudiohoek systemd-udevd[458]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Nov 22 09:11:35 thomsaudiohoek systemd-udevd[444]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Found device KINGSTON SNV2S500G boot. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Nov 22 09:11:35 thomsaudiohoek systemd[1]: Starting File System Check on /dev/disk/by-uuid/466A-50F2... Nov 22 09:11:35 thomsaudiohoek systemd[1]: Started File System Check Daemon to report status. Nov 22 09:11:35 thomsaudiohoek kernel: input: 4-0038 generic ft5x06 (79) as /devices/platform/axi/1000120000.pcie/1f00080000.i2c/i2c-4/4-0038/input/input1 Nov 22 09:11:35 thomsaudiohoek kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Nov 22 09:11:35 thomsaudiohoek kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Nov 22 09:11:35 thomsaudiohoek kernel: pispbe 1000880000.pisp_be: Runtime PM usage count underflow! Nov 22 09:11:35 thomsaudiohoek systemd-fsck[690]: fsck.fat 4.1 (2017-01-24) Nov 22 09:11:35 thomsaudiohoek systemd-fsck[690]: 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Nov 22 09:11:35 thomsaudiohoek systemd-fsck[690]: Automatically removing dirty bit. Nov 22 09:11:35 thomsaudiohoek systemd-fsck[690]: Performing changes. Nov 22 09:11:35 thomsaudiohoek systemd-fsck[690]: /dev/nvme0n1p1: 406 files, 166098/184582 clusters Nov 22 09:11:35 thomsaudiohoek systemd[1]: Started File System Check on /dev/disk/by-uuid/466A-50F2. Nov 22 09:11:35 thomsaudiohoek kernel: rpivid 1000800000.codec: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Nov 22 09:11:36 thomsaudiohoek kernel: rpivid 1000800000.codec: Device registered as /dev/video19 Nov 22 09:11:36 thomsaudiohoek kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Nov 22 09:11:36 thomsaudiohoek kernel: Bluetooth: Core ver 2.22 Nov 22 09:11:36 thomsaudiohoek kernel: NET: Registered PF_BLUETOOTH protocol family Nov 22 09:11:36 thomsaudiohoek kernel: Bluetooth: HCI device and connection manager initialized Nov 22 09:11:36 thomsaudiohoek kernel: Bluetooth: HCI socket layer initialized Nov 22 09:11:36 thomsaudiohoek kernel: Bluetooth: L2CAP socket layer initialized Nov 22 09:11:36 thomsaudiohoek kernel: Bluetooth: SCO socket layer initialized Nov 22 09:11:36 thomsaudiohoek kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Nov 22 09:11:36 thomsaudiohoek kernel: usbcore: registered new interface driver brcmfmac Nov 22 09:11:36 thomsaudiohoek kernel: brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,5-model-b.bin failed with error -2 Nov 22 09:11:36 thomsaudiohoek systemd[1]: Mounting /boot... Nov 22 09:11:36 thomsaudiohoek systemd[1]: Mounted /boot. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Reached target Local File Systems. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Starting Preprocess NFS configuration... Nov 22 09:11:36 thomsaudiohoek systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Nov 22 09:11:36 thomsaudiohoek kernel: Bluetooth: HCI UART driver ver 2.3 Nov 22 09:11:36 thomsaudiohoek kernel: Bluetooth: HCI UART protocol H4 registered Nov 22 09:11:36 thomsaudiohoek kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Nov 22 09:11:36 thomsaudiohoek kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Nov 22 09:11:36 thomsaudiohoek kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Nov 22 09:11:36 thomsaudiohoek kernel: Bluetooth: HCI UART protocol Broadcom registered Nov 22 09:11:36 thomsaudiohoek systemd[1]: Starting Raise network interfaces... Nov 22 09:11:36 thomsaudiohoek systemd[1]: Started ifup for eth0. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Starting Create Volatile Files and Directories... Nov 22 09:11:36 thomsaudiohoek systemd[1]: nfs-config.service: Succeeded. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Started Preprocess NFS configuration. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Reached target NFS client services. Nov 22 09:11:36 thomsaudiohoek systemd[1]: plymouth-read-write.service: Succeeded. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Received SIGRTMIN+20 from PID 297 (plymouthd). Nov 22 09:11:36 thomsaudiohoek systemd-udevd[459]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Started Create Volatile Files and Directories. Nov 22 09:11:36 thomsaudiohoek sh[724]: command failed: No such device (-19) Nov 22 09:11:36 thomsaudiohoek ifup[723]: command failed: No such device (-19) Nov 22 09:11:36 thomsaudiohoek systemd[1]: Starting RPC bind portmap service... Nov 22 09:11:36 thomsaudiohoek systemd[1]: Starting Update UTMP about System Boot/Shutdown... Nov 22 09:11:36 thomsaudiohoek systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Started Update UTMP about System Boot/Shutdown. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Reached target System Initialization. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Started Daily apt download activities. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Started Daily man-db regeneration. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Started Daily Cleanup of Temporary Directories. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Started Daily apt upgrade and clean activities. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Reached target Timers. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Listening on D-Bus System Message Bus Socket. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Listening on triggerhappy.socket. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Reached target Sockets. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Reached target Basic System. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Started D-Bus System Message Bus. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Starting Login Service... Nov 22 09:11:36 thomsaudiohoek kernel: usb 1-2: 1:3 : unsupported format bits 0x100000000 Nov 22 09:11:36 thomsaudiohoek systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Nov 22 09:11:36 thomsaudiohoek systemd[1]: Started UPnP Renderer front-end to MPD. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Started volumio-remote-updater.service. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Started Volumio Iptables Module. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Starting Wireless Services... Nov 22 09:11:36 thomsaudiohoek systemd[1]: Condition check resulted in Configure Bluetooth Modems connected by UART being skipped. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Starting Volumio Time Update Utility... Nov 22 09:11:36 thomsaudiohoek systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Starting WPA supplicant... Nov 22 09:11:36 thomsaudiohoek systemd[1]: Started Volumio Log Rotation Service. Nov 22 09:11:36 thomsaudiohoek systemd-udevd[483]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Starting triggerhappy global hotkey daemon... Nov 22 09:11:36 thomsaudiohoek systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Starting dhcpcd on all interfaces... Nov 22 09:11:36 thomsaudiohoek systemd[1]: Started MPD Monitor Service. Nov 22 09:11:36 thomsaudiohoek kernel: usbcore: registered new interface driver snd-usb-audio Nov 22 09:11:36 thomsaudiohoek volumio-time-update[779]: volumio-time-update-util: Fetching time from Volumio... Nov 22 09:11:36 thomsaudiohoek ifup[723]: command failed: No such device (-19) Nov 22 09:11:36 thomsaudiohoek dhcpcd[786]: Not running dhcpcd because /etc/network/interfaces Nov 22 09:11:36 thomsaudiohoek dhcpcd[786]: defines some interfaces that will use a Nov 22 09:11:36 thomsaudiohoek dhcpcd[786]: DHCP client or static address Nov 22 09:11:36 thomsaudiohoek thd[784]: Unable to parse trigger line: Nov 22 09:11:36 thomsaudiohoek thd[784]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Nov 22 09:11:36 thomsaudiohoek thd[784]: Unable to parse trigger line: Nov 22 09:11:36 thomsaudiohoek thd[784]: Unable to parse trigger line: Nov 22 09:11:36 thomsaudiohoek thd[784]: Found socket passed from systemd Nov 22 09:11:36 thomsaudiohoek systemd-logind[769]: New seat seat0. Nov 22 09:11:36 thomsaudiohoek systemd-logind[769]: Watching system buttons on /dev/input/event0 (pwr_button) Nov 22 09:11:36 thomsaudiohoek mpd_monitor.sh[787]: MPD error: Connection refused Nov 22 09:11:36 thomsaudiohoek kernel: 8021q: 802.1Q VLAN Support v1.8 Nov 22 09:11:36 thomsaudiohoek kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Nov 22 09:11:36 thomsaudiohoek 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 Nov 22 09:11:36 thomsaudiohoek avahi-daemon[770]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Nov 22 09:11:36 thomsaudiohoek avahi-daemon[770]: Successfully dropped root privileges. Nov 22 09:11:36 thomsaudiohoek avahi-daemon[770]: avahi-daemon 0.7 starting up. Nov 22 09:11:36 thomsaudiohoek mpd_monitor.sh[787]: mpd: no process found Nov 22 09:11:36 thomsaudiohoek kernel: vc4-drm axi:gpu: bcm2712_iommu_of_xlate: MMU 1000005200.iommu Nov 22 09:11:36 thomsaudiohoek systemd-udevd[483]: Using default interface naming scheme 'v240'. Nov 22 09:11:36 thomsaudiohoek kernel: vc4-drm axi:gpu: bound 107c580000.hvs (ops vc4_hvs_ops [vc4]) Nov 22 09:11:36 thomsaudiohoek systemd-udevd[483]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Nov 22 09:11:36 thomsaudiohoek kernel: Registered IR keymap rc-cec Nov 22 09:11:36 thomsaudiohoek kernel: rc rc0: vc4-hdmi-0 as /devices/platform/soc/107c701400.hdmi/rc/rc0 Nov 22 09:11:36 thomsaudiohoek kernel: input: vc4-hdmi-0 as /devices/platform/soc/107c701400.hdmi/rc/rc0/input2 Nov 22 09:11:36 thomsaudiohoek kernel: input: vc4-hdmi-0 HDMI Jack as /devices/platform/soc/107c701400.hdmi/sound/card0/input3 Nov 22 09:11:36 thomsaudiohoek kernel: vc4-drm axi:gpu: bound 107c701400.hdmi (ops vc4_hdmi_ops [vc4]) Nov 22 09:11:36 thomsaudiohoek kernel: Registered IR keymap rc-cec Nov 22 09:11:36 thomsaudiohoek kernel: rc rc1: vc4-hdmi-1 as /devices/platform/soc/107c706400.hdmi/rc/rc1 Nov 22 09:11:36 thomsaudiohoek kernel: input: vc4-hdmi-1 as /devices/platform/soc/107c706400.hdmi/rc/rc1/input4 Nov 22 09:11:36 thomsaudiohoek kernel: input: vc4-hdmi-1 HDMI Jack as /devices/platform/soc/107c706400.hdmi/sound/card1/input5 Nov 22 09:11:36 thomsaudiohoek kernel: vc4-drm axi:gpu: bound 107c706400.hdmi (ops vc4_hdmi_ops [vc4]) Nov 22 09:11:36 thomsaudiohoek kernel: vc4-drm axi:gpu: bound 107c500000.mop (ops vc4_txp_ops [vc4]) Nov 22 09:11:36 thomsaudiohoek kernel: vc4-drm axi:gpu: bound 107c501000.moplet (ops vc4_txp_ops [vc4]) Nov 22 09:11:36 thomsaudiohoek kernel: vc4-drm axi:gpu: bound 107c410000.pixelvalve (ops vc4_crtc_ops [vc4]) Nov 22 09:11:36 thomsaudiohoek kernel: vc4-drm axi:gpu: bound 107c411000.pixelvalve (ops vc4_crtc_ops [vc4]) Nov 22 09:11:36 thomsaudiohoek kernel: [drm] Initialized vc4 0.0.0 20140616 for axi:gpu on minor 2 Nov 22 09:11:36 thomsaudiohoek kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Nov 22 09:11:36 thomsaudiohoek kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Nov 22 09:11:36 thomsaudiohoek kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Nov 22 09:11:36 thomsaudiohoek kernel: Bluetooth: hci0: BCM: chip id 107 Nov 22 09:11:36 thomsaudiohoek kernel: Bluetooth: hci0: BCM: features 0x2f Nov 22 09:11:36 thomsaudiohoek kernel: Bluetooth: hci0: BCM4345C0 Nov 22 09:11:36 thomsaudiohoek kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Nov 22 09:11:36 thomsaudiohoek kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Nov 22 09:11:36 thomsaudiohoek wpa_supplicant[781]: Successfully initialized wpa_supplicant Nov 22 09:11:36 thomsaudiohoek dbus-daemon[763]: [system] Successfully activated service 'org.freedesktop.systemd1' Nov 22 09:11:36 thomsaudiohoek kernel: cma_alloc: 2 callbacks suppressed Nov 22 09:11:36 thomsaudiohoek kernel: cma: cma_alloc: linux,cma: alloc failed, req-size: 3 pages, ret: -12 Nov 22 09:11:36 thomsaudiohoek kernel: cma: cma_alloc: linux,cma: alloc failed, req-size: 3 pages, ret: -12 Nov 22 09:11:36 thomsaudiohoek systemd[1]: Started WPA supplicant. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Started Login Service. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Started RPC bind portmap service. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Started triggerhappy global hotkey daemon. Nov 22 09:11:36 thomsaudiohoek systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Nov 22 09:11:36 thomsaudiohoek systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Failed to start dhcpcd on all interfaces. Nov 22 09:11:36 thomsaudiohoek avahi-daemon[770]: Successfully called chroot(). Nov 22 09:11:36 thomsaudiohoek avahi-daemon[770]: Successfully dropped remaining capabilities. Nov 22 09:11:36 thomsaudiohoek systemd-udevd[452]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 22 09:11:36 thomsaudiohoek systemd-logind[769]: Watching system buttons on /dev/input/event2 (vc4-hdmi-0) Nov 22 09:11:36 thomsaudiohoek kernel: macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:01] driver [Broadcom BCM54213PE] (irq=POLL) Nov 22 09:11:36 thomsaudiohoek kernel: macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode Nov 22 09:11:36 thomsaudiohoek systemd-udevd[465]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 22 09:11:36 thomsaudiohoek dhcpcd[765]: eth0: waiting for carrier Nov 22 09:11:36 thomsaudiohoek sh[724]: eth0: waiting for carrier Nov 22 09:11:36 thomsaudiohoek systemd-udevd[486]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 22 09:11:36 thomsaudiohoek systemd-logind[769]: Watching system buttons on /dev/input/event4 (vc4-hdmi-1) Nov 22 09:11:36 thomsaudiohoek kernel: pps pps0: new PPS source ptp0 Nov 22 09:11:36 thomsaudiohoek kernel: macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered. Nov 22 09:11:36 thomsaudiohoek avahi-daemon[770]: Loading service file /services/volumio.service. Nov 22 09:11:36 thomsaudiohoek avahi-daemon[770]: Network interface enumeration completed. Nov 22 09:11:36 thomsaudiohoek avahi-daemon[770]: Server startup complete. Host name is thomsaudiohoek.local. Local service cookie is 3377274809. Nov 22 09:11:36 thomsaudiohoek avahi-daemon[770]: Service "ThomsAudioHoek" (/services/volumio.service) successfully established. Nov 22 09:11:36 thomsaudiohoek systemd-udevd[458]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 22 09:11:36 thomsaudiohoek volumio-remote-updater[775]: Error: No active session Nov 22 09:11:36 thomsaudiohoek volumio-remote-updater[775]: [2024-11-22 09:11:36] [info] asio async_connect error: system:111 (Connection refused) Nov 22 09:11:36 thomsaudiohoek volumio-remote-updater[775]: [2024-11-22 09:11:36] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 22 09:11:36 thomsaudiohoek volumio-remote-updater[775]: [2024-11-22 09:11:36] [error] handle_connect error: Underlying Transport Error Nov 22 09:11:36 thomsaudiohoek volumio[774]: Could not open config: /tmp/upmpdcli.conf Nov 22 09:11:36 thomsaudiohoek systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Nov 22 09:11:36 thomsaudiohoek systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Nov 22 09:11:36 thomsaudiohoek systemd-udevd[460]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Nov 22 09:11:36 thomsaudiohoek volumio-time-update[779]: volumio-time-update-util: Date not found in response Nov 22 09:11:36 thomsaudiohoek volumio-time-update[779]: volumio-time-update-util: Retrying in 5 seconds... Nov 22 09:11:36 thomsaudiohoek systemd[1]: iptables.service: Succeeded. Nov 22 09:11:36 thomsaudiohoek kernel: alsactl[847]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Nov 22 09:11:36 thomsaudiohoek systemd[1]: Started Avahi mDNS/DNS-SD Stack. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Listening on mpd.socket. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Created slice system-bthelper.slice. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Condition check resulted in Configure Bluetooth Modems connected by UART being skipped. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Starting Raspberry Pi bluetooth helper... Nov 22 09:11:36 thomsaudiohoek systemd[1]: Started Manage Sound Card State (restore and store). Nov 22 09:11:36 thomsaudiohoek alsactl[930]: alsactl 1.1.8 daemon started Nov 22 09:11:36 thomsaudiohoek systemd[1]: Starting Save/Restore Sound Card State... Nov 22 09:11:36 thomsaudiohoek systemd[1]: Starting Load/Save RF Kill Switch Status... Nov 22 09:11:36 thomsaudiohoek systemd[1]: Reached target Remote File Systems (Pre). Nov 22 09:11:36 thomsaudiohoek systemd[1]: Reached target Remote File Systems. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Nov 22 09:11:36 thomsaudiohoek systemd[1]: Reached target RPC Port Mapper. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Started Save/Restore Sound Card State. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Nov 22 09:11:36 thomsaudiohoek systemd[1]: Started Load/Save RF Kill Switch Status. Nov 22 09:11:37 thomsaudiohoek systemd[1]: Started ifup for wlan0. Nov 22 09:11:37 thomsaudiohoek systemd[1]: Reached target Sound Card. Nov 22 09:11:37 thomsaudiohoek systemd[1]: Started Raise network interfaces. Nov 22 09:11:37 thomsaudiohoek systemd[1]: Reached target Network. Nov 22 09:11:37 thomsaudiohoek systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Nov 22 09:11:37 thomsaudiohoek systemd[1]: Starting Permit User Sessions... Nov 22 09:11:37 thomsaudiohoek kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Nov 22 09:11:37 thomsaudiohoek systemd[1]: Starting OpenBSD Secure Shell server... Nov 22 09:11:37 thomsaudiohoek systemd[1]: Starting Network Time Service... Nov 22 09:11:37 thomsaudiohoek systemd[1]: Starting Music Player Daemon... Nov 22 09:11:37 thomsaudiohoek systemd[1]: Reached target Network is Online. Nov 22 09:11:37 thomsaudiohoek systemd[1]: Starting LSB: Brings up/down network automatically... Nov 22 09:11:37 thomsaudiohoek systemd[1]: Starting /etc/rc.local Compatibility... Nov 22 09:11:37 thomsaudiohoek systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 22 09:11:37 thomsaudiohoek systemd[1]: Starting Samba NMB Daemon... Nov 22 09:11:37 thomsaudiohoek kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 22 09:11:37 thomsaudiohoek systemd[1]: Started Permit User Sessions. Nov 22 09:11:37 thomsaudiohoek systemd[1]: Started /etc/rc.local Compatibility. Nov 22 09:11:37 thomsaudiohoek systemd[1]: Starting Terminate Plymouth Boot Screen... Nov 22 09:11:37 thomsaudiohoek systemd[1]: Starting Hold until boot process finishes up... Nov 22 09:11:37 thomsaudiohoek systemd[1]: Received SIGRTMIN+21 from PID 297 (plymouthd). Nov 22 09:11:37 thomsaudiohoek sshd[1002]: Server listening on 0.0.0.0 port 22. Nov 22 09:11:37 thomsaudiohoek sshd[1002]: Server listening on :: port 22. Nov 22 09:11:37 thomsaudiohoek systemd[1]: Started OpenBSD Secure Shell server. Nov 22 09:11:37 thomsaudiohoek ntpd[960]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 22 09:11:37 thomsaudiohoek ntpd[960]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Nov 22 09:11:37 thomsaudiohoek ntpd[1023]: proto: precision = 0.556 usec (-21) Nov 22 09:11:37 thomsaudiohoek systemd[1]: Started Network Time Service. Nov 22 09:11:37 thomsaudiohoek haveged[753]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Nov 22 09:11:37 thomsaudiohoek haveged[753]: haveged: cpu: (VC); data: 64K (V); inst: 64K (V); idx: 39/40; sz: 63488/63488 Nov 22 09:11:37 thomsaudiohoek haveged[753]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99907 Nov 22 09:11:37 thomsaudiohoek haveged[753]: haveged: fills: 0, generated: 0 Nov 22 09:11:37 thomsaudiohoek ntpd[1023]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 22 09:11:37 thomsaudiohoek ntpd[1023]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 22 09:11:37 thomsaudiohoek ntpd[1023]: Listen and drop on 0 v6wildcard [::]:123 Nov 22 09:11:37 thomsaudiohoek ntpd[1023]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 22 09:11:37 thomsaudiohoek ntpd[1023]: Listen normally on 2 lo 127.0.0.1:123 Nov 22 09:11:37 thomsaudiohoek ntpd[1023]: Listening on routing socket on fd #19 for interface updates Nov 22 09:11:37 thomsaudiohoek ntpd[1023]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 22 09:11:37 thomsaudiohoek ntpd[1023]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 22 09:11:37 thomsaudiohoek loadcpufreq[934]: Loading cpufreq kernel modules...done (none). Nov 22 09:11:37 thomsaudiohoek systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Nov 22 09:11:37 thomsaudiohoek sh[937]: wlan0=wlan0 Nov 22 09:11:37 thomsaudiohoek systemd[1]: Starting LSB: set CPUFreq kernel parameters... Nov 22 09:11:37 thomsaudiohoek systemd[1]: plymouth-quit.service: Succeeded. Nov 22 09:11:37 thomsaudiohoek systemd[1]: Started Terminate Plymouth Boot Screen. Nov 22 09:11:37 thomsaudiohoek systemd[1]: plymouth-quit-wait.service: Succeeded. Nov 22 09:11:37 thomsaudiohoek ifplugd(eth0)[1044]: ifplugd 0.28 initializing. Nov 22 09:11:37 thomsaudiohoek systemd[1]: Started Hold until boot process finishes up. Nov 22 09:11:37 thomsaudiohoek ifplugd(eth0)[1044]: Using interface eth0/2C:CF:67:26:EE:88 with driver (version: 6.6.56-v8+) Nov 22 09:11:37 thomsaudiohoek ifplugd(eth0)[1044]: Using detection mode: SIOCETHTOOL Nov 22 09:11:37 thomsaudiohoek ifplugd(eth0)[1044]: Initialization complete, link beat not detected. Nov 22 09:11:37 thomsaudiohoek systemd[1]: Received SIGRTMIN+21 from PID 297 (n/a). Nov 22 09:11:37 thomsaudiohoek systemd[1]: Started Serial Getty on ttyAMA10. Nov 22 09:11:37 thomsaudiohoek systemd[1]: Reached target Login Prompts. Nov 22 09:11:37 thomsaudiohoek sudo[965]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 22 09:11:37 thomsaudiohoek sudo[965]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:37 thomsaudiohoek sudo[965]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Nov 22 09:11:37 thomsaudiohoek sudo[965]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:37 thomsaudiohoek kernel: Bluetooth: hci0: BCM: features 0x2f Nov 22 09:11:37 thomsaudiohoek kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Nov 22 09:11:37 thomsaudiohoek kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Nov 22 09:11:37 thomsaudiohoek kernel: Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac) Nov 22 09:11:37 thomsaudiohoek cpufrequtils[1028]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Nov 22 09:11:37 thomsaudiohoek systemd[1]: Started LSB: set CPUFreq kernel parameters. Nov 22 09:11:37 thomsaudiohoek bthelper[929]: Raspberry Pi BDADDR already set Nov 22 09:11:37 thomsaudiohoek systemd[1]: Started Raspberry Pi bluetooth helper. Nov 22 09:11:37 thomsaudiohoek systemd[1]: Starting Bluetooth service... Nov 22 09:11:37 thomsaudiohoek ifplugd[968]: Network Interface Plugging Daemon...start eth0...done. Nov 22 09:11:37 thomsaudiohoek systemd[1]: Started LSB: Brings up/down network automatically. Nov 22 09:11:37 thomsaudiohoek bluetoothd[1066]: Bluetooth daemon 5.50 Nov 22 09:11:37 thomsaudiohoek bluetoothd[1066]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Nov 22 09:11:37 thomsaudiohoek systemd[1]: Started Bluetooth service. Nov 22 09:11:37 thomsaudiohoek systemd[1]: Reached target Bluetooth. Nov 22 09:11:37 thomsaudiohoek bluetoothd[1066]: Starting SDP server Nov 22 09:11:37 thomsaudiohoek bluetoothd[1066]: Excluding (cli) sap Nov 22 09:11:37 thomsaudiohoek kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Nov 22 09:11:37 thomsaudiohoek kernel: Bluetooth: BNEP filters: protocol multicast Nov 22 09:11:37 thomsaudiohoek kernel: Bluetooth: BNEP socket layer initialized Nov 22 09:11:37 thomsaudiohoek kernel: Bluetooth: MGMT ver 1.22 Nov 22 09:11:37 thomsaudiohoek bluetoothd[1066]: Bluetooth management interface 1.22 initialized Nov 22 09:11:37 thomsaudiohoek dbus-daemon[763]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=1066 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Nov 22 09:11:37 thomsaudiohoek wireless.js[778]: Cleaning previous... Nov 22 09:11:37 thomsaudiohoek systemd[1]: Starting Hostname Service... Nov 22 09:11:37 thomsaudiohoek nmbd[983]: [2024/11/22 09:11:37.437125, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Nov 22 09:11:37 thomsaudiohoek nmbd[983]: started asyncdns process 1075 Nov 22 09:11:37 thomsaudiohoek nmbd[983]: [2024/11/22 09:11:37.437854, 0] ../lib/util/become_daemon.c:149(daemon_status) Nov 22 09:11:37 thomsaudiohoek nmbd[983]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Nov 22 09:11:37 thomsaudiohoek nmbd[983]: [2024/11/22 09:11:37.437908, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Nov 22 09:11:37 thomsaudiohoek nmbd[983]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Nov 22 09:11:37 thomsaudiohoek dbus-daemon[763]: [system] Successfully activated service 'org.freedesktop.hostname1' Nov 22 09:11:37 thomsaudiohoek sudo[1077]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 22 09:11:37 thomsaudiohoek systemd[1]: Started Hostname Service. Nov 22 09:11:37 thomsaudiohoek sudo[1077]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:37 thomsaudiohoek sudo[1077]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:37 thomsaudiohoek bluetoothd[1066]: Failed to set privacy: Rejected (0x0b) Nov 22 09:11:37 thomsaudiohoek sudo[1079]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 22 09:11:37 thomsaudiohoek sudo[1079]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:37 thomsaudiohoek mpd[1049]: Nov 22 09:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 22 09:11:37 thomsaudiohoek systemd[1]: Started Music Player Daemon. Nov 22 09:11:37 thomsaudiohoek mpd_monitor.sh[787]: MPD restarted due to no mpc output. Nov 22 09:11:38 thomsaudiohoek sudo[1079]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:38 thomsaudiohoek wireless.js[778]: Stopped aP Nov 22 09:11:38 thomsaudiohoek sudo[1089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 22 09:11:38 thomsaudiohoek sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:38 thomsaudiohoek sudo[1089]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:38 thomsaudiohoek kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 22 09:11:38 thomsaudiohoek sudo[1091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Nov 22 09:11:38 thomsaudiohoek sudo[1091]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:38 thomsaudiohoek sudo[1091]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:38 thomsaudiohoek sudo[1098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 22 09:11:38 thomsaudiohoek sudo[1098]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:38 thomsaudiohoek sudo[1098]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:38 thomsaudiohoek sudo[1100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Nov 22 09:11:38 thomsaudiohoek sudo[1100]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:38 thomsaudiohoek ntpd[1023]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Nov 22 09:11:39 thomsaudiohoek ntpd[1023]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Nov 22 09:11:40 thomsaudiohoek ntpd[1023]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Nov 22 09:11:40 thomsaudiohoek sudo[1100]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:40 thomsaudiohoek wireless.js[778]: SETTING APPROPRIATE REG DOMAIN: DE Nov 22 09:11:40 thomsaudiohoek sudo[1105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 22 09:11:40 thomsaudiohoek sudo[1105]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:40 thomsaudiohoek sudo[1105]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:40 thomsaudiohoek sudo[1107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Nov 22 09:11:40 thomsaudiohoek sudo[1107]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:40 thomsaudiohoek sudo[1107]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:40 thomsaudiohoek wireless.js[778]: SUCCESSFULLY SET NEW REGDOMAIN: DE Nov 22 09:11:40 thomsaudiohoek wireless.js[778]: Start wireless flow Nov 22 09:11:40 thomsaudiohoek wireless.js[778]: Stopped hotspot (if there).. Nov 22 09:11:40 thomsaudiohoek sudo[1112]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 22 09:11:40 thomsaudiohoek sudo[1112]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:40 thomsaudiohoek sudo[1112]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:40 thomsaudiohoek sudo[1114]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 22 09:11:40 thomsaudiohoek sudo[1114]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:41 thomsaudiohoek ntpd[1023]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Nov 22 09:11:41 thomsaudiohoek sudo[1114]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:41 thomsaudiohoek wireless.js[778]: DHCP IP Nov 22 09:11:41 thomsaudiohoek wireless.js[778]: Start ap Nov 22 09:11:41 thomsaudiohoek wpa_supplicant[1116]: Successfully initialized wpa_supplicant Nov 22 09:11:41 thomsaudiohoek sudo[1117]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Nov 22 09:11:41 thomsaudiohoek sudo[1117]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:41 thomsaudiohoek kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 22 09:11:41 thomsaudiohoek dhcpcd[1118]: dev: loaded udev Nov 22 09:11:41 thomsaudiohoek dhcpcd-run-hooks[1135]: wlan0: starting wpa_supplicant Nov 22 09:11:41 thomsaudiohoek dhcpcd-run-hooks[1140]: wlan0: failed to start wpa_supplicant Nov 22 09:11:41 thomsaudiohoek dhcpcd-run-hooks[1141]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Nov 22 09:11:41 thomsaudiohoek dhcpcd[1118]: wlan0: connected to Access Point `' Nov 22 09:11:41 thomsaudiohoek dhcpcd[1118]: no interfaces have a carrier Nov 22 09:11:41 thomsaudiohoek dhcpcd[1118]: forked to background, child pid 1150 Nov 22 09:11:41 thomsaudiohoek dhcpcd[1150]: eth0: waiting for carrier Nov 22 09:11:41 thomsaudiohoek dhcpcd[1150]: wlan0: waiting for carrier Nov 22 09:11:41 thomsaudiohoek sudo[1117]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:41 thomsaudiohoek volumio-remote-updater[775]: [2024-11-22 09:11:41] [info] asio async_connect error: system:111 (Connection refused) Nov 22 09:11:41 thomsaudiohoek volumio-remote-updater[775]: [2024-11-22 09:11:41] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 22 09:11:41 thomsaudiohoek volumio-remote-updater[775]: [2024-11-22 09:11:41] [error] handle_connect error: Underlying Transport Error Nov 22 09:11:41 thomsaudiohoek volumio-time-update[779]: volumio-time-update-util: Fetching time from Volumio... Nov 22 09:11:41 thomsaudiohoek volumio-time-update[779]: volumio-time-update-util: Date not found in response Nov 22 09:11:41 thomsaudiohoek volumio-time-update[779]: volumio-time-update-util: Retrying in 5 seconds... Nov 22 09:11:41 thomsaudiohoek systemd[1]: systemd-rfkill.service: Succeeded. Nov 22 09:11:42 thomsaudiohoek wireless.js[778]: trying... Nov 22 09:11:42 thomsaudiohoek sudo[1157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 09:11:42 thomsaudiohoek sudo[1157]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:42 thomsaudiohoek sudo[1157]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:42 thomsaudiohoek bthelper[929]: Changing power off succeeded Nov 22 09:11:42 thomsaudiohoek bthelper[929]: [CHG] Controller 2C:CF:67:26:EE:8A Class: 0x0000041c Nov 22 09:11:42 thomsaudiohoek bthelper[929]: Changing power on succeeded Nov 22 09:11:43 thomsaudiohoek wireless.js[778]: trying... Nov 22 09:11:43 thomsaudiohoek sudo[1161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 09:11:43 thomsaudiohoek sudo[1161]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:43 thomsaudiohoek sudo[1161]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:44 thomsaudiohoek wireless.js[778]: trying... Nov 22 09:11:44 thomsaudiohoek sudo[1164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 09:11:44 thomsaudiohoek sudo[1164]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:44 thomsaudiohoek sudo[1164]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:45 thomsaudiohoek wireless.js[778]: trying... Nov 22 09:11:45 thomsaudiohoek sudo[1167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 09:11:45 thomsaudiohoek sudo[1167]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:45 thomsaudiohoek sudo[1167]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:46 thomsaudiohoek wireless.js[778]: trying... Nov 22 09:11:46 thomsaudiohoek sudo[1170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 09:11:46 thomsaudiohoek sudo[1170]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:46 thomsaudiohoek sudo[1170]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:46 thomsaudiohoek volumio-time-update[779]: volumio-time-update-util: Fetching time from Volumio... Nov 22 09:11:46 thomsaudiohoek volumio-time-update[779]: volumio-time-update-util: Date not found in response Nov 22 09:11:46 thomsaudiohoek volumio-time-update[779]: volumio-time-update-util: Retrying in 5 seconds... Nov 22 09:11:47 thomsaudiohoek wpa_supplicant[1139]: wlan0: Trying to associate with SSID 'FiberFirst' Nov 22 09:11:47 thomsaudiohoek dhcpcd[1150]: wlan0: carrier acquired Nov 22 09:11:47 thomsaudiohoek dhcpcd[1150]: wlan0: connected to Access Point `FiberFirst' Nov 22 09:11:47 thomsaudiohoek wpa_supplicant[1139]: wlan0: Associated with 78:8c:b5:d6:b8:80 Nov 22 09:11:47 thomsaudiohoek wpa_supplicant[1139]: wlan0: CTRL-EVENT-CONNECTED - Connection to 78:8c:b5:d6:b8:80 completed [id=0 id_str=] Nov 22 09:11:47 thomsaudiohoek wpa_supplicant[1139]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 22 09:11:47 thomsaudiohoek wpa_supplicant[1139]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Nov 22 09:11:47 thomsaudiohoek dhcpcd[1150]: DUID 00:01:00:01:2e:41:03:42:2c:cf:67:26:ee:88 Nov 22 09:11:47 thomsaudiohoek dhcpcd[1150]: wlan0: IAID 67:26:ee:89 Nov 22 09:11:47 thomsaudiohoek dhcpcd[1150]: wlan0: adding address fe80::d015:acab:a6ba:6f20 Nov 22 09:11:47 thomsaudiohoek dhcpcd[1150]: ipv6_addaddr1: Permission denied Nov 22 09:11:47 thomsaudiohoek dhcpcd[1150]: wlan0: carrier lost Nov 22 09:11:47 thomsaudiohoek wireless.js[778]: trying... Nov 22 09:11:47 thomsaudiohoek dhcpcd[1150]: wlan0: carrier acquired Nov 22 09:11:47 thomsaudiohoek sudo[1183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 09:11:47 thomsaudiohoek sudo[1183]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:47 thomsaudiohoek sudo[1183]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:47 thomsaudiohoek dhcpcd[1150]: wlan0: IAID 67:26:ee:89 Nov 22 09:11:47 thomsaudiohoek dhcpcd[1150]: wlan0: soliciting an IPv6 router Nov 22 09:11:48 thomsaudiohoek dhcpcd[1150]: wlan0: soliciting a DHCP lease Nov 22 09:11:48 thomsaudiohoek dhcpcd[1150]: wlan0: offered 192.168.68.65 from 192.168.68.1 Nov 22 09:11:48 thomsaudiohoek wireless.js[778]: trying... Nov 22 09:11:48 thomsaudiohoek sudo[1195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 09:11:48 thomsaudiohoek sudo[1195]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:48 thomsaudiohoek sudo[1195]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:48 thomsaudiohoek dhcpcd[1150]: wlan0: probing address 192.168.68.65/22 Nov 22 09:11:49 thomsaudiohoek volumio-remote-updater[775]: [2024-11-22 09:11:49] [info] asio async_connect error: system:111 (Connection refused) Nov 22 09:11:49 thomsaudiohoek volumio-remote-updater[775]: [2024-11-22 09:11:49] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 22 09:11:49 thomsaudiohoek volumio-remote-updater[775]: [2024-11-22 09:11:49] [error] handle_connect error: Underlying Transport Error Nov 22 09:11:49 thomsaudiohoek wireless.js[778]: trying... Nov 22 09:11:49 thomsaudiohoek sudo[1198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 09:11:49 thomsaudiohoek sudo[1198]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:49 thomsaudiohoek sudo[1198]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:50 thomsaudiohoek wireless.js[778]: trying... Nov 22 09:11:50 thomsaudiohoek sudo[1201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 09:11:50 thomsaudiohoek sudo[1201]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:50 thomsaudiohoek sudo[1201]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:51 thomsaudiohoek wireless.js[778]: trying... Nov 22 09:11:51 thomsaudiohoek sudo[1204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 09:11:51 thomsaudiohoek sudo[1204]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:51 thomsaudiohoek sudo[1204]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:51 thomsaudiohoek volumio-time-update[779]: volumio-time-update-util: Fetching time from Volumio... Nov 22 09:11:51 thomsaudiohoek volumio-time-update[779]: volumio-time-update-util: Date not found in response Nov 22 09:11:51 thomsaudiohoek volumio-time-update[779]: volumio-time-update-util: Retrying in 5 seconds... Nov 22 09:11:52 thomsaudiohoek wireless.js[778]: trying... Nov 22 09:11:52 thomsaudiohoek sudo[1212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 09:11:52 thomsaudiohoek sudo[1212]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:52 thomsaudiohoek sudo[1212]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:53 thomsaudiohoek wireless.js[778]: trying... Nov 22 09:11:53 thomsaudiohoek dhcpcd[1150]: wlan0: leased 192.168.68.65 for 7200 seconds Nov 22 09:11:53 thomsaudiohoek avahi-daemon[770]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.68.65. Nov 22 09:11:53 thomsaudiohoek avahi-daemon[770]: New relevant interface wlan0.IPv4 for mDNS. Nov 22 09:11:53 thomsaudiohoek dhcpcd[1150]: wlan0: adding route to 192.168.68.0/22 Nov 22 09:11:53 thomsaudiohoek avahi-daemon[770]: Registering new address record for 192.168.68.65 on wlan0.IPv4. Nov 22 09:11:53 thomsaudiohoek dhcpcd[1150]: wlan0: adding default route via 192.168.68.1 Nov 22 09:11:53 thomsaudiohoek sudo[1215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 22 09:11:53 thomsaudiohoek sudo[1215]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:53 thomsaudiohoek sudo[1215]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:53 thomsaudiohoek wireless.js[778]: Connected to: ----FiberFirst Nov 22 09:11:53 thomsaudiohoek wireless.js[778]: ---- Nov 22 09:11:53 thomsaudiohoek sudo[1230]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 22 09:11:53 thomsaudiohoek sudo[1230]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:53 thomsaudiohoek sudo[1230]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:53 thomsaudiohoek wireless.js[778]: ... joined AP, wlan0 IPv4 is 192.168.68.65, ipV6 is undefined Nov 22 09:11:53 thomsaudiohoek wireless.js[778]: It's done! AP Nov 22 09:11:53 thomsaudiohoek kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 22 09:11:53 thomsaudiohoek systemd[1]: Started Wireless Services. Nov 22 09:11:53 thomsaudiohoek systemd[1]: Started Volumio Backend Module. Nov 22 09:11:53 thomsaudiohoek systemd[1]: Started Volumio Cpu Tweaker. Nov 22 09:11:53 thomsaudiohoek volumio-cpu-tweak[1243]: Setting RT Priority for mpd Nov 22 09:11:53 thomsaudiohoek volumio-cpu-tweak[1243]: Setting MPD Affinity Nov 22 09:11:53 thomsaudiohoek volumio-cpu-tweak[1243]: pid 1049's current affinity mask: f Nov 22 09:11:53 thomsaudiohoek volumio-cpu-tweak[1243]: pid 1049's new affinity mask: 3 Nov 22 09:11:53 thomsaudiohoek volumio-cpu-tweak[1243]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Nov 22 09:11:53 thomsaudiohoek volumio-cpu-tweak[1243]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Nov 22 09:11:53 thomsaudiohoek systemd[1]: volumio_cpu_tweak.service: Succeeded. Nov 22 09:11:53 thomsaudiohoek volumio[1242]: info: ------------------------------------------- Nov 22 09:11:53 thomsaudiohoek volumio[1242]: info: ----- Volumio3 ---- Nov 22 09:11:53 thomsaudiohoek volumio[1242]: info: ------------------------------------------- Nov 22 09:11:53 thomsaudiohoek volumio[1242]: info: ----- System startup ---- Nov 22 09:11:53 thomsaudiohoek volumio[1242]: info: ------------------------------------------- Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: MYVOLUMIO Environment detected Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Plugin folders cleanup Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Scanning into folder /volumio/app/plugins/ Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Scanning category audio_interface Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Scanning category miscellanea Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Scanning category music_service Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Scanning category plugins.json Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Scanning category system_controller Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Scanning category user_interface Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Scanning into folder /data/plugins/ Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Scanning category audio_interface Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Scanning category music_service Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Scanning category system_controller Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Scanning category system_hardware Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Scanning category user_interface Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Plugin folders cleanup completed Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: ------------------------------------------- Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: ----- Core plugins startup ---- Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: ------------------------------------------- Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Loading plugins from folder /volumio/app/plugins/ Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Adding plugin upnp to MyMusic Plugins Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Loading plugins from folder /data/plugins/ Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Loading plugin "system"... Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Loading plugin "appearance"... Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Loading plugin "network"... Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Refreshing Cached IP Addresses Nov 22 09:11:54 thomsaudiohoek sudo[1273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 22 09:11:54 thomsaudiohoek sudo[1273]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:54 thomsaudiohoek sudo[1273]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:54 thomsaudiohoek sudo[1275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 22 09:11:54 thomsaudiohoek sudo[1275]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:54 thomsaudiohoek sudo[1275]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Loading plugin "services"... Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Loading plugin "alsa_controller"... Nov 22 09:11:54 thomsaudiohoek sudo[1284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 22 09:11:54 thomsaudiohoek sudo[1284]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Loading plugin "wizard"... Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Loading plugin "networkfs"... Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Starting Udev Watcher for removable devices Nov 22 09:11:54 thomsaudiohoek sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=thom van zee,password=Ikwilopdenas!1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.68.150/music /mnt/NAS/Gangkast Nov 22 09:11:54 thomsaudiohoek sudo[1301]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Ignoring mount for partition: boot Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Ignoring mount for partition: volumio Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Ignoring mount for partition: volumio_data Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Loading plugin "volumio_command_line_client"... Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Loading plugin "upnp"... Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: [1732263114530] Starting Upmpd Daemon Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Loading plugin "my_music"... Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Loading plugin "mpd"... Nov 22 09:11:54 thomsaudiohoek kernel: Key type cifs.spnego registered Nov 22 09:11:54 thomsaudiohoek kernel: Key type cifs.idmap registered Nov 22 09:11:54 thomsaudiohoek 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. Nov 22 09:11:54 thomsaudiohoek kernel: CIFS: Attempting to mount //192.168.68.150/music Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Plugin upnp_browser is not enabled Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Loading plugin "alarm-clock"... Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Loading plugin "airplay_emulation"... Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Starting Shairport Sync Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Loading plugin "last_100"... Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Loading plugin "webradio"... Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Loading plugin "i2s_dacs"... Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: I2S DAC not set, start Auto-detection Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Loading plugin "volumiodiscovery"... Nov 22 09:11:54 thomsaudiohoek volumio[1242]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 22 09:11:54 thomsaudiohoek volumio[1242]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 22 09:11:54 thomsaudiohoek volumio[1242]: *** WARNING *** For more information see Nov 22 09:11:54 thomsaudiohoek volumio[1242]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 22 09:11:54 thomsaudiohoek node[1242]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 22 09:11:54 thomsaudiohoek volumio[1242]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 22 09:11:54 thomsaudiohoek volumio[1242]: *** WARNING *** For more information see Nov 22 09:11:54 thomsaudiohoek node[1242]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 22 09:11:54 thomsaudiohoek node[1242]: *** WARNING *** For more information see Nov 22 09:11:54 thomsaudiohoek node[1242]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 22 09:11:54 thomsaudiohoek node[1242]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 22 09:11:54 thomsaudiohoek node[1242]: *** WARNING *** For more information see Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Discovery: Started advertising with name: ThomsAudioHoek Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Plugin spop is not enabled Nov 22 09:11:54 thomsaudiohoek volumio[1242]: info: Loading plugin "youtube2"... Nov 22 09:11:54 thomsaudiohoek sudo[1301]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:55 thomsaudiohoek volumio[1242]: info: Loading plugin "ytcr"... Nov 22 09:11:55 thomsaudiohoek ntpd[1023]: Listen normally on 3 wlan0 192.168.68.65:123 Nov 22 09:11:55 thomsaudiohoek ntpd[1023]: new interface(s) found: waking up resolver Nov 22 09:11:55 thomsaudiohoek volumio[1242]: info: Loading plugin "ytmusic"... Nov 22 09:11:55 thomsaudiohoek volumio[1242]: info: Loading plugin "autostart"... Nov 22 09:11:55 thomsaudiohoek volumio[1242]: info: Applying required configuration parameters for plugin autostart Nov 22 09:11:55 thomsaudiohoek volumio[1242]: info: AutoStart - onVolumioStart - read config.json Nov 22 09:11:55 thomsaudiohoek volumio[1242]: info: Loading plugin "outputs"... Nov 22 09:11:55 thomsaudiohoek volumio[1242]: info: Loading plugin "albumart"... Nov 22 09:11:55 thomsaudiohoek volumio[1242]: info: Plugin example_plugin is not enabled Nov 22 09:11:55 thomsaudiohoek volumio[1242]: info: Loading plugin "inputs"... Nov 22 09:11:55 thomsaudiohoek volumio[1242]: info: Loading plugin "updater_comm"... Nov 22 09:11:55 thomsaudiohoek volumio[1242]: info: Plugin mpdemulation is not enabled Nov 22 09:11:55 thomsaudiohoek volumio[1242]: info: Loading plugin "rest_api"... Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Loading plugin "websocket"... Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Starting Socket.io Server version 2.3.0 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Loading plugin "fusiondsp"... Nov 22 09:11:56 thomsaudiohoek volumio[1242]: Forking 3 albumart workers Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Applying required configuration parameters for plugin fusiondsp Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Loading plugin "motherearthradio"... Nov 22 09:11:56 thomsaudiohoek volumio[1242]: Starting albumart workers Nov 22 09:11:56 thomsaudiohoek volumio[1242]: Starting albumart workers Nov 22 09:11:56 thomsaudiohoek volumio[1242]: Starting albumart workers Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Applying required configuration parameters for plugin motherearthradio Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: [1732263116334] [MotherEarth] API delay: 5 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Loading plugin "radio_paradise"... Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Applying required configuration parameters for plugin radio_paradise Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: [1732263116425] [RadioParadise] API delay: 5 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Plugin music_services_shield is not enabled Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Loading plugin "Systeminfo"... Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Loading plugin "touch_display"... Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Applying required configuration parameters for plugin touch_display Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Loading i18n strings for locale nl Nov 22 09:11:56 thomsaudiohoek volumio[1242]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 22 09:11:56 thomsaudiohoek volumio[1242]: Updating browse sources language Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::initPlayerControls Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 09:11:56 thomsaudiohoek volumio[1242]: Express server listening on port 3000 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: [Metrics] WebUI: 3s 41.09ms Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreStateMachine::resetVolumioState Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreStateMachine::getcurrentVolume Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioRetrievevolume Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Volumio Network Manager: Network status updated: 2 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: verbose: New Socket.io Connection to 192.168.68.65 from 192.168.68.75 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: verbose: New Socket.io Connection to 192.168.68.65 from 192.168.68.75 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Reloading queue from file Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreStateMachine::setRepeat null single undefined Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreStateMachine::pushState Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioPushState Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreStateMachine::setRandom null Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreStateMachine::pushState Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioPushState Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Setting Device type: Raspberry PI Nov 22 09:11:56 thomsaudiohoek volumio-time-update[779]: volumio-time-update-util: Fetching time from Volumio... Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: VolumeController:: Volume=58 Mute =false Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreStateMachine::pushState Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioPushState Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreStateMachine::updateTrackBlock Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrackBlock Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioRetrievevolume Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d04170 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 22 09:11:56 thomsaudiohoek volumio[1242]: verbose: New Socket.io Connection to 192.168.68.65 from 192.168.68.75 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Completed loading Core Plugins Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Preparing to generate the ALSA configuration file Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Reading ALSA contributions from plugins. Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: VolumeController:: Volume=58 Mute =false Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreStateMachine::pushState Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioPushState Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Asound.conf file unchanged, so no further update is needed Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Output device has changed, restarting MPD Nov 22 09:11:56 thomsaudiohoek sudo[1400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 22 09:11:56 thomsaudiohoek sudo[1400]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Output device has changed, restarting Shairport Sync Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:11:56 thomsaudiohoek sudo[1400]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:56 thomsaudiohoek sudo[1403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 22 09:11:56 thomsaudiohoek sudo[1403]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:56 thomsaudiohoek systemd[1]: Stopping Music Player Daemon... Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: ___________ START PLUGINS ___________ Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: ControllerMpd::onStart: Initializing MPD Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Creating MPD Configuration file Nov 22 09:11:56 thomsaudiohoek sudo[1409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:11:56 thomsaudiohoek sudo[1409]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:56 thomsaudiohoek sudo[1409]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:56 thomsaudiohoek sudo[1411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 22 09:11:56 thomsaudiohoek sudo[1411]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:56 thomsaudiohoek systemd[1]: mpd.service: Succeeded. Nov 22 09:11:56 thomsaudiohoek systemd[1]: Stopped Music Player Daemon. Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:11:56 thomsaudiohoek systemd[1]: Starting Music Player Daemon... Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: [1732263116883] CoreMusicLibrary::Adding element Last_100 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: [1732263116884] CoreMusicLibrary::Adding element Webradio Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Initializing BBC Radios Nov 22 09:11:56 thomsaudiohoek sudo[1417]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 22 09:11:56 thomsaudiohoek sudo[1417]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:56 thomsaudiohoek sudo[1417]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: [1732263116914] CoreMusicLibrary::Adding element YouTube2 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 09:11:56 thomsaudiohoek volumio[1242]: Cannot find translation for source YouTube2 Nov 22 09:11:56 thomsaudiohoek volumio-time-update[779]: volumio-time-update-util: Setting system time to: 2024-11-22 09:11:56 Nov 22 09:11:56 thomsaudiohoek sudo[1437]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2024-11-22 09:11:56 Nov 22 09:11:56 thomsaudiohoek sudo[1437]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: [1732263116929] CoreMusicLibrary::Adding element YouTube Music Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 09:11:56 thomsaudiohoek volumio[1242]: Cannot find translation for source YouTube2 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: Cannot find translation for source YouTube Music Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: AutoStart - onStart Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Loading i18n strings for locale nl Nov 22 09:11:56 thomsaudiohoek dbus-daemon[763]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.12' (uid=0 pid=1438 comm="timedatectl set-time 2024-11-22 09:11:56 ") Nov 22 09:11:56 thomsaudiohoek volumio[1242]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: FusionDsp - mixtype--------------------- Hardware Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: [1732263116938] CoreMusicLibrary::Adding element Mother Earth Radio Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 09:11:56 thomsaudiohoek volumio[1242]: Cannot find translation for source YouTube2 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: Cannot find translation for source YouTube Music Nov 22 09:11:56 thomsaudiohoek volumio[1242]: Cannot find translation for source Mother Earth Radio Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: [1732263116941] CoreMusicLibrary::Adding element Radio Paradise Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 09:11:56 thomsaudiohoek volumio[1242]: Cannot find translation for source YouTube2 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: Cannot find translation for source YouTube Music Nov 22 09:11:56 thomsaudiohoek volumio[1242]: Cannot find translation for source Mother Earth Radio Nov 22 09:11:56 thomsaudiohoek volumio[1242]: Cannot find translation for source Radio Paradise Nov 22 09:11:56 thomsaudiohoek systemd[1]: Starting Time & Date Service... Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Loading i18n strings for locale nl Nov 22 09:11:56 thomsaudiohoek volumio[1242]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Volumio Calling Home Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Preparing to generate the ALSA configuration file Nov 22 09:11:56 thomsaudiohoek sudo[1444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Nov 22 09:11:56 thomsaudiohoek sudo[1444]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:56 thomsaudiohoek sudo[1447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Nov 22 09:11:56 thomsaudiohoek sudo[1447]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:57 thomsaudiohoek sudo[1449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 22 09:11:57 thomsaudiohoek sudo[1449]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:57 thomsaudiohoek sudo[1444]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:57 thomsaudiohoek systemd[1]: Reloading. Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: Discovery: adding 9213bafe-8343-4fbb-9113-ef2e432d28e4 Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: Discovery: Found device ThomsAudioHoek Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioGetState Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: Reading ALSA contributions from plugins. Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: touch_display: Backlight interface detected. Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: touch_display: systemctl stop getty@tty1.service succeeded. Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: MPD Permissions set Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: MPD Permissions set Nov 22 09:11:57 thomsaudiohoek volumio[1242]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:57 thomsaudiohoek dbus-daemon[763]: [system] Successfully activated service 'org.freedesktop.timedate1' Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:57 thomsaudiohoek 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. Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: Volumio called home Nov 22 09:11:57 thomsaudiohoek sudo[1474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/4-0045/brightness Nov 22 09:11:57 thomsaudiohoek sudo[1474]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:57 thomsaudiohoek sudo[1474]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: touch_display: Raspberry Pi Foundation touch screen detected. Nov 22 09:11:57 thomsaudiohoek sudo[1477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Nov 22 09:11:57 thomsaudiohoek sudo[1477]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioGetState Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:11:57 thomsaudiohoek sudo[1477]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: Starting Shairport Sync Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: Starting Shairport Sync Nov 22 09:11:57 thomsaudiohoek sudo[1480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 22 09:11:57 thomsaudiohoek sudo[1480]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: Starting Shairport Sync Nov 22 09:11:57 thomsaudiohoek mpd[1432]: Nov 22 09:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 22 09:11:57 thomsaudiohoek sudo[1483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 22 09:11:57 thomsaudiohoek sudo[1483]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:57 thomsaudiohoek sudo[1485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 22 09:11:57 thomsaudiohoek sudo[1485]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: touch_display: File permissions for backlight brightness control set. Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: Asound.conf file unchanged, so no further update is needed Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: Output device has changed, restarting MPD Nov 22 09:11:57 thomsaudiohoek systemd[1]: serial-getty@ttyAMA10.service: Current command vanished from the unit file, execution of the command list won't be resumed. Nov 22 09:11:57 thomsaudiohoek sudo[1489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 22 09:11:57 thomsaudiohoek sudo[1489]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:57 thomsaudiohoek sudo[1489]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: Output device has changed, restarting Shairport Sync Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:11:57 thomsaudiohoek systemd[1]: ifup@eth0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Nov 22 09:11:57 thomsaudiohoek sudo[1492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 22 09:11:57 thomsaudiohoek sudo[1492]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: MPD Permissions set Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: Starting Shairport Sync Nov 22 09:11:57 thomsaudiohoek sudo[1502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 22 09:11:57 thomsaudiohoek sudo[1502]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:57 thomsaudiohoek systemd[1]: Started Music Player Daemon. Nov 22 09:11:57 thomsaudiohoek sudo[1449]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:57 thomsaudiohoek sudo[1411]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:57 thomsaudiohoek systemd[1]: Reloading. Nov 22 09:11:57 thomsaudiohoek sudo[1403]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: touch_display: systemctl daemon-reload succeeded. Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: MPD running with PID1432 Nov 22 09:11:57 thomsaudiohoek volumio[1242]: ,establishing connection Nov 22 09:11:57 thomsaudiohoek sudo[1509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Nov 22 09:11:57 thomsaudiohoek sudo[1509]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:57 thomsaudiohoek volumio[1242]: error: MPD error: The expression evaluated to a falsy value: Nov 22 09:11:57 thomsaudiohoek volumio[1242]: assert.ok(self.idling) Nov 22 09:11:57 thomsaudiohoek volumio[1242]: error: The expression evaluated to a falsy value: Nov 22 09:11:57 thomsaudiohoek volumio[1242]: assert.ok(self.idling) Nov 22 09:11:57 thomsaudiohoek volumio[1242]: error: MPD error: The expression evaluated to a falsy value: Nov 22 09:11:57 thomsaudiohoek volumio[1242]: assert.ok(self.idling) Nov 22 09:11:57 thomsaudiohoek volumio[1242]: error: The expression evaluated to a falsy value: Nov 22 09:11:57 thomsaudiohoek volumio[1242]: assert.ok(self.idling) Nov 22 09:11:57 thomsaudiohoek volumio[1242]: error: updateQueue error: null Nov 22 09:11:57 thomsaudiohoek 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. Nov 22 09:11:57 thomsaudiohoek nmbd[983]: [2024/11/22 09:11:57.439568, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 22 09:11:57 thomsaudiohoek nmbd[983]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Nov 22 09:11:57 thomsaudiohoek systemd[1]: Started Samba NMB Daemon. Nov 22 09:11:57 thomsaudiohoek sudo[1447]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: touch_display: systemctl disable getty@tty1.service succeeded. Nov 22 09:11:56 thomsaudiohoek systemd-timedated[1439]: Changed local time to Fri Nov 22 09:11:56 2024 Nov 22 09:11:56 thomsaudiohoek sudo[1437]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:56 thomsaudiohoek volumio-time-update[779]: volumio-time-update-util: System time updated successfully. Nov 22 09:11:56 thomsaudiohoek systemd[1]: Started Volumio Time Update Utility. Nov 22 09:11:56 thomsaudiohoek sudo[1509]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:56 thomsaudiohoek systemd[1]: Stopping Music Player Daemon... Nov 22 09:11:56 thomsaudiohoek systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 22 09:11:56 thomsaudiohoek volumio[1242]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Nov 22 09:11:56 thomsaudiohoek volumio[1242]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Nov 22 09:11:56 thomsaudiohoek systemd[1]: Starting Samba Winbind Daemon... Nov 22 09:11:56 thomsaudiohoek systemd[1]: shairport-sync.service: Succeeded. Nov 22 09:11:56 thomsaudiohoek systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 22 09:11:56 thomsaudiohoek systemd[1]: mpd.service: Succeeded. Nov 22 09:11:56 thomsaudiohoek systemd[1]: Stopped Music Player Daemon. Nov 22 09:11:56 thomsaudiohoek systemd[1]: Starting Music Player Daemon... Nov 22 09:11:56 thomsaudiohoek systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 22 09:11:56 thomsaudiohoek sudo[1480]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:56 thomsaudiohoek sudo[1483]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:56 thomsaudiohoek sudo[1485]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:56 thomsaudiohoek sudo[1502]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Shairport-Sync Started Nov 22 09:11:56 thomsaudiohoek volumio[1242]: Error adding Membership: Error: addMembership EINVAL Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Shairport-Sync Started Nov 22 09:11:56 thomsaudiohoek systemd[1]: Started Time & Date Service. Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Shairport-Sync Started Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Shairport-Sync Started Nov 22 09:11:56 thomsaudiohoek sudo[1530]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 22 09:11:56 thomsaudiohoek sudo[1530]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:56 thomsaudiohoek sudo[1530]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:56 thomsaudiohoek winbindd[1526]: [2024/11/22 09:11:56.132487, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Nov 22 09:11:56 thomsaudiohoek winbindd[1526]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Nov 22 09:11:56 thomsaudiohoek systemd[1]: Started Samba Winbind Daemon. Nov 22 09:11:56 thomsaudiohoek winbindd[1526]: [2024/11/22 09:11:56.135466, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 22 09:11:56 thomsaudiohoek systemd[1]: Starting Samba SMB Daemon... Nov 22 09:11:56 thomsaudiohoek winbindd[1526]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Nov 22 09:11:56 thomsaudiohoek smbd[1540]: [2024/11/22 09:11:56.259364, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 22 09:11:56 thomsaudiohoek systemd[1]: Started Samba SMB Daemon. Nov 22 09:11:56 thomsaudiohoek smbd[1540]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Nov 22 09:11:56 thomsaudiohoek systemd[1]: Reached target Multi-User System. Nov 22 09:11:56 thomsaudiohoek systemd[1]: Reached target Graphical Interface. Nov 22 09:11:56 thomsaudiohoek systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 22 09:11:56 thomsaudiohoek systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Nov 22 09:11:56 thomsaudiohoek systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 22 09:11:56 thomsaudiohoek mpd[1536]: Nov 22 09:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 22 09:11:56 thomsaudiohoek systemd[1]: Started Music Player Daemon. Nov 22 09:11:56 thomsaudiohoek systemd[1]: Startup finished in 10.676s (kernel) + 23.147s (userspace) = 33.823s. Nov 22 09:11:56 thomsaudiohoek sudo[1492]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:56 thomsaudiohoek volumio[1242]: error: updateQueue error: null Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: [yt-cast-receiver] DIAL server listening on port 8098 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioRetrievevolume Nov 22 09:11:56 thomsaudiohoek sudo[1284]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: VolumeController:: Volume=58 Mute =false Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioGetState Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreStateMachine::pushState Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioPushState Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: Completed starting Core Plugins Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: ------------------------------------------- Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: ----- MyVolumio plugins startup ---- Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: ------------------------------------------- Nov 22 09:11:56 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: camilladsp spawned new process with pid 1562, instance 1, run: true Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: camilladsp service started and running in background, instance 1 Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: FusionDsp loaded Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 09:11:57 thomsaudiohoek sudo[1572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Nov 22 09:11:57 thomsaudiohoek sudo[1572]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: FusionDsp - Reporting Fusion DSP Enabled Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: Adding Signal Path Element [object Object] Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: Adding fusiondspeq DSP Signal Path Element Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: FusionDsp - ---- installed callbackRead Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 22 09:11:57 thomsaudiohoek volumio[1242]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Nov 22 09:11:57 thomsaudiohoek volumio[1242]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 22 09:11:57 thomsaudiohoek systemd[1]: Started FusionDsp Daemon. Nov 22 09:11:57 thomsaudiohoek sudo[1572]: pam_unix(sudo:session): session closed for user root Nov 22 09:11:58 thomsaudiohoek volumio[1242]: verbose: New Socket.io Connection to 192.168.68.65 from 192.168.68.75 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Nov 22 09:11:58 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioGetVisibleSources Nov 22 09:11:58 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 09:11:58 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioGetState Nov 22 09:11:58 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:11:58 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioGetQueue Nov 22 09:11:58 thomsaudiohoek volumio[1242]: info: CoreStateMachine::getQueue Nov 22 09:11:58 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getQueue Nov 22 09:11:58 thomsaudiohoek volumio[1242]: info: Listing playlists Nov 22 09:11:59 thomsaudiohoek volumio-remote-updater[775]: [2024-11-22 09:11:59] [connect] Successful connection Nov 22 09:11:59 thomsaudiohoek volumio-remote-updater[775]: [2024-11-22 09:11:59] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732263119 101 Nov 22 09:11:59 thomsaudiohoek volumio[1242]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 8 Nov 22 09:12:00 thomsaudiohoek volumio[1242]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 22 09:12:01 thomsaudiohoek volumio[1242]: Cannot compose Albumart path Nov 22 09:12:02 thomsaudiohoek volumio[1242]: Cannot compose Albumart path Nov 22 09:12:02 thomsaudiohoek volumio[1242]: Cannot compose Albumart path Nov 22 09:12:02 thomsaudiohoek volumio[1242]: Cannot compose Albumart path Nov 22 09:12:02 thomsaudiohoek volumio[1242]: Cannot compose Albumart path Nov 22 09:12:02 thomsaudiohoek volumio[1242]: Cannot compose Albumart path Nov 22 09:12:02 thomsaudiohoek volumio[1242]: Cannot compose Albumart path Nov 22 09:12:03 thomsaudiohoek sudo[1591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 22 09:12:03 thomsaudiohoek sudo[1591]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:03 thomsaudiohoek sudo[1591]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:03 thomsaudiohoek sudo[1594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 22 09:12:03 thomsaudiohoek sudo[1594]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:03 thomsaudiohoek sudo[1594]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:03 thomsaudiohoek sudo[1597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 22 09:12:03 thomsaudiohoek sudo[1597]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:03 thomsaudiohoek systemd[1]: Started UPnP Renderer front-end to MPD. Nov 22 09:12:03 thomsaudiohoek sudo[1597]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:03 thomsaudiohoek volumio[1242]: info: Upmpdcli Daemon Started Nov 22 09:12:03 thomsaudiohoek volumio[1599]: Generating RSA private key, 4096 bit long modulus (2 primes) Nov 22 09:12:04 thomsaudiohoek systemd[1]: systemd-fsckd.service: Succeeded. Nov 22 09:12:05 thomsaudiohoek sh[724]: timed out Nov 22 09:12:05 thomsaudiohoek dhcpcd[765]: timed out Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 22 09:12:05 thomsaudiohoek dhcpcd[765]: dhcpcd exited Nov 22 09:12:05 thomsaudiohoek sh[724]: dhcpcd exited Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 22 09:12:05 thomsaudiohoek sh[724]: ifup: failed to bring up eth0 Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: Adding plugin bluetooth to MyMusic Plugins Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: Adding plugin multiroom to MyMusic Plugins Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: Adding plugin metavolumio to MyMusic Plugins Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: Adding plugin cd_controller to MyMusic Plugins Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 22 09:12:05 thomsaudiohoek systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Nov 22 09:12:05 thomsaudiohoek systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: Starting MyVolumio Remote Streaming Endpoints Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: MyVolumio login type: Token Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: Starting Streaming Service Transparent Proxy Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: Streaming services startup Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: Starting Streaming Daemon Nov 22 09:12:05 thomsaudiohoek sudo[1624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 22 09:12:05 thomsaudiohoek sudo[1624]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:05 thomsaudiohoek sudo[1624]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:05 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 22 09:12:05 thomsaudiohoek volumio[1242]: error: Cannot start Volumio Streaming Daemon Nov 22 09:12:05 thomsaudiohoek volumio[1242]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 22 09:12:05 thomsaudiohoek volumio[1242]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 22 09:12:05 thomsaudiohoek volumio[1242]: STREAMING PROXY: Starting server on port 3245 Nov 22 09:12:05 thomsaudiohoek volumio[1242]: Node JS runtime: 14 Nov 22 09:12:05 thomsaudiohoek systemd[1]: systemd-hostnamed.service: Succeeded. Nov 22 09:12:06 thomsaudiohoek volumio[1242]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 22 09:12:06 thomsaudiohoek volumio[1242]: info: MyVolumio token set successfully Nov 22 09:12:06 thomsaudiohoek volumio[1242]: info: MYVOLUMIO: Adding device Nov 22 09:12:06 thomsaudiohoek volumio[1242]: info: MYVOLUMIO: Evaluating Server Nov 22 09:12:06 thomsaudiohoek volumio[1242]: info: MyVolumio Plan changed: premium Nov 22 09:12:06 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Nov 22 09:12:06 thomsaudiohoek volumio[1242]: info: Removing browser output: myVolumio user plan is not superstar Nov 22 09:12:06 thomsaudiohoek volumio[1242]: info: Removing audio output: Nov 22 09:12:06 thomsaudiohoek volumio[1242]: info: MYVOLUMIO: Adding device Nov 22 09:12:06 thomsaudiohoek volumio[1242]: info: MYVOLUMIO: Evaluating Server Nov 22 09:12:06 thomsaudiohoek volumio[1242]: info: Remote config written successfully Nov 22 09:12:06 thomsaudiohoek volumio[1242]: info: Starting Tunnel 1 Nov 22 09:12:06 thomsaudiohoek volumio[1242]: info: Starting Tunnel Connection Checker Nov 22 09:12:06 thomsaudiohoek volumio[1242]: info: Completed starting MyVolumio Plugin Nov 22 09:12:07 thomsaudiohoek volumio[1242]: info: MYVolumio Device enabled Nov 22 09:12:07 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Nov 22 09:12:07 thomsaudiohoek volumio[1242]: info: MyVolumio status changed Nov 22 09:12:07 thomsaudiohoek volumio[1242]: info: Streaming services startup Nov 22 09:12:07 thomsaudiohoek volumio[1242]: info: Starting Streaming Daemon Nov 22 09:12:07 thomsaudiohoek sudo[1677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 22 09:12:07 thomsaudiohoek sudo[1677]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:07 thomsaudiohoek volumio[1242]: info: Setting Geolocation for MyVolumio to eu6 Nov 22 09:12:07 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:12:07 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:12:07 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:12:07 thomsaudiohoek sudo[1677]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:07 thomsaudiohoek volumio[1242]: error: Cannot start Volumio Streaming Daemon Nov 22 09:12:07 thomsaudiohoek volumio[1242]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 22 09:12:07 thomsaudiohoek volumio[1242]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 22 09:12:07 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Nov 22 09:12:07 thomsaudiohoek volumio[1242]: error: [MyVolumio PluginManager] Cache data is invalid! Nov 22 09:12:07 thomsaudiohoek volumio[1242]: info: Setting Geolocation for MyVolumio to eu4 Nov 22 09:12:07 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:12:07 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:12:07 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:12:07 thomsaudiohoek volumio[1242]: info: Successfully Added MyVolumio device Nov 22 09:12:07 thomsaudiohoek volumio[1242]: info: Successfully Added MyVolumio device Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: Updating MyVolumio device info Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: [1732263128434] Starting BluetoothController Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Nov 22 09:12:08 thomsaudiohoek sudo[1680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Nov 22 09:12:08 thomsaudiohoek sudo[1680]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:08 thomsaudiohoek sudo[1680]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: MRS: MultiRoom plugin initialized Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: MRS: STOPPING SNAPCLIENT Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: MRS: Snap server stop Nov 22 09:12:08 thomsaudiohoek sudo[1696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Nov 22 09:12:08 thomsaudiohoek sudo[1696]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:08 thomsaudiohoek sudo[1696]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: MRS: STOPPING volumioStreaming Nov 22 09:12:08 thomsaudiohoek sudo[1699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Nov 22 09:12:08 thomsaudiohoek sudo[1699]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:08 thomsaudiohoek sudo[1699]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:08 thomsaudiohoek sudo[1702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Nov 22 09:12:08 thomsaudiohoek sudo[1702]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Nov 22 09:12:08 thomsaudiohoek sudo[1702]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:08 thomsaudiohoek sudo[1705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Nov 22 09:12:08 thomsaudiohoek sudo[1705]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:08 thomsaudiohoek sudo[1705]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: Preparing to generate the ALSA configuration file Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: Updating MyVolumio device info Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: Reading ALSA contributions from plugins. Nov 22 09:12:08 thomsaudiohoek volumio[1242]: info: Successfully Updated MyVolumio device Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: MRS: Removed streaming files Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: MRS: volumioStreaming STOPPED Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: MRS: SNAPSERVER STOPPED Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: MRS: SNAPCLIENT STOPPED Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Asound.conf file unchanged, so no further update is needed Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Output device has changed, restarting MPD Nov 22 09:12:09 thomsaudiohoek sudo[1708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 22 09:12:09 thomsaudiohoek sudo[1708]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:09 thomsaudiohoek sudo[1708]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Output device has changed, restarting Shairport Sync Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:12:09 thomsaudiohoek sudo[1711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 22 09:12:09 thomsaudiohoek sudo[1711]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:09 thomsaudiohoek systemd[1]: Stopping Music Player Daemon... Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:12:09 thomsaudiohoek systemd[1]: mpd.service: Succeeded. Nov 22 09:12:09 thomsaudiohoek systemd[1]: Stopped Music Player Daemon. Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 22 09:12:09 thomsaudiohoek systemd[1]: Starting Music Player Daemon... Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Plugin multiroom disabled by user. Not starting Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Adding METAVOLUMIO REST API Endpoints Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Preparing CD Folders Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Adding CD REST API Endpoints Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Starting UDEV Watcher for CD Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Detecting CD presence with UDEV Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Nov 22 09:12:09 thomsaudiohoek sudo[1720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name ThomsAudioHoek Nov 22 09:12:09 thomsaudiohoek sudo[1720]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Adding inputs REST Endpoints Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Scanning Audio Inputs Nov 22 09:12:09 thomsaudiohoek sudo[1720]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:09 thomsaudiohoek sudo[1717]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 22 09:12:09 thomsaudiohoek sudo[1717]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:09 thomsaudiohoek sudo[1717]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Checking against Known Cards name Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Adding Server instance for streaming Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Nov 22 09:12:09 thomsaudiohoek volumio[1242]: error: Hi Res Audio Failed Login: Missing Login Data Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Adding HIGHRESAUDIO REST API Endpoints Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Stopping AccessToken refresher cron Nov 22 09:12:09 thomsaudiohoek sudo[1727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 22 09:12:09 thomsaudiohoek sudo[1727]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: AccessToken refresher cron started Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Stopping AccessToken refresher cron for QOBUZ Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: AccessToken refresher cron started for QOBUZ Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Adding TIDAL REST API Endpoints Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Adding QOBUZ REST API Endpoints Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Bluetooth name changed to ThomsAudioHoek Nov 22 09:12:09 thomsaudiohoek volumio[1242]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: MPD Permissions set Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioGetState Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:12:09 thomsaudiohoek sudo[1727]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Starting Shairport Sync Nov 22 09:12:09 thomsaudiohoek sudo[1735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 22 09:12:09 thomsaudiohoek sudo[1735]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:09 thomsaudiohoek systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 22 09:12:09 thomsaudiohoek systemd[1]: shairport-sync.service: Succeeded. Nov 22 09:12:09 thomsaudiohoek systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 22 09:12:09 thomsaudiohoek systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 22 09:12:09 thomsaudiohoek sudo[1735]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Shairport-Sync Started Nov 22 09:12:09 thomsaudiohoek mpd[1725]: Nov 22 09:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 22 09:12:09 thomsaudiohoek systemd[1]: Started Music Player Daemon. Nov 22 09:12:09 thomsaudiohoek sudo[1711]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:09 thomsaudiohoek volumio[1242]: error: updateQueue error: null Nov 22 09:12:09 thomsaudiohoek volumio[1242]: info: Successfully Updated MyVolumio device Nov 22 09:12:10 thomsaudiohoek volumio[1599]: ............................................................................................................................................................++++ Nov 22 09:12:11 thomsaudiohoek volumio[1599]: ........++++ Nov 22 09:12:11 thomsaudiohoek volumio[1599]: e is 65537 (0x010001) Nov 22 09:12:11 thomsaudiohoek volumio[1599]: writing RSA key Nov 22 09:12:12 thomsaudiohoek sudo[1774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 22 09:12:12 thomsaudiohoek sudo[1774]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:12 thomsaudiohoek systemd[1]: Started MyVolumio SSH Tunnel. Nov 22 09:12:12 thomsaudiohoek sudo[1774]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:12 thomsaudiohoek volumio[1242]: info: Remote SSH Started Nov 22 09:12:12 thomsaudiohoek autossh[1777]: port set to 0, monitoring disabled Nov 22 09:12:12 thomsaudiohoek autossh[1777]: starting ssh (count 1) Nov 22 09:12:12 thomsaudiohoek autossh[1777]: ssh child pid is 1780 Nov 22 09:12:12 thomsaudiohoek sudo[1782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Nov 22 09:12:12 thomsaudiohoek sudo[1782]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:12 thomsaudiohoek volumio[1242]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Nov 22 09:12:12 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioGetState Nov 22 09:12:12 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:12:12 thomsaudiohoek systemd[1]: Started Volumio Bluetooth Module. Nov 22 09:12:12 thomsaudiohoek sudo[1782]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:12 thomsaudiohoek volumio[1242]: info: Volumio BT Module successfully started Nov 22 09:12:12 thomsaudiohoek sudo[1785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Nov 22 09:12:12 thomsaudiohoek sudo[1785]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:12 thomsaudiohoek volumiobt[1784]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Nov 22 09:12:12 thomsaudiohoek sudo[1785]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:12 thomsaudiohoek volumio[1242]: info: TidalConnect service stoped! Nov 22 09:12:12 thomsaudiohoek volumio[1242]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 22 09:12:12 thomsaudiohoek volumio[1242]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 22 09:12:12 thomsaudiohoek sudo[1797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 22 09:12:12 thomsaudiohoek sudo[1797]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:12 thomsaudiohoek systemd[1]: Started Volumio Tidal Connect Service. Nov 22 09:12:12 thomsaudiohoek sudo[1797]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:12 thomsaudiohoek volumio[1242]: info: Executing endpoint tc_getconfig Nov 22 09:12:12 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 22 09:12:12 thomsaudiohoek vtcs[1799]: STARTING TidalConnect services, version: 1.4.0.34 Nov 22 09:12:12 thomsaudiohoek vtcs[1799]: STARTED TidalConnect services. Nov 22 09:12:12 thomsaudiohoek volumio[1242]: info: Executing endpoint tc_connect Nov 22 09:12:12 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 22 09:12:12 thomsaudiohoek volumio[1242]: info: Connecting to TidalConnect Nov 22 09:12:12 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::servicePushState Nov 22 09:12:12 thomsaudiohoek volumio[1242]: info: CoreStateMachine::pushState Nov 22 09:12:12 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:12:12 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioPushState Nov 22 09:12:12 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:12:12 thomsaudiohoek volumio[1242]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Nov 22 09:12:12 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::servicePushState Nov 22 09:12:12 thomsaudiohoek volumio[1242]: info: CoreStateMachine::pushState Nov 22 09:12:12 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:12:12 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioPushState Nov 22 09:12:12 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:12:12 thomsaudiohoek volumio[1242]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Nov 22 09:12:13 thomsaudiohoek sudo[1832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Nov 22 09:12:13 thomsaudiohoek sudo[1832]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:13 thomsaudiohoek volumiobt[1784]: pulseaudio: no process found Nov 22 09:12:13 thomsaudiohoek sudo[1832]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:13 thomsaudiohoek dbus-daemon[763]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.20' (uid=1000 pid=1836 comm="/usr/bin/pulseaudio --high-priority=true ") Nov 22 09:12:13 thomsaudiohoek systemd[1]: Starting RealtimeKit Scheduling Policy Service... Nov 22 09:12:13 thomsaudiohoek volumio[1242]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 22 09:12:13 thomsaudiohoek dbus-daemon[763]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Nov 22 09:12:13 thomsaudiohoek systemd[1]: Started RealtimeKit Scheduling Policy Service. Nov 22 09:12:13 thomsaudiohoek rtkit-daemon[1837]: Successfully called chroot. Nov 22 09:12:13 thomsaudiohoek rtkit-daemon[1837]: Successfully dropped privileges. Nov 22 09:12:13 thomsaudiohoek rtkit-daemon[1837]: Successfully limited resources. Nov 22 09:12:13 thomsaudiohoek rtkit-daemon[1837]: Running. Nov 22 09:12:13 thomsaudiohoek rtkit-daemon[1837]: Canary thread running. Nov 22 09:12:13 thomsaudiohoek rtkit-daemon[1837]: Watchdog thread running. Nov 22 09:12:13 thomsaudiohoek dbus-daemon[763]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.21' (uid=0 pid=1837 comm="/usr/lib/rtkit/rtkit-daemon ") Nov 22 09:12:13 thomsaudiohoek systemd[1]: Starting Authorization Manager... Nov 22 09:12:13 thomsaudiohoek volumio[1242]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 22 09:12:13 thomsaudiohoek polkitd[1841]: started daemon version 0.105 using authority implementation `local' version `0.105' Nov 22 09:12:13 thomsaudiohoek dbus-daemon[763]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Nov 22 09:12:13 thomsaudiohoek systemd[1]: Started Authorization Manager. Nov 22 09:12:13 thomsaudiohoek pulseaudio[1836]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 22 09:12:13 thomsaudiohoek pulseaudio[1836]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 22 09:12:13 thomsaudiohoek volumiobt[1784]: Applying permissions Nov 22 09:12:13 thomsaudiohoek volumiobt[1784]: Setting BT discoverable and pairable Nov 22 09:12:13 thomsaudiohoek bluetoothd[1066]: Endpoint registered: sender=:1.41 path=/MediaEndpoint/A2DPSource Nov 22 09:12:13 thomsaudiohoek bluetoothd[1066]: Endpoint registered: sender=:1.41 path=/MediaEndpoint/A2DPSink Nov 22 09:12:13 thomsaudiohoek pulseaudio[1836]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Nov 22 09:12:13 thomsaudiohoek volumiobt[1784]: [CHG] Controller 2C:CF:67:26:EE:8A Class: 0x0008041c Nov 22 09:12:13 thomsaudiohoek volumiobt[1784]: [55B blob data] Nov 22 09:12:13 thomsaudiohoek kernel: Bluetooth: RFCOMM TTY layer initialized Nov 22 09:12:13 thomsaudiohoek kernel: Bluetooth: RFCOMM socket layer initialized Nov 22 09:12:13 thomsaudiohoek kernel: Bluetooth: RFCOMM ver 1.11 Nov 22 09:12:13 thomsaudiohoek volumiobt[1784]: [bluetooth]# -e power on Nov 22 09:12:13 thomsaudiohoek volumiobt[1784]: Invalid command in menu main: -e Nov 22 09:12:13 thomsaudiohoek volumiobt[1784]: Nov 22 09:12:13 thomsaudiohoek volumiobt[1784]: Use "help" for a list of available commands in a menu. Nov 22 09:12:13 thomsaudiohoek volumiobt[1784]: Use "menu " if you want to enter any submenu. Nov 22 09:12:13 thomsaudiohoek volumiobt[1784]: Use "back" if you want to return to menu main. Nov 22 09:12:13 thomsaudiohoek volumiobt[1784]: [bluetooth]# agent on Nov 22 09:12:13 thomsaudiohoek volumiobt[1784]: Agent is already registered Nov 22 09:12:13 thomsaudiohoek volumiobt[1784]: [bluetooth]# discoverable on Nov 22 09:12:13 thomsaudiohoek volumiobt[1784]: [bluetooth]# pairable on Nov 22 09:12:13 thomsaudiohoek volumiobt[1784]: [bluetooth]# agent NoInputNoOutput Nov 22 09:12:13 thomsaudiohoek volumiobt[1784]: Agent is already registered Nov 22 09:12:13 thomsaudiohoek volumiobt[1784]: [bluetooth]# default-agent Nov 22 09:12:13 thomsaudiohoek volumiobt[1784]: [bluetooth]# quit Nov 22 09:12:13 thomsaudiohoek volumiobt[1784]: [59B blob data] Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: TidalConnect service started! Nov 22 09:12:15 thomsaudiohoek volumio[1242]: [Metrics] CommandRouter: 22s 947.51ms Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumiosetStartupVolume Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: VolumeController:: Setting startup Volume 60 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: VolumeController::SetAlsaVolume60 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::Close All Modals sent Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioGetState Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreStateMachine::pushState Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioPushState Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: camilladsp stopping service pid 1562... Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: camilladsp service terminated, instance 1 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: camilladsp service started and running in background, instance 1 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 22 09:12:15 thomsaudiohoek volumio[1242]: error: FusionDsp - WebSocket error: [object Object] Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: camilladsp spawned new process with pid 1859, instance 1, run: true Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: AutoStart - Plugin is starting Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioGetQueue Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreStateMachine::getQueue Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getQueue Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: AutoStart - start playing Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: AutoStart - start playing with no specific position Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioPlay Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreStateMachine::play index 0 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreStateMachine::stop Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreStateMachine::play index undefined Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreStateMachine::startPlaybackTimer Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioGetVisibleSources Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 22 09:12:15 thomsaudiohoek volumio[1242]: verbose: ControllerMpd::sendMpdCommand stop Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: sendMpdCommand stop took 0 milliseconds Nov 22 09:12:15 thomsaudiohoek volumio[1242]: verbose: ControllerMpd::sendMpdCommand clear Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: Nov 22 09:12:15 thomsaudiohoek volumio[1242]: ---------------------------- MPD announces system playlist update Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: Ignoring MPD Status Update Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: sendMpdCommand clear took 1 milliseconds Nov 22 09:12:15 thomsaudiohoek volumio[1242]: verbose: ControllerMpd::sendMpdCommand consume 1 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: error: updateQueue error: null Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: Nov 22 09:12:15 thomsaudiohoek volumio[1242]: ---------------------------- MPD announces state update: options Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: ------------------------------ 2ms Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: sendMpdCommand consume 1 took 2 milliseconds Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: ControllerMpd::getState Nov 22 09:12:15 thomsaudiohoek volumio[1242]: verbose: ControllerMpd::sendMpdCommand status Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: [1732263135433] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/mellow-flac Nov 22 09:12:15 thomsaudiohoek volumio[1242]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: Nov 22 09:12:15 thomsaudiohoek volumio[1242]: ---------------------------- MPD announces system playlist update Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: Ignoring MPD Status Update Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: sendMpdCommand status took 6 milliseconds Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" took 6 milliseconds Nov 22 09:12:15 thomsaudiohoek volumio[1242]: verbose: ControllerMpd::parseState Nov 22 09:12:15 thomsaudiohoek volumio[1242]: verbose: ControllerMpd::sendMpdCommand play Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: ControllerMpd::pushState Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::servicePushState Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreStateMachine::pushState Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioPushState Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: ------------------------------ 14ms Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: ------------------------------ 9ms Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: sendMpdCommand play took 5 milliseconds Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: [1732263135448] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: Nov 22 09:12:15 thomsaudiohoek volumio[1242]: ---------------------------- MPD announces state update: player Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: ControllerMpd::getState Nov 22 09:12:15 thomsaudiohoek volumio[1242]: verbose: ControllerMpd::sendMpdCommand status Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: Nov 22 09:12:15 thomsaudiohoek volumio[1242]: ---------------------------- MPD announces state update: player Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: sendMpdCommand status took 8 milliseconds Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: ControllerMpd::getState Nov 22 09:12:15 thomsaudiohoek volumio[1242]: verbose: ControllerMpd::sendMpdCommand status Nov 22 09:12:15 thomsaudiohoek volumio[1242]: verbose: ControllerMpd::parseState Nov 22 09:12:15 thomsaudiohoek volumio[1242]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: sendMpdCommand status took 0 milliseconds Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 22 09:12:15 thomsaudiohoek volumio[1242]: verbose: ControllerMpd::parseState Nov 22 09:12:15 thomsaudiohoek volumio[1242]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 09:12:15 thomsaudiohoek volumio[1242]: verbose: ControllerMpd::parseTrackInfo Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: ControllerMpd::pushState Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::servicePushState Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: ------------------------------ 12ms Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: Nov 22 09:12:15 thomsaudiohoek volumio[1242]: ---------------------------- MPD announces system playlist update Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: Ignoring MPD Status Update Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: Nov 22 09:12:15 thomsaudiohoek volumio[1242]: ---------------------------- MPD announces state update: player Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: ControllerMpd::getState Nov 22 09:12:15 thomsaudiohoek volumio[1242]: verbose: ControllerMpd::sendMpdCommand status Nov 22 09:12:15 thomsaudiohoek volumio[1242]: verbose: ControllerMpd::parseTrackInfo Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: ControllerMpd::pushState Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::servicePushState Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: ------------------------------ 4ms Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: ------------------------------ 2ms Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: sendMpdCommand status took 2 milliseconds Nov 22 09:12:15 thomsaudiohoek volumio[1242]: verbose: ControllerMpd::parseState Nov 22 09:12:15 thomsaudiohoek volumio[1242]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 22 09:12:15 thomsaudiohoek volumio[1242]: verbose: ControllerMpd::parseTrackInfo Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: ControllerMpd::pushState Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::servicePushState Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: ------------------------------ 3ms Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: [1732263135930] [RadioParadise] received new metadata: {"time":78,"artist":"Jackson Browne","title":"The Road","album":"Running on Empty","year":"1977","cover":"https://img.radioparadise.com/covers/l/B000002GW5.jpg","cover_med":"https://img.radioparadise.com/covers/m/B000002GW5.jpg","cover_small":"https://img.radioparadise.com/covers/s/B000002GW5.jpg"} Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::servicePushState Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000002GW5.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"The Road","title":"The Road","artist":"Jackson Browne","album":"Running on Empty","streaming":true,"disableUiControls":true,"duration":83,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Nov 22 09:12:15 thomsaudiohoek volumio[1242]: verbose: CURRENT POSITION 0 Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreStateMachine::syncState stateService play Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: CoreStateMachine::syncState currentStatus stop Nov 22 09:12:15 thomsaudiohoek volumio[1242]: info: [1732263135932] [RadioParadise] setting new timer with duration of 83000 seconds. Nov 22 09:12:16 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 22 09:12:16 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 22 09:12:16 thomsaudiohoek volumio[1242]: xcb_connection_has_error() returned true Nov 22 09:12:16 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 22 09:12:16 thomsaudiohoek volumio[1242]: info: Version has changed, forcing UI Reload Nov 22 09:12:16 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::Reload Ui Nov 22 09:12:16 thomsaudiohoek sudo[1878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 22 09:12:16 thomsaudiohoek sudo[1878]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:16 thomsaudiohoek sudo[1878]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:16 thomsaudiohoek sudo[1881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 22 09:12:16 thomsaudiohoek sudo[1881]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:16 thomsaudiohoek sudo[1881]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:16 thomsaudiohoek volumio[1242]: verbose: New Socket.io Connection to 192.168.68.65 from 192.168.68.75 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0 Engine version: 3 Transport: polling Total Clients: 9 Nov 22 09:12:16 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 22 09:12:16 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 22 09:12:16 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioGetState Nov 22 09:12:16 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:12:16 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 22 09:12:16 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 22 09:12:16 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 22 09:12:16 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 22 09:12:17 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 22 09:12:17 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 22 09:12:17 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Nov 22 09:12:17 thomsaudiohoek volumio[1242]: info: Received Get System Version Nov 22 09:12:17 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 22 09:12:17 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Nov 22 09:12:18 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 22 09:12:18 thomsaudiohoek volumio[1242]: info: Received Get System Info Nov 22 09:12:18 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 09:12:18 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 09:12:18 thomsaudiohoek volumio[1242]: info: Discovery: Getting this device information Nov 22 09:12:18 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioGetState Nov 22 09:12:18 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:12:18 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 09:12:19 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 22 09:12:19 thomsaudiohoek volumio[1242]: info: Received Get System Info Nov 22 09:12:19 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 09:12:19 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 09:12:19 thomsaudiohoek volumio[1242]: info: Discovery: Getting this device information Nov 22 09:12:19 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioGetState Nov 22 09:12:19 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:12:19 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 09:12:20 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 22 09:12:20 thomsaudiohoek volumio[1242]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Nov 22 09:12:20 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioGetState Nov 22 09:12:20 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:12:22 thomsaudiohoek volumio[1242]: info: BOOT COMPLETED Nov 22 09:12:25 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:12:25 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 09:12:25 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 22 09:12:25 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 22 09:12:25 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 22 09:12:25 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioGetBrowseSources Nov 22 09:12:25 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 22 09:12:25 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 22 09:12:25 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 09:12:25 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 22 09:12:25 thomsaudiohoek volumio-remote-updater[775]: Test mode enabled Nov 22 09:12:25 thomsaudiohoek volumio-remote-updater[775]: No alpha test mode Nov 22 09:12:25 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 22 09:12:25 thomsaudiohoek volumio[1242]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 22 09:12:25 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 22 09:12:26 thomsaudiohoek systemd[1]: systemd-timedated.service: Succeeded. Nov 22 09:12:27 thomsaudiohoek volumiossh-tunnel[1776]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Nov 22 09:12:34 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 22 09:12:34 thomsaudiohoek volumio[1242]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 22 09:12:34 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 22 09:12:34 thomsaudiohoek volumio[1242]: info: Received Get System Version Nov 22 09:12:34 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 22 09:12:34 thomsaudiohoek volumio[1242]: info: Received Get System Info Nov 22 09:12:34 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 09:12:34 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 09:12:34 thomsaudiohoek volumio[1242]: info: Discovery: Getting this device information Nov 22 09:12:34 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::volumioGetState Nov 22 09:12:34 thomsaudiohoek volumio[1242]: info: CorePlayQueue::getTrack 0 Nov 22 09:12:34 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 09:12:37 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 22 09:12:37 thomsaudiohoek volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 22 09:12:42 thomsaudiohoek ntpd[1023]: Soliciting pool server 185.51.192.61 Nov 22 09:12:43 thomsaudiohoek ntpd[1023]: Soliciting pool server 185.51.192.62 Nov 22 09:12:43 thomsaudiohoek ntpd[1023]: Soliciting pool server 167.99.42.106 Nov 22 09:12:44 thomsaudiohoek ntpd[1023]: Soliciting pool server 185.244.24.36 Nov 22 09:12:44 thomsaudiohoek ntpd[1023]: Soliciting pool server 185.51.192.63 Nov 22 09:12:44 thomsaudiohoek ntpd[1023]: Soliciting pool server 162.159.200.1 Nov 22 09:12:45 thomsaudiohoek ntpd[1023]: Soliciting pool server 45.32.4.67 Nov 22 09:12:45 thomsaudiohoek ntpd[1023]: Soliciting pool server 5.255.99.180 Nov 22 09:12:45 thomsaudiohoek ntpd[1023]: Soliciting pool server 5.255.124.190 Nov 22 09:12:45 thomsaudiohoek ntpd[1023]: Soliciting pool server 209.250.249.159 Nov 22 09:12:46 thomsaudiohoek ntpd[1023]: Soliciting pool server 84.82.15.107 Nov 22 09:12:46 thomsaudiohoek ntpd[1023]: Soliciting pool server 2001:678:8::123 Nov 22 09:12:46 thomsaudiohoek ntpd[1023]: Soliciting pool server 45.83.205.227 Nov 22 09:12:46 thomsaudiohoek ntpd[1023]: Soliciting pool server 5.39.184.5 Nov 22 09:12:47 thomsaudiohoek ntpd[1023]: Soliciting pool server 83.98.155.30 Nov 22 09:12:47 thomsaudiohoek ntpd[1023]: Soliciting pool server 94.198.159.16 Nov 22 09:12:47 thomsaudiohoek ntpd[1023]: Soliciting pool server 193.123.56.220 Nov 22 09:12:48 thomsaudiohoek ntpd[1023]: Soliciting pool server 195.35.113.80 Nov 22 09:12:48 thomsaudiohoek ntpd[1023]: Soliciting pool server 178.215.228.24 Nov 22 09:12:51 thomsaudiohoek ntpd[1023]: receive: Unexpected origin timestamp 0xeaeabb82.a62142ff does not match aorg 0000000000.00000000 from server@167.99.42.106 xmt 0xeaeabb83.d91f5274 Nov 22 09:12:51 thomsaudiohoek ntpd[1023]: receive: Unexpected origin timestamp 0xeaeabb82.a61b83f7 does not match aorg 0000000000.00000000 from server@84.82.15.107 xmt 0xeaeabb83.da86d0a6 Nov 22 09:12:52 thomsaudiohoek volumio[1242]: info: Starting Uninstall of plugin user_interface - touch_display Nov 22 09:12:52 thomsaudiohoek volumio[1242]: info: Uninstalling plugin touch_display Nov 22 09:12:52 thomsaudiohoek sudo[1996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Nov 22 09:12:52 thomsaudiohoek sudo[1996]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:52 thomsaudiohoek sudo[1996]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:52 thomsaudiohoek sudo[1999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Nov 22 09:12:52 thomsaudiohoek sudo[1999]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:52 thomsaudiohoek sudo[2002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Nov 22 09:12:52 thomsaudiohoek sudo[2002]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:52 thomsaudiohoek systemd[1]: Created slice system-getty.slice. Nov 22 09:12:52 thomsaudiohoek volumio[1242]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Nov 22 09:12:52 thomsaudiohoek volumio[1242]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 22 09:12:52 thomsaudiohoek sudo[2006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Nov 22 09:12:52 thomsaudiohoek sudo[2006]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 09:12:52 thomsaudiohoek volumio[1242]: TypeError: Cannot read property 'split' of undefined Nov 22 09:12:52 thomsaudiohoek volumio[1242]: at /data/plugins/user_interface/touch_display/index.js:1258:37 Nov 22 09:12:52 thomsaudiohoek volumio[1242]: at ChildProcess.exithandler (child_process.js:299:7) Nov 22 09:12:52 thomsaudiohoek volumio[1242]: at ChildProcess.emit (events.js:315:20) Nov 22 09:12:52 thomsaudiohoek volumio[1242]: at maybeClose (internal/child_process.js:1048:16) Nov 22 09:12:52 thomsaudiohoek volumio[1242]: at Socket. (internal/child_process.js:439:11) Nov 22 09:12:52 thomsaudiohoek volumio[1242]: at Socket.emit (events.js:315:20) Nov 22 09:12:52 thomsaudiohoek volumio[1242]: at Pipe. (net.js:673:12) Nov 22 09:12:52 thomsaudiohoek volumio[1242]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 22 09:12:52 thomsaudiohoek systemd[1]: Started Getty on tty1. Nov 22 09:12:52 thomsaudiohoek sudo[1999]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:52 thomsaudiohoek systemd[1]: Reloading. Nov 22 09:12:52 thomsaudiohoek sudo[2006]: pam_unix(sudo:session): session closed for user root Nov 22 09:12:52 thomsaudiohoek 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. Nov 22 09:12:52 thomsaudiohoek sudo[2030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-22 09:11 Nov 22 09:12:52 thomsaudiohoek sudo[2030]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 21 Nov 2024 11:00:22 AM CET" VOLUMIO_VERSION="3.781" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="274050037be981e489ea334352baf229"