-- Logs begin at Thu 2022-09-22 01:03:44 UTC, end at Thu 2022-09-22 01:04:28 UTC. -- Sep 22 01:03:44 minidsp-raccio systemd-journal[241]: Runtime journal is using 4.0M (max allowed 30.0M, trying to leave 74.0M free of 489.3M available → current limit 30.0M). Sep 22 01:03:44 minidsp-raccio kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd034] Sep 22 01:03:44 minidsp-raccio kernel: Linux version 5.4.75+ (nanopi@busteros) (gcc version 6.3.1 20170109 (Linaro GCC 6.3-2017.02)) #2 SMP Fri Apr 9 12:35:58 CEST 2021 Sep 22 01:03:44 minidsp-raccio kernel: Machine model: FriendlyElec NanoPi NEO3 Sep 22 01:03:44 minidsp-raccio kernel: earlycon: uart8250 at MMIO32 0x00000000ff130000 (options '') Sep 22 01:03:44 minidsp-raccio kernel: printk: bootconsole [uart8250] enabled Sep 22 01:03:44 minidsp-raccio kernel: On node 0 totalpages: 261632 Sep 22 01:03:44 minidsp-raccio kernel: DMA32 zone: 4088 pages used for memmap Sep 22 01:03:44 minidsp-raccio kernel: DMA32 zone: 0 pages reserved Sep 22 01:03:44 minidsp-raccio kernel: DMA32 zone: 261632 pages, LIFO batch:63 Sep 22 01:03:44 minidsp-raccio kernel: psci: probing for conduit method from DT. Sep 22 01:03:44 minidsp-raccio kernel: psci: PSCIv1.0 detected in firmware. Sep 22 01:03:44 minidsp-raccio kernel: psci: Using standard PSCI v0.2 function IDs Sep 22 01:03:44 minidsp-raccio kernel: psci: MIGRATE_INFO_TYPE not supported. Sep 22 01:03:44 minidsp-raccio kernel: psci: SMC Calling Convention v1.0 Sep 22 01:03:44 minidsp-raccio kernel: percpu: Embedded 30 pages/cpu s85352 r8192 d29336 u122880 Sep 22 01:03:44 minidsp-raccio kernel: pcpu-alloc: s85352 r8192 d29336 u122880 alloc=30*4096 Sep 22 01:03:44 minidsp-raccio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Sep 22 01:03:44 minidsp-raccio kernel: Detected VIPT I-cache on CPU0 Sep 22 01:03:44 minidsp-raccio kernel: CPU features: detected: ARM erratum 845719 Sep 22 01:03:44 minidsp-raccio kernel: Built 1 zonelists, mobility grouping on. Total pages: 257544 Sep 22 01:03:44 minidsp-raccio kernel: Kernel command line: earlycon=uart8250,mmio32,0xff130000 console=ttyS2,1500000 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh hwdevice=nanopineo3 bootdev=mmcblk0 Sep 22 01:03:44 minidsp-raccio kernel: Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 22 01:03:44 minidsp-raccio kernel: Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 22 01:03:44 minidsp-raccio kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 22 01:03:44 minidsp-raccio kernel: Memory: 1005996K/1046528K available (9854K kernel code, 1286K rwdata, 3304K rodata, 1152K init, 506K bss, 40532K reserved, 0K cma-reserved) Sep 22 01:03:44 minidsp-raccio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 22 01:03:44 minidsp-raccio kernel: ftrace: allocating 39982 entries in 157 pages Sep 22 01:03:44 minidsp-raccio kernel: rcu: Hierarchical RCU implementation. Sep 22 01:03:44 minidsp-raccio kernel: rcu: RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=4. Sep 22 01:03:44 minidsp-raccio kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 22 01:03:44 minidsp-raccio kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 22 01:03:44 minidsp-raccio kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Sep 22 01:03:44 minidsp-raccio kernel: GIC: Using split EOI/Deactivate mode Sep 22 01:03:44 minidsp-raccio kernel: random: get_random_bytes called from start_kernel+0x324/0x4b4 with crng_init=0 Sep 22 01:03:44 minidsp-raccio kernel: arch_timer: cp15 timer(s) running at 24.00MHz (phys). Sep 22 01:03:44 minidsp-raccio kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns Sep 22 01:03:44 minidsp-raccio kernel: sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns Sep 22 01:03:44 minidsp-raccio kernel: Console: colour dummy device 80x25 Sep 22 01:03:44 minidsp-raccio kernel: printk: console [tty1] enabled Sep 22 01:03:44 minidsp-raccio kernel: printk: bootconsole [uart8250] disabled Sep 22 01:03:44 minidsp-raccio kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=24000) Sep 22 01:03:44 minidsp-raccio kernel: pid_max: default: 32768 minimum: 301 Sep 22 01:03:44 minidsp-raccio kernel: LSM: Security Framework initializing Sep 22 01:03:44 minidsp-raccio kernel: Yama: becoming mindful. Sep 22 01:03:44 minidsp-raccio kernel: Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) Sep 22 01:03:44 minidsp-raccio kernel: Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) Sep 22 01:03:44 minidsp-raccio kernel: ASID allocator initialised with 32768 entries Sep 22 01:03:44 minidsp-raccio kernel: rcu: Hierarchical SRCU implementation. Sep 22 01:03:44 minidsp-raccio kernel: smp: Bringing up secondary CPUs ... Sep 22 01:03:44 minidsp-raccio kernel: Detected VIPT I-cache on CPU1 Sep 22 01:03:44 minidsp-raccio kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd034] Sep 22 01:03:44 minidsp-raccio kernel: Detected VIPT I-cache on CPU2 Sep 22 01:03:44 minidsp-raccio kernel: CPU2: Booted secondary processor 0x0000000002 [0x410fd034] Sep 22 01:03:44 minidsp-raccio kernel: Detected VIPT I-cache on CPU3 Sep 22 01:03:44 minidsp-raccio kernel: CPU3: Booted secondary processor 0x0000000003 [0x410fd034] Sep 22 01:03:44 minidsp-raccio kernel: smp: Brought up 1 node, 4 CPUs Sep 22 01:03:44 minidsp-raccio kernel: SMP: Total of 4 processors activated. Sep 22 01:03:44 minidsp-raccio kernel: CPU features: detected: 32-bit EL0 Support Sep 22 01:03:44 minidsp-raccio kernel: CPU features: detected: CRC32 instructions Sep 22 01:03:44 minidsp-raccio kernel: CPU: All CPU(s) started at EL2 Sep 22 01:03:44 minidsp-raccio kernel: alternatives: patching kernel code Sep 22 01:03:44 minidsp-raccio kernel: devtmpfs: initialized Sep 22 01:03:44 minidsp-raccio kernel: Registered cp15_barrier emulation handler Sep 22 01:03:44 minidsp-raccio kernel: Registered setend emulation handler Sep 22 01:03:44 minidsp-raccio kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 22 01:03:44 minidsp-raccio kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 22 01:03:44 minidsp-raccio kernel: pinctrl core: initialized pinctrl subsystem Sep 22 01:03:44 minidsp-raccio kernel: regulator-dummy: no parameters Sep 22 01:03:44 minidsp-raccio kernel: NET: Registered protocol family 16 Sep 22 01:03:44 minidsp-raccio kernel: DMA: preallocated 256 KiB pool for atomic allocations Sep 22 01:03:44 minidsp-raccio kernel: audit: initializing netlink subsys (disabled) Sep 22 01:03:44 minidsp-raccio kernel: audit: type=2000 audit(0.044:1): state=initialized audit_enabled=0 res=1 Sep 22 01:03:44 minidsp-raccio kernel: cpuidle: using governor ladder Sep 22 01:03:44 minidsp-raccio kernel: cpuidle: using governor menu Sep 22 01:03:44 minidsp-raccio kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Sep 22 01:03:44 minidsp-raccio kernel: gpio gpiochip0: (gpio0): added GPIO chardev (254:0) Sep 22 01:03:44 minidsp-raccio kernel: gpiochip_setup_dev: registered GPIOs 0 to 31 on device: gpiochip0 (gpio0) Sep 22 01:03:44 minidsp-raccio kernel: gpio gpiochip1: (gpio1): added GPIO chardev (254:1) Sep 22 01:03:44 minidsp-raccio kernel: gpiochip_setup_dev: registered GPIOs 32 to 63 on device: gpiochip1 (gpio1) Sep 22 01:03:44 minidsp-raccio kernel: gpio gpiochip2: (gpio2): added GPIO chardev (254:2) Sep 22 01:03:44 minidsp-raccio kernel: gpiochip_setup_dev: registered GPIOs 64 to 95 on device: gpiochip2 (gpio2) Sep 22 01:03:44 minidsp-raccio kernel: gpio gpiochip3: (gpio3): added GPIO chardev (254:3) Sep 22 01:03:44 minidsp-raccio kernel: gpiochip_setup_dev: registered GPIOs 96 to 127 on device: gpiochip3 (gpio3) Sep 22 01:03:44 minidsp-raccio kernel: cryptd: max_cpu_qlen set to 1000 Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage sdmmc-regulator: GPIO lookup for consumer (null) Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage sdmmc-regulator: using device tree for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'gpios' property of node '/sdmmc-regulator[0]' Sep 22 01:03:44 minidsp-raccio kernel: sdmmc-regulator GPIO handle specifies active low - ignored Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: parsed 'gpio' property of node '/sdmmc-regulator[0]' - status (0) Sep 22 01:03:44 minidsp-raccio kernel: gpio gpiochip0: Persistence not supported for GPIO 30 Sep 22 01:03:44 minidsp-raccio kernel: vcc_sd: 3300 mV Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: vcc_sd: unable to resolve supply Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage sdmmc-regulator: vcc_sd supplying 3300000uV Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage vcc-sys: GPIO lookup for consumer (null) Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage vcc-sys: using device tree for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'gpios' property of node '/vcc-sys[0]' Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'gpio' property of node '/vcc-sys[0]' Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage vcc-sys: using lookup tables for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage vcc-sys: No GPIO consumer (null) found Sep 22 01:03:44 minidsp-raccio kernel: vcc_sys: 5000 mV Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: vcc_sd: unable to resolve supply Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage vcc-sys: vcc_sys supplying 5000000uV Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage vcc-phy-regulator: GPIO lookup for consumer (null) Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage vcc-phy-regulator: using device tree for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'gpios' property of node '/vcc-phy-regulator[0]' Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'gpio' property of node '/vcc-phy-regulator[0]' Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage vcc-phy-regulator: using lookup tables for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage vcc-phy-regulator: No GPIO consumer (null) found Sep 22 01:03:44 minidsp-raccio kernel: vcc_phy: no parameters Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: vcc_sd: unable to resolve supply Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage vcc-phy-regulator: vcc_phy supplying 0uV Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage host-vbus-regulator: GPIO lookup for consumer (null) Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage host-vbus-regulator: using device tree for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'gpios' property of node '/host-vbus-regulator[0]' Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'gpio' property of node '/host-vbus-regulator[0]' Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage host-vbus-regulator: using lookup tables for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage host-vbus-regulator: No GPIO consumer (null) found Sep 22 01:03:44 minidsp-raccio kernel: vcc_host_vbus: 5000 mV Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: vcc_sd: unable to resolve supply Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage host-vbus-regulator: vcc_host_vbus supplying 5000000uV Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage vcc-rtl8153-regulator: GPIO lookup for consumer (null) Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage vcc-rtl8153-regulator: using device tree for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'gpios' property of node '/vcc-rtl8153-regulator[0]' Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: parsed 'gpio' property of node '/vcc-rtl8153-regulator[0]' - status (0) Sep 22 01:03:44 minidsp-raccio kernel: gpio gpiochip2: Persistence not supported for GPIO 22 Sep 22 01:03:44 minidsp-raccio kernel: vcc_rtl8153: 5000 mV Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: vcc_sd: unable to resolve supply Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage vcc-rtl8153-regulator: vcc_rtl8153 supplying 5000000uV Sep 22 01:03:44 minidsp-raccio kernel: gpio-regulator sdmmcio-regulator: GPIO lookup for consumer (null) Sep 22 01:03:44 minidsp-raccio kernel: gpio-regulator sdmmcio-regulator: using device tree for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: parsed 'gpios' property of node '/sdmmcio-regulator[0]' - status (0) Sep 22 01:03:44 minidsp-raccio kernel: gpio gpiochip1: Persistence not supported for GPIO 28 Sep 22 01:03:44 minidsp-raccio kernel: gpio-regulator sdmmcio-regulator: GPIO lookup for consumer enable Sep 22 01:03:44 minidsp-raccio kernel: gpio-regulator sdmmcio-regulator: using device tree for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'enable-gpios' property of node '/sdmmcio-regulator[0]' Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'enable-gpio' property of node '/sdmmcio-regulator[0]' Sep 22 01:03:44 minidsp-raccio kernel: gpio-regulator sdmmcio-regulator: using lookup tables for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: gpio-regulator sdmmcio-regulator: No GPIO consumer enable found Sep 22 01:03:44 minidsp-raccio kernel: vccio_sd: 1800 <--> 3300 mV at 1800 mV Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: vcc_sd: unable to resolve supply Sep 22 01:03:44 minidsp-raccio kernel: iommu: Default domain type: Translated Sep 22 01:03:44 minidsp-raccio kernel: SCSI subsystem initialized Sep 22 01:03:44 minidsp-raccio kernel: usbcore: registered new interface driver usbfs Sep 22 01:03:44 minidsp-raccio kernel: usbcore: registered new interface driver hub Sep 22 01:03:44 minidsp-raccio kernel: usbcore: registered new device driver usb Sep 22 01:03:44 minidsp-raccio kernel: videodev: Linux video capture interface: v2.00 Sep 22 01:03:44 minidsp-raccio kernel: pps_core: LinuxPPS API ver. 1 registered Sep 22 01:03:44 minidsp-raccio kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Sep 22 01:03:44 minidsp-raccio kernel: PTP clock support registered Sep 22 01:03:44 minidsp-raccio kernel: Advanced Linux Sound Architecture Driver Initialized. Sep 22 01:03:44 minidsp-raccio kernel: Bluetooth: Core ver 2.22 Sep 22 01:03:44 minidsp-raccio kernel: NET: Registered protocol family 31 Sep 22 01:03:44 minidsp-raccio kernel: Bluetooth: HCI device and connection manager initialized Sep 22 01:03:44 minidsp-raccio kernel: Bluetooth: HCI socket layer initialized Sep 22 01:03:44 minidsp-raccio kernel: Bluetooth: L2CAP socket layer initialized Sep 22 01:03:44 minidsp-raccio kernel: Bluetooth: SCO socket layer initialized Sep 22 01:03:44 minidsp-raccio kernel: NetLabel: Initializing Sep 22 01:03:44 minidsp-raccio kernel: NetLabel: domain hash size = 128 Sep 22 01:03:44 minidsp-raccio kernel: NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO Sep 22 01:03:44 minidsp-raccio kernel: NetLabel: unlabeled traffic allowed by default Sep 22 01:03:44 minidsp-raccio kernel: clocksource: Switched to clocksource arch_sys_counter Sep 22 01:03:44 minidsp-raccio kernel: VFS: Disk quotas dquot_6.6.0 Sep 22 01:03:44 minidsp-raccio kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 22 01:03:44 minidsp-raccio kernel: thermal_sys: Registered thermal governor 'fair_share' Sep 22 01:03:44 minidsp-raccio kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 22 01:03:44 minidsp-raccio kernel: thermal_sys: Registered thermal governor 'power_allocator' Sep 22 01:03:44 minidsp-raccio kernel: NET: Registered protocol family 2 Sep 22 01:03:44 minidsp-raccio kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 2, 20480 bytes, linear) Sep 22 01:03:44 minidsp-raccio kernel: TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 22 01:03:44 minidsp-raccio kernel: TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) Sep 22 01:03:44 minidsp-raccio kernel: TCP: Hash tables configured (established 8192 bind 8192) Sep 22 01:03:44 minidsp-raccio kernel: UDP hash table entries: 512 (order: 3, 49152 bytes, linear) Sep 22 01:03:44 minidsp-raccio kernel: UDP-Lite hash table entries: 512 (order: 3, 49152 bytes, linear) Sep 22 01:03:44 minidsp-raccio kernel: NET: Registered protocol family 1 Sep 22 01:03:44 minidsp-raccio kernel: PCI: CLS 0 bytes, default 64 Sep 22 01:03:44 minidsp-raccio kernel: Trying to unpack rootfs image as initramfs... Sep 22 01:03:44 minidsp-raccio kernel: Freeing initrd memory: 3512K Sep 22 01:03:44 minidsp-raccio kernel: hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available Sep 22 01:03:44 minidsp-raccio kernel: kvm [1]: IPA Size Limit: 40bits Sep 22 01:03:44 minidsp-raccio kernel: kvm [1]: vgic interrupt IRQ1 Sep 22 01:03:44 minidsp-raccio kernel: kvm [1]: Hyp mode initialized successfully Sep 22 01:03:44 minidsp-raccio kernel: Initialise system trusted keyrings Sep 22 01:03:44 minidsp-raccio kernel: workingset: timestamp_bits=46 max_order=18 bucket_order=0 Sep 22 01:03:44 minidsp-raccio kernel: fuse: init (API version 7.31) Sep 22 01:03:44 minidsp-raccio kernel: NET: Registered protocol family 38 Sep 22 01:03:44 minidsp-raccio kernel: Key type asymmetric registered Sep 22 01:03:44 minidsp-raccio kernel: Asymmetric key parser 'x509' registered Sep 22 01:03:44 minidsp-raccio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Sep 22 01:03:44 minidsp-raccio kernel: io scheduler mq-deadline registered Sep 22 01:03:44 minidsp-raccio kernel: io scheduler kyber registered Sep 22 01:03:44 minidsp-raccio kernel: phy phy-ff450000.syscon:usb2-phy@100.0: Looking up phy-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: phy phy-ff450000.syscon:usb2-phy@100.0: Looking up phy-supply property in node /syscon@ff450000/usb2-phy@100/otg-port failed Sep 22 01:03:44 minidsp-raccio kernel: phy phy-ff450000.syscon:usb2-phy@100.1: Looking up phy-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: phy phy-ff450000.syscon:usb2-phy@100.1: Looking up phy-supply property in node /syscon@ff450000/usb2-phy@100/host-port failed Sep 22 01:03:44 minidsp-raccio kernel: rockchip-u3phy ff470000.usb3-phy: Looking up vbus-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: phy phy-ff470000.usb3-phy.2: Looking up phy-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: phy phy-ff470000.usb3-phy.2: Looking up phy-supply property in node /usb3-phy@ff470000/utmi@ff470000 failed Sep 22 01:03:44 minidsp-raccio kernel: phy phy-ff470000.usb3-phy.3: Looking up phy-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: phy phy-ff470000.usb3-phy.3: Looking up phy-supply property in node /usb3-phy@ff470000/pipe@ff478000 failed Sep 22 01:03:44 minidsp-raccio kernel: rockchip-u3phy ff470000.usb3-phy: Rockchip u3phy initialized successfully Sep 22 01:03:44 minidsp-raccio kernel: dma-pl330 ff1f0000.dmac: Loaded driver for PL330 DMAC-241330 Sep 22 01:03:44 minidsp-raccio kernel: dma-pl330 ff1f0000.dmac: DBUFF-128x8bytes Num_Chans-8 Num_Peri-20 Num_Events-16 Sep 22 01:03:44 minidsp-raccio kernel: Serial: 8250/16550 driver, 5 ports, IRQ sharing disabled Sep 22 01:03:44 minidsp-raccio kernel: ff120000.serial: ttyS1 at MMIO 0xff120000 (irq = 14, base_baud = 1500000) is a 16550A Sep 22 01:03:44 minidsp-raccio kernel: ff130000.serial: ttyS2 at MMIO 0xff130000 (irq = 15, base_baud = 1500000) is a 16550A Sep 22 01:03:44 minidsp-raccio kernel: printk: console [ttyS2] enabled Sep 22 01:03:44 minidsp-raccio kernel: rockchip-drm display-subsystem: [drm:rockchip_drm_platform_probe] *ERROR* No available vop found for display-subsystem. Sep 22 01:03:44 minidsp-raccio kernel: brd: module loaded Sep 22 01:03:44 minidsp-raccio kernel: loop: module loaded Sep 22 01:03:44 minidsp-raccio kernel: zram: Added device: zram0 Sep 22 01:03:44 minidsp-raccio kernel: lkdtm: No crash points registered, enable through debugfs Sep 22 01:03:44 minidsp-raccio kernel: mdio_bus fixed-0: GPIO lookup for consumer reset Sep 22 01:03:44 minidsp-raccio kernel: mdio_bus fixed-0: using lookup tables for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: mdio_bus fixed-0: No GPIO consumer reset found Sep 22 01:03:44 minidsp-raccio kernel: libphy: Fixed MDIO Bus: probed Sep 22 01:03:44 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet: IRQ eth_wake_irq not found Sep 22 01:03:44 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet: IRQ eth_lpi not found Sep 22 01:03:44 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet: PTP uses main clock Sep 22 01:03:44 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet: Looking up phy-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet: clock input or output? (input). Sep 22 01:03:44 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet: TX delay(0x24). Sep 22 01:03:44 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet: RX delay(0x18). Sep 22 01:03:44 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet: integrated PHY? (no). Sep 22 01:03:44 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet: cannot get clock clk_mac_speed Sep 22 01:03:44 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet: clock input from PHY Sep 22 01:03:44 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet: init for RGMII Sep 22 01:03:44 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet: User ID: 0x10, Synopsys ID: 0x35 Sep 22 01:03:44 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet: DWMAC1000 Sep 22 01:03:44 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet: DMA HW capability register supported Sep 22 01:03:44 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet: RX Checksum Offload Engine supported Sep 22 01:03:44 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet: COE Type 2 Sep 22 01:03:44 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet: TX Checksum insertion supported Sep 22 01:03:44 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet: Wake-Up On Lan supported Sep 22 01:03:44 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet: Normal descriptors Sep 22 01:03:44 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet: Ring mode enabled Sep 22 01:03:44 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet: Enable RX Mitigation via HW Watchdog Timer Sep 22 01:03:44 minidsp-raccio kernel: mdio_bus stmmac-0: GPIO lookup for consumer reset Sep 22 01:03:44 minidsp-raccio kernel: mdio_bus stmmac-0: using device tree for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'reset-gpios' property of node '/ethernet@ff540000/mdio[0]' Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'reset-gpio' property of node '/ethernet@ff540000/mdio[0]' Sep 22 01:03:44 minidsp-raccio kernel: mdio_bus stmmac-0: using lookup tables for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: mdio_bus stmmac-0: No GPIO consumer reset found Sep 22 01:03:44 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet: GPIO lookup for consumer snps,reset Sep 22 01:03:44 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet: using device tree for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'snps,reset-gpios' property of node '/ethernet@ff540000[0]' Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: parsed 'snps,reset-gpio' property of node '/ethernet@ff540000[0]' - status (0) Sep 22 01:03:44 minidsp-raccio kernel: gpio gpiochip1: Persistence not supported for GPIO 18 Sep 22 01:03:44 minidsp-raccio kernel: libphy: stmmac: probed Sep 22 01:03:44 minidsp-raccio kernel: mdio_bus stmmac-0:01: GPIO lookup for consumer reset Sep 22 01:03:44 minidsp-raccio kernel: mdio_bus stmmac-0:01: using device tree for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'reset-gpios' property of node '/ethernet@ff540000/mdio/ethernet-phy@1[0]' Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'reset-gpio' property of node '/ethernet@ff540000/mdio/ethernet-phy@1[0]' Sep 22 01:03:44 minidsp-raccio kernel: mdio_bus stmmac-0:01: using lookup tables for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: mdio_bus stmmac-0:01: No GPIO consumer reset found Sep 22 01:03:44 minidsp-raccio kernel: usbcore: registered new interface driver cdc_ether Sep 22 01:03:44 minidsp-raccio kernel: usbcore: registered new interface driver rndis_host Sep 22 01:03:44 minidsp-raccio kernel: dwc3 ff600000.dwc3: Failed to get clk 'ref': -2 Sep 22 01:03:44 minidsp-raccio kernel: phy phy-ff470000.usb3-phy.2: u3phy u2 power on Sep 22 01:03:44 minidsp-raccio kernel: phy phy-ff470000.usb3-phy.3: u3phy u3 power on Sep 22 01:03:44 minidsp-raccio kernel: dwc2 ff580000.usb: Looking up vusb_d-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: dwc2 ff580000.usb: Looking up vusb_d-supply property in node /usb@ff580000 failed Sep 22 01:03:44 minidsp-raccio kernel: dwc2 ff580000.usb: ff580000.usb supply vusb_d not found, using dummy regulator Sep 22 01:03:44 minidsp-raccio kernel: dwc2 ff580000.usb: Looking up vusb_a-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: dwc2 ff580000.usb: Looking up vusb_a-supply property in node /usb@ff580000 failed Sep 22 01:03:44 minidsp-raccio kernel: dwc2 ff580000.usb: ff580000.usb supply vusb_a not found, using dummy regulator Sep 22 01:03:44 minidsp-raccio kernel: dwc2 ff580000.usb: Looking up vbus-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: dwc2 ff580000.usb: Looking up vbus-supply property in node /usb@ff580000 failed Sep 22 01:03:44 minidsp-raccio kernel: dwc2 ff580000.usb: EPs: 10, dedicated fifos, 972 entries in SPRAM Sep 22 01:03:44 minidsp-raccio kernel: dwc2 ff580000.usb: DWC OTG Controller Sep 22 01:03:44 minidsp-raccio kernel: dwc2 ff580000.usb: new USB bus registered, assigned bus number 1 Sep 22 01:03:44 minidsp-raccio kernel: dwc2 ff580000.usb: irq 32, io mem 0xff580000 Sep 22 01:03:44 minidsp-raccio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04 Sep 22 01:03:44 minidsp-raccio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Sep 22 01:03:44 minidsp-raccio kernel: usb usb1: Product: DWC OTG Controller Sep 22 01:03:44 minidsp-raccio kernel: usb usb1: Manufacturer: Linux 5.4.75+ dwc2_hsotg Sep 22 01:03:44 minidsp-raccio kernel: usb usb1: SerialNumber: ff580000.usb Sep 22 01:03:44 minidsp-raccio kernel: hub 1-0:1.0: USB hub found Sep 22 01:03:44 minidsp-raccio kernel: hub 1-0:1.0: 1 port detected Sep 22 01:03:44 minidsp-raccio kernel: ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver Sep 22 01:03:44 minidsp-raccio kernel: ehci-pci: EHCI PCI platform driver Sep 22 01:03:44 minidsp-raccio kernel: ehci-platform: EHCI generic platform driver Sep 22 01:03:44 minidsp-raccio kernel: ehci-platform ff5c0000.usb: EHCI Host Controller Sep 22 01:03:44 minidsp-raccio kernel: ehci-platform ff5c0000.usb: new USB bus registered, assigned bus number 2 Sep 22 01:03:44 minidsp-raccio kernel: ehci-platform ff5c0000.usb: irq 33, io mem 0xff5c0000 Sep 22 01:03:44 minidsp-raccio kernel: ehci-platform ff5c0000.usb: USB 2.0 started, EHCI 1.00 Sep 22 01:03:44 minidsp-raccio kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04 Sep 22 01:03:44 minidsp-raccio kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Sep 22 01:03:44 minidsp-raccio kernel: usb usb2: Product: EHCI Host Controller Sep 22 01:03:44 minidsp-raccio kernel: usb usb2: Manufacturer: Linux 5.4.75+ ehci_hcd Sep 22 01:03:44 minidsp-raccio kernel: usb usb2: SerialNumber: ff5c0000.usb Sep 22 01:03:44 minidsp-raccio kernel: hub 2-0:1.0: USB hub found Sep 22 01:03:44 minidsp-raccio kernel: hub 2-0:1.0: 1 port detected Sep 22 01:03:44 minidsp-raccio kernel: ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver Sep 22 01:03:44 minidsp-raccio kernel: ohci-platform: OHCI generic platform driver Sep 22 01:03:44 minidsp-raccio kernel: ohci-platform ff5d0000.usb: Generic Platform OHCI controller Sep 22 01:03:44 minidsp-raccio kernel: ohci-platform ff5d0000.usb: new USB bus registered, assigned bus number 3 Sep 22 01:03:44 minidsp-raccio kernel: ohci-platform ff5d0000.usb: irq 34, io mem 0xff5d0000 Sep 22 01:03:44 minidsp-raccio kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 5.04 Sep 22 01:03:44 minidsp-raccio kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Sep 22 01:03:44 minidsp-raccio kernel: usb usb3: Product: Generic Platform OHCI controller Sep 22 01:03:44 minidsp-raccio kernel: usb usb3: Manufacturer: Linux 5.4.75+ ohci_hcd Sep 22 01:03:44 minidsp-raccio kernel: usb usb3: SerialNumber: ff5d0000.usb Sep 22 01:03:44 minidsp-raccio kernel: hub 3-0:1.0: USB hub found Sep 22 01:03:44 minidsp-raccio kernel: hub 3-0:1.0: 1 port detected Sep 22 01:03:44 minidsp-raccio kernel: xhci-hcd xhci-hcd.0.auto: xHCI Host Controller Sep 22 01:03:44 minidsp-raccio kernel: xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 4 Sep 22 01:03:44 minidsp-raccio kernel: xhci-hcd xhci-hcd.0.auto: hcc params 0x0220fe64 hci version 0x110 quirks 0x0000001002030010 Sep 22 01:03:44 minidsp-raccio kernel: xhci-hcd xhci-hcd.0.auto: irq 169, io mem 0xff600000 Sep 22 01:03:44 minidsp-raccio kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04 Sep 22 01:03:44 minidsp-raccio kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Sep 22 01:03:44 minidsp-raccio kernel: usb usb4: Product: xHCI Host Controller Sep 22 01:03:44 minidsp-raccio kernel: usb usb4: Manufacturer: Linux 5.4.75+ xhci-hcd Sep 22 01:03:44 minidsp-raccio kernel: usb usb4: SerialNumber: xhci-hcd.0.auto Sep 22 01:03:44 minidsp-raccio kernel: hub 4-0:1.0: USB hub found Sep 22 01:03:44 minidsp-raccio kernel: hub 4-0:1.0: 1 port detected Sep 22 01:03:44 minidsp-raccio kernel: xhci-hcd xhci-hcd.0.auto: xHCI Host Controller Sep 22 01:03:44 minidsp-raccio kernel: xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 5 Sep 22 01:03:44 minidsp-raccio kernel: xhci-hcd xhci-hcd.0.auto: Host supports USB 3.0 SuperSpeed Sep 22 01:03:44 minidsp-raccio kernel: usb usb5: We don't know the algorithms for LPM for this host, disabling LPM. Sep 22 01:03:44 minidsp-raccio kernel: usb usb5: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.04 Sep 22 01:03:44 minidsp-raccio kernel: usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Sep 22 01:03:44 minidsp-raccio kernel: usb usb5: Product: xHCI Host Controller Sep 22 01:03:44 minidsp-raccio kernel: usb usb5: Manufacturer: Linux 5.4.75+ xhci-hcd Sep 22 01:03:44 minidsp-raccio kernel: usb usb5: SerialNumber: xhci-hcd.0.auto Sep 22 01:03:44 minidsp-raccio kernel: hub 5-0:1.0: USB hub found Sep 22 01:03:44 minidsp-raccio kernel: hub 5-0:1.0: 1 port detected Sep 22 01:03:44 minidsp-raccio kernel: usbcore: registered new interface driver cdc_wdm Sep 22 01:03:44 minidsp-raccio kernel: usbcore: registered new interface driver uas Sep 22 01:03:44 minidsp-raccio kernel: usbcore: registered new interface driver usb-storage Sep 22 01:03:44 minidsp-raccio kernel: usbcore: registered new interface driver usbtouchscreen Sep 22 01:03:44 minidsp-raccio kernel: i2c /dev entries driver Sep 22 01:03:44 minidsp-raccio kernel: rk808 1-0018: chip id: 0x8050 Sep 22 01:03:44 minidsp-raccio kernel: rk808 1-0018: GPIO lookup for consumer dvs Sep 22 01:03:44 minidsp-raccio kernel: rk808 1-0018: using device tree for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'dvs-gpios' property of node '/i2c@ff160000/pmic@18[0]' Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'dvs-gpio' property of node '/i2c@ff160000/pmic@18[0]' Sep 22 01:03:44 minidsp-raccio kernel: rk808 1-0018: using lookup tables for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: rk808 1-0018: No GPIO consumer dvs found Sep 22 01:03:44 minidsp-raccio kernel: rk808-regulator rk808-regulator: there is no dvs0 gpio Sep 22 01:03:44 minidsp-raccio kernel: rk808 1-0018: GPIO lookup for consumer dvs Sep 22 01:03:44 minidsp-raccio kernel: rk808 1-0018: using device tree for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'dvs-gpios' property of node '/i2c@ff160000/pmic@18[1]' Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'dvs-gpio' property of node '/i2c@ff160000/pmic@18[1]' Sep 22 01:03:44 minidsp-raccio kernel: rk808 1-0018: using lookup tables for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: rk808 1-0018: No GPIO consumer dvs found Sep 22 01:03:44 minidsp-raccio kernel: rk808-regulator rk808-regulator: there is no dvs1 gpio Sep 22 01:03:44 minidsp-raccio kernel: vdd_logic: 712 <--> 1450 mV at 1100 mV Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: vcc_sd: unable to resolve supply Sep 22 01:03:44 minidsp-raccio kernel: rk808 1-0018: Looking up vcc1-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: vdd_logic: supplied by vcc_sys Sep 22 01:03:44 minidsp-raccio kernel: vdd_arm: 712 <--> 1450 mV at 1100 mV Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: vcc_sd: unable to resolve supply Sep 22 01:03:44 minidsp-raccio kernel: rk808 1-0018: Looking up vcc2-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: vdd_arm: supplied by vcc_sys Sep 22 01:03:44 minidsp-raccio kernel: vcc_ddr: no parameters Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: vcc_sd: unable to resolve supply Sep 22 01:03:44 minidsp-raccio kernel: rk808 1-0018: Looking up vcc3-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: vcc_ddr: supplied by vcc_sys Sep 22 01:03:44 minidsp-raccio kernel: vcc_io: 3300 mV Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: vcc_sd: unable to resolve supply Sep 22 01:03:44 minidsp-raccio kernel: rk808 1-0018: Looking up vcc4-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: vcc_io: supplied by vcc_sys Sep 22 01:03:44 minidsp-raccio kernel: vcc_18: 1800 mV Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: vcc_sd: unable to resolve supply Sep 22 01:03:44 minidsp-raccio kernel: rk808 1-0018: Looking up vcc5-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: vcc_18: supplied by vcc_io Sep 22 01:03:44 minidsp-raccio kernel: vcc18_emmc: 1800 mV Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: vcc_sd: unable to resolve supply Sep 22 01:03:44 minidsp-raccio kernel: rk808 1-0018: Looking up vcc5-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: vcc18_emmc: supplied by vcc_io Sep 22 01:03:44 minidsp-raccio kernel: vdd_10: 1000 mV Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: vcc_sd: unable to resolve supply Sep 22 01:03:44 minidsp-raccio kernel: rk808 1-0018: Looking up vcc6-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: vdd_10: supplied by vcc_io Sep 22 01:03:44 minidsp-raccio kernel: rk808-rtc rk808-rtc: registered as rtc0 Sep 22 01:03:44 minidsp-raccio kernel: IR NEC protocol handler initialized Sep 22 01:03:44 minidsp-raccio kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio4-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio5-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio6-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up pmuio-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: rockchip-thermal ff250000.tsadc: Missing tshut mode property, using default (cru) Sep 22 01:03:44 minidsp-raccio kernel: rockchip-thermal ff250000.tsadc: Missing tshut-polarity property, using default (low) Sep 22 01:03:44 minidsp-raccio kernel: device-mapper: ioctl: 4.41.0-ioctl (2019-09-16) initialised: dm-devel@redhat.com Sep 22 01:03:44 minidsp-raccio kernel: Bluetooth: HCI UART driver ver 2.3 Sep 22 01:03:44 minidsp-raccio kernel: Bluetooth: HCI UART protocol H4 registered Sep 22 01:03:44 minidsp-raccio kernel: Bluetooth: HCI UART protocol ATH3K registered Sep 22 01:03:44 minidsp-raccio kernel: usbcore: registered new interface driver bfusb Sep 22 01:03:44 minidsp-raccio kernel: usbcore: registered new interface driver btusb Sep 22 01:03:44 minidsp-raccio kernel: cpu cpu0: Looking up cpu-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: cpu cpu0: Looking up cpu-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: vdd_arm: ramp_delay not set Sep 22 01:03:44 minidsp-raccio kernel: vdd_arm: ramp_delay not set Sep 22 01:03:44 minidsp-raccio kernel: sdhci: Secure Digital Host Controller Interface driver Sep 22 01:03:44 minidsp-raccio kernel: sdhci: Copyright(c) Pierre Ossman Sep 22 01:03:44 minidsp-raccio kernel: Synopsys Designware Multimedia Card Interface Driver Sep 22 01:03:44 minidsp-raccio kernel: dwmmc_rockchip ff500000.dwmmc: IDMAC supports 32-bit address mode. Sep 22 01:03:44 minidsp-raccio kernel: dwmmc_rockchip ff500000.dwmmc: Using internal DMA controller. Sep 22 01:03:44 minidsp-raccio kernel: dwmmc_rockchip ff500000.dwmmc: Version ID is 270a Sep 22 01:03:44 minidsp-raccio kernel: dwmmc_rockchip ff500000.dwmmc: DW MMC controller at irq 30,32 bit host data width,256 deep fifo Sep 22 01:03:44 minidsp-raccio kernel: dwmmc_rockchip ff500000.dwmmc: Looking up vmmc-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: reg-fixed-voltage sdmmc-regulator: Looking up vin-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: vcc_sd: supplied by vcc_io Sep 22 01:03:44 minidsp-raccio kernel: dwmmc_rockchip ff500000.dwmmc: Looking up vqmmc-supply from device tree Sep 22 01:03:44 minidsp-raccio kernel: dwmmc_rockchip ff500000.dwmmc: GPIO lookup for consumer cd Sep 22 01:03:44 minidsp-raccio kernel: dwmmc_rockchip ff500000.dwmmc: using device tree for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'cd-gpios' property of node '/dwmmc@ff500000[0]' Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'cd-gpio' property of node '/dwmmc@ff500000[0]' Sep 22 01:03:44 minidsp-raccio kernel: dwmmc_rockchip ff500000.dwmmc: using lookup tables for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: dwmmc_rockchip ff500000.dwmmc: No GPIO consumer cd found Sep 22 01:03:44 minidsp-raccio kernel: dwmmc_rockchip ff500000.dwmmc: GPIO lookup for consumer wp Sep 22 01:03:44 minidsp-raccio kernel: dwmmc_rockchip ff500000.dwmmc: using device tree for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/dwmmc@ff500000[0]' Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/dwmmc@ff500000[0]' Sep 22 01:03:44 minidsp-raccio kernel: dwmmc_rockchip ff500000.dwmmc: using lookup tables for GPIO lookup Sep 22 01:03:44 minidsp-raccio kernel: dwmmc_rockchip ff500000.dwmmc: No GPIO consumer wp found Sep 22 01:03:44 minidsp-raccio kernel: mmc_host mmc0: Bus speed (slot 0) = 400000Hz (slot req 400000Hz, actual 400000HZ div = 0) Sep 22 01:03:44 minidsp-raccio kernel: sdhci-pltfm: SDHCI platform and OF driver helper Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: parsed 'gpios' property of node '/gpio-leds/led@1[0]' - status (0) Sep 22 01:03:44 minidsp-raccio kernel: gpio gpiochip0: Persistence not supported for GPIO 2 Sep 22 01:03:44 minidsp-raccio kernel: no flags found for gpios Sep 22 01:03:44 minidsp-raccio kernel: hidraw: raw HID events driver (C) Jiri Kosina Sep 22 01:03:44 minidsp-raccio kernel: usbcore: registered new interface driver usbhid Sep 22 01:03:44 minidsp-raccio kernel: usbhid: USB HID core driver Sep 22 01:03:44 minidsp-raccio kernel: u32 classifier Sep 22 01:03:44 minidsp-raccio kernel: input device check on Sep 22 01:03:44 minidsp-raccio kernel: Initializing XFRM netlink socket Sep 22 01:03:44 minidsp-raccio kernel: NET: Registered protocol family 10 Sep 22 01:03:44 minidsp-raccio kernel: Segment Routing with IPv6 Sep 22 01:03:44 minidsp-raccio kernel: NET: Registered protocol family 17 Sep 22 01:03:44 minidsp-raccio kernel: NET: Registered protocol family 15 Sep 22 01:03:44 minidsp-raccio kernel: Bridge firewalling registered Sep 22 01:03:44 minidsp-raccio kernel: Bluetooth: RFCOMM socket layer initialized Sep 22 01:03:44 minidsp-raccio kernel: Bluetooth: RFCOMM ver 1.11 Sep 22 01:03:44 minidsp-raccio kernel: Bluetooth: HIDP (Human Interface Emulation) ver 1.2 Sep 22 01:03:44 minidsp-raccio kernel: Bluetooth: HIDP socket layer initialized Sep 22 01:03:44 minidsp-raccio kernel: 8021q: 802.1Q VLAN Support v1.8 Sep 22 01:03:44 minidsp-raccio kernel: Key type dns_resolver registered Sep 22 01:03:44 minidsp-raccio kernel: registered taskstats version 1 Sep 22 01:03:44 minidsp-raccio kernel: Loading compiled-in X.509 certificates Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'simple-audio-card,hp-det-gpio' property of node '/i2s-sound[0]' Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'simple-audio-card,mic-det-gpio' property of node '/i2s-sound[0]' Sep 22 01:03:44 minidsp-raccio kernel: asoc-simple-card i2s-sound: pcm5102a-hifi <-> ff010000.i2s mapping ok Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'simple-audio-card,hp-det-gpio' property of node '/sound-spdif[0]' Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: can't parse 'simple-audio-card,mic-det-gpio' property of node '/sound-spdif[0]' Sep 22 01:03:44 minidsp-raccio kernel: asoc-simple-card sound-spdif: dit-hifi <-> ff030000.spdif mapping ok Sep 22 01:03:44 minidsp-raccio kernel: of_get_named_gpiod_flags: parsed 'gpios' property of node '/gpio-keys/button@0[0]' - status (0) Sep 22 01:03:44 minidsp-raccio kernel: gpio gpiochip0: Persistence not supported for GPIO 0 Sep 22 01:03:44 minidsp-raccio kernel: input: gpio-keys as /devices/platform/gpio-keys/input/input0 Sep 22 01:03:44 minidsp-raccio kernel: rk808-rtc rk808-rtc: setting system clock to 2016-01-21T08:50:06 UTC (1453366206) Sep 22 01:03:44 minidsp-raccio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Sep 22 01:03:44 minidsp-raccio kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Sep 22 01:03:44 minidsp-raccio kernel: platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 Sep 22 01:03:44 minidsp-raccio kernel: cfg80211: failed to load regulatory.db Sep 22 01:03:44 minidsp-raccio kernel: ALSA device list: Sep 22 01:03:44 minidsp-raccio kernel: #0: I2S Out Sep 22 01:03:44 minidsp-raccio kernel: #1: SPDIF Sep 22 01:03:44 minidsp-raccio kernel: Freeing unused kernel memory: 1152K Sep 22 01:03:44 minidsp-raccio kernel: Run /init as init process Sep 22 01:03:44 minidsp-raccio kernel: random: fast init done Sep 22 01:03:44 minidsp-raccio kernel: mmc_host mmc0: Bus speed (slot 0) = 150000000Hz (slot req 150000000Hz, actual 150000000HZ div = 0) Sep 22 01:03:44 minidsp-raccio kernel: dwmmc_rockchip ff500000.dwmmc: Successfully tuned phase to 181 Sep 22 01:03:44 minidsp-raccio kernel: mmc0: new ultra high speed SDR104 SDHC card at address aaaa Sep 22 01:03:44 minidsp-raccio kernel: mmcblk0: mmc0:aaaa SC32G 29.7 GiB Sep 22 01:03:44 minidsp-raccio kernel: mmcblk0: p1 p2 p3 Sep 22 01:03:44 minidsp-raccio initramfs: Booting Volumio for nanopineo3 Sep 22 01:03:44 minidsp-raccio initramfs: This script mounts rootfs RO with an overlay RW layer. Sep 22 01:03:44 minidsp-raccio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 22 01:03:44 minidsp-raccio initramfs: IMGPART=/dev/mmcblk0p2 Sep 22 01:03:44 minidsp-raccio initramfs: IMGFILE=/volumio_current.sqsh Sep 22 01:03:44 minidsp-raccio initramfs: DATAPART=/dev/mmcblk0p3 Sep 22 01:03:44 minidsp-raccio initramfs: BOOTPART=/dev/mmcblk0p1 Sep 22 01:03:44 minidsp-raccio initramfs: BOOTCONFIG=empty Sep 22 01:03:44 minidsp-raccio kernel: EXT4-fs (mmcblk0p2): recovery complete Sep 22 01:03:44 minidsp-raccio kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Sep 22 01:03:44 minidsp-raccio initramfs: Checking for USB updates if you did not boot from USB... Sep 22 01:03:44 minidsp-raccio initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Sep 22 01:03:44 minidsp-raccio kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Sep 22 01:03:44 minidsp-raccio kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Sep 22 01:03:44 minidsp-raccio kernel: EXT4-fs (mmcblk0p3): recovery complete Sep 22 01:03:44 minidsp-raccio kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Sep 22 01:03:44 minidsp-raccio initramfs: With Option: Sep 22 01:03:44 minidsp-raccio initramfs: found fstab entry with device /dev/mmcblk0p1 for /boot Sep 22 01:03:44 minidsp-raccio initramfs: VOLUMIO_VERSION="1.125" Sep 22 01:03:44 minidsp-raccio initramfs: Finish initramfs, continue booting Volumio Sep 22 01:03:44 minidsp-raccio kernel: random: systemd: uninitialized urandom read (16 bytes read) Sep 22 01:03:44 minidsp-raccio systemd[1]: systemd 215 running in system mode. (+PAM +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ -SECCOMP -APPARMOR) Sep 22 01:03:44 minidsp-raccio systemd[1]: Detected architecture 'arm64'. Sep 22 01:03:44 minidsp-raccio systemd[1]: Inserted module 'autofs4' Sep 22 01:03:44 minidsp-raccio systemd[1]: Set hostname to . Sep 22 01:03:44 minidsp-raccio kernel: random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) Sep 22 01:03:44 minidsp-raccio kernel: random: systemd: uninitialized urandom read (16 bytes read) Sep 22 01:03:44 minidsp-raccio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Sep 22 01:03:44 minidsp-raccio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Sep 22 01:03:44 minidsp-raccio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Sep 22 01:03:44 minidsp-raccio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Sep 22 01:03:44 minidsp-raccio systemd[1]: [/lib/systemd/system/volumio-remote-updater-push.service:6] Failed to parse usec_t value, ignoring: infinity Sep 22 01:03:44 minidsp-raccio systemd[1]: Configuration file /lib/systemd/system/reset-8821cu-port.service is marked executable. Please remove executable permission bits. Proceeding anyway. Sep 22 01:03:44 minidsp-raccio systemd-udevd[261]: starting version 215 Sep 22 01:03:44 minidsp-raccio kernel: Removed cp15_barrier emulation handler Sep 22 01:03:44 minidsp-raccio kernel: Enabled cp15_barrier support Sep 22 01:03:44 minidsp-raccio systemd-journal[241]: Journal started Sep 22 01:03:44 minidsp-raccio systemd[1]: Starting Slices. Sep 22 01:03:44 minidsp-raccio systemd[1]: Reached target Slices. Sep 22 01:03:44 minidsp-raccio systemd[1]: Starting Remount Root and Kernel File Systems... Sep 22 01:03:44 minidsp-raccio systemd[1]: Mounted Debug File System. Sep 22 01:03:44 minidsp-raccio systemd[1]: Mounted POSIX Message Queue File System. Sep 22 01:03:44 minidsp-raccio systemd[1]: Started Create list of required static device nodes for the current kernel. Sep 22 01:03:44 minidsp-raccio systemd[1]: Starting Create Static Device Nodes in /dev... Sep 22 01:03:44 minidsp-raccio systemd[1]: Started Restore / save the current clock. Sep 22 01:03:44 minidsp-raccio systemd[1]: Time has been changed Sep 22 01:03:44 minidsp-raccio systemd[1]: Started Remount Root and Kernel File Systems. Sep 22 01:03:44 minidsp-raccio systemd-modules-load[236]: Module 'fuse' is builtin Sep 22 01:03:44 minidsp-raccio systemd[1]: Mounted FUSE Control File System. Sep 22 01:03:44 minidsp-raccio systemd[1]: Mounted /var/spool/cups. Sep 22 01:03:44 minidsp-raccio systemd[1]: Mounted /tmp. Sep 22 01:03:44 minidsp-raccio systemd[1]: Mounted /var/log. Sep 22 01:03:44 minidsp-raccio systemd[1]: Started udev Kernel Device Manager. Sep 22 01:03:44 minidsp-raccio systemd[1]: Started udev Coldplug all Devices. Sep 22 01:03:44 minidsp-raccio systemd[1]: Started Apply Kernel Variables. Sep 22 01:03:44 minidsp-raccio systemd[1]: Started Load/Save Random Seed. Sep 22 01:03:44 minidsp-raccio fake-hwclock[227]: Thu Sep 22 01:03:44 UTC 2022 Sep 22 01:03:44 minidsp-raccio systemd[1]: Found device /dev/ttyS2. Sep 22 01:03:44 minidsp-raccio systemd[1]: Started Show Plymouth Boot Screen. Sep 22 01:03:44 minidsp-raccio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Sep 22 01:03:44 minidsp-raccio systemd[1]: Starting Dispatch Password Requests to Console Directory Watch. Sep 22 01:03:44 minidsp-raccio systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Sep 22 01:03:44 minidsp-raccio systemd[1]: Starting Paths. Sep 22 01:03:44 minidsp-raccio systemd[1]: Reached target Paths. Sep 22 01:03:44 minidsp-raccio systemd[1]: Starting Copy rules generated while the root was ro... Sep 22 01:03:44 minidsp-raccio systemd[1]: Mounting /var/spool/cups/tmp... Sep 22 01:03:44 minidsp-raccio systemd[1]: Mounted /var/spool/cups/tmp. Sep 22 01:03:44 minidsp-raccio systemd[1]: Started Copy rules generated while the root was ro. Sep 22 01:03:44 minidsp-raccio systemd[1]: Starting Sound Card. Sep 22 01:03:44 minidsp-raccio systemd[1]: Reached target Sound Card. Sep 22 01:03:45 minidsp-raccio systemd[1]: Starting system-ifup.slice. Sep 22 01:03:45 minidsp-raccio systemd[1]: Created slice system-ifup.slice. Sep 22 01:03:45 minidsp-raccio systemd[1]: Found device /dev/mmcblk0p1. Sep 22 01:03:45 minidsp-raccio systemd[1]: Starting File System Check on /dev/mmcblk0p1... Sep 22 01:03:45 minidsp-raccio systemd-fsck[374]: fsck.fat 3.0.27 (2014-11-12) Sep 22 01:03:45 minidsp-raccio systemd-fsck[374]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Sep 22 01:03:45 minidsp-raccio systemd-fsck[374]: Automatically removing dirty bit. Sep 22 01:03:45 minidsp-raccio systemd-fsck[374]: Performing changes. Sep 22 01:03:45 minidsp-raccio systemd-fsck[374]: /dev/mmcblk0p1: 14 files, 9728/31161 clusters Sep 22 01:03:45 minidsp-raccio systemd[1]: Started File System Check on /dev/mmcblk0p1. Sep 22 01:03:45 minidsp-raccio systemd[1]: Mounting /boot... Sep 22 01:03:45 minidsp-raccio systemd[1]: Mounted /boot. Sep 22 01:03:45 minidsp-raccio systemd[1]: Starting Local File Systems. Sep 22 01:03:45 minidsp-raccio systemd[1]: Reached target Local File Systems. Sep 22 01:03:45 minidsp-raccio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Sep 22 01:03:45 minidsp-raccio systemd[1]: Starting Remote File Systems. Sep 22 01:03:45 minidsp-raccio systemd[1]: Reached target Remote File Systems. Sep 22 01:03:45 minidsp-raccio systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage... Sep 22 01:03:45 minidsp-raccio systemd[1]: Starting Create Volatile Files and Directories... Sep 22 01:03:45 minidsp-raccio systemd[1]: Starting LSB: Raise network interfaces.... Sep 22 01:03:45 minidsp-raccio systemd[1]: Started Trigger Flushing of Journal to Persistent Storage. Sep 22 01:03:45 minidsp-raccio systemd[1]: Started Create Volatile Files and Directories. Sep 22 01:03:45 minidsp-raccio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Sep 22 01:03:45 minidsp-raccio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Sep 22 01:03:45 minidsp-raccio systemd[1]: Started Update UTMP about System Boot/Shutdown. Sep 22 01:03:45 minidsp-raccio networking[387]: Configuring network interfaces...command failed: No such device (-19) Sep 22 01:03:45 minidsp-raccio networking[387]: command failed: No such device (-19) Sep 22 01:03:45 minidsp-raccio networking[387]: command failed: No such device (-19) Sep 22 01:03:46 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet eth0: PHY [stmmac-0:01] driver [Generic PHY] Sep 22 01:03:46 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet eth0: No Safety Features support found Sep 22 01:03:46 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet eth0: PTP not supported by HW Sep 22 01:03:46 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet eth0: configuring for phy/rgmii link mode Sep 22 01:03:46 minidsp-raccio networking[387]: done. Sep 22 01:03:46 minidsp-raccio systemd[1]: Started LSB: Raise network interfaces.. Sep 22 01:03:46 minidsp-raccio systemd[1]: Starting ifup for eth0... Sep 22 01:03:46 minidsp-raccio systemd[1]: Started ifup for eth0. Sep 22 01:03:46 minidsp-raccio systemd[1]: Starting Network. Sep 22 01:03:46 minidsp-raccio systemd[1]: Reached target Network. Sep 22 01:03:46 minidsp-raccio systemd[1]: Starting Network is Online. Sep 22 01:03:46 minidsp-raccio systemd[1]: Reached target Network is Online. Sep 22 01:03:46 minidsp-raccio systemd[1]: Starting LSB: RPC portmapper replacement... Sep 22 01:03:46 minidsp-raccio ifup[517]: command failed: No such device (-19) Sep 22 01:03:46 minidsp-raccio ifup[517]: dhcpcd[540]: version 6.0.5 starting Sep 22 01:03:46 minidsp-raccio dhcpcd[540]: version 6.0.5 starting Sep 22 01:03:46 minidsp-raccio dhcpcd[540]: eth0: waiting for carrier Sep 22 01:03:46 minidsp-raccio ifup[517]: dhcpcd[540]: eth0: waiting for carrier Sep 22 01:03:46 minidsp-raccio rpcbind[518]: Starting rpcbind daemon.... Sep 22 01:03:46 minidsp-raccio systemd[1]: Started LSB: RPC portmapper replacement. Sep 22 01:03:46 minidsp-raccio systemd[1]: Starting RPC Port Mapper. Sep 22 01:03:46 minidsp-raccio systemd[1]: Reached target RPC Port Mapper. Sep 22 01:03:46 minidsp-raccio systemd[1]: Starting LSB: NFS support files common to client and server... Sep 22 01:03:46 minidsp-raccio rpc.statd[566]: Version 1.2.8 starting Sep 22 01:03:46 minidsp-raccio sm-notify[567]: Version 1.2.8 starting Sep 22 01:03:46 minidsp-raccio kernel: RPC: Registered named UNIX socket transport module. Sep 22 01:03:46 minidsp-raccio kernel: RPC: Registered udp transport module. Sep 22 01:03:46 minidsp-raccio kernel: RPC: Registered tcp transport module. Sep 22 01:03:46 minidsp-raccio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 22 01:03:46 minidsp-raccio kernel: FS-Cache: Loaded Sep 22 01:03:46 minidsp-raccio kernel: FS-Cache: Netfs 'nfs' registered for caching Sep 22 01:03:46 minidsp-raccio kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de). Sep 22 01:03:46 minidsp-raccio nfs-common[560]: Starting NFS common utilities: statd idmapd. Sep 22 01:03:46 minidsp-raccio systemd[1]: Started LSB: NFS support files common to client and server. Sep 22 01:03:46 minidsp-raccio systemd[1]: Starting System Initialization. Sep 22 01:03:46 minidsp-raccio systemd[1]: Reached target System Initialization. Sep 22 01:03:46 minidsp-raccio systemd[1]: Starting Avahi mDNS/DNS-SD Stack Activation Socket. Sep 22 01:03:46 minidsp-raccio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Sep 22 01:03:46 minidsp-raccio systemd[1]: Starting mpd.socket. Sep 22 01:03:46 minidsp-raccio systemd[1]: Listening on mpd.socket. Sep 22 01:03:46 minidsp-raccio systemd[1]: Starting D-Bus System Message Bus Socket. Sep 22 01:03:46 minidsp-raccio systemd[1]: Listening on D-Bus System Message Bus Socket. Sep 22 01:03:46 minidsp-raccio systemd[1]: Starting Sockets. Sep 22 01:03:46 minidsp-raccio systemd[1]: Reached target Sockets. Sep 22 01:03:46 minidsp-raccio systemd[1]: Starting Daily Cleanup of Temporary Directories. Sep 22 01:03:46 minidsp-raccio systemd[1]: Started Daily Cleanup of Temporary Directories. Sep 22 01:03:46 minidsp-raccio systemd[1]: Starting Timers. Sep 22 01:03:46 minidsp-raccio systemd[1]: Reached target Timers. Sep 22 01:03:46 minidsp-raccio systemd[1]: Started Manage Sound Card State (restore and store). Sep 22 01:03:46 minidsp-raccio systemd[1]: Starting Restore Sound Card State... Sep 22 01:03:46 minidsp-raccio systemd[1]: Starting Basic System. Sep 22 01:03:46 minidsp-raccio systemd[1]: Reached target Basic System. Sep 22 01:03:46 minidsp-raccio systemd[1]: Starting Entropy daemon using the HAVEGE algorithm... Sep 22 01:03:46 minidsp-raccio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Sep 22 01:03:46 minidsp-raccio systemd[1]: Starting dater.service... Sep 22 01:03:46 minidsp-raccio systemd[1]: Started dater.service. Sep 22 01:03:46 minidsp-raccio systemd[1]: Starting Volumio Iptables Module... Sep 22 01:03:46 minidsp-raccio systemd[1]: Started Volumio Iptables Module. Sep 22 01:03:46 minidsp-raccio systemd[1]: Starting Reset Realtek 8821CU Dongle USB Port (one-shot)... Sep 22 01:03:46 minidsp-raccio systemd[1]: Starting mount disks automatically with standby... Sep 22 01:03:46 minidsp-raccio systemd[1]: Started mount disks automatically with standby. Sep 22 01:03:46 minidsp-raccio systemd[1]: Starting UPnP Renderer front-end to MPD... Sep 22 01:03:46 minidsp-raccio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 22 01:03:46 minidsp-raccio systemd[1]: Starting volumio-remote-updater-push.service... Sep 22 01:03:46 minidsp-raccio systemd[1]: Starting volumio-remote-updater.service... Sep 22 01:03:47 minidsp-raccio systemd[1]: Started volumio-remote-updater.service. Sep 22 01:03:47 minidsp-raccio systemd[1]: Starting Volumio Log Rotation Service... Sep 22 01:03:47 minidsp-raccio systemd[1]: Started Volumio Log Rotation Service. Sep 22 01:03:47 minidsp-raccio systemd[1]: Starting Volumio SSH enabler... Sep 22 01:03:47 minidsp-raccio systemd[1]: Started Volumio SSH enabler. Sep 22 01:03:47 minidsp-raccio systemd[1]: Starting Wireless Services... Sep 22 01:03:47 minidsp-raccio systemd[1]: Starting /etc/rc.local Compatibility... Sep 22 01:03:47 minidsp-raccio systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available. Sep 22 01:03:47 minidsp-raccio systemd[1]: Starting Login Service... Sep 22 01:03:47 minidsp-raccio systemd[1]: Starting LSB: start Winbind daemon... Sep 22 01:03:47 minidsp-raccio systemd[1]: Starting LSB: triggerhappy hotkey daemon... Sep 22 01:03:47 minidsp-raccio systemd[1]: Starting LSB: start Samba daemons for the AD DC... Sep 22 01:03:47 minidsp-raccio systemd[1]: Starting LSB: Start NTP daemon... Sep 22 01:03:47 minidsp-raccio systemd[1]: Starting LSB: start Samba NetBIOS nameserver (nmbd)... Sep 22 01:03:47 minidsp-raccio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Sep 22 01:03:47 minidsp-raccio systemd[1]: Starting LSB: Brings up/down network automatically... Sep 22 01:03:47 minidsp-raccio systemd[1]: Starting LSB: Advanced IEEE 802.11 management daemon... Sep 22 01:03:47 minidsp-raccio systemd[1]: Starting LSB: IPv4 DHCP client with IPv4LL support... Sep 22 01:03:47 minidsp-raccio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Sep 22 01:03:47 minidsp-raccio systemd[1]: Starting Music Player Daemon... Sep 22 01:03:47 minidsp-raccio systemd[1]: Starting D-Bus System Message Bus... Sep 22 01:03:47 minidsp-raccio systemd[1]: Started D-Bus System Message Bus. Sep 22 01:03:47 minidsp-raccio triggerhappy[610]: Unable to parse trigger line: Sep 22 01:03:47 minidsp-raccio triggerhappy[610]: Unable to parse trigger line: Sep 22 01:03:47 minidsp-raccio triggerhappy[610]: Unable to parse trigger line: Sep 22 01:03:47 minidsp-raccio triggerhappy[610]: Unable to parse trigger line: Sep 22 01:03:47 minidsp-raccio triggerhappy[610]: Unable to parse trigger line: Sep 22 01:03:47 minidsp-raccio triggerhappy[610]: Unable to parse trigger line: Sep 22 01:03:47 minidsp-raccio triggerhappy[610]: Unable to parse trigger line: Sep 22 01:03:47 minidsp-raccio dhcpcd[629]: Not running dhcpcd because there is aleady an interface specific instance ... failed! Sep 22 01:03:47 minidsp-raccio dhcpcd[629]: /var/run/dhcpcd-eth0.pid ... failed! Sep 22 01:03:47 minidsp-raccio ifplugd(eth0)[697]: ifplugd 0.28 initializing. Sep 22 01:03:47 minidsp-raccio ifplugd(eth0)[697]: Using interface eth0/36:A7:5D:82:5D:E7 with driver (version: Jan_2016) Sep 22 01:03:47 minidsp-raccio ifplugd(eth0)[697]: Using detection mode: SIOCETHTOOL Sep 22 01:03:47 minidsp-raccio ifplugd(eth0)[697]: Initialization complete, link beat not detected. Sep 22 01:03:47 minidsp-raccio loadcpufreq[615]: Loading cpufreq kernel modules...done (none). Sep 22 01:03:47 minidsp-raccio ntpd[669]: ntpd 4.2.6p5@1.2349-o Fri Jul 22 17:59:22 UTC 2016 (1) Sep 22 01:03:47 minidsp-raccio ntp[612]: Starting NTP server: ntpd. Sep 22 01:03:47 minidsp-raccio ntpd[702]: proto: precision = 0.583 usec Sep 22 01:03:47 minidsp-raccio ntpd[702]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 Sep 22 01:03:47 minidsp-raccio ntpd[702]: Listen and drop on 1 v6wildcard :: UDP 123 Sep 22 01:03:47 minidsp-raccio ntpd[702]: Listen normally on 2 lo 127.0.0.1 UDP 123 Sep 22 01:03:47 minidsp-raccio ntpd[702]: peers refreshed Sep 22 01:03:47 minidsp-raccio ntpd[702]: Listening on routing socket on fd #19 for interface updates Sep 22 01:03:47 minidsp-raccio ifplugd[620]: Network Interface Plugging Daemon...start eth0...done. Sep 22 01:03:47 minidsp-raccio sudo[608]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Sep 22 01:03:47 minidsp-raccio sudo[608]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 22 01:03:47 minidsp-raccio dbus[649]: [system] Successfully activated service 'org.freedesktop.systemd1' Sep 22 01:03:47 minidsp-raccio systemd[1]: Starting Permit User Sessions... Sep 22 01:03:47 minidsp-raccio systemd[1]: Started Restore Sound Card State. Sep 22 01:03:47 minidsp-raccio wifistart.sh[602]: command failed: No such device (-19) Sep 22 01:03:47 minidsp-raccio sudo[608]: pam_unix(sudo:session): session closed for user root Sep 22 01:03:47 minidsp-raccio systemd[1]: haveged.service: main process exited, code=killed, status=11/SEGV Sep 22 01:03:47 minidsp-raccio systemd[1]: Unit haveged.service entered failed state. Sep 22 01:03:47 minidsp-raccio systemd[1]: Started /etc/rc.local Compatibility. Sep 22 01:03:47 minidsp-raccio systemd[1]: Started LSB: triggerhappy hotkey daemon. Sep 22 01:03:47 minidsp-raccio systemd[1]: Started LSB: Start NTP daemon. Sep 22 01:03:47 minidsp-raccio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Sep 22 01:03:47 minidsp-raccio systemd[1]: Started LSB: Brings up/down network automatically. Sep 22 01:03:47 minidsp-raccio systemd[1]: Started LSB: Advanced IEEE 802.11 management daemon. Sep 22 01:03:47 minidsp-raccio systemd[1]: dhcpcd.service: control process exited, code=exited status=6 Sep 22 01:03:47 minidsp-raccio systemd[1]: Failed to start LSB: IPv4 DHCP client with IPv4LL support. Sep 22 01:03:47 minidsp-raccio systemd[1]: Unit dhcpcd.service entered failed state. Sep 22 01:03:47 minidsp-raccio systemd[1]: Started Permit User Sessions. Sep 22 01:03:47 minidsp-raccio avahi-daemon[633]: Found user 'avahi' (UID 104) and group 'avahi' (GID 106). Sep 22 01:03:47 minidsp-raccio avahi-daemon[633]: Successfully dropped root privileges. Sep 22 01:03:47 minidsp-raccio avahi-daemon[633]: avahi-daemon 0.6.31 starting up. Sep 22 01:03:47 minidsp-raccio avahi-daemon[633]: Successfully called chroot(). Sep 22 01:03:47 minidsp-raccio avahi-daemon[633]: Successfully dropped remaining capabilities. Sep 22 01:03:47 minidsp-raccio avahi-daemon[633]: Loading service file /services/volumio.service. Sep 22 01:03:47 minidsp-raccio avahi-daemon[633]: Network interface enumeration completed. Sep 22 01:03:47 minidsp-raccio avahi-daemon[633]: Registering HINFO record with values 'AARCH64'/'LINUX'. Sep 22 01:03:47 minidsp-raccio avahi-daemon[633]: Server startup complete. Host name is minidsp-raccio.local. Local service cookie is 774343324. Sep 22 01:03:47 minidsp-raccio avahi-daemon[633]: Service "minidsp-Raccio" (/services/volumio.service) successfully established. Sep 22 01:03:47 minidsp-raccio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Sep 22 01:03:47 minidsp-raccio systemd-logind[606]: New seat seat0. Sep 22 01:03:47 minidsp-raccio systemd[1]: Started Login Service. Sep 22 01:03:47 minidsp-raccio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Sep 22 01:03:47 minidsp-raccio systemd[1]: Starting Wait for Plymouth Boot Screen to Quit... Sep 22 01:03:47 minidsp-raccio systemd[1]: Starting Terminate Plymouth Boot Screen... Sep 22 01:03:47 minidsp-raccio systemd[1]: Started Wait for Plymouth Boot Screen to Quit. Sep 22 01:03:47 minidsp-raccio systemd[1]: Started Terminate Plymouth Boot Screen. Sep 22 01:03:48 minidsp-raccio dbus[649]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service' Sep 22 01:03:48 minidsp-raccio systemd[1]: Starting Disk Manager (legacy version)... Sep 22 01:03:48 minidsp-raccio systemd[1]: Starting Serial Getty on ttyS2... Sep 22 01:03:48 minidsp-raccio systemd[1]: Started Serial Getty on ttyS2. Sep 22 01:03:48 minidsp-raccio systemd[1]: Starting Login Prompts. Sep 22 01:03:48 minidsp-raccio systemd[1]: Reached target Login Prompts. Sep 22 01:03:48 minidsp-raccio cpufrequtils[718]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Sep 22 01:03:48 minidsp-raccio systemd[1]: Started LSB: set CPUFreq kernel parameters. Sep 22 01:03:48 minidsp-raccio dbus[649]: [system] Successfully activated service 'org.freedesktop.UDisks' Sep 22 01:03:48 minidsp-raccio systemd[1]: Started Disk Manager (legacy version). Sep 22 01:03:48 minidsp-raccio dbus[649]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkitd.service' Sep 22 01:03:48 minidsp-raccio systemd[1]: Starting Authenticate and Authorize Users to Run Privileged Tasks... Sep 22 01:03:48 minidsp-raccio polkitd[738]: started daemon version 0.105 using authority implementation `local' version `0.105' Sep 22 01:03:48 minidsp-raccio dbus[649]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Sep 22 01:03:48 minidsp-raccio systemd[1]: Started Authenticate and Authorize Users to Run Privileged Tasks. Sep 22 01:03:48 minidsp-raccio volumio-remote-updater[598]: Error: No active session Sep 22 01:03:48 minidsp-raccio volumio-remote-updater[598]: [2022-09-22 01:03:48] [info] asio async_connect error: system:111 (Connection refused) Sep 22 01:03:48 minidsp-raccio volumio-remote-updater[598]: [2022-09-22 01:03:48] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 22 01:03:48 minidsp-raccio volumio-remote-updater[598]: [2022-09-22 01:03:48] [error] handle_connect error: Underlying Transport Error Sep 22 01:03:48 minidsp-raccio volumio[590]: Could not open config: /tmp/upmpdcli.conf Sep 22 01:03:48 minidsp-raccio udisks-glue[588]: Device file /dev/mmcblk0 inserted Sep 22 01:03:48 minidsp-raccio udisks-glue[588]: Device file /dev/mmcblk0p3 inserted Sep 22 01:03:48 minidsp-raccio systemd[1]: upmpdcli.service: main process exited, code=exited, status=1/FAILURE Sep 22 01:03:48 minidsp-raccio systemd[1]: Unit upmpdcli.service entered failed state. Sep 22 01:03:49 minidsp-raccio reset_8821cu_nic_usb_port.sh[713]: Realtek 8821CU Dongle not present. Sep 22 01:03:49 minidsp-raccio systemd[1]: Started Reset Realtek 8821CU Dongle USB Port (one-shot). Sep 22 01:03:49 minidsp-raccio ntpd[702]: Deferring DNS for 0.debian.pool.ntp.org 1 Sep 22 01:03:49 minidsp-raccio ntpd[702]: Deferring DNS for 1.debian.pool.ntp.org 1 Sep 22 01:03:49 minidsp-raccio ntpd[702]: Deferring DNS for 2.debian.pool.ntp.org 1 Sep 22 01:03:49 minidsp-raccio ntpd[702]: Deferring DNS for 3.debian.pool.ntp.org 1 Sep 22 01:03:49 minidsp-raccio ntpd[748]: signal_no_reset: signal 17 had flags 4000000 Sep 22 01:03:50 minidsp-raccio kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Sep 22 01:03:50 minidsp-raccio dhcpcd[540]: eth0: carrier acquired Sep 22 01:03:50 minidsp-raccio ifup[517]: dhcpcd[540]: eth0: carrier acquired Sep 22 01:03:50 minidsp-raccio ifup[517]: dhcpcd[540]: eth0: soliciting an IPv6 router Sep 22 01:03:50 minidsp-raccio dhcpcd[540]: eth0: soliciting an IPv6 router Sep 22 01:03:50 minidsp-raccio ifplugd(eth0)[697]: Link beat detected. Sep 22 01:03:50 minidsp-raccio ifplugd(eth0)[697]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Sep 22 01:03:50 minidsp-raccio ifplugd(eth0)[697]: client: /sbin/ifup: interface eth0 already configured Sep 22 01:03:50 minidsp-raccio ifplugd(eth0)[697]: Program executed successfully. Sep 22 01:03:51 minidsp-raccio ntpd_intres[748]: host name not found: 0.debian.pool.ntp.org Sep 22 01:03:51 minidsp-raccio ntpd_intres[748]: host name not found: 1.debian.pool.ntp.org Sep 22 01:03:51 minidsp-raccio ntpd_intres[748]: host name not found: 2.debian.pool.ntp.org Sep 22 01:03:51 minidsp-raccio ntpd_intres[748]: host name not found: 3.debian.pool.ntp.org Sep 22 01:03:52 minidsp-raccio wireless.js[715]: WIRELESS: No wireless interface, exiting Sep 22 01:03:52 minidsp-raccio systemd[1]: wireless.service: control process exited, code=exited status=1 Sep 22 01:03:52 minidsp-raccio systemd[1]: Failed to start Wireless Services. Sep 22 01:03:52 minidsp-raccio systemd[1]: Unit wireless.service entered failed state. Sep 22 01:03:52 minidsp-raccio systemd[1]: Starting Volumio Backend Module... Sep 22 01:03:52 minidsp-raccio systemd[1]: Started Volumio Backend Module. Sep 22 01:03:52 minidsp-raccio systemd[1]: Starting Volumio Streaming Daemon... Sep 22 01:03:52 minidsp-raccio systemd[1]: Started Volumio Streaming Daemon. Sep 22 01:03:52 minidsp-raccio systemd[1]: Started LSB: start Samba daemons for the AD DC. Sep 22 01:03:52 minidsp-raccio winbind[609]: Starting the Winbind daemon: winbindmkdir failed on directory /var/log/samba/cores: No such file or directory Sep 22 01:03:52 minidsp-raccio winbind[609]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Sep 22 01:03:52 minidsp-raccio winbind[609]: Unable to setup corepath for winbindd: No such file or directory Sep 22 01:03:52 minidsp-raccio winbind[609]: mkdir failed on directory /var/log/samba/cores: No such file or directory Sep 22 01:03:52 minidsp-raccio winbind[609]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Sep 22 01:03:52 minidsp-raccio winbind[609]: Unable to setup corepath for winbindd: No such file or directory Sep 22 01:03:52 minidsp-raccio winbind[609]: [2022/09/22 01:03:52, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 22 01:03:52 minidsp-raccio winbind[609]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Sep 22 01:03:52 minidsp-raccio winbind[609]: [2022/09/22 01:03:52, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 22 01:03:52 minidsp-raccio winbind[609]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Sep 22 01:03:52 minidsp-raccio winbind[609]: [2022/09/22 01:03:52, 0] ../source3/winbindd/winbindd.c:1549(main) Sep 22 01:03:52 minidsp-raccio winbind[609]: winbindd version 4.2.14-Debian started. Sep 22 01:03:52 minidsp-raccio winbind[609]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Sep 22 01:03:52 minidsp-raccio winbind[609]: [2022/09/22 01:03:52, 0] ../lib/util/util.c:220(directory_create_or_exist) Sep 22 01:03:52 minidsp-raccio winbind[609]: mkdir failed on directory /var/log/samba/cores: No such file or directory Sep 22 01:03:52 minidsp-raccio winbind[609]: [2022/09/22 01:03:52, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Sep 22 01:03:52 minidsp-raccio winbind[609]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Sep 22 01:03:52 minidsp-raccio winbind[609]: [2022/09/22 01:03:52, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Sep 22 01:03:52 minidsp-raccio winbind[609]: Unable to setup corepath for winbindd: No such file or directory Sep 22 01:03:52 minidsp-raccio nmbd[804]: [2022/09/22 01:03:52.899115, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 22 01:03:52 minidsp-raccio nmbd[804]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Sep 22 01:03:52 minidsp-raccio nmbd[804]: [2022/09/22 01:03:52.909521, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 22 01:03:52 minidsp-raccio nmbd[804]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Sep 22 01:03:52 minidsp-raccio nmbd[809]: [2022/09/22 01:03:52.917153, 0] ../source3/nmbd/asyncdns.c:157(start_async_dns) Sep 22 01:03:52 minidsp-raccio nmbd[809]: started asyncdns process 810 Sep 22 01:03:52 minidsp-raccio winbindd[797]: [2022/09/22 01:03:52.920468, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 22 01:03:52 minidsp-raccio winbindd[797]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Sep 22 01:03:52 minidsp-raccio winbindd[797]: [2022/09/22 01:03:52.922285, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 22 01:03:52 minidsp-raccio winbindd[797]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Sep 22 01:03:52 minidsp-raccio nmbd[809]: [2022/09/22 01:03:52.940717, 0] ../lib/util/become_daemon.c:135(daemon_status) Sep 22 01:03:52 minidsp-raccio nmbd[809]: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ...NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Sep 22 01:03:52 minidsp-raccio systemd[1]: Started Music Player Daemon. Sep 22 01:03:52 minidsp-raccio systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd). Sep 22 01:03:52 minidsp-raccio systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)... Sep 22 01:03:52 minidsp-raccio systemd[1]: Started LSB: start Winbind daemon. Sep 22 01:03:52 minidsp-raccio winbindd[812]: [2022/09/22 01:03:52.954457, 0] ../source3/winbindd/winbindd_cache.c:3235(initialize_winbindd_cache) Sep 22 01:03:52 minidsp-raccio winbindd[812]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Sep 22 01:03:52 minidsp-raccio winbind[609]: [2022/09/22 01:03:52.920468, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 22 01:03:52 minidsp-raccio winbind[609]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Sep 22 01:03:52 minidsp-raccio winbind[609]: [2022/09/22 01:03:52.922285, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 22 01:03:52 minidsp-raccio winbind[609]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Sep 22 01:03:52 minidsp-raccio volumio-streaming-daemon[783]: ############################ Sep 22 01:03:52 minidsp-raccio volumio-streaming-daemon[783]: # Volumio Streaming Daemon # Sep 22 01:03:52 minidsp-raccio volumio-streaming-daemon[783]: # Running on port 7777 # Sep 22 01:03:52 minidsp-raccio volumio-streaming-daemon[783]: ############################ Sep 22 01:03:52 minidsp-raccio volumio-streaming-daemon[783]: Checking variant in static cache Sep 22 01:03:52 minidsp-raccio volumio-streaming-daemon[783]: Enabled services: Sep 22 01:03:52 minidsp-raccio volumio-streaming-daemon[783]: 1) tidal Sep 22 01:03:52 minidsp-raccio volumio-streaming-daemon[783]: 2) qobuz Sep 22 01:03:52 minidsp-raccio nmbd[613]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: No such file or directory Sep 22 01:03:52 minidsp-raccio nmbd[613]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Sep 22 01:03:52 minidsp-raccio nmbd[613]: Unable to setup corepath for nmbd: No such file or directory Sep 22 01:03:52 minidsp-raccio nmbd[613]: [2022/09/22 01:03:52, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 22 01:03:52 minidsp-raccio nmbd[613]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Sep 22 01:03:52 minidsp-raccio nmbd[613]: [2022/09/22 01:03:52, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 22 01:03:52 minidsp-raccio nmbd[613]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Sep 22 01:03:52 minidsp-raccio nmbd[613]: [2022/09/22 01:03:52, 0] ../source3/nmbd/nmbd.c:908(main) Sep 22 01:03:52 minidsp-raccio nmbd[613]: nmbd version 4.2.14-Debian started. Sep 22 01:03:52 minidsp-raccio nmbd[613]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Sep 22 01:03:52 minidsp-raccio nmbd[613]: [2022/09/22 01:03:52.899115, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 22 01:03:52 minidsp-raccio nmbd[613]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Sep 22 01:03:52 minidsp-raccio nmbd[613]: [2022/09/22 01:03:52.909521, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 22 01:03:52 minidsp-raccio nmbd[613]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Sep 22 01:03:52 minidsp-raccio winbind[609]: . Sep 22 01:03:52 minidsp-raccio nmbd[613]: . Sep 22 01:03:53 minidsp-raccio winbindd[812]: [2022/09/22 01:03:53.001648, 0] ../lib/util/become_daemon.c:124(daemon_ready) Sep 22 01:03:53 minidsp-raccio winbindd[812]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Sep 22 01:03:53 minidsp-raccio winbindd[818]: [2022/09/22 01:03:53.011277, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 22 01:03:53 minidsp-raccio winbindd[818]: Unable to open new log file '/var/log/samba/log.wb-MINIDSP-RACCIO': No such file or directory Sep 22 01:03:53 minidsp-raccio volumio-remote-updater[598]: [2022-09-22 01:03:53] [info] asio async_connect error: system:111 (Connection refused) Sep 22 01:03:53 minidsp-raccio volumio-remote-updater[598]: [2022-09-22 01:03:53] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 22 01:03:53 minidsp-raccio volumio-remote-updater[598]: [2022-09-22 01:03:53] [error] handle_connect error: Underlying Transport Error Sep 22 01:03:54 minidsp-raccio smbd[811]: Starting SMB/CIFS daemon: smbd[2022/09/22 01:03:54, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 22 01:03:54 minidsp-raccio smbd[811]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Sep 22 01:03:54 minidsp-raccio smbd[811]: [2022/09/22 01:03:54, 0] ../lib/util/util.c:220(directory_create_or_exist) Sep 22 01:03:54 minidsp-raccio smbd[811]: mkdir failed on directory /var/log/samba/cores: No such file or directory Sep 22 01:03:54 minidsp-raccio smbd[811]: [2022/09/22 01:03:54, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Sep 22 01:03:54 minidsp-raccio smbd[811]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Sep 22 01:03:54 minidsp-raccio smbd[811]: [2022/09/22 01:03:54, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Sep 22 01:03:54 minidsp-raccio smbd[811]: Unable to setup corepath for smbd: No such file or directory Sep 22 01:03:54 minidsp-raccio smbd[811]: [2022/09/22 01:03:54, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 22 01:03:54 minidsp-raccio smbd[811]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Sep 22 01:03:54 minidsp-raccio smbd[811]: [2022/09/22 01:03:54, 0] ../source3/smbd/server.c:1241(main) Sep 22 01:03:54 minidsp-raccio smbd[811]: smbd version 4.2.14-Debian started. Sep 22 01:03:54 minidsp-raccio smbd[811]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Sep 22 01:03:54 minidsp-raccio smbd[823]: [2022/09/22 01:03:54.551887, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 22 01:03:54 minidsp-raccio smbd[823]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Sep 22 01:03:54 minidsp-raccio smbd[823]: [2022/09/22 01:03:54.554099, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 22 01:03:54 minidsp-raccio smbd[823]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Sep 22 01:03:54 minidsp-raccio smbd[823]: [2022/09/22 01:03:54.554762, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 22 01:03:54 minidsp-raccio smbd[823]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Sep 22 01:03:54 minidsp-raccio smbd[811]: [2022/09/22 01:03:54.551887, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 22 01:03:54 minidsp-raccio smbd[811]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Sep 22 01:03:54 minidsp-raccio smbd[811]: [2022/09/22 01:03:54.554099, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 22 01:03:54 minidsp-raccio smbd[811]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Sep 22 01:03:54 minidsp-raccio smbd[811]: [2022/09/22 01:03:54.554762, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 22 01:03:54 minidsp-raccio smbd[811]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Sep 22 01:03:54 minidsp-raccio smbd[811]: . Sep 22 01:03:54 minidsp-raccio systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd). Sep 22 01:03:54 minidsp-raccio winbindd[827]: [2022/09/22 01:03:54.666358, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 22 01:03:54 minidsp-raccio winbindd[827]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Sep 22 01:03:54 minidsp-raccio smbd[824]: [2022/09/22 01:03:54.698903, 0] ../lib/util/become_daemon.c:124(daemon_ready) Sep 22 01:03:54 minidsp-raccio smbd[824]: STATUS=daemon 'smbd' finished starting up and ready to serve connections Sep 22 01:03:54 minidsp-raccio smbd[828]: [2022/09/22 01:03:54.706441, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 22 01:03:54 minidsp-raccio smbd[828]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Sep 22 01:03:55 minidsp-raccio dhcpcd[540]: eth0: using IPv4LL address 169.254.175.132 Sep 22 01:03:55 minidsp-raccio dhcpcd[540]: eth0: adding host route to 169.254.175.132 via 127.0.0.1 Sep 22 01:03:55 minidsp-raccio dhcpcd[540]: eth0: adding route to 169.254.0.0/16 Sep 22 01:03:55 minidsp-raccio avahi-daemon[633]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.175.132. Sep 22 01:03:55 minidsp-raccio avahi-daemon[633]: New relevant interface eth0.IPv4 for mDNS. Sep 22 01:03:55 minidsp-raccio avahi-daemon[633]: Registering new address record for 169.254.175.132 on eth0.IPv4. Sep 22 01:03:55 minidsp-raccio kernel: random: crng init done Sep 22 01:03:55 minidsp-raccio kernel: random: 7 urandom warning(s) missed due to ratelimiting Sep 22 01:03:55 minidsp-raccio ifup[517]: dhcpcd[540]: eth0: using IPv4LL address 169.254.175.132 Sep 22 01:03:55 minidsp-raccio ifup[517]: dhcpcd[540]: eth0: adding host route to 169.254.175.132 via 127.0.0.1 Sep 22 01:03:55 minidsp-raccio ifup[517]: dhcpcd[540]: eth0: adding route to 169.254.0.0/16 Sep 22 01:03:55 minidsp-raccio ifup[517]: dhcpcd[540]: forked to background, child pid 843 Sep 22 01:03:55 minidsp-raccio dhcpcd[540]: forked to background, child pid 843 Sep 22 01:03:57 minidsp-raccio volumio[782]: info: ------------------------------------------- Sep 22 01:03:57 minidsp-raccio volumio[782]: info: ----- Volumio2 ---- Sep 22 01:03:57 minidsp-raccio volumio[782]: info: ------------------------------------------- Sep 22 01:03:57 minidsp-raccio volumio[782]: info: ----- System startup ---- Sep 22 01:03:57 minidsp-raccio volumio[782]: info: ------------------------------------------- Sep 22 01:03:57 minidsp-raccio ntpd[702]: Listen normally on 3 eth0 169.254.175.132 UDP 123 Sep 22 01:03:57 minidsp-raccio ntpd[702]: peers refreshed Sep 22 01:03:57 minidsp-raccio nmbd[809]: [2022/09/22 01:03:57.948758, 0] ../lib/util/become_daemon.c:124(daemon_ready) Sep 22 01:03:57 minidsp-raccio nmbd[809]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Sep 22 01:03:59 minidsp-raccio dhcpcd[843]: eth0: soliciting a DHCP lease Sep 22 01:03:59 minidsp-raccio volumio[782]: info: Plugin folders cleanup Sep 22 01:03:59 minidsp-raccio volumio[782]: info: Scanning into folder /volumio/app/plugins/ Sep 22 01:03:59 minidsp-raccio volumio[782]: info: Scanning category audio_interface Sep 22 01:03:59 minidsp-raccio volumio[782]: info: Scanning category miscellanea Sep 22 01:03:59 minidsp-raccio volumio[782]: info: Scanning category music_service Sep 22 01:03:59 minidsp-raccio volumio[782]: info: Scanning category plugins.json Sep 22 01:03:59 minidsp-raccio volumio[782]: info: Scanning category system_controller Sep 22 01:03:59 minidsp-raccio volumio[782]: info: Scanning category user_interface Sep 22 01:03:59 minidsp-raccio volumio[782]: info: Scanning into folder /data/plugins/ Sep 22 01:03:59 minidsp-raccio volumio[782]: info: Plugin folders cleanup completed Sep 22 01:03:59 minidsp-raccio volumio[782]: info: ------------------------------------------- Sep 22 01:03:59 minidsp-raccio volumio[782]: info: ----- Core plugins startup ---- Sep 22 01:03:59 minidsp-raccio volumio[782]: info: ------------------------------------------- Sep 22 01:03:59 minidsp-raccio volumio[782]: info: Loading plugins from folder /volumio/app/plugins/ Sep 22 01:03:59 minidsp-raccio volumio[782]: info: Adding plugin bluetooth to MyMusic Plugins Sep 22 01:03:59 minidsp-raccio volumio[782]: info: Adding plugin upnp to MyMusic Plugins Sep 22 01:03:59 minidsp-raccio volumio[782]: info: Adding plugin metavolumio to MyMusic Plugins Sep 22 01:03:59 minidsp-raccio volumio[782]: info: Adding plugin ms_surface_dial to MyMusic Plugins Sep 22 01:03:59 minidsp-raccio volumio[782]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 22 01:03:59 minidsp-raccio volumio[782]: info: Adding plugin cd_controller to MyMusic Plugins Sep 22 01:03:59 minidsp-raccio volumio[782]: info: Adding plugin raat to MyMusic Plugins Sep 22 01:03:59 minidsp-raccio volumio[782]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 22 01:03:59 minidsp-raccio volumio[782]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 22 01:03:59 minidsp-raccio volumio[782]: info: Loading plugins from folder /data/plugins/ Sep 22 01:03:59 minidsp-raccio volumio[782]: info: Loading plugin "system"... Sep 22 01:03:59 minidsp-raccio volumio[782]: info: Loading plugin "appearance"... Sep 22 01:03:59 minidsp-raccio ntpd_intres[748]: host name not found: 0.debian.pool.ntp.org Sep 22 01:03:59 minidsp-raccio ntpd_intres[748]: host name not found: 1.debian.pool.ntp.org Sep 22 01:03:59 minidsp-raccio ntpd_intres[748]: host name not found: 2.debian.pool.ntp.org Sep 22 01:03:59 minidsp-raccio ntpd_intres[748]: host name not found: 3.debian.pool.ntp.org Sep 22 01:04:01 minidsp-raccio volumio-remote-updater[598]: [2022-09-22 01:04:01] [connect] Successful connection Sep 22 01:04:01 minidsp-raccio volumio[782]: info: Loading plugin "network"... Sep 22 01:04:01 minidsp-raccio volumio[782]: info: Refreshing Cached IP Addresses Sep 22 01:04:01 minidsp-raccio volumio[782]: info: Loading plugin "services"... Sep 22 01:04:01 minidsp-raccio sudo[898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 22 01:04:01 minidsp-raccio sudo[898]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 22 01:04:01 minidsp-raccio volumio[782]: info: Loading plugin "alsa_controller"... Sep 22 01:04:01 minidsp-raccio sudo[898]: pam_unix(sudo:session): session closed for user root Sep 22 01:04:01 minidsp-raccio sudo[900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 22 01:04:01 minidsp-raccio sudo[900]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 22 01:04:01 minidsp-raccio sudo[900]: pam_unix(sudo:session): session closed for user root Sep 22 01:04:01 minidsp-raccio volumio[782]: info: Loading plugin "wizard"... Sep 22 01:04:01 minidsp-raccio volumio[782]: info: Loading plugin "volumio_command_line_client"... Sep 22 01:04:01 minidsp-raccio volumio[782]: info: Loading plugin "upnp"... Sep 22 01:04:02 minidsp-raccio volumio[782]: info: [1663808642009] Starting Upmpd Daemon Sep 22 01:04:02 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 22 01:04:02 minidsp-raccio volumio[782]: info: Loading plugin "my_music"... Sep 22 01:04:02 minidsp-raccio volumio[782]: info: Loading plugin "mpd"... Sep 22 01:04:02 minidsp-raccio volumio[782]: info: Loading plugin "upnp_browser"... Sep 22 01:04:03 minidsp-raccio volumio[782]: info: Loading plugin "networkfs"... Sep 22 01:04:04 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 22 01:04:04 minidsp-raccio volumio[782]: info: Loading plugin "bluetooth"... Sep 22 01:04:04 minidsp-raccio volumio[782]: info: [1663808644505] Starting BluetoothController Sep 22 01:04:04 minidsp-raccio volumio[782]: info: Loading plugin "alarm-clock"... Sep 22 01:04:05 minidsp-raccio volumio[782]: info: Loading plugin "metavolumio"... Sep 22 01:04:05 minidsp-raccio volumio[782]: info: Loading plugin "airplay_emulation"... Sep 22 01:04:05 minidsp-raccio volumio[782]: info: Starting Shairport Sync Sep 22 01:04:05 minidsp-raccio volumio[782]: info: Loading plugin "cd_controller"... Sep 22 01:04:05 minidsp-raccio volumio[782]: info: Loading plugin "last_100"... Sep 22 01:04:05 minidsp-raccio volumio[782]: info: Loading plugin "raat"... Sep 22 01:04:05 minidsp-raccio volumio[782]: info: RAAT Plugin loaded Sep 22 01:04:05 minidsp-raccio volumio[782]: info: Adding restartRAATSocket REST API Endpoint Sep 22 01:04:05 minidsp-raccio volumio[782]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Sep 22 01:04:05 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: inputs , getVolume Sep 22 01:04:05 minidsp-raccio volumio[782]: error: Could not retrieve plugin music_service inputs Sep 22 01:04:05 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: inputs , getDSP Sep 22 01:04:05 minidsp-raccio volumio[782]: error: Could not retrieve plugin music_service inputs Sep 22 01:04:05 minidsp-raccio volumio[782]: info: Loading plugin "streaming_services"... Sep 22 01:04:05 minidsp-raccio volumio[782]: info: Loading plugin "tidalconnect"... Sep 22 01:04:06 minidsp-raccio volumio[782]: info: Loading plugin "webradio"... Sep 22 01:04:06 minidsp-raccio volumio[782]: info: Loading plugin "i2s_dacs"... Sep 22 01:04:06 minidsp-raccio volumio[782]: info: I2S DAC not set, start Auto-detection Sep 22 01:04:06 minidsp-raccio volumio[782]: info: Loading plugin "volumiodiscovery"... Sep 22 01:04:06 minidsp-raccio volumio[782]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 22 01:04:06 minidsp-raccio node[782]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 22 01:04:06 minidsp-raccio node[782]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 22 01:04:06 minidsp-raccio node[782]: *** WARNING *** For more information see Sep 22 01:04:06 minidsp-raccio node[782]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 22 01:04:06 minidsp-raccio node[782]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 22 01:04:06 minidsp-raccio node[782]: *** WARNING *** For more information see Sep 22 01:04:06 minidsp-raccio volumio[782]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 22 01:04:06 minidsp-raccio volumio[782]: *** WARNING *** For more information see Sep 22 01:04:06 minidsp-raccio volumio[782]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 22 01:04:06 minidsp-raccio volumio[782]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 22 01:04:06 minidsp-raccio volumio[782]: *** WARNING *** For more information see Sep 22 01:04:06 minidsp-raccio volumio[782]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 22 01:04:06 minidsp-raccio volumio[782]: Discovery: StartAdv! undefined Sep 22 01:04:06 minidsp-raccio volumio[782]: Discovery: Started advertising... minidsp-raccio - undefined Sep 22 01:04:06 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 22 01:04:06 minidsp-raccio volumio[782]: info: Loading plugin "outputs"... Sep 22 01:04:06 minidsp-raccio volumio[782]: info: Loading plugin "albumart"... Sep 22 01:04:06 minidsp-raccio volumio[782]: info: Loading plugin "ms_surface_dial"... Sep 22 01:04:07 minidsp-raccio volumio[782]: Forking 3 albumart workers Sep 22 01:04:08 minidsp-raccio volumio[782]: info: Applying required configuration parameters for plugin ms_surface_dial Sep 22 01:04:08 minidsp-raccio volumio[782]: info: Plugin example_plugin is not enabled Sep 22 01:04:08 minidsp-raccio volumio[782]: info: Loading plugin "hi_res_audio"... Sep 22 01:04:08 minidsp-raccio volumio[782]: info: Applying required configuration parameters for plugin hi_res_audio Sep 22 01:04:08 minidsp-raccio volumio[782]: info: Loading plugin "inputs"... Sep 22 01:04:08 minidsp-raccio volumio[782]: info: Loading plugin "qobuz"... Sep 22 01:04:09 minidsp-raccio volumio[782]: Starting albumart workers Sep 22 01:04:09 minidsp-raccio volumio[782]: Starting albumart workers Sep 22 01:04:09 minidsp-raccio volumio[782]: Starting albumart workers Sep 22 01:04:11 minidsp-raccio dhcpcd[843]: eth0: offered 10.21.71.200 from 10.21.71.193 Sep 22 01:04:12 minidsp-raccio volumio[782]: info: Loading plugin "tidal"... Sep 22 01:04:15 minidsp-raccio volumio[782]: info: Loading plugin "updater_comm"... Sep 22 01:04:15 minidsp-raccio volumio[782]: info: Plugin mpdemulation is not enabled Sep 22 01:04:15 minidsp-raccio volumio[782]: info: Loading plugin "rest_api"... Sep 22 01:04:15 minidsp-raccio volumio[782]: info: Loading plugin "websocket"... Sep 22 01:04:15 minidsp-raccio volumio[782]: info: ___________ START PLUGINS ___________ Sep 22 01:04:15 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 22 01:04:15 minidsp-raccio volumio[782]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 22 01:04:15 minidsp-raccio volumio[782]: info: [1663808655812] CoreMusicLibrary::Adding element Media Servers Sep 22 01:04:15 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 22 01:04:15 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 22 01:04:15 minidsp-raccio dhcpcd[843]: eth0: leased 10.21.71.200 for 3600 seconds Sep 22 01:04:15 minidsp-raccio dhcpcd[843]: eth0: adding host route to 10.21.71.200 via 127.0.0.1 Sep 22 01:04:15 minidsp-raccio dhcpcd[843]: eth0: adding route to 10.21.71.192/27 Sep 22 01:04:15 minidsp-raccio dhcpcd[843]: eth0: adding default route via 10.21.71.193 Sep 22 01:04:15 minidsp-raccio dhcpcd[843]: eth0: deleting host route to 169.254.175.132 via 127.0.0.1 Sep 22 01:04:15 minidsp-raccio dhcpcd[843]: eth0: deleting route to 169.254.0.0/16 Sep 22 01:04:15 minidsp-raccio avahi-daemon[633]: Registering new address record for 10.21.71.200 on eth0.IPv4. Sep 22 01:04:15 minidsp-raccio avahi-daemon[633]: Withdrawing address record for 169.254.175.132 on eth0. Sep 22 01:04:15 minidsp-raccio avahi-daemon[633]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.175.132. Sep 22 01:04:15 minidsp-raccio avahi-daemon[633]: Joining mDNS multicast group on interface eth0.IPv4 with address 10.21.71.200. Sep 22 01:04:15 minidsp-raccio volumio[782]: info: Adding METAVOLUMIO REST API Endpoints Sep 22 01:04:15 minidsp-raccio volumio[782]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Sep 22 01:04:15 minidsp-raccio sudo[964]: pam_unix(sudo:auth): conversation failed Sep 22 01:04:15 minidsp-raccio sudo[964]: pam_unix(sudo:auth): auth could not identify password for [volumio] Sep 22 01:04:16 minidsp-raccio volumio[782]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 22 01:04:16 minidsp-raccio volumio[782]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 22 01:04:16 minidsp-raccio volumio[782]: info: Preparing CD Folders Sep 22 01:04:16 minidsp-raccio volumio[782]: info: Adding CD REST API Endpoints Sep 22 01:04:16 minidsp-raccio volumio[782]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Sep 22 01:04:16 minidsp-raccio volumio[782]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Sep 22 01:04:16 minidsp-raccio volumio[782]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Sep 22 01:04:16 minidsp-raccio volumio[782]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Sep 22 01:04:16 minidsp-raccio volumio[782]: info: Detecting CD presence with Flag file Sep 22 01:04:16 minidsp-raccio volumio[782]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 22 01:04:16 minidsp-raccio volumio[782]: info: [1663808656100] CoreMusicLibrary::Adding element Last_100 Sep 22 01:04:16 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 22 01:04:16 minidsp-raccio volumio[782]: info: Starting RAAT Plugin Sep 22 01:04:16 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Sep 22 01:04:16 minidsp-raccio volumio[782]: info: Additional UI Settings Added for plugin music_service/raat Sep 22 01:04:16 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 22 01:04:16 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 22 01:04:16 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 22 01:04:16 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 22 01:04:16 minidsp-raccio volumio[782]: info: Not Reporting Auto name since its the default one Sep 22 01:04:16 minidsp-raccio volumio[782]: info: Streaming services startup Sep 22 01:04:16 minidsp-raccio volumio[782]: info: Starting Streaming Daemon Sep 22 01:04:16 minidsp-raccio volumio-remote-updater[598]: [2022-09-22 01:04:16] [connect] Successful connection Sep 22 01:04:16 minidsp-raccio sudo[1000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 22 01:04:16 minidsp-raccio sudo[1000]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 22 01:04:16 minidsp-raccio systemd[1]: Stopping Volumio Streaming Daemon... Sep 22 01:04:16 minidsp-raccio systemd[1]: Starting Volumio Streaming Daemon... Sep 22 01:04:16 minidsp-raccio systemd[1]: Started Volumio Streaming Daemon. Sep 22 01:04:16 minidsp-raccio sudo[1000]: pam_unix(sudo:session): session closed for user root Sep 22 01:04:16 minidsp-raccio volumio[782]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 22 01:04:16 minidsp-raccio volumio-streaming-daemon[1007]: ############################ Sep 22 01:04:16 minidsp-raccio volumio-streaming-daemon[1007]: # Volumio Streaming Daemon # Sep 22 01:04:16 minidsp-raccio volumio-streaming-daemon[1007]: # Running on port 7777 # Sep 22 01:04:16 minidsp-raccio volumio-streaming-daemon[1007]: ############################ Sep 22 01:04:16 minidsp-raccio volumio[782]: info: [1663808656344] CoreMusicLibrary::Adding element Webradio Sep 22 01:04:16 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 22 01:04:16 minidsp-raccio volumio-streaming-daemon[1007]: Checking variant in static cache Sep 22 01:04:16 minidsp-raccio volumio-streaming-daemon[1007]: Enabled services: Sep 22 01:04:16 minidsp-raccio volumio-streaming-daemon[1007]: 1) tidal Sep 22 01:04:16 minidsp-raccio volumio-streaming-daemon[1007]: 2) qobuz Sep 22 01:04:16 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 22 01:04:16 minidsp-raccio sudo[1014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 22 01:04:16 minidsp-raccio sudo[1014]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 22 01:04:16 minidsp-raccio systemd[1]: Configuration file /lib/systemd/system/vtcs.service is marked executable. Please remove executable permission bits. Proceeding anyway. Sep 22 01:04:16 minidsp-raccio systemd[1]: Stopped Volumio Tidal Connect Service. Sep 22 01:04:16 minidsp-raccio systemd[1]: Configuration file /lib/systemd/system/vtcs.service is marked executable. Please remove executable permission bits. Proceeding anyway. Sep 22 01:04:16 minidsp-raccio systemd[1]: Configuration file /lib/systemd/system/vtcs.service is marked executable. Please remove executable permission bits. Proceeding anyway. Sep 22 01:04:16 minidsp-raccio systemd[1]: Configuration file /lib/systemd/system/vtcs.service is marked executable. Please remove executable permission bits. Proceeding anyway. Sep 22 01:04:16 minidsp-raccio volumio[782]: error: Hi Res Audio Failed Login: Missing Login Data Sep 22 01:04:16 minidsp-raccio sudo[1014]: pam_unix(sudo:session): session closed for user root Sep 22 01:04:16 minidsp-raccio volumio[782]: info: Refreshing TIDAL token Sep 22 01:04:16 minidsp-raccio volumio[782]: info: Loading i18n strings for locale en Sep 22 01:04:16 minidsp-raccio volumio[782]: Updating browse sources language Sep 22 01:04:16 minidsp-raccio volumio[782]: Cannot find translation for sourceMedia Servers Sep 22 01:04:16 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 22 01:04:16 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 22 01:04:16 minidsp-raccio volumio[782]: info: CoreCommandRouter::initPlayerControls Sep 22 01:04:16 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 22 01:04:16 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Sep 22 01:04:16 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 22 01:04:16 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 22 01:04:16 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 22 01:04:16 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 22 01:04:16 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 22 01:04:16 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 22 01:04:16 minidsp-raccio volumio[782]: info: BOOT COMPLETED Sep 22 01:04:16 minidsp-raccio volumio[782]: [Metrics] CommandRouter: 19s 402.56ms Sep 22 01:04:16 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 22 01:04:17 minidsp-raccio ntpd[702]: Listen normally on 4 eth0 10.21.71.200 UDP 123 Sep 22 01:04:17 minidsp-raccio ntpd[702]: Deleting interface #3 eth0, 169.254.175.132#123, interface stats: received=0, sent=0, dropped=0, active_time=20 secs Sep 22 01:04:17 minidsp-raccio ntpd[702]: peers refreshed Sep 22 01:04:19 minidsp-raccio ntpd_intres[748]: DNS 0.debian.pool.ntp.org -> 45.33.53.84 Sep 22 01:04:19 minidsp-raccio ntpd_intres[748]: DNS 1.debian.pool.ntp.org -> 23.155.72.147 Sep 22 01:04:19 minidsp-raccio ntpd_intres[748]: DNS 2.debian.pool.ntp.org -> 23.186.168.126 Sep 22 01:04:19 minidsp-raccio ntpd_intres[748]: DNS 3.debian.pool.ntp.org -> 23.111.186.186 Sep 22 01:04:21 minidsp-raccio volumio[782]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Sep 22 01:04:21 minidsp-raccio volumio[782]: info: CoreCommandRouter::Close All Modals sent Sep 22 01:04:21 minidsp-raccio volumio[782]: info: CoreCommandRouter::Close All Modals sent Sep 22 01:04:21 minidsp-raccio volumio[782]: Express server listening on port 3000 Sep 22 01:04:21 minidsp-raccio volumio[782]: [Metrics] WebUI: 26s 284.39ms Sep 22 01:04:21 minidsp-raccio volumio[782]: Volumio Calling Home Sep 22 01:04:22 minidsp-raccio volumio[782]: info: CoreStateMachine::resetVolumioState Sep 22 01:04:22 minidsp-raccio volumio[782]: info: CoreStateMachine::getcurrentVolume Sep 22 01:04:22 minidsp-raccio volumio[782]: info: CoreCommandRouter::volumioRetrievevolume Sep 22 01:04:22 minidsp-raccio volumio[782]: info: Stopping AccessToken refresher cron for QOBUZ Sep 22 01:04:22 minidsp-raccio volumio[782]: info: AccessToken refresher cron started for QOBUZ Sep 22 01:04:22 minidsp-raccio volumio[782]: info: msSurfaceDial volumioupdatevolume callback: {"vol":100,"mute":false,"disableVolumeControl":true} Sep 22 01:04:22 minidsp-raccio volumio[782]: info: CoreStateMachine::pushState Sep 22 01:04:22 minidsp-raccio volumio[782]: info: CorePlayQueue::getTrack 0 Sep 22 01:04:22 minidsp-raccio volumio[782]: info: CoreCommandRouter::volumioPushState Sep 22 01:04:22 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 22 01:04:22 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 22 01:04:22 minidsp-raccio sudo[1028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 22 01:04:22 minidsp-raccio sudo[1028]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 22 01:04:22 minidsp-raccio sudo[1028]: pam_unix(sudo:session): session closed for user root Sep 22 01:04:22 minidsp-raccio sudo[1030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 22 01:04:22 minidsp-raccio sudo[1030]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 22 01:04:22 minidsp-raccio sudo[1030]: pam_unix(sudo:session): session closed for user root Sep 22 01:04:22 minidsp-raccio sudo[1033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Sep 22 01:04:22 minidsp-raccio sudo[1033]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 22 01:04:22 minidsp-raccio systemd[1]: Configuration file /lib/systemd/system/volumiobt.service is marked executable. Please remove executable permission bits. Proceeding anyway. Sep 22 01:04:22 minidsp-raccio systemd[1]: Starting Volumio Bluetooth Module... Sep 22 01:04:22 minidsp-raccio systemd[1]: Started Volumio Bluetooth Module. Sep 22 01:04:22 minidsp-raccio sudo[1033]: pam_unix(sudo:session): session closed for user root Sep 22 01:04:22 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 22 01:04:22 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 22 01:04:22 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 22 01:04:22 minidsp-raccio sudo[1039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Sep 22 01:04:22 minidsp-raccio sudo[1039]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 22 01:04:22 minidsp-raccio volumiobt[1038]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Sep 22 01:04:22 minidsp-raccio sudo[1039]: pam_unix(sudo:session): session closed for user root Sep 22 01:04:22 minidsp-raccio volumio[782]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 22 01:04:22 minidsp-raccio volumio[782]: info: Serial port opened successfully Sep 22 01:04:22 minidsp-raccio volumio[782]: info: Volumio BT Module successfully started Sep 22 01:04:22 minidsp-raccio volumio[782]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Hardware Sep 22 01:04:22 minidsp-raccio volumio[782]: info: TidalConnect service stoped! Sep 22 01:04:22 minidsp-raccio volumio[782]: info: RAAT Albumart path created successfully Sep 22 01:04:22 minidsp-raccio volumio[782]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'minidsp-raccio' Sep 22 01:04:22 minidsp-raccio volumio[782]: We trust you have received the usual lecture from the local System Sep 22 01:04:22 minidsp-raccio volumio[782]: Administrator. It usually boils down to these three things: Sep 22 01:04:22 minidsp-raccio volumio[782]: #1) Respect the privacy of others. Sep 22 01:04:22 minidsp-raccio volumio[782]: #2) Think before you type. Sep 22 01:04:22 minidsp-raccio volumio[782]: #3) With great power comes great responsibility. Sep 22 01:04:22 minidsp-raccio volumio[782]: sudo: no tty present and no askpass program specified Sep 22 01:04:22 minidsp-raccio volumio[782]: info: MPD running with PID641 Sep 22 01:04:22 minidsp-raccio volumio[782]: ,establishing connection Sep 22 01:04:22 minidsp-raccio sudo[1047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Sep 22 01:04:22 minidsp-raccio sudo[1047]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 22 01:04:22 minidsp-raccio sudo[1047]: pam_unix(sudo:session): session closed for user root Sep 22 01:04:22 minidsp-raccio dbus[649]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' Sep 22 01:04:22 minidsp-raccio systemd[1]: Starting Bluetooth service... Sep 22 01:04:22 minidsp-raccio bluetoothd[1049]: Bluetooth daemon 5.23 Sep 22 01:04:22 minidsp-raccio dbus[649]: [system] Successfully activated service 'org.bluez' Sep 22 01:04:22 minidsp-raccio systemd[1]: Started Bluetooth service. Sep 22 01:04:22 minidsp-raccio bluetoothd[1049]: Starting SDP server Sep 22 01:04:22 minidsp-raccio bluetoothd[1049]: Excluding (cli) sap Sep 22 01:04:22 minidsp-raccio bluetoothd[1049]: kernel lacks bnep-protocol support Sep 22 01:04:22 minidsp-raccio bluetoothd[1049]: System does not support network plugin Sep 22 01:04:22 minidsp-raccio dbus[649]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' Sep 22 01:04:22 minidsp-raccio bluetoothd[1049]: Bluetooth management interface 1.14 initialized Sep 22 01:04:22 minidsp-raccio systemd[1]: Starting Hostname Service... Sep 22 01:04:23 minidsp-raccio systemd-hostnamed[1051]: Warning: nss-myhostname is not installed. Changing the local hostname might make it unresolveable. Please install nss-myhostname! Sep 22 01:04:23 minidsp-raccio dbus[649]: [system] Successfully activated service 'org.freedesktop.hostname1' Sep 22 01:04:23 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 22 01:04:23 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 22 01:04:23 minidsp-raccio systemd[1]: Started Hostname Service. Sep 22 01:04:23 minidsp-raccio volumio[782]: xcb_connection_has_error() returned true Sep 22 01:04:23 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 22 01:04:23 minidsp-raccio volumio[782]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 22 01:04:23 minidsp-raccio volumio[782]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 22 01:04:23 minidsp-raccio volumio[782]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 22 01:04:23 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 22 01:04:23 minidsp-raccio sudo[1059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 22 01:04:23 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Sep 22 01:04:23 minidsp-raccio sudo[1059]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 22 01:04:23 minidsp-raccio systemd[1]: Configuration file /lib/systemd/system/vtcs.service is marked executable. Please remove executable permission bits. Proceeding anyway. Sep 22 01:04:23 minidsp-raccio systemd[1]: Starting Volumio Tidal Connect Service... Sep 22 01:04:23 minidsp-raccio sudo[1060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Sep 22 01:04:23 minidsp-raccio sudo[1060]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 22 01:04:23 minidsp-raccio systemd[1]: Started Volumio Tidal Connect Service. Sep 22 01:04:23 minidsp-raccio volumiobt[1038]: pulseaudio: no process found Sep 22 01:04:23 minidsp-raccio sudo[1059]: pam_unix(sudo:session): session closed for user root Sep 22 01:04:23 minidsp-raccio sudo[1060]: pam_unix(sudo:session): session closed for user root Sep 22 01:04:23 minidsp-raccio volumio[782]: xcb_connection_has_error() returned true Sep 22 01:04:23 minidsp-raccio volumio[782]: info: Reloading queue from file Sep 22 01:04:23 minidsp-raccio volumio[782]: info: Serial Data: Sep 22 01:04:23 minidsp-raccio sudo[1070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Sep 22 01:04:23 minidsp-raccio sudo[1070]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 22 01:04:23 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: raat , reconfigureAndRestartRaat Sep 22 01:04:23 minidsp-raccio volumio[782]: info: Reconfiguring and Restarting RAAT Plugin Sep 22 01:04:23 minidsp-raccio systemd[1]: Starting RAAT DAEMON... Sep 22 01:04:23 minidsp-raccio systemd[1]: Started RAAT DAEMON. Sep 22 01:04:23 minidsp-raccio sudo[1070]: pam_unix(sudo:session): session closed for user root Sep 22 01:04:23 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 22 01:04:23 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 22 01:04:23 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 22 01:04:23 minidsp-raccio dbus[649]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' Sep 22 01:04:23 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 22 01:04:23 minidsp-raccio volumio[782]: info: Not Reporting Auto name since its the default one Sep 22 01:04:23 minidsp-raccio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Sep 22 01:04:23 minidsp-raccio volumio[782]: info: RAAT Overriding default device vendor model Sep 22 01:04:23 minidsp-raccio volumio[782]: info: Adding MINIDSP Inputs Sep 22 01:04:23 minidsp-raccio volumio[782]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 22 01:04:23 minidsp-raccio volumio[782]: info: [1663808663816] CoreMusicLibrary::Adding element Inputs Sep 22 01:04:23 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 22 01:04:23 minidsp-raccio volumio[782]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 22 01:04:23 minidsp-raccio volumio[782]: info: [1663808663826] CoreMusicLibrary::Adding element Presets Sep 22 01:04:23 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 22 01:04:23 minidsp-raccio volumio[782]: info: msSurfaceDial Managed Objs {"/org/bluez":{"org.freedesktop.DBus.Introspectable":{},"org.bluez.AgentManager1":{},"org.bluez.ProfileManager1":{},"org.bluez.Alert1":{},"org.bluez.HealthManager1":{}}} Sep 22 01:04:23 minidsp-raccio volumio[782]: info: [msSurfaceDial init()] check /org/bluez Sep 22 01:04:23 minidsp-raccio dbus[649]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Sep 22 01:04:23 minidsp-raccio volumio[782]: info: [msSurfaceDial init()] Adapter: null; SurfaceDial: null Sep 22 01:04:23 minidsp-raccio rtkit-daemon[1079]: Successfully called chroot. Sep 22 01:04:23 minidsp-raccio rtkit-daemon[1079]: Successfully dropped privileges. Sep 22 01:04:23 minidsp-raccio rtkit-daemon[1079]: Successfully limited resources. Sep 22 01:04:23 minidsp-raccio rtkit-daemon[1079]: Running. Sep 22 01:04:23 minidsp-raccio rtkit-daemon[1079]: Canary thread running. Sep 22 01:04:23 minidsp-raccio rtkit-daemon[1079]: Watchdog thread running. Sep 22 01:04:23 minidsp-raccio systemd[1]: Started RealtimeKit Scheduling Policy Service. Sep 22 01:04:23 minidsp-raccio volumio[782]: info: msSurfaceDial BluetoothSurfaceDial init() - ready! Sep 22 01:04:23 minidsp-raccio volumio[782]: info: CoreCommandRouter::Reload Ui Sep 22 01:04:23 minidsp-raccio volumio[782]: error: updateQueue error: null Sep 22 01:04:23 minidsp-raccio volumio[782]: error: Failed LSINFO: Error: [50@0] {} No such directory Sep 22 01:04:24 minidsp-raccio volumio[782]: info: CoreStateMachine::setRepeat true single undefined Sep 22 01:04:24 minidsp-raccio volumio[782]: info: CoreStateMachine::pushState Sep 22 01:04:24 minidsp-raccio volumio[782]: info: CorePlayQueue::getTrack 0 Sep 22 01:04:24 minidsp-raccio volumio[782]: info: CoreCommandRouter::volumioPushState Sep 22 01:04:24 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 22 01:04:24 minidsp-raccio volumio[782]: error: Help! Some callbacks for volumioPushState are crashing! Sep 22 01:04:24 minidsp-raccio volumio[782]: error: Cannot read property 'sendVolumeMute' of undefined Sep 22 01:04:24 minidsp-raccio volumio[782]: info: CoreStateMachine::setRandom null Sep 22 01:04:24 minidsp-raccio volumio[782]: info: CoreStateMachine::pushState Sep 22 01:04:24 minidsp-raccio volumio[782]: info: CorePlayQueue::getTrack 0 Sep 22 01:04:24 minidsp-raccio volumio[782]: info: CoreCommandRouter::volumioPushState Sep 22 01:04:24 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 22 01:04:24 minidsp-raccio volumio[782]: error: Help! Some callbacks for volumioPushState are crashing! Sep 22 01:04:24 minidsp-raccio volumio[782]: error: Cannot read property 'sendVolumeMute' of undefined Sep 22 01:04:24 minidsp-raccio volumio[782]: info: Serial Data: Sep 22 01:04:24 minidsp-raccio volumio[782]: info: msSurfaceDial volumioupdatevolume callback: {"mute":false,"disableVolumeControl":false} Sep 22 01:04:24 minidsp-raccio volumio[782]: info: CoreStateMachine::pushState Sep 22 01:04:24 minidsp-raccio volumio[782]: info: CorePlayQueue::getTrack 0 Sep 22 01:04:24 minidsp-raccio volumio[782]: info: CoreCommandRouter::volumioPushState Sep 22 01:04:24 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 22 01:04:24 minidsp-raccio volumio[782]: error: Help! Some callbacks for volumioPushState are crashing! Sep 22 01:04:24 minidsp-raccio volumio[782]: error: Cannot read property 'sendVolumeMute' of undefined Sep 22 01:04:24 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Sep 22 01:04:24 minidsp-raccio volumio[782]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 22 01:04:24 minidsp-raccio volumio[782]: info: Updating RAAT Signal Path Sep 22 01:04:24 minidsp-raccio volumio[782]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 22 01:04:24 minidsp-raccio volumio[782]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 22 01:04:24 minidsp-raccio volumio[782]: info: Raat Daemon started successfully Sep 22 01:04:24 minidsp-raccio volumio[782]: info: Serial Data: Sep 22 01:04:24 minidsp-raccio volumio[782]: info: CorePlayQueue::getTrack 0 Sep 22 01:04:24 minidsp-raccio volumio[782]: info: CoreCommandRouter::volumiosetSourceActiveno-source Sep 22 01:04:24 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 22 01:04:24 minidsp-raccio volumio[782]: info: CoreCommandRouter::volumioStop Sep 22 01:04:24 minidsp-raccio volumio[782]: info: CoreStateMachine::stop Sep 22 01:04:24 minidsp-raccio volumio[782]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 22 01:04:24 minidsp-raccio volumio[782]: verbose: UNSET VOLATILE Sep 22 01:04:24 minidsp-raccio volumio[782]: info: Notifying Active Input {"trackType":"input","service":"inputs","title":"TOSLINK","disableUiControls":true,"albumart":"/albumart"} Sep 22 01:04:24 minidsp-raccio volumio[782]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 22 01:04:24 minidsp-raccio volumio[782]: error: No access token received for TIDAL Sep 22 01:04:24 minidsp-raccio pulseaudio[1078]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Sep 22 01:04:24 minidsp-raccio pulseaudio[1078]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Sep 22 01:04:24 minidsp-raccio pulseaudio[1078]: org.bluez.Manager.GetProperties() failed: org.freedesktop.DBus.Error.UnknownMethod: Method "GetProperties" with signature "" on interface "org.bluez.Manager" doesn't exist Sep 22 01:04:24 minidsp-raccio volumiobt[1038]: Applying permissions Sep 22 01:04:24 minidsp-raccio volumiobt[1038]: Setting BT discoverable and pairable Sep 22 01:04:24 minidsp-raccio volumio[782]: info: Executing endpoint restartRAATSocket Sep 22 01:04:24 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Sep 22 01:04:24 minidsp-raccio volumio[782]: info: Starting Shairport Sync Sep 22 01:04:24 minidsp-raccio volumiobt[1038]: [38B blob data] Sep 22 01:04:24 minidsp-raccio volumiobt[1038]: Invalid command Sep 22 01:04:24 minidsp-raccio volumiobt[1038]: [bluetooth]# agent on Sep 22 01:04:24 minidsp-raccio volumiobt[1038]: [bluetooth]# discoverable on Sep 22 01:04:24 minidsp-raccio volumiobt[1038]: No default controller available Sep 22 01:04:24 minidsp-raccio volumiobt[1038]: [bluetooth]# pairable on Sep 22 01:04:24 minidsp-raccio volumiobt[1038]: No default controller available Sep 22 01:04:24 minidsp-raccio volumio[782]: info: Starting Shairport Sync Sep 22 01:04:24 minidsp-raccio volumiobt[1038]: [bluetooth]# agent NoInputNoOutput Sep 22 01:04:24 minidsp-raccio volumiobt[1038]: Failed to register agent object Sep 22 01:04:24 minidsp-raccio volumiobt[1038]: [bluetooth]# default-agent Sep 22 01:04:24 minidsp-raccio volumiobt[1038]: No agent is registered Sep 22 01:04:24 minidsp-raccio volumiobt[1038]: [bluetooth]# quit Sep 22 01:04:24 minidsp-raccio volumiobt[1038]: [61B blob data] Sep 22 01:04:24 minidsp-raccio volumiobt[1038]: [62B blob data] Sep 22 01:04:24 minidsp-raccio sudo[1097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 22 01:04:24 minidsp-raccio sudo[1097]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 22 01:04:24 minidsp-raccio volumio[782]: info: Serial Data: Sep 22 01:04:24 minidsp-raccio sudo[1101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 22 01:04:24 minidsp-raccio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Sep 22 01:04:24 minidsp-raccio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 22 01:04:24 minidsp-raccio volumio[782]: Discovery: adding 097d85f0-d87c-4de2-a9f0-2b560f21563a Sep 22 01:04:24 minidsp-raccio volumio[782]: info: mDNS: Found device minidsp-raccio Sep 22 01:04:24 minidsp-raccio sudo[1097]: pam_unix(sudo:session): session closed for user root Sep 22 01:04:24 minidsp-raccio sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 22 01:04:24 minidsp-raccio volumio[782]: info: CoreCommandRouter::volumioGetState Sep 22 01:04:24 minidsp-raccio volumio[782]: error: Could not retrieve plugin audio_interface multiroom Sep 22 01:04:24 minidsp-raccio sudo[1104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 22 01:04:24 minidsp-raccio volumio[782]: error: Could not retrieve plugin audio_interface multiroom Sep 22 01:04:24 minidsp-raccio sudo[1104]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 22 01:04:24 minidsp-raccio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 22 01:04:24 minidsp-raccio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Sep 22 01:04:24 minidsp-raccio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 22 01:04:24 minidsp-raccio sudo[1101]: pam_unix(sudo:session): session closed for user root Sep 22 01:04:24 minidsp-raccio volumio[782]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 22 01:04:24 minidsp-raccio volumio[782]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 22 01:04:24 minidsp-raccio systemd[1]: Starting UPnP Renderer front-end to MPD... Sep 22 01:04:24 minidsp-raccio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 22 01:04:24 minidsp-raccio sudo[1104]: pam_unix(sudo:session): session closed for user root Sep 22 01:04:25 minidsp-raccio volumio[782]: info: CoreCommandRouter::servicePushState Sep 22 01:04:25 minidsp-raccio volumio[782]: info: CoreStateMachine::pushState Sep 22 01:04:25 minidsp-raccio volumio[782]: info: CoreCommandRouter::volumioPushState Sep 22 01:04:25 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 22 01:04:25 minidsp-raccio volumio[782]: error: Help! Some callbacks for volumioPushState are crashing! Sep 22 01:04:25 minidsp-raccio volumio[782]: error: Cannot read property 'sendVolumeMute' of undefined Sep 22 01:04:25 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Sep 22 01:04:25 minidsp-raccio volumio[782]: xcb_connection_has_error() returned true Sep 22 01:04:25 minidsp-raccio volumio[782]: info: Serial Data: Sep 22 01:04:25 minidsp-raccio volumio[782]: info: msSurfaceDial volumioupdatevolume callback: {"vol":74,"dbVolume":-33.5,"mute":false,"disableVolumeControl":false} Sep 22 01:04:25 minidsp-raccio volumio[782]: info: CoreStateMachine::pushState Sep 22 01:04:25 minidsp-raccio volumio[782]: info: CoreCommandRouter::volumioPushState Sep 22 01:04:25 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 22 01:04:25 minidsp-raccio volumio[782]: error: Help! Some callbacks for volumioPushState are crashing! Sep 22 01:04:25 minidsp-raccio volumio[782]: error: Cannot read property 'sendVolumeMute' of undefined Sep 22 01:04:25 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Sep 22 01:04:25 minidsp-raccio volumio[782]: info: Updating RAAT Signal Path Sep 22 01:04:25 minidsp-raccio sudo[1123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Sep 22 01:04:25 minidsp-raccio sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 22 01:04:25 minidsp-raccio systemd[1]: Stopping RAAT DAEMON... Sep 22 01:04:25 minidsp-raccio systemd[1]: Starting RAAT DAEMON... Sep 22 01:04:25 minidsp-raccio systemd[1]: Started RAAT DAEMON. Sep 22 01:04:25 minidsp-raccio sudo[1123]: pam_unix(sudo:session): session closed for user root Sep 22 01:04:25 minidsp-raccio volumio[782]: info: Upmpdcli Daemon Started Sep 22 01:04:25 minidsp-raccio volumio[782]: info: Shairport-Sync Started Sep 22 01:04:25 minidsp-raccio volumio[782]: Error adding Membership: Error: addMembership EINVAL Sep 22 01:04:25 minidsp-raccio volumio[782]: info: Shairport-Sync Started Sep 22 01:04:25 minidsp-raccio volumio[782]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 22 01:04:25 minidsp-raccio volumio[782]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 22 01:04:25 minidsp-raccio volumio[782]: info: Executing endpoint tc_getconfig Sep 22 01:04:25 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 22 01:04:25 minidsp-raccio vtcs[1065]: STARTING TidalConnect services, version: 1.1.7.5 Sep 22 01:04:25 minidsp-raccio volumio[782]: info: Raat Daemon started successfully Sep 22 01:04:25 minidsp-raccio vtcs[1065]: STARTED TidalConnect services. Sep 22 01:04:25 minidsp-raccio volumio[782]: info: Executing endpoint restartRAATSocket Sep 22 01:04:25 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Sep 22 01:04:25 minidsp-raccio volumio-streaming-daemon[1007]: Checking variant in static cache Sep 22 01:04:25 minidsp-raccio volumio[782]: info: CoreCommandRouter::volumioGetState Sep 22 01:04:26 minidsp-raccio volumio[782]: info: Executing endpoint tc_connect Sep 22 01:04:26 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Sep 22 01:04:26 minidsp-raccio volumio[782]: info: Connecting to TidalConnect Sep 22 01:04:26 minidsp-raccio volumio[782]: info: CoreCommandRouter::servicePushState Sep 22 01:04:26 minidsp-raccio volumio[782]: info: CoreStateMachine::pushState Sep 22 01:04:26 minidsp-raccio volumio[782]: info: CoreCommandRouter::volumioPushState Sep 22 01:04:26 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 22 01:04:26 minidsp-raccio volumio[782]: info: CorePlayQueue::getTrack 0 Sep 22 01:04:26 minidsp-raccio volumio[782]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Sep 22 01:04:26 minidsp-raccio volumio[782]: info: CoreCommandRouter::servicePushState Sep 22 01:04:26 minidsp-raccio volumio[782]: info: CoreStateMachine::pushState Sep 22 01:04:26 minidsp-raccio volumio[782]: info: CoreCommandRouter::volumioPushState Sep 22 01:04:26 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 22 01:04:26 minidsp-raccio volumio[782]: info: CorePlayQueue::getTrack 0 Sep 22 01:04:26 minidsp-raccio volumio[782]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Sep 22 01:04:26 minidsp-raccio volumio[782]: info: Fetching Streaming Services browse cache Sep 22 01:04:26 minidsp-raccio volumio[782]: info: TidalConnect service started! Sep 22 01:04:26 minidsp-raccio volumio[782]: info: Serial Data: Sep 22 01:04:26 minidsp-raccio volumio[782]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Sep 22 01:04:26 minidsp-raccio volumio[782]: info: updateDSP function in raat called! Sep 22 01:04:26 minidsp-raccio volumio[782]: info: Updating RAAT Signal Path Sep 22 01:04:27 minidsp-raccio volumio[782]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 22 01:04:27 minidsp-raccio volumio[782]: { Error: addMembership ENODEV Sep 22 01:04:27 minidsp-raccio volumio[782]: at Socket.addMembership (dgram.js:615:11) Sep 22 01:04:27 minidsp-raccio volumio[782]: at Timeout.addMembership [as _onTimeout] (/volumio/node_modules/node-ssdp/lib/index.js:264:16) Sep 22 01:04:27 minidsp-raccio volumio[782]: at ontimeout (timers.js:498:11) Sep 22 01:04:27 minidsp-raccio volumio[782]: at tryOnTimeout (timers.js:323:5) Sep 22 01:04:27 minidsp-raccio volumio[782]: at Timer.listOnTimeout (timers.js:290:5) errno: 'ENODEV', code: 'ENODEV', syscall: 'addMembership' } Sep 22 01:04:27 minidsp-raccio volumio[782]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 22 01:04:28 minidsp-raccio sudo[1174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2022-09-22 01:03 Sep 22 01:04:28 minidsp-raccio sudo[1174]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="6d9442f761618503268d0366db805a252514dec1" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Jul 26 13:05:05 CEST 2022" VOLUMIO_VERSION="1.125" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="b36618da2d3cf6839ff2c23420b9861f"