-- Logs begin at Sun 2024-10-27 02:03:03 WET, end at Sun 2024-10-27 02:04:11 WET. -- Oct 27 02:03:03 motivo kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] Oct 27 02:03:03 motivo kernel: Linux version 6.6.30-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) #1763 SMP PREEMPT Mon May 13 17:50:31 BST 2024 Oct 27 02:03:03 motivo kernel: KASLR enabled Oct 27 02:03:03 motivo kernel: random: crng init done Oct 27 02:03:03 motivo kernel: Machine model: Raspberry Pi Compute Module 4 Rev 1.1 Oct 27 02:03:03 motivo kernel: efi: UEFI not found. Oct 27 02:03:03 motivo kernel: Reserved memory: created CMA memory pool at 0x0000000016800000, size 384 MiB Oct 27 02:03:03 motivo kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Oct 27 02:03:03 motivo kernel: OF: reserved mem: 0x0000000016800000..0x000000002e7fffff (393216 KiB) map reusable linux,cma Oct 27 02:03:03 motivo kernel: OF: reserved mem: 0x000000003f12a860..0x000000003f12ac5f (1 KiB) nomap non-reusable nvram@1 Oct 27 02:03:03 motivo kernel: OF: reserved mem: 0x000000003f12aca0..0x000000003f12ae53 (0 KiB) nomap non-reusable nvram@0 Oct 27 02:03:03 motivo kernel: Zone ranges: Oct 27 02:03:03 motivo kernel: DMA [mem 0x0000000000000000-0x000000003fffffff] Oct 27 02:03:03 motivo kernel: DMA32 [mem 0x0000000040000000-0x000000007fffffff] Oct 27 02:03:03 motivo kernel: Normal empty Oct 27 02:03:03 motivo kernel: Movable zone start for each node Oct 27 02:03:03 motivo kernel: Early memory node ranges Oct 27 02:03:03 motivo kernel: node 0: [mem 0x0000000000000000-0x000000002fffffff] Oct 27 02:03:03 motivo kernel: node 0: [mem 0x0000000040000000-0x000000007fffffff] Oct 27 02:03:03 motivo kernel: Initmem setup node 0 [mem 0x0000000000000000-0x000000007fffffff] Oct 27 02:03:03 motivo kernel: percpu: Embedded 30 pages/cpu s85672 r8192 d29016 u122880 Oct 27 02:03:03 motivo kernel: pcpu-alloc: s85672 r8192 d29016 u122880 alloc=30*4096 Oct 27 02:03:03 motivo kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 27 02:03:03 motivo kernel: Detected PIPT I-cache on CPU0 Oct 27 02:03:03 motivo kernel: CPU features: detected: Spectre-v2 Oct 27 02:03:03 motivo kernel: CPU features: detected: Spectre-v3a Oct 27 02:03:03 motivo kernel: CPU features: detected: Spectre-v4 Oct 27 02:03:03 motivo kernel: CPU features: detected: Spectre-BHB Oct 27 02:03:03 motivo kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 27 02:03:03 motivo kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 27 02:03:03 motivo kernel: CPU features: detected: ARM erratum 1742098 Oct 27 02:03:03 motivo kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 Oct 27 02:03:03 motivo kernel: alternatives: applying boot alternatives Oct 27 02:03:03 motivo kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_hdmi=0 snd_bcm2835.enable_hdmi=0 smsc95xx.macaddr=D8:3A:DD:B1:DB:34 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=aa29d975-d4d2-48dd-9a94-d7ad7157c5e8 imgfile=/volumio_current.sqsh bootpart=UUID=EE6E-1229 datapart=UUID=cddde018-c4e7-47b9-a698-1af8f5bdeffb 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=1 loglevel=0 nodebug use_kmsg=no Oct 27 02:03:03 motivo kernel: PCIe ASPM is disabled Oct 27 02:03:03 motivo kernel: Unknown kernel command line parameters "splash nodebug imgpart=UUID=aa29d975-d4d2-48dd-9a94-d7ad7157c5e8 imgfile=/volumio_current.sqsh bootpart=UUID=EE6E-1229 datapart=UUID=cddde018-c4e7-47b9-a698-1af8f5bdeffb uuidconfig=cmdline.txt bootdelay=7 use_kmsg=no", will be passed to user space. Oct 27 02:03:03 motivo kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 27 02:03:03 motivo kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 27 02:03:03 motivo kernel: Built 1 zonelists, mobility grouping on. Total pages: 451584 Oct 27 02:03:03 motivo kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 27 02:03:03 motivo kernel: software IO TLB: area num 4. Oct 27 02:03:03 motivo kernel: software IO TLB: mapped [mem 0x0000000012800000-0x0000000016800000] (64MB) Oct 27 02:03:03 motivo kernel: Memory: 1306660K/1835008K available (13376K kernel code, 2210K rwdata, 4260K rodata, 4864K init, 1083K bss, 135132K reserved, 393216K cma-reserved) Oct 27 02:03:03 motivo kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 27 02:03:03 motivo kernel: ftrace: allocating 43232 entries in 169 pages Oct 27 02:03:03 motivo kernel: ftrace: allocated 169 pages with 4 groups Oct 27 02:03:03 motivo kernel: trace event string verifier disabled Oct 27 02:03:03 motivo kernel: rcu: Preemptible hierarchical RCU implementation. Oct 27 02:03:03 motivo kernel: rcu: RCU event tracing is enabled. Oct 27 02:03:03 motivo kernel: rcu: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4. Oct 27 02:03:03 motivo kernel: Trampoline variant of Tasks RCU enabled. Oct 27 02:03:03 motivo kernel: Rude variant of Tasks RCU enabled. Oct 27 02:03:03 motivo kernel: Tracing variant of Tasks RCU enabled. Oct 27 02:03:03 motivo kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Oct 27 02:03:03 motivo kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 27 02:03:03 motivo kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 27 02:03:03 motivo kernel: Root IRQ handler: gic_handle_irq Oct 27 02:03:03 motivo kernel: GIC: Using split EOI/Deactivate mode Oct 27 02:03:03 motivo kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 27 02:03:03 motivo kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Oct 27 02:03:03 motivo kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Oct 27 02:03:03 motivo kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Oct 27 02:03:03 motivo kernel: Console: colour dummy device 80x25 Oct 27 02:03:03 motivo kernel: printk: console [tty1] enabled Oct 27 02:03:03 motivo kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000) Oct 27 02:03:03 motivo kernel: pid_max: default: 32768 minimum: 301 Oct 27 02:03:03 motivo kernel: LSM: initializing lsm=capability,integrity Oct 27 02:03:03 motivo kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Oct 27 02:03:03 motivo kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Oct 27 02:03:03 motivo kernel: cgroup: Disabling memory control group subsystem Oct 27 02:03:03 motivo kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Oct 27 02:03:03 motivo kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Oct 27 02:03:03 motivo kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Oct 27 02:03:03 motivo kernel: rcu: Hierarchical SRCU implementation. Oct 27 02:03:03 motivo kernel: rcu: Max phase no-delay instances is 1000. Oct 27 02:03:03 motivo kernel: EFI services will not be available. Oct 27 02:03:03 motivo kernel: smp: Bringing up secondary CPUs ... Oct 27 02:03:03 motivo kernel: Detected PIPT I-cache on CPU1 Oct 27 02:03:03 motivo kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] Oct 27 02:03:03 motivo kernel: Detected PIPT I-cache on CPU2 Oct 27 02:03:03 motivo kernel: CPU2: Booted secondary processor 0x0000000002 [0x410fd083] Oct 27 02:03:03 motivo kernel: Detected PIPT I-cache on CPU3 Oct 27 02:03:03 motivo kernel: CPU3: Booted secondary processor 0x0000000003 [0x410fd083] Oct 27 02:03:03 motivo kernel: smp: Brought up 1 node, 4 CPUs Oct 27 02:03:03 motivo kernel: SMP: Total of 4 processors activated. Oct 27 02:03:03 motivo kernel: CPU features: detected: 32-bit EL0 Support Oct 27 02:03:03 motivo kernel: CPU features: detected: 32-bit EL1 Support Oct 27 02:03:03 motivo kernel: CPU features: detected: CRC32 instructions Oct 27 02:03:03 motivo kernel: CPU: All CPU(s) started at EL2 Oct 27 02:03:03 motivo kernel: alternatives: applying system-wide alternatives Oct 27 02:03:03 motivo kernel: devtmpfs: initialized Oct 27 02:03:03 motivo kernel: Enabled cp15_barrier support Oct 27 02:03:03 motivo kernel: Enabled setend support Oct 27 02:03:03 motivo kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Oct 27 02:03:03 motivo kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 27 02:03:03 motivo kernel: pinctrl core: initialized pinctrl subsystem Oct 27 02:03:03 motivo kernel: DMI not present or invalid. Oct 27 02:03:03 motivo kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 27 02:03:03 motivo kernel: DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations Oct 27 02:03:03 motivo kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 27 02:03:03 motivo kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 27 02:03:03 motivo kernel: audit: initializing netlink subsys (disabled) Oct 27 02:03:03 motivo kernel: audit: type=2000 audit(0.032:1): state=initialized audit_enabled=0 res=1 Oct 27 02:03:03 motivo kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 27 02:03:03 motivo kernel: cpuidle: using governor menu Oct 27 02:03:03 motivo kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 27 02:03:03 motivo kernel: ASID allocator initialised with 32768 entries Oct 27 02:03:03 motivo kernel: Serial: AMBA PL011 UART driver Oct 27 02:03:03 motivo kernel: platform fe101000.cprman: Fixed dependency cycle(s) with /soc/dsi@7e700000 Oct 27 02:03:03 motivo kernel: bcm2835-mbox fe00b880.mailbox: mailbox enabled Oct 27 02:03:03 motivo kernel: platform rpi_backlight: Fixed dependency cycle(s) with /soc/pwm@7e20c000 Oct 27 02:03:03 motivo kernel: platform fe20c000.pwm: Fixed dependency cycle(s) with /soc/cprman@7e101000 Oct 27 02:03:03 motivo kernel: platform fe101000.cprman: Fixed dependency cycle(s) with /soc/dsi@7e700000 Oct 27 02:03:03 motivo kernel: platform fe700000.dsi: Fixed dependency cycle(s) with /soc/dsi@7e700000/panel@0 Oct 27 02:03:03 motivo kernel: platform fe700000.dsi: Fixed dependency cycle(s) with /soc/cprman@7e101000 Oct 27 02:03:03 motivo kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2024-05-03T19:32:58, variant start_x Oct 27 02:03:03 motivo kernel: raspberrypi-firmware soc:firmware: Firmware hash is fef7849c350cb250be836afc6d43c742f9ebc97a Oct 27 02:03:03 motivo kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Oct 27 02:03:03 motivo kernel: Modules: 0 pages in range for non-PLT usage Oct 27 02:03:03 motivo kernel: Modules: 517792 pages in range for PLT usage Oct 27 02:03:03 motivo kernel: bcm2835-dma fe007000.dma-controller: DMA legacy API manager, dmachans=0x1 Oct 27 02:03:03 motivo kernel: iommu: Default domain type: Translated Oct 27 02:03:03 motivo kernel: iommu: DMA domain TLB invalidation policy: strict mode Oct 27 02:03:03 motivo kernel: SCSI subsystem initialized Oct 27 02:03:03 motivo kernel: usbcore: registered new interface driver usbfs Oct 27 02:03:03 motivo kernel: usbcore: registered new interface driver hub Oct 27 02:03:03 motivo kernel: usbcore: registered new device driver usb Oct 27 02:03:03 motivo kernel: pps_core: LinuxPPS API ver. 1 registered Oct 27 02:03:03 motivo kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Oct 27 02:03:03 motivo kernel: PTP clock support registered Oct 27 02:03:03 motivo kernel: vgaarb: loaded Oct 27 02:03:03 motivo kernel: clocksource: Switched to clocksource arch_sys_counter Oct 27 02:03:03 motivo kernel: VFS: Disk quotas dquot_6.6.0 Oct 27 02:03:03 motivo kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 27 02:03:03 motivo kernel: FS-Cache: Loaded Oct 27 02:03:03 motivo kernel: CacheFiles: Loaded Oct 27 02:03:03 motivo kernel: NET: Registered PF_INET protocol family Oct 27 02:03:03 motivo kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 27 02:03:03 motivo kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) Oct 27 02:03:03 motivo kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 27 02:03:03 motivo kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) Oct 27 02:03:03 motivo kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear) Oct 27 02:03:03 motivo kernel: TCP: Hash tables configured (established 16384 bind 16384) Oct 27 02:03:03 motivo kernel: MPTCP token hash table entries: 2048 (order: 3, 49152 bytes, linear) Oct 27 02:03:03 motivo kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) Oct 27 02:03:03 motivo kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) Oct 27 02:03:03 motivo kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 27 02:03:03 motivo kernel: RPC: Registered named UNIX socket transport module. Oct 27 02:03:03 motivo kernel: RPC: Registered udp transport module. Oct 27 02:03:03 motivo kernel: RPC: Registered tcp transport module. Oct 27 02:03:03 motivo kernel: RPC: Registered tcp-with-tls transport module. Oct 27 02:03:03 motivo kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Oct 27 02:03:03 motivo kernel: PCI: CLS 0 bytes, default 64 Oct 27 02:03:03 motivo kernel: kvm [1]: IPA Size Limit: 44 bits Oct 27 02:03:03 motivo kernel: Trying to unpack rootfs image as initramfs... Oct 27 02:03:03 motivo kernel: kvm [1]: vgic interrupt IRQ9 Oct 27 02:03:03 motivo kernel: kvm [1]: Hyp mode initialized successfully Oct 27 02:03:03 motivo kernel: Initialise system trusted keyrings Oct 27 02:03:03 motivo kernel: workingset: timestamp_bits=46 max_order=19 bucket_order=0 Oct 27 02:03:03 motivo kernel: zbud: loaded Oct 27 02:03:03 motivo kernel: NFS: Registering the id_resolver key type Oct 27 02:03:03 motivo kernel: Key type id_resolver registered Oct 27 02:03:03 motivo kernel: Key type id_legacy registered Oct 27 02:03:03 motivo kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Oct 27 02:03:03 motivo kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Oct 27 02:03:03 motivo kernel: Key type asymmetric registered Oct 27 02:03:03 motivo kernel: Asymmetric key parser 'x509' registered Oct 27 02:03:03 motivo kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Oct 27 02:03:03 motivo kernel: io scheduler mq-deadline registered Oct 27 02:03:03 motivo kernel: io scheduler kyber registered Oct 27 02:03:03 motivo kernel: io scheduler bfq registered Oct 27 02:03:03 motivo kernel: irq_brcmstb_l2: registered L2 intc (/soc/interrupt-controller@7ef00100, parent irq: 23) Oct 27 02:03:03 motivo kernel: pinctrl-bcm2835 fe200000.gpio: GPIO_OUT persistence: yes Oct 27 02:03:03 motivo kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: Oct 27 02:03:03 motivo kernel: brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] Oct 27 02:03:03 motivo kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000 Oct 27 02:03:03 motivo kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x007fffffff -> 0x0400000000 Oct 27 02:03:03 motivo kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Oct 27 02:03:03 motivo kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 27 02:03:03 motivo kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff]) Oct 27 02:03:03 motivo kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 Oct 27 02:03:03 motivo kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Oct 27 02:03:03 motivo kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Oct 27 02:03:03 motivo kernel: pci_bus 0000:01: supply vpcie3v3 not found, using dummy regulator Oct 27 02:03:03 motivo kernel: pci_bus 0000:01: supply vpcie3v3aux not found, using dummy regulator Oct 27 02:03:03 motivo kernel: pci_bus 0000:01: supply vpcie12v not found, using dummy regulator Oct 27 02:03:03 motivo kernel: Freeing initrd memory: 6644K Oct 27 02:03:03 motivo kernel: brcm-pcie fd500000.pcie: link down Oct 27 02:03:03 motivo kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Oct 27 02:03:03 motivo kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Oct 27 02:03:03 motivo kernel: pci 0000:00:00.0: Max Payload Size set to 128/ 512 (was 128), Max Read Rq 512 Oct 27 02:03:03 motivo kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 27 Oct 27 02:03:03 motivo kernel: pcieport 0000:00:00.0: AER: enabled with IRQ 27 Oct 27 02:03:03 motivo kernel: pci_bus 0000:01: busn_res: [bus 01] is released Oct 27 02:03:03 motivo kernel: pci_bus 0000:00: busn_res: [bus 00-ff] is released Oct 27 02:03:03 motivo kernel: Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled Oct 27 02:03:03 motivo kernel: iproc-rng200 fe104000.rng: hwrng registered Oct 27 02:03:03 motivo kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Oct 27 02:03:03 motivo kernel: brd: module loaded Oct 27 02:03:03 motivo kernel: loop: module loaded Oct 27 02:03:03 motivo kernel: Loading iSCSI transport class v2.0-870. Oct 27 02:03:03 motivo kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Oct 27 02:03:03 motivo kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Oct 27 02:03:03 motivo kernel: usbcore: registered new device driver r8152-cfgselector Oct 27 02:03:03 motivo kernel: usbcore: registered new interface driver r8152 Oct 27 02:03:03 motivo kernel: usbcore: registered new interface driver lan78xx Oct 27 02:03:03 motivo kernel: usbcore: registered new interface driver smsc95xx Oct 27 02:03:03 motivo kernel: xhci-hcd fe9c0000.xhci: xHCI Host Controller Oct 27 02:03:03 motivo kernel: xhci-hcd fe9c0000.xhci: new USB bus registered, assigned bus number 1 Oct 27 02:03:03 motivo kernel: xhci-hcd fe9c0000.xhci: USB3 root hub has no ports Oct 27 02:03:03 motivo kernel: xhci-hcd fe9c0000.xhci: hcc params 0x0220fe65 hci version 0x110 quirks 0x0000000000000010 Oct 27 02:03:03 motivo kernel: xhci-hcd fe9c0000.xhci: irq 30, io mem 0xfe9c0000 Oct 27 02:03:03 motivo kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Oct 27 02:03:03 motivo kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 27 02:03:03 motivo kernel: usb usb1: Product: xHCI Host Controller Oct 27 02:03:03 motivo kernel: usb usb1: Manufacturer: Linux 6.6.30-v8+ xhci-hcd Oct 27 02:03:03 motivo kernel: usb usb1: SerialNumber: fe9c0000.xhci Oct 27 02:03:03 motivo kernel: hub 1-0:1.0: USB hub found Oct 27 02:03:03 motivo kernel: hub 1-0:1.0: 1 port detected Oct 27 02:03:03 motivo kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Oct 27 02:03:03 motivo kernel: dwc_otg: FIQ enabled Oct 27 02:03:03 motivo kernel: dwc_otg: NAK holdoff enabled Oct 27 02:03:03 motivo kernel: dwc_otg: FIQ split-transaction FSM enabled Oct 27 02:03:03 motivo kernel: Module dwc_common_port init Oct 27 02:03:03 motivo kernel: usbcore: registered new interface driver uas Oct 27 02:03:03 motivo kernel: usbcore: registered new interface driver usb-storage Oct 27 02:03:03 motivo kernel: mousedev: PS/2 mouse device common for all mice Oct 27 02:03:03 motivo kernel: sdhci: Secure Digital Host Controller Interface driver Oct 27 02:03:03 motivo kernel: sdhci: Copyright(c) Pierre Ossman Oct 27 02:03:03 motivo kernel: sdhci-pltfm: SDHCI platform and OF driver helper Oct 27 02:03:03 motivo kernel: ledtrig-cpu: registered to indicate activity on CPUs Oct 27 02:03:03 motivo kernel: hid: raw HID events driver (C) Jiri Kosina Oct 27 02:03:03 motivo kernel: usbcore: registered new interface driver usbhid Oct 27 02:03:03 motivo kernel: usbhid: USB HID core driver Oct 27 02:03:03 motivo kernel: bcm2835_vchiq fe00b840.mailbox: there is not valid maps for state default Oct 27 02:03:03 motivo kernel: hw perfevents: enabled with armv8_cortex_a72 PMU driver, 7 counters available Oct 27 02:03:03 motivo kernel: NET: Registered PF_PACKET protocol family Oct 27 02:03:03 motivo kernel: Key type dns_resolver registered Oct 27 02:03:03 motivo kernel: registered taskstats version 1 Oct 27 02:03:03 motivo kernel: Loading compiled-in X.509 certificates Oct 27 02:03:03 motivo kernel: Key type .fscrypt registered Oct 27 02:03:03 motivo kernel: Key type fscrypt-provisioning registered Oct 27 02:03:03 motivo kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Oct 27 02:03:03 motivo kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Oct 27 02:03:03 motivo kernel: fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 36, base_baud = 0) is a PL011 rev2 Oct 27 02:03:03 motivo kernel: serial serial0: tty port ttyAMA1 registered Oct 27 02:03:03 motivo kernel: fe201800.serial: ttyAMA4 at MMIO 0xfe201800 (irq = 36, base_baud = 0) is a PL011 rev2 Oct 27 02:03:03 motivo kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Oct 27 02:03:03 motivo kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Oct 27 02:03:03 motivo kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Oct 27 02:03:03 motivo kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Oct 27 02:03:03 motivo kernel: of_cfs_init Oct 27 02:03:03 motivo kernel: of_cfs_init: OK Oct 27 02:03:03 motivo kernel: clk: Disabling unused clocks Oct 27 02:03:03 motivo kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Oct 27 02:03:03 motivo kernel: Freeing unused kernel memory: 4864K Oct 27 02:03:03 motivo kernel: Run /init as init process Oct 27 02:03:03 motivo kernel: with arguments: Oct 27 02:03:03 motivo kernel: /init Oct 27 02:03:03 motivo kernel: splash Oct 27 02:03:03 motivo kernel: nodebug Oct 27 02:03:03 motivo kernel: with environment: Oct 27 02:03:03 motivo kernel: HOME=/ Oct 27 02:03:03 motivo kernel: TERM=linux Oct 27 02:03:03 motivo kernel: imgpart=UUID=aa29d975-d4d2-48dd-9a94-d7ad7157c5e8 Oct 27 02:03:03 motivo kernel: imgfile=/volumio_current.sqsh Oct 27 02:03:03 motivo kernel: bootpart=UUID=EE6E-1229 Oct 27 02:03:03 motivo kernel: datapart=UUID=cddde018-c4e7-47b9-a698-1af8f5bdeffb Oct 27 02:03:03 motivo kernel: uuidconfig=cmdline.txt Oct 27 02:03:03 motivo kernel: bootdelay=7 Oct 27 02:03:03 motivo kernel: use_kmsg=no Oct 27 02:03:03 motivo kernel: mmc1: new high speed SDIO card at address 0001 Oct 27 02:03:03 motivo kernel: mmc0: new DDR MMC card at address 0001 Oct 27 02:03:03 motivo kernel: mmcblk0: mmc0:0001 AJTD4R 14.6 GiB Oct 27 02:03:03 motivo kernel: mmcblk0: p1 p2 p3 Oct 27 02:03:03 motivo kernel: mmcblk0: mmc0:0001 AJTD4R 14.6 GiB Oct 27 02:03:03 motivo kernel: mmcblk0boot0: mmc0:0001 AJTD4R 4.00 MiB Oct 27 02:03:03 motivo kernel: mmcblk0boot1: mmc0:0001 AJTD4R 4.00 MiB Oct 27 02:03:03 motivo kernel: mmcblk0rpmb: mmc0:0001 AJTD4R 4.00 MiB, chardev (243:0) Oct 27 02:03:03 motivo kernel: usb 1-1: new high-speed USB device number 2 using xhci-hcd Oct 27 02:03:03 motivo kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3 Oct 27 02:03:03 motivo kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Oct 27 02:03:03 motivo kernel: hub 1-1:1.0: USB hub found Oct 27 02:03:03 motivo kernel: hub 1-1:1.0: 4 ports detected Oct 27 02:03:03 motivo kernel: usb 1-1.3: new full-speed USB device number 3 using xhci-hcd Oct 27 02:03:03 motivo kernel: usb 1-1.3: New USB device found, idVendor=2e8a, idProduct=0005, bcdDevice= 1.00 Oct 27 02:03:03 motivo kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Oct 27 02:03:03 motivo kernel: usb 1-1.3: Product: Board in FS mode Oct 27 02:03:03 motivo kernel: usb 1-1.3: Manufacturer: MicroPython Oct 27 02:03:03 motivo kernel: usb 1-1.3: SerialNumber: 1120022408010011 Oct 27 02:03:03 motivo kernel: fuse: init (API version 7.39) Oct 27 02:03:03 motivo kernel: usb 1-1.4: new high-speed USB device number 4 using xhci-hcd Oct 27 02:03:03 motivo kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 27 02:03:03 motivo kernel: usb 1-1.4: New USB device found, idVendor=16d0, idProduct=0a23, bcdDevice= 1.9b Oct 27 02:03:03 motivo kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Oct 27 02:03:03 motivo kernel: usb 1-1.4: Product: Combo384 Amanero Oct 27 02:03:03 motivo kernel: usb 1-1.4: Manufacturer: Amanero Technologies Oct 27 02:03:03 motivo kernel: usb 1-1.4: SerialNumber: 415-001 Oct 27 02:03:03 motivo kernel: EXT4-fs (mmcblk0p2): recovery complete Oct 27 02:03:03 motivo kernel: EXT4-fs (mmcblk0p2): mounted filesystem aa29d975-d4d2-48dd-9a94-d7ad7157c5e8 r/w with ordered data mode. Quota mode: none. Oct 27 02:03:03 motivo kernel: loop0: detected capacity change from 0 to 1416864 Oct 27 02:03:03 motivo kernel: EXT4-fs (mmcblk0p3): recovery complete Oct 27 02:03:03 motivo kernel: EXT4-fs (mmcblk0p3): mounted filesystem cddde018-c4e7-47b9-a698-1af8f5bdeffb r/w with ordered data mode. Quota mode: none. Oct 27 02:03:03 motivo systemd[1]: System time before build time, advancing clock. Oct 27 02:03:03 motivo kernel: NET: Registered PF_INET6 protocol family Oct 27 02:03:03 motivo kernel: Segment Routing with IPv6 Oct 27 02:03:03 motivo kernel: In-situ OAM (IOAM) with IPv6 Oct 27 02:03:03 motivo 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) Oct 27 02:03:03 motivo systemd[1]: Detected architecture arm64. Oct 27 02:03:03 motivo systemd[1]: Set hostname to . Oct 27 02:03:03 motivo 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. Oct 27 02:03:03 motivo systemd[1]: Listening on Journal Audit Socket. Oct 27 02:03:03 motivo systemd[1]: Listening on udev Control Socket. Oct 27 02:03:03 motivo systemd[1]: Listening on RPCbind Server Activation Socket. Oct 27 02:03:03 motivo systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 27 02:03:03 motivo systemd[1]: Listening on udev Kernel Socket. Oct 27 02:03:03 motivo systemd[1]: Listening on Journal Socket. Oct 27 02:03:03 motivo kernel: i2c_dev: i2c /dev entries driver Oct 27 02:03:03 motivo systemd-journald[316]: Journal started Oct 27 02:03:03 motivo systemd-journald[316]: Runtime journal (/run/log/journal/954740ce280c089d7ced5b1d669e79fc) is 3.7M, max 30.0M, 26.2M free. Oct 27 02:03:03 motivo systemd-modules-load[308]: Inserted module 'i2c_dev' Oct 27 02:03:03 motivo fake-hwclock[304]: Sun Oct 27 02:03:03 UTC 2024 Oct 27 02:03:03 motivo systemd[1]: Started Create System Users. Oct 27 02:03:03 motivo systemd[1]: Starting Create Static Device Nodes in /dev... Oct 27 02:03:03 motivo systemd[1]: Started udev Coldplug all Devices. Oct 27 02:03:03 motivo systemd[1]: Starting Helper to synchronize boot up for ifupdown... Oct 27 02:03:03 motivo systemd[1]: Started Create Static Device Nodes in /dev. Oct 27 02:03:03 motivo systemd[1]: Starting udev Kernel Device Manager... Oct 27 02:03:03 motivo systemd[1]: Reached target Local File Systems (Pre). Oct 27 02:03:03 motivo systemd[1]: Mounting /var/spool/cups... Oct 27 02:03:03 motivo systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Oct 27 02:03:03 motivo systemd[1]: Mounting /var/log... Oct 27 02:03:03 motivo systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Oct 27 02:03:03 motivo systemd[1]: Mounting /tmp... Oct 27 02:03:03 motivo systemd[1]: Mounted /var/spool/cups. Oct 27 02:03:03 motivo systemd[1]: Mounted /var/log. Oct 27 02:03:03 motivo systemd[1]: Mounted /tmp. Oct 27 02:03:03 motivo systemd[1]: Starting Flush Journal to Persistent Storage... Oct 27 02:03:03 motivo systemd[1]: Mounting /var/spool/cups/tmp... Oct 27 02:03:03 motivo systemd[1]: Mounted /var/spool/cups/tmp. Oct 27 02:03:03 motivo systemd-udevd[336]: Network interface NamePolicy= disabled on kernel command line, ignoring. Oct 27 02:03:03 motivo systemd-journald[316]: Runtime journal (/run/log/journal/954740ce280c089d7ced5b1d669e79fc) is 3.7M, max 30.0M, 26.2M free. Oct 27 02:03:03 motivo systemd[1]: Started Flush Journal to Persistent Storage. Oct 27 02:03:03 motivo systemd[1]: Started udev Kernel Device Manager. Oct 27 02:03:03 motivo systemd[1]: Starting Show Plymouth Boot Screen... Oct 27 02:03:03 motivo systemd[1]: Received SIGRTMIN+20 from PID 360 (plymouthd). Oct 27 02:03:03 motivo systemd[1]: Started Show Plymouth Boot Screen. Oct 27 02:03:03 motivo systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Oct 27 02:03:03 motivo systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Oct 27 02:03:03 motivo systemd[1]: Reached target Paths. Oct 27 02:03:03 motivo systemd[1]: Reached target Local Encrypted Volumes. Oct 27 02:03:03 motivo systemd-udevd[345]: Using default interface naming scheme 'v240'. Oct 27 02:03:03 motivo kernel: mc: Linux media interface: v0.10 Oct 27 02:03:03 motivo systemd-udevd[345]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 27 02:03:03 motivo kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Oct 27 02:03:03 motivo kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Oct 27 02:03:03 motivo kernel: [vc_sm_connected_init]: start Oct 27 02:03:03 motivo kernel: [vc_sm_connected_init]: installed successfully Oct 27 02:03:04 motivo kernel: videodev: Linux video capture interface: v2.00 Oct 27 02:03:04 motivo kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Oct 27 02:03:04 motivo kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Oct 27 02:03:04 motivo kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Oct 27 02:03:04 motivo kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Oct 27 02:03:04 motivo kernel: cdc_acm 1-1.3:1.0: ttyACM0: USB ACM device Oct 27 02:03:04 motivo kernel: usbcore: registered new interface driver cdc_acm Oct 27 02:03:04 motivo kernel: cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters Oct 27 02:03:04 motivo kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Oct 27 02:03:04 motivo kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Oct 27 02:03:04 motivo kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Oct 27 02:03:04 motivo kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Oct 27 02:03:04 motivo kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Oct 27 02:03:04 motivo kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Oct 27 02:03:04 motivo kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Oct 27 02:03:04 motivo kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Oct 27 02:03:04 motivo kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Oct 27 02:03:04 motivo kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Oct 27 02:03:04 motivo kernel: rpivid feb10000.codec: Device registered as /dev/video19 Oct 27 02:03:04 motivo kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Oct 27 02:03:04 motivo kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Oct 27 02:03:04 motivo kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Oct 27 02:03:04 motivo kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Oct 27 02:03:04 motivo kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Oct 27 02:03:04 motivo kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Oct 27 02:03:04 motivo kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Oct 27 02:03:04 motivo kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Oct 27 02:03:04 motivo kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Oct 27 02:03:04 motivo kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Oct 27 02:03:04 motivo kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Oct 27 02:03:04 motivo kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Oct 27 02:03:04 motivo kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Oct 27 02:03:04 motivo kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Oct 27 02:03:04 motivo kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Oct 27 02:03:04 motivo kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Oct 27 02:03:04 motivo kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Oct 27 02:03:04 motivo kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Oct 27 02:03:04 motivo kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Oct 27 02:03:04 motivo kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Oct 27 02:03:04 motivo kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Oct 27 02:03:04 motivo kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Oct 27 02:03:04 motivo systemd[1]: Found device /dev/disk/by-uuid/EE6E-1229. Oct 27 02:03:04 motivo kernel: brcmstb-i2c fef04500.i2c: @97500hz registered in polling mode Oct 27 02:03:04 motivo kernel: brcmstb-i2c fef09500.i2c: @97500hz registered in polling mode Oct 27 02:03:04 motivo kernel: Bluetooth: Core ver 2.22 Oct 27 02:03:04 motivo kernel: NET: Registered PF_BLUETOOTH protocol family Oct 27 02:03:04 motivo kernel: Bluetooth: HCI device and connection manager initialized Oct 27 02:03:04 motivo kernel: Bluetooth: HCI socket layer initialized Oct 27 02:03:04 motivo kernel: Bluetooth: L2CAP socket layer initialized Oct 27 02:03:04 motivo kernel: Bluetooth: SCO socket layer initialized Oct 27 02:03:04 motivo kernel: [drm] Initialized v3d 1.0.0 20180419 for fec00000.v3d on minor 0 Oct 27 02:03:04 motivo kernel: Bluetooth: HCI UART driver ver 2.3 Oct 27 02:03:04 motivo kernel: Bluetooth: HCI UART protocol H4 registered Oct 27 02:03:04 motivo kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Oct 27 02:03:04 motivo kernel: Bluetooth: HCI UART protocol Broadcom registered Oct 27 02:03:04 motivo kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Oct 27 02:03:04 motivo kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Oct 27 02:03:04 motivo kernel: vc4_dsi fe700000.dsi: Fixed dependency cycle(s) with /soc/dsi@7e700000/panel@0 Oct 27 02:03:04 motivo kernel: mipi-dsi fe700000.dsi.0: Fixed dependency cycle(s) with /soc/dsi@7e700000 Oct 27 02:03:04 motivo kernel: panel-dsi-mt fe700000.dsi.0: supply power not found, using dummy regulator Oct 27 02:03:04 motivo systemd[1]: Starting File System Check on /dev/disk/by-uuid/EE6E-1229... Oct 27 02:03:04 motivo kernel: vc4-drm gpu: bound fe400000.hvs (ops vc4_hvs_ops [vc4]) Oct 27 02:03:04 motivo kernel: Registered IR keymap rc-cec Oct 27 02:03:04 motivo kernel: rc rc0: vc4-hdmi-0 as /devices/platform/soc/fef00700.hdmi/rc/rc0 Oct 27 02:03:04 motivo kernel: input: vc4-hdmi-0 as /devices/platform/soc/fef00700.hdmi/rc/rc0/input0 Oct 27 02:03:04 motivo kernel: vc4_hdmi fef00700.hdmi: 'dmas' DT property is missing or empty, no HDMI audio Oct 27 02:03:04 motivo kernel: vc4-drm gpu: bound fef00700.hdmi (ops vc4_hdmi_ops [vc4]) Oct 27 02:03:04 motivo kernel: Registered IR keymap rc-cec Oct 27 02:03:04 motivo kernel: rc rc1: vc4-hdmi-1 as /devices/platform/soc/fef05700.hdmi/rc/rc1 Oct 27 02:03:04 motivo kernel: input: vc4-hdmi-1 as /devices/platform/soc/fef05700.hdmi/rc/rc1/input1 Oct 27 02:03:04 motivo kernel: vc4_hdmi fef05700.hdmi: 'dmas' DT property is missing or empty, no HDMI audio Oct 27 02:03:04 motivo kernel: vc4-drm gpu: bound fef05700.hdmi (ops vc4_hdmi_ops [vc4]) Oct 27 02:03:04 motivo kernel: vc4-drm gpu: bound fe700000.dsi (ops vc4_dsi_ops [vc4]) Oct 27 02:03:04 motivo kernel: vc4-drm gpu: bound fe004000.txp (ops vc4_txp_ops [vc4]) Oct 27 02:03:04 motivo kernel: vc4-drm gpu: bound fe206000.pixelvalve (ops vc4_crtc_ops [vc4]) Oct 27 02:03:04 motivo kernel: vc4-drm gpu: bound fe207000.pixelvalve (ops vc4_crtc_ops [vc4]) Oct 27 02:03:04 motivo kernel: vc4-drm gpu: bound fe20a000.pixelvalve (ops vc4_crtc_ops [vc4]) Oct 27 02:03:04 motivo kernel: vc4-drm gpu: bound fe216000.pixelvalve (ops vc4_crtc_ops [vc4]) Oct 27 02:03:04 motivo kernel: vc4-drm gpu: bound fec12000.pixelvalve (ops vc4_crtc_ops [vc4]) Oct 27 02:03:04 motivo kernel: [drm] Initialized vc4 0.0.0 20140616 for gpu on minor 1 Oct 27 02:03:04 motivo kernel: uart-pl011 fe201000.serial: no DMA platform data Oct 27 02:03:05 motivo kernel: usb 1-1.4: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Oct 27 02:03:05 motivo kernel: usb 1-1.4: [10] FU [PCM Playback Volume] ch = 2, val = -32767/0/1 Oct 27 02:03:05 motivo kernel: usb 1-1.4: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Oct 27 02:03:05 motivo kernel: usb 1-1.4: [10] FU [PCM Playback Volume] ch = 1, val = -32767/0/1 Oct 27 02:03:05 motivo kernel: usbcore: registered new interface driver snd-usb-audio Oct 27 02:03:05 motivo systemd-fsck[442]: fsck.fat 4.1 (2017-01-24) Oct 27 02:03:05 motivo systemd-fsck[442]: /dev/mmcblk0p1: 394 files, 23767/46774 clusters Oct 27 02:03:05 motivo systemd[1]: Started File System Check on /dev/disk/by-uuid/EE6E-1229. Oct 27 02:03:05 motivo systemd-udevd[346]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 27 02:03:05 motivo kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Oct 27 02:03:05 motivo kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Oct 27 02:03:05 motivo kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Oct 27 02:03:05 motivo kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Oct 27 02:03:05 motivo kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Oct 27 02:03:05 motivo kernel: Bluetooth: hci0: BCM: chip id 107 Oct 27 02:03:05 motivo kernel: Bluetooth: hci0: BCM: features 0x2f Oct 27 02:03:05 motivo kernel: Bluetooth: hci0: BCM4345C0 Oct 27 02:03:05 motivo kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Oct 27 02:03:05 motivo kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Oct 27 02:03:05 motivo kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Oct 27 02:03:05 motivo kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Oct 27 02:03:05 motivo kernel: brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,4-compute-module.bin failed with error -2 Oct 27 02:03:05 motivo kernel: usbcore: registered new interface driver brcmfmac Oct 27 02:03:05 motivo kernel: Console: switching to colour frame buffer device 160x50 Oct 27 02:03:05 motivo kernel: vc4-drm gpu: [drm] fb0: vc4drmfb frame buffer device Oct 27 02:03:05 motivo kernel: Goodix-TS 6-005d: supply AVDD28 not found, using dummy regulator Oct 27 02:03:05 motivo kernel: Goodix-TS 6-005d: supply VDDIO not found, using dummy regulator Oct 27 02:03:05 motivo kernel: wm8804 1-003b: revision E Oct 27 02:03:05 motivo kernel: Goodix-TS 6-005d: ID 911, version: 1060 Oct 27 02:03:05 motivo kernel: Goodix-TS 6-005d: Direct firmware load for goodix_911_cfg.bin failed with error -2 Oct 27 02:03:05 motivo kernel: input: 6-005d Goodix Capacitive TouchScreen as /devices/platform/soc/fe205c00.i2c/i2c-6/6-005d/input/input2 Oct 27 02:03:05 motivo kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Oct 27 02:03:05 motivo 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 Oct 27 02:03:05 motivo systemd-udevd[345]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 27 02:03:05 motivo systemd-udevd[343]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Oct 27 02:03:05 motivo kernel: Bluetooth: hci0: BCM: features 0x2f Oct 27 02:03:05 motivo kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Oct 27 02:03:05 motivo kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Oct 27 02:03:06 motivo systemd-udevd[358]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Oct 27 02:03:06 motivo systemd-udevd[355]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Oct 27 02:03:06 motivo systemd-udevd[346]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Oct 27 02:03:06 motivo systemd-udevd[347]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Oct 27 02:03:06 motivo kernel: alsactl[514]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Oct 27 02:03:06 motivo systemd[1]: Found device /sys/subsystem/net/devices/eth0. Oct 27 02:03:06 motivo systemd[1]: Started Helper to synchronize boot up for ifupdown. Oct 27 02:03:07 motivo systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Oct 27 02:03:07 motivo systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Oct 27 02:03:07 motivo systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Oct 27 02:03:07 motivo systemd[1]: Created slice system-bthelper.slice. Oct 27 02:03:07 motivo systemd[1]: Condition check resulted in Huge Pages File System being skipped. Oct 27 02:03:07 motivo systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 27 02:03:07 motivo systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Oct 27 02:03:07 motivo systemd[1]: Created slice system-systemd\x2dbacklight.slice. Oct 27 02:03:07 motivo systemd[1]: Starting Load/Save Screen Backlight Brightness of backlight:rpi_backlight... Oct 27 02:03:07 motivo systemd[1]: Mounting /boot... Oct 27 02:03:07 motivo systemd[1]: Started File System Check Daemon to report status. Oct 27 02:03:07 motivo systemd[1]: Starting Load/Save RF Kill Switch Status... Oct 27 02:03:07 motivo systemd[1]: Started Load/Save Screen Backlight Brightness of backlight:rpi_backlight. Oct 27 02:03:07 motivo systemd[1]: Mounted /boot. Oct 27 02:03:07 motivo systemd[1]: Reached target Local File Systems. Oct 27 02:03:07 motivo systemd[1]: Starting Create Volatile Files and Directories... Oct 27 02:03:07 motivo systemd[1]: Started ifup for eth0. Oct 27 02:03:07 motivo systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Oct 27 02:03:07 motivo systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Oct 27 02:03:07 motivo systemd[1]: Started ifup for wlan0. Oct 27 02:03:07 motivo systemd[1]: Starting Raise network interfaces... Oct 27 02:03:07 motivo systemd[1]: Starting Preprocess NFS configuration... Oct 27 02:03:07 motivo systemd[1]: Started Load/Save RF Kill Switch Status. Oct 27 02:03:07 motivo systemd[1]: nfs-config.service: Succeeded. Oct 27 02:03:07 motivo systemd[1]: Started Preprocess NFS configuration. Oct 27 02:03:07 motivo systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Oct 27 02:03:07 motivo systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Oct 27 02:03:07 motivo systemd[1]: Reached target NFS client services. Oct 27 02:03:07 motivo systemd[1]: Started Create Volatile Files and Directories. Oct 27 02:03:07 motivo kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Oct 27 02:03:07 motivo systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Oct 27 02:03:07 motivo systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Oct 27 02:03:07 motivo systemd[1]: Starting Update UTMP about System Boot/Shutdown... Oct 27 02:03:07 motivo systemd[1]: Starting RPC bind portmap service... Oct 27 02:03:07 motivo systemd[1]: Started Update UTMP about System Boot/Shutdown. Oct 27 02:03:07 motivo systemd[1]: Started RPC bind portmap service. Oct 27 02:03:07 motivo systemd[1]: Reached target Remote File Systems (Pre). Oct 27 02:03:07 motivo systemd[1]: Reached target Remote File Systems. Oct 27 02:03:07 motivo systemd[1]: Reached target RPC Port Mapper. Oct 27 02:03:07 motivo kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 27 02:03:07 motivo kernel: 8021q: 802.1Q VLAN Support v1.8 Oct 27 02:03:07 motivo ifup[567]: ifup: waiting for lock on /run/network/ifstate.wlan0 Oct 27 02:03:07 motivo sh[564]: wlan0=wlan0 Oct 27 02:03:07 motivo kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Oct 27 02:03:07 motivo sh[562]: eth0: waiting for carrier Oct 27 02:03:07 motivo dhcpcd[617]: eth0: waiting for carrier Oct 27 02:03:07 motivo kernel: bcmgenet fd580000.ethernet eth0: Link is Down Oct 27 02:03:07 motivo systemd[1]: Started Raise network interfaces. Oct 27 02:03:07 motivo systemd[1]: Received SIGRTMIN+20 from PID 360 (plymouthd). Oct 27 02:03:07 motivo systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Oct 27 02:03:07 motivo systemd[1]: Reached target System Initialization. Oct 27 02:03:07 motivo systemd[1]: Started Daily apt download activities. Oct 27 02:03:07 motivo systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Oct 27 02:03:07 motivo systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 27 02:03:07 motivo systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 27 02:03:07 motivo systemd[1]: Started Daily apt upgrade and clean activities. Oct 27 02:03:07 motivo systemd[1]: Listening on triggerhappy.socket. Oct 27 02:03:07 motivo systemd[1]: Reached target Sockets. Oct 27 02:03:07 motivo systemd[1]: Reached target Basic System. Oct 27 02:03:08 motivo systemd[1]: Starting Configure Bluetooth Modems connected by UART... Oct 27 02:03:08 motivo systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Oct 27 02:03:08 motivo systemd[1]: Started Volumio Iptables Module. Oct 27 02:03:08 motivo systemd[1]: Started Manage Sound Card State (restore and store). Oct 27 02:03:08 motivo systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Oct 27 02:03:08 motivo systemd[1]: Starting dhcpcd on all interfaces... Oct 27 02:03:08 motivo alsactl[703]: alsactl 1.1.8 daemon started Oct 27 02:03:08 motivo systemd[1]: Started UPnP Renderer front-end to MPD. Oct 27 02:03:08 motivo systemd[1]: Started Volumio Log Rotation Service. Oct 27 02:03:08 motivo systemd[1]: Starting Save/Restore Sound Card State... Oct 27 02:03:08 motivo systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Oct 27 02:03:08 motivo dhcpcd[705]: Not running dhcpcd because /etc/network/interfaces Oct 27 02:03:08 motivo dhcpcd[705]: defines some interfaces that will use a Oct 27 02:03:08 motivo dhcpcd[705]: DHCP client or static address Oct 27 02:03:08 motivo systemd[1]: Started D-Bus System Message Bus. Oct 27 02:03:08 motivo systemd[1]: Starting WPA supplicant... Oct 27 02:03:08 motivo systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Oct 27 02:03:08 motivo systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Oct 27 02:03:08 motivo systemd[1]: Reached target Login Prompts. Oct 27 02:03:08 motivo systemd[1]: Starting Wireless Services... Oct 27 02:03:08 motivo systemd[1]: Starting triggerhappy global hotkey daemon... Oct 27 02:03:08 motivo systemd[1]: Started volumio-remote-updater.service. Oct 27 02:03:08 motivo systemd[1]: Starting Login Service... Oct 27 02:03:08 motivo systemd[1]: Started Daily man-db regeneration. Oct 27 02:03:08 motivo systemd[1]: Reached target Timers. Oct 27 02:03:08 motivo avahi-daemon[701]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Oct 27 02:03:08 motivo avahi-daemon[701]: Successfully dropped root privileges. Oct 27 02:03:08 motivo avahi-daemon[701]: avahi-daemon 0.7 starting up. Oct 27 02:03:08 motivo systemd[1]: hciuart.service: Succeeded. Oct 27 02:03:08 motivo systemd[1]: Started Configure Bluetooth Modems connected by UART. Oct 27 02:03:08 motivo systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Oct 27 02:03:08 motivo systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Oct 27 02:03:08 motivo systemd[1]: Failed to start dhcpcd on all interfaces. Oct 27 02:03:08 motivo systemd[1]: Started Save/Restore Sound Card State. Oct 27 02:03:08 motivo systemd[1]: Reached target Sound Card. Oct 27 02:03:08 motivo systemd[1]: Starting Raspberry Pi bluetooth helper... Oct 27 02:03:08 motivo thd[719]: Unable to parse trigger line: Oct 27 02:03:08 motivo thd[719]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Oct 27 02:03:08 motivo thd[719]: Unable to parse trigger line: Oct 27 02:03:08 motivo thd[719]: Found socket passed from systemd Oct 27 02:03:08 motivo systemd-logind[722]: New seat seat0. Oct 27 02:03:08 motivo systemd-logind[722]: Watching system buttons on /dev/input/event0 (vc4-hdmi-0) Oct 27 02:03:08 motivo systemd-logind[722]: Watching system buttons on /dev/input/event1 (vc4-hdmi-1) Oct 27 02:03:08 motivo bthelper[726]: Raspberry Pi BDADDR already set Oct 27 02:03:08 motivo volumio-remote-updater[720]: Error: No active session Oct 27 02:03:08 motivo volumio-remote-updater[720]: [2024-10-27 02:03:08] [info] asio async_connect error: system:111 (Connection refused) Oct 27 02:03:08 motivo volumio-remote-updater[720]: [2024-10-27 02:03:08] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 27 02:03:08 motivo volumio-remote-updater[720]: [2024-10-27 02:03:08] [error] handle_connect error: Underlying Transport Error Oct 27 02:03:08 motivo dbus-daemon[714]: [system] Successfully activated service 'org.freedesktop.systemd1' Oct 27 02:03:08 motivo avahi-daemon[701]: Successfully called chroot(). Oct 27 02:03:08 motivo avahi-daemon[701]: Successfully dropped remaining capabilities. Oct 27 02:03:08 motivo systemd[1]: Started Avahi mDNS/DNS-SD Stack. Oct 27 02:03:08 motivo avahi-daemon[701]: No service file found in /etc/avahi/services. Oct 27 02:03:08 motivo systemd[1]: Started Login Service. Oct 27 02:03:08 motivo wpa_supplicant[716]: Successfully initialized wpa_supplicant Oct 27 02:03:08 motivo systemd[1]: Started triggerhappy global hotkey daemon. Oct 27 02:03:08 motivo systemd[1]: Started Raspberry Pi bluetooth helper. Oct 27 02:03:08 motivo avahi-daemon[701]: Network interface enumeration completed. Oct 27 02:03:08 motivo avahi-daemon[701]: Server startup complete. Host name is motivo.local. Local service cookie is 964762712. Oct 27 02:03:08 motivo volumio[707]: Could not open config: /tmp/upmpdcli.conf Oct 27 02:03:08 motivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 27 02:03:08 motivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 27 02:03:08 motivo systemd[1]: Started WPA supplicant. Oct 27 02:03:09 motivo systemd[1]: Reached target Network. Oct 27 02:03:09 motivo systemd[1]: Starting Network Time Service... Oct 27 02:03:09 motivo systemd[1]: Starting Permit User Sessions... Oct 27 02:03:09 motivo systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Oct 27 02:03:09 motivo systemd[1]: Reached target Network is Online. Oct 27 02:03:09 motivo systemd[1]: Starting LSB: Brings up/down network automatically... Oct 27 02:03:09 motivo systemd[1]: Starting Samba NMB Daemon... Oct 27 02:03:09 motivo systemd[1]: Starting /etc/rc.local Compatibility... Oct 27 02:03:09 motivo systemd[1]: Starting Bluetooth service... Oct 27 02:03:09 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 27 02:03:09 motivo systemd[1]: Started Permit User Sessions. Oct 27 02:03:09 motivo systemd[1]: Started /etc/rc.local Compatibility. Oct 27 02:03:09 motivo systemd[1]: iptables.service: Succeeded. Oct 27 02:03:09 motivo ifplugd(eth0)[791]: ifplugd 0.28 initializing. Oct 27 02:03:09 motivo ifplugd(eth0)[791]: Using interface eth0/D8:3A:DD:B1:DB:34 with driver (version: 6.6.30-v8+) Oct 27 02:03:09 motivo ifplugd(eth0)[791]: Using detection mode: SIOCETHTOOL Oct 27 02:03:09 motivo ifplugd(eth0)[791]: Initialization complete, link beat not detected. Oct 27 02:03:09 motivo ifplugd[758]: Network Interface Plugging Daemon...start eth0...done. Oct 27 02:03:09 motivo systemd[1]: Started LSB: Brings up/down network automatically. Oct 27 02:03:09 motivo loadcpufreq[713]: Loading cpufreq kernel modules...done (none). Oct 27 02:03:09 motivo ntpd[754]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 27 02:03:09 motivo ntpd[754]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Oct 27 02:03:09 motivo systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Oct 27 02:03:09 motivo systemd[1]: Started Network Time Service. Oct 27 02:03:09 motivo bluetoothd[765]: Bluetooth daemon 5.50 Oct 27 02:03:09 motivo systemd[1]: Starting LSB: set CPUFreq kernel parameters... Oct 27 02:03:09 motivo bluetoothd[765]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Oct 27 02:03:09 motivo systemd[1]: Started Bluetooth service. Oct 27 02:03:09 motivo systemd[1]: Reached target Bluetooth. Oct 27 02:03:09 motivo ntpd[808]: proto: precision = 2.389 usec (-19) Oct 27 02:03:09 motivo bluetoothd[765]: Starting SDP server Oct 27 02:03:09 motivo bluetoothd[765]: Excluding (cli) sap Oct 27 02:03:09 motivo ntpd[808]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 27 02:03:09 motivo ntpd[808]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 27 02:03:09 motivo ntpd[808]: Listen and drop on 0 v6wildcard [::]:123 Oct 27 02:03:09 motivo ntpd[808]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 27 02:03:09 motivo ntpd[808]: Listen normally on 2 lo 127.0.0.1:123 Oct 27 02:03:09 motivo ntpd[808]: Listening on routing socket on fd #19 for interface updates Oct 27 02:03:09 motivo ntpd[808]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 27 02:03:09 motivo ntpd[808]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 27 02:03:09 motivo kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Oct 27 02:03:09 motivo kernel: Bluetooth: BNEP filters: protocol multicast Oct 27 02:03:09 motivo kernel: Bluetooth: BNEP socket layer initialized Oct 27 02:03:09 motivo kernel: Bluetooth: MGMT ver 1.22 Oct 27 02:03:09 motivo bluetoothd[765]: Bluetooth management interface 1.22 initialized Oct 27 02:03:09 motivo dbus-daemon[714]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=765 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Oct 27 02:03:09 motivo bluetoothd[765]: Failed to set privacy: Rejected (0x0b) Oct 27 02:03:09 motivo cpufrequtils[809]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Oct 27 02:03:09 motivo systemd[1]: Started LSB: set CPUFreq kernel parameters. Oct 27 02:03:09 motivo systemd[1]: Starting Hostname Service... Oct 27 02:03:10 motivo dbus-daemon[714]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 27 02:03:10 motivo systemd[1]: Started Hostname Service. Oct 27 02:03:10 motivo nmbd[771]: [2024/10/27 02:03:10.335343, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Oct 27 02:03:10 motivo nmbd[771]: started asyncdns process 834 Oct 27 02:03:10 motivo nmbd[771]: [2024/10/27 02:03:10.337042, 0] ../lib/util/become_daemon.c:149(daemon_status) Oct 27 02:03:10 motivo nmbd[771]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Oct 27 02:03:10 motivo nmbd[771]: [2024/10/27 02:03:10.337157, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Oct 27 02:03:10 motivo nmbd[771]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Oct 27 02:03:10 motivo haveged[597]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Oct 27 02:03:10 motivo haveged[597]: haveged: cpu: (VC); data: 32K (V); inst: 48K (V); idx: 30/40; sz: 47828/63488 Oct 27 02:03:10 motivo haveged[597]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00275 Oct 27 02:03:10 motivo haveged[597]: haveged: fills: 0, generated: 0 Oct 27 02:03:10 motivo ntpd[808]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 27 02:03:10 motivo wireless.js[718]: Cleaning previous... Oct 27 02:03:10 motivo sudo[842]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 27 02:03:10 motivo sudo[842]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:10 motivo sudo[842]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:10 motivo sudo[844]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 27 02:03:10 motivo sudo[844]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:11 motivo sudo[844]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:11 motivo wireless.js[718]: Stopped aP Oct 27 02:03:11 motivo wireless.js[718]: Wireless Networking DISABLED, not starting wireless flow Oct 27 02:03:11 motivo systemd[1]: wireless.service: Succeeded. Oct 27 02:03:11 motivo systemd[1]: Started Wireless Services. Oct 27 02:03:11 motivo systemd[1]: Started Volumio Backend Module. Oct 27 02:03:11 motivo systemd[1]: Started Volumio Cpu Tweaker. Oct 27 02:03:11 motivo systemd[1]: Started Start Volumio Kiosk. Oct 27 02:03:11 motivo systemd[1]: Starting Terminate Plymouth Boot Screen... Oct 27 02:03:11 motivo systemd[1]: Starting Hold until boot process finishes up... Oct 27 02:03:11 motivo startkiosk.sh[854]: Starting Volumio Kiosk with arguments for cursor display Oct 27 02:03:11 motivo systemd[1]: Received SIGRTMIN+21 from PID 360 (plymouthd). Oct 27 02:03:11 motivo volumio-cpu-tweak[853]: Setting RT Priority for mpd Oct 27 02:03:11 motivo volumio-cpu-tweak[853]: pid 35's current scheduling policy: SCHED_OTHER Oct 27 02:03:11 motivo volumio-cpu-tweak[853]: pid 35's current scheduling priority: 0 Oct 27 02:03:11 motivo volumio-cpu-tweak[853]: Setting MPD Affinity Oct 27 02:03:11 motivo volumio-cpu-tweak[853]: pid 3's current affinity mask: f Oct 27 02:03:11 motivo volumio-cpu-tweak[853]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Oct 27 02:03:11 motivo volumio-cpu-tweak[853]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Oct 27 02:03:11 motivo systemd[1]: Received SIGRTMIN+21 from PID 360 (plymouthd). Oct 27 02:03:11 motivo systemd[1]: Started Hold until boot process finishes up. Oct 27 02:03:11 motivo systemd[1]: Started Terminate Plymouth Boot Screen. Oct 27 02:03:11 motivo systemd[1]: volumio_cpu_tweak.service: Succeeded. Oct 27 02:03:11 motivo ntpd[808]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 27 02:03:11 motivo startkiosk.sh[854]: X.Org X Server 1.20.4 Oct 27 02:03:11 motivo startkiosk.sh[854]: X Protocol Version 11, Revision 0 Oct 27 02:03:11 motivo startkiosk.sh[854]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Oct 27 02:03:11 motivo startkiosk.sh[854]: Current Operating System: Linux motivo 6.6.30-v8+ #1763 SMP PREEMPT Mon May 13 17:50:31 BST 2024 aarch64 Oct 27 02:03:11 motivo startkiosk.sh[854]: Kernel command line: coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_hdmi=0 snd_bcm2835.enable_hdmi=0 smsc95xx.macaddr=D8:3A:DD:B1:DB:34 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=aa29d975-d4d2-48dd-9a94-d7ad7157c5e8 imgfile=/volumio_current.sqsh bootpart=UUID=EE6E-1229 datapart=UUID=cddde018-c4e7-47b9-a698-1af8f5bdeffb 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=1 loglevel=0 nodebug use_kmsg=no Oct 27 02:03:11 motivo startkiosk.sh[854]: Build Date: 04 April 2023 07:50:56AM Oct 27 02:03:11 motivo startkiosk.sh[854]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Oct 27 02:03:11 motivo startkiosk.sh[854]: Current version of pixman: 0.36.0 Oct 27 02:03:11 motivo startkiosk.sh[854]: Before reporting problems, check http://wiki.x.org Oct 27 02:03:11 motivo startkiosk.sh[854]: to make sure that you have the latest version. Oct 27 02:03:11 motivo startkiosk.sh[854]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 27 02:03:11 motivo startkiosk.sh[854]: (++) from command line, (!!) notice, (II) informational, Oct 27 02:03:11 motivo startkiosk.sh[854]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 27 02:03:11 motivo startkiosk.sh[854]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Oct 27 02:03:11 2024 Oct 27 02:03:11 motivo startkiosk.sh[854]: (==) Using config directory: "/etc/X11/xorg.conf.d" Oct 27 02:03:11 motivo startkiosk.sh[854]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 27 02:03:11 motivo kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Oct 27 02:03:11 motivo sh[562]: eth0: carrier acquired Oct 27 02:03:11 motivo dhcpcd[617]: eth0: carrier acquired Oct 27 02:03:11 motivo sh[562]: DUID 00:01:00:01:2e:31:39:41:d8:3a:dd:b1:db:34 Oct 27 02:03:11 motivo sh[562]: eth0: IAID dd:b1:db:34 Oct 27 02:03:11 motivo sh[562]: eth0: adding address fe80::da3a:ddff:feb1:db34 Oct 27 02:03:11 motivo sh[562]: ipv6_addaddr1: Permission denied Oct 27 02:03:11 motivo dhcpcd[617]: DUID 00:01:00:01:2e:31:39:41:d8:3a:dd:b1:db:34 Oct 27 02:03:11 motivo dhcpcd[617]: eth0: IAID dd:b1:db:34 Oct 27 02:03:11 motivo dhcpcd[617]: eth0: adding address fe80::da3a:ddff:feb1:db34 Oct 27 02:03:11 motivo dhcpcd[617]: ipv6_addaddr1: Permission denied Oct 27 02:03:12 motivo sh[562]: eth0: soliciting an IPv6 router Oct 27 02:03:12 motivo dhcpcd[617]: eth0: soliciting an IPv6 router Oct 27 02:03:12 motivo sh[562]: eth0: soliciting a DHCP lease Oct 27 02:03:12 motivo dhcpcd[617]: eth0: soliciting a DHCP lease Oct 27 02:03:12 motivo systemd[1]: systemd-rfkill.service: Succeeded. Oct 27 02:03:12 motivo sh[562]: eth0: offered 79.168.68.144 from 85.138.111.254 Oct 27 02:03:12 motivo dhcpcd[617]: eth0: offered 79.168.68.144 from 85.138.111.254 Oct 27 02:03:12 motivo sh[562]: eth0: probing address 79.168.68.144/22 Oct 27 02:03:12 motivo dhcpcd[617]: eth0: probing address 79.168.68.144/22 Oct 27 02:03:12 motivo ifplugd(eth0)[791]: Link beat detected. Oct 27 02:03:12 motivo ntpd[808]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 27 02:03:12 motivo ifplugd(eth0)[791]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Oct 27 02:03:12 motivo ifplugd(eth0)[791]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Oct 27 02:03:12 motivo kernel: broken atomic modeset userspace detected, disabling atomic Oct 27 02:03:12 motivo startkiosk.sh[854]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Oct 27 02:03:13 motivo volumio[852]: info: ------------------------------------------- Oct 27 02:03:13 motivo volumio[852]: info: ----- Volumio3 ---- Oct 27 02:03:13 motivo volumio[852]: info: ------------------------------------------- Oct 27 02:03:13 motivo volumio[852]: info: ----- System startup ---- Oct 27 02:03:13 motivo volumio[852]: info: ------------------------------------------- Oct 27 02:03:13 motivo ntpd[808]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 27 02:03:13 motivo bthelper[726]: Changing power off succeeded Oct 27 02:03:13 motivo bthelper[726]: [CHG] Controller D8:3A:DD:B1:DB:36 Powered: no Oct 27 02:03:13 motivo bthelper[726]: [CHG] Controller D8:3A:DD:B1:DB:36 Discovering: no Oct 27 02:03:13 motivo bthelper[726]: [CHG] Controller D8:3A:DD:B1:DB:36 Class: 0x00000000 Oct 27 02:03:13 motivo volumio-remote-updater[720]: [2024-10-27 02:03:13] [connect] Successful connection Oct 27 02:03:13 motivo bthelper[726]: [CHG] Controller D8:3A:DD:B1:DB:36 Class: 0x0000041c Oct 27 02:03:13 motivo bthelper[726]: Changing power on succeeded Oct 27 02:03:14 motivo volumio[852]: info: MYVOLUMIO Environment detected Oct 27 02:03:14 motivo volumio[852]: info: Plugin folders cleanup Oct 27 02:03:14 motivo volumio[852]: info: Scanning into folder /volumio/app/plugins/ Oct 27 02:03:14 motivo volumio[852]: info: Scanning category audio_interface Oct 27 02:03:14 motivo volumio[852]: info: Scanning category miscellanea Oct 27 02:03:14 motivo volumio[852]: info: Scanning category music_service Oct 27 02:03:14 motivo volumio[852]: info: Scanning category plugins.json Oct 27 02:03:14 motivo volumio[852]: info: Scanning category system_controller Oct 27 02:03:14 motivo volumio[852]: info: Scanning category user_interface Oct 27 02:03:14 motivo volumio[852]: info: Scanning into folder /data/plugins/ Oct 27 02:03:14 motivo volumio[852]: info: Plugin folders cleanup completed Oct 27 02:03:14 motivo volumio[852]: info: ------------------------------------------- Oct 27 02:03:14 motivo volumio[852]: info: ----- Core plugins startup ---- Oct 27 02:03:14 motivo volumio[852]: info: ------------------------------------------- Oct 27 02:03:14 motivo volumio[852]: info: Loading plugins from folder /volumio/app/plugins/ Oct 27 02:03:14 motivo volumio[852]: info: Adding plugin bluetooth to MyMusic Plugins Oct 27 02:03:14 motivo volumio[852]: info: Adding plugin multiroom to MyMusic Plugins Oct 27 02:03:14 motivo volumio[852]: info: Adding plugin upnp to MyMusic Plugins Oct 27 02:03:14 motivo volumio[852]: info: Adding plugin metavolumio to MyMusic Plugins Oct 27 02:03:14 motivo volumio[852]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 27 02:03:14 motivo volumio[852]: info: Adding plugin cd_controller to MyMusic Plugins Oct 27 02:03:14 motivo volumio[852]: info: Adding plugin raat to MyMusic Plugins Oct 27 02:03:14 motivo volumio[852]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 27 02:03:14 motivo volumio[852]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 27 02:03:14 motivo volumio[852]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 27 02:03:14 motivo volumio[852]: info: Loading plugins from folder /data/plugins/ Oct 27 02:03:14 motivo volumio[852]: info: Loading plugin "system"... Oct 27 02:03:14 motivo volumio[852]: info: Loading plugin "appearance"... Oct 27 02:03:15 motivo volumio[852]: info: Loading plugin "network"... Oct 27 02:03:15 motivo volumio[852]: info: Refreshing Cached IP Addresses Oct 27 02:03:15 motivo volumio[852]: info: Loading plugin "services"... Oct 27 02:03:15 motivo sudo[973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 27 02:03:15 motivo volumio[852]: info: Loading plugin "alsa_controller"... Oct 27 02:03:15 motivo sudo[973]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:15 motivo sudo[973]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:15 motivo sudo[975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 27 02:03:15 motivo sudo[975]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:15 motivo sudo[975]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:15 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 27 02:03:15 motivo volumio[852]: info: Loading plugin "wizard"... Oct 27 02:03:15 motivo volumio[852]: info: Loading plugin "networkfs"... Oct 27 02:03:15 motivo volumio[852]: info: Starting Udev Watcher for removable devices Oct 27 02:03:15 motivo sudo[983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=morais.renato@gmail.com,password=saromesd1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.26/Music /mnt/NAS/Music_PC Oct 27 02:03:15 motivo sudo[983]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:15 motivo volumio[852]: info: Ignoring mount for partition: boot Oct 27 02:03:15 motivo volumio[852]: info: Ignoring mount for partition: volumio Oct 27 02:03:15 motivo volumio[852]: info: Ignoring mount for partition: volumio_data Oct 27 02:03:15 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 27 02:03:15 motivo volumio[852]: info: Loading plugin "volumio_command_line_client"... Oct 27 02:03:15 motivo volumio[852]: info: Loading plugin "upnp"... Oct 27 02:03:15 motivo volumio[852]: info: [1729994595952] Starting Upmpd Daemon Oct 27 02:03:15 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 27 02:03:15 motivo volumio[852]: info: Loading plugin "my_music"... Oct 27 02:03:15 motivo volumio[852]: info: Loading plugin "mpd"... Oct 27 02:03:16 motivo kernel: Key type cifs.spnego registered Oct 27 02:03:16 motivo kernel: Key type cifs.idmap registered Oct 27 02:03:16 motivo 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. Oct 27 02:03:16 motivo kernel: CIFS: Attempting to mount //192.168.1.26/Music Oct 27 02:03:16 motivo kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 27 02:03:16 motivo kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Oct 27 02:03:16 motivo sudo[983]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:16 motivo volumio[852]: info: Loading plugin "upnp_browser"... Oct 27 02:03:16 motivo volumio[852]: info: Plugin bluetooth is not enabled Oct 27 02:03:16 motivo volumio[852]: info: Loading plugin "alarm-clock"... Oct 27 02:03:16 motivo volumio[852]: info: Loading plugin "manifestui"... Oct 27 02:03:16 motivo volumio[852]: info: Loading plugin "metavolumio"... Oct 27 02:03:17 motivo sh[562]: eth0: leased 79.168.68.144 for 14400 seconds Oct 27 02:03:17 motivo dhcpcd[617]: eth0: leased 79.168.68.144 for 14400 seconds Oct 27 02:03:17 motivo sh[562]: eth0: adding route to 79.168.68.0/22 Oct 27 02:03:17 motivo sh[562]: eth0: adding default route via 79.168.71.254 Oct 27 02:03:17 motivo avahi-daemon[701]: Joining mDNS multicast group on interface eth0.IPv4 with address 79.168.68.144. Oct 27 02:03:17 motivo avahi-daemon[701]: New relevant interface eth0.IPv4 for mDNS. Oct 27 02:03:17 motivo dhcpcd[617]: eth0: adding route to 79.168.68.0/22 Oct 27 02:03:17 motivo avahi-daemon[701]: Registering new address record for 79.168.68.144 on eth0.IPv4. Oct 27 02:03:17 motivo dhcpcd[617]: eth0: adding default route via 79.168.71.254 Oct 27 02:03:17 motivo ntpd[808]: ntpd exiting on signal 15 (Terminated) Oct 27 02:03:17 motivo systemd[1]: Stopping Network Time Service... Oct 27 02:03:17 motivo systemd[1]: ntp.service: Succeeded. Oct 27 02:03:17 motivo systemd[1]: Stopped Network Time Service. Oct 27 02:03:17 motivo systemd[1]: Starting Network Time Service... Oct 27 02:03:17 motivo ntpd[1027]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 27 02:03:17 motivo ntpd[1027]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Oct 27 02:03:17 motivo systemd[1]: Started Network Time Service. Oct 27 02:03:17 motivo ntpd[1033]: proto: precision = 0.908 usec (-20) Oct 27 02:03:17 motivo ntpd[1033]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 27 02:03:17 motivo ntpd[1033]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 27 02:03:17 motivo sh[562]: forked to background, child pid 1034 Oct 27 02:03:17 motivo ntpd[1033]: Listen and drop on 0 v6wildcard [::]:123 Oct 27 02:03:17 motivo dhcpcd[617]: forked to background, child pid 1034 Oct 27 02:03:17 motivo ntpd[1033]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 27 02:03:17 motivo ntpd[1033]: Listen normally on 2 lo 127.0.0.1:123 Oct 27 02:03:17 motivo ntpd[1033]: Listen normally on 3 eth0 79.168.68.144:123 Oct 27 02:03:17 motivo ntpd[1033]: Listening on routing socket on fd #20 for interface updates Oct 27 02:03:17 motivo ntpd[1033]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 27 02:03:17 motivo ntpd[1033]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 27 02:03:17 motivo ifplugd(eth0)[791]: client: ifup: interface eth0 already configured Oct 27 02:03:17 motivo sh[562]: eth0=eth0 Oct 27 02:03:17 motivo ifplugd(eth0)[791]: Program executed successfully. Oct 27 02:03:17 motivo volumio[852]: info: Plugin airplay_emulation is not enabled Oct 27 02:03:17 motivo volumio[852]: info: Plugin cd_controller is not enabled Oct 27 02:03:17 motivo volumio[852]: info: Loading plugin "last_100"... Oct 27 02:03:17 motivo volumio[852]: info: Loading plugin "raat"... Oct 27 02:03:18 motivo volumio[852]: info: RAAT Plugin loaded Oct 27 02:03:18 motivo volumio[852]: info: Adding restartRAATSocket REST API Endpoint Oct 27 02:03:18 motivo volumio[852]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Oct 27 02:03:18 motivo volumio[852]: info: Loading plugin "streaming_services"... Oct 27 02:03:18 motivo ntpd[1033]: Soliciting pool server 85.138.124.211 Oct 27 02:03:19 motivo ntpd[1033]: Soliciting pool server 162.159.200.123 Oct 27 02:03:19 motivo ntpd[1033]: Soliciting pool server 23.147.168.132 Oct 27 02:03:19 motivo volumio[852]: info: Starting Streaming Service Transparent Proxy Oct 27 02:03:19 motivo volumio[852]: info: Plugin tidalconnect is not enabled Oct 27 02:03:19 motivo volumio[852]: info: Loading plugin "webradio"... Oct 27 02:03:19 motivo volumio[852]: info: Loading plugin "i2s_dacs"... Oct 27 02:03:19 motivo volumio[852]: info: I2S DAC not set, start Auto-detection Oct 27 02:03:19 motivo volumio[852]: info: Loading plugin "volumiodiscovery"... Oct 27 02:03:20 motivo volumio[852]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 27 02:03:20 motivo volumio[852]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 27 02:03:20 motivo volumio[852]: *** WARNING *** For more information see Oct 27 02:03:20 motivo node[852]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 27 02:03:20 motivo volumio[852]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 27 02:03:20 motivo volumio[852]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 27 02:03:20 motivo volumio[852]: *** WARNING *** For more information see Oct 27 02:03:20 motivo node[852]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 27 02:03:20 motivo node[852]: *** WARNING *** For more information see Oct 27 02:03:20 motivo node[852]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 27 02:03:20 motivo node[852]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 27 02:03:20 motivo node[852]: *** WARNING *** For more information see Oct 27 02:03:20 motivo volumio[852]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 27 02:03:20 motivo volumio[852]: info: Discovery: Started advertising with name: Motivo Oct 27 02:03:20 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 27 02:03:20 motivo volumio[852]: info: Loading plugin "multiroom"... Oct 27 02:03:20 motivo nmbd[771]: [2024/10/27 02:03:20.342191, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 27 02:03:20 motivo systemd[1]: Started Samba NMB Daemon. Oct 27 02:03:20 motivo nmbd[771]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 27 02:03:20 motivo systemd[1]: Starting Samba Winbind Daemon... Oct 27 02:03:20 motivo ntpd[1033]: Soliciting pool server 193.136.164.4 Oct 27 02:03:20 motivo ntpd[1033]: Soliciting pool server 194.117.47.42 Oct 27 02:03:20 motivo ntpd[1033]: Soliciting pool server 23.147.168.177 Oct 27 02:03:20 motivo volumio[852]: STREAMING PROXY: Starting server on port 3245 Oct 27 02:03:20 motivo volumio[852]: Node JS runtime: 14 Oct 27 02:03:20 motivo winbindd[1094]: [2024/10/27 02:03:20.602579, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Oct 27 02:03:20 motivo winbindd[1094]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 27 02:03:20 motivo winbindd[1094]: [2024/10/27 02:03:20.613133, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 27 02:03:20 motivo winbindd[1094]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 27 02:03:20 motivo systemd[1]: Started Samba Winbind Daemon. Oct 27 02:03:20 motivo systemd[1]: Starting Samba SMB Daemon... Oct 27 02:03:20 motivo smbd[1099]: [2024/10/27 02:03:20.966079, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 27 02:03:20 motivo smbd[1099]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 27 02:03:20 motivo systemd[1]: Started Samba SMB Daemon. Oct 27 02:03:20 motivo systemd[1]: Reached target Multi-User System. Oct 27 02:03:20 motivo systemd[1]: Reached target Graphical Interface. Oct 27 02:03:21 motivo systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 27 02:03:21 motivo systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 27 02:03:21 motivo systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 27 02:03:21 motivo systemd[1]: Startup finished in 13.320s (kernel) + 19.284s (userspace) = 32.605s. Oct 27 02:03:21 motivo ntpd[1033]: Soliciting pool server 23.147.168.176 Oct 27 02:03:21 motivo ntpd[1033]: Soliciting pool server 162.159.200.1 Oct 27 02:03:21 motivo ntpd[1033]: Soliciting pool server 91.209.16.78 Oct 27 02:03:22 motivo volumio[852]: info: Applying required configuration parameters for plugin multiroom Oct 27 02:03:22 motivo sudo[1162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 27 02:03:22 motivo sudo[1162]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:22 motivo sudo[1162]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:22 motivo volumio[852]: info: MRS: MultiRoom plugin initialized Oct 27 02:03:22 motivo volumio[852]: info: MRS: STOPPING SNAPCLIENT Oct 27 02:03:22 motivo volumio[852]: info: MRS: Snap server stop Oct 27 02:03:22 motivo volumio[852]: info: MRS: STOPPING volumioStreaming Oct 27 02:03:22 motivo sudo[1183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 27 02:03:22 motivo sudo[1185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 27 02:03:22 motivo sudo[1183]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:22 motivo sudo[1185]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:22 motivo ntpd[1033]: Soliciting pool server 2602:faa5:131::132 Oct 27 02:03:22 motivo volumio[852]: info: Loading plugin "outputs"... Oct 27 02:03:22 motivo volumio[852]: info: Loading plugin "albumart"... Oct 27 02:03:22 motivo sudo[1189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 27 02:03:22 motivo sudo[1191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 27 02:03:22 motivo sudo[1189]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:22 motivo sudo[1191]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:22 motivo ntpd[1033]: Soliciting pool server 23.147.168.175 Oct 27 02:03:22 motivo sudo[1191]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:22 motivo volumio[852]: info: Plugin example_plugin is not enabled Oct 27 02:03:22 motivo volumio[852]: info: Loading plugin "hi_res_audio"... Oct 27 02:03:22 motivo sudo[1183]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:22 motivo sudo[1185]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:22 motivo sudo[1189]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:22 motivo volumio[852]: Forking 3 albumart workers Oct 27 02:03:23 motivo ntpd[1033]: Soliciting pool server 193.136.152.72 Oct 27 02:03:23 motivo volumio[852]: Starting albumart workers Oct 27 02:03:24 motivo volumio[852]: Starting albumart workers Oct 27 02:03:24 motivo volumio[852]: Starting albumart workers Oct 27 02:03:24 motivo ntpd[1033]: Soliciting pool server 194.117.47.44 Oct 27 02:03:49 motivo volumio[852]: info: Applying required configuration parameters for plugin hi_res_audio Oct 27 02:03:49 motivo volumio[852]: info: Loading plugin "inputs"... Oct 27 02:03:49 motivo volumio-remote-updater[720]: [2024-10-27 02:03:49] [connect] Successful connection Oct 27 02:03:50 motivo volumio[852]: info: Loading plugin "qobuz"... Oct 27 02:03:51 motivo volumio[852]: info: Plugin smart_inputs is not enabled Oct 27 02:03:51 motivo volumio[852]: info: Loading plugin "tidal"... Oct 27 02:03:52 motivo volumio[852]: info: Loading plugin "motivocontrol"... Oct 27 02:03:52 motivo volumio[852]: info: Initializing System Ready GPIO for kernel version: 6.6.30-v8+ Oct 27 02:03:52 motivo volumio[852]: info: Adding this device properties Oct 27 02:03:52 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Oct 27 02:03:52 motivo volumio[852]: info: Setting Additional Device Volumio Properties: [object Object] Oct 27 02:03:52 motivo volumio[852]: info: Checking For Motivo Hardware Oct 27 02:03:52 motivo volumio[852]: info: Loading plugin "updater_comm"... Oct 27 02:03:52 motivo volumio[852]: info: Plugin mpdemulation is not enabled Oct 27 02:03:52 motivo volumio[852]: info: Loading plugin "rest_api"... Oct 27 02:03:52 motivo volumio[852]: info: Loading plugin "websocket"... Oct 27 02:03:52 motivo volumio[852]: info: Starting Socket.io Server version 2.3.0 Oct 27 02:03:52 motivo volumio[852]: info: Loading i18n strings for locale en Oct 27 02:03:52 motivo volumio[852]: Updating browse sources language Oct 27 02:03:52 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 02:03:52 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 02:03:52 motivo volumio[852]: info: CoreCommandRouter::initPlayerControls Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 02:03:53 motivo volumio[852]: Express server listening on port 3000 Oct 27 02:03:53 motivo volumio[852]: [Metrics] WebUI: 16s 589.50ms Oct 27 02:03:53 motivo volumio[852]: info: CoreStateMachine::resetVolumioState Oct 27 02:03:53 motivo volumio[852]: info: CoreStateMachine::getcurrentVolume Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::volumioRetrievevolume Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 02:03:53 motivo sudo[1326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 27 02:03:53 motivo sudo[1326]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:53 motivo sudo[1326]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:53 motivo sudo[1328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 27 02:03:53 motivo sudo[1328]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:53 motivo sudo[1328]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:53 motivo volumio[852]: info: Motivo Hardware found Oct 27 02:03:53 motivo volumio[852]: info: MRS: Removed streaming files Oct 27 02:03:53 motivo volumio[852]: info: MRS: volumioStreaming STOPPED Oct 27 02:03:53 motivo volumio[852]: info: MRS: SNAPSERVER STOPPED Oct 27 02:03:53 motivo volumio[852]: info: MRS: SNAPCLIENT STOPPED Oct 27 02:03:53 motivo volumio[852]: info: Cannot mount NAS Music_PC at system boot, trial number 1 ,retrying in 5 seconds Oct 27 02:03:53 motivo volumio-remote-updater[720]: [2024-10-27 02:03:53] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1729994629 101 Oct 27 02:03:53 motivo volumio[852]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Oct 27 02:03:53 motivo volumio[852]: info: VolumeController:: Volume=46 Mute =false Oct 27 02:03:53 motivo volumio[852]: info: CoreStateMachine::pushState Oct 27 02:03:53 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::volumioPushState Oct 27 02:03:53 motivo volumio[852]: info: CoreStateMachine::updateTrackBlock Oct 27 02:03:53 motivo volumio[852]: info: CorePlayQueue::getTrackBlock Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::volumioRetrievevolume Oct 27 02:03:53 motivo volumio[852]: info: Reloading queue from file Oct 27 02:03:53 motivo volumio[852]: info: CoreStateMachine::setRepeat null single undefined Oct 27 02:03:53 motivo volumio[852]: info: CoreStateMachine::pushState Oct 27 02:03:53 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::volumioPushState Oct 27 02:03:53 motivo volumio[852]: info: CoreStateMachine::setRandom true Oct 27 02:03:53 motivo volumio[852]: info: CoreStateMachine::pushState Oct 27 02:03:53 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::volumioPushState Oct 27 02:03:53 motivo volumio[852]: info: Setting Device type: Raspberry PI Oct 27 02:03:53 motivo volumio[852]: info: Completed loading Core Plugins Oct 27 02:03:53 motivo volumio[852]: info: Preparing to generate the ALSA configuration file Oct 27 02:03:53 motivo sudo[1342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 27 02:03:53 motivo sudo[1342]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:53 motivo volumio[852]: info: VolumeController:: Volume=46 Mute =false Oct 27 02:03:53 motivo volumio[852]: info: CoreStateMachine::pushState Oct 27 02:03:53 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::volumioPushState Oct 27 02:03:53 motivo systemd[1]: Started UPnP Renderer front-end to MPD. Oct 27 02:03:53 motivo volumio[852]: info: Discovery: adding cb12514a-465b-43a3-a910-24b56f501396 Oct 27 02:03:53 motivo volumio[852]: info: Discovery: Found device Motivo Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::volumioGetState Oct 27 02:03:53 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:53 motivo volumio[852]: info: MRS: Pushing multiroomSync output for this device Oct 27 02:03:53 motivo volumio[852]: info: MRS: Pushing multiroomSync output Oct 27 02:03:53 motivo sudo[1342]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:53 motivo volumio[852]: info: Adding audio output: Oct 27 02:03:53 motivo volumio[852]: info: Adding audio output: Oct 27 02:03:53 motivo volumio[852]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 27 02:03:53 motivo volumio[852]: info: Reading ALSA contributions from plugins. Oct 27 02:03:53 motivo sudo[1346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 27 02:03:53 motivo sudo[1346]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:53 motivo volumio[852]: info: Upmpdcli Daemon Started Oct 27 02:03:53 motivo volumio[852]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Oct 27 02:03:53 motivo sudo[1348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 27 02:03:53 motivo sudo[1348]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:53 motivo sudo[1346]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:53 motivo volumio[852]: info: Asound.conf file unchanged, so no further update is needed Oct 27 02:03:53 motivo volumio[852]: info: Output device has changed, restarting MPD Oct 27 02:03:53 motivo sudo[1348]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:53 motivo sudo[1352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 27 02:03:53 motivo sudo[1352]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:53 motivo volumio[852]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:53 motivo sudo[1352]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:53 motivo sudo[1354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 27 02:03:53 motivo sudo[1354]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:53 motivo volumio[852]: info: ___________ START PLUGINS ___________ Oct 27 02:03:53 motivo volumio[852]: info: ControllerMpd::onStart: Initializing MPD Oct 27 02:03:53 motivo volumio[852]: info: Creating MPD Configuration file Oct 27 02:03:53 motivo sudo[1360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 27 02:03:53 motivo volumio[852]: info: [1729994633783] CoreMusicLibrary::Adding element Media Servers Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 02:03:53 motivo sudo[1360]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:53 motivo sudo[1360]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:53 motivo sudo[1363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 27 02:03:53 motivo sudo[1363]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:53 motivo systemd[1]: Listening on mpd.socket. Oct 27 02:03:53 motivo systemd[1]: Starting Music Player Daemon... Oct 27 02:03:53 motivo volumio[852]: info: Adding METAVOLUMIO REST API Endpoints Oct 27 02:03:53 motivo volumio[852]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 27 02:03:53 motivo volumio[852]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 27 02:03:53 motivo volumio[852]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 27 02:03:53 motivo volumio[852]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 27 02:03:53 motivo volumio[852]: info: [1729994633814] CoreMusicLibrary::Adding element Last_100 Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 02:03:53 motivo volumio[852]: info: Starting RAAT Plugin Oct 27 02:03:53 motivo systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 27 02:03:53 motivo systemd[1]: mpd.service: Succeeded. Oct 27 02:03:53 motivo systemd[1]: Stopped Music Player Daemon. Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Oct 27 02:03:53 motivo volumio[852]: info: Additional UI Settings Added for plugin music_service/raat Oct 27 02:03:53 motivo volumio[852]: info: Registering DSP Elements listener and retrieving current ones Oct 27 02:03:53 motivo volumio[852]: info: Additional DSP elements updated Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:53 motivo volumio[852]: info: Updating RAAT Signal Path Oct 27 02:03:53 motivo volumio[852]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 27 02:03:53 motivo systemd[1]: Starting Music Player Daemon... Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 02:03:53 motivo volumio[852]: info: Not Reporting Auto name since its the default one Oct 27 02:03:53 motivo volumio[852]: info: Streaming services startup Oct 27 02:03:53 motivo volumio[852]: info: Starting Streaming Daemon Oct 27 02:03:53 motivo sudo[1375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 27 02:03:53 motivo sudo[1375]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:53 motivo sudo[1368]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 27 02:03:53 motivo sudo[1368]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:53 motivo sudo[1368]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 27 02:03:53 motivo sudo[1368]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 27 02:03:53 motivo volumio[852]: info: [1729994633954] CoreMusicLibrary::Adding element Webradio Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 02:03:53 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 27 02:03:53 motivo volumio[852]: info: Initializing BBC Radios Oct 27 02:03:53 motivo sudo[1375]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 27 02:03:54 motivo volumio[852]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:54 motivo volumio[852]: error: Hi Res Audio Failed Login: Missing Login Data Oct 27 02:03:54 motivo volumio[852]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 27 02:03:54 motivo volumio[852]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 27 02:03:54 motivo volumio[852]: info: Initializing Serial Communication on port /dev/ttyAMA4 Oct 27 02:03:54 motivo volumio[852]: info: Touch Event Listener Process Starting Oct 27 02:03:54 motivo kernel: uart-pl011 fe201800.serial: no DMA platform data Oct 27 02:03:54 motivo volumio[852]: info: Volumio Calling Home Oct 27 02:03:54 motivo sudo[1397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Oct 27 02:03:54 motivo sudo[1397]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:54 motivo volumio[852]: (node:852) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Oct 27 02:03:54 motivo volumio[852]: (Use `node --trace-deprecation ...` to show where the warning was created) Oct 27 02:03:54 motivo volumio[852]: info: Stopping AccessToken refresher cron for QOBUZ Oct 27 02:03:54 motivo volumio[852]: info: AccessToken refresher cron started for QOBUZ Oct 27 02:03:54 motivo volumio[852]: info: Stopping AccessToken refresher cron Oct 27 02:03:54 motivo volumio[852]: info: AccessToken refresher cron started Oct 27 02:03:54 motivo volumio[852]: info: Adding TIDAL REST API Endpoints Oct 27 02:03:54 motivo volumio[852]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Oct 27 02:03:54 motivo volumio[852]: info: Adding QOBUZ REST API Endpoints Oct 27 02:03:54 motivo volumio[852]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Oct 27 02:03:54 motivo volumio[852]: info: Serial port opened successfully Oct 27 02:03:54 motivo volumio[852]: info: Sending serial start messages Oct 27 02:03:54 motivo volumio[852]: info: Display with brightness control is connected, setting display brightness Oct 27 02:03:54 motivo volumio[852]: info: Setting Display Brightness to 7 Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:54 motivo volumio[852]: error: Cannot start Volumio Streaming Daemon Oct 27 02:03:54 motivo volumio[852]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 27 02:03:54 motivo volumio[852]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 27 02:03:54 motivo volumio[852]: info: RAAT Albumart path created successfully Oct 27 02:03:54 motivo volumio[852]: info: MPD Permissions set Oct 27 02:03:54 motivo volumio[852]: info: MPD Permissions set Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Oct 27 02:03:54 motivo volumio[852]: info: Setting Device Volume Override Oct 27 02:03:54 motivo volumio[852]: info: Applying Volume Override Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 27 02:03:54 motivo volumio[852]: info: Updating Volume Controller Parameters: Device: 5 Name: Analog + I2S + Headphones Mixer: PCM Max Vol: 90 Vol Curve; logarithmic Vol Steps: 1 Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 27 02:03:54 motivo volumio[852]: info: Enabling external Volume Control Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: inputs , updateVolumeSettings Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: inputs , retrievevolume Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:54 motivo volumio[852]: info: CoreStateMachine::pushState Oct 27 02:03:54 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::volumioPushState Oct 27 02:03:54 motivo volumio[852]: info: MRS: Pushing multiroomSync output update for this device Oct 27 02:03:54 motivo volumio[852]: info: MRS: Pushing multiroomSync output Oct 27 02:03:54 motivo volumio[852]: info: Volumio called home Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Oct 27 02:03:54 motivo volumio[852]: info: Setting Additional System Software info: Hardware Revision: 2.2 Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Oct 27 02:03:54 motivo volumio[852]: info: Setting HW Firmware info: undefined Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Oct 27 02:03:54 motivo volumio[852]: info: Setting HW Version info: 2.2 Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Oct 27 02:03:54 motivo volumio[852]: info: Setting Additional System Software info: Hardware Revision: 2.2, Firmware Version: 0.1.2 Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Oct 27 02:03:54 motivo volumio[852]: info: Setting HW Firmware info: 0.1.2 Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Oct 27 02:03:54 motivo volumio[852]: info: Setting HW Version info: 2.2 Oct 27 02:03:54 motivo volumio[852]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 27 02:03:54 motivo volumio[852]: info: MCU Signalled Headphone Mode Disabled Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:54 motivo volumio[852]: info: Updating RAAT Signal Path Oct 27 02:03:54 motivo volumio[852]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 27 02:03:54 motivo volumio[852]: info: MCU Signalled Sleep Mode Disabled Oct 27 02:03:54 motivo volumio[852]: info: Enabling Advanced system settings configuration Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Oct 27 02:03:54 motivo volumio[852]: info: Additional UI Settings Added for plugin music_service/inputs Oct 27 02:03:54 motivo volumio[852]: info: MCU Signalled Auto Boot Mode On Power Disabled Oct 27 02:03:54 motivo sudo[1407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Oct 27 02:03:54 motivo sudo[1407]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:54 motivo volumio[852]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:54 motivo sudo[1407]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 02:03:54 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 27 02:03:54 motivo volumio[852]: error: Serial API: Failed to decode command: MAXVOL, message: 90 Oct 27 02:03:54 motivo volumio[852]: error: Serial API: Failed to decode command: MAXVOL, message: 90 Oct 27 02:03:54 motivo sudo[1420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 27 02:03:54 motivo sudo[1420]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:54 motivo sudo[1420]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:54 motivo volumio[852]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 27 02:03:54 motivo sudo[1422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 27 02:03:54 motivo sudo[1422]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:55 motivo systemd[1]: Started RAAT DAEMON. Oct 27 02:03:55 motivo volumio[852]: verbose: New Socket.io Connection to 79.168.68.144 from 85.138.37.190 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 27 02:03:55 motivo sudo[1422]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:55 motivo sudo[1445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Oct 27 02:03:55 motivo sudo[1445]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:55 motivo volumio[852]: info: Raat Daemon started successfully Oct 27 02:03:55 motivo volumio[852]: info: Adding Inputs via Serial API Oct 27 02:03:55 motivo volumio[852]: info: Adding Advanced Audio Settings via Serial API Oct 27 02:03:55 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Oct 27 02:03:55 motivo volumio[852]: info: Additional UI Settings Added for plugin music_service/inputs Oct 27 02:03:55 motivo volumio[852]: info: Adding Advanced Audio Settings via Serial API Oct 27 02:03:55 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Oct 27 02:03:55 motivo sudo[1445]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:55 motivo volumio[852]: info: CoreCommandRouter::volumioGetState Oct 27 02:03:55 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:55 motivo volumio[852]: info: Executing endpoint restartRAATSocket Oct 27 02:03:55 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 27 02:03:55 motivo volumio[852]: info: CoreStateMachine::pushState Oct 27 02:03:55 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:55 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 02:03:55 motivo volumio[852]: info: CoreCommandRouter::volumioPushState Oct 27 02:03:55 motivo volumio[852]: info: MRS: Pushing multiroomSync output update for this device Oct 27 02:03:55 motivo volumio[852]: info: MRS: Pushing multiroomSync output Oct 27 02:03:55 motivo volumio[852]: info: CoreStateMachine::pushState Oct 27 02:03:55 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:55 motivo volumio[852]: info: CoreCommandRouter::volumioPushState Oct 27 02:03:55 motivo volumio[852]: info: MRS: Pushing multiroomSync output update for this device Oct 27 02:03:55 motivo volumio[852]: info: MRS: Pushing multiroomSync output Oct 27 02:03:55 motivo volumio[852]: info: CoreCommandRouter::volumioGetState Oct 27 02:03:55 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:55 motivo volumio[852]: info: CoreCommandRouter::servicePushState Oct 27 02:03:55 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:55 motivo volumio[852]: verbose: STATE SERVICE {"status":"play","service":"inputs","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"","seek":0,"duration":0,"samplerate":"","bitdepth":"","stream":true,"disableUiControls":true,"channels":2} Oct 27 02:03:55 motivo volumio[852]: verbose: CURRENT POSITION 0 Oct 27 02:03:55 motivo volumio[852]: info: CoreStateMachine::syncState stateService play Oct 27 02:03:55 motivo volumio[852]: info: CoreStateMachine::syncState currentStatus stop Oct 27 02:03:55 motivo volumio[852]: info: CoreCommandRouter::volumiosetSourceActiveno-source Oct 27 02:03:55 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 02:03:55 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:55 motivo volumio[852]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 27 02:03:55 motivo volumio[852]: info: MCU Signalled Playback Inactive Oct 27 02:03:55 motivo mpd[1382]: Oct 27 02:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 27 02:03:55 motivo systemd[1]: Started Music Player Daemon. Oct 27 02:03:55 motivo sudo[1354]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:55 motivo sudo[1363]: pam_unix(sudo:session): session closed for user root Oct 27 02:03:55 motivo volumio[852]: info: Completed starting Core Plugins Oct 27 02:03:55 motivo volumio[852]: info: ------------------------------------------- Oct 27 02:03:55 motivo volumio[852]: info: ----- MyVolumio plugins startup ---- Oct 27 02:03:55 motivo volumio[852]: info: ------------------------------------------- Oct 27 02:03:55 motivo volumio[852]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 27 02:03:55 motivo volumio[852]: info: MPD running with PID1382 Oct 27 02:03:55 motivo volumio[852]: ,establishing connection Oct 27 02:03:55 motivo volumio[852]: error: MPD error: The expression evaluated to a falsy value: Oct 27 02:03:55 motivo volumio[852]: assert.ok(self.idling) Oct 27 02:03:55 motivo volumio[852]: error: The expression evaluated to a falsy value: Oct 27 02:03:55 motivo volumio[852]: assert.ok(self.idling) Oct 27 02:03:55 motivo volumio[852]: error: MPD error: The expression evaluated to a falsy value: Oct 27 02:03:55 motivo volumio[852]: assert.ok(self.idling) Oct 27 02:03:55 motivo volumio[852]: error: The expression evaluated to a falsy value: Oct 27 02:03:55 motivo volumio[852]: assert.ok(self.idling) Oct 27 02:03:55 motivo volumio[852]: error: updateQueue error: null Oct 27 02:03:55 motivo volumio[852]: verbose: New Socket.io Connection to 79.168.68.144 from 85.138.37.190 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 27 02:03:55 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 27 02:03:56 motivo volumio[852]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 7 Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::volumioGetVisibleSources Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::volumioGetState Oct 27 02:03:56 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 27 02:03:56 motivo volumio[852]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 27 02:03:56 motivo volumio[852]: info: Received Get System Info Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 02:03:56 motivo volumio[852]: info: Discovery: Getting this device information Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::volumioGetState Oct 27 02:03:56 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::volumioGetState Oct 27 02:03:56 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:56 motivo volumio[852]: info: Listing playlists Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 27 02:03:56 motivo volumio[852]: info: Received Get System Info Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 02:03:56 motivo volumio[852]: info: Discovery: Getting this device information Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::volumioGetState Oct 27 02:03:56 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::volumioGetState Oct 27 02:03:56 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::volumioGetVisibleSources Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::volumioGetState Oct 27 02:03:56 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::volumioGetState Oct 27 02:03:56 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 27 02:03:56 motivo volumio[852]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 27 02:03:56 motivo volumio[852]: info: Received Get System Info Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 02:03:56 motivo volumio[852]: info: Discovery: Getting this device information Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::volumioGetState Oct 27 02:03:56 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::volumioGetState Oct 27 02:03:56 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:56 motivo volumio[852]: info: Listing playlists Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 27 02:03:56 motivo volumio[852]: info: Received Get System Info Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 02:03:56 motivo volumio[852]: info: Discovery: Getting this device information Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::volumioGetState Oct 27 02:03:56 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 27 02:03:56 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 27 02:03:57 motivo volumio[852]: info: Initializing I2S Bus Oct 27 02:03:57 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 27 02:03:57 motivo volumio[852]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 8 Oct 27 02:03:57 motivo volumio[1344]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 27 02:03:57 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 27 02:03:58 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 27 02:03:58 motivo sudo[1472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=morais.renato@gmail.com,password=saromesd1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.26/Music /mnt/NAS/Music_PC Oct 27 02:03:58 motivo sudo[1472]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:03:58 motivo kernel: CIFS: Attempting to mount //192.168.1.26/Music Oct 27 02:03:58 motivo volumio[852]: info: CoreCommandRouter::volumioGetVisibleSources Oct 27 02:03:58 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 02:03:58 motivo volumio[852]: info: CoreCommandRouter::volumioGetState Oct 27 02:03:58 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:58 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 27 02:03:58 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 27 02:03:58 motivo volumio[852]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 27 02:03:58 motivo volumio[852]: info: Received Get System Info Oct 27 02:03:58 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 02:03:58 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 02:03:58 motivo volumio[852]: info: Discovery: Getting this device information Oct 27 02:03:58 motivo volumio[852]: info: CoreCommandRouter::volumioGetState Oct 27 02:03:58 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:58 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 02:03:58 motivo volumio[852]: info: CoreCommandRouter::volumioGetState Oct 27 02:03:58 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:58 motivo volumio[852]: info: Listing playlists Oct 27 02:03:58 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 27 02:03:58 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 27 02:03:58 motivo volumio[852]: info: Successfully initialized Motivo I2S Bus Oct 27 02:03:58 motivo volumio[852]: info: CoreCommandRouter::volumioGetState Oct 27 02:03:58 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:58 motivo volumio[1344]: ..........++++ Oct 27 02:03:59 motivo volumio[852]: info: MRS: Getting audio outputs on start Oct 27 02:03:59 motivo volumio[852]: info: MRS: Requesting all other devices output Oct 27 02:03:59 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 27 02:03:59 motivo volumio[852]: info: Received Get System Info Oct 27 02:03:59 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 02:03:59 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 02:03:59 motivo volumio[852]: info: Discovery: Getting this device information Oct 27 02:03:59 motivo volumio[852]: info: CoreCommandRouter::volumioGetState Oct 27 02:03:59 motivo volumio[852]: info: CorePlayQueue::getTrack 0 Oct 27 02:03:59 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 02:03:59 motivo volumio[1344]: ............++++ Oct 27 02:03:59 motivo volumio[1344]: e is 65537 (0x010001) Oct 27 02:03:59 motivo volumio[1344]: writing RSA key Oct 27 02:04:00 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 27 02:04:00 motivo systemd[1]: systemd-fsckd.service: Succeeded. Oct 27 02:04:00 motivo volumio[852]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 27 02:04:00 motivo volumio[852]: Cannot compose Albumart path Oct 27 02:04:03 motivo systemd[1]: systemd-hostnamed.service: Succeeded. Oct 27 02:04:04 motivo volumio[852]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 02:04:04 motivo volumio[852]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 02:04:04 motivo volumio[852]: info: Retrieving Cloud Streaming UI Oct 27 02:04:04 motivo volumio[852]: info: Checking for updated MCU Firmware Oct 27 02:04:04 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 27 02:04:04 motivo volumio[852]: info: Firware on device is on latest version, no need to update Oct 27 02:04:04 motivo volumio[852]: info: Getting Tidal Cloud Configuration Oct 27 02:04:04 motivo volumio[852]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 02:04:04 motivo volumio[852]: info: Getting Qobuz Cloud Configuration Oct 27 02:04:04 motivo volumio[852]: info: Asking plugin for UI Config Oct 27 02:04:04 motivo volumio[852]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 02:04:04 motivo volumio[852]: info: Getting Spotify Cloud Configuration Oct 27 02:04:04 motivo volumio[852]: info: Asking plugin for UI Config Oct 27 02:04:04 motivo volumio[852]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 02:04:04 motivo volumio[852]: info: Saving Spotify Acccount Oct 27 02:04:04 motivo volumio[852]: info: Got it Oct 27 02:04:04 motivo volumio[852]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 27 02:04:04 motivo volumio[852]: info: Got it Oct 27 02:04:04 motivo volumio[852]: info: Got Tidal Cloud Configuration Oct 27 02:04:04 motivo volumio[852]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 02:04:04 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 27 02:04:04 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 27 02:04:04 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 27 02:04:04 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 27 02:04:04 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 02:04:04 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 02:04:04 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 02:04:04 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 02:04:04 motivo volumio[852]: info: CoreCommandRouter::volumioGetBrowseSources Oct 27 02:04:04 motivo volumio[852]: info: CoreCommandRouter::volumioGetBrowseSources Oct 27 02:04:04 motivo volumio[852]: info: CoreCommandRouter::volumioGetBrowseSources Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 27 02:04:04 motivo volumio[852]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 27 02:04:05 motivo volumio[852]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 27 02:04:05 motivo volumio[852]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 27 02:04:05 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 02:04:05 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 02:04:05 motivo volumio[852]: info: Starting MyVolumio Remote Streaming Endpoints Oct 27 02:04:05 motivo volumio[852]: info: MyVolumio login type: Token Oct 27 02:04:05 motivo volumio[852]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 27 02:04:05 motivo volumio[852]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Oct 27 02:04:05 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 27 02:04:05 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 27 02:04:05 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 27 02:04:05 motivo volumio[852]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 27 02:04:06 motivo volumio[852]: info: MyVolumio token set successfully Oct 27 02:04:06 motivo volumio[852]: info: MYVOLUMIO: Adding device Oct 27 02:04:06 motivo volumio[852]: info: MYVOLUMIO: Evaluating Server Oct 27 02:04:06 motivo kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 27 02:04:06 motivo kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 27 02:04:06 motivo sudo[1472]: pam_unix(sudo:session): session closed for user root Oct 27 02:04:06 motivo volumio[852]: info: Cannot mount NAS Music_PC at system boot, trial number 2 ,retrying in 5 seconds Oct 27 02:04:06 motivo volumio[852]: info: MyVolumio status changed Oct 27 02:04:06 motivo volumio[852]: info: Streaming services startup Oct 27 02:04:06 motivo volumio[852]: info: Starting Streaming Daemon Oct 27 02:04:06 motivo volumio[852]: info: Removing browser output: myVolumio user plan is not superstar Oct 27 02:04:06 motivo volumio[852]: info: Removing audio output: Oct 27 02:04:06 motivo volumio[852]: info: Stoppping Tunnel 1 Oct 27 02:04:06 motivo sudo[1524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 27 02:04:06 motivo sudo[1524]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:04:06 motivo sudo[1526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 27 02:04:06 motivo sudo[1526]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 02:04:06 motivo sudo[1524]: pam_unix(sudo:session): session closed for user root Oct 27 02:04:07 motivo volumio[852]: error: Cannot start Volumio Streaming Daemon Oct 27 02:04:07 motivo volumio[852]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 27 02:04:07 motivo volumio[852]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 27 02:04:07 motivo sudo[1526]: pam_unix(sudo:session): session closed for user root Oct 27 02:04:07 motivo volumio[852]: info: Remote SSH Stopped Oct 27 02:04:07 motivo volumio[852]: info: Setting Geolocation for MyVolumio to eu3 Oct 27 02:04:07 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 02:04:07 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 02:04:07 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 02:04:07 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 27 02:04:07 motivo volumio[852]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 27 02:04:08 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Oct 27 02:04:08 motivo volumio[852]: info: Updating MyVolumio device info Oct 27 02:04:08 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 02:04:08 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 02:04:08 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 02:04:08 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 27 02:04:08 motivo volumio[852]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 27 02:04:09 motivo volumio[852]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 27 02:04:11 motivo volumio[852]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 02:04:11 motivo volumio[852]: TypeError: Cannot read property 'replace' of undefined Oct 27 02:04:11 motivo volumio[852]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Oct 27 02:04:11 motivo volumio[852]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Oct 27 02:04:11 motivo volumio[852]: at listOnTimeout (internal/timers.js:554:17) Oct 27 02:04:11 motivo volumio[852]: at processTimers (internal/timers.js:497:7) Oct 27 02:04:11 motivo volumio[852]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 02:04:11 motivo sudo[1540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-27 02:03 Oct 27 02:04:11 motivo sudo[1540]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 07:14:04 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="1b70fb201c03de663c0d0492dcaf203c"